2026-03-19 14:41:42.050090 | Job console starting...
2026-03-19 14:41:42.061882 | Updating repositories
2026-03-19 14:41:42.101314 | Preparing job workspace
2026-03-19 14:41:48.147193 | Running Ansible setup...
2026-03-19 14:41:52.922416 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2026-03-19 14:41:53.505927 | 
2026-03-19 14:41:53.506034 | PLAY [localhost]
2026-03-19 14:41:53.514805 | 
2026-03-19 14:41:53.514875 | TASK [Gathering Facts]
2026-03-19 14:41:54.556130 | localhost | ok
2026-03-19 14:41:54.587436 | 
2026-03-19 14:41:54.587667 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-03-19 14:41:55.001858 | localhost -> localhost | changed
2026-03-19 14:41:55.007873 | 
2026-03-19 14:41:55.007954 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-03-19 14:41:55.874142 | localhost -> localhost | changed
2026-03-19 14:41:55.886377 | 
2026-03-19 14:41:55.886490 | TASK [Setup log path fact]
2026-03-19 14:41:55.904838 | localhost | ok
2026-03-19 14:41:55.915788 | 
2026-03-19 14:41:55.915880 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-03-19 14:41:55.943483 | localhost | ok
2026-03-19 14:41:55.950906 | 
2026-03-19 14:41:55.950998 | TASK [emit-job-header : Print job information]
2026-03-19 14:41:55.979712 | # Job Information
2026-03-19 14:41:55.979961 | Ansible Version: 2.15.12
2026-03-19 14:41:55.979988 | Job: cifmw-molecule-operator_deploy
2026-03-19 14:41:55.980008 | Pipeline: github-check
2026-03-19 14:41:55.980027 | Executor: ze03.softwarefactory-project.io
2026-03-19 14:41:55.980045 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3783
2026-03-19 14:41:55.980063 | Log URL (when completed): https://logserver.rdoproject.org/a2f/rdoproject.org/a2f0fdd6bc094b24b8be3ae56e69bd0d/
2026-03-19 14:41:55.980081 | Event ID: 921e4480-23a1-11f1-92c8-d30e474fbd2e
2026-03-19 14:41:55.983883 | 
2026-03-19 14:41:55.983953 | LOOP [emit-job-header : Print node information]
2026-03-19 14:41:56.086000 | localhost | ok:
2026-03-19 14:41:56.086216 | localhost | # Node Information
2026-03-19 14:41:56.086245 | localhost | Inventory Hostname: controller
2026-03-19 14:41:56.086269 | localhost | Hostname: np0005652054
2026-03-19 14:41:56.086289 | localhost | Username: zuul
2026-03-19 14:41:56.086309 | localhost | Distro: CentOS 9
2026-03-19 14:41:56.086327 | localhost | Provider: vexxhost-nodepool-tripleo
2026-03-19 14:41:56.086346 | localhost | Region: RegionOne
2026-03-19 14:41:56.086364 | localhost | Label: centos-9-stream-crc-2-48-0-xl
2026-03-19 14:41:56.086382 | localhost | Product Name: OpenStack Nova
2026-03-19 14:41:56.086401 | localhost | Interface IP: 38.102.83.106
2026-03-19 14:41:56.111559 | 
2026-03-19 14:41:56.111665 | PLAY [all]
2026-03-19 14:41:56.118050 | 
2026-03-19 14:41:56.118112 | TASK [Gather network facts]
2026-03-19 14:41:56.660526 | controller | ok
2026-03-19 14:41:56.680631 | 
2026-03-19 14:41:56.680756 | TASK [include_role : start-zuul-console]
2026-03-19 14:41:56.699575 | controller | ok
2026-03-19 14:41:56.711468 | 
2026-03-19 14:41:56.711566 | TASK [start-zuul-console : Start zuul_console daemon.]
2026-03-19 14:41:57.207505 | controller | ok
2026-03-19 14:41:57.222887 | 
2026-03-19 14:41:57.222997 | TASK [include_role : add-build-sshkey]
2026-03-19 14:41:57.244981 | controller | ok
2026-03-19 14:41:57.266912 | 
2026-03-19 14:41:57.267053 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-03-19 14:41:57.529927 | controller -> localhost | ok
2026-03-19 14:41:57.535103 | 
2026-03-19 14:41:57.535165 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-03-19 14:41:57.567671 | controller | ok
2026-03-19 14:41:57.585074 | controller | included: /var/lib/zuul/builds/a2f0fdd6bc094b24b8be3ae56e69bd0d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-03-19 14:41:57.590573 | 
2026-03-19 14:41:57.590634 | TASK [add-build-sshkey : Create Temp SSH key]
2026-03-19 14:41:58.578015 | controller -> localhost | Generating public/private rsa key pair.
2026-03-19 14:41:58.578194 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/a2f0fdd6bc094b24b8be3ae56e69bd0d/work/a2f0fdd6bc094b24b8be3ae56e69bd0d_id_rsa.
2026-03-19 14:41:58.578227 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/a2f0fdd6bc094b24b8be3ae56e69bd0d/work/a2f0fdd6bc094b24b8be3ae56e69bd0d_id_rsa.pub.
2026-03-19 14:41:58.578336 | controller -> localhost | The key fingerprint is:
2026-03-19 14:41:58.578363 | controller -> localhost | SHA256:ua+54Zet3RWqw7A1SBIIaxUSZj5vo0eagm3k207fZ1M zuul-build-sshkey
2026-03-19 14:41:58.578383 | controller -> localhost | The key's randomart image is:
2026-03-19 14:41:58.578464 | controller -> localhost | +---[RSA 3072]----+
2026-03-19 14:41:58.578497 | controller -> localhost | |   *ooo          |
2026-03-19 14:41:58.578523 | controller -> localhost | |  + +. .         |
2026-03-19 14:41:58.578541 | controller -> localhost | |   =    .        |
2026-03-19 14:41:58.578567 | controller -> localhost | |  . o  . o       |
2026-03-19 14:41:58.578584 | controller -> localhost | |  .  =  S .    . |
2026-03-19 14:41:58.578601 | controller -> localhost | |.+  * .  + oE . .|
2026-03-19 14:41:58.578617 | controller -> localhost | |..++..  o =+..  .|
2026-03-19 14:41:58.578637 | controller -> localhost | | ..+.. o =*+o. . |
2026-03-19 14:41:58.578656 | controller -> localhost | |  ..o . **o+o .  |
2026-03-19 14:41:58.578677 | controller -> localhost | +----[SHA256]-----+
2026-03-19 14:41:58.578755 | controller -> localhost | ok: Runtime: 0:00:00.593027
2026-03-19 14:41:58.584777 | 
2026-03-19 14:41:58.584847 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-03-19 14:41:58.604315 | controller | ok
2026-03-19 14:41:58.615603 | controller | included: /var/lib/zuul/builds/a2f0fdd6bc094b24b8be3ae56e69bd0d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-03-19 14:41:58.623539 | 
2026-03-19 14:41:58.623615 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-03-19 14:41:58.637852 | controller | skipping: Conditional result was False
2026-03-19 14:41:58.643769 | 
2026-03-19 14:41:58.643840 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-03-19 14:41:59.222690 | controller | changed
2026-03-19 14:41:59.239627 | 
2026-03-19 14:41:59.239778 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-03-19 14:41:59.628987 | controller | ok
2026-03-19 14:41:59.637110 | 
2026-03-19 14:41:59.637273 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-03-19 14:42:01.134182 | controller | changed
2026-03-19 14:42:01.145057 | 
2026-03-19 14:42:01.145196 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-03-19 14:42:02.656323 | controller | changed
2026-03-19 14:42:02.667241 | 
2026-03-19 14:42:02.667401 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-03-19 14:42:02.693789 | controller | skipping: Conditional result was False
2026-03-19 14:42:02.705415 | 
2026-03-19 14:42:02.705678 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-03-19 14:42:03.113008 | controller -> localhost | changed
2026-03-19 14:42:03.122811 | 
2026-03-19 14:42:03.122936 | TASK [add-build-sshkey : Add back temp key]
2026-03-19 14:42:03.403341 | controller -> localhost | Identity added: /var/lib/zuul/builds/a2f0fdd6bc094b24b8be3ae56e69bd0d/work/a2f0fdd6bc094b24b8be3ae56e69bd0d_id_rsa (zuul-build-sshkey)
2026-03-19 14:42:03.403580 | controller -> localhost | ok: Runtime: 0:00:00.013221
2026-03-19 14:42:03.409543 | 
2026-03-19 14:42:03.409611 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-03-19 14:42:03.896802 | controller | ok
2026-03-19 14:42:03.902078 | 
2026-03-19 14:42:03.902150 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-03-19 14:42:03.926577 | controller | skipping: Conditional result was False
2026-03-19 14:42:03.936279 | 
2026-03-19 14:42:03.936344 | TASK [include_role : validate-host]
2026-03-19 14:42:03.957640 | controller | ok
2026-03-19 14:42:04.010404 | 
2026-03-19 14:42:04.010514 | TASK [validate-host : Define zuul_info_dir fact]
2026-03-19 14:42:04.059949 | controller | ok
2026-03-19 14:42:04.064788 | 
2026-03-19 14:42:04.064922 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2026-03-19 14:42:04.315167 | controller -> localhost | ok
2026-03-19 14:42:04.321112 | 
2026-03-19 14:42:04.321202 | TASK [validate-host : Collect information about the host]
2026-03-19 14:42:05.404856 | controller | ok
2026-03-19 14:42:05.425625 | 
2026-03-19 14:42:05.425958 | TASK [validate-host : Sanitize hostname]
2026-03-19 14:42:05.502171 | controller | ok
2026-03-19 14:42:05.507419 | 
2026-03-19 14:42:05.507491 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2026-03-19 14:42:05.970494 | controller -> localhost | changed
2026-03-19 14:42:05.976810 | 
2026-03-19 14:42:05.976886 | TASK [validate-host : Collect information about zuul worker]
2026-03-19 14:42:06.533884 | controller | ok
2026-03-19 14:42:06.553295 | 
2026-03-19 14:42:06.553577 | TASK [validate-host : Write out all zuul information for each host]
2026-03-19 14:42:07.048768 | controller -> localhost | changed
2026-03-19 14:42:07.061465 | 
2026-03-19 14:42:07.061573 | TASK [include_role : prepare-workspace-openshift]
2026-03-19 14:42:07.076526 | controller | skipping: Conditional result was False
2026-03-19 14:42:07.084341 | 
2026-03-19 14:42:07.084424 | TASK [include_role : remove-zuul-sshkey]
2026-03-19 14:42:07.109020 | controller | skipping: Conditional result was False
2026-03-19 14:42:07.116372 | 
2026-03-19 14:42:07.116458 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-03-19 14:42:07.426119 | controller | ok: "logs"
2026-03-19 14:42:07.426576 | controller | ok: All items complete
2026-03-19 14:42:07.426625 | 
2026-03-19 14:42:07.694980 | controller | ok: "artifacts"
2026-03-19 14:42:07.979256 | controller | ok: "docs"
2026-03-19 14:42:07.995954 | 
2026-03-19 14:42:07.996093 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-03-19 14:42:08.322295 | controller | changed: "logs"
2026-03-19 14:42:08.628574 | controller | changed: "artifacts"
2026-03-19 14:42:08.948960 | controller | changed: "docs"
2026-03-19 14:42:09.004489 | 
2026-03-19 14:42:09.004609 | PLAY RECAP
2026-03-19 14:42:09.004686 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2026-03-19 14:42:09.004779 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-03-19 14:42:09.004820 | 
2026-03-19 14:42:09.127694 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2026-03-19 14:42:09.128469 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2026-03-19 14:42:09.758713 | 
2026-03-19 14:42:09.758865 | PLAY [all]
2026-03-19 14:42:09.808532 | 
2026-03-19 14:42:09.808694 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2026-03-19 14:42:09.881849 | controller | ok
2026-03-19 14:42:09.889051 | 
2026-03-19 14:42:09.889158 | TASK [mirror-info-fork : Create /etc/ci]
2026-03-19 14:42:10.461091 | controller | changed
2026-03-19 14:42:10.467340 | 
2026-03-19 14:42:10.467483 | TASK [mirror-info-fork : Install ci_mirror script]
2026-03-19 14:42:12.182257 | controller | changed
2026-03-19 14:42:12.203057 | 
2026-03-19 14:42:12.203291 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2026-03-19 14:42:12.749828 | controller | changed:
2026-03-19 14:42:12.750072 | controller | {
2026-03-19 14:42:12.750126 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2026-03-19 14:42:12.750176 | controller | }
2026-03-19 14:42:13.146241 | controller | changed:
2026-03-19 14:42:13.146442 | controller | {
2026-03-19 14:42:13.146504 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2026-03-19 14:42:13.146554 | controller | }
2026-03-19 14:42:13.518009 | controller | changed:
2026-03-19 14:42:13.518107 | controller | {
2026-03-19 14:42:13.518132 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com"
2026-03-19 14:42:13.518152 | controller | }
2026-03-19 14:42:13.923387 | controller | changed:
2026-03-19 14:42:13.923532 | controller | {
2026-03-19 14:42:13.923584 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2026-03-19 14:42:13.923627 | controller | }
2026-03-19 14:42:14.295693 | controller | changed:
2026-03-19 14:42:14.296043 | controller | {
2026-03-19 14:42:14.296109 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2026-03-19 14:42:14.296154 | controller | }
2026-03-19 14:42:14.680057 | controller | changed:
2026-03-19 14:42:14.680294 | controller | {
2026-03-19 14:42:14.680355 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2026-03-19 14:42:14.680400 | controller | }
2026-03-19 14:42:15.081451 | controller | changed:
2026-03-19 14:42:15.081607 | controller | {
2026-03-19 14:42:15.081675 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDLOQd4ZLtkZXQGY6UwAr/06ppWQK4fDO3HaqxPk98csyOCBXsliSKK39Bso828+5srIXiW7aI6aC9P5mwi4mUZlGPfJlQbfrcGvY+b/SocuvaGK+1RrHLoJCT52LBhwgrzlXio2jeksZeein8iaTrhsPrOAs7KggIL/rB9hEiB3NaOPWhhoCP4vlW6MEMExGcqB/1FVxXFBPnLkEyW0Lk7ycVflZl2ocRxbfjZi0+tI1Wlinp8PvSQSc/WVrAcDgKjc/mB4ODPOyYy3G8FHgfMsrXSDEyjBKgLKMsdCrAUcqJQWjkqXleXSYOV4q3pzL+9umK+q/e3P/bIoSFQzmJKTU1eDfuvPXmow9F5H54fii/Da7ezlMJ+wPGHJrRAkmzvMbALy7xwswLhZMkOGNtRcPqaKYRmIBKpw3o6bCTtcNUHOtOQnzwY8JzrM2eBWJBXAANYw+9/ho80JIiwhg29CFNpVBuHbql2YxJQNrnl90guN65rYNpDxdIluweyUf8= anbanerj@kaermorhen"
2026-03-19 14:42:15.081770 | controller | }
2026-03-19 14:42:15.456944 | controller | changed:
2026-03-19 14:42:15.457035 | controller | {
2026-03-19 14:42:15.457059 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQC3VwV8Im9kRm49lt3tM36hj4Zv27FxGo4C1Q/0jqhzFmHY7RHbmeRr8ObhwWoHjXSozKWg8FL5ER0z3hTwL0W6lez3sL7hUaCmSuZmG5Hnl3x4vTSxDI9JZ/Y65rtYiiWQo2fC5xJhU/4+0e5e/pseCm8cKRSu+SaxhO+sd6FDojA2x1BzOzKiQRDy/1zWGp/cZkxcEuB1wHI5LMzN03c67vmbu+fhZRAUO4dQkvcnj2LrhQtpa+ytvnSjr8icMDosf1OsbSffwZFyHB/hfWGAfe0eIeSA2XPraxiPknXxiPKx2MJsaUTYbsZcm3EjFdHBBMumw5rBI74zLrMRvCO9GwBEmGT4rFng1nP+yw5DB8sn2zqpOsPg1LYRwCPOUveC13P6pgsZZPh812e8v5EKnETct+5XI3dVpdw6CnNiLwAyVAF15DJvBGT/u1k0Myg/bQn+Gv9k2MSj6LvQmf6WbZu2Wgjm30z3FyCneBqTL7mLF19YXzeC0ufHz5pnO1E= dasm@fedora"
2026-03-19 14:42:15.457078 | controller | }
2026-03-19 14:42:15.796511 | controller | changed:
2026-03-19 14:42:15.796699 | controller | {
2026-03-19 14:42:15.796797 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2026-03-19 14:42:15.796845 | controller | }
2026-03-19 14:42:16.257376 | controller | changed:
2026-03-19 14:42:16.257534 | controller | {
2026-03-19 14:42:16.257615 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2026-03-19 14:42:16.257666 | controller | }
2026-03-19 14:42:16.665467 | controller | changed:
2026-03-19 14:42:16.665561 | controller | {
2026-03-19 14:42:16.665585 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2026-03-19 14:42:16.665606 | controller | }
2026-03-19 14:42:17.053224 | controller | changed:
2026-03-19 14:42:17.053332 | controller | {
2026-03-19 14:42:17.053356 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2026-03-19 14:42:17.053376 | controller | }
2026-03-19 14:42:17.393939 | controller | changed:
2026-03-19 14:42:17.394087 | controller | {
2026-03-19 14:42:17.394140 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICWBreHW95Wz2Toz5YwCGQwFcUG8oFYkienDh9tntmDc ralfieri@redhat.com"
2026-03-19 14:42:17.394182 | controller | }
2026-03-19 14:42:17.768639 | controller | changed:
2026-03-19 14:42:17.768833 | controller | {
2026-03-19 14:42:17.768887 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2026-03-19 14:42:17.768928 | controller | }
2026-03-19 14:42:18.141154 | controller | changed:
2026-03-19 14:42:18.142078 | controller | {
2026-03-19 14:42:18.142127 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2026-03-19 14:42:18.142150 | controller | }
2026-03-19 14:42:18.536354 | controller | changed:
2026-03-19 14:42:18.536601 | controller | {
2026-03-19 14:42:18.536662 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2026-03-19 14:42:18.536705 | controller | }
2026-03-19 14:42:18.856437 | controller | changed:
2026-03-19 14:42:18.856628 | controller | {
2026-03-19 14:42:18.856699 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQCfNtF7NvKl915TGsGGoseUb06Hj8L/S4toWf0hExeY+F00woL6NvBlJD0nDct+P5a22I4EhvoQCRQ8reaPCm1lybR3uiRIJsj+8zkVvLwby9LXzfZorlNG9ofjd00FEmB09uW/YvTl6Q9XwwwX6tInzIOv3TMqTHHGOL74ibbj8J/FJR0cFEyj0z4WQRvtkh32xAHl83gbuINryMt0sqRI+clj2381NKL55DRLQrVw0gsfqqxiHAnXg21qWmc4J+b9e9kiuAFQjcjwTVkwJCcg3xbPwC/qokYRby/Y5S40UUd7/jEARGXT7RZgpzTuDd1oZiCVrnrqJNPaMNdVv5MLeFdf1B7iIe5aa/fGouX7AO4SdKhZUdnJmCFAGvjC6S3JMZ2wAcUl+OHnssfmdj7XL50cLo27vjuzMtLAgSqi6N99m92WCF2s8J9aVzszX7Xz9OKZCeGsiVJp3/NdABKzSEAyM9xBD/5Vho894Sav+otpySHe3p6RUTgbB5Zu8VyZRZ/UtB3ueXxyo764yrc6qWIDqrehm84Xm9g+/jpIBzGPl07NUNJpdt/6Sgf9RIKXw/7XypO5yZfUcuFNGTxLfqjTNrtgLZNcjfav6sSdVXVcMPL//XNuRdKmVFaO76eV/oGMQGr1fGcCD+N+CpI7+Q+fCNB6VFWG4nZFuI/Iuw== averdagu@redhat.com"
2026-03-19 14:42:18.856809 | controller | }
2026-03-19 14:42:19.229161 | controller | changed:
2026-03-19 14:42:19.229335 | controller | {
2026-03-19 14:42:19.229409 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDq8l27xI+QlQVdS4djp9ogSoyrNE2+Ox6vKPdhSNL1J3PE5w+WCSvMz9A5gnNuH810zwbekEApbxTze/gLQJwBHA52CChfURpXrFaxY7ePXRElwKAL3mJfzBWY/c5jnNL9TCVmFJTGZkFZP3Nh+BMgZvL6xBkt3WKm6Uq18qzd9XeKcZusrA+O+uLv1fVeQnadY9RIqOCyeFYCzLWrUfTyE8x/XG0hAWIM7qpnF2cALQS2h9n4hW5ybiUN790H08wf9hFwEf5nxY9Z9dVkPFQiTSGKNBzmnCXU9skxS/xhpFjJ5duGSZdtAHe9O+nGZm9c67hxgtf8e5PDuqAdXEv2cf6e3VBAt+Bz8EKI3yosTj0oZHfwr42Yzb1l/SKy14Rggsrc9KAQlrGXan6+u2jcQqqx7l+SWmnpFiWTV9u5cWj2IgOhApOitmRBPYqk9rE2usfO0hLn/Pj/R/Nau4803e1/EikdLE7Ps95s9mX5jRDjAoUa2JwFF5RsVFyL910= ashigupt@ashigupt.remote.csb"
2026-03-19 14:42:19.229488 | controller | }
2026-03-19 14:42:19.619068 | controller | changed:
2026-03-19 14:42:19.619233 | controller | {
2026-03-19 14:42:19.619298 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2026-03-19 14:42:19.619350 | controller | }
2026-03-19 14:42:20.033015 | controller | changed:
2026-03-19 14:42:20.033160 | controller | {
2026-03-19 14:42:20.033213 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2026-03-19 14:42:20.033255 | controller | }
2026-03-19 14:42:20.449449 | controller | changed:
2026-03-19 14:42:20.449688 | controller | {
2026-03-19 14:42:20.449782 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2026-03-19 14:42:20.449827 | controller | }
2026-03-19 14:42:20.817648 | controller | changed:
2026-03-19 14:42:20.817951 | controller | {
2026-03-19 14:42:20.818017 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2026-03-19 14:42:20.818064 | controller | }
2026-03-19 14:42:21.189386 | controller | changed:
2026-03-19 14:42:21.189530 | controller | {
2026-03-19 14:42:21.189583 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQDZdI7t1cxYx65heVI24HTV4F7oQLW1zyfxHreL2TIJKxjyrUUKIFEUmTutcBlJRLNT2Eoix6x1sOw9YrchloCLcn//SGfTElr9mSc5jbjb7QXEU+zJMhtxyEJ1Po3CUGnj7ckiIXw7wcawZtrEOAQ9pH3ExYCJcEMiyNjRQZCxT3tPK+S4B95EWh5Fsrz9CkwpjNRPPH7LigCeQTM3Wc7r97utAslBUUvYceDSLA7rMgkitJE38b7rZBeYzsGQ8YYUBjTCtehqQXxCRjizbHWaaZkBU+N3zkKB6n/iCNGIO690NK7A/qb6msTijiz1PeuM8ThOsi9qXnbX5v0PoTpcFSojV7NHAQ71f0XXuS43FhZctT+Dcx44dT8Fb5vJu2cJGrk+qF8ZgJYNpRS7gPg0EG2EqjK7JMf9ULdjSu0r+KlqIAyLvtzT4eOnQipoKlb/WG5D/0ohKv7OMQ352ggfkBFIQsRXyyTCT98Ft9juqPuahi3CAQmP4H9dyE+7+Kz437PEtsxLmfm6naNmWi7Ee1DqWPwS8rEajsm4sNM4wW9gdBboJQtc0uZw0DfLj1I9r3Mc8Ol0jYtz0yNQDSzVLrGCaJlC311trU70tZ+ZkAVV6Mn8lOhSbj1cK0lvSr6ZK4dgqGl3I1eTZJJhbLNdg7UOVaiRx9543+C/p/As7w== brjackma@redhat.com"
2026-03-19 14:42:21.189627 | controller | }
2026-03-19 14:42:21.573596 | controller | changed:
2026-03-19 14:42:21.573773 | controller | {
2026-03-19 14:42:21.573831 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2026-03-19 14:42:21.573873 | controller | }
2026-03-19 14:42:21.980250 | controller | changed:
2026-03-19 14:42:21.980999 | controller | {
2026-03-19 14:42:21.981066 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2026-03-19 14:42:21.981099 | controller | }
2026-03-19 14:42:22.389921 | controller | changed:
2026-03-19 14:42:22.390093 | controller | {
2026-03-19 14:42:22.390157 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2026-03-19 14:42:22.390212 | controller | }
2026-03-19 14:42:22.427357 | 
2026-03-19 14:42:22.427497 | TASK [Set timezone to UTC]
2026-03-19 14:42:23.011243 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2026-03-19 14:42:23.022862 | 
2026-03-19 14:42:23.023006 | TASK [Create nodepool directory]
2026-03-19 14:42:23.386459 | controller | changed
2026-03-19 14:42:23.398902 | 
2026-03-19 14:42:23.399070 | TASK [Create nodepool sub_nodes file]
2026-03-19 14:42:24.750770 | controller | changed
2026-03-19 14:42:24.763661 | 
2026-03-19 14:42:24.763892 | TASK [Create nodepool sub_nodes_private file]
2026-03-19 14:42:26.080988 | controller | changed
2026-03-19 14:42:26.086528 | 
2026-03-19 14:42:26.086617 | LOOP [Populate nodepool sub_nodes file]
2026-03-19 14:42:26.126334 | 
2026-03-19 14:42:26.126478 | LOOP [Populate nodepool sub_nodes_private file]
2026-03-19 14:42:26.165039 | 
2026-03-19 14:42:26.165181 | TASK [Create nodepool primary file]
2026-03-19 14:42:26.198512 | controller | skipping: Conditional result was False
2026-03-19 14:42:26.204306 | 
2026-03-19 14:42:26.204379 | TASK [Create nodepool node_private for this node]
2026-03-19 14:42:27.590566 | controller | changed
2026-03-19 14:42:27.606357 | 
2026-03-19 14:42:27.606541 | LOOP [Copy ssh keys to nodepool directory]
2026-03-19 14:42:28.193535 | controller | ok: Item: id_rsa Runtime: 0:00:00.010042
2026-03-19 14:42:28.194115 | 
2026-03-19 14:42:28.511615 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.015789
2026-03-19 14:42:28.519154 | 
2026-03-19 14:42:28.519273 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2026-03-19 14:42:29.984328 | controller | changed
2026-03-19 14:42:29.994171 | 
2026-03-19 14:42:29.994337 | TASK [Validate sudoers config after edits]
2026-03-19 14:42:30.369931 | controller | /etc/sudoers: parsed OK
2026-03-19 14:42:30.369991 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2026-03-19 14:42:30.370006 | controller | /etc/sudoers.d/zuul: parsed OK
2026-03-19 14:42:30.370016 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2026-03-19 14:42:30.532207 | controller | ok: Runtime: 0:00:00.007563
2026-03-19 14:42:30.544705 | 
2026-03-19 14:42:30.544884 | TASK [Show the environment passed in to job shell scripts]
2026-03-19 14:42:30.974287 | controller | SHELL=/bin/bash
2026-03-19 14:42:30.974360 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2026-03-19 14:42:30.974376 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2026-03-19 14:42:30.974428 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/83/3783/eb8d33b9cdc4a6ee90c6a78a02d04804bc384301
2026-03-19 14:42:30.974442 | controller | PWD=/home/zuul
2026-03-19 14:42:30.974452 | controller | ZUUL_PIPELINE=github-check
2026-03-19 14:42:30.974462 | controller | LOGNAME=zuul
2026-03-19 14:42:30.974472 | controller | XDG_SESSION_TYPE=tty
2026-03-19 14:42:30.974482 | controller | _=/usr/bin/env
2026-03-19 14:42:30.974492 | controller | MOTD_SHOWN=pam
2026-03-19 14:42:30.974501 | controller | HOME=/home/zuul
2026-03-19 14:42:30.974511 | controller | LANG=en_US.UTF-8
2026-03-19 14:42:30.974521 | controller | SSH_CONNECTION=38.102.83.114 46634 38.102.83.106 22
2026-03-19 14:42:30.974531 | controller | TOX_TESTENV_PASSENV=ZUUL_UUID ZUUL_PROJECT ZUUL_SHORT_PROJECT_NAME ZUUL_PIPELINE ZUUL_VOTING WORKSPACE BUILD_TIMEOUT ZUUL_BRANCH ZUUL_CHANGES ZUUL_CHANGE_IDS ZUUL_CHANGE ZUUL_PATCHSET
2026-03-19 14:42:30.974545 | controller | ZUUL_CHANGE_IDS=3783,eb8d33b9cdc4a6ee90c6a78a02d04804bc384301
2026-03-19 14:42:30.974555 | controller | WORKSPACE=/home/zuul/workspace
2026-03-19 14:42:30.974565 | controller | XDG_SESSION_CLASS=user
2026-03-19 14:42:30.974575 | controller | SELINUX_ROLE_REQUESTED=
2026-03-19 14:42:30.974585 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2026-03-19 14:42:30.974595 | controller | USER=zuul
2026-03-19 14:42:30.974605 | controller | ZUUL_VOTING=True
2026-03-19 14:42:30.974614 | controller | BUILD_TIMEOUT=1800000
2026-03-19 14:42:30.974624 | controller | SELINUX_USE_CURRENT_RANGE=
2026-03-19 14:42:30.974634 | controller | SHLVL=1
2026-03-19 14:42:30.974643 | controller | ZUUL_PATCHSET=eb8d33b9cdc4a6ee90c6a78a02d04804bc384301
2026-03-19 14:42:30.974653 | controller | XDG_SESSION_ID=1
2026-03-19 14:42:30.974663 | controller | ZUUL_BRANCH=main
2026-03-19 14:42:30.974673 | controller | XDG_RUNTIME_DIR=/run/user/1000
2026-03-19 14:42:30.974683 | controller | SSH_CLIENT=38.102.83.114 46634 22
2026-03-19 14:42:30.974693 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2026-03-19 14:42:30.974703 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2026-03-19 14:42:30.974713 | controller | which_declare=declare -f
2026-03-19 14:42:30.974723 | controller | PATH=/home/zuul/.crc/bin/oc:/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2026-03-19 14:42:30.974733 | controller | SELINUX_LEVEL_REQUESTED=
2026-03-19 14:42:30.974743 | controller | ZUUL_CHANGE=3783
2026-03-19 14:42:30.974753 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2026-03-19 14:42:30.974762 | controller | ZUUL_UUID=a2f0fdd6bc094b24b8be3ae56e69bd0d
2026-03-19 14:42:30.974772 | controller | BASH_FUNC_which%%=() {  ( alias;
2026-03-19 14:42:30.974782 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2026-03-19 14:42:30.974792 | controller | }
2026-03-19 14:42:31.128839 | controller | ok: Runtime: 0:00:00.008222
2026-03-19 14:42:31.141137 | 
2026-03-19 14:42:31.141270 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2026-03-19 14:42:31.178588 | controller | skipping: Conditional result was False
2026-03-19 14:42:31.192592 | 
2026-03-19 14:42:31.192844 | TASK [Symlink /home/zuul-worker/workspace]
2026-03-19 14:42:31.744227 | controller | skipping: Conditional result was False
2026-03-19 14:42:31.755344 | 
2026-03-19 14:42:31.755514 | TASK [Ensure legacy workspace directory]
2026-03-19 14:42:32.089020 | controller | changed
2026-03-19 14:42:32.129710 | 
2026-03-19 14:42:32.129823 | PLAY RECAP
2026-03-19 14:42:32.129883 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2026-03-19 14:42:32.129914 | 
2026-03-19 14:42:32.231063 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2026-03-19 14:42:32.231861 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2026-03-19 14:42:32.801987 | 
2026-03-19 14:42:32.802114 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2026-03-19 14:42:32.834880 | 
2026-03-19 14:42:32.835028 | TASK [Create zuul-output directory]
2026-03-19 14:42:33.374069 | controller | changed
2026-03-19 14:42:33.387497 | 
2026-03-19 14:42:33.387704 | TASK [Slurp Zuul inventory test]
2026-03-19 14:42:33.765946 | controller -> localhost | ok
2026-03-19 14:42:33.780578 | 
2026-03-19 14:42:33.780716 | TASK [Save zuul inventory]
2026-03-19 14:42:36.481638 | controller | changed
2026-03-19 14:42:36.487015 | 
2026-03-19 14:42:36.487083 | TASK [Save zuul vars without the change_message]
2026-03-19 14:42:37.991409 | controller | changed
2026-03-19 14:42:38.017810 | 
2026-03-19 14:42:38.017916 | PLAY RECAP
2026-03-19 14:42:38.017961 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-03-19 14:42:38.017981 | 
2026-03-19 14:42:38.109155 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2026-03-19 14:42:38.110658 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2026-03-19 14:42:38.712847 | 
2026-03-19 14:42:38.712961 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2026-03-19 14:42:38.735222 | 
2026-03-19 14:42:38.735343 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2026-03-19 14:42:38.754717 | controller | ok
2026-03-19 14:42:38.772158 | 
2026-03-19 14:42:38.772260 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2026-03-19 14:42:38.806451 | controller | skipping: Conditional result was False
2026-03-19 14:42:38.812219 | 
2026-03-19 14:42:38.812287 | TASK [mirror-info-fork : Create /etc/ci]
2026-03-19 14:42:39.348967 | controller | ok
2026-03-19 14:42:39.361955 | 
2026-03-19 14:42:39.362097 | TASK [mirror-info-fork : Install ci_mirror script]
2026-03-19 14:42:41.416079 | controller | ok
2026-03-19 14:42:41.440232 | 
2026-03-19 14:42:41.440398 | TASK [Prepare workspace]
2026-03-19 14:42:41.476765 | controller | ok
2026-03-19 14:42:41.516215 | 
2026-03-19 14:42:41.516363 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-03-19 14:42:42.042689 | controller | ok
2026-03-19 14:42:42.057052 | 
2026-03-19 14:42:42.057207 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-03-19 14:42:44.880264 | controller | Output suppressed because no_log was given
2026-03-19 14:42:44.893963 | 
2026-03-19 14:42:44.894066 | LOOP [Create zuul-output directory]
2026-03-19 14:42:45.261710 | controller | changed: "/home/zuul/ci-framework-data/logs"
2026-03-19 14:42:45.593649 | controller | ok: "/home/zuul/zuul-output/logs"
2026-03-19 14:42:45.616340 | 
2026-03-19 14:42:45.616537 | TASK [Install required packages]
2026-03-19 14:43:05.581388 | controller | ok: Nothing to do
2026-03-19 14:43:05.586963 | 
2026-03-19 14:43:05.587025 | TASK [Install venv]
2026-03-19 14:44:32.501879 | controller | changed
2026-03-19 14:44:32.585353 | 
2026-03-19 14:44:32.585463 | PLAY RECAP
2026-03-19 14:44:32.585502 | controller | ok: 7 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-03-19 14:44:32.585522 | 
2026-03-19 14:44:32.700186 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2026-03-19 14:44:32.701011 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2026-03-19 14:44:33.293613 | 
2026-03-19 14:44:33.293745 | PLAY [Run ci/playbooks/molecule-test.yml]
2026-03-19 14:44:33.315335 | 
2026-03-19 14:44:33.315417 | TASK [Gather required facts]
2026-03-19 14:44:33.991591 | controller | ok
2026-03-19 14:44:34.004803 | 
2026-03-19 14:44:34.004978 | TASK [Load environment var if instructed to]
2026-03-19 14:44:34.032269 | controller | skipping: Conditional result was False
2026-03-19 14:44:34.044467 | 
2026-03-19 14:44:34.044625 | TASK [Ensure group_vars dir exists]
2026-03-19 14:44:34.508320 | controller | ok
2026-03-19 14:44:34.515696 | 
2026-03-19 14:44:34.515816 | TASK [Print related variables]
2026-03-19 14:44:34.549996 | controller | ok:
2026-03-19 14:44:34.550209 | controller | mol_config_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config_local.yml
2026-03-19 14:44:34.550256 | controller | roles_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/operator_deploy
2026-03-19 14:44:34.558457 | 
2026-03-19 14:44:34.558578 | TASK [Run molecule]
2026-03-19 14:44:35.798936 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2026-03-19 14:44:35.870558 | controller | INFO     Performing prerun with role_name_check=0...
2026-03-19 14:45:21.318477 | controller | WARNING  Another version of '@NAMESPACE@.@NAME@' 3.1.4 was found installed in /home/zuul/.cache/ansible-compat/9ee428/collections/ansible_collections, only the first one will be used, 3.1.4 (/home/zuul/.ansible/collections/ansible_collections).
2026-03-19 14:45:21.319486 | controller | WARNING  Another version of 'ansible.netcommon' 7.1.0 was found installed in /home/zuul/.cache/ansible-compat/9ee428/collections/ansible_collections, only the first one will be used, 7.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-03-19 14:45:21.320539 | controller | WARNING  Another version of 'ansible.posix' 1.6.2 was found installed in /home/zuul/.cache/ansible-compat/9ee428/collections/ansible_collections, only the first one will be used, 1.6.2 (/home/zuul/.ansible/collections/ansible_collections).
2026-03-19 14:45:21.321558 | controller | WARNING  Another version of 'ansible.utils' 5.1.2 was found installed in /home/zuul/.cache/ansible-compat/9ee428/collections/ansible_collections, only the first one will be used, 5.1.2 (/home/zuul/.ansible/collections/ansible_collections).
2026-03-19 14:45:21.322651 | controller | WARNING  Another version of 'cifmw.general' 1.0.0+27b616e1 was found installed in /home/zuul/.cache/ansible-compat/9ee428/collections/ansible_collections, only the first one will be used, 1.0.0+27b616e1 (/home/zuul/.ansible/collections/ansible_collections).
2026-03-19 14:45:21.323688 | controller | WARNING  Another version of 'cisco.ios' 9.0.3 was found installed in /home/zuul/.cache/ansible-compat/9ee428/collections/ansible_collections, only the first one will be used, 9.0.3 (/home/zuul/.ansible/collections/ansible_collections).
2026-03-19 14:45:21.324792 | controller | WARNING  Another version of 'community.crypto' 2.22.3 was found installed in /home/zuul/.cache/ansible-compat/9ee428/collections/ansible_collections, only the first one will be used, 2.22.3 (/home/zuul/.ansible/collections/ansible_collections).
2026-03-19 14:45:21.325861 | controller | WARNING  Another version of 'community.general' 10.0.1 was found installed in /home/zuul/.cache/ansible-compat/9ee428/collections/ansible_collections, only the first one will be used, 10.0.1 (/home/zuul/.ansible/collections/ansible_collections).
2026-03-19 14:45:21.327135 | controller | WARNING  Another version of 'community.libvirt' 1.3.0 was found installed in /home/zuul/.cache/ansible-compat/9ee428/collections/ansible_collections, only the first one will be used, 1.3.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-03-19 14:45:21.328060 | controller | WARNING  Another version of 'community.okd' 4.0.0 was found installed in /home/zuul/.cache/ansible-compat/9ee428/collections/ansible_collections, only the first one will be used, 4.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-03-19 14:45:21.329068 | controller | WARNING  Another version of 'containers.podman' 1.16.2 was found installed in /home/zuul/.cache/ansible-compat/9ee428/collections/ansible_collections, only the first one will be used, 1.16.2 (/home/zuul/.ansible/collections/ansible_collections).
2026-03-19 14:45:21.329991 | controller | WARNING  Another version of 'junipernetworks.junos' 9.1.0 was found installed in /home/zuul/.cache/ansible-compat/9ee428/collections/ansible_collections, only the first one will be used, 9.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-03-19 14:45:21.331005 | controller | WARNING  Another version of 'kubernetes.core' 5.0.0 was found installed in /home/zuul/.cache/ansible-compat/9ee428/collections/ansible_collections, only the first one will be used, 5.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-03-19 14:45:21.331996 | controller | WARNING  Another version of 'mellanox.onyx' 1.0.0 was found installed in /home/zuul/.cache/ansible-compat/9ee428/collections/ansible_collections, only the first one will be used, 1.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2026-03-19 14:45:21.332998 | controller | WARNING  Another version of 'openstack.config_template' 2.1.1 was found installed in /home/zuul/.cache/ansible-compat/9ee428/collections/ansible_collections, only the first one will be used, 2.1.1 (/home/zuul/.ansible/collections/ansible_collections).
2026-03-19 14:45:21.353704 | controller | INFO     Running default > prepare
2026-03-19 14:45:22.033585 | controller |
2026-03-19 14:45:22.033664 | controller | PLAY [Prepare] *****************************************************************
2026-03-19 14:45:22.033773 | controller |
2026-03-19 14:45:22.033932 | controller | TASK [Gathering Facts] *********************************************************
2026-03-19 14:45:22.034081 | controller | Thursday 19 March 2026  14:45:22 +0000 (0:00:00.018)       0:00:00.018 ********
2026-03-19 14:45:23.080301 | controller | ok: [instance]
2026-03-19 14:45:23.080433 | controller |
2026-03-19 14:45:23.080453 | controller | TASK [test_deps : Gather facts if they don't exist] ****************************
2026-03-19 14:45:23.080530 | controller | Thursday 19 March 2026  14:45:23 +0000 (0:00:01.047)       0:00:01.065 ********
2026-03-19 14:45:23.110806 | controller | skipping: [instance]
2026-03-19 14:45:23.110859 | controller |
2026-03-19 14:45:23.110944 | controller | TASK [test_deps : Gather variables for each operating system] ******************
2026-03-19 14:45:23.111062 | controller | Thursday 19 March 2026  14:45:23 +0000 (0:00:00.030)       0:00:01.096 ********
2026-03-19 14:45:23.187818 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/test_deps/vars/centos-9.yml)
2026-03-19 14:45:23.187995 | controller |
2026-03-19 14:45:23.188269 | controller | TASK [test_deps : Detect mirrors file] *****************************************
2026-03-19 14:45:23.188487 | controller | Thursday 19 March 2026  14:45:23 +0000 (0:00:00.076)       0:00:01.173 ********
2026-03-19 14:45:23.563916 | controller | ok: [instance]
2026-03-19 14:45:23.563971 | controller |
2026-03-19 14:45:23.564084 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] ***********
2026-03-19 14:45:23.564176 | controller | Thursday 19 March 2026  14:45:23 +0000 (0:00:00.376)       0:00:01.549 ********
2026-03-19 14:45:23.955657 | controller | ok: [instance]
2026-03-19 14:45:23.955820 | controller |
2026-03-19 14:45:23.956045 | controller | TASK [test_deps : Disable ubi 9 repos] *****************************************
2026-03-19 14:45:23.956293 | controller | Thursday 19 March 2026  14:45:23 +0000 (0:00:00.391)       0:00:01.941 ********
2026-03-19 14:45:24.914086 | controller | ok: [instance] => (item=ubi-9-appstream)
2026-03-19 14:45:24.914160 | controller | ok: [instance] => (item=ubi-9-baseos)
2026-03-19 14:45:24.914202 | controller | ok: [instance] => (item=ubi-9-codeready-builder)
2026-03-19 14:45:24.914305 | controller |
2026-03-19 14:45:24.914403 | controller | TASK [Deploy repo-setup] *******************************************************
2026-03-19 14:45:24.914492 | controller | Thursday 19 March 2026  14:45:24 +0000 (0:00:00.958)       0:00:02.899 ********
2026-03-19 14:45:25.010460 | controller |
2026-03-19 14:45:25.010542 | controller | TASK [repo_setup : Ensure directories are present] *****************************
2026-03-19 14:45:25.010811 | controller | Thursday 19 March 2026  14:45:25 +0000 (0:00:00.096)       0:00:02.996 ********
2026-03-19 14:45:25.700971 | controller | changed: [instance] => (item=tmp)
2026-03-19 14:45:25.701101 | controller | changed: [instance] => (item=artifacts/repositories)
2026-03-19 14:45:25.701264 | controller | changed: [instance] => (item=venv/repo_setup)
2026-03-19 14:45:25.701436 | controller |
2026-03-19 14:45:25.701587 | controller | TASK [repo_setup : Make sure git-core package is installed] ********************
2026-03-19 14:45:25.701724 | controller | Thursday 19 March 2026  14:45:25 +0000 (0:00:00.690)       0:00:03.686 ********
2026-03-19 14:45:26.674926 | controller | ok: [instance]
2026-03-19 14:45:26.675004 | controller |
2026-03-19 14:45:26.675292 | controller | TASK [repo_setup : Get repo-setup repository] **********************************
2026-03-19 14:45:28.221237 | controller | Thursday 19 March 2026  14:45:26 +0000 (0:00:00.974)       0:00:04.660 ********
2026-03-19 14:45:28.221339 | controller | changed: [instance]
2026-03-19 14:45:28.221398 | controller |
2026-03-19 14:45:28.222134 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************
2026-03-19 14:45:36.394949 | controller | Thursday 19 March 2026  14:45:28 +0000 (0:00:01.545)       0:00:06.206 ********
2026-03-19 14:45:36.395000 | controller | changed: [instance]
2026-03-19 14:45:37.195928 | controller |
2026-03-19 14:45:37.195984 | controller | TASK [repo_setup : Install repo-setup package] *********************************
2026-03-19 14:45:37.195998 | controller | Thursday 19 March 2026  14:45:36 +0000 (0:00:08.173)       0:00:14.379 ********
2026-03-19 14:45:37.196015 | controller | changed: [instance]
2026-03-19 14:45:37.229284 | controller |
2026-03-19 14:45:37.229337 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
2026-03-19 14:45:37.229351 | controller | Thursday 19 March 2026  14:45:37 +0000 (0:00:00.800)       0:00:15.180 ********
2026-03-19 14:45:37.229368 | controller | skipping: [instance]
2026-03-19 14:45:37.229410 | controller |
2026-03-19 14:45:37.229427 | controller | TASK [repo_setup : Run repo-setup] *********************************************
2026-03-19 14:45:37.229540 | controller | Thursday 19 March 2026  14:45:37 +0000 (0:00:00.034)       0:00:15.215 ********
2026-03-19 14:45:37.952928 | controller | changed: [instance]
2026-03-19 14:45:38.019804 | controller |
2026-03-19 14:45:38.019844 | controller | TASK [repo_setup : Get component repo] *****************************************
2026-03-19 14:45:38.019858 | controller | Thursday 19 March 2026  14:45:37 +0000 (0:00:00.722)       0:00:15.937 ********
2026-03-19 14:45:38.019874 | controller | skipping: [instance]
2026-03-19 14:45:38.090208 | controller |
2026-03-19 14:45:38.090253 | controller | TASK [repo_setup : Rename component repo] **************************************
2026-03-19 14:45:38.090259 | controller | Thursday 19 March 2026  14:45:38 +0000 (0:00:00.066)       0:00:16.004 ********
2026-03-19 14:45:38.090270 | controller | skipping: [instance]
2026-03-19 14:45:38.090424 | controller |
2026-03-19 14:45:38.090471 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
2026-03-19 14:45:38.090492 | controller | Thursday 19 March 2026  14:45:38 +0000 (0:00:00.070)       0:00:16.075 ********
2026-03-19 14:45:38.140269 | controller | skipping: [instance]
2026-03-19 14:45:38.140544 | controller |
2026-03-19 14:45:38.140567 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************
2026-03-19 14:45:38.140710 | controller | Thursday 19 March 2026  14:45:38 +0000 (0:00:00.050)       0:00:16.125 ********
2026-03-19 14:45:38.703136 | controller | changed: [instance]
2026-03-19 14:45:38.703888 | controller |
2026-03-19 14:45:39.291436 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
2026-03-19 14:45:39.291477 | controller | Thursday 19 March 2026  14:45:38 +0000 (0:00:00.562)       0:00:16.688 ********
2026-03-19 14:45:39.291492 | controller | changed: [instance]
2026-03-19 14:45:39.350684 | controller |
2026-03-19 14:45:39.350727 | controller | TASK [repo_setup : Dump current-podified hash] *********************************
2026-03-19 14:45:39.350737 | controller | Thursday 19 March 2026  14:45:39 +0000 (0:00:00.588)       0:00:17.277 ********
2026-03-19 14:45:39.350751 | controller | skipping: [instance]
2026-03-19 14:45:39.401270 | controller |
2026-03-19 14:45:39.401364 | controller | TASK [repo_setup : Slurp current podified hash] ********************************
2026-03-19 14:45:39.401379 | controller | Thursday 19 March 2026  14:45:39 +0000 (0:00:00.058)       0:00:17.335 ********
2026-03-19 14:45:39.401429 | controller | skipping: [instance]
2026-03-19 14:45:39.401464 | controller |
2026-03-19 14:45:39.401483 | controller | TASK [repo_setup : Update the value of full_hash] ******************************
2026-03-19 14:45:39.401711 | controller | Thursday 19 March 2026  14:45:39 +0000 (0:00:00.051)       0:00:17.386 ********
2026-03-19 14:45:39.456690 | controller | skipping: [instance]
2026-03-19 14:45:39.456925 | controller |
2026-03-19 14:45:39.457013 | controller | TASK [repo_setup : Export hashes facts for further use] ************************
2026-03-19 14:45:39.457269 | controller | Thursday 19 March 2026  14:45:39 +0000 (0:00:00.055)       0:00:17.442 ********
2026-03-19 14:45:39.532354 | controller | ok: [instance]
2026-03-19 14:45:39.532545 | controller |
2026-03-19 14:45:39.532832 | controller | TASK [repo_setup : Create download directory] **********************************
2026-03-19 14:45:39.533133 | controller | Thursday 19 March 2026  14:45:39 +0000 (0:00:00.075)       0:00:17.517 ********
2026-03-19 14:45:39.583913 | controller | skipping: [instance]
2026-03-19 14:45:39.584088 | controller |
2026-03-19 14:45:39.584382 | controller | TASK [repo_setup : Print the URL to request] ***********************************
2026-03-19 14:45:39.584641 | controller | Thursday 19 March 2026  14:45:39 +0000 (0:00:00.051)       0:00:17.569 ********
2026-03-19 14:45:39.641253 | controller | skipping: [instance]
2026-03-19 14:45:39.641468 | controller |
2026-03-19 14:45:39.641755 | controller | TASK [Download the RPM] ********************************************************
2026-03-19 14:45:39.642058 | controller | Thursday 19 March 2026  14:45:39 +0000 (0:00:00.057)       0:00:17.626 ********
2026-03-19 14:45:39.713770 | controller | skipping: [instance]
2026-03-19 14:45:39.713997 | controller |
2026-03-19 14:45:39.714353 | controller | TASK [repo_setup : Install RHOS Release tool] **********************************
2026-03-19 14:45:39.714635 | controller | Thursday 19 March 2026  14:45:39 +0000 (0:00:00.072)       0:00:17.699 ********
2026-03-19 14:45:39.769229 | controller | skipping: [instance]
2026-03-19 14:45:39.769284 | controller |
2026-03-19 14:45:39.769419 | controller | TASK [repo_setup : Get rhos-release tool version] ******************************
2026-03-19 14:45:39.769538 | controller | Thursday 19 March 2026  14:45:39 +0000 (0:00:00.055)       0:00:17.754 ********
2026-03-19 14:45:39.826647 | controller | skipping: [instance]
2026-03-19 14:45:39.826747 | controller |
2026-03-19 14:45:39.826948 | controller | TASK [repo_setup : Print rhos-release tool version] ****************************
2026-03-19 14:45:39.827255 | controller | Thursday 19 March 2026  14:45:39 +0000 (0:00:00.057)       0:00:17.812 ********
2026-03-19 14:45:39.865102 | controller | skipping: [instance]
2026-03-19 14:45:39.865216 | controller |
2026-03-19 14:45:39.865424 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
2026-03-19 14:45:39.865641 | controller | Thursday 19 March 2026  14:45:39 +0000 (0:00:00.038)       0:00:17.851 ********
2026-03-19 14:45:39.898325 | controller | skipping: [instance]
2026-03-19 14:45:39.898526 | controller |
2026-03-19 14:45:39.898799 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
2026-03-19 14:45:39.899003 | controller | Thursday 19 March 2026  14:45:39 +0000 (0:00:00.033)       0:00:17.884 ********
2026-03-19 14:45:40.155063 | controller | ok: [instance]
2026-03-19 14:45:40.155196 | controller |
2026-03-19 14:45:40.155411 | controller | TASK [repo_setup : Use RDO proxy mirrors] **************************************
2026-03-19 14:45:40.155617 | controller | Thursday 19 March 2026  14:45:40 +0000 (0:00:00.256)       0:00:18.140 ********
2026-03-19 14:45:40.429971 | controller | changed: [instance]
2026-03-19 14:45:40.774541 | controller |
2026-03-19 14:45:40.774595 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
2026-03-19 14:45:40.774609 | controller | Thursday 19 March 2026  14:45:40 +0000 (0:00:00.274)       0:00:18.415 ********
2026-03-19 14:45:40.774628 | controller | changed: [instance]
2026-03-19 14:45:40.813075 | controller |
2026-03-19 14:45:40.813118 | controller | TASK [repo_setup : Check for gating.repo file on content provider] *************
2026-03-19 14:45:40.813132 | controller | Thursday 19 March 2026  14:45:40 +0000 (0:00:00.344)       0:00:18.759 ********
2026-03-19 14:45:40.813149 | controller | skipping: [instance]
2026-03-19 14:45:40.851866 | controller |
2026-03-19 14:45:40.851908 | controller | TASK [repo_setup : Populate gating repo from content provider ip] **************
2026-03-19 14:45:40.851921 | controller | Thursday 19 March 2026  14:45:40 +0000 (0:00:00.038)       0:00:18.798 ********
2026-03-19 14:45:40.851939 | controller | skipping: [instance]
2026-03-19 14:45:40.891019 | controller |
2026-03-19 14:45:40.891094 | controller | TASK [repo_setup : Check for DLRN repo at the destination] *********************
2026-03-19 14:45:40.891108 | controller | Thursday 19 March 2026  14:45:40 +0000 (0:00:00.039)       0:00:18.837 ********
2026-03-19 14:45:40.891125 | controller | skipping: [instance]
2026-03-19 14:45:40.929771 | controller |
2026-03-19 14:45:40.929820 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
2026-03-19 14:45:40.929834 | controller | Thursday 19 March 2026  14:45:40 +0000 (0:00:00.039)       0:00:18.876 ********
2026-03-19 14:45:40.929852 | controller | skipping: [instance]
2026-03-19 14:45:40.929979 | controller |
2026-03-19 14:45:40.930301 | controller | TASK [repo_setup : Check for DLRN component repo] ******************************
2026-03-19 14:45:40.930546 | controller | Thursday 19 March 2026  14:45:40 +0000 (0:00:00.038)       0:00:18.915 ********
2026-03-19 14:45:40.969705 | controller | skipping: [instance]
2026-03-19 14:45:40.969958 | controller |
2026-03-19 14:45:40.970245 | controller | TASK [repo_setup : Lower the priority of component repos to allow installation from gating repo] ***
2026-03-19 14:45:40.970442 | controller | Thursday 19 March 2026  14:45:40 +0000 (0:00:00.040)       0:00:18.955 ********
2026-03-19 14:45:41.009699 | controller | skipping: [instance]
2026-03-19 14:45:41.010006 | controller |
2026-03-19 14:45:41.010286 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
2026-03-19 14:45:41.010532 | controller | Thursday 19 March 2026  14:45:41 +0000 (0:00:00.039)       0:00:18.995 ********
2026-03-19 14:45:41.039601 | controller | skipping: [instance]
2026-03-19 14:45:41.039859 | controller |
2026-03-19 14:45:41.040105 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
2026-03-19 14:45:41.040377 | controller | Thursday 19 March 2026  14:45:41 +0000 (0:00:00.030)       0:00:19.025 ********
2026-03-19 14:45:41.094652 | controller | skipping: [instance]
2026-03-19 14:45:41.094939 | controller |
2026-03-19 14:45:41.095237 | controller | TASK [repo_setup : Cleanup existing metadata] **********************************
2026-03-19 14:45:41.095455 | controller | Thursday 19 March 2026  14:45:41 +0000 (0:00:00.055)       0:00:19.080 ********
2026-03-19 14:45:41.127563 | controller | skipping: [instance]
2026-03-19 14:45:41.127741 | controller |
2026-03-19 14:45:41.128063 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
2026-03-19 14:45:41.128305 | controller | Thursday 19 March 2026  14:45:41 +0000 (0:00:00.032)       0:00:19.113 ********
2026-03-19 14:45:41.166089 | controller | skipping: [instance]
2026-03-19 14:45:41.166339 | controller |
2026-03-19 14:45:41.166595 | controller | TASK [test_deps : Look for redhat-release rpm] *********************************
2026-03-19 14:45:41.166846 | controller | Thursday 19 March 2026  14:45:41 +0000 (0:00:00.038)       0:00:19.151 ********
2026-03-19 14:45:41.218893 | controller | skipping: [instance]
2026-03-19 14:45:41.219185 | controller |
2026-03-19 14:45:41.219446 | controller | TASK [test_deps : Remove redhat-release] ***************************************
2026-03-19 14:45:41.219657 | controller | Thursday 19 March 2026  14:45:41 +0000 (0:00:00.053)       0:00:19.204 ********
2026-03-19 14:45:41.256152 | controller | skipping: [instance]
2026-03-19 14:45:41.256422 | controller |
2026-03-19 14:45:41.256670 | controller | TASK [test_deps : Install centos-stream-release] *******************************
2026-03-19 14:45:41.256913 | controller | Thursday 19 March 2026  14:45:41 +0000 (0:00:00.037)       0:00:19.242 ********
2026-03-19 14:45:41.294274 | controller | skipping: [instance]
2026-03-19 14:45:41.294529 | controller |
2026-03-19 14:45:41.294778 | controller | TASK [test_deps : Install additional packages] *********************************
2026-03-19 14:45:41.295084 | controller | Thursday 19 March 2026  14:45:41 +0000 (0:00:00.037)       0:00:19.280 ********
2026-03-19 14:45:41.346314 | controller | skipping: [instance]
2026-03-19 14:45:41.346498 | controller |
2026-03-19 14:45:41.346805 | controller | TASK [test_deps : Install selinux python libs] *********************************
2026-03-19 14:45:41.347075 | controller | Thursday 19 March 2026  14:45:41 +0000 (0:00:00.051)       0:00:19.332 ********
2026-03-19 14:46:23.427068 | controller | ok: [instance]
2026-03-19 14:46:24.884189 | controller |
2026-03-19 14:46:24.884245 | controller | TASK [test_deps : Install python yaml libs] ************************************
2026-03-19 14:46:24.884258 | controller | Thursday 19 March 2026  14:46:23 +0000 (0:00:42.079)       0:01:01.411 ********
2026-03-19 14:46:24.884298 | controller | ok: [instance]
2026-03-19 14:46:24.938222 | controller |
2026-03-19 14:46:24.938271 | controller | TASK [test_deps : Install extra packages] **************************************
2026-03-19 14:46:24.938284 | controller | Thursday 19 March 2026  14:46:24 +0000 (0:00:01.457)       0:01:02.869 ********
2026-03-19 14:46:24.938301 | controller | skipping: [instance]
2026-03-19 14:46:24.938579 | controller |
2026-03-19 14:46:24.938641 | controller | TASK [ci_setup : Gather variables for each operating system] *******************
2026-03-19 14:46:24.938668 | controller | Thursday 19 March 2026  14:46:24 +0000 (0:00:00.054)       0:01:02.923 ********
2026-03-19 14:46:25.009280 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_setup/vars/redhat.yml)
2026-03-19 14:46:25.009520 | controller |
2026-03-19 14:46:25.009554 | controller | TASK [ci_setup : List packages to install] *************************************
2026-03-19 14:46:25.009578 | controller | Thursday 19 March 2026  14:46:25 +0000 (0:00:00.070)       0:01:02.994 ********
2026-03-19 14:46:25.045267 | controller | ok: [instance] =>
2026-03-19 14:46:25.045603 | controller |   cifmw_ci_setup_packages:
2026-03-19 14:46:25.045900 | controller |   - bash-completion
2026-03-19 14:46:25.046240 | controller |   - ca-certificates
2026-03-19 14:46:25.046548 | controller |   - git-core
2026-03-19 14:46:25.046847 | controller |   - make
2026-03-19 14:46:25.047193 | controller |   - tar
2026-03-19 14:46:25.047508 | controller |   - tmux
2026-03-19 14:46:25.047806 | controller |   - python3-pip
2026-03-19 14:46:25.048111 | controller |
2026-03-19 14:46:25.048442 | controller | TASK [ci_setup : Install needed packages] **************************************
2026-03-19 14:46:25.049061 | controller | Thursday 19 March 2026  14:46:25 +0000 (0:00:00.036)       0:01:03.030 ********
2026-03-19 14:46:31.382624 | controller | changed: [instance]
2026-03-19 14:46:32.478545 | controller |
2026-03-19 14:46:32.478593 | controller | TASK [ci_setup : Gather version of openshift client] ***************************
2026-03-19 14:46:32.478606 | controller | Thursday 19 March 2026  14:46:31 +0000 (0:00:06.337)       0:01:09.368 ********
2026-03-19 14:46:32.478623 | controller | ok: [instance]
2026-03-19 14:46:32.706249 | controller |
2026-03-19 14:46:32.706310 | controller | TASK [ci_setup : Ensure openshift client install path is present] **************
2026-03-19 14:46:32.706323 | controller | Thursday 19 March 2026  14:46:32 +0000 (0:00:01.094)       0:01:10.463 ********
2026-03-19 14:46:32.706342 | controller | changed: [instance]
2026-03-19 14:46:32.706673 | controller |
2026-03-19 14:46:32.706727 | controller | TASK [ci_setup : Install openshift client] *************************************
2026-03-19 14:46:37.597190 | controller | Thursday 19 March 2026  14:46:32 +0000 (0:00:00.228)       0:01:10.692 ********
2026-03-19 14:46:37.597246 | controller | changed: [instance]
2026-03-19 14:46:37.597280 | controller |
2026-03-19 14:46:37.597288 | controller | TASK [ci_setup : Add the OC path to cifmw_path if needed] **********************
2026-03-19 14:46:37.597297 | controller | Thursday 19 March 2026  14:46:37 +0000 (0:00:04.889)       0:01:15.582 ********
2026-03-19 14:46:37.647993 | controller | ok: [instance]
2026-03-19 14:46:38.130128 | controller |
2026-03-19 14:46:38.130171 | controller | TASK [ci_setup : Create completion file] ***************************************
2026-03-19 14:46:38.130180 | controller | Thursday 19 March 2026  14:46:37 +0000 (0:00:00.050)       0:01:15.633 ********
2026-03-19 14:46:38.130192 | controller | changed: [instance]
2026-03-19 14:46:38.130218 | controller |
2026-03-19 14:46:38.130226 | controller | TASK [ci_setup : Source completion from within .bashrc] ************************
2026-03-19 14:46:38.130234 | controller | Thursday 19 March 2026  14:46:38 +0000 (0:00:00.482)       0:01:16.115 ********
2026-03-19 14:46:38.461982 | controller | changed: [instance]
2026-03-19 14:46:38.490795 | controller |
2026-03-19 14:46:38.490833 | controller | TASK [ci_setup : Check rhsm status] ********************************************
2026-03-19 14:46:38.490846 | controller | Thursday 19 March 2026  14:46:38 +0000 (0:00:00.331)       0:01:16.446 ********
2026-03-19 14:46:38.490861 | controller | skipping: [instance]
2026-03-19 14:46:38.512994 | controller |
2026-03-19 14:46:38.513053 | controller | TASK [ci_setup : Gather the repos to be enabled] *******************************
2026-03-19 14:46:38.513069 | controller | Thursday 19 March 2026  14:46:38 +0000 (0:00:00.029)       0:01:16.475 ********
2026-03-19 14:46:38.513085 | controller | skipping: [instance]
2026-03-19 14:46:38.535502 | controller |
2026-03-19 14:46:38.535533 | controller | TASK [ci_setup : Enabling the required repositories.] **************************
2026-03-19 14:46:38.535544 | controller | Thursday 19 March 2026  14:46:38 +0000 (0:00:00.022)       0:01:16.498 ********
2026-03-19 14:46:38.535559 | controller | skipping: [instance]
2026-03-19 14:46:38.535819 | controller |
2026-03-19 14:46:38.536133 | controller | TASK [ci_setup : Get current /etc/redhat-release] ******************************
2026-03-19 14:46:38.536411 | controller | Thursday 19 March 2026  14:46:38 +0000 (0:00:00.023)       0:01:16.521 ********
2026-03-19 14:46:38.557069 | controller | skipping: [instance]
2026-03-19 14:46:38.557358 | controller |
2026-03-19 14:46:38.557643 | controller | TASK [ci_setup : Print current /etc/redhat-release] ****************************
2026-03-19 14:46:38.557932 | controller | Thursday 19 March 2026  14:46:38 +0000 (0:00:00.021)       0:01:16.542 ********
2026-03-19 14:46:38.585067 | controller | skipping: [instance]
2026-03-19 14:46:38.585354 | controller |
2026-03-19 14:46:38.585630 | controller | TASK [ci_setup : Ensure the repos are enabled in the system using yum] *********
2026-03-19 14:46:38.585913 | controller | Thursday 19 March 2026  14:46:38 +0000 (0:00:00.027)       0:01:16.570 ********
2026-03-19 14:46:38.615923 | controller | skipping: [instance]
2026-03-19 14:46:38.616233 | controller |
2026-03-19 14:46:38.616521 | controller | TASK [ci_setup : Manage directories] *******************************************
2026-03-19 14:46:38.616793 | controller | Thursday 19 March 2026  14:46:38 +0000 (0:00:00.030)       0:01:16.601 ********
2026-03-19 14:46:39.853282 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/artifacts/manifests/openstack/cr)
2026-03-19 14:46:39.853384 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/logs)
2026-03-19 14:46:39.853414 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/tmp)
2026-03-19 14:46:39.854377 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/volumes)
2026-03-19 14:46:39.875940 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/parameters)
2026-03-19 14:46:39.875980 | controller |
2026-03-19 14:46:39.875992 | controller | TASK [Start CRC] ***************************************************************
2026-03-19 14:46:39.876002 | controller | Thursday 19 March 2026  14:46:39 +0000 (0:00:01.236)       0:01:17.838 ********
2026-03-19 14:46:39.876019 | controller |
2026-03-19 14:54:21.952565 | controller | TASK [cifmw_helpers : Ensure CRC is started] ***********************************
2026-03-19 14:54:21.952902 | controller | Thursday 19 March 2026  14:46:39 +0000 (0:00:00.022)       0:01:17.861 ********
2026-03-19 14:54:21.952932 | controller | changed: [instance]
2026-03-19 14:54:21.987232 | controller |
2026-03-19 14:54:21.987285 | controller | TASK [cifmw_helpers : Login to the OpenShift when certificate is expired] ******
2026-03-19 14:54:21.987300 | controller | Thursday 19 March 2026  14:54:21 +0000 (0:07:42.075)       0:08:59.937 ********
2026-03-19 14:54:21.987318 | controller | skipping: [instance]
2026-03-19 14:54:21.987385 | controller |
2026-03-19 14:54:21.987401 | controller | TASK [Fetch install_yamls repository] ******************************************
2026-03-19 14:54:22.001956 | controller | Thursday 19 March 2026  14:54:21 +0000 (0:00:00.035)       0:08:59.972 ********
2026-03-19 14:54:22.002007 | controller | fatal: [instance]: FAILED! =>
2026-03-19 14:54:22.002173 | controller |   msg: |-
2026-03-19 14:54:22.002185 | controller |     The task includes an option with an undefined variable. The error was: 'cifmw_install_yamls_src' is undefined. 'cifmw_install_yamls_src' is undefined
2026-03-19 14:54:22.002192 | controller |
2026-03-19 14:54:22.002199 | controller |     The error appears to be in '/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/operator_deploy/molecule/default/prepare.yml': line 32, column 7, but may
2026-03-19 14:54:22.002206 | controller |     be elsewhere in the file depending on the exact syntax problem.
2026-03-19 14:54:22.002213 | controller |
2026-03-19 14:54:22.002219 | controller |     The offending line appears to be:
2026-03-19 14:54:22.002225 | controller |
2026-03-19 14:54:22.002231 | controller |
2026-03-19 14:54:22.002237 | controller |         - name: Fetch install_yamls repository # noqa: latest[git]
2026-03-19 14:54:22.002245 | controller |           ^ here
2026-03-19 14:54:22.007097 | controller |
2026-03-19 14:54:22.007132 | controller | PLAY RECAP *********************************************************************
2026-03-19 14:54:22.007140 | controller | instance                   : ok=30   changed=16   unreachable=0    failed=1    skipped=37   rescued=0    ignored=0
2026-03-19 14:54:22.007147 | controller |
2026-03-19 14:54:22.007153 | controller | Thursday 19 March 2026  14:54:22 +0000 (0:00:00.017)       0:08:59.990 ********
2026-03-19 14:54:22.007159 | controller | ===============================================================================
2026-03-19 14:54:22.007165 | controller | cifmw_helpers : Ensure CRC is started --------------------------------- 462.08s
2026-03-19 14:54:22.007171 | controller | test_deps : Install selinux python libs -------------------------------- 42.08s
2026-03-19 14:54:22.007241 | controller | repo_setup : Initialize python venv and install requirements ------------ 8.17s
2026-03-19 14:54:22.007247 | controller | ci_setup : Install needed packages -------------------------------------- 6.34s
2026-03-19 14:54:22.007254 | controller | ci_setup : Install openshift client ------------------------------------- 4.89s
2026-03-19 14:54:22.007260 | controller | repo_setup : Get repo-setup repository ---------------------------------- 1.55s
2026-03-19 14:54:22.007277 | controller | test_deps : Install python yaml libs ------------------------------------ 1.46s
2026-03-19 14:54:22.007286 | controller | ci_setup : Manage directories ------------------------------------------- 1.24s
2026-03-19 14:54:22.007407 | controller | ci_setup : Gather version of openshift client --------------------------- 1.10s
2026-03-19 14:54:22.007654 | controller | Gathering Facts --------------------------------------------------------- 1.05s
2026-03-19 14:54:22.007912 | controller | repo_setup : Make sure git-core package is installed -------------------- 0.97s
2026-03-19 14:54:22.008217 | controller | test_deps : Disable ubi 9 repos ----------------------------------------- 0.96s
2026-03-19 14:54:22.008472 | controller | repo_setup : Install repo-setup package --------------------------------- 0.80s
2026-03-19 14:54:22.008766 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.72s
2026-03-19 14:54:22.009054 | controller | repo_setup : Ensure directories are present ----------------------------- 0.69s
2026-03-19 14:54:22.009334 | controller | repo_setup : Dump full hash in delorean.repo.md5 file ------------------- 0.59s
2026-03-19 14:54:22.009582 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.56s
2026-03-19 14:54:22.009840 | controller | ci_setup : Create completion file --------------------------------------- 0.48s
2026-03-19 14:54:22.010150 | controller | test_deps : Disable ubi host subscription-manager integration ----------- 0.39s
2026-03-19 14:54:22.010464 | controller | test_deps : Detect mirrors file ----------------------------------------- 0.38s
2026-03-19 14:54:22.068347 | controller | CRITICAL Ansible return code was 2, command was: ['ansible-playbook', '--inventory', '/home/zuul/.cache/molecule/operator_deploy/default/inventory', '--skip-tags', 'molecule-notest,notest', '/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/operator_deploy/molecule/default/prepare.yml']
2026-03-19 14:54:22.069389 | controller | WARNING  An error occurred during the test sequence action: 'prepare'. Cleaning up.
2026-03-19 14:54:22.089059 | controller | INFO     Running default > cleanup
2026-03-19 14:54:22.089596 | controller | WARNING  Skipping, cleanup playbook not configured.
2026-03-19 14:54:22.100995 | controller | INFO     Running default > destroy
2026-03-19 14:54:22.101470 | controller | WARNING  Skipping, instances are delegated.
2026-03-19 14:54:22.101918 | controller | INFO     Pruning extra files from scenario ephemeral directory
2026-03-19 14:54:22.103886 | controller | INFO     Writing /tmp/report.html report.
2026-03-19 14:54:22.300349 | controller | ERROR
2026-03-19 14:54:22.300812 | controller | {
2026-03-19 14:54:22.300893 | controller |   "delta": "0:09:47.112104",
2026-03-19 14:54:22.300939 | controller |   "end": "2026-03-19 14:54:22.161727",
2026-03-19 14:54:22.300979 | controller |   "msg": "non-zero return code",
2026-03-19 14:54:22.301016 | controller |   "rc": 1,
2026-03-19 14:54:22.301056 | controller |   "start": "2026-03-19 14:44:35.049623"
2026-03-19 14:54:22.301093 | controller | }
failure
2026-03-19 14:54:22.309929 | 
2026-03-19 14:54:22.310036 | PLAY RECAP
2026-03-19 14:54:22.310116 | controller | ok: 3 changed: 0 unreachable: 0 failed: 1 skipped: 1 rescued: 0 ignored: 0
2026-03-19 14:54:22.310161 | 
2026-03-19 14:54:22.420407 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2026-03-19 14:54:22.421274 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2026-03-19 14:54:23.017713 | 
2026-03-19 14:54:23.017850 | PLAY [Run ci/playbooks/collect-logs.yml]
2026-03-19 14:54:23.040513 | 
2026-03-19 14:54:23.040621 | TASK [Filter out host if needed]
2026-03-19 14:54:23.049784 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2026-03-19 14:54:23.054462 | 
2026-03-19 14:54:23.054524 | TASK [Ensure file is present]
2026-03-19 14:54:23.632328 | controller | ok
2026-03-19 14:54:23.638109 | 
2026-03-19 14:54:23.638188 | TASK [Manage molecule report file]
2026-03-19 14:54:25.192700 | controller | changed
2026-03-19 14:54:25.204606 | 
2026-03-19 14:54:25.204793 | TASK [Check if we get ci-framework-data basedir]
2026-03-19 14:54:25.579900 | controller | ok
2026-03-19 14:54:25.591919 | 
2026-03-19 14:54:25.592058 | TASK [Create ci-framework-data log directory for zuul]
2026-03-19 14:54:26.085259 | controller | changed
2026-03-19 14:54:26.099679 | 
2026-03-19 14:54:26.099871 | TASK [Copy ci-framework interesting files]
2026-03-19 14:54:31.818935 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2026-03-19 14:54:34.579026 | controller | changed
2026-03-19 14:54:34.590366 | 
2026-03-19 14:54:34.590517 | TASK [Get SELinux listing]
2026-03-19 14:54:35.138835 | controller | changed
2026-03-19 14:54:35.147461 | 
2026-03-19 14:54:35.147571 | TASK [Generate log index]
2026-03-19 14:54:36.511361 | controller | changed
2026-03-19 14:54:36.523601 | 
2026-03-19 14:54:36.523809 | TASK [Get some env related data]
2026-03-19 14:54:37.461807 | controller | /home/zuul/.local/bin/ansible
2026-03-19 14:54:38.576528 | controller | changed
2026-03-19 14:54:38.588376 | 
2026-03-19 14:54:38.588529 | TASK [Generate list of logs to collect in home directory]
2026-03-19 14:54:39.078370 | controller | ok: All paths examined
2026-03-19 14:54:39.092257 | 
2026-03-19 14:54:39.092412 | LOOP [Copy logs from home directory]
2026-03-19 14:54:39.809923 | controller | changed:
2026-03-19 14:54:39.810369 | controller | {
2026-03-19 14:54:39.810427 | controller |   "atime": 1773350327.2786021,
2026-03-19 14:54:39.810468 | controller |   "ctime": 1773351461.47114,
2026-03-19 14:54:39.810505 | controller |   "dev": 64513,
2026-03-19 14:54:39.810539 | controller |   "gid": 1000,
2026-03-19 14:54:39.810586 | controller |   "gr_name": "zuul",
2026-03-19 14:54:39.810622 | controller |   "inode": 6822,
2026-03-19 14:54:39.810656 | controller |   "isblk": false,
2026-03-19 14:54:39.810690 | controller |   "ischr": false,
2026-03-19 14:54:39.810755 | controller |   "isdir": false,
2026-03-19 14:54:39.810801 | controller |   "isfifo": false,
2026-03-19 14:54:39.810836 | controller |   "isgid": false,
2026-03-19 14:54:39.810870 | controller |   "islnk": false,
2026-03-19 14:54:39.810902 | controller |   "isreg": true,
2026-03-19 14:54:39.810934 | controller |   "issock": false,
2026-03-19 14:54:39.810967 | controller |   "isuid": false,
2026-03-19 14:54:39.810998 | controller |   "mode": "0644",
2026-03-19 14:54:39.811030 | controller |   "mtime": 1773351461.47114,
2026-03-19 14:54:39.811062 | controller |   "nlink": 1,
2026-03-19 14:54:39.811096 | controller |   "path": "/home/zuul/crc-setup.log",
2026-03-19 14:54:39.811146 | controller |   "pw_name": "zuul",
2026-03-19 14:54:39.811183 | controller |   "rgrp": true,
2026-03-19 14:54:39.811217 | controller |   "roth": true,
2026-03-19 14:54:39.811251 | controller |   "rusr": true,
2026-03-19 14:54:39.811285 | controller |   "size": 4108,
2026-03-19 14:54:39.811318 | controller |   "uid": 1000,
2026-03-19 14:54:39.811351 | controller |   "wgrp": false,
2026-03-19 14:54:39.811384 | controller |   "woth": false,
2026-03-19 14:54:39.811422 | controller |   "wusr": true,
2026-03-19 14:54:39.811458 | controller |   "xgrp": false,
2026-03-19 14:54:39.811492 | controller |   "xoth": false,
2026-03-19 14:54:39.811529 | controller |   "xusr": false
2026-03-19 14:54:39.811562 | controller | }
2026-03-19 14:54:40.467408 | controller | changed:
2026-03-19 14:54:40.467576 | controller | {
2026-03-19 14:54:40.467625 | controller |   "atime": 1773351463.1371913,
2026-03-19 14:54:40.467665 | controller |   "ctime": 1773352236.2951868,
2026-03-19 14:54:40.467875 | controller |   "dev": 64513,
2026-03-19 14:54:40.467932 | controller |   "gid": 1000,
2026-03-19 14:54:40.467984 | controller |   "gr_name": "zuul",
2026-03-19 14:54:40.468021 | controller |   "inode": 6833,
2026-03-19 14:54:40.468053 | controller |   "isblk": false,
2026-03-19 14:54:40.468086 | controller |   "ischr": false,
2026-03-19 14:54:40.468117 | controller |   "isdir": false,
2026-03-19 14:54:40.468149 | controller |   "isfifo": false,
2026-03-19 14:54:40.468181 | controller |   "isgid": false,
2026-03-19 14:54:40.468212 | controller |   "islnk": false,
2026-03-19 14:54:40.468243 | controller |   "isreg": true,
2026-03-19 14:54:40.468276 | controller |   "issock": false,
2026-03-19 14:54:40.468307 | controller |   "isuid": false,
2026-03-19 14:54:40.468338 | controller |   "mode": "0644",
2026-03-19 14:54:40.468370 | controller |   "mtime": 1773352236.2951868,
2026-03-19 14:54:40.468404 | controller |   "nlink": 1,
2026-03-19 14:54:40.468438 | controller |   "path": "/home/zuul/crc-start.log",
2026-03-19 14:54:40.468471 | controller |   "pw_name": "zuul",
2026-03-19 14:54:40.468506 | controller |   "rgrp": true,
2026-03-19 14:54:40.468540 | controller |   "roth": true,
2026-03-19 14:54:40.468574 | controller |   "rusr": true,
2026-03-19 14:54:40.468608 | controller |   "size": 4527,
2026-03-19 14:54:40.468642 | controller |   "uid": 1000,
2026-03-19 14:54:40.468676 | controller |   "wgrp": false,
2026-03-19 14:54:40.468712 | controller |   "woth": false,
2026-03-19 14:54:40.468836 | controller |   "wusr": true,
2026-03-19 14:54:40.468876 | controller |   "xgrp": false,
2026-03-19 14:54:40.468910 | controller |   "xoth": false,
2026-03-19 14:54:40.468944 | controller |   "xusr": false
2026-03-19 14:54:40.468977 | controller | }
2026-03-19 14:54:41.167067 | controller | changed:
2026-03-19 14:54:41.167235 | controller | {
2026-03-19 14:54:41.167286 | controller |   "atime": 1773931433.2688708,
2026-03-19 14:54:41.167325 | controller |   "ctime": 1773931472.021604,
2026-03-19 14:54:41.167360 | controller |   "dev": 64513,
2026-03-19 14:54:41.167395 | controller |   "gid": 1000,
2026-03-19 14:54:41.167438 | controller |   "gr_name": "zuul",
2026-03-19 14:54:41.167472 | controller |   "inode": 6830,
2026-03-19 14:54:41.167505 | controller |   "isblk": false,
2026-03-19 14:54:41.167537 | controller |   "ischr": false,
2026-03-19 14:54:41.167568 | controller |   "isdir": false,
2026-03-19 14:54:41.167600 | controller |   "isfifo": false,
2026-03-19 14:54:41.167631 | controller |   "isgid": false,
2026-03-19 14:54:41.167663 | controller |   "islnk": false,
2026-03-19 14:54:41.167695 | controller |   "isreg": true,
2026-03-19 14:54:41.167759 | controller |   "issock": false,
2026-03-19 14:54:41.167803 | controller |   "isuid": false,
2026-03-19 14:54:41.167836 | controller |   "mode": "0644",
2026-03-19 14:54:41.167868 | controller |   "mtime": 1773931472.021604,
2026-03-19 14:54:41.167900 | controller |   "nlink": 1,
2026-03-19 14:54:41.167933 | controller |   "path": "/home/zuul/ansible.log",
2026-03-19 14:54:41.167966 | controller |   "pw_name": "zuul",
2026-03-19 14:54:41.167999 | controller |   "rgrp": true,
2026-03-19 14:54:41.168035 | controller |   "roth": true,
2026-03-19 14:54:41.168075 | controller |   "rusr": true,
2026-03-19 14:54:41.168112 | controller |   "size": 6701,
2026-03-19 14:54:41.168147 | controller |   "uid": 1000,
2026-03-19 14:54:41.168181 | controller |   "wgrp": false,
2026-03-19 14:54:41.168215 | controller |   "woth": false,
2026-03-19 14:54:41.168247 | controller |   "wusr": true,
2026-03-19 14:54:41.168279 | controller |   "xgrp": false,
2026-03-19 14:54:41.168311 | controller |   "xoth": false,
2026-03-19 14:54:41.168345 | controller |   "xusr": false
2026-03-19 14:54:41.168378 | controller | }
2026-03-19 14:54:41.196703 | 
2026-03-19 14:54:41.196998 | TASK [Copy crio stats log file]
2026-03-19 14:54:41.225884 | controller | skipping: Conditional result was False
2026-03-19 14:54:41.238975 | 
2026-03-19 14:54:41.239134 | TASK [Get SELinux related data]
2026-03-19 14:54:41.607867 | controller | <no matches>
2026-03-19 14:54:41.794707 | controller | ERROR
2026-03-19 14:54:41.795193 | controller | {
2026-03-19 14:54:41.795255 | controller |   "delta": "0:00:00.010840",
2026-03-19 14:54:41.795295 | controller |   "end": "2026-03-19 14:54:41.608817",
2026-03-19 14:54:41.795331 | controller |   "msg": "non-zero return code",
2026-03-19 14:54:41.795365 | controller |   "rc": 1,
2026-03-19 14:54:41.795398 | controller |   "start": "2026-03-19 14:54:41.597977"
2026-03-19 14:54:41.795430 | controller | }
2026-03-19 14:54:41.795479 | controller | ERROR: Ignoring Errors
2026-03-19 14:54:41.807860 | 
2026-03-19 14:54:41.808033 | TASK [Create system configuration directory]
2026-03-19 14:54:42.136954 | controller | changed
2026-03-19 14:54:42.150632 | 
2026-03-19 14:54:42.150856 | TASK [Get some of the system configurations]
2026-03-19 14:54:42.699882 | controller | changed
2026-03-19 14:54:42.713271 | 
2026-03-19 14:54:42.713496 | TASK [Copy generated documentation if available]
2026-03-19 14:54:42.742206 | controller | skipping: Conditional result was False
2026-03-19 14:54:42.757665 | 
2026-03-19 14:54:42.757923 | TASK [Copy generated AsciiDoc documentation if available]
2026-03-19 14:54:42.787322 | controller | skipping: Conditional result was False
2026-03-19 14:54:42.800344 | 
2026-03-19 14:54:42.800500 | TASK [Compress logs bigger than 2MB]
2026-03-19 14:54:43.347437 | controller | changed
2026-03-19 14:54:43.363117 | 
2026-03-19 14:54:43.363354 | TASK [Copy files from workspace on node]
2026-03-19 14:54:43.387571 | controller | ok
2026-03-19 14:54:43.412002 | 
2026-03-19 14:54:43.412166 | TASK [fetch-output : Set log path for multiple nodes]
2026-03-19 14:54:43.437589 | controller | skipping: Conditional result was False
2026-03-19 14:54:43.445380 | 
2026-03-19 14:54:43.445462 | TASK [fetch-output : Set log path for single node]
2026-03-19 14:54:43.476467 | controller | ok
2026-03-19 14:54:43.481932 | 
2026-03-19 14:54:43.481997 | LOOP [fetch-output : Ensure local output dirs]
2026-03-19 14:54:43.767710 | controller -> localhost | ok: "/var/lib/zuul/builds/a2f0fdd6bc094b24b8be3ae56e69bd0d/work/logs"
2026-03-19 14:54:43.768131 | controller -> localhost | changed: All items complete
2026-03-19 14:54:43.768176 | 
2026-03-19 14:54:44.065718 | controller -> localhost | changed: "/var/lib/zuul/builds/a2f0fdd6bc094b24b8be3ae56e69bd0d/work/artifacts"
2026-03-19 14:54:44.307175 | controller -> localhost | changed: "/var/lib/zuul/builds/a2f0fdd6bc094b24b8be3ae56e69bd0d/work/docs"
2026-03-19 14:54:44.322190 | 
2026-03-19 14:54:44.322321 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-03-19 14:54:45.148354 | controller | changed:
2026-03-19 14:54:45.148682 | controller | .d..t...... ./
2026-03-19 14:54:45.148776 | controller | >f+++++++++ README.html
2026-03-19 14:54:45.148840 | controller | >f+++++++++ ansible-execution.log
2026-03-19 14:54:45.148893 | controller | >f+++++++++ ansible.log
2026-03-19 14:54:45.148933 | controller | >f+++++++++ crc-setup.log
2026-03-19 14:54:45.148970 | controller | >f+++++++++ crc-start.log
2026-03-19 14:54:45.149009 | controller | >f+++++++++ dmesg.log
2026-03-19 14:54:45.149048 | controller | >f+++++++++ installed-pkgs.log
2026-03-19 14:54:45.149085 | controller | >f+++++++++ python.log
2026-03-19 14:54:45.149122 | controller | >f+++++++++ registries.conf
2026-03-19 14:54:45.149158 | controller | >f+++++++++ report.html
2026-03-19 14:54:45.149193 | controller | >f+++++++++ selinux-denials.log
2026-03-19 14:54:45.149226 | controller | >f+++++++++ selinux-listing.log
2026-03-19 14:54:45.149260 | controller | cd+++++++++ ci-framework-data/
2026-03-19 14:54:45.149296 | controller | cd+++++++++ ci-framework-data/artifacts/
2026-03-19 14:54:45.149330 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2026-03-19 14:54:45.149365 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/
2026-03-19 14:54:45.149401 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/openstack/
2026-03-19 14:54:45.149436 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/openstack/cr/
2026-03-19 14:54:45.149470 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2026-03-19 14:54:45.149504 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2026-03-19 14:54:45.149539 | controller | cd+++++++++ ci-framework-data/artifacts/repositories/
2026-03-19 14:54:45.149576 | controller | >f+++++++++ ci-framework-data/artifacts/repositories/delorean.repo.md5
2026-03-19 14:54:45.149612 | controller | cd+++++++++ ci-framework-data/logs/
2026-03-19 14:54:45.149649 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2026-03-19 14:54:45.149685 | controller | cd+++++++++ registries.conf.d/
2026-03-19 14:54:45.149720 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2026-03-19 14:54:45.149795 | controller | cd+++++++++ system-config/
2026-03-19 14:54:45.149834 | controller | cd+++++++++ system-config/libvirt/
2026-03-19 14:54:45.149871 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2026-03-19 14:54:45.149910 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2026-03-19 14:54:45.149957 | controller | >f+++++++++ system-config/libvirt/libvirtd.conf
2026-03-19 14:54:45.149995 | controller | >f+++++++++ system-config/libvirt/network.conf
2026-03-19 14:54:45.150030 | controller | >f+++++++++ system-config/libvirt/qemu-lockd.conf
2026-03-19 14:54:45.150065 | controller | >f+++++++++ system-config/libvirt/qemu.conf
2026-03-19 14:54:45.150098 | controller | >f+++++++++ system-config/libvirt/virtinterfaced.conf
2026-03-19 14:54:45.150133 | controller | >f+++++++++ system-config/libvirt/virtlockd.conf
2026-03-19 14:54:45.150166 | controller | >f+++++++++ system-config/libvirt/virtlogd.conf
2026-03-19 14:54:45.150200 | controller | >f+++++++++ system-config/libvirt/virtnetworkd.conf
2026-03-19 14:54:45.150232 | controller | >f+++++++++ system-config/libvirt/virtnodedevd.conf
2026-03-19 14:54:45.150266 | controller | >f+++++++++ system-config/libvirt/virtnwfilterd.conf
2026-03-19 14:54:45.150299 | controller | >f+++++++++ system-config/libvirt/virtproxyd.conf
2026-03-19 14:54:45.150345 | controller | >f+++++++++ system-config/libvirt/virtqemud.conf
2026-03-19 14:54:45.150380 | controller | >f+++++++++ system-config/libvirt/virtsecretd.conf
2026-03-19 14:54:45.150414 | controller | >f+++++++++ system-config/libvirt/virtstoraged.conf
2026-03-19 14:54:45.710418 | controller | changed: .d..t...... ./
2026-03-19 14:54:46.333032 | controller | changed: .d..t...... ./
2026-03-19 14:54:46.366324 | 
2026-03-19 14:54:46.366494 | TASK [Return artifact to Zuul]
2026-03-19 14:54:46.411509 | controller | ok
2026-03-19 14:54:46.438412 | 
2026-03-19 14:54:46.438590 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2026-03-19 14:54:46.438742 | 
2026-03-19 14:54:46.438786 | PLAY RECAP
2026-03-19 14:54:46.438836 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2026-03-19 14:54:46.438865 | 
2026-03-19 14:54:46.568843 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2026-03-19 14:54:46.569633 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2026-03-19 14:54:47.141655 | 
2026-03-19 14:54:47.141820 | PLAY [all]
2026-03-19 14:54:47.161840 | 
2026-03-19 14:54:47.161980 | TASK [include_role : fetch-output]
2026-03-19 14:54:47.201964 | controller | ok
2026-03-19 14:54:47.220224 | 
2026-03-19 14:54:47.220374 | TASK [fetch-output : Set log path for multiple nodes]
2026-03-19 14:54:47.265464 | controller | skipping: Conditional result was False
2026-03-19 14:54:47.271493 | 
2026-03-19 14:54:47.271620 | TASK [fetch-output : Set log path for single node]
2026-03-19 14:54:47.322223 | controller | ok
2026-03-19 14:54:47.328934 | 
2026-03-19 14:54:47.329060 | LOOP [fetch-output : Ensure local output dirs]
2026-03-19 14:54:47.760379 | controller -> localhost | ok: "/var/lib/zuul/builds/a2f0fdd6bc094b24b8be3ae56e69bd0d/work/logs"
2026-03-19 14:54:48.043867 | controller -> localhost | ok: "/var/lib/zuul/builds/a2f0fdd6bc094b24b8be3ae56e69bd0d/work/artifacts"
2026-03-19 14:54:48.309281 | controller -> localhost | ok: "/var/lib/zuul/builds/a2f0fdd6bc094b24b8be3ae56e69bd0d/work/docs"
2026-03-19 14:54:48.322760 | 
2026-03-19 14:54:48.322871 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-03-19 14:54:49.018628 | controller | ok
2026-03-19 14:54:49.018930 | controller | ok: All items complete
2026-03-19 14:54:49.018969 | 
2026-03-19 14:54:49.570844 | controller | ok
2026-03-19 14:54:50.219470 | controller | ok
2026-03-19 14:54:50.252401 | 
2026-03-19 14:54:50.252552 | TASK [include_role : fetch-output-openshift]
2026-03-19 14:54:50.278196 | controller | skipping: Conditional result was False
2026-03-19 14:54:50.287983 | 
2026-03-19 14:54:50.288082 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-03-19 14:54:50.788341 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.013492
2026-03-19 14:54:51.065882 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.012987
2026-03-19 14:54:51.100036 | 
2026-03-19 14:54:51.100213 | PLAY [all]
2026-03-19 14:54:51.121299 | 
2026-03-19 14:54:51.121432 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-03-19 14:54:51.670254 | controller | changed
2026-03-19 14:54:51.721117 | 
2026-03-19 14:54:51.721283 | PLAY RECAP
2026-03-19 14:54:51.721356 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2026-03-19 14:54:51.721398 | 
2026-03-19 14:54:51.826357 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2026-03-19 14:54:51.827918 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2026-03-19 14:54:52.446095 | 
2026-03-19 14:54:52.446286 | PLAY [localhost]
2026-03-19 14:54:52.466079 | 
2026-03-19 14:54:52.466227 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-03-19 14:54:52.918308 | localhost | changed
2026-03-19 14:54:52.929394 | 
2026-03-19 14:54:52.929575 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-03-19 14:54:52.978246 | localhost | ok
2026-03-19 14:54:52.997704 | 
2026-03-19 14:54:52.997885 | TASK [add-fileserver : Create SSH private key tempfile]
2026-03-19 14:54:53.444022 | localhost | changed
2026-03-19 14:54:53.454905 | 
2026-03-19 14:54:53.455075 | TASK [add-fileserver : Create SSH private key from secret]
2026-03-19 14:54:54.274488 | localhost | changed
2026-03-19 14:54:54.285372 | 
2026-03-19 14:54:54.285513 | TASK [add-fileserver : Add fileserver ssh key]
2026-03-19 14:54:54.765856 | localhost | Identity added: /var/lib/zuul/builds/a2f0fdd6bc094b24b8be3ae56e69bd0d/work/tmp/ansible.q8hb94k9 (/var/lib/zuul/builds/a2f0fdd6bc094b24b8be3ae56e69bd0d/work/tmp/ansible.q8hb94k9)
2026-03-19 14:54:54.766153 | localhost | ok: Runtime: 0:00:00.012563
2026-03-19 14:54:54.770960 | 
2026-03-19 14:54:54.771048 | TASK [add-fileserver : Remove SSH private key from disk]
2026-03-19 14:54:55.104965 | localhost | ok: Runtime: 0:00:00.008017
2026-03-19 14:54:55.116068 | 
2026-03-19 14:54:55.116238 | TASK [add-fileserver : Add fileserver to inventory]
2026-03-19 14:54:55.195276 | localhost | changed
2026-03-19 14:54:55.205077 | 
2026-03-19 14:54:55.205228 | TASK [add-fileserver : Add fileserver server to known hosts]
2026-03-19 14:54:55.598177 | localhost | changed
2026-03-19 14:54:55.637644 | 
2026-03-19 14:54:55.637809 | PLAY [localhost]
2026-03-19 14:54:55.656550 | 
2026-03-19 14:54:55.656683 | TASK [Generate bulk log download script]
2026-03-19 14:54:55.678703 | localhost | ok
2026-03-19 14:54:55.696568 | 
2026-03-19 14:54:55.696720 | TASK [local-log-download : Check API endpoint is defined]
2026-03-19 14:54:55.739377 | localhost | ok: All assertions passed
2026-03-19 14:54:55.746265 | 
2026-03-19 14:54:55.746384 | TASK [local-log-download : Create download script]
2026-03-19 14:54:56.189706 | localhost -> localhost | changed
2026-03-19 14:54:56.210964 | 
2026-03-19 14:54:56.211149 | TASK [Register quick-download link]
2026-03-19 14:54:56.279890 | localhost | ok
2026-03-19 14:54:56.301455 | 
2026-03-19 14:54:56.301563 | PLAY [logserver.rdoproject.org]
2026-03-19 14:54:56.314419 | 
2026-03-19 14:54:56.314508 | TASK [Set zuul-log-path fact]
2026-03-19 14:54:56.330423 | logserver.rdoproject.org | ok
2026-03-19 14:54:56.339035 | 
2026-03-19 14:54:56.339100 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-03-19 14:54:56.364930 | logserver.rdoproject.org | ok
2026-03-19 14:54:56.369904 | 
2026-03-19 14:54:56.369981 | TASK [upload-logs : Create log directories]
2026-03-19 14:54:57.182488 | logserver.rdoproject.org | changed
2026-03-19 14:54:57.185611 | 
2026-03-19 14:54:57.185680 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-03-19 14:54:57.514962 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.009666
2026-03-19 14:54:57.524904 | 
2026-03-19 14:54:57.525051 | TASK [upload-logs : Upload logs to log server]
2026-03-19 14:54:58.332342 | logserver.rdoproject.org | Output suppressed because no_log was given
2026-03-19 14:54:58.338992 | 
2026-03-19 14:54:58.339132 | LOOP [upload-logs : Compress console log and json output]
2026-03-19 14:54:58.394326 | logserver.rdoproject.org | skipping: Conditional result was False
2026-03-19 14:54:58.406549 | logserver.rdoproject.org | skipping: Conditional result was False
2026-03-19 14:54:58.424614 | 
2026-03-19 14:54:58.424819 | LOOP [upload-logs : Upload compressed console log and json output]
2026-03-19 14:54:58.473211 | logserver.rdoproject.org | skipping: Conditional result was False
2026-03-19 14:54:58.473623 | 
2026-03-19 14:54:58.476876 | logserver.rdoproject.org | skipping: Conditional result was False
2026-03-19 14:54:58.491793 | 
2026-03-19 14:54:58.491945 | LOOP [upload-logs : Upload console log and json output]