2025-12-03 11:16:39.375850 | Job console starting...
2025-12-03 11:16:39.390192 | Updating repositories
2025-12-03 11:16:39.427759 | Preparing job workspace
2025-12-03 11:16:48.805453 | Running Ansible setup...
2025-12-03 11:16:53.920142 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-12-03 11:16:55.061825 | 
2025-12-03 11:16:55.061934 | PLAY [localhost]
2025-12-03 11:16:55.071111 | 
2025-12-03 11:16:55.071181 | TASK [Gathering Facts]
2025-12-03 11:16:56.544326 | localhost | ok
2025-12-03 11:16:56.565635 | 
2025-12-03 11:16:56.565739 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-12-03 11:16:57.341067 | localhost -> localhost | changed
2025-12-03 11:16:57.346615 | 
2025-12-03 11:16:57.346688 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-12-03 11:16:58.428576 | localhost -> localhost | changed
2025-12-03 11:16:58.439400 | 
2025-12-03 11:16:58.439492 | TASK [Setup log path fact]
2025-12-03 11:16:58.458001 | localhost | ok
2025-12-03 11:16:58.470516 | 
2025-12-03 11:16:58.470588 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-12-03 11:16:58.498852 | localhost | ok
2025-12-03 11:16:58.542157 | 
2025-12-03 11:16:58.542297 | TASK [emit-job-header : Print job information]
2025-12-03 11:16:58.587008 | # Job Information
2025-12-03 11:16:58.587221 | Ansible Version: 2.15.12
2025-12-03 11:16:58.587247 | Job: cifmw-molecule-ci_nmstate
2025-12-03 11:16:58.587266 | Pipeline: github-check
2025-12-03 11:16:58.587284 | Executor: ze01.softwarefactory-project.io
2025-12-03 11:16:58.587302 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3451
2025-12-03 11:16:58.587319 | Log URL (when completed): https://logserver.rdoproject.org/e3f/rdoproject.org/e3f79eac2708499abc53fb6760a65239/
2025-12-03 11:16:58.587337 | Event ID: 4ab514e0-d039-11f0-81e0-fb882bdc02c6
2025-12-03 11:16:58.591328 | 
2025-12-03 11:16:58.591395 | LOOP [emit-job-header : Print node information]
2025-12-03 11:16:58.693162 | localhost | ok:
2025-12-03 11:16:58.693381 | localhost | # Node Information
2025-12-03 11:16:58.693409 | localhost | Inventory Hostname: controller
2025-12-03 11:16:58.693431 | localhost | Hostname: np0005543700
2025-12-03 11:16:58.693450 | localhost | Username: zuul
2025-12-03 11:16:58.693470 | localhost | Distro: CentOS 9
2025-12-03 11:16:58.693487 | localhost | Provider: vexxhost-nodepool-tripleo
2025-12-03 11:16:58.693504 | localhost | Region: RegionOne
2025-12-03 11:16:58.693520 | localhost | Label: centos-9-stream-crc-2-48-0-xxl
2025-12-03 11:16:58.693536 | localhost | Product Name: OpenStack Nova
2025-12-03 11:16:58.693552 | localhost | Interface IP: 38.129.56.247
2025-12-03 11:16:58.717718 | 
2025-12-03 11:16:58.717788 | PLAY [all]
2025-12-03 11:16:58.724214 | 
2025-12-03 11:16:58.724272 | TASK [Gather network facts]
2025-12-03 11:16:59.322752 | controller | ok
2025-12-03 11:16:59.361985 | 
2025-12-03 11:16:59.362107 | TASK [include_role : start-zuul-console]
2025-12-03 11:16:59.381211 | controller | ok
2025-12-03 11:16:59.397029 | 
2025-12-03 11:16:59.397114 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-12-03 11:16:59.892669 | controller | ok
2025-12-03 11:16:59.906168 | 
2025-12-03 11:16:59.906427 | TASK [include_role : add-build-sshkey]
2025-12-03 11:16:59.972068 | controller | ok
2025-12-03 11:16:59.997111 | 
2025-12-03 11:16:59.997224 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-12-03 11:17:00.297828 | controller -> localhost | ok
2025-12-03 11:17:00.303282 | 
2025-12-03 11:17:00.303347 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-12-03 11:17:00.333133 | controller | ok
2025-12-03 11:17:00.354586 | controller | included: /var/lib/zuul/builds/e3f79eac2708499abc53fb6760a65239/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-12-03 11:17:00.361964 | 
2025-12-03 11:17:00.362069 | TASK [add-build-sshkey : Create Temp SSH key]
2025-12-03 11:17:01.049756 | controller -> localhost | Generating public/private rsa key pair.
2025-12-03 11:17:01.050317 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/e3f79eac2708499abc53fb6760a65239/work/e3f79eac2708499abc53fb6760a65239_id_rsa.
2025-12-03 11:17:01.050387 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/e3f79eac2708499abc53fb6760a65239/work/e3f79eac2708499abc53fb6760a65239_id_rsa.pub.
2025-12-03 11:17:01.050419 | controller -> localhost | The key fingerprint is:
2025-12-03 11:17:01.050523 | controller -> localhost | SHA256:5j2V8bDAS1STbLs7MU8Ld1dqTYINLEZA2mi+1mZVYCs zuul-build-sshkey
2025-12-03 11:17:01.050556 | controller -> localhost | The key's randomart image is:
2025-12-03 11:17:01.050584 | controller -> localhost | +---[RSA 3072]----+
2025-12-03 11:17:01.050612 | controller -> localhost | |       .oo=+o.   |
2025-12-03 11:17:01.050637 | controller -> localhost | |       + +oo*.   |
2025-12-03 11:17:01.050661 | controller -> localhost | |      + E.=o+=   |
2025-12-03 11:17:01.050685 | controller -> localhost | |     o   o +o*o o|
2025-12-03 11:17:01.050710 | controller -> localhost | |      . S o +..=.|
2025-12-03 11:17:01.050735 | controller -> localhost | |       = o .= = +|
2025-12-03 11:17:01.050759 | controller -> localhost | |      o = o  X o.|
2025-12-03 11:17:01.050784 | controller -> localhost | |     . o   .o o  |
2025-12-03 11:17:01.050808 | controller -> localhost | |             .   |
2025-12-03 11:17:01.050832 | controller -> localhost | +----[SHA256]-----+
2025-12-03 11:17:01.050920 | controller -> localhost | ok: Runtime: 0:00:00.172810
2025-12-03 11:17:01.059341 | 
2025-12-03 11:17:01.059423 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-12-03 11:17:01.078949 | controller | ok
2025-12-03 11:17:01.091265 | controller | included: /var/lib/zuul/builds/e3f79eac2708499abc53fb6760a65239/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-12-03 11:17:01.099410 | 
2025-12-03 11:17:01.099546 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-12-03 11:17:01.124400 | controller | skipping: Conditional result was False
2025-12-03 11:17:01.132351 | 
2025-12-03 11:17:01.132452 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-12-03 11:17:01.759872 | controller | changed
2025-12-03 11:17:01.766274 | 
2025-12-03 11:17:01.766367 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-12-03 11:17:02.105480 | controller | ok
2025-12-03 11:17:02.112005 | 
2025-12-03 11:17:02.112087 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-12-03 11:17:04.361953 | controller | changed
2025-12-03 11:17:04.374778 | 
2025-12-03 11:17:04.374900 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-12-03 11:17:06.129160 | controller | changed
2025-12-03 11:17:06.134361 | 
2025-12-03 11:17:06.134426 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-12-03 11:17:06.148867 | controller | skipping: Conditional result was False
2025-12-03 11:17:06.155375 | 
2025-12-03 11:17:06.155440 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-12-03 11:17:06.504320 | controller -> localhost | changed
2025-12-03 11:17:06.514200 | 
2025-12-03 11:17:06.514266 | TASK [add-build-sshkey : Add back temp key]
2025-12-03 11:17:06.817810 | controller -> localhost | Identity added: /var/lib/zuul/builds/e3f79eac2708499abc53fb6760a65239/work/e3f79eac2708499abc53fb6760a65239_id_rsa (zuul-build-sshkey)
2025-12-03 11:17:06.818068 | controller -> localhost | ok: Runtime: 0:00:00.008974
2025-12-03 11:17:06.823641 | 
2025-12-03 11:17:06.823703 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-12-03 11:17:07.330871 | controller | ok
2025-12-03 11:17:07.338469 | 
2025-12-03 11:17:07.338558 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-12-03 11:17:07.388647 | controller | skipping: Conditional result was False
2025-12-03 11:17:07.398251 | 
2025-12-03 11:17:07.398313 | TASK [include_role : validate-host]
2025-12-03 11:17:07.417047 | controller | ok
2025-12-03 11:17:07.435652 | 
2025-12-03 11:17:07.435717 | TASK [validate-host : Define zuul_info_dir fact]
2025-12-03 11:17:07.464156 | controller | ok
2025-12-03 11:17:07.468612 | 
2025-12-03 11:17:07.468671 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-12-03 11:17:07.696480 | controller -> localhost | ok
2025-12-03 11:17:07.702198 | 
2025-12-03 11:17:07.702272 | TASK [validate-host : Collect information about the host]
2025-12-03 11:17:08.624106 | controller | ok
2025-12-03 11:17:08.641344 | 
2025-12-03 11:17:08.641470 | TASK [validate-host : Sanitize hostname]
2025-12-03 11:17:08.703177 | controller | ok
2025-12-03 11:17:08.710328 | 
2025-12-03 11:17:08.710424 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-12-03 11:17:09.215128 | controller -> localhost | changed
2025-12-03 11:17:09.222053 | 
2025-12-03 11:17:09.222137 | TASK [validate-host : Collect information about zuul worker]
2025-12-03 11:17:09.743775 | controller | ok
2025-12-03 11:17:09.749930 | 
2025-12-03 11:17:09.750036 | TASK [validate-host : Write out all zuul information for each host]
2025-12-03 11:17:10.202893 | controller -> localhost | changed
2025-12-03 11:17:10.222497 | 
2025-12-03 11:17:10.222616 | TASK [include_role : prepare-workspace-openshift]
2025-12-03 11:17:10.247502 | controller | skipping: Conditional result was False
2025-12-03 11:17:10.256776 | 
2025-12-03 11:17:10.256879 | TASK [include_role : remove-zuul-sshkey]
2025-12-03 11:17:10.281808 | controller | skipping: Conditional result was False
2025-12-03 11:17:10.290415 | 
2025-12-03 11:17:10.290513 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-12-03 11:17:10.620098 | controller | ok: "logs"
2025-12-03 11:17:10.620686 | controller | ok: All items complete
2025-12-03 11:17:10.620750 | 
2025-12-03 11:17:10.919479 | controller | ok: "artifacts"
2025-12-03 11:17:11.308350 | controller | ok: "docs"
2025-12-03 11:17:11.316276 | 
2025-12-03 11:17:11.316492 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-12-03 11:17:11.665938 | controller | changed: "logs"
2025-12-03 11:17:11.998624 | controller | changed: "artifacts"
2025-12-03 11:17:12.341473 | controller | changed: "docs"
2025-12-03 11:17:12.364744 | 
2025-12-03 11:17:12.364850 | PLAY RECAP
2025-12-03 11:17:12.364891 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-12-03 11:17:12.364917 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-12-03 11:17:12.364934 | 
2025-12-03 11:17:12.468421 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-12-03 11:17:12.469268 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-12-03 11:17:13.054117 | 
2025-12-03 11:17:13.054222 | PLAY [all]
2025-12-03 11:17:13.074677 | 
2025-12-03 11:17:13.074757 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-12-03 11:17:13.144428 | controller | ok
2025-12-03 11:17:13.149430 | 
2025-12-03 11:17:13.149502 | TASK [mirror-info-fork : Create /etc/ci]
2025-12-03 11:17:13.698783 | controller | changed
2025-12-03 11:17:13.704802 | 
2025-12-03 11:17:13.704875 | TASK [mirror-info-fork : Install ci_mirror script]
2025-12-03 11:17:15.678470 | controller | changed
2025-12-03 11:17:15.697436 | 
2025-12-03 11:17:15.697710 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-12-03 11:17:16.505360 | controller | changed:
2025-12-03 11:17:16.505539 | controller | {
2025-12-03 11:17:16.505568 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-12-03 11:17:16.505598 | controller | }
2025-12-03 11:17:16.888464 | controller | changed:
2025-12-03 11:17:16.888568 | controller | {
2025-12-03 11:17:16.888597 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-12-03 11:17:16.888619 | controller | }
2025-12-03 11:17:17.360080 | controller | changed:
2025-12-03 11:17:17.360166 | controller | {
2025-12-03 11:17:17.360191 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com"
2025-12-03 11:17:17.360211 | controller | }
2025-12-03 11:17:17.811775 | controller | changed:
2025-12-03 11:17:17.811915 | controller | {
2025-12-03 11:17:17.811941 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-12-03 11:17:17.811961 | controller | }
2025-12-03 11:17:18.172620 | controller | changed:
2025-12-03 11:17:18.172726 | controller | {
2025-12-03 11:17:18.172754 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-12-03 11:17:18.172777 | controller | }
2025-12-03 11:17:18.560294 | controller | changed:
2025-12-03 11:17:18.563068 | controller | {
2025-12-03 11:17:18.563117 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-12-03 11:17:18.563142 | controller | }
2025-12-03 11:17:19.025361 | controller | changed:
2025-12-03 11:17:19.025464 | controller | {
2025-12-03 11:17:19.025496 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDLOQd4ZLtkZXQGY6UwAr/06ppWQK4fDO3HaqxPk98csyOCBXsliSKK39Bso828+5srIXiW7aI6aC9P5mwi4mUZlGPfJlQbfrcGvY+b/SocuvaGK+1RrHLoJCT52LBhwgrzlXio2jeksZeein8iaTrhsPrOAs7KggIL/rB9hEiB3NaOPWhhoCP4vlW6MEMExGcqB/1FVxXFBPnLkEyW0Lk7ycVflZl2ocRxbfjZi0+tI1Wlinp8PvSQSc/WVrAcDgKjc/mB4ODPOyYy3G8FHgfMsrXSDEyjBKgLKMsdCrAUcqJQWjkqXleXSYOV4q3pzL+9umK+q/e3P/bIoSFQzmJKTU1eDfuvPXmow9F5H54fii/Da7ezlMJ+wPGHJrRAkmzvMbALy7xwswLhZMkOGNtRcPqaKYRmIBKpw3o6bCTtcNUHOtOQnzwY8JzrM2eBWJBXAANYw+9/ho80JIiwhg29CFNpVBuHbql2YxJQNrnl90guN65rYNpDxdIluweyUf8= anbanerj@kaermorhen"
2025-12-03 11:17:19.025516 | controller | }
2025-12-03 11:17:19.399027 | controller | changed:
2025-12-03 11:17:19.399124 | controller | {
2025-12-03 11:17:19.399150 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQC3VwV8Im9kRm49lt3tM36hj4Zv27FxGo4C1Q/0jqhzFmHY7RHbmeRr8ObhwWoHjXSozKWg8FL5ER0z3hTwL0W6lez3sL7hUaCmSuZmG5Hnl3x4vTSxDI9JZ/Y65rtYiiWQo2fC5xJhU/4+0e5e/pseCm8cKRSu+SaxhO+sd6FDojA2x1BzOzKiQRDy/1zWGp/cZkxcEuB1wHI5LMzN03c67vmbu+fhZRAUO4dQkvcnj2LrhQtpa+ytvnSjr8icMDosf1OsbSffwZFyHB/hfWGAfe0eIeSA2XPraxiPknXxiPKx2MJsaUTYbsZcm3EjFdHBBMumw5rBI74zLrMRvCO9GwBEmGT4rFng1nP+yw5DB8sn2zqpOsPg1LYRwCPOUveC13P6pgsZZPh812e8v5EKnETct+5XI3dVpdw6CnNiLwAyVAF15DJvBGT/u1k0Myg/bQn+Gv9k2MSj6LvQmf6WbZu2Wgjm30z3FyCneBqTL7mLF19YXzeC0ufHz5pnO1E= dasm@fedora"
2025-12-03 11:17:19.399170 | controller | }
2025-12-03 11:17:19.820321 | controller | changed:
2025-12-03 11:17:19.821331 | controller | {
2025-12-03 11:17:19.821377 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-12-03 11:17:19.821411 | controller | }
2025-12-03 11:17:20.185445 | controller | changed:
2025-12-03 11:17:20.185565 | controller | {
2025-12-03 11:17:20.185600 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-12-03 11:17:20.185627 | controller | }
2025-12-03 11:17:20.573828 | controller | changed:
2025-12-03 11:17:20.573920 | controller | {
2025-12-03 11:17:20.573943 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-12-03 11:17:20.573962 | controller | }
2025-12-03 11:17:20.968935 | controller | changed:
2025-12-03 11:17:20.969114 | controller | {
2025-12-03 11:17:20.969151 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-12-03 11:17:20.969193 | controller | }
2025-12-03 11:17:21.390566 | controller | changed:
2025-12-03 11:17:21.390724 | controller | {
2025-12-03 11:17:21.390752 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-12-03 11:17:21.390772 | controller | }
2025-12-03 11:17:21.784135 | controller | changed:
2025-12-03 11:17:21.784233 | controller | {
2025-12-03 11:17:21.784256 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-12-03 11:17:21.784275 | controller | }
2025-12-03 11:17:22.145275 | controller | changed:
2025-12-03 11:17:22.145391 | controller | {
2025-12-03 11:17:22.145416 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-12-03 11:17:22.145437 | controller | }
2025-12-03 11:17:22.518990 | controller | changed:
2025-12-03 11:17:22.519117 | controller | {
2025-12-03 11:17:22.519142 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-12-03 11:17:22.519162 | controller | }
2025-12-03 11:17:22.897179 | controller | changed:
2025-12-03 11:17:22.897498 | controller | {
2025-12-03 11:17:22.897529 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQCfNtF7NvKl915TGsGGoseUb06Hj8L/S4toWf0hExeY+F00woL6NvBlJD0nDct+P5a22I4EhvoQCRQ8reaPCm1lybR3uiRIJsj+8zkVvLwby9LXzfZorlNG9ofjd00FEmB09uW/YvTl6Q9XwwwX6tInzIOv3TMqTHHGOL74ibbj8J/FJR0cFEyj0z4WQRvtkh32xAHl83gbuINryMt0sqRI+clj2381NKL55DRLQrVw0gsfqqxiHAnXg21qWmc4J+b9e9kiuAFQjcjwTVkwJCcg3xbPwC/qokYRby/Y5S40UUd7/jEARGXT7RZgpzTuDd1oZiCVrnrqJNPaMNdVv5MLeFdf1B7iIe5aa/fGouX7AO4SdKhZUdnJmCFAGvjC6S3JMZ2wAcUl+OHnssfmdj7XL50cLo27vjuzMtLAgSqi6N99m92WCF2s8J9aVzszX7Xz9OKZCeGsiVJp3/NdABKzSEAyM9xBD/5Vho894Sav+otpySHe3p6RUTgbB5Zu8VyZRZ/UtB3ueXxyo764yrc6qWIDqrehm84Xm9g+/jpIBzGPl07NUNJpdt/6Sgf9RIKXw/7XypO5yZfUcuFNGTxLfqjTNrtgLZNcjfav6sSdVXVcMPL//XNuRdKmVFaO76eV/oGMQGr1fGcCD+N+CpI7+Q+fCNB6VFWG4nZFuI/Iuw== averdagu@redhat.com"
2025-12-03 11:17:22.897549 | controller | }
2025-12-03 11:17:23.307110 | controller | changed:
2025-12-03 11:17:23.307207 | controller | {
2025-12-03 11:17:23.307230 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDq8l27xI+QlQVdS4djp9ogSoyrNE2+Ox6vKPdhSNL1J3PE5w+WCSvMz9A5gnNuH810zwbekEApbxTze/gLQJwBHA52CChfURpXrFaxY7ePXRElwKAL3mJfzBWY/c5jnNL9TCVmFJTGZkFZP3Nh+BMgZvL6xBkt3WKm6Uq18qzd9XeKcZusrA+O+uLv1fVeQnadY9RIqOCyeFYCzLWrUfTyE8x/XG0hAWIM7qpnF2cALQS2h9n4hW5ybiUN790H08wf9hFwEf5nxY9Z9dVkPFQiTSGKNBzmnCXU9skxS/xhpFjJ5duGSZdtAHe9O+nGZm9c67hxgtf8e5PDuqAdXEv2cf6e3VBAt+Bz8EKI3yosTj0oZHfwr42Yzb1l/SKy14Rggsrc9KAQlrGXan6+u2jcQqqx7l+SWmnpFiWTV9u5cWj2IgOhApOitmRBPYqk9rE2usfO0hLn/Pj/R/Nau4803e1/EikdLE7Ps95s9mX5jRDjAoUa2JwFF5RsVFyL910= ashigupt@ashigupt.remote.csb"
2025-12-03 11:17:23.307249 | controller | }
2025-12-03 11:17:23.729130 | controller | changed:
2025-12-03 11:17:23.729231 | controller | {
2025-12-03 11:17:23.729254 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-12-03 11:17:23.729273 | controller | }
2025-12-03 11:17:24.133296 | controller | changed:
2025-12-03 11:17:24.133387 | controller | {
2025-12-03 11:17:24.133410 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-12-03 11:17:24.133429 | controller | }
2025-12-03 11:17:24.553362 | controller | changed:
2025-12-03 11:17:24.553449 | controller | {
2025-12-03 11:17:24.553472 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-12-03 11:17:24.553491 | controller | }
2025-12-03 11:17:24.973546 | controller | changed:
2025-12-03 11:17:24.973647 | controller | {
2025-12-03 11:17:24.973670 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-12-03 11:17:24.973689 | controller | }
2025-12-03 11:17:25.335268 | controller | changed:
2025-12-03 11:17:25.335384 | controller | {
2025-12-03 11:17:25.335408 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQDZdI7t1cxYx65heVI24HTV4F7oQLW1zyfxHreL2TIJKxjyrUUKIFEUmTutcBlJRLNT2Eoix6x1sOw9YrchloCLcn//SGfTElr9mSc5jbjb7QXEU+zJMhtxyEJ1Po3CUGnj7ckiIXw7wcawZtrEOAQ9pH3ExYCJcEMiyNjRQZCxT3tPK+S4B95EWh5Fsrz9CkwpjNRPPH7LigCeQTM3Wc7r97utAslBUUvYceDSLA7rMgkitJE38b7rZBeYzsGQ8YYUBjTCtehqQXxCRjizbHWaaZkBU+N3zkKB6n/iCNGIO690NK7A/qb6msTijiz1PeuM8ThOsi9qXnbX5v0PoTpcFSojV7NHAQ71f0XXuS43FhZctT+Dcx44dT8Fb5vJu2cJGrk+qF8ZgJYNpRS7gPg0EG2EqjK7JMf9ULdjSu0r+KlqIAyLvtzT4eOnQipoKlb/WG5D/0ohKv7OMQ352ggfkBFIQsRXyyTCT98Ft9juqPuahi3CAQmP4H9dyE+7+Kz437PEtsxLmfm6naNmWi7Ee1DqWPwS8rEajsm4sNM4wW9gdBboJQtc0uZw0DfLj1I9r3Mc8Ol0jYtz0yNQDSzVLrGCaJlC311trU70tZ+ZkAVV6Mn8lOhSbj1cK0lvSr6ZK4dgqGl3I1eTZJJhbLNdg7UOVaiRx9543+C/p/As7w== brjackma@redhat.com"
2025-12-03 11:17:25.336425 | controller | }
2025-12-03 11:17:25.724582 | controller | changed:
2025-12-03 11:17:25.724746 | controller | {
2025-12-03 11:17:25.724832 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-12-03 11:17:25.724862 | controller | }
2025-12-03 11:17:26.135126 | controller | changed:
2025-12-03 11:17:26.135231 | controller | {
2025-12-03 11:17:26.135264 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-12-03 11:17:26.135290 | controller | }
2025-12-03 11:17:26.531081 | controller | changed:
2025-12-03 11:17:26.531185 | controller | {
2025-12-03 11:17:26.531209 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-12-03 11:17:26.531227 | controller | }
2025-12-03 11:17:26.558850 | 
2025-12-03 11:17:26.558968 | TASK [Set timezone to UTC]
2025-12-03 11:17:27.259219 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-12-03 11:17:27.264321 | 
2025-12-03 11:17:27.264383 | TASK [Create nodepool directory]
2025-12-03 11:17:27.623950 | controller | changed
2025-12-03 11:17:27.629514 | 
2025-12-03 11:17:27.629579 | TASK [Create nodepool sub_nodes file]
2025-12-03 11:17:29.083537 | controller | changed
2025-12-03 11:17:29.088818 | 
2025-12-03 11:17:29.088883 | TASK [Create nodepool sub_nodes_private file]
2025-12-03 11:17:30.454300 | controller | changed
2025-12-03 11:17:30.461546 | 
2025-12-03 11:17:30.461627 | LOOP [Populate nodepool sub_nodes file]
2025-12-03 11:17:30.502979 | 
2025-12-03 11:17:30.503162 | LOOP [Populate nodepool sub_nodes_private file]
2025-12-03 11:17:30.547190 | 
2025-12-03 11:17:30.547349 | TASK [Create nodepool primary file]
2025-12-03 11:17:30.581791 | controller | skipping: Conditional result was False
2025-12-03 11:17:30.588372 | 
2025-12-03 11:17:30.588451 | TASK [Create nodepool node_private for this node]
2025-12-03 11:17:32.204814 | controller | changed
2025-12-03 11:17:32.211117 | 
2025-12-03 11:17:32.211224 | LOOP [Copy ssh keys to nodepool directory]
2025-12-03 11:17:32.824657 | controller | ok: Item: id_rsa Runtime: 0:00:00.016002
2025-12-03 11:17:32.824829 | 
2025-12-03 11:17:33.177568 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.006863
2025-12-03 11:17:33.192212 | 
2025-12-03 11:17:33.192462 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-12-03 11:17:34.620487 | controller | changed
2025-12-03 11:17:34.626299 | 
2025-12-03 11:17:34.626375 | TASK [Validate sudoers config after edits]
2025-12-03 11:17:35.031566 | controller | /etc/sudoers: parsed OK
2025-12-03 11:17:35.031729 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-12-03 11:17:35.031745 | controller | /etc/sudoers.d/zuul: parsed OK
2025-12-03 11:17:35.031754 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-12-03 11:17:35.170385 | controller | ok: Runtime: 0:00:00.008835
2025-12-03 11:17:35.177864 | 
2025-12-03 11:17:35.177931 | TASK [Show the environment passed in to job shell scripts]
2025-12-03 11:17:35.582040 | controller | SHELL=/bin/bash
2025-12-03 11:17:35.582133 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-12-03 11:17:35.582153 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-12-03 11:17:35.582167 | controller | ZUUL_CHANGES=rdo-jobs:master:refs/changes/88/58488/1^openstack-k8s-operators/ci-framework:main:refs/changes/51/3451/2261ed433b240c3ce81443dcd27657d36bfed699
2025-12-03 11:17:35.582267 | controller | PWD=/home/zuul
2025-12-03 11:17:35.582282 | controller | ZUUL_PIPELINE=github-check
2025-12-03 11:17:35.582294 | controller | LOGNAME=zuul
2025-12-03 11:17:35.582306 | controller | XDG_SESSION_TYPE=tty
2025-12-03 11:17:35.582317 | controller | _=/usr/bin/env
2025-12-03 11:17:35.582328 | controller | MOTD_SHOWN=pam
2025-12-03 11:17:35.582340 | controller | HOME=/home/zuul
2025-12-03 11:17:35.582352 | controller | LANG=en_US.UTF-8
2025-12-03 11:17:35.582363 | controller | SSH_CONNECTION=38.102.83.114 47092 38.129.56.247 22
2025-12-03 11:17:35.582375 | controller | TOX_TESTENV_PASSENV=ZUUL_UUID ZUUL_PROJECT ZUUL_SHORT_PROJECT_NAME ZUUL_PIPELINE ZUUL_VOTING WORKSPACE BUILD_TIMEOUT ZUUL_BRANCH ZUUL_CHANGES ZUUL_CHANGE_IDS ZUUL_CHANGE ZUUL_PATCHSET
2025-12-03 11:17:35.582391 | controller | ZUUL_CHANGE_IDS=58488,1 3451,2261ed433b240c3ce81443dcd27657d36bfed699
2025-12-03 11:17:35.582403 | controller | WORKSPACE=/home/zuul/workspace
2025-12-03 11:17:35.582416 | controller | XDG_SESSION_CLASS=user
2025-12-03 11:17:35.582428 | controller | SELINUX_ROLE_REQUESTED=
2025-12-03 11:17:35.582439 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-12-03 11:17:35.582452 | controller | USER=zuul
2025-12-03 11:17:35.582464 | controller | ZUUL_VOTING=True
2025-12-03 11:17:35.582476 | controller | BUILD_TIMEOUT=1800000
2025-12-03 11:17:35.582488 | controller | SELINUX_USE_CURRENT_RANGE=
2025-12-03 11:17:35.582500 | controller | SHLVL=1
2025-12-03 11:17:35.582513 | controller | ZUUL_PATCHSET=2261ed433b240c3ce81443dcd27657d36bfed699
2025-12-03 11:17:35.582525 | controller | XDG_SESSION_ID=1
2025-12-03 11:17:35.582537 | controller | ZUUL_BRANCH=main
2025-12-03 11:17:35.582550 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-12-03 11:17:35.582562 | controller | SSH_CLIENT=38.102.83.114 47092 22
2025-12-03 11:17:35.582577 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-12-03 11:17:35.582589 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-12-03 11:17:35.582600 | controller | which_declare=declare -f
2025-12-03 11:17:35.582613 | controller | PATH=/home/zuul/.crc/bin/oc:/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2025-12-03 11:17:35.582624 | controller | SELINUX_LEVEL_REQUESTED=
2025-12-03 11:17:35.582636 | controller | ZUUL_CHANGE=3451
2025-12-03 11:17:35.582648 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-12-03 11:17:35.582660 | controller | ZUUL_UUID=e3f79eac2708499abc53fb6760a65239
2025-12-03 11:17:35.582672 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-12-03 11:17:35.582685 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-12-03 11:17:35.582697 | controller | }
2025-12-03 11:17:35.740813 | controller | ok: Runtime: 0:00:00.007726
2025-12-03 11:17:35.762135 | 
2025-12-03 11:17:35.762281 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-12-03 11:17:35.783222 | controller | skipping: Conditional result was False
2025-12-03 11:17:35.801856 | 
2025-12-03 11:17:35.802047 | TASK [Symlink /home/zuul-worker/workspace]
2025-12-03 11:17:36.342673 | controller | skipping: Conditional result was False
2025-12-03 11:17:36.356468 | 
2025-12-03 11:17:36.356558 | TASK [Ensure legacy workspace directory]
2025-12-03 11:17:36.692153 | controller | changed
2025-12-03 11:17:36.711360 | 
2025-12-03 11:17:36.711413 | PLAY RECAP
2025-12-03 11:17:36.711455 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-12-03 11:17:36.711476 | 
2025-12-03 11:17:36.816934 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-12-03 11:17:36.817703 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/crc/podman.yaml@master]
2025-12-03 11:17:37.423059 | 
2025-12-03 11:17:37.423186 | PLAY [all,!compute]
2025-12-03 11:17:37.444325 | 
2025-12-03 11:17:37.444427 | TASK [Enable container-tools rhel-modules for c8/crc node]
2025-12-03 11:17:37.974570 | controller | skipping: Conditional result was False
2025-12-03 11:17:37.980405 | 
2025-12-03 11:17:37.980484 | TASK [Install podman]
2025-12-03 11:17:38.028648 | controller | skipping: Conditional result was False
2025-12-03 11:17:38.035487 | 
2025-12-03 11:17:38.035590 | TASK [Set var name for quay login zuul secret]
2025-12-03 11:17:38.080468 | controller | skipping: Conditional result was False
2025-12-03 11:17:38.086112 | 
2025-12-03 11:17:38.086178 | TASK [Print the username]
2025-12-03 11:17:38.623269 | controller | skipping: Conditional result was False
2025-12-03 11:17:38.629835 | 
2025-12-03 11:17:38.629918 | TASK [Fail when user or password is not set]
2025-12-03 11:17:38.694918 | controller | skipping: Conditional result was False
2025-12-03 11:17:38.700352 | 
2025-12-03 11:17:38.700415 | TASK [Set vars for quay login]
2025-12-03 11:17:38.740841 | 
2025-12-03 11:17:38.741052 | LOOP [Perform container registry login(s) with podman]
2025-12-03 11:17:38.795768 | 
2025-12-03 11:17:38.795935 | LOOP [Perform container registry login(s) with buildah]
2025-12-03 11:17:38.848417 | 
2025-12-03 11:17:38.848626 | TASK [Set Insecure registry for content provider]
2025-12-03 11:17:38.884245 | controller | skipping: Conditional result was False
2025-12-03 11:17:38.913833 | 
2025-12-03 11:17:38.913957 | PLAY RECAP
2025-12-03 11:17:38.914041 | controller | ok: 0 changed: 0 unreachable: 0 failed: 0 skipped: 9 rescued: 0 ignored: 0
2025-12-03 11:17:38.914077 | 
2025-12-03 11:17:39.015704 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/crc/podman.yaml@master]
2025-12-03 11:17:39.017403 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/crc/simple-start.yaml@master]
2025-12-03 11:17:39.613565 | 
2025-12-03 11:17:39.613699 | PLAY [Prefer ipv4 over ipv6]
2025-12-03 11:17:39.648599 | 
2025-12-03 11:17:39.648717 | TASK [Configure /etc/gai.conf to prever ipv4 over ipv6]
2025-12-03 11:17:39.694669 | controller | skipping: Conditional result was False
2025-12-03 11:17:39.716178 | 
2025-12-03 11:17:39.716248 | PLAY [controller]
2025-12-03 11:17:39.731008 | 
2025-12-03 11:17:39.731112 | TASK [Fail when openshift_pull_sec not provided]
2025-12-03 11:17:39.755326 | controller | skipping: Conditional result was False
2025-12-03 11:17:39.761382 | 
2025-12-03 11:17:39.761446 | TASK [Clone repos in the job workspace]
2025-12-03 11:17:39.780001 | controller | ok
2025-12-03 11:17:39.801339 | 
2025-12-03 11:17:39.801827 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-12-03 11:17:40.276849 | controller | ok
2025-12-03 11:17:40.285403 | 
2025-12-03 11:17:40.285528 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-12-03 11:17:45.980417 | controller | Output suppressed because no_log was given
2025-12-03 11:17:45.989732 | 
2025-12-03 11:17:45.989795 | TASK [Create pull-secret.txt file]
2025-12-03 11:17:48.723433 | controller | Output suppressed because no_log was given
2025-12-03 11:17:48.733873 | 
2025-12-03 11:17:48.734010 | TASK [Apply CRC features and print image build date]
2025-12-03 11:17:48.762550 | controller | ok
2025-12-03 11:17:48.779449 | controller | included: /var/lib/zuul/builds/e3f79eac2708499abc53fb6760a65239/trusted/project_0/review.rdoproject.org/config/playbooks/crc/features.yaml
2025-12-03 11:17:48.787228 | 
2025-12-03 11:17:48.787304 | TASK [Set additional parameters for crc - telemetry]
2025-12-03 11:17:49.319823 | controller | skipping: Conditional result was False
2025-12-03 11:17:49.333433 | 
2025-12-03 11:17:49.333575 | TASK [Set additional parameters for crc - monitoring]
2025-12-03 11:17:49.894242 | controller | skipping: Conditional result was False
2025-12-03 11:17:49.906401 | 
2025-12-03 11:17:49.906540 | TASK [Set minimum required memory to run the CRC when monitoring enabled]
2025-12-03 11:17:50.457841 | controller | skipping: Conditional result was False
2025-12-03 11:17:50.465229 | 
2025-12-03 11:17:50.465311 | TASK [Set additional parameters for crc - enable microshift preset]
2025-12-03 11:17:51.027545 | controller | skipping: Conditional result was False
2025-12-03 11:17:51.039921 | 
2025-12-03 11:17:51.040121 | TASK [Set additional parameters for crc - enable okd preset]
2025-12-03 11:17:51.596756 | controller | skipping: Conditional result was False
2025-12-03 11:17:51.611997 | 
2025-12-03 11:17:51.612228 | TASK [Delete the cluster if there's a non-default bundle URL]
2025-12-03 11:17:52.173871 | controller | skipping: Conditional result was False
2025-12-03 11:17:52.181824 | 
2025-12-03 11:17:52.181911 | TASK [Execute crc start command]
2025-12-03 11:31:51.575603 | controller | ok: Runtime: 0:13:58.489892
2025-12-03 11:31:51.584647 | 
2025-12-03 11:31:51.584755 | TASK [Verify that OpenShift is up and running]
2025-12-03 11:31:51.628824 | controller | ok
2025-12-03 11:31:51.641806 | controller | included: /var/lib/zuul/builds/e3f79eac2708499abc53fb6760a65239/trusted/project_0/review.rdoproject.org/config/playbooks/crc/verify_openshift_start.yaml
2025-12-03 11:31:51.651935 | 
2025-12-03 11:31:51.652049 | TASK [Create script to login and verify services]
2025-12-03 11:31:54.467606 | controller | changed
2025-12-03 11:31:54.483479 | 
2025-12-03 11:31:54.483853 | TASK [Ensure that user is logged]
2025-12-03 11:31:55.019218 | controller | Checking login to the cluster 1...
2025-12-03 11:31:57.829253 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-12-03 11:31:58.335956 | controller |
2025-12-03 11:31:58.336102 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-12-03 11:31:58.344660 | controller | Can not login to the CRC cluster. Sleeping...
2025-12-03 11:32:03.349056 | controller | Checking login to the cluster 2...
2025-12-03 11:32:03.561132 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-12-03 11:32:03.590871 | controller |
2025-12-03 11:32:03.590991 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-12-03 11:32:03.598822 | controller | Can not login to the CRC cluster. Sleeping...
2025-12-03 11:32:08.602198 | controller | Checking login to the cluster 3...
2025-12-03 11:32:08.964990 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-12-03 11:32:08.995035 | controller |
2025-12-03 11:32:08.995193 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-12-03 11:32:09.003602 | controller | Can not login to the CRC cluster. Sleeping...
2025-12-03 11:32:14.008921 | controller | Checking login to the cluster 4...
2025-12-03 11:32:14.297732 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-12-03 11:32:14.324465 | controller |
2025-12-03 11:32:14.324616 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-12-03 11:32:14.333909 | controller | Can not login to the CRC cluster. Sleeping...
2025-12-03 11:32:19.342134 | controller | Checking login to the cluster 5...
2025-12-03 11:32:19.663656 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-12-03 11:32:19.706335 | controller |
2025-12-03 11:32:19.706489 | controller | Unable to connect to the server: EOF
2025-12-03 11:32:19.714272 | controller | Can not login to the CRC cluster. Sleeping...
2025-12-03 11:32:24.718263 | controller | Checking login to the cluster 6...
2025-12-03 11:32:24.917074 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-12-03 11:32:24.940816 | controller |
2025-12-03 11:32:24.940980 | controller | Unable to connect to the server: EOF
2025-12-03 11:32:24.948501 | controller | Can not login to the CRC cluster. Sleeping...
2025-12-03 11:32:29.953849 | controller | Checking login to the cluster 7...
2025-12-03 11:32:30.112586 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-12-03 11:32:30.138622 | controller |
2025-12-03 11:32:30.138759 | controller | Unable to connect to the server: EOF
2025-12-03 11:32:30.154890 | controller | Can not login to the CRC cluster. Sleeping...
2025-12-03 11:32:35.157628 | controller | Checking login to the cluster 8...
2025-12-03 11:32:35.323857 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-12-03 11:32:35.349015 | controller |
2025-12-03 11:32:35.349139 | controller | Unable to connect to the server: EOF
2025-12-03 11:32:35.358433 | controller | Can not login to the CRC cluster. Sleeping...
2025-12-03 11:32:40.361868 | controller | Checking login to the cluster 9...
2025-12-03 11:32:40.529392 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-12-03 11:32:40.556502 | controller |
2025-12-03 11:32:40.556638 | controller | Unable to connect to the server: EOF
2025-12-03 11:32:40.565106 | controller | Can not login to the CRC cluster. Sleeping...
2025-12-03 11:32:45.567681 | controller | Checking login to the cluster 10...
2025-12-03 11:32:45.721827 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-12-03 11:32:45.743990 | controller |
2025-12-03 11:32:45.744109 | controller | Unable to connect to the server: EOF
2025-12-03 11:32:45.752588 | controller | Can not login to the CRC cluster. Sleeping...
2025-12-03 11:32:50.755142 | controller | Checking login to the cluster 11...
2025-12-03 11:32:50.884412 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-12-03 11:32:50.912438 | controller |
2025-12-03 11:32:50.912524 | controller | Unable to connect to the server: EOF
2025-12-03 11:32:50.919889 | controller | Can not login to the CRC cluster. Sleeping...
2025-12-03 11:32:55.966770 | controller | Checking login to the cluster 12...
2025-12-03 11:32:56.110816 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-12-03 11:32:56.141168 | controller |
2025-12-03 11:32:56.141271 | controller | Unable to connect to the server: EOF
2025-12-03 11:32:56.148834 | controller | Can not login to the CRC cluster. Sleeping...
2025-12-03 11:33:01.152181 | controller | Checking login to the cluster 13...
2025-12-03 11:33:01.414841 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-12-03 11:33:01.476424 | controller |
2025-12-03 11:33:01.476543 | controller | Unable to connect to the server: EOF
2025-12-03 11:33:01.485435 | controller | Can not login to the CRC cluster. Sleeping...
2025-12-03 11:33:06.489282 | controller | Checking login to the cluster 14...
2025-12-03 11:33:06.702876 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-12-03 11:33:06.752281 | controller |
2025-12-03 11:33:06.752398 | controller | Unable to connect to the server: EOF
2025-12-03 11:33:06.761632 | controller | Can not login to the CRC cluster. Sleeping...
2025-12-03 11:33:11.765753 | controller | Checking login to the cluster 15...
2025-12-03 11:33:11.957002 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-12-03 11:33:11.977748 | controller |
2025-12-03 11:33:11.977850 | controller | Unable to connect to the server: EOF
2025-12-03 11:33:11.987652 | controller | Can not login to the CRC cluster. Sleeping...
2025-12-03 11:33:16.991255 | controller | Checking login to the cluster 16...
2025-12-03 11:33:17.175688 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-12-03 11:33:17.205030 | controller |
2025-12-03 11:33:17.205113 | controller | Unable to connect to the server: EOF
2025-12-03 11:33:17.212732 | controller | Can not login to the CRC cluster. Sleeping...
2025-12-03 11:33:22.216461 | controller | Checking login to the cluster 17...
2025-12-03 11:33:22.459772 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-12-03 11:33:41.943756 | controller |
2025-12-03 11:33:41.944030 | controller | Login successful.
2025-12-03 11:33:44.248832 | controller |
2025-12-03 11:33:44.248970 | controller | You have access to 65 projects, the list has been suppressed. You can list all projects with 'oc projects'
2025-12-03 11:33:45.026040 | controller |
2025-12-03 11:33:45.026106 | controller | Using project "default".
2025-12-03 11:33:45.260885 | controller | changed
2025-12-03 11:33:45.273286 | 
2025-12-03 11:33:45.273443 | TASK [Delete all openshift-marketplace pods]
2025-12-03 11:33:53.478637 | controller | pod "certified-operators-2hctt" deleted
2025-12-03 11:33:56.465623 | controller | pod "certified-operators-mz9qw" deleted
2025-12-03 11:33:56.623296 | controller | pod "community-operators-d7mb2" deleted
2025-12-03 11:33:57.906187 | controller | pod "community-operators-rl9mj" deleted
2025-12-03 11:33:58.072433 | controller | pod "marketplace-operator-79b997595-bv56r" deleted
2025-12-03 11:33:58.247716 | controller | pod "redhat-marketplace-fr9hp" deleted
2025-12-03 11:33:58.303673 | controller | pod "redhat-marketplace-ww44x" deleted
2025-12-03 11:33:58.508021 | controller | pod "redhat-operators-gb66j" deleted
2025-12-03 11:33:58.787729 | controller | pod "redhat-operators-x9gsj" deleted
2025-12-03 11:34:26.913177 | controller | ok
2025-12-03 11:34:26.924649 | 
2025-12-03 11:34:26.924779 | TASK [Copy script ensure_services_up.sh]
2025-12-03 11:34:26.992949 | controller | skipping: Conditional result was False
2025-12-03 11:34:27.008707 | 
2025-12-03 11:34:27.008853 | TASK [Ensure all pods are up and running]
2025-12-03 11:34:27.559088 | controller | skipping: Conditional result was False
2025-12-03 11:34:27.571595 | 
2025-12-03 11:34:27.571732 | TASK [Check if there is a image build date file]
2025-12-03 11:34:28.264579 | controller | ok
2025-12-03 11:34:28.276211 | 
2025-12-03 11:34:28.276343 | TASK [Print image date if available]
2025-12-03 11:34:28.699487 | controller | Mon Nov 24 08:30:59 AM EST 2025
2025-12-03 11:34:28.826925 | controller | changed
2025-12-03 11:34:28.838858 | 
2025-12-03 11:34:28.838991 | TASK [Fix machineconfigpool]
2025-12-03 11:34:28.907275 | controller | skipping: Conditional result was False
2025-12-03 11:34:28.920232 | 
2025-12-03 11:34:28.920363 | TASK [Show available nodes]
2025-12-03 11:34:29.786522 | controller | NAME   STATUS   ROLES                         AGE    VERSION
2025-12-03 11:34:29.789346 | controller | crc    Ready    control-plane,master,worker   283d   v1.31.5
2025-12-03 11:34:29.979705 | controller | ok: Runtime: 0:00:00.371859
2025-12-03 11:34:29.991264 | 
2025-12-03 11:34:29.991401 | TASK [Configure insecure registry inside crc vm]
2025-12-03 11:34:30.029135 | controller | skipping: Conditional result was False
2025-12-03 11:34:30.042185 | 
2025-12-03 11:34:30.042350 | TASK [Catch CRC IP]
2025-12-03 11:34:30.642082 | controller | 192.168.130.11
2025-12-03 11:34:31.130995 | controller | changed
2025-12-03 11:34:31.144400 | 
2025-12-03 11:34:31.144601 | TASK [Check if id_ed25519 key exists]
2025-12-03 11:34:31.626576 | controller | ok
2025-12-03 11:34:31.639835 | 
2025-12-03 11:34:31.639984 | TASK [Set fact if new keypair exists]
2025-12-03 11:34:31.692117 | controller | ok
2025-12-03 11:34:31.705141 | 
2025-12-03 11:34:31.705281 | TASK [Run crc]
2025-12-03 11:34:33.683971 | controller |
2025-12-03 11:34:40.467221 | controller | PLAY [OpenShift secret copy and CRC securitization] ****************************
2025-12-03 11:34:40.467319 | controller |
2025-12-03 11:34:40.467328 | controller | TASK [Gathering Facts] *********************************************************
2025-12-03 11:34:40.467343 | controller | [WARNING]: Platform linux on host 192.168.130.11 is using the discovered Python
2025-12-03 11:34:40.519610 | controller | interpreter at /usr/bin/python3.9, but future installation of another Python
2025-12-03 11:34:40.519673 | controller | interpreter could change the meaning of that path. See
2025-12-03 11:34:40.519684 | controller | https://docs.ansible.com/ansible-
2025-12-03 11:34:40.519693 | controller | core/2.14/reference_appendices/interpreter_discovery.html for more information.
2025-12-03 11:34:40.519709 | controller | ok: [192.168.130.11]
2025-12-03 11:34:42.350044 | controller |
2025-12-03 11:34:42.350147 | controller | TASK [Copy pull-secret] ********************************************************
2025-12-03 11:34:42.350189 | controller | changed: [192.168.130.11]
2025-12-03 11:34:43.858046 | controller |
2025-12-03 11:34:43.858135 | controller | TASK [Add crio config] *********************************************************
2025-12-03 11:34:43.858163 | controller | changed: [192.168.130.11]
2025-12-03 11:34:47.845226 | controller |
2025-12-03 11:34:47.845302 | controller | TASK [Restart crio to pickup new configuration] ********************************
2025-12-03 11:34:47.845328 | controller | changed: [192.168.130.11]
2025-12-03 11:34:47.908664 | controller |
2025-12-03 11:34:47.908740 | controller | PLAY RECAP *********************************************************************
2025-12-03 11:34:47.908748 | controller | 192.168.130.11             : ok=4    changed=3    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2025-12-03 11:34:47.908755 | controller |
2025-12-03 11:34:48.293513 | controller | changed
2025-12-03 11:34:48.302857 | 
2025-12-03 11:34:48.303011 | TASK [Set slower etcd profile]
2025-12-03 11:34:48.368128 | controller | ok
2025-12-03 11:34:48.401797 | 
2025-12-03 11:34:48.401960 | TASK [prepare-crc-cloud : Set slower profile]
2025-12-03 11:34:49.073328 | controller | etcd.operator.openshift.io/cluster patched
2025-12-03 11:34:49.440364 | controller | ok
2025-12-03 11:34:49.454766 | 
2025-12-03 11:34:49.454918 | TASK [prepare-crc-cloud : Wait 30 seconds to apply]
2025-12-03 11:35:19.518592 | controller | ok
2025-12-03 11:35:19.529919 | 
2025-12-03 11:35:19.530096 | TASK [prepare-crc-cloud : Get pod phase status]
2025-12-03 11:40:44.796163 | controller | ok
2025-12-03 11:40:44.863175 | 
2025-12-03 11:40:44.863301 | TASK [Set limit for disk usage - IOPS and Read/Write]
2025-12-03 11:40:44.935356 | controller | ok
2025-12-03 11:40:44.943409 | controller | included: /var/lib/zuul/builds/e3f79eac2708499abc53fb6760a65239/trusted/project_0/review.rdoproject.org/config/playbooks/crc/limit-disk-usage.yaml
2025-12-03 11:40:44.957550 | 
2025-12-03 11:40:44.957710 | TASK [Set facts for disk limitation]
2025-12-03 11:40:44.993093 | controller | ok
2025-12-03 11:40:45.000847 | 
2025-12-03 11:40:45.000944 | TASK [Print limits for the disk]
2025-12-03 11:40:45.042733 | controller | ok: Set limit to crc VM to the vda disk: iops 18000 read/write 262144000
2025-12-03 11:40:45.049899 | 
2025-12-03 11:40:45.050047 | TASK [Set limit to crc VM to the vda disk]
2025-12-03 11:40:45.466223 | controller |
2025-12-03 11:40:45.585652 | controller | changed
2025-12-03 11:40:45.601468 | 
2025-12-03 11:40:45.601627 | TASK [Pause machineconfigpool]
2025-12-03 11:40:45.665952 | controller | ok
2025-12-03 11:40:45.712820 | 
2025-12-03 11:40:45.712988 | LOOP [prepare-crc-cloud : Pause the machineconfig configuration if mcp is not needed]
2025-12-03 11:40:46.310907 | controller | ok
2025-12-03 11:40:46.892617 | controller | ok
2025-12-03 11:40:46.913521 | 
2025-12-03 11:40:46.913682 | TASK [prepare-crc-cloud : Print current machineconfigpool]
2025-12-03 11:40:47.429143 | controller | NAME     CONFIG                                             UPDATED   UPDATING   DEGRADED   MACHINECOUNT   READYMACHINECOUNT   UPDATEDMACHINECOUNT   DEGRADEDMACHINECOUNT   AGE
2025-12-03 11:40:47.429248 | controller | master   rendered-master-63af5c6b4fa17c607a1f2008d8a3ac33   True      False      False      1              1                   1                     0                      283d
2025-12-03 11:40:47.429262 | controller | worker   rendered-worker-594cc07c9feefb5e37f2530ac457d36c   True      False      False      0              0                   0                     0                      283d
2025-12-03 11:40:47.500856 | controller | ok
2025-12-03 11:40:47.551052 | 
2025-12-03 11:40:47.551173 | PLAY [Set IOPS and Read/Write disk limitation excluding controller]
2025-12-03 11:40:47.551303 | 
2025-12-03 11:40:47.551339 | PLAY RECAP
2025-12-03 11:40:47.551387 | controller | ok: 25 changed: 10 unreachable: 0 failed: 0 skipped: 12 rescued: 0 ignored: 0
2025-12-03 11:40:47.551417 | 
2025-12-03 11:40:47.672647 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/crc/simple-start.yaml@master]
2025-12-03 11:40:47.674443 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-12-03 11:40:48.264449 | 
2025-12-03 11:40:48.264570 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-12-03 11:40:48.285972 | 
2025-12-03 11:40:48.286094 | TASK [Create zuul-output directory]
2025-12-03 11:40:48.901430 | controller | changed
2025-12-03 11:40:48.908981 | 
2025-12-03 11:40:48.909295 | TASK [Slurp Zuul inventory test]
2025-12-03 11:40:49.280606 | controller -> localhost | ok
2025-12-03 11:40:49.300573 | 
2025-12-03 11:40:49.300693 | TASK [Save zuul inventory]
2025-12-03 11:40:51.191990 | controller | changed
2025-12-03 11:40:51.204752 | 
2025-12-03 11:40:51.204841 | TASK [Save zuul vars without the change_message]
2025-12-03 11:40:52.792347 | controller | changed
2025-12-03 11:40:52.822346 | 
2025-12-03 11:40:52.822464 | PLAY RECAP
2025-12-03 11:40:52.822518 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-12-03 11:40:52.822547 | 
2025-12-03 11:40:52.953570 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-12-03 11:40:52.954832 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-12-03 11:40:53.612213 | 
2025-12-03 11:40:53.612341 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2025-12-03 11:40:53.640905 | 
2025-12-03 11:40:53.641027 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2025-12-03 11:40:53.676646 | controller | ok
2025-12-03 11:40:53.708011 | 
2025-12-03 11:40:53.708176 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-12-03 11:40:53.742988 | controller | skipping: Conditional result was False
2025-12-03 11:40:53.748912 | 
2025-12-03 11:40:53.748984 | TASK [mirror-info-fork : Create /etc/ci]
2025-12-03 11:40:54.346758 | controller | ok
2025-12-03 11:40:54.359492 | 
2025-12-03 11:40:54.359635 | TASK [mirror-info-fork : Install ci_mirror script]
2025-12-03 11:40:55.818207 | controller | ok
2025-12-03 11:40:55.829217 | 
2025-12-03 11:40:55.829393 | TASK [Prepare workspace]
2025-12-03 11:40:55.849245 | controller | ok
2025-12-03 11:40:55.869159 | 
2025-12-03 11:40:55.869260 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-12-03 11:40:56.418964 | controller | ok
2025-12-03 11:40:56.433133 | 
2025-12-03 11:40:56.433273 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-12-03 11:40:57.725979 | controller | Output suppressed because no_log was given
2025-12-03 11:40:57.772550 | 
2025-12-03 11:40:57.772662 | LOOP [Create zuul-output directory]
2025-12-03 11:40:58.140974 | controller | changed: "/home/zuul/ci-framework-data/logs"
2025-12-03 11:40:58.479545 | controller | ok: "/home/zuul/zuul-output/logs"
2025-12-03 11:40:58.497128 | 
2025-12-03 11:40:58.497316 | TASK [Install required packages]
2025-12-03 11:41:15.394407 | controller | ok: Nothing to do
2025-12-03 11:41:15.399781 | 
2025-12-03 11:41:15.399850 | TASK [Install venv]
2025-12-03 11:43:44.266308 | controller | changed
2025-12-03 11:43:44.322917 | 
2025-12-03 11:43:44.323064 | PLAY RECAP
2025-12-03 11:43:44.323124 | controller | ok: 7 changed: 2 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-12-03 11:43:44.323154 | 
2025-12-03 11:43:44.436385 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-12-03 11:43:44.438102 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-12-03 11:43:45.041112 | 
2025-12-03 11:43:45.041264 | PLAY [Run ci/playbooks/molecule-test.yml]
2025-12-03 11:43:45.064590 | 
2025-12-03 11:43:45.064717 | TASK [Gather required facts]
2025-12-03 11:43:45.859168 | controller | ok
2025-12-03 11:43:45.874781 | 
2025-12-03 11:43:45.874917 | TASK [Load environment var if instructed to]
2025-12-03 11:43:45.900790 | controller | skipping: Conditional result was False
2025-12-03 11:43:45.907364 | 
2025-12-03 11:43:45.907447 | TASK [Ensure group_vars dir exists]
2025-12-03 11:43:46.423185 | controller | ok
2025-12-03 11:43:46.435841 | 
2025-12-03 11:43:46.435982 | TASK [Print related variables]
2025-12-03 11:43:46.484826 | controller | ok:
2025-12-03 11:43:46.485142 | controller | mol_config_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config_local.yml
2025-12-03 11:43:46.485205 | controller | roles_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_nmstate
2025-12-03 11:43:46.497983 | 
2025-12-03 11:43:46.498159 | TASK [Run molecule]
2025-12-03 11:43:47.922649 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2025-12-03 11:43:48.004524 | controller | INFO     Performing prerun with role_name_check=0...
2025-12-03 11:44:12.708824 | controller | WARNING  Another version of '@NAMESPACE@.@NAME@' 3.1.4 was found installed in /home/zuul/.cache/ansible-compat/2ef247/collections/ansible_collections, only the first one will be used, 3.1.4 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-03 11:44:12.709162 | controller | WARNING  Another version of 'ansible.netcommon' 7.1.0 was found installed in /home/zuul/.cache/ansible-compat/2ef247/collections/ansible_collections, only the first one will be used, 7.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-03 11:44:12.709642 | controller | WARNING  Another version of 'ansible.posix' 1.6.2 was found installed in /home/zuul/.cache/ansible-compat/2ef247/collections/ansible_collections, only the first one will be used, 1.6.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-03 11:44:12.710207 | controller | WARNING  Another version of 'ansible.utils' 5.1.2 was found installed in /home/zuul/.cache/ansible-compat/2ef247/collections/ansible_collections, only the first one will be used, 5.1.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-03 11:44:12.710740 | controller | WARNING  Another version of 'cifmw.general' 1.0.0+2261ed43 was found installed in /home/zuul/.cache/ansible-compat/2ef247/collections/ansible_collections, only the first one will be used, 1.0.0+2261ed43 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-03 11:44:12.711321 | controller | WARNING  Another version of 'cisco.ios' 9.0.3 was found installed in /home/zuul/.cache/ansible-compat/2ef247/collections/ansible_collections, only the first one will be used, 9.0.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-03 11:44:12.711810 | controller | WARNING  Another version of 'community.crypto' 2.22.3 was found installed in /home/zuul/.cache/ansible-compat/2ef247/collections/ansible_collections, only the first one will be used, 2.22.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-03 11:44:12.712368 | controller | WARNING  Another version of 'community.general' 10.0.1 was found installed in /home/zuul/.cache/ansible-compat/2ef247/collections/ansible_collections, only the first one will be used, 10.0.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-03 11:44:12.712920 | controller | WARNING  Another version of 'community.libvirt' 1.3.0 was found installed in /home/zuul/.cache/ansible-compat/2ef247/collections/ansible_collections, only the first one will be used, 1.3.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-03 11:44:12.713393 | controller | WARNING  Another version of 'community.okd' 4.0.0 was found installed in /home/zuul/.cache/ansible-compat/2ef247/collections/ansible_collections, only the first one will be used, 4.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-03 11:44:12.713891 | controller | WARNING  Another version of 'containers.podman' 1.16.2 was found installed in /home/zuul/.cache/ansible-compat/2ef247/collections/ansible_collections, only the first one will be used, 1.16.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-03 11:44:12.714395 | controller | WARNING  Another version of 'junipernetworks.junos' 9.1.0 was found installed in /home/zuul/.cache/ansible-compat/2ef247/collections/ansible_collections, only the first one will be used, 9.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-03 11:44:12.714868 | controller | WARNING  Another version of 'kubernetes.core' 5.0.0 was found installed in /home/zuul/.cache/ansible-compat/2ef247/collections/ansible_collections, only the first one will be used, 5.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-03 11:44:12.715315 | controller | WARNING  Another version of 'mellanox.onyx' 1.0.0 was found installed in /home/zuul/.cache/ansible-compat/2ef247/collections/ansible_collections, only the first one will be used, 1.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-03 11:44:12.715775 | controller | WARNING  Another version of 'openstack.config_template' 2.1.1 was found installed in /home/zuul/.cache/ansible-compat/2ef247/collections/ansible_collections, only the first one will be used, 2.1.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-03 11:44:12.727339 | controller | INFO     Running default > prepare
2025-12-03 11:44:13.925261 | controller |
2025-12-03 11:44:13.926269 | controller | PLAY [Prepare] *****************************************************************
2025-12-03 11:44:15.113097 | controller |
2025-12-03 11:44:15.113148 | controller | TASK [Gathering Facts] *********************************************************
2025-12-03 11:44:15.113161 | controller | Wednesday 03 December 2025  11:44:13 +0000 (0:00:00.016)       0:00:00.016 ****
2025-12-03 11:44:15.113179 | controller | ok: [instance]
2025-12-03 11:44:15.113438 | controller |
2025-12-03 11:44:15.113452 | controller | TASK [test_deps : Gather facts if they don't exist] ****************************
2025-12-03 11:44:15.113465 | controller | Wednesday 03 December 2025  11:44:15 +0000 (0:00:01.187)       0:00:01.204 ****
2025-12-03 11:44:15.141776 | controller | skipping: [instance]
2025-12-03 11:44:15.142052 | controller |
2025-12-03 11:44:15.142340 | controller | TASK [test_deps : Gather variables for each operating system] ******************
2025-12-03 11:44:15.142682 | controller | Wednesday 03 December 2025  11:44:15 +0000 (0:00:00.029)       0:00:01.233 ****
2025-12-03 11:44:15.240699 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/test_deps/vars/centos-9.yml)
2025-12-03 11:44:15.241047 | controller |
2025-12-03 11:44:15.241286 | controller | TASK [test_deps : Detect mirrors file] *****************************************
2025-12-03 11:44:15.241587 | controller | Wednesday 03 December 2025  11:44:15 +0000 (0:00:00.098)       0:00:01.331 ****
2025-12-03 11:44:15.777010 | controller | ok: [instance]
2025-12-03 11:44:15.777147 | controller |
2025-12-03 11:44:15.777165 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] ***********
2025-12-03 11:44:15.777266 | controller | Wednesday 03 December 2025  11:44:15 +0000 (0:00:00.531)       0:00:01.863 ****
2025-12-03 11:44:16.185458 | controller | ok: [instance]
2025-12-03 11:44:16.185899 | controller |
2025-12-03 11:44:16.186665 | controller | TASK [test_deps : Disable ubi 9 repos] *****************************************
2025-12-03 11:44:16.186817 | controller | Wednesday 03 December 2025  11:44:16 +0000 (0:00:00.413)       0:00:02.276 ****
2025-12-03 11:44:17.406557 | controller | ok: [instance] => (item=ubi-9-appstream)
2025-12-03 11:44:17.406615 | controller | ok: [instance] => (item=ubi-9-baseos)
2025-12-03 11:44:17.406763 | controller | ok: [instance] => (item=ubi-9-codeready-builder)
2025-12-03 11:44:17.406881 | controller |
2025-12-03 11:44:17.407032 | controller | TASK [Deploy repo-setup] *******************************************************
2025-12-03 11:44:17.407178 | controller | Wednesday 03 December 2025  11:44:17 +0000 (0:00:01.222)       0:00:03.499 ****
2025-12-03 11:44:17.480253 | controller |
2025-12-03 11:44:17.480406 | controller | TASK [repo_setup : Ensure directories are present] *****************************
2025-12-03 11:44:18.505135 | controller | Wednesday 03 December 2025  11:44:17 +0000 (0:00:00.073)       0:00:03.572 ****
2025-12-03 11:44:18.505218 | controller | changed: [instance] => (item=tmp)
2025-12-03 11:44:18.505420 | controller | changed: [instance] => (item=artifacts/repositories)
2025-12-03 11:44:18.505467 | controller | changed: [instance] => (item=venv/repo_setup)
2025-12-03 11:44:18.505480 | controller |
2025-12-03 11:44:18.505491 | controller | TASK [repo_setup : Make sure git-core package is installed] ********************
2025-12-03 11:44:18.505773 | controller | Wednesday 03 December 2025  11:44:18 +0000 (0:00:01.024)       0:00:04.597 ****
2025-12-03 11:44:19.854847 | controller | ok: [instance]
2025-12-03 11:44:21.847926 | controller |
2025-12-03 11:44:21.848016 | controller | TASK [repo_setup : Get repo-setup repository] **********************************
2025-12-03 11:44:21.848033 | controller | Wednesday 03 December 2025  11:44:19 +0000 (0:00:01.348)       0:00:05.946 ****
2025-12-03 11:44:21.848055 | controller | changed: [instance]
2025-12-03 11:44:41.416527 | controller |
2025-12-03 11:44:41.416734 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************
2025-12-03 11:44:41.416750 | controller | Wednesday 03 December 2025  11:44:21 +0000 (0:00:01.992)       0:00:07.939 ****
2025-12-03 11:44:41.416785 | controller | changed: [instance]
2025-12-03 11:44:42.534491 | controller |
2025-12-03 11:44:42.534570 | controller | TASK [repo_setup : Install repo-setup package] *********************************
2025-12-03 11:44:42.534583 | controller | Wednesday 03 December 2025  11:44:41 +0000 (0:00:19.566)       0:00:27.505 ****
2025-12-03 11:44:42.534598 | controller | changed: [instance]
2025-12-03 11:44:42.568579 | controller |
2025-12-03 11:44:42.568634 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
2025-12-03 11:44:42.568647 | controller | Wednesday 03 December 2025  11:44:42 +0000 (0:00:01.120)       0:00:28.625 ****
2025-12-03 11:44:42.568663 | controller | skipping: [instance]
2025-12-03 11:44:43.326895 | controller |
2025-12-03 11:44:43.326980 | controller | TASK [repo_setup : Run repo-setup] *********************************************
2025-12-03 11:44:43.326992 | controller | Wednesday 03 December 2025  11:44:42 +0000 (0:00:00.034)       0:00:28.660 ****
2025-12-03 11:44:43.327005 | controller | changed: [instance]
2025-12-03 11:44:43.384002 | controller |
2025-12-03 11:44:43.384046 | controller | TASK [repo_setup : Get component repo] *****************************************
2025-12-03 11:44:43.384055 | controller | Wednesday 03 December 2025  11:44:43 +0000 (0:00:00.757)       0:00:29.418 ****
2025-12-03 11:44:43.384068 | controller | skipping: [instance]
2025-12-03 11:44:43.442281 | controller |
2025-12-03 11:44:43.442332 | controller | TASK [repo_setup : Rename component repo] **************************************
2025-12-03 11:44:43.442340 | controller | Wednesday 03 December 2025  11:44:43 +0000 (0:00:00.057)       0:00:29.476 ****
2025-12-03 11:44:43.442353 | controller | skipping: [instance]
2025-12-03 11:44:43.442449 | controller |
2025-12-03 11:44:43.442858 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
2025-12-03 11:44:43.444411 | controller | Wednesday 03 December 2025  11:44:43 +0000 (0:00:00.058)       0:00:29.534 ****
2025-12-03 11:44:43.500991 | controller | skipping: [instance]
2025-12-03 11:44:43.501096 | controller |
2025-12-03 11:44:43.501290 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************
2025-12-03 11:44:43.501463 | controller | Wednesday 03 December 2025  11:44:43 +0000 (0:00:00.058)       0:00:29.593 ****
2025-12-03 11:44:44.104197 | controller | changed: [instance]
2025-12-03 11:44:44.716958 | controller |
2025-12-03 11:44:44.717018 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
2025-12-03 11:44:44.717069 | controller | Wednesday 03 December 2025  11:44:44 +0000 (0:00:00.602)       0:00:30.195 ****
2025-12-03 11:44:44.717089 | controller | changed: [instance]
2025-12-03 11:44:44.775163 | controller |
2025-12-03 11:44:44.775219 | controller | TASK [repo_setup : Dump current-podified hash] *********************************
2025-12-03 11:44:44.775232 | controller | Wednesday 03 December 2025  11:44:44 +0000 (0:00:00.613)       0:00:30.809 ****
2025-12-03 11:44:44.775250 | controller | skipping: [instance]
2025-12-03 11:44:44.775394 | controller |
2025-12-03 11:44:44.775417 | controller | TASK [repo_setup : Slurp current podified hash] ********************************
2025-12-03 11:44:44.775585 | controller | Wednesday 03 December 2025  11:44:44 +0000 (0:00:00.058)       0:00:30.867 ****
2025-12-03 11:44:44.828786 | controller | skipping: [instance]
2025-12-03 11:44:44.828839 | controller |
2025-12-03 11:44:44.829027 | controller | TASK [repo_setup : Update the value of full_hash] ******************************
2025-12-03 11:44:44.829243 | controller | Wednesday 03 December 2025  11:44:44 +0000 (0:00:00.053)       0:00:30.920 ****
2025-12-03 11:44:44.872343 | controller | skipping: [instance]
2025-12-03 11:44:44.872390 | controller |
2025-12-03 11:44:44.872572 | controller | TASK [repo_setup : Export hashes facts for further use] ************************
2025-12-03 11:44:44.872700 | controller | Wednesday 03 December 2025  11:44:44 +0000 (0:00:00.044)       0:00:30.964 ****
2025-12-03 11:44:44.928811 | controller | ok: [instance]
2025-12-03 11:44:44.928891 | controller |
2025-12-03 11:44:44.929195 | controller | TASK [repo_setup : Create download directory] **********************************
2025-12-03 11:44:44.929400 | controller | Wednesday 03 December 2025  11:44:44 +0000 (0:00:00.055)       0:00:31.020 ****
2025-12-03 11:44:44.975591 | controller | skipping: [instance]
2025-12-03 11:44:44.976694 | controller |
2025-12-03 11:44:45.020742 | controller | TASK [repo_setup : Print the URL to request] ***********************************
2025-12-03 11:44:45.020829 | controller | Wednesday 03 December 2025  11:44:44 +0000 (0:00:00.047)       0:00:31.068 ****
2025-12-03 11:44:45.020871 | controller | skipping: [instance]
2025-12-03 11:44:45.020928 | controller |
2025-12-03 11:44:45.020968 | controller | TASK [Download the RPM] ********************************************************
2025-12-03 11:44:45.021045 | controller | Wednesday 03 December 2025  11:44:45 +0000 (0:00:00.045)       0:00:31.113 ****
2025-12-03 11:44:45.062323 | controller | skipping: [instance]
2025-12-03 11:44:45.062394 | controller |
2025-12-03 11:44:45.062578 | controller | TASK [repo_setup : Install RHOS Release tool] **********************************
2025-12-03 11:44:45.062742 | controller | Wednesday 03 December 2025  11:44:45 +0000 (0:00:00.041)       0:00:31.154 ****
2025-12-03 11:44:45.109467 | controller | skipping: [instance]
2025-12-03 11:44:45.109557 | controller |
2025-12-03 11:44:45.109701 | controller | TASK [repo_setup : Get rhos-release tool version] ******************************
2025-12-03 11:44:45.109850 | controller | Wednesday 03 December 2025  11:44:45 +0000 (0:00:00.046)       0:00:31.201 ****
2025-12-03 11:44:45.149434 | controller | skipping: [instance]
2025-12-03 11:44:45.149510 | controller |
2025-12-03 11:44:45.149670 | controller | TASK [repo_setup : Print rhos-release tool version] ****************************
2025-12-03 11:44:45.149817 | controller | Wednesday 03 December 2025  11:44:45 +0000 (0:00:00.040)       0:00:31.241 ****
2025-12-03 11:44:45.198187 | controller | skipping: [instance]
2025-12-03 11:44:45.198244 | controller |
2025-12-03 11:44:45.198432 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
2025-12-03 11:44:45.198539 | controller | Wednesday 03 December 2025  11:44:45 +0000 (0:00:00.048)       0:00:31.290 ****
2025-12-03 11:44:45.233776 | controller | skipping: [instance]
2025-12-03 11:44:45.233820 | controller |
2025-12-03 11:44:45.233926 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
2025-12-03 11:44:45.234062 | controller | Wednesday 03 December 2025  11:44:45 +0000 (0:00:00.036)       0:00:31.326 ****
2025-12-03 11:44:45.492440 | controller | ok: [instance]
2025-12-03 11:44:45.492587 | controller |
2025-12-03 11:44:45.492644 | controller | TASK [repo_setup : Use RDO proxy mirrors] **************************************
2025-12-03 11:44:45.492801 | controller | Wednesday 03 December 2025  11:44:45 +0000 (0:00:00.257)       0:00:31.583 ****
2025-12-03 11:44:45.805985 | controller | changed: [instance]
2025-12-03 11:44:45.806097 | controller |
2025-12-03 11:44:45.806115 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
2025-12-03 11:44:45.806260 | controller | Wednesday 03 December 2025  11:44:45 +0000 (0:00:00.314)       0:00:31.898 ****
2025-12-03 11:44:46.227451 | controller | changed: [instance]
2025-12-03 11:44:46.227520 | controller |
2025-12-03 11:44:46.227778 | controller | TASK [repo_setup : Check for gating.repo file on content provider] *************
2025-12-03 11:44:46.257408 | controller | Wednesday 03 December 2025  11:44:46 +0000 (0:00:00.421)       0:00:32.319 ****
2025-12-03 11:44:46.257463 | controller | skipping: [instance]
2025-12-03 11:44:46.286890 | controller |
2025-12-03 11:44:46.286956 | controller | TASK [repo_setup : Populate gating repo from content provider ip] **************
2025-12-03 11:44:46.286967 | controller | Wednesday 03 December 2025  11:44:46 +0000 (0:00:00.029)       0:00:32.349 ****
2025-12-03 11:44:46.286981 | controller | skipping: [instance]
2025-12-03 11:44:46.321140 | controller |
2025-12-03 11:44:46.321201 | controller | TASK [repo_setup : Check for DLRN repo at the destination] *********************
2025-12-03 11:44:46.321211 | controller | Wednesday 03 December 2025  11:44:46 +0000 (0:00:00.029)       0:00:32.378 ****
2025-12-03 11:44:46.321228 | controller | skipping: [instance]
2025-12-03 11:44:46.321256 | controller |
2025-12-03 11:44:46.321367 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
2025-12-03 11:44:46.321389 | controller | Wednesday 03 December 2025  11:44:46 +0000 (0:00:00.034)       0:00:32.413 ****
2025-12-03 11:44:46.356749 | controller | skipping: [instance]
2025-12-03 11:44:46.356823 | controller |
2025-12-03 11:44:46.356981 | controller | TASK [repo_setup : Check for DLRN component repo] ******************************
2025-12-03 11:44:46.357214 | controller | Wednesday 03 December 2025  11:44:46 +0000 (0:00:00.035)       0:00:32.449 ****
2025-12-03 11:44:46.392452 | controller | skipping: [instance]
2025-12-03 11:44:46.427683 | controller |
2025-12-03 11:44:46.427737 | controller | TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] ***
2025-12-03 11:44:46.427763 | controller | Wednesday 03 December 2025  11:44:46 +0000 (0:00:00.035)       0:00:32.484 ****
2025-12-03 11:44:46.427776 | controller | skipping: [instance]
2025-12-03 11:44:46.457325 | controller |
2025-12-03 11:44:46.457365 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
2025-12-03 11:44:46.457373 | controller | Wednesday 03 December 2025  11:44:46 +0000 (0:00:00.035)       0:00:32.519 ****
2025-12-03 11:44:46.457384 | controller | skipping: [instance]
2025-12-03 11:44:46.502118 | controller |
2025-12-03 11:44:46.502179 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
2025-12-03 11:44:46.502189 | controller | Wednesday 03 December 2025  11:44:46 +0000 (0:00:00.029)       0:00:32.549 ****
2025-12-03 11:44:46.502212 | controller | skipping: [instance]
2025-12-03 11:44:46.502249 | controller |
2025-12-03 11:44:46.502261 | controller | TASK [repo_setup : Cleanup existing metadata] **********************************
2025-12-03 11:44:46.502270 | controller | Wednesday 03 December 2025  11:44:46 +0000 (0:00:00.044)       0:00:32.594 ****
2025-12-03 11:44:46.526686 | controller | skipping: [instance]
2025-12-03 11:44:46.526761 | controller |
2025-12-03 11:44:46.526903 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
2025-12-03 11:44:46.527100 | controller | Wednesday 03 December 2025  11:44:46 +0000 (0:00:00.024)       0:00:32.619 ****
2025-12-03 11:44:46.555621 | controller | skipping: [instance]
2025-12-03 11:44:46.555683 | controller |
2025-12-03 11:44:46.555715 | controller | TASK [test_deps : Look for redhat-release rpm] *********************************
2025-12-03 11:44:46.555831 | controller | Wednesday 03 December 2025  11:44:46 +0000 (0:00:00.028)       0:00:32.648 ****
2025-12-03 11:44:46.613542 | controller | skipping: [instance]
2025-12-03 11:44:46.613657 | controller |
2025-12-03 11:44:46.613801 | controller | TASK [test_deps : Remove redhat-release] ***************************************
2025-12-03 11:44:46.613824 | controller | Wednesday 03 December 2025  11:44:46 +0000 (0:00:00.057)       0:00:32.705 ****
2025-12-03 11:44:46.659109 | controller | skipping: [instance]
2025-12-03 11:44:46.659192 | controller |
2025-12-03 11:44:46.659358 | controller | TASK [test_deps : Install centos-stream-release] *******************************
2025-12-03 11:44:46.659518 | controller | Wednesday 03 December 2025  11:44:46 +0000 (0:00:00.045)       0:00:32.751 ****
2025-12-03 11:44:46.700586 | controller | skipping: [instance]
2025-12-03 11:44:46.700659 | controller |
2025-12-03 11:44:46.700721 | controller | TASK [test_deps : Install additional packages] *********************************
2025-12-03 11:44:46.700827 | controller | Wednesday 03 December 2025  11:44:46 +0000 (0:00:00.041)       0:00:32.792 ****
2025-12-03 11:44:46.747641 | controller | skipping: [instance]
2025-12-03 11:44:46.747719 | controller |
2025-12-03 11:44:46.747847 | controller | TASK [test_deps : Install selinux python libs] *********************************
2025-12-03 11:44:46.748010 | controller | Wednesday 03 December 2025  11:44:46 +0000 (0:00:00.047)       0:00:32.840 ****
2025-12-03 11:45:53.560248 | controller | ok: [instance]
2025-12-03 11:45:53.560379 | controller |
2025-12-03 11:45:53.560396 | controller | TASK [test_deps : Install python yaml libs] ************************************
2025-12-03 11:45:53.560475 | controller | Wednesday 03 December 2025  11:45:53 +0000 (0:01:06.809)       0:01:39.649 ****
2025-12-03 11:45:55.364901 | controller | ok: [instance]
2025-12-03 11:45:55.364993 | controller |
2025-12-03 11:45:55.365103 | controller | TASK [test_deps : Install extra packages] **************************************
2025-12-03 11:45:55.365273 | controller | Wednesday 03 December 2025  11:45:55 +0000 (0:00:01.806)       0:01:41.456 ****
2025-12-03 11:45:55.436986 | controller | skipping: [instance]
2025-12-03 11:45:55.437058 | controller |
2025-12-03 11:45:55.437132 | controller | PLAY RECAP *********************************************************************
2025-12-03 11:45:55.437256 | controller | instance                   : ok=19   changed=9    unreachable=0    failed=0    skipped=30   rescued=0    ignored=0
2025-12-03 11:45:55.437351 | controller |
2025-12-03 11:45:55.437542 | controller | Wednesday 03 December 2025  11:45:55 +0000 (0:00:00.073)       0:01:41.529 ****
2025-12-03 11:45:55.437671 | controller | ===============================================================================
2025-12-03 11:45:55.437798 | controller | test_deps : Install selinux python libs -------------------------------- 66.81s
2025-12-03 11:45:55.437912 | controller | repo_setup : Initialize python venv and install requirements ----------- 19.57s
2025-12-03 11:45:55.438054 | controller | repo_setup : Get repo-setup repository ---------------------------------- 1.99s
2025-12-03 11:45:55.438145 | controller | test_deps : Install python yaml libs ------------------------------------ 1.81s
2025-12-03 11:45:55.438248 | controller | repo_setup : Make sure git-core package is installed -------------------- 1.35s
2025-12-03 11:45:55.438321 | controller | test_deps : Disable ubi 9 repos ----------------------------------------- 1.22s
2025-12-03 11:45:55.438414 | controller | Gathering Facts --------------------------------------------------------- 1.19s
2025-12-03 11:45:55.438516 | controller | repo_setup : Install repo-setup package --------------------------------- 1.12s
2025-12-03 11:45:55.438624 | controller | repo_setup : Ensure directories are present ----------------------------- 1.02s
2025-12-03 11:45:55.438767 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.76s
2025-12-03 11:45:55.438895 | controller | repo_setup : Dump full hash in delorean.repo.md5 file ------------------- 0.61s
2025-12-03 11:45:55.439053 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.60s
2025-12-03 11:45:55.439228 | controller | test_deps : Detect mirrors file ----------------------------------------- 0.53s
2025-12-03 11:45:55.439401 | controller | repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist) --- 0.42s
2025-12-03 11:45:55.439524 | controller | test_deps : Disable ubi host subscription-manager integration ----------- 0.41s
2025-12-03 11:45:55.439635 | controller | repo_setup : Use RDO proxy mirrors -------------------------------------- 0.31s
2025-12-03 11:45:55.439764 | controller | repo_setup : Check for /etc/ci/mirror_info.sh --------------------------- 0.26s
2025-12-03 11:45:55.439861 | controller | test_deps : Gather variables for each operating system ------------------ 0.10s
2025-12-03 11:45:55.439984 | controller | Deploy repo-setup ------------------------------------------------------- 0.07s
2025-12-03 11:45:55.440121 | controller | test_deps : Install extra packages -------------------------------------- 0.07s
2025-12-03 11:45:55.536255 | controller | INFO     Running default > converge
2025-12-03 11:45:56.169663 | controller |
2025-12-03 11:45:56.169784 | controller | PLAY [Add crc to the inventory] ************************************************
2025-12-03 11:45:56.169905 | controller |
2025-12-03 11:45:56.170095 | controller | TASK [Gathering Facts] *********************************************************
2025-12-03 11:45:56.170249 | controller | Wednesday 03 December 2025  11:45:56 +0000 (0:00:00.022)       0:00:00.022 ****
2025-12-03 11:45:57.238893 | controller | ok: [instance]
2025-12-03 11:45:57.238994 | controller |
2025-12-03 11:45:57.239197 | controller | TASK [Add crc hostname with it's IP to /etc/hosts] *****************************
2025-12-03 11:45:57.239343 | controller | Wednesday 03 December 2025  11:45:57 +0000 (0:00:01.070)       0:00:01.092 ****
2025-12-03 11:45:57.658634 | controller | changed: [instance]
2025-12-03 11:45:57.658694 | controller |
2025-12-03 11:45:57.659024 | controller | TASK [Check if new ssh keypair exists] *****************************************
2025-12-03 11:45:57.690423 | controller | Wednesday 03 December 2025  11:45:57 +0000 (0:00:00.419)       0:00:01.512 ****
2025-12-03 11:45:57.690507 | controller |
2025-12-03 11:45:57.690544 | controller | TASK [recognize_ssh_keypair : Check if id_ed25519 key exists] ******************
2025-12-03 11:45:57.690694 | controller | Wednesday 03 December 2025  11:45:57 +0000 (0:00:00.031)       0:00:01.544 ****
2025-12-03 11:45:58.106884 | controller | ok: [instance]
2025-12-03 11:45:58.106983 | controller |
2025-12-03 11:45:58.107123 | controller | TASK [recognize_ssh_keypair : Set fact if new keypair exists] ******************
2025-12-03 11:45:58.107536 | controller | Wednesday 03 December 2025  11:45:58 +0000 (0:00:00.416)       0:00:01.960 ****
2025-12-03 11:45:58.136428 | controller | ok: [instance]
2025-12-03 11:45:58.136476 | controller |
2025-12-03 11:45:58.136576 | controller | TASK [recognize_ssh_keypair : Set fact if new keypair does not exists] *********
2025-12-03 11:45:58.136688 | controller | Wednesday 03 December 2025  11:45:58 +0000 (0:00:00.029)       0:00:01.990 ****
2025-12-03 11:45:58.167168 | controller | skipping: [instance]
2025-12-03 11:45:58.167228 | controller |
2025-12-03 11:45:58.167364 | controller | TASK [Add the crc host dynamically] ********************************************
2025-12-03 11:45:58.167471 | controller | Wednesday 03 December 2025  11:45:58 +0000 (0:00:00.030)       0:00:02.021 ****
2025-12-03 11:45:58.253239 | controller | changed: [instance]
2025-12-03 11:45:58.253324 | controller |
2025-12-03 11:45:58.253441 | controller | TASK [Add a dummy ethernet interface to target the crc NMstate] ****************
2025-12-03 11:45:58.253552 | controller | Wednesday 03 December 2025  11:45:58 +0000 (0:00:00.086)       0:00:02.107 ****
2025-12-03 11:45:58.825376 | controller | changed: [instance]
2025-12-03 11:46:03.989740 | controller |
2025-12-03 11:46:03.989801 | controller | PLAY [Get CRC facts] ***********************************************************
2025-12-03 11:46:03.989812 | controller |
2025-12-03 11:46:03.989820 | controller | TASK [Fetch crc network facts] *************************************************
2025-12-03 11:46:03.989829 | controller | Wednesday 03 December 2025  11:45:58 +0000 (0:00:00.571)       0:00:02.678 ****
2025-12-03 11:46:03.989844 | controller | ok: [crc]
2025-12-03 11:46:04.029945 | controller |
2025-12-03 11:46:04.029993 | controller | PLAY [Converge] ****************************************************************
2025-12-03 11:46:04.030002 | controller |
2025-12-03 11:46:04.030008 | controller | TASK [Set common molecule test facts] ******************************************
2025-12-03 11:46:04.030015 | controller | Wednesday 03 December 2025  11:46:03 +0000 (0:00:05.164)       0:00:07.843 ****
2025-12-03 11:46:04.030027 | controller | ok: [instance]
2025-12-03 11:46:04.030145 | controller |
2025-12-03 11:46:04.030156 | controller | TASK [Prepare the Networking Env Definition for ci_nmstate role] ***************
2025-12-03 11:46:04.030297 | controller | Wednesday 03 December 2025  11:46:04 +0000 (0:00:00.040)       0:00:07.883 ****
2025-12-03 11:46:04.097026 | controller |
2025-12-03 11:46:04.097131 | controller | TASK [networking_mapper : Check for Networking Definition file existence] ******
2025-12-03 11:46:04.097300 | controller | Wednesday 03 December 2025  11:46:04 +0000 (0:00:00.067)       0:00:07.951 ****
2025-12-03 11:46:04.225241 | controller | skipping: [instance]
2025-12-03 11:46:04.225308 | controller |
2025-12-03 11:46:04.225384 | controller | TASK [networking_mapper : Load the Networking Definition from file] ************
2025-12-03 11:46:04.225480 | controller | Wednesday 03 December 2025  11:46:04 +0000 (0:00:00.128)       0:00:08.079 ****
2025-12-03 11:46:04.321426 | controller | skipping: [instance]
2025-12-03 11:46:04.321568 | controller |
2025-12-03 11:46:04.321584 | controller | TASK [networking_mapper : Check for interfaces info file existence] ************
2025-12-03 11:46:04.387245 | controller | Wednesday 03 December 2025  11:46:04 +0000 (0:00:00.096)       0:00:08.175 ****
2025-12-03 11:46:04.387303 | controller | skipping: [instance]
2025-12-03 11:46:04.388047 | controller |
2025-12-03 11:46:04.388124 | controller | TASK [networking_mapper : Load the interfaces info from file] ******************
2025-12-03 11:46:04.442364 | controller | Wednesday 03 December 2025  11:46:04 +0000 (0:00:00.065)       0:00:08.241 ****
2025-12-03 11:46:04.442468 | controller | skipping: [instance]
2025-12-03 11:46:04.442523 | controller |
2025-12-03 11:46:04.442539 | controller | TASK [networking_mapper : Ensure local fact is empty] **************************
2025-12-03 11:46:04.442641 | controller | Wednesday 03 December 2025  11:46:04 +0000 (0:00:00.054)       0:00:08.295 ****
2025-12-03 11:46:04.474329 | controller | ok: [instance]
2025-12-03 11:46:04.474428 | controller |
2025-12-03 11:46:04.474449 | controller | TASK [networking_mapper : Set the input Networking Definition and patch it if needed] ***
2025-12-03 11:46:04.474590 | controller | Wednesday 03 December 2025  11:46:04 +0000 (0:00:00.032)       0:00:08.328 ****
2025-12-03 11:46:04.621653 | controller | ok: [instance] => (item={'networks': {'default': {'network': '38.129.56.0/24', 'gateway': '38.129.56.1', 'dns': ['1.1.1.1', '1.0.0.1'], 'mtu': '1450'}, 'testing-untagged': {'network': '172.17.20.0/24', 'dns': ['192.168.130.11', '192.168.130.1'], 'mtu': '1500'}, 'testing-vlan': {'network': '172.17.21.0/24', 'vlan': 21}}, 'instances': {'crc': {'networks': {'testing-untagged': {'ip': '172.17.20.5'}, 'testing-vlan': {'ip': '172.17.21.5', 'trunk-parent': 'testing-untagged'}}}, 'instance': {'networks': {'default': {'ip': '38.129.56.247'}}}}})
2025-12-03 11:46:04.621756 | controller |
2025-12-03 11:46:04.621770 | controller | TASK [networking_mapper : Gather facts if requested] ***************************
2025-12-03 11:46:04.621867 | controller | Wednesday 03 December 2025  11:46:04 +0000 (0:00:00.147)       0:00:08.475 ****
2025-12-03 11:46:04.657559 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/networking_mapper/tasks/_gather_facts.yml for instance
2025-12-03 11:46:04.657680 | controller |
2025-12-03 11:46:04.657829 | controller | TASK [networking_mapper : Gather the facts] ************************************
2025-12-03 11:46:04.657844 | controller | Wednesday 03 December 2025  11:46:04 +0000 (0:00:00.035)       0:00:08.511 ****
2025-12-03 11:46:05.113912 | controller | skipping: [instance] => (item=crc)
2025-12-03 11:46:05.114016 | controller | skipping: [instance] => (item=instance)
2025-12-03 11:46:05.114405 | controller | skipping: [instance]
2025-12-03 11:46:05.114466 | controller |
2025-12-03 11:46:05.114477 | controller | TASK [networking_mapper : Save instances refreshed facts for troubleshooting purposes] ***
2025-12-03 11:46:05.114641 | controller | Wednesday 03 December 2025  11:46:05 +0000 (0:00:00.456)       0:00:08.967 ****
2025-12-03 11:46:05.952549 | controller | changed: [instance]
2025-12-03 11:46:06.422187 | controller |
2025-12-03 11:46:06.422247 | controller | TASK [networking_mapper : Ensure CI infrastructure dir exists] *****************
2025-12-03 11:46:06.422259 | controller | Wednesday 03 December 2025  11:46:05 +0000 (0:00:00.838)       0:00:09.806 ****
2025-12-03 11:46:06.422288 | controller | changed: [instance]
2025-12-03 11:46:07.005140 | controller |
2025-12-03 11:46:07.005201 | controller | TASK [networking_mapper : Write the Networking Definition to file] *************
2025-12-03 11:46:07.005210 | controller | Wednesday 03 December 2025  11:46:06 +0000 (0:00:00.468)       0:00:10.274 ****
2025-12-03 11:46:07.005247 | controller | changed: [instance]
2025-12-03 11:46:07.151554 | controller |
2025-12-03 11:46:07.151612 | controller | TASK [networking_mapper : Call the networking mapper] **************************
2025-12-03 11:46:07.151625 | controller | Wednesday 03 December 2025  11:46:07 +0000 (0:00:00.584)       0:00:10.858 ****
2025-12-03 11:46:07.151643 | controller | ok: [instance]
2025-12-03 11:46:07.151653 | controller |
2025-12-03 11:46:07.151662 | controller | TASK [networking_mapper : Set networking mapper facts] *************************
2025-12-03 11:46:07.151674 | controller | Wednesday 03 December 2025  11:46:07 +0000 (0:00:00.146)       0:00:11.005 ****
2025-12-03 11:46:07.186012 | controller | ok: [instance]
2025-12-03 11:46:07.186170 | controller |
2025-12-03 11:46:07.186339 | controller | TASK [networking_mapper : Write the Networking Environment Definition to file] ***
2025-12-03 11:46:07.186531 | controller | Wednesday 03 December 2025  11:46:07 +0000 (0:00:00.034)       0:00:11.039 ****
2025-12-03 11:46:07.954039 | controller | changed: [instance]
2025-12-03 11:46:07.955096 | controller |
2025-12-03 11:46:08.014536 | controller | TASK [Call the role under test] ************************************************
2025-12-03 11:46:08.014633 | controller | Wednesday 03 December 2025  11:46:07 +0000 (0:00:00.768)       0:00:11.808 ****
2025-12-03 11:46:08.014659 | controller |
2025-12-03 11:46:08.014733 | controller | TASK [networking_mapper : Check for Networking Environment Definition file existence] ***
2025-12-03 11:46:08.014748 | controller | Wednesday 03 December 2025  11:46:08 +0000 (0:00:00.060)       0:00:11.868 ****
2025-12-03 11:46:08.051301 | controller | skipping: [instance]
2025-12-03 11:46:08.051725 | controller |
2025-12-03 11:46:08.051754 | controller | TASK [networking_mapper : Check for Networking Definition file existance] ******
2025-12-03 11:46:08.051951 | controller | Wednesday 03 December 2025  11:46:08 +0000 (0:00:00.036)       0:00:11.905 ****
2025-12-03 11:46:08.084901 | controller | skipping: [instance]
2025-12-03 11:46:08.084998 | controller |
2025-12-03 11:46:08.085086 | controller | TASK [networking_mapper : Load the Networking Definition from file] ************
2025-12-03 11:46:08.085220 | controller | Wednesday 03 December 2025  11:46:08 +0000 (0:00:00.033)       0:00:11.938 ****
2025-12-03 11:46:08.120839 | controller | skipping: [instance]
2025-12-03 11:46:08.120997 | controller |
2025-12-03 11:46:08.121240 | controller | TASK [networking_mapper : Set cifmw_networking_env_definition is present] ******
2025-12-03 11:46:08.121474 | controller | Wednesday 03 December 2025  11:46:08 +0000 (0:00:00.036)       0:00:11.974 ****
2025-12-03 11:46:08.167666 | controller | skipping: [instance]
2025-12-03 11:46:08.167811 | controller |
2025-12-03 11:46:08.168119 | controller | TASK [ci_nmstate : Initialize NMstates for each instance] **********************
2025-12-03 11:46:08.168333 | controller | Wednesday 03 December 2025  11:46:08 +0000 (0:00:00.046)       0:00:12.021 ****
2025-12-03 11:46:08.233228 | controller | ok: [instance]
2025-12-03 11:46:08.233306 | controller |
2025-12-03 11:46:08.233491 | controller | TASK [ci_nmstate : Build NMstates from the Networking Environment Definition] ***
2025-12-03 11:46:08.233633 | controller | Wednesday 03 December 2025  11:46:08 +0000 (0:00:00.065)       0:00:12.087 ****
2025-12-03 11:46:08.309771 | controller | ok: [instance] => (item=crc)
2025-12-03 11:46:08.309856 | controller | ok: [instance] => (item=instance)
2025-12-03 11:46:08.310058 | controller |
2025-12-03 11:46:08.310290 | controller | TASK [ci_nmstate : Get k8s nodes] **********************************************
2025-12-03 11:46:08.310502 | controller | Wednesday 03 December 2025  11:46:08 +0000 (0:00:00.076)       0:00:12.163 ****
2025-12-03 11:46:10.055537 | controller | ok: [instance]
2025-12-03 11:46:10.055620 | controller |
2025-12-03 11:46:10.055753 | controller | TASK [ci_nmstate : Set the hosts that will provision nmstate using k8s] ********
2025-12-03 11:46:10.055929 | controller | Wednesday 03 December 2025  11:46:10 +0000 (0:00:01.745)       0:00:13.909 ****
2025-12-03 11:46:10.159905 | controller | ok: [instance]
2025-12-03 11:46:10.160165 | controller |
2025-12-03 11:46:10.160498 | controller | TASK [ci_nmstate : Provision k8s workers with nmstate] *************************
2025-12-03 11:46:10.160812 | controller | Wednesday 03 December 2025  11:46:10 +0000 (0:00:00.103)       0:00:14.013 ****
2025-12-03 11:46:10.243382 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_nmstate/tasks/nmstate_k8s_install.yml for instance
2025-12-03 11:46:10.243472 | controller |
2025-12-03 11:46:10.243599 | controller | TASK [ci_nmstate : Create the target dir to dump NMstate manifests] ************
2025-12-03 11:46:10.243747 | controller | Wednesday 03 December 2025  11:46:10 +0000 (0:00:00.083)       0:00:14.097 ****
2025-12-03 11:46:10.526687 | controller | changed: [instance]
2025-12-03 11:46:10.526916 | controller |
2025-12-03 11:46:10.526955 | controller | TASK [ci_nmstate : Create the nmstate namespace] *******************************
2025-12-03 11:46:10.526973 | controller | Wednesday 03 December 2025  11:46:10 +0000 (0:00:00.283)       0:00:14.380 ****
2025-12-03 11:46:11.705875 | controller | changed: [instance]
2025-12-03 11:46:11.706182 | controller |
2025-12-03 11:46:11.706266 | controller | TASK [ci_nmstate : Generate the OperatorGroup final CR] ************************
2025-12-03 11:46:11.706284 | controller | Wednesday 03 December 2025  11:46:11 +0000 (0:00:01.179)       0:00:15.559 ****
2025-12-03 11:46:11.818833 | controller | ok: [instance] => (item={'apiVersion': 'operators.coreos.com/v1', 'kind': 'OperatorGroup', 'metadata': {'annotations': {'olm.providedAPIs': 'NMState.v1.nmstate.io'}, 'name': 'openshift-nmstate', 'namespace': 'openshift-nmstate'}, 'spec': {'targetNamespaces': ['openshift-nmstate']}})
2025-12-03 11:46:11.818974 | controller |
2025-12-03 11:46:11.819021 | controller | TASK [ci_nmstate : Generate the Subscription final CR] *************************
2025-12-03 11:46:11.819159 | controller | Wednesday 03 December 2025  11:46:11 +0000 (0:00:00.112)       0:00:15.671 ****
2025-12-03 11:46:11.920213 | controller | ok: [instance] => (item={'apiVersion': 'operators.coreos.com/v1alpha1', 'kind': 'Subscription', 'metadata': {'labels': {'operators.coreos.com/kubernetes-nmstate-operator.openshift-nmstate': ''}, 'name': 'kubernetes-nmstate-operator', 'namespace': 'openshift-nmstate'}, 'spec': {'channel': 'stable', 'installPlanApproval': 'Automatic', 'name': 'kubernetes-nmstate-operator', 'source': 'redhat-operators', 'sourceNamespace': 'openshift-marketplace'}})
2025-12-03 11:46:11.920269 | controller |
2025-12-03 11:46:11.920431 | controller | TASK [ci_nmstate : Save k8s nmstate OLM manifests as artifacts] ****************
2025-12-03 11:46:11.920568 | controller | Wednesday 03 December 2025  11:46:11 +0000 (0:00:00.102)       0:00:15.773 ****
2025-12-03 11:46:13.510123 | controller | changed: [instance] => (item=openshift-nmstate)
2025-12-03 11:46:13.510195 | controller | changed: [instance] => (item=kubernetes-nmstate-operator)
2025-12-03 11:46:13.510497 | controller |
2025-12-03 11:46:13.510543 | controller | TASK [ci_nmstate : Create the OLM subscription resources] **********************
2025-12-03 11:46:15.448315 | controller | Wednesday 03 December 2025  11:46:13 +0000 (0:00:01.589)       0:00:17.363 ****
2025-12-03 11:46:15.448378 | controller | changed: [instance] => (item=openshift-nmstate)
2025-12-03 11:46:47.882405 | controller | changed: [instance] => (item=kubernetes-nmstate-operator)
2025-12-03 11:46:47.882472 | controller |
2025-12-03 11:46:47.882480 | controller | TASK [ci_nmstate : Wait for nmstate operator to be installed] ******************
2025-12-03 11:46:47.882488 | controller | Wednesday 03 December 2025  11:46:15 +0000 (0:00:01.938)       0:00:19.301 ****
2025-12-03 11:46:47.882503 | controller | FAILED - RETRYING: [instance]: Wait for nmstate operator to be installed (12 retries left).
2025-12-03 11:46:47.882644 | controller | FAILED - RETRYING: [instance]: Wait for nmstate operator to be installed (11 retries left).
2025-12-03 11:46:47.882659 | controller | FAILED - RETRYING: [instance]: Wait for nmstate operator to be installed (10 retries left).
2025-12-03 11:46:47.882688 | controller | ok: [instance]
2025-12-03 11:46:47.883044 | controller |
2025-12-03 11:46:47.883083 | controller | TASK [ci_nmstate : Save nmstate operator config as an artifact] ****************
2025-12-03 11:46:47.883094 | controller | Wednesday 03 December 2025  11:46:47 +0000 (0:00:32.434)       0:00:51.736 ****
2025-12-03 11:46:48.434493 | controller | changed: [instance]
2025-12-03 11:46:49.274326 | controller |
2025-12-03 11:46:49.274386 | controller | TASK [ci_nmstate : Trigger nmstate operator to install nmstate] ****************
2025-12-03 11:46:49.274395 | controller | Wednesday 03 December 2025  11:46:48 +0000 (0:00:00.551)       0:00:52.288 ****
2025-12-03 11:46:49.274405 | controller | changed: [instance]
2025-12-03 11:46:49.274575 | controller |
2025-12-03 11:46:49.274595 | controller | TASK [ci_nmstate : Wait for nsmtate Deployments to be ready] *******************
2025-12-03 11:46:54.455535 | RUN END RESULT_TIMED_OUT: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-12-03 11:46:54.456459 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-12-03 11:46:55.089588 | 
2025-12-03 11:46:55.089697 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-12-03 11:46:55.116720 | 
2025-12-03 11:46:55.116850 | TASK [Filter out host if needed]
2025-12-03 11:46:55.126053 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-12-03 11:46:55.130709 | 
2025-12-03 11:46:55.130781 | TASK [Ensure file is present]
2025-12-03 11:46:55.637082 | controller | ok
2025-12-03 11:46:55.649010 | 
2025-12-03 11:46:55.649164 | TASK [Manage molecule report file]
2025-12-03 11:46:56.184664 | controller | skipping: Conditional result was False
2025-12-03 11:46:56.190759 | 
2025-12-03 11:46:56.190829 | TASK [Check if we get ci-framework-data basedir]
2025-12-03 11:46:56.615800 | controller | ok
2025-12-03 11:46:56.621364 | 
2025-12-03 11:46:56.621438 | TASK [Create ci-framework-data log directory for zuul]
2025-12-03 11:46:57.216874 | controller | changed
2025-12-03 11:46:57.234545 | 
2025-12-03 11:46:57.234861 | TASK [Copy ci-framework interesting files]
2025-12-03 11:46:57.904508 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2025-12-03 11:46:58.288058 | controller | changed
2025-12-03 11:46:58.296448 | 
2025-12-03 11:46:58.296680 | TASK [Get SELinux listing]
2025-12-03 11:46:58.848469 | controller | changed
2025-12-03 11:46:58.868806 | 
2025-12-03 11:46:58.869130 | TASK [Generate log index]
2025-12-03 11:47:00.711398 | controller | changed
2025-12-03 11:47:00.721000 | 
2025-12-03 11:47:00.721180 | TASK [Get some env related data]
2025-12-03 11:47:01.736351 | controller | /home/zuul/.local/bin/ansible
2025-12-03 11:47:02.768527 | controller | changed
2025-12-03 11:47:02.854319 | 
2025-12-03 11:47:02.854467 | TASK [Generate list of logs to collect in home directory]
2025-12-03 11:47:03.425606 | controller | ok: All paths examined
2025-12-03 11:47:03.434839 | 
2025-12-03 11:47:03.434940 | LOOP [Copy logs from home directory]
2025-12-03 11:47:04.578531 | controller | changed:
2025-12-03 11:47:04.578876 | controller | {
2025-12-03 11:47:04.578903 | controller |   "atime": 1763989167.9348345,
2025-12-03 11:47:04.578923 | controller |   "ctime": 1763990251.6129293,
2025-12-03 11:47:04.578940 | controller |   "dev": 64513,
2025-12-03 11:47:04.578956 | controller |   "gid": 1000,
2025-12-03 11:47:04.578973 | controller |   "gr_name": "zuul",
2025-12-03 11:47:04.578991 | controller |   "inode": 5131423,
2025-12-03 11:47:04.579007 | controller |   "isblk": false,
2025-12-03 11:47:04.579047 | controller |   "ischr": false,
2025-12-03 11:47:04.579065 | controller |   "isdir": false,
2025-12-03 11:47:04.579081 | controller |   "isfifo": false,
2025-12-03 11:47:04.579097 | controller |   "isgid": false,
2025-12-03 11:47:04.579112 | controller |   "islnk": false,
2025-12-03 11:47:04.579127 | controller |   "isreg": true,
2025-12-03 11:47:04.579143 | controller |   "issock": false,
2025-12-03 11:47:04.579158 | controller |   "isuid": false,
2025-12-03 11:47:04.579173 | controller |   "mode": "0644",
2025-12-03 11:47:04.579188 | controller |   "mtime": 1763990251.6129293,
2025-12-03 11:47:04.579204 | controller |   "nlink": 1,
2025-12-03 11:47:04.579219 | controller |   "path": "/home/zuul/crc-setup.log",
2025-12-03 11:47:04.579239 | controller |   "pw_name": "zuul",
2025-12-03 11:47:04.579255 | controller |   "rgrp": true,
2025-12-03 11:47:04.579270 | controller |   "roth": true,
2025-12-03 11:47:04.579286 | controller |   "rusr": true,
2025-12-03 11:47:04.579301 | controller |   "size": 4108,
2025-12-03 11:47:04.579317 | controller |   "uid": 1000,
2025-12-03 11:47:04.579331 | controller |   "wgrp": false,
2025-12-03 11:47:04.579346 | controller |   "woth": false,
2025-12-03 11:47:04.579365 | controller |   "wusr": true,
2025-12-03 11:47:04.579381 | controller |   "xgrp": false,
2025-12-03 11:47:04.579397 | controller |   "xoth": false,
2025-12-03 11:47:04.579413 | controller |   "xusr": false
2025-12-03 11:47:04.579428 | controller | }
2025-12-03 11:47:05.783285 | controller | changed:
2025-12-03 11:47:05.783379 | controller | {
2025-12-03 11:47:05.783402 | controller |   "atime": 1764761515.00938,
2025-12-03 11:47:05.783420 | controller |   "ctime": 1764761511.136283,
2025-12-03 11:47:05.783439 | controller |   "dev": 64513,
2025-12-03 11:47:05.783461 | controller |   "gid": 1000,
2025-12-03 11:47:05.784673 | controller |   "gr_name": "zuul",
2025-12-03 11:47:05.784700 | controller |   "inode": 5131425,
2025-12-03 11:47:05.784736 | controller |   "isblk": false,
2025-12-03 11:47:05.784755 | controller |   "ischr": false,
2025-12-03 11:47:05.784793 | controller |   "isdir": false,
2025-12-03 11:47:05.784819 | controller |   "isfifo": false,
2025-12-03 11:47:05.785085 | controller |   "isgid": false,
2025-12-03 11:47:05.785113 | controller |   "islnk": false,
2025-12-03 11:47:05.785136 | controller |   "isreg": true,
2025-12-03 11:47:05.785160 | controller |   "issock": false,
2025-12-03 11:47:05.785181 | controller |   "isuid": false,
2025-12-03 11:47:05.785201 | controller |   "mode": "0644",
2025-12-03 11:47:05.785222 | controller |   "mtime": 1764761511.136283,
2025-12-03 11:47:05.785241 | controller |   "nlink": 1,
2025-12-03 11:47:05.785256 | controller |   "path": "/home/zuul/crc-start.log",
2025-12-03 11:47:05.785271 | controller |   "pw_name": "zuul",
2025-12-03 11:47:05.785286 | controller |   "rgrp": true,
2025-12-03 11:47:05.785301 | controller |   "roth": true,
2025-12-03 11:47:05.786000 | controller |   "rusr": true,
2025-12-03 11:47:05.786060 | controller |   "size": 4872,
2025-12-03 11:47:05.786640 | controller |   "uid": 1000,
2025-12-03 11:47:05.786672 | controller |   "wgrp": false,
2025-12-03 11:47:05.786690 | controller |   "woth": false,
2025-12-03 11:47:05.786712 | controller |   "wusr": true,
2025-12-03 11:47:05.786736 | controller |   "xgrp": false,
2025-12-03 11:47:05.786759 | controller |   "xoth": false,
2025-12-03 11:47:05.786782 | controller |   "xusr": false
2025-12-03 11:47:05.786806 | controller | }
2025-12-03 11:47:06.753324 | controller | changed:
2025-12-03 11:47:06.753437 | controller | {
2025-12-03 11:47:06.753461 | controller |   "atime": 1764762195.4567769,
2025-12-03 11:47:06.753479 | controller |   "ctime": 1764762223.5974848,
2025-12-03 11:47:06.753495 | controller |   "dev": 64513,
2025-12-03 11:47:06.753512 | controller |   "gid": 1000,
2025-12-03 11:47:06.753527 | controller |   "gr_name": "zuul",
2025-12-03 11:47:06.753542 | controller |   "inode": 4764380,
2025-12-03 11:47:06.753558 | controller |   "isblk": false,
2025-12-03 11:47:06.753572 | controller |   "ischr": false,
2025-12-03 11:47:06.753587 | controller |   "isdir": false,
2025-12-03 11:47:06.753601 | controller |   "isfifo": false,
2025-12-03 11:47:06.753616 | controller |   "isgid": false,
2025-12-03 11:47:06.753630 | controller |   "islnk": false,
2025-12-03 11:47:06.753645 | controller |   "isreg": true,
2025-12-03 11:47:06.753659 | controller |   "issock": false,
2025-12-03 11:47:06.753674 | controller |   "isuid": false,
2025-12-03 11:47:06.753689 | controller |   "mode": "0644",
2025-12-03 11:47:06.753703 | controller |   "mtime": 1764762223.5974848,
2025-12-03 11:47:06.753718 | controller |   "nlink": 1,
2025-12-03 11:47:06.753733 | controller |   "path": "/home/zuul/ansible.log",
2025-12-03 11:47:06.753748 | controller |   "pw_name": "zuul",
2025-12-03 11:47:06.753764 | controller |   "rgrp": true,
2025-12-03 11:47:06.753779 | controller |   "roth": true,
2025-12-03 11:47:06.753797 | controller |   "rusr": true,
2025-12-03 11:47:06.753814 | controller |   "size": 6749,
2025-12-03 11:47:06.753829 | controller |   "uid": 1000,
2025-12-03 11:47:06.753844 | controller |   "wgrp": false,
2025-12-03 11:47:06.753859 | controller |   "woth": false,
2025-12-03 11:47:06.753873 | controller |   "wusr": true,
2025-12-03 11:47:06.753888 | controller |   "xgrp": false,
2025-12-03 11:47:06.753902 | controller |   "xoth": false,
2025-12-03 11:47:06.753917 | controller |   "xusr": false
2025-12-03 11:47:06.753931 | controller | }
2025-12-03 11:47:06.762720 | 
2025-12-03 11:47:06.762783 | TASK [Copy crio stats log file]
2025-12-03 11:47:06.795434 | controller | skipping: Conditional result was False
2025-12-03 11:47:06.802421 | 
2025-12-03 11:47:06.802502 | TASK [Get SELinux related data]
2025-12-03 11:47:07.208010 | controller | <no matches>
2025-12-03 11:47:07.341909 | controller | ERROR
2025-12-03 11:47:07.342198 | controller | {
2025-12-03 11:47:07.342228 | controller |   "delta": "0:00:00.018061",
2025-12-03 11:47:07.342248 | controller |   "end": "2025-12-03 11:47:07.208386",
2025-12-03 11:47:07.342266 | controller |   "msg": "non-zero return code",
2025-12-03 11:47:07.342282 | controller |   "rc": 1,
2025-12-03 11:47:07.342298 | controller |   "start": "2025-12-03 11:47:07.190325"
2025-12-03 11:47:07.342314 | controller | }
2025-12-03 11:47:07.342336 | controller | ERROR: Ignoring Errors
2025-12-03 11:47:07.348300 | 
2025-12-03 11:47:07.348396 | TASK [Create system configuration directory]
2025-12-03 11:47:07.754621 | controller | changed
2025-12-03 11:47:07.763813 | 
2025-12-03 11:47:07.764582 | TASK [Get some of the system configurations]
2025-12-03 11:47:08.813236 | controller | changed
2025-12-03 11:47:08.823935 | 
2025-12-03 11:47:08.824088 | TASK [Copy generated documentation if available]
2025-12-03 11:47:08.866070 | controller | skipping: Conditional result was False
2025-12-03 11:47:08.874503 | 
2025-12-03 11:47:08.874598 | TASK [Copy generated AsciiDoc documentation if available]
2025-12-03 11:47:08.909593 | controller | skipping: Conditional result was False
2025-12-03 11:47:08.916747 | 
2025-12-03 11:47:08.916817 | TASK [Compress logs bigger than 2MB]
2025-12-03 11:47:09.478808 | controller | changed
2025-12-03 11:47:09.485763 | 
2025-12-03 11:47:09.485827 | TASK [Copy files from workspace on node]
2025-12-03 11:47:09.517311 | controller | ok
2025-12-03 11:47:09.579703 | 
2025-12-03 11:47:09.579824 | TASK [fetch-output : Set log path for multiple nodes]
2025-12-03 11:47:09.682426 | controller | skipping: Conditional result was False
2025-12-03 11:47:09.696737 | 
2025-12-03 11:47:09.696861 | TASK [fetch-output : Set log path for single node]
2025-12-03 11:47:09.755228 | controller | ok
2025-12-03 11:47:09.760340 | 
2025-12-03 11:47:09.760403 | LOOP [fetch-output : Ensure local output dirs]
2025-12-03 11:47:10.162655 | controller -> localhost | ok: "/var/lib/zuul/builds/e3f79eac2708499abc53fb6760a65239/work/logs"
2025-12-03 11:47:10.162984 | controller -> localhost | changed: All items complete
2025-12-03 11:47:10.163090 | 
2025-12-03 11:47:10.442160 | controller -> localhost | changed: "/var/lib/zuul/builds/e3f79eac2708499abc53fb6760a65239/work/artifacts"
2025-12-03 11:47:10.704878 | controller -> localhost | changed: "/var/lib/zuul/builds/e3f79eac2708499abc53fb6760a65239/work/docs"
2025-12-03 11:47:10.725113 | 
2025-12-03 11:47:10.725258 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-12-03 11:47:11.684096 | controller | changed:
2025-12-03 11:47:11.684324 | controller | .d..t...... ./
2025-12-03 11:47:11.684375 | controller | >f+++++++++ README.html
2025-12-03 11:47:11.684407 | controller | >f+++++++++ ansible-execution.log
2025-12-03 11:47:11.684432 | controller | >f+++++++++ ansible.log
2025-12-03 11:47:11.684457 | controller | >f+++++++++ crc-setup.log
2025-12-03 11:47:11.684480 | controller | >f+++++++++ crc-start.log
2025-12-03 11:47:11.684503 | controller | >f+++++++++ dmesg.log
2025-12-03 11:47:11.684530 | controller | >f+++++++++ installed-pkgs.log
2025-12-03 11:47:11.684561 | controller | >f+++++++++ python.log
2025-12-03 11:47:11.684598 | controller | >f+++++++++ registries.conf
2025-12-03 11:47:11.684634 | controller | >f+++++++++ selinux-denials.log
2025-12-03 11:47:11.684697 | controller | >f+++++++++ selinux-listing.log
2025-12-03 11:47:11.684730 | controller | cd+++++++++ ci-framework-data/
2025-12-03 11:47:11.684762 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-12-03 11:47:11.684789 | controller | >f+++++++++ ci-framework-data/artifacts/networking-mapper-instances-facts.yml
2025-12-03 11:47:11.684812 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-12-03 11:47:11.684834 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/
2025-12-03 11:47:11.684855 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/nmstate/
2025-12-03 11:47:11.684877 | controller | >f+++++++++ ci-framework-data/artifacts/manifests/nmstate/nmstate-nmstate-olm.yaml
2025-12-03 11:47:11.684899 | controller | >f+++++++++ ci-framework-data/artifacts/manifests/nmstate/nmstate-operatorgroup-olm.yaml
2025-12-03 11:47:11.684921 | controller | >f+++++++++ ci-framework-data/artifacts/manifests/nmstate/nmstate-subscription-olm.yaml
2025-12-03 11:47:11.684942 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-12-03 11:47:11.684965 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-12-03 11:47:11.684988 | controller | cd+++++++++ ci-framework-data/artifacts/repositories/
2025-12-03 11:47:11.685011 | controller | >f+++++++++ ci-framework-data/artifacts/repositories/delorean.repo.md5
2025-12-03 11:47:11.685064 | controller | cd+++++++++ ci-framework-data/logs/
2025-12-03 11:47:11.685088 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2025-12-03 11:47:11.685110 | controller | cd+++++++++ registries.conf.d/
2025-12-03 11:47:11.685132 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-12-03 11:47:11.685154 | controller | cd+++++++++ system-config/
2025-12-03 11:47:11.685175 | controller | cd+++++++++ system-config/libvirt/
2025-12-03 11:47:11.685196 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-12-03 11:47:11.685219 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-12-03 11:47:11.685244 | controller | >f+++++++++ system-config/libvirt/libvirtd.conf
2025-12-03 11:47:11.685265 | controller | >f+++++++++ system-config/libvirt/network.conf
2025-12-03 11:47:11.685286 | controller | >f+++++++++ system-config/libvirt/qemu-lockd.conf
2025-12-03 11:47:11.685307 | controller | >f+++++++++ system-config/libvirt/qemu.conf
2025-12-03 11:47:11.685337 | controller | >f+++++++++ system-config/libvirt/virtinterfaced.conf
2025-12-03 11:47:11.685359 | controller | >f+++++++++ system-config/libvirt/virtlockd.conf
2025-12-03 11:47:11.685381 | controller | >f+++++++++ system-config/libvirt/virtlogd.conf
2025-12-03 11:47:11.685402 | controller | >f+++++++++ system-config/libvirt/virtnetworkd.conf
2025-12-03 11:47:11.685424 | controller | >f+++++++++ system-config/libvirt/virtnodedevd.conf
2025-12-03 11:47:11.685446 | controller | >f+++++++++ system-config/libvirt/virtnwfilterd.conf
2025-12-03 11:47:11.685468 | controller | >f+++++++++ system-config/libvirt/virtproxyd.conf
2025-12-03 11:47:11.685489 | controller | >f+++++++++ system-config/libvirt/virtqemud.conf
2025-12-03 11:47:11.685511 | controller | >f+++++++++ system-config/libvirt/virtsecretd.conf
2025-12-03 11:47:11.685532 | controller | >f+++++++++ system-config/libvirt/virtstoraged.conf
2025-12-03 11:47:12.351315 | controller | changed: .d..t...... ./
2025-12-03 11:47:13.185248 | controller | changed: .d..t...... ./
2025-12-03 11:47:13.207434 | 
2025-12-03 11:47:13.207529 | TASK [Return artifact to Zuul]
2025-12-03 11:47:13.231808 | controller | skipping: Conditional result was False
2025-12-03 11:47:13.253714 | 
2025-12-03 11:47:13.253802 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-12-03 11:47:13.253902 | 
2025-12-03 11:47:13.253929 | PLAY RECAP
2025-12-03 11:47:13.253968 | controller | ok: 16 changed: 12 unreachable: 0 failed: 0 skipped: 6 rescued: 0 ignored: 1
2025-12-03 11:47:13.253988 | 
2025-12-03 11:47:13.425907 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-12-03 11:47:13.426983 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/dlrn/dlrn-report.yaml@master]
2025-12-03 11:47:14.018751 | 
2025-12-03 11:47:14.018875 | PLAY [Report DLRN results after job run]
2025-12-03 11:47:14.040574 | 
2025-12-03 11:47:14.040711 | TASK [Report job status to DLRN]
2025-12-03 11:47:14.059743 | controller | ok
2025-12-03 11:47:14.086125 | 
2025-12-03 11:47:14.086271 | TASK [dlrn_report : Warning if DLRN is not running]
2025-12-03 11:47:14.151802 | controller | skipping: Conditional result was False
2025-12-03 11:47:14.158675 | 
2025-12-03 11:47:14.158803 | TASK [dlrn_report : Install dlrnapi-client shyaml package]
2025-12-03 11:47:14.217583 | controller | skipping: Conditional result was False
2025-12-03 11:47:14.227466 | 
2025-12-03 11:47:14.227703 | TASK [dlrn_report : Install kinit related package]
2025-12-03 11:47:14.284298 | controller | skipping: Conditional result was False
2025-12-03 11:47:14.291750 | 
2025-12-03 11:47:14.291883 | TASK [dlrn_report : Install dlrn kerberos related packages]
2025-12-03 11:47:14.347328 | controller | skipping: Conditional result was False
2025-12-03 11:47:14.355282 | 
2025-12-03 11:47:14.355411 | TASK [Set zuul-log-path fact]
2025-12-03 11:47:14.402691 | controller | skipping: Conditional result was False
2025-12-03 11:47:14.410758 | 
2025-12-03 11:47:14.410872 | TASK [dlrn_report : Set the the value of cifmw_repo_setup_promotion when multiple dlrn tags are used]
2025-12-03 11:47:14.506672 | controller | skipping: Conditional result was False
2025-12-03 11:47:14.513521 | 
2025-12-03 11:47:14.514007 | TASK [Get hash related data from repo_setup role]
2025-12-03 11:47:14.589601 | controller | skipping: Conditional result was False
2025-12-03 11:47:14.602173 | 
2025-12-03 11:47:14.602394 | TASK [dlrn_report : Perform kinit for DLRN kerberos authentication]
2025-12-03 11:47:15.140436 | controller | skipping: Conditional result was False
2025-12-03 11:47:15.146361 | 
2025-12-03 11:47:15.146434 | TASK [dlrn_report : Set empty value for dlrnapi password]
2025-12-03 11:47:15.200697 | controller | skipping: Conditional result was False
2025-12-03 11:47:15.207576 | 
2025-12-03 11:47:15.207708 | TASK [dlrn_report : Report results to dlrn for the tested hash]
2025-12-03 11:47:15.752428 | controller | skipping: Conditional result was False
2025-12-03 11:47:15.782338 | 
2025-12-03 11:47:15.782432 | PLAY RECAP
2025-12-03 11:47:15.782476 | controller | ok: 0 changed: 0 unreachable: 0 failed: 0 skipped: 10 rescued: 0 ignored: 0
2025-12-03 11:47:15.782499 | 
2025-12-03 11:47:15.947642 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/dlrn/dlrn-report.yaml@master]
2025-12-03 11:47:15.948947 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-12-03 11:47:16.635908 | 
2025-12-03 11:47:16.636062 | PLAY [all]
2025-12-03 11:47:16.657876 | 
2025-12-03 11:47:16.658063 | TASK [include_role : fetch-output]
2025-12-03 11:47:16.730361 | controller | ok
2025-12-03 11:47:16.758254 | 
2025-12-03 11:47:16.758404 | TASK [fetch-output : Set log path for multiple nodes]
2025-12-03 11:47:16.850591 | controller | skipping: Conditional result was False
2025-12-03 11:47:16.866385 | 
2025-12-03 11:47:16.866522 | TASK [fetch-output : Set log path for single node]
2025-12-03 11:47:16.922747 | controller | ok
2025-12-03 11:47:16.929175 | 
2025-12-03 11:47:16.929741 | LOOP [fetch-output : Ensure local output dirs]
2025-12-03 11:47:17.462153 | controller -> localhost | ok: "/var/lib/zuul/builds/e3f79eac2708499abc53fb6760a65239/work/logs"
2025-12-03 11:47:17.733543 | controller -> localhost | ok: "/var/lib/zuul/builds/e3f79eac2708499abc53fb6760a65239/work/artifacts"
2025-12-03 11:47:17.994286 | controller -> localhost | ok: "/var/lib/zuul/builds/e3f79eac2708499abc53fb6760a65239/work/docs"
2025-12-03 11:47:18.007880 | 
2025-12-03 11:47:18.007993 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-12-03 11:47:18.880900 | controller | ok
2025-12-03 11:47:18.881194 | controller | ok: All items complete
2025-12-03 11:47:18.881228 | 
2025-12-03 11:47:19.473580 | controller | ok
2025-12-03 11:47:20.088445 | controller | ok
2025-12-03 11:47:20.111501 | 
2025-12-03 11:47:20.111624 | TASK [include_role : fetch-output-openshift]
2025-12-03 11:47:20.147091 | controller | skipping: Conditional result was False
2025-12-03 11:47:20.163553 | 
2025-12-03 11:47:20.163662 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-12-03 11:47:20.598203 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.007856
2025-12-03 11:47:20.840557 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.009035
2025-12-03 11:47:20.911306 | 
2025-12-03 11:47:20.912334 | PLAY [all]
2025-12-03 11:47:20.950186 | 
2025-12-03 11:47:20.950415 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-12-03 11:47:21.619793 | controller | changed
2025-12-03 11:47:21.647565 | 
2025-12-03 11:47:21.647678 | PLAY RECAP
2025-12-03 11:47:21.647723 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-12-03 11:47:21.647744 | 
2025-12-03 11:47:21.774606 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-12-03 11:47:21.775412 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-12-03 11:47:22.504226 | 
2025-12-03 11:47:22.504346 | PLAY [localhost]
2025-12-03 11:47:22.525231 | 
2025-12-03 11:47:22.525374 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-12-03 11:47:22.960572 | localhost | changed
2025-12-03 11:47:22.965674 | 
2025-12-03 11:47:22.965761 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-12-03 11:47:22.994389 | localhost | ok
2025-12-03 11:47:23.004212 | 
2025-12-03 11:47:23.004365 | TASK [add-fileserver : Create SSH private key tempfile]
2025-12-03 11:47:23.513153 | localhost | changed
2025-12-03 11:47:23.518112 | 
2025-12-03 11:47:23.520354 | TASK [add-fileserver : Create SSH private key from secret]
2025-12-03 11:47:24.366069 | localhost | changed
2025-12-03 11:47:24.375624 | 
2025-12-03 11:47:24.375729 | TASK [add-fileserver : Add fileserver ssh key]
2025-12-03 11:47:24.817005 | localhost | Identity added: /var/lib/zuul/builds/e3f79eac2708499abc53fb6760a65239/work/tmp/ansible.la7xkuzg (/var/lib/zuul/builds/e3f79eac2708499abc53fb6760a65239/work/tmp/ansible.la7xkuzg)
2025-12-03 11:47:24.817220 | localhost | ok: Runtime: 0:00:00.013987
2025-12-03 11:47:24.822971 | 
2025-12-03 11:47:24.823139 | TASK [add-fileserver : Remove SSH private key from disk]
2025-12-03 11:47:25.077365 | localhost | ok: Runtime: 0:00:00.013053
2025-12-03 11:47:25.082968 | 
2025-12-03 11:47:25.083052 | TASK [add-fileserver : Add fileserver to inventory]
2025-12-03 11:47:25.143075 | localhost | changed
2025-12-03 11:47:25.148207 | 
2025-12-03 11:47:25.148319 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-12-03 11:47:25.588536 | localhost | changed
2025-12-03 11:47:25.611036 | 
2025-12-03 11:47:25.611145 | PLAY [localhost]
2025-12-03 11:47:25.623544 | 
2025-12-03 11:47:25.623611 | TASK [Generate bulk log download script]
2025-12-03 11:47:25.648568 | localhost | ok
2025-12-03 11:47:25.665142 | 
2025-12-03 11:47:25.665232 | TASK [local-log-download : Check API endpoint is defined]
2025-12-03 11:47:25.693728 | localhost | ok: All assertions passed
2025-12-03 11:47:25.699531 | 
2025-12-03 11:47:25.699602 | TASK [local-log-download : Create download script]
2025-12-03 11:47:26.116792 | localhost -> localhost | changed
2025-12-03 11:47:26.127309 | 
2025-12-03 11:47:26.127387 | TASK [Register quick-download link]
2025-12-03 11:47:26.166865 | localhost | ok
2025-12-03 11:47:26.212406 | 
2025-12-03 11:47:26.212504 | PLAY [logserver.rdoproject.org]
2025-12-03 11:47:26.226225 | 
2025-12-03 11:47:26.226332 | TASK [Set zuul-log-path fact]
2025-12-03 11:47:26.242690 | logserver.rdoproject.org | ok
2025-12-03 11:47:26.253111 | 
2025-12-03 11:47:26.253725 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-12-03 11:47:26.279938 | logserver.rdoproject.org | ok
2025-12-03 11:47:26.285745 | 
2025-12-03 11:47:26.285823 | TASK [upload-logs : Create log directories]
2025-12-03 11:47:27.148036 | logserver.rdoproject.org | changed
2025-12-03 11:47:27.151609 | 
2025-12-03 11:47:27.151682 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-12-03 11:47:27.450518 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.008381
2025-12-03 11:47:27.455676 | 
2025-12-03 11:47:27.455763 | TASK [upload-logs : Upload logs to log server]
2025-12-03 11:47:28.184458 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-12-03 11:47:28.187838 | 
2025-12-03 11:47:28.187906 | LOOP [upload-logs : Compress console log and json output]
2025-12-03 11:47:28.245036 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-03 11:47:28.265851 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-03 11:47:28.270562 | 
2025-12-03 11:47:28.270630 | LOOP [upload-logs : Upload compressed console log and json output]
2025-12-03 11:47:28.316786 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-03 11:47:28.317022 | 
2025-12-03 11:47:28.317540 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-03 11:47:28.328761 | 
2025-12-03 11:47:28.328840 | LOOP [upload-logs : Upload console log and json output]