2025-10-10 22:00:56.828863 | Job console starting...
2025-10-10 22:00:57.066735 | Updating repositories
2025-10-10 22:00:57.844717 | Preparing job workspace
2025-10-10 22:01:04.897190 | Running Ansible setup...
2025-10-10 22:01:07.568069 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-10-10 22:01:08.037342 | 
2025-10-10 22:01:08.037430 | PLAY [localhost]
2025-10-10 22:01:08.045339 | 
2025-10-10 22:01:08.045405 | TASK [Gathering Facts]
2025-10-10 22:01:08.808717 | localhost | ok
2025-10-10 22:01:08.821185 | 
2025-10-10 22:01:08.821264 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-10-10 22:01:09.118270 | localhost -> localhost | changed
2025-10-10 22:01:09.123325 | 
2025-10-10 22:01:09.123390 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-10-10 22:01:09.744587 | localhost -> localhost | changed
2025-10-10 22:01:09.752273 | 
2025-10-10 22:01:09.752344 | TASK [Setup log path fact]
2025-10-10 22:01:09.768529 | localhost | ok
2025-10-10 22:01:09.778267 | 
2025-10-10 22:01:09.778328 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-10 22:01:09.794073 | localhost | ok
2025-10-10 22:01:09.800581 | 
2025-10-10 22:01:09.800640 | TASK [emit-job-header : Print job information]
2025-10-10 22:01:09.826365 | # Job Information
2025-10-10 22:01:09.826471 | Ansible Version: 2.15.12
2025-10-10 22:01:09.826498 | Job: cifmw-molecule-operator_build
2025-10-10 22:01:09.826519 | Pipeline: github-check
2025-10-10 22:01:09.826537 | Executor: ibm-bm4-ze.softwarefactory-project.io
2025-10-10 22:01:09.826553 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3400
2025-10-10 22:01:09.826570 | Log URL (when completed): https://logserver.rdoproject.org/a92/rdoproject.org/a92840bc01cf4623a0bad38b1a4d922b/
2025-10-10 22:01:09.826587 | Event ID: 528f4580-a624-11f0-920a-d91eeee5a30e
2025-10-10 22:01:09.829894 | 
2025-10-10 22:01:09.829976 | LOOP [emit-job-header : Print node information]
2025-10-10 22:01:09.907513 | localhost | ok:
2025-10-10 22:01:09.907669 | localhost | # Node Information
2025-10-10 22:01:09.907699 | localhost | Inventory Hostname: controller
2025-10-10 22:01:09.907722 | localhost | Hostname: np0005480651
2025-10-10 22:01:09.907743 | localhost | Username: zuul
2025-10-10 22:01:09.907763 | localhost | Distro: CentOS 9
2025-10-10 22:01:09.907781 | localhost | Provider: ibm-bm4-nodepool
2025-10-10 22:01:09.907798 | localhost | Region: regionOne
2025-10-10 22:01:09.907814 | localhost | Label: cloud-centos-9-stream-tripleo-ibm
2025-10-10 22:01:09.907830 | localhost | Product Name: OpenStack Compute
2025-10-10 22:01:09.907846 | localhost | Interface IP: 192.168.26.42
2025-10-10 22:01:09.931323 | 
2025-10-10 22:01:09.931398 | PLAY [all]
2025-10-10 22:01:09.937185 | 
2025-10-10 22:01:09.937245 | TASK [Gather network facts]
2025-10-10 22:01:10.259272 | controller | ok
2025-10-10 22:01:10.271529 | 
2025-10-10 22:01:10.271589 | TASK [include_role : start-zuul-console]
2025-10-10 22:01:10.289296 | controller | ok
2025-10-10 22:01:10.300067 | 
2025-10-10 22:01:10.300127 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-10-10 22:01:10.570586 | controller | ok
2025-10-10 22:01:10.578070 | 
2025-10-10 22:01:10.578130 | TASK [include_role : add-build-sshkey]
2025-10-10 22:01:10.595131 | controller | ok
2025-10-10 22:01:10.606192 | 
2025-10-10 22:01:10.606250 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-10-10 22:01:10.781139 | controller -> localhost | ok
2025-10-10 22:01:10.786037 | 
2025-10-10 22:01:10.786098 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-10-10 22:01:10.803061 | controller | ok
2025-10-10 22:01:10.815493 | controller | included: /var/lib/zuul/builds/a92840bc01cf4623a0bad38b1a4d922b/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-10-10 22:01:10.820627 | 
2025-10-10 22:01:10.820689 | TASK [add-build-sshkey : Create Temp SSH key]
2025-10-10 22:01:11.228483 | controller -> localhost | Generating public/private rsa key pair.
2025-10-10 22:01:11.228642 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/a92840bc01cf4623a0bad38b1a4d922b/work/a92840bc01cf4623a0bad38b1a4d922b_id_rsa.
2025-10-10 22:01:11.228672 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/a92840bc01cf4623a0bad38b1a4d922b/work/a92840bc01cf4623a0bad38b1a4d922b_id_rsa.pub.
2025-10-10 22:01:11.228693 | controller -> localhost | The key fingerprint is:
2025-10-10 22:01:11.228710 | controller -> localhost | SHA256:wnbxHEOwPg194q027FvCp1N82P+y32EB1EJdll+SXtI zuul-build-sshkey
2025-10-10 22:01:11.228726 | controller -> localhost | The key's randomart image is:
2025-10-10 22:01:11.228741 | controller -> localhost | +---[RSA 3072]----+
2025-10-10 22:01:11.228756 | controller -> localhost | |        ... .ooo=|
2025-10-10 22:01:11.228770 | controller -> localhost | |         +  ..+=E|
2025-10-10 22:01:11.228784 | controller -> localhost | |        + = .o.+o|
2025-10-10 22:01:11.228798 | controller -> localhost | |     . . B *  o .|
2025-10-10 22:01:11.228812 | controller -> localhost | |      + S =..o . |
2025-10-10 22:01:11.228828 | controller -> localhost | |     . o + .+ o .|
2025-10-10 22:01:11.228844 | controller -> localhost | |          B.o. + |
2025-10-10 22:01:11.228861 | controller -> localhost | |         o.*  o +|
2025-10-10 22:01:11.228876 | controller -> localhost | |          +o  .+=|
2025-10-10 22:01:11.228889 | controller -> localhost | +----[SHA256]-----+
2025-10-10 22:01:11.228948 | controller -> localhost | ok: Runtime: 0:00:00.105158
2025-10-10 22:01:11.233870 | 
2025-10-10 22:01:11.233944 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-10-10 22:01:11.251065 | controller | ok
2025-10-10 22:01:11.259486 | controller | included: /var/lib/zuul/builds/a92840bc01cf4623a0bad38b1a4d922b/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-10-10 22:01:11.266346 | 
2025-10-10 22:01:11.266408 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-10-10 22:01:11.279262 | controller | skipping: Conditional result was False
2025-10-10 22:01:11.283846 | 
2025-10-10 22:01:11.283920 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-10-10 22:01:11.614597 | controller | changed
2025-10-10 22:01:11.619950 | 
2025-10-10 22:01:11.620013 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-10-10 22:01:11.789476 | controller | ok
2025-10-10 22:01:11.793700 | 
2025-10-10 22:01:11.793759 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-10-10 22:01:12.286927 | controller | changed
2025-10-10 22:01:12.291520 | 
2025-10-10 22:01:12.291579 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-10-10 22:01:12.816560 | controller | changed
2025-10-10 22:01:12.821074 | 
2025-10-10 22:01:12.821137 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-10-10 22:01:12.833967 | controller | skipping: Conditional result was False
2025-10-10 22:01:12.838926 | 
2025-10-10 22:01:12.838991 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-10-10 22:01:13.114614 | controller -> localhost | changed
2025-10-10 22:01:13.123417 | 
2025-10-10 22:01:13.123481 | TASK [add-build-sshkey : Add back temp key]
2025-10-10 22:01:13.319031 | controller -> localhost | Identity added: /var/lib/zuul/builds/a92840bc01cf4623a0bad38b1a4d922b/work/a92840bc01cf4623a0bad38b1a4d922b_id_rsa (zuul-build-sshkey)
2025-10-10 22:01:13.319260 | controller -> localhost | ok: Runtime: 0:00:00.006337
2025-10-10 22:01:13.324627 | 
2025-10-10 22:01:13.324689 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-10-10 22:01:13.595740 | controller | ok
2025-10-10 22:01:13.599899 | 
2025-10-10 22:01:13.599977 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-10-10 22:01:13.623271 | controller | skipping: Conditional result was False
2025-10-10 22:01:13.631722 | 
2025-10-10 22:01:13.631779 | TASK [include_role : validate-host]
2025-10-10 22:01:13.648417 | controller | ok
2025-10-10 22:01:13.666754 | 
2025-10-10 22:01:13.666822 | TASK [validate-host : Define zuul_info_dir fact]
2025-10-10 22:01:13.684251 | controller | ok
2025-10-10 22:01:13.688509 | 
2025-10-10 22:01:13.688572 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-10-10 22:01:13.873311 | controller -> localhost | ok
2025-10-10 22:01:13.878599 | 
2025-10-10 22:01:13.878663 | TASK [validate-host : Collect information about the host]
2025-10-10 22:01:14.447004 | controller | ok
2025-10-10 22:01:14.454200 | 
2025-10-10 22:01:14.454259 | TASK [validate-host : Sanitize hostname]
2025-10-10 22:01:14.511556 | controller | ok
2025-10-10 22:01:14.515848 | 
2025-10-10 22:01:14.515927 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-10-10 22:01:14.874017 | controller -> localhost | changed
2025-10-10 22:01:14.896853 | 
2025-10-10 22:01:14.896952 | TASK [validate-host : Collect information about zuul worker]
2025-10-10 22:01:15.197747 | controller | ok
2025-10-10 22:01:15.201775 | 
2025-10-10 22:01:15.201833 | TASK [validate-host : Write out all zuul information for each host]
2025-10-10 22:01:15.530140 | controller -> localhost | changed
2025-10-10 22:01:15.538511 | 
2025-10-10 22:01:15.538568 | TASK [include_role : prepare-workspace-openshift]
2025-10-10 22:01:15.551184 | controller | skipping: Conditional result was False
2025-10-10 22:01:15.555322 | 
2025-10-10 22:01:15.555381 | TASK [include_role : remove-zuul-sshkey]
2025-10-10 22:01:15.567932 | controller | skipping: Conditional result was False
2025-10-10 22:01:15.572771 | 
2025-10-10 22:01:15.572833 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-10-10 22:01:15.755975 | controller | ok: "logs"
2025-10-10 22:01:15.756194 | controller | ok: All items complete
2025-10-10 22:01:15.756223 | 
2025-10-10 22:01:15.917701 | controller | ok: "artifacts"
2025-10-10 22:01:16.071000 | controller | ok: "docs"
2025-10-10 22:01:16.078112 | 
2025-10-10 22:01:16.078230 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-10-10 22:01:16.270188 | controller | changed: "logs"
2025-10-10 22:01:16.438764 | controller | changed: "artifacts"
2025-10-10 22:01:16.603891 | controller | changed: "docs"
2025-10-10 22:01:16.627678 | 
2025-10-10 22:01:16.627764 | PLAY RECAP
2025-10-10 22:01:16.627809 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-10-10 22:01:16.627839 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-10 22:01:16.627858 | 
2025-10-10 22:01:16.700553 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-10-10 22:01:16.701316 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-10-10 22:01:17.225771 | 
2025-10-10 22:01:17.225880 | PLAY [all]
2025-10-10 22:01:17.243515 | 
2025-10-10 22:01:17.243583 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-10-10 22:01:17.291840 | controller | ok
2025-10-10 22:01:17.296190 | 
2025-10-10 22:01:17.296263 | TASK [mirror-info-fork : Create /etc/ci]
2025-10-10 22:01:17.629684 | controller | changed
2025-10-10 22:01:17.635024 | 
2025-10-10 22:01:17.635100 | TASK [mirror-info-fork : Install ci_mirror script]
2025-10-10 22:01:18.375806 | controller | changed
2025-10-10 22:01:18.384802 | 
2025-10-10 22:01:18.384872 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-10-10 22:01:18.733533 | controller | changed:
2025-10-10 22:01:18.733715 | controller | {
2025-10-10 22:01:18.733754 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-10-10 22:01:18.733784 | controller | }
2025-10-10 22:01:18.921486 | controller | changed:
2025-10-10 22:01:18.921544 | controller | {
2025-10-10 22:01:18.921572 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-10-10 22:01:18.921595 | controller | }
2025-10-10 22:01:19.110346 | controller | changed:
2025-10-10 22:01:19.110396 | controller | {
2025-10-10 22:01:19.110423 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com"
2025-10-10 22:01:19.110444 | controller | }
2025-10-10 22:01:19.296889 | controller | changed:
2025-10-10 22:01:19.296958 | controller | {
2025-10-10 22:01:19.296986 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-10-10 22:01:19.297008 | controller | }
2025-10-10 22:01:19.485895 | controller | changed:
2025-10-10 22:01:19.485971 | controller | {
2025-10-10 22:01:19.485996 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-10-10 22:01:19.486014 | controller | }
2025-10-10 22:01:19.672655 | controller | changed:
2025-10-10 22:01:19.672702 | controller | {
2025-10-10 22:01:19.672728 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-10-10 22:01:19.672747 | controller | }
2025-10-10 22:01:19.858311 | controller | changed:
2025-10-10 22:01:19.858358 | controller | {
2025-10-10 22:01:19.858382 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDLOQd4ZLtkZXQGY6UwAr/06ppWQK4fDO3HaqxPk98csyOCBXsliSKK39Bso828+5srIXiW7aI6aC9P5mwi4mUZlGPfJlQbfrcGvY+b/SocuvaGK+1RrHLoJCT52LBhwgrzlXio2jeksZeein8iaTrhsPrOAs7KggIL/rB9hEiB3NaOPWhhoCP4vlW6MEMExGcqB/1FVxXFBPnLkEyW0Lk7ycVflZl2ocRxbfjZi0+tI1Wlinp8PvSQSc/WVrAcDgKjc/mB4ODPOyYy3G8FHgfMsrXSDEyjBKgLKMsdCrAUcqJQWjkqXleXSYOV4q3pzL+9umK+q/e3P/bIoSFQzmJKTU1eDfuvPXmow9F5H54fii/Da7ezlMJ+wPGHJrRAkmzvMbALy7xwswLhZMkOGNtRcPqaKYRmIBKpw3o6bCTtcNUHOtOQnzwY8JzrM2eBWJBXAANYw+9/ho80JIiwhg29CFNpVBuHbql2YxJQNrnl90guN65rYNpDxdIluweyUf8= anbanerj@kaermorhen"
2025-10-10 22:01:19.858401 | controller | }
2025-10-10 22:01:20.043904 | controller | changed:
2025-10-10 22:01:20.043970 | controller | {
2025-10-10 22:01:20.043994 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQC3VwV8Im9kRm49lt3tM36hj4Zv27FxGo4C1Q/0jqhzFmHY7RHbmeRr8ObhwWoHjXSozKWg8FL5ER0z3hTwL0W6lez3sL7hUaCmSuZmG5Hnl3x4vTSxDI9JZ/Y65rtYiiWQo2fC5xJhU/4+0e5e/pseCm8cKRSu+SaxhO+sd6FDojA2x1BzOzKiQRDy/1zWGp/cZkxcEuB1wHI5LMzN03c67vmbu+fhZRAUO4dQkvcnj2LrhQtpa+ytvnSjr8icMDosf1OsbSffwZFyHB/hfWGAfe0eIeSA2XPraxiPknXxiPKx2MJsaUTYbsZcm3EjFdHBBMumw5rBI74zLrMRvCO9GwBEmGT4rFng1nP+yw5DB8sn2zqpOsPg1LYRwCPOUveC13P6pgsZZPh812e8v5EKnETct+5XI3dVpdw6CnNiLwAyVAF15DJvBGT/u1k0Myg/bQn+Gv9k2MSj6LvQmf6WbZu2Wgjm30z3FyCneBqTL7mLF19YXzeC0ufHz5pnO1E= dasm@fedora"
2025-10-10 22:01:20.044013 | controller | }
2025-10-10 22:01:20.229242 | controller | changed:
2025-10-10 22:01:20.229297 | controller | {
2025-10-10 22:01:20.229322 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-10-10 22:01:20.229340 | controller | }
2025-10-10 22:01:20.420034 | controller | changed:
2025-10-10 22:01:20.420090 | controller | {
2025-10-10 22:01:20.420115 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-10-10 22:01:20.420134 | controller | }
2025-10-10 22:01:20.611058 | controller | changed:
2025-10-10 22:01:20.611110 | controller | {
2025-10-10 22:01:20.611134 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-10-10 22:01:20.611152 | controller | }
2025-10-10 22:01:20.815110 | controller | changed:
2025-10-10 22:01:20.815197 | controller | {
2025-10-10 22:01:20.815225 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-10-10 22:01:20.815245 | controller | }
2025-10-10 22:01:20.989701 | controller | changed:
2025-10-10 22:01:20.989773 | controller | {
2025-10-10 22:01:20.989799 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-10-10 22:01:20.989820 | controller | }
2025-10-10 22:01:21.185731 | controller | changed:
2025-10-10 22:01:21.185788 | controller | {
2025-10-10 22:01:21.185815 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-10-10 22:01:21.185840 | controller | }
2025-10-10 22:01:21.405775 | controller | changed:
2025-10-10 22:01:21.405821 | controller | {
2025-10-10 22:01:21.405845 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-10-10 22:01:21.405863 | controller | }
2025-10-10 22:01:21.595054 | controller | changed:
2025-10-10 22:01:21.595145 | controller | {
2025-10-10 22:01:21.595182 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-10-10 22:01:21.595203 | controller | }
2025-10-10 22:01:21.782483 | controller | changed:
2025-10-10 22:01:21.782529 | controller | {
2025-10-10 22:01:21.782553 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQCfNtF7NvKl915TGsGGoseUb06Hj8L/S4toWf0hExeY+F00woL6NvBlJD0nDct+P5a22I4EhvoQCRQ8reaPCm1lybR3uiRIJsj+8zkVvLwby9LXzfZorlNG9ofjd00FEmB09uW/YvTl6Q9XwwwX6tInzIOv3TMqTHHGOL74ibbj8J/FJR0cFEyj0z4WQRvtkh32xAHl83gbuINryMt0sqRI+clj2381NKL55DRLQrVw0gsfqqxiHAnXg21qWmc4J+b9e9kiuAFQjcjwTVkwJCcg3xbPwC/qokYRby/Y5S40UUd7/jEARGXT7RZgpzTuDd1oZiCVrnrqJNPaMNdVv5MLeFdf1B7iIe5aa/fGouX7AO4SdKhZUdnJmCFAGvjC6S3JMZ2wAcUl+OHnssfmdj7XL50cLo27vjuzMtLAgSqi6N99m92WCF2s8J9aVzszX7Xz9OKZCeGsiVJp3/NdABKzSEAyM9xBD/5Vho894Sav+otpySHe3p6RUTgbB5Zu8VyZRZ/UtB3ueXxyo764yrc6qWIDqrehm84Xm9g+/jpIBzGPl07NUNJpdt/6Sgf9RIKXw/7XypO5yZfUcuFNGTxLfqjTNrtgLZNcjfav6sSdVXVcMPL//XNuRdKmVFaO76eV/oGMQGr1fGcCD+N+CpI7+Q+fCNB6VFWG4nZFuI/Iuw== averdagu@redhat.com"
2025-10-10 22:01:21.782572 | controller | }
2025-10-10 22:01:21.970786 | controller | changed:
2025-10-10 22:01:21.970837 | controller | {
2025-10-10 22:01:21.970864 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDq8l27xI+QlQVdS4djp9ogSoyrNE2+Ox6vKPdhSNL1J3PE5w+WCSvMz9A5gnNuH810zwbekEApbxTze/gLQJwBHA52CChfURpXrFaxY7ePXRElwKAL3mJfzBWY/c5jnNL9TCVmFJTGZkFZP3Nh+BMgZvL6xBkt3WKm6Uq18qzd9XeKcZusrA+O+uLv1fVeQnadY9RIqOCyeFYCzLWrUfTyE8x/XG0hAWIM7qpnF2cALQS2h9n4hW5ybiUN790H08wf9hFwEf5nxY9Z9dVkPFQiTSGKNBzmnCXU9skxS/xhpFjJ5duGSZdtAHe9O+nGZm9c67hxgtf8e5PDuqAdXEv2cf6e3VBAt+Bz8EKI3yosTj0oZHfwr42Yzb1l/SKy14Rggsrc9KAQlrGXan6+u2jcQqqx7l+SWmnpFiWTV9u5cWj2IgOhApOitmRBPYqk9rE2usfO0hLn/Pj/R/Nau4803e1/EikdLE7Ps95s9mX5jRDjAoUa2JwFF5RsVFyL910= ashigupt@ashigupt.remote.csb"
2025-10-10 22:01:21.970894 | controller | }
2025-10-10 22:01:22.173008 | controller | changed:
2025-10-10 22:01:22.173056 | controller | {
2025-10-10 22:01:22.173079 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-10-10 22:01:22.173097 | controller | }
2025-10-10 22:01:22.378295 | controller | changed:
2025-10-10 22:01:22.378356 | controller | {
2025-10-10 22:01:22.378385 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-10-10 22:01:22.378406 | controller | }
2025-10-10 22:01:22.581868 | controller | changed:
2025-10-10 22:01:22.581932 | controller | {
2025-10-10 22:01:22.581961 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-10-10 22:01:22.581981 | controller | }
2025-10-10 22:01:22.773016 | controller | changed:
2025-10-10 22:01:22.773064 | controller | {
2025-10-10 22:01:22.773087 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-10-10 22:01:22.773104 | controller | }
2025-10-10 22:01:22.968291 | controller | changed:
2025-10-10 22:01:22.968345 | controller | {
2025-10-10 22:01:22.968371 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQDZdI7t1cxYx65heVI24HTV4F7oQLW1zyfxHreL2TIJKxjyrUUKIFEUmTutcBlJRLNT2Eoix6x1sOw9YrchloCLcn//SGfTElr9mSc5jbjb7QXEU+zJMhtxyEJ1Po3CUGnj7ckiIXw7wcawZtrEOAQ9pH3ExYCJcEMiyNjRQZCxT3tPK+S4B95EWh5Fsrz9CkwpjNRPPH7LigCeQTM3Wc7r97utAslBUUvYceDSLA7rMgkitJE38b7rZBeYzsGQ8YYUBjTCtehqQXxCRjizbHWaaZkBU+N3zkKB6n/iCNGIO690NK7A/qb6msTijiz1PeuM8ThOsi9qXnbX5v0PoTpcFSojV7NHAQ71f0XXuS43FhZctT+Dcx44dT8Fb5vJu2cJGrk+qF8ZgJYNpRS7gPg0EG2EqjK7JMf9ULdjSu0r+KlqIAyLvtzT4eOnQipoKlb/WG5D/0ohKv7OMQ352ggfkBFIQsRXyyTCT98Ft9juqPuahi3CAQmP4H9dyE+7+Kz437PEtsxLmfm6naNmWi7Ee1DqWPwS8rEajsm4sNM4wW9gdBboJQtc0uZw0DfLj1I9r3Mc8Ol0jYtz0yNQDSzVLrGCaJlC311trU70tZ+ZkAVV6Mn8lOhSbj1cK0lvSr6ZK4dgqGl3I1eTZJJhbLNdg7UOVaiRx9543+C/p/As7w== brjackma@redhat.com"
2025-10-10 22:01:22.968391 | controller | }
2025-10-10 22:01:23.175367 | controller | changed:
2025-10-10 22:01:23.175414 | controller | {
2025-10-10 22:01:23.175437 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-10-10 22:01:23.175456 | controller | }
2025-10-10 22:01:23.370783 | controller | changed:
2025-10-10 22:01:23.370832 | controller | {
2025-10-10 22:01:23.370857 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-10-10 22:01:23.370875 | controller | }
2025-10-10 22:01:23.557473 | controller | changed:
2025-10-10 22:01:23.557561 | controller | {
2025-10-10 22:01:23.557587 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-10-10 22:01:23.557606 | controller | }
2025-10-10 22:01:23.581016 | 
2025-10-10 22:01:23.581075 | TASK [Set timezone to UTC]
2025-10-10 22:01:23.963270 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-10-10 22:01:23.967594 | 
2025-10-10 22:01:23.967656 | TASK [Create nodepool directory]
2025-10-10 22:01:24.169041 | controller | changed
2025-10-10 22:01:24.173963 | 
2025-10-10 22:01:24.174024 | TASK [Create nodepool sub_nodes file]
2025-10-10 22:01:24.678374 | controller | changed
2025-10-10 22:01:24.682772 | 
2025-10-10 22:01:24.682835 | TASK [Create nodepool sub_nodes_private file]
2025-10-10 22:01:25.167088 | controller | changed
2025-10-10 22:01:25.172097 | 
2025-10-10 22:01:25.172153 | LOOP [Populate nodepool sub_nodes file]
2025-10-10 22:01:25.200396 | 
2025-10-10 22:01:25.200507 | LOOP [Populate nodepool sub_nodes_private file]
2025-10-10 22:01:25.228294 | 
2025-10-10 22:01:25.228410 | TASK [Create nodepool primary file]
2025-10-10 22:01:25.251589 | controller | skipping: Conditional result was False
2025-10-10 22:01:25.256534 | 
2025-10-10 22:01:25.256602 | TASK [Create nodepool node_private for this node]
2025-10-10 22:01:25.769590 | controller | changed
2025-10-10 22:01:25.774809 | 
2025-10-10 22:01:25.774871 | LOOP [Copy ssh keys to nodepool directory]
2025-10-10 22:01:26.083267 | controller | ok: Item: id_rsa Runtime: 0:00:00.006980
2025-10-10 22:01:26.083420 | 
2025-10-10 22:01:26.240711 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.004167
2025-10-10 22:01:26.252739 | 
2025-10-10 22:01:26.252855 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-10-10 22:01:26.790457 | controller | changed
2025-10-10 22:01:26.795158 | 
2025-10-10 22:01:26.795232 | TASK [Validate sudoers config after edits]
2025-10-10 22:01:27.010360 | controller | /etc/sudoers: parsed OK
2025-10-10 22:01:27.010426 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-10-10 22:01:27.010435 | controller | /etc/sudoers.d/zuul: parsed OK
2025-10-10 22:01:27.010441 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-10-10 22:01:27.320736 | controller | ok: Runtime: 0:00:00.006071
2025-10-10 22:01:27.326828 | 
2025-10-10 22:01:27.326899 | TASK [Show the environment passed in to job shell scripts]
2025-10-10 22:01:27.527167 | controller | SHELL=/bin/bash
2025-10-10 22:01:27.527283 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-10-10 22:01:27.527295 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-10-10 22:01:27.527302 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/00/3400/95860fa2bc1bbb4399cfdb1e121467ccac2f7191
2025-10-10 22:01:27.527308 | controller | PWD=/home/zuul
2025-10-10 22:01:27.527314 | controller | ZUUL_PIPELINE=github-check
2025-10-10 22:01:27.527319 | controller | LOGNAME=zuul
2025-10-10 22:01:27.527324 | controller | XDG_SESSION_TYPE=tty
2025-10-10 22:01:27.527329 | controller | _=/usr/bin/env
2025-10-10 22:01:27.527333 | controller | MOTD_SHOWN=pam
2025-10-10 22:01:27.527338 | controller | HOME=/home/zuul
2025-10-10 22:01:27.527343 | controller | LANG=en_US.UTF-8
2025-10-10 22:01:27.527348 | controller | SSH_CONNECTION=192.168.26.12 41652 192.168.26.42 22
2025-10-10 22:01:27.527353 | controller | TOX_TESTENV_PASSENV=ZUUL_UUID ZUUL_PROJECT ZUUL_SHORT_PROJECT_NAME ZUUL_PIPELINE ZUUL_VOTING WORKSPACE BUILD_TIMEOUT ZUUL_BRANCH ZUUL_CHANGES ZUUL_CHANGE_IDS ZUUL_CHANGE ZUUL_PATCHSET
2025-10-10 22:01:27.527363 | controller | ZUUL_CHANGE_IDS=3400,95860fa2bc1bbb4399cfdb1e121467ccac2f7191
2025-10-10 22:01:27.527369 | controller | WORKSPACE=/home/zuul/workspace
2025-10-10 22:01:27.527374 | controller | XDG_SESSION_CLASS=user
2025-10-10 22:01:27.527379 | controller | SELINUX_ROLE_REQUESTED=
2025-10-10 22:01:27.527384 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-10-10 22:01:27.527389 | controller | USER=zuul
2025-10-10 22:01:27.527394 | controller | ZUUL_VOTING=True
2025-10-10 22:01:27.527407 | controller | BUILD_TIMEOUT=1800000
2025-10-10 22:01:27.527411 | controller | SELINUX_USE_CURRENT_RANGE=
2025-10-10 22:01:27.527416 | controller | SHLVL=1
2025-10-10 22:01:27.527421 | controller | ZUUL_PATCHSET=95860fa2bc1bbb4399cfdb1e121467ccac2f7191
2025-10-10 22:01:27.527426 | controller | XDG_SESSION_ID=1
2025-10-10 22:01:27.527431 | controller | ZUUL_BRANCH=main
2025-10-10 22:01:27.527436 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-10-10 22:01:27.527441 | controller | SSH_CLIENT=192.168.26.12 41652 22
2025-10-10 22:01:27.527448 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-10-10 22:01:27.527454 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-10-10 22:01:27.527459 | controller | which_declare=declare -f
2025-10-10 22:01:27.527465 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2025-10-10 22:01:27.527470 | controller | SELINUX_LEVEL_REQUESTED=
2025-10-10 22:01:27.527475 | controller | ZUUL_CHANGE=3400
2025-10-10 22:01:27.527480 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-10-10 22:01:27.527485 | controller | ZUUL_UUID=a92840bc01cf4623a0bad38b1a4d922b
2025-10-10 22:01:27.527491 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-10-10 22:01:27.527496 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-10-10 22:01:27.527502 | controller | }
2025-10-10 22:01:27.850821 | controller | ok: Runtime: 0:00:00.005966
2025-10-10 22:01:27.855607 | 
2025-10-10 22:01:27.855665 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-10-10 22:01:27.868571 | controller | skipping: Conditional result was False
2025-10-10 22:01:27.873542 | 
2025-10-10 22:01:27.873602 | TASK [Symlink /home/zuul-worker/workspace]
2025-10-10 22:01:28.395704 | controller | skipping: Conditional result was False
2025-10-10 22:01:28.402261 | 
2025-10-10 22:01:28.402329 | TASK [Ensure legacy workspace directory]
2025-10-10 22:01:28.603424 | controller | changed
2025-10-10 22:01:28.620696 | 
2025-10-10 22:01:28.620750 | PLAY RECAP
2025-10-10 22:01:28.620793 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-10-10 22:01:28.620817 | 
2025-10-10 22:01:28.694149 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-10-10 22:01:28.694892 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-10-10 22:01:29.174980 | 
2025-10-10 22:01:29.175074 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-10-10 22:01:29.195982 | 
2025-10-10 22:01:29.196053 | TASK [Create zuul-output directory]
2025-10-10 22:01:29.489426 | controller | changed
2025-10-10 22:01:29.495437 | 
2025-10-10 22:01:29.495513 | TASK [Slurp Zuul inventory test]
2025-10-10 22:01:29.762450 | controller -> localhost | ok
2025-10-10 22:01:29.768611 | 
2025-10-10 22:01:29.768679 | TASK [Save zuul inventory]
2025-10-10 22:01:30.484969 | controller | changed
2025-10-10 22:01:30.489658 | 
2025-10-10 22:01:30.489725 | TASK [Save zuul vars without the change_message]
2025-10-10 22:01:31.046130 | controller | changed
2025-10-10 22:01:31.063700 | 
2025-10-10 22:01:31.063762 | PLAY RECAP
2025-10-10 22:01:31.063807 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-10 22:01:31.063829 | 
2025-10-10 22:01:31.129278 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-10-10 22:01:31.130018 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-10-10 22:01:31.637930 | 
2025-10-10 22:01:31.638034 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2025-10-10 22:01:31.657294 | 
2025-10-10 22:01:31.657366 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2025-10-10 22:01:31.676136 | controller | ok
2025-10-10 22:01:31.691930 | 
2025-10-10 22:01:31.692003 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-10-10 22:01:31.714862 | controller | skipping: Conditional result was False
2025-10-10 22:01:31.720021 | 
2025-10-10 22:01:31.720087 | TASK [mirror-info-fork : Create /etc/ci]
2025-10-10 22:01:32.011098 | controller | ok
2025-10-10 22:01:32.016447 | 
2025-10-10 22:01:32.016516 | TASK [mirror-info-fork : Install ci_mirror script]
2025-10-10 22:01:32.612350 | controller | ok
2025-10-10 22:01:32.621485 | 
2025-10-10 22:01:32.621545 | TASK [Prepare workspace]
2025-10-10 22:01:32.638807 | controller | ok
2025-10-10 22:01:32.654064 | 
2025-10-10 22:01:32.654126 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-10-10 22:01:32.935292 | controller | ok
2025-10-10 22:01:32.941005 | 
2025-10-10 22:01:32.941072 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-10-10 22:01:35.171583 | controller | Output suppressed because no_log was given
2025-10-10 22:01:35.179888 | 
2025-10-10 22:01:35.179977 | LOOP [Create zuul-output directory]
2025-10-10 22:01:35.360449 | controller | changed: "/home/zuul/ci-framework-data/logs"
2025-10-10 22:01:35.507703 | controller | ok: "/home/zuul/zuul-output/logs"
2025-10-10 22:01:35.513972 | 
2025-10-10 22:01:35.514053 | TASK [Install required packages]
2025-10-10 22:02:26.141946 | controller | changed
2025-10-10 22:02:26.146612 | 
2025-10-10 22:02:26.146670 | TASK [Install venv]
2025-10-10 22:03:26.752789 | controller | changed
2025-10-10 22:03:26.794830 | 
2025-10-10 22:03:26.794978 | PLAY RECAP
2025-10-10 22:03:26.795028 | controller | ok: 7 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-10-10 22:03:26.795052 | 
2025-10-10 22:03:26.853795 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-10-10 22:03:26.854556 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-10-10 22:03:27.320530 | 
2025-10-10 22:03:27.320621 | PLAY [Run ci/playbooks/molecule-test.yml]
2025-10-10 22:03:27.339213 | 
2025-10-10 22:03:27.339283 | TASK [Gather required facts]
2025-10-10 22:03:27.774030 | controller | ok
2025-10-10 22:03:27.778748 | 
2025-10-10 22:03:27.778819 | TASK [Load environment var if instructed to]
2025-10-10 22:03:27.793024 | controller | skipping: Conditional result was False
2025-10-10 22:03:27.798200 | 
2025-10-10 22:03:27.798265 | TASK [Ensure group_vars dir exists]
2025-10-10 22:03:28.079048 | controller | ok
2025-10-10 22:03:28.084421 | 
2025-10-10 22:03:28.084485 | TASK [Run molecule]
2025-10-10 22:03:28.944760 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2025-10-10 22:03:29.008891 | controller | INFO     Performing prerun with role_name_check=0...
2025-10-10 22:03:46.306255 | controller | WARNING  Another version of '@NAMESPACE@.@NAME@' 3.1.4 was found installed in /home/zuul/.cache/ansible-compat/976c2b/collections/ansible_collections, only the first one will be used, 3.1.4 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-10 22:03:46.306554 | controller | WARNING  Another version of 'ansible.netcommon' 7.1.0 was found installed in /home/zuul/.cache/ansible-compat/976c2b/collections/ansible_collections, only the first one will be used, 7.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-10 22:03:46.306567 | controller | WARNING  Another version of 'ansible.posix' 1.6.2 was found installed in /home/zuul/.cache/ansible-compat/976c2b/collections/ansible_collections, only the first one will be used, 1.6.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-10 22:03:46.306703 | controller | WARNING  Another version of 'ansible.utils' 5.1.2 was found installed in /home/zuul/.cache/ansible-compat/976c2b/collections/ansible_collections, only the first one will be used, 5.1.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-10 22:03:46.306722 | controller | WARNING  Another version of 'cifmw.general' 1.0.0+95860fa2 was found installed in /home/zuul/.cache/ansible-compat/976c2b/collections/ansible_collections, only the first one will be used, 1.0.0+95860fa2 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-10 22:03:46.306742 | controller | WARNING  Another version of 'cisco.ios' 9.0.3 was found installed in /home/zuul/.cache/ansible-compat/976c2b/collections/ansible_collections, only the first one will be used, 9.0.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-10 22:03:46.307144 | controller | WARNING  Another version of 'community.crypto' 2.22.3 was found installed in /home/zuul/.cache/ansible-compat/976c2b/collections/ansible_collections, only the first one will be used, 2.22.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-10 22:03:46.307749 | controller | WARNING  Another version of 'community.general' 10.0.1 was found installed in /home/zuul/.cache/ansible-compat/976c2b/collections/ansible_collections, only the first one will be used, 10.0.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-10 22:03:46.308163 | controller | WARNING  Another version of 'community.libvirt' 1.3.0 was found installed in /home/zuul/.cache/ansible-compat/976c2b/collections/ansible_collections, only the first one will be used, 1.3.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-10 22:03:46.308567 | controller | WARNING  Another version of 'community.okd' 4.0.0 was found installed in /home/zuul/.cache/ansible-compat/976c2b/collections/ansible_collections, only the first one will be used, 4.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-10 22:03:46.308953 | controller | WARNING  Another version of 'containers.podman' 1.16.2 was found installed in /home/zuul/.cache/ansible-compat/976c2b/collections/ansible_collections, only the first one will be used, 1.16.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-10 22:03:46.309361 | controller | WARNING  Another version of 'junipernetworks.junos' 9.1.0 was found installed in /home/zuul/.cache/ansible-compat/976c2b/collections/ansible_collections, only the first one will be used, 9.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-10 22:03:46.309767 | controller | WARNING  Another version of 'kubernetes.core' 5.0.0 was found installed in /home/zuul/.cache/ansible-compat/976c2b/collections/ansible_collections, only the first one will be used, 5.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-10 22:03:46.310174 | controller | WARNING  Another version of 'mellanox.onyx' 1.0.0 was found installed in /home/zuul/.cache/ansible-compat/976c2b/collections/ansible_collections, only the first one will be used, 1.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-10 22:03:46.310577 | controller | WARNING  Another version of 'openstack.config_template' 2.1.1 was found installed in /home/zuul/.cache/ansible-compat/976c2b/collections/ansible_collections, only the first one will be used, 2.1.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-10 22:03:46.319567 | controller | INFO     Running default > prepare
2025-10-10 22:03:46.951594 | controller |
2025-10-10 22:03:46.951647 | controller | PLAY [Prepare] *****************************************************************
2025-10-10 22:03:46.951772 | controller |
2025-10-10 22:03:46.951877 | controller | TASK [Gathering Facts] *********************************************************
2025-10-10 22:03:46.952005 | controller | Friday 10 October 2025  22:03:46 +0000 (0:00:00.011)       0:00:00.011 ********
2025-10-10 22:03:47.835073 | controller | ok: [instance]
2025-10-10 22:03:47.835160 | controller |
2025-10-10 22:03:47.835170 | controller | TASK [test_deps : Gather facts if they don't exist] ****************************
2025-10-10 22:03:47.835251 | controller | Friday 10 October 2025  22:03:47 +0000 (0:00:00.883)       0:00:00.895 ********
2025-10-10 22:03:47.853062 | controller | skipping: [instance]
2025-10-10 22:03:47.853141 | controller |
2025-10-10 22:03:47.853306 | controller | TASK [test_deps : Gather variables for each operating system] ******************
2025-10-10 22:03:47.853398 | controller | Friday 10 October 2025  22:03:47 +0000 (0:00:00.018)       0:00:00.914 ********
2025-10-10 22:03:47.894147 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/test_deps/vars/centos-9.yml)
2025-10-10 22:03:47.894235 | controller |
2025-10-10 22:03:47.894337 | controller | TASK [test_deps : Detect mirrors file] *****************************************
2025-10-10 22:03:47.894443 | controller | Friday 10 October 2025  22:03:47 +0000 (0:00:00.041)       0:00:00.955 ********
2025-10-10 22:03:48.174684 | controller | ok: [instance]
2025-10-10 22:03:48.174769 | controller |
2025-10-10 22:03:48.174879 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] ***********
2025-10-10 22:03:48.174996 | controller | Friday 10 October 2025  22:03:48 +0000 (0:00:00.280)       0:00:01.235 ********
2025-10-10 22:03:48.483010 | controller | ok: [instance]
2025-10-10 22:03:48.483102 | controller |
2025-10-10 22:03:48.483244 | controller | TASK [test_deps : Disable ubi 9 repos] *****************************************
2025-10-10 22:03:48.483314 | controller | Friday 10 October 2025  22:03:48 +0000 (0:00:00.308)       0:00:01.543 ********
2025-10-10 22:03:49.163240 | controller | ok: [instance] => (item=ubi-9-appstream)
2025-10-10 22:03:49.163305 | controller | ok: [instance] => (item=ubi-9-baseos)
2025-10-10 22:03:49.163360 | controller | ok: [instance] => (item=ubi-9-codeready-builder)
2025-10-10 22:03:49.163452 | controller |
2025-10-10 22:03:49.163541 | controller | TASK [Deploy repo-setup] *******************************************************
2025-10-10 22:03:49.163632 | controller | Friday 10 October 2025  22:03:49 +0000 (0:00:00.680)       0:00:02.224 ********
2025-10-10 22:03:49.222598 | controller |
2025-10-10 22:03:49.222747 | controller | TASK [repo_setup : Ensure directories are present] *****************************
2025-10-10 22:03:49.222862 | controller | Friday 10 October 2025  22:03:49 +0000 (0:00:00.059)       0:00:02.283 ********
2025-10-10 22:03:49.731001 | controller | changed: [instance] => (item=tmp)
2025-10-10 22:03:49.731062 | controller | changed: [instance] => (item=artifacts/repositories)
2025-10-10 22:03:49.731146 | controller | changed: [instance] => (item=venv/repo_setup)
2025-10-10 22:03:49.731238 | controller |
2025-10-10 22:03:49.731337 | controller | TASK [repo_setup : Make sure git-core package is installed] ********************
2025-10-10 22:03:49.731439 | controller | Friday 10 October 2025  22:03:49 +0000 (0:00:00.508)       0:00:02.792 ********
2025-10-10 22:03:50.462665 | controller | ok: [instance]
2025-10-10 22:03:50.462733 | controller |
2025-10-10 22:03:50.462951 | controller | TASK [repo_setup : Get repo-setup repository] **********************************
2025-10-10 22:03:51.610293 | controller | Friday 10 October 2025  22:03:50 +0000 (0:00:00.731)       0:00:03.523 ********
2025-10-10 22:03:51.610330 | controller | changed: [instance]
2025-10-10 22:03:59.041041 | controller |
2025-10-10 22:03:59.041090 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************
2025-10-10 22:03:59.041101 | controller | Friday 10 October 2025  22:03:51 +0000 (0:00:01.146)       0:00:04.669 ********
2025-10-10 22:03:59.041113 | controller | changed: [instance]
2025-10-10 22:03:59.711023 | controller |
2025-10-10 22:03:59.711065 | controller | TASK [repo_setup : Install repo-setup package] *********************************
2025-10-10 22:03:59.711073 | controller | Friday 10 October 2025  22:03:59 +0000 (0:00:07.431)       0:00:12.101 ********
2025-10-10 22:03:59.711083 | controller | changed: [instance]
2025-10-10 22:03:59.731464 | controller |
2025-10-10 22:03:59.731480 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
2025-10-10 22:03:59.731487 | controller | Friday 10 October 2025  22:03:59 +0000 (0:00:00.670)       0:00:12.771 ********
2025-10-10 22:03:59.731495 | controller | skipping: [instance]
2025-10-10 22:04:01.752830 | controller |
2025-10-10 22:04:01.752865 | controller | TASK [repo_setup : Run repo-setup] *********************************************
2025-10-10 22:04:01.752873 | controller | Friday 10 October 2025  22:03:59 +0000 (0:00:00.020)       0:00:12.792 ********
2025-10-10 22:04:01.752884 | controller | changed: [instance]
2025-10-10 22:04:01.790841 | controller |
2025-10-10 22:04:01.790859 | controller | TASK [repo_setup : Get component repo] *****************************************
2025-10-10 22:04:01.790866 | controller | Friday 10 October 2025  22:04:01 +0000 (0:00:02.020)       0:00:14.813 ********
2025-10-10 22:04:01.790874 | controller | skipping: [instance]
2025-10-10 22:04:01.827908 | controller |
2025-10-10 22:04:01.827925 | controller | TASK [repo_setup : Rename component repo] **************************************
2025-10-10 22:04:01.827932 | controller | Friday 10 October 2025  22:04:01 +0000 (0:00:00.038)       0:00:14.851 ********
2025-10-10 22:04:01.827940 | controller | skipping: [instance]
2025-10-10 22:04:01.863412 | controller |
2025-10-10 22:04:01.863429 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
2025-10-10 22:04:01.863436 | controller | Friday 10 October 2025  22:04:01 +0000 (0:00:00.036)       0:00:14.888 ********
2025-10-10 22:04:01.863444 | controller | skipping: [instance]
2025-10-10 22:04:02.411180 | controller |
2025-10-10 22:04:02.411211 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************
2025-10-10 22:04:02.411219 | controller | Friday 10 October 2025  22:04:01 +0000 (0:00:00.035)       0:00:14.924 ********
2025-10-10 22:04:02.411229 | controller | changed: [instance]
2025-10-10 22:04:02.848621 | controller |
2025-10-10 22:04:02.848654 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
2025-10-10 22:04:02.848663 | controller | Friday 10 October 2025  22:04:02 +0000 (0:00:00.547)       0:00:15.471 ********
2025-10-10 22:04:02.848674 | controller | changed: [instance]
2025-10-10 22:04:02.877792 | controller |
2025-10-10 22:04:02.877809 | controller | TASK [repo_setup : Dump current-podified hash] *********************************
2025-10-10 22:04:02.877816 | controller | Friday 10 October 2025  22:04:02 +0000 (0:00:00.437)       0:00:15.909 ********
2025-10-10 22:04:02.877825 | controller | skipping: [instance]
2025-10-10 22:04:02.906542 | controller |
2025-10-10 22:04:02.906564 | controller | TASK [repo_setup : Slurp current podified hash] ********************************
2025-10-10 22:04:02.906572 | controller | Friday 10 October 2025  22:04:02 +0000 (0:00:00.029)       0:00:15.938 ********
2025-10-10 22:04:02.906585 | controller | skipping: [instance]
2025-10-10 22:04:02.935886 | controller |
2025-10-10 22:04:02.935902 | controller | TASK [repo_setup : Update the value of full_hash] ******************************
2025-10-10 22:04:02.935909 | controller | Friday 10 October 2025  22:04:02 +0000 (0:00:00.028)       0:00:15.967 ********
2025-10-10 22:04:02.935918 | controller | skipping: [instance]
2025-10-10 22:04:02.971912 | controller |
2025-10-10 22:04:02.971934 | controller | TASK [repo_setup : Export hashes facts for further use] ************************
2025-10-10 22:04:02.971944 | controller | Friday 10 October 2025  22:04:02 +0000 (0:00:00.029)       0:00:15.996 ********
2025-10-10 22:04:02.971955 | controller | ok: [instance]
2025-10-10 22:04:02.997648 | controller |
2025-10-10 22:04:02.997664 | controller | TASK [repo_setup : Create download directory] **********************************
2025-10-10 22:04:02.997670 | controller | Friday 10 October 2025  22:04:02 +0000 (0:00:00.036)       0:00:16.032 ********
2025-10-10 22:04:02.997678 | controller | skipping: [instance]
2025-10-10 22:04:03.023316 | controller |
2025-10-10 22:04:03.023337 | controller | TASK [repo_setup : Print the URL to request] ***********************************
2025-10-10 22:04:03.023346 | controller | Friday 10 October 2025  22:04:02 +0000 (0:00:00.025)       0:00:16.058 ********
2025-10-10 22:04:03.023367 | controller | skipping: [instance]
2025-10-10 22:04:03.048821 | controller |
2025-10-10 22:04:03.048844 | controller | TASK [Download the RPM] ********************************************************
2025-10-10 22:04:03.048853 | controller | Friday 10 October 2025  22:04:03 +0000 (0:00:00.025)       0:00:16.084 ********
2025-10-10 22:04:03.048865 | controller | skipping: [instance]
2025-10-10 22:04:03.074136 | controller |
2025-10-10 22:04:03.074151 | controller | TASK [repo_setup : Install RHOS Release tool] **********************************
2025-10-10 22:04:03.074158 | controller | Friday 10 October 2025  22:04:03 +0000 (0:00:00.025)       0:00:16.109 ********
2025-10-10 22:04:03.074165 | controller | skipping: [instance]
2025-10-10 22:04:03.100856 | controller |
2025-10-10 22:04:03.100875 | controller | TASK [repo_setup : Get rhos-release tool version] ******************************
2025-10-10 22:04:03.100883 | controller | Friday 10 October 2025  22:04:03 +0000 (0:00:00.025)       0:00:16.134 ********
2025-10-10 22:04:03.100894 | controller | skipping: [instance]
2025-10-10 22:04:03.130031 | controller |
2025-10-10 22:04:03.130062 | controller | TASK [repo_setup : Print rhos-release tool version] ****************************
2025-10-10 22:04:03.130071 | controller | Friday 10 October 2025  22:04:03 +0000 (0:00:00.026)       0:00:16.161 ********
2025-10-10 22:04:03.130079 | controller | skipping: [instance]
2025-10-10 22:04:03.130211 | controller |
2025-10-10 22:04:03.130354 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
2025-10-10 22:04:03.130482 | controller | Friday 10 October 2025  22:04:03 +0000 (0:00:00.029)       0:00:16.191 ********
2025-10-10 22:04:03.155254 | controller | skipping: [instance]
2025-10-10 22:04:03.155405 | controller |
2025-10-10 22:04:03.155558 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
2025-10-10 22:04:03.155691 | controller | Friday 10 October 2025  22:04:03 +0000 (0:00:00.025)       0:00:16.216 ********
2025-10-10 22:04:03.336846 | controller | ok: [instance]
2025-10-10 22:04:03.541502 | controller |
2025-10-10 22:04:03.541533 | controller | TASK [repo_setup : Use RDO proxy mirrors] **************************************
2025-10-10 22:04:03.541540 | controller | Friday 10 October 2025  22:04:03 +0000 (0:00:00.180)       0:00:16.397 ********
2025-10-10 22:04:03.541551 | controller | changed: [instance]
2025-10-10 22:04:03.760482 | controller |
2025-10-10 22:04:03.760512 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
2025-10-10 22:04:03.760520 | controller | Friday 10 October 2025  22:04:03 +0000 (0:00:00.204)       0:00:16.602 ********
2025-10-10 22:04:03.760531 | controller | changed: [instance]
2025-10-10 22:04:03.781121 | controller |
2025-10-10 22:04:03.781157 | controller | TASK [repo_setup : Check for gating.repo file on content provider] *************
2025-10-10 22:04:03.781165 | controller | Friday 10 October 2025  22:04:03 +0000 (0:00:00.218)       0:00:16.821 ********
2025-10-10 22:04:03.781176 | controller | skipping: [instance]
2025-10-10 22:04:03.781222 | controller |
2025-10-10 22:04:03.781248 | controller | TASK [repo_setup : Populate gating repo from content provider ip] **************
2025-10-10 22:04:03.781261 | controller | Friday 10 October 2025  22:04:03 +0000 (0:00:00.020)       0:00:16.842 ********
2025-10-10 22:04:03.802750 | controller | skipping: [instance]
2025-10-10 22:04:03.824578 | controller |
2025-10-10 22:04:03.824601 | controller | TASK [repo_setup : Check for DLRN repo at the destination] *********************
2025-10-10 22:04:03.824608 | controller | Friday 10 October 2025  22:04:03 +0000 (0:00:00.021)       0:00:16.863 ********
2025-10-10 22:04:03.824617 | controller | skipping: [instance]
2025-10-10 22:04:03.847116 | controller |
2025-10-10 22:04:03.847159 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
2025-10-10 22:04:03.847171 | controller | Friday 10 October 2025  22:04:03 +0000 (0:00:00.021)       0:00:16.885 ********
2025-10-10 22:04:03.847186 | controller | skipping: [instance]
2025-10-10 22:04:03.868887 | controller |
2025-10-10 22:04:03.868907 | controller | TASK [repo_setup : Check for DLRN component repo] ******************************
2025-10-10 22:04:03.868922 | controller | Friday 10 October 2025  22:04:03 +0000 (0:00:00.022)       0:00:16.908 ********
2025-10-10 22:04:03.868933 | controller | skipping: [instance]
2025-10-10 22:04:03.868971 | controller |
2025-10-10 22:04:03.869144 | controller | TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] ***
2025-10-10 22:04:03.869266 | controller | Friday 10 October 2025  22:04:03 +0000 (0:00:00.022)       0:00:16.930 ********
2025-10-10 22:04:03.891436 | controller | skipping: [instance]
2025-10-10 22:04:03.907513 | controller |
2025-10-10 22:04:03.907532 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
2025-10-10 22:04:03.907541 | controller | Friday 10 October 2025  22:04:03 +0000 (0:00:00.022)       0:00:16.952 ********
2025-10-10 22:04:03.907549 | controller | skipping: [instance]
2025-10-10 22:04:03.935064 | controller |
2025-10-10 22:04:03.935079 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
2025-10-10 22:04:03.935085 | controller | Friday 10 October 2025  22:04:03 +0000 (0:00:00.016)       0:00:16.968 ********
2025-10-10 22:04:03.935093 | controller | skipping: [instance]
2025-10-10 22:04:03.951589 | controller |
2025-10-10 22:04:03.951607 | controller | TASK [repo_setup : Cleanup existing metadata] **********************************
2025-10-10 22:04:03.951615 | controller | Friday 10 October 2025  22:04:03 +0000 (0:00:00.027)       0:00:16.996 ********
2025-10-10 22:04:03.951625 | controller | skipping: [instance]
2025-10-10 22:04:03.971794 | controller |
2025-10-10 22:04:03.971812 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
2025-10-10 22:04:03.971819 | controller | Friday 10 October 2025  22:04:03 +0000 (0:00:00.016)       0:00:17.012 ********
2025-10-10 22:04:03.971829 | controller | skipping: [instance]
2025-10-10 22:04:04.002976 | controller |
2025-10-10 22:04:04.003014 | controller | TASK [test_deps : Look for redhat-release rpm] *********************************
2025-10-10 22:04:04.003022 | controller | Friday 10 October 2025  22:04:03 +0000 (0:00:00.020)       0:00:17.032 ********
2025-10-10 22:04:04.003032 | controller | skipping: [instance]
2025-10-10 22:04:04.031078 | controller |
2025-10-10 22:04:04.031109 | controller | TASK [test_deps : Remove redhat-release] ***************************************
2025-10-10 22:04:04.031120 | controller | Friday 10 October 2025  22:04:04 +0000 (0:00:00.031)       0:00:17.063 ********
2025-10-10 22:04:04.031133 | controller | skipping: [instance]
2025-10-10 22:04:04.031142 | controller |
2025-10-10 22:04:04.031150 | controller | TASK [test_deps : Install centos-stream-release] *******************************
2025-10-10 22:04:04.031160 | controller | Friday 10 October 2025  22:04:04 +0000 (0:00:00.028)       0:00:17.092 ********
2025-10-10 22:04:04.058827 | controller | skipping: [instance]
2025-10-10 22:04:04.086649 | controller |
2025-10-10 22:04:04.086668 | controller | TASK [test_deps : Install additional packages] *********************************
2025-10-10 22:04:04.086676 | controller | Friday 10 October 2025  22:04:04 +0000 (0:00:00.027)       0:00:17.119 ********
2025-10-10 22:04:04.086684 | controller | skipping: [instance]
2025-10-10 22:04:48.149773 | controller |
2025-10-10 22:04:48.149811 | controller | TASK [test_deps : Install selinux python libs] *********************************
2025-10-10 22:04:48.149819 | controller | Friday 10 October 2025  22:04:04 +0000 (0:00:00.027)       0:00:17.147 ********
2025-10-10 22:04:48.149830 | controller | ok: [instance]
2025-10-10 22:04:49.240738 | controller |
2025-10-10 22:04:49.240785 | controller | TASK [test_deps : Install python yaml libs] ************************************
2025-10-10 22:04:49.240794 | controller | Friday 10 October 2025  22:04:48 +0000 (0:00:44.060)       0:01:01.208 ********
2025-10-10 22:04:49.240805 | controller | ok: [instance]
2025-10-10 22:04:49.290069 | controller |
2025-10-10 22:04:49.290114 | controller | TASK [test_deps : Install extra packages] **************************************
2025-10-10 22:04:49.290122 | controller | Friday 10 October 2025  22:04:49 +0000 (0:00:01.092)       0:01:02.300 ********
2025-10-10 22:04:49.290134 | controller | skipping: [instance]
2025-10-10 22:04:49.290181 | controller |
2025-10-10 22:04:49.290204 | controller | PLAY RECAP *********************************************************************
2025-10-10 22:04:49.290212 | controller | instance                   : ok=19   changed=9    unreachable=0    failed=0    skipped=30   rescued=0    ignored=0
2025-10-10 22:04:49.292481 | controller |
2025-10-10 22:04:49.382060 | controller | Friday 10 October 2025  22:04:49 +0000 (0:00:00.049)       0:01:02.350 ********
2025-10-10 22:04:49.382080 | controller | ===============================================================================
2025-10-10 22:04:49.382087 | controller | test_deps : Install selinux python libs -------------------------------- 44.06s
2025-10-10 22:04:49.382098 | controller | repo_setup : Initialize python venv and install requirements ------------ 7.43s
2025-10-10 22:04:49.382103 | controller | repo_setup : Run repo-setup --------------------------------------------- 2.02s
2025-10-10 22:04:49.382109 | controller | repo_setup : Get repo-setup repository ---------------------------------- 1.15s
2025-10-10 22:04:49.382118 | controller | test_deps : Install python yaml libs ------------------------------------ 1.09s
2025-10-10 22:04:49.382124 | controller | Gathering Facts --------------------------------------------------------- 0.88s
2025-10-10 22:04:49.382128 | controller | repo_setup : Make sure git-core package is installed -------------------- 0.73s
2025-10-10 22:04:49.382133 | controller | test_deps : Disable ubi 9 repos ----------------------------------------- 0.68s
2025-10-10 22:04:49.382138 | controller | repo_setup : Install repo-setup package --------------------------------- 0.67s
2025-10-10 22:04:49.382143 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.55s
2025-10-10 22:04:49.382148 | controller | repo_setup : Ensure directories are present ----------------------------- 0.51s
2025-10-10 22:04:49.382153 | controller | repo_setup : Dump full hash in delorean.repo.md5 file ------------------- 0.44s
2025-10-10 22:04:49.382158 | controller | test_deps : Disable ubi host subscription-manager integration ----------- 0.31s
2025-10-10 22:04:49.382162 | controller | test_deps : Detect mirrors file ----------------------------------------- 0.28s
2025-10-10 22:04:49.382167 | controller | repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist) --- 0.22s
2025-10-10 22:04:49.382172 | controller | repo_setup : Use RDO proxy mirrors -------------------------------------- 0.20s
2025-10-10 22:04:49.382177 | controller | repo_setup : Check for /etc/ci/mirror_info.sh --------------------------- 0.18s
2025-10-10 22:04:49.382182 | controller | Deploy repo-setup ------------------------------------------------------- 0.06s
2025-10-10 22:04:49.382187 | controller | test_deps : Install extra packages -------------------------------------- 0.05s
2025-10-10 22:04:49.382192 | controller | test_deps : Gather variables for each operating system ------------------ 0.04s
2025-10-10 22:04:49.382200 | controller | INFO     Running default > converge
2025-10-10 22:04:49.763922 | controller |
2025-10-10 22:04:50.496939 | controller | PLAY [Converge] ****************************************************************
2025-10-10 22:04:50.496973 | controller |
2025-10-10 22:04:50.496997 | controller | TASK [Gathering Facts] *********************************************************
2025-10-10 22:04:50.497007 | controller | Friday 10 October 2025  22:04:49 +0000 (0:00:00.012)       0:00:00.012 ********
2025-10-10 22:04:50.497017 | controller | ok: [instance]
2025-10-10 22:04:51.403131 | controller |
2025-10-10 22:04:51.403174 | controller | TASK [Get open PR list for mariadb-operator] ***********************************
2025-10-10 22:04:51.403185 | controller | Friday 10 October 2025  22:04:50 +0000 (0:00:00.733)       0:00:00.745 ********
2025-10-10 22:04:51.403198 | controller | ok: [instance]
2025-10-10 22:04:51.403232 | controller |
2025-10-10 22:04:51.403243 | controller | TASK [Extract latest PR from list] *********************************************
2025-10-10 22:04:51.403335 | controller | Friday 10 October 2025  22:04:51 +0000 (0:00:00.906)       0:00:01.651 ********
2025-10-10 22:04:51.441838 | controller | ok: [instance]
2025-10-10 22:04:51.483618 | controller |
2025-10-10 22:04:51.483643 | controller | TASK [Build keystone-operator] *************************************************
2025-10-10 22:04:51.483653 | controller | Friday 10 October 2025  22:04:51 +0000 (0:00:00.038)       0:00:01.690 ********
2025-10-10 22:04:51.483665 | controller |
2025-10-10 22:04:51.922541 | controller | TASK [operator_build : Ensure mandatory directories exist] *********************
2025-10-10 22:04:51.922569 | controller | Friday 10 October 2025  22:04:51 +0000 (0:00:00.041)       0:00:01.732 ********
2025-10-10 22:04:51.922580 | controller | ok: [instance] => (item=artifacts)
2025-10-10 22:04:51.940505 | controller | ok: [instance] => (item=logs)
2025-10-10 22:04:51.940522 | controller |
2025-10-10 22:04:51.940529 | controller | TASK [operator_build : Initialize role output] *********************************
2025-10-10 22:04:51.940534 | controller | Friday 10 October 2025  22:04:51 +0000 (0:00:00.438)       0:00:02.170 ********
2025-10-10 22:04:51.940548 | controller | ok: [instance]
2025-10-10 22:04:51.961278 | controller |
2025-10-10 22:04:51.961301 | controller | TASK [operator_build : Populate operators list with zuul info] *****************
2025-10-10 22:04:51.961311 | controller | Friday 10 October 2025  22:04:51 +0000 (0:00:00.018)       0:00:02.189 ********
2025-10-10 22:04:51.961322 | controller | skipping: [instance]
2025-10-10 22:04:52.007146 | controller |
2025-10-10 22:04:52.007168 | controller | TASK [operator_build : Merge lists of operators] *******************************
2025-10-10 22:04:52.007184 | controller | Friday 10 October 2025  22:04:51 +0000 (0:00:00.020)       0:00:02.209 ********
2025-10-10 22:04:52.007196 | controller | ok: [instance]
2025-10-10 22:04:52.042542 | controller |
2025-10-10 22:04:52.042559 | controller | TASK [operator_build : Get meta_operator src dir from operators_list] **********
2025-10-10 22:04:52.042565 | controller | Friday 10 October 2025  22:04:52 +0000 (0:00:00.045)       0:00:02.255 ********
2025-10-10 22:04:52.042573 | controller | ok: [instance]
2025-10-10 22:04:52.084076 | controller |
2025-10-10 22:04:52.084112 | controller | TASK [operator_build : Adds meta-operator to the list] *************************
2025-10-10 22:04:52.084120 | controller | Friday 10 October 2025  22:04:52 +0000 (0:00:00.035)       0:00:02.291 ********
2025-10-10 22:04:52.084131 | controller | ok: [instance]
2025-10-10 22:04:52.114182 | controller |
2025-10-10 22:04:52.114224 | controller | TASK [operator_build : Clone operator's code when src dir is empty] ************
2025-10-10 22:04:52.114235 | controller | Friday 10 October 2025  22:04:52 +0000 (0:00:00.041)       0:00:02.332 ********
2025-10-10 22:04:52.114249 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/operator_build/tasks/clone.yml for instance => (item={'name': 'keystone-operator', 'src': '/home/zuul/keystone-operator'})
2025-10-10 22:04:52.114286 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/operator_build/tasks/clone.yml for instance => (item={'name': 'openstack-operator', 'src': '/home/zuul/openstack-operator'})
2025-10-10 22:04:52.114295 | controller |
2025-10-10 22:04:52.114303 | controller | TASK [operator_build : keystone-operator - Check if src code exists] ***********
2025-10-10 22:04:52.114314 | controller | Friday 10 October 2025  22:04:52 +0000 (0:00:00.030)       0:00:02.362 ********
2025-10-10 22:04:52.354966 | controller | ok: [instance]
2025-10-10 22:04:53.761636 | controller |
2025-10-10 22:04:53.761666 | controller | TASK [operator_build : keystone-operator - Clone from github if no code was provided] ***
2025-10-10 22:04:53.761674 | controller | Friday 10 October 2025  22:04:52 +0000 (0:00:00.240)       0:00:02.603 ********
2025-10-10 22:04:53.761685 | controller | changed: [instance]
2025-10-10 22:04:53.939681 | controller |
2025-10-10 22:04:53.939712 | controller | TASK [operator_build : openstack-operator - Check if src code exists] **********
2025-10-10 22:04:53.939720 | controller | Friday 10 October 2025  22:04:53 +0000 (0:00:01.406)       0:00:04.009 ********
2025-10-10 22:04:53.939731 | controller | ok: [instance]
2025-10-10 22:04:56.519515 | controller |
2025-10-10 22:04:56.519545 | controller | TASK [operator_build : openstack-operator - Clone from github if no code was provided] ***
2025-10-10 22:04:56.519553 | controller | Friday 10 October 2025  22:04:53 +0000 (0:00:00.177)       0:00:04.187 ********
2025-10-10 22:04:56.519562 | controller | changed: [instance]
2025-10-10 22:04:56.564096 | controller |
2025-10-10 22:04:56.564141 | controller | TASK [operator_build : Building operators] *************************************
2025-10-10 22:04:56.564152 | controller | Friday 10 October 2025  22:04:56 +0000 (0:00:02.579)       0:00:06.767 ********
2025-10-10 22:04:56.564167 | controller | skipping: [instance] => (item={'name': 'openstack-operator', 'src': '/home/zuul/openstack-operator'})
2025-10-10 22:04:56.815700 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/operator_build/tasks/build.yml for instance => (item={'name': 'keystone-operator', 'src': '/home/zuul/keystone-operator'})
2025-10-10 22:04:56.815729 | controller |
2025-10-10 22:04:56.815736 | controller | TASK [operator_build : keystone-operator - Read go.mod file contents of Openstack Operator] ***
2025-10-10 22:04:56.815742 | controller | Friday 10 October 2025  22:04:56 +0000 (0:00:00.044)       0:00:06.812 ********
2025-10-10 22:04:56.815763 | controller | ok: [instance]
2025-10-10 22:04:56.848867 | controller |
2025-10-10 22:04:56.848883 | controller | TASK [operator_build : keystone-operator - Get base module from go.mod] ********
2025-10-10 22:04:56.848896 | controller | Friday 10 October 2025  22:04:56 +0000 (0:00:00.252)       0:00:07.064 ********
2025-10-10 22:04:56.848904 | controller | ok: [instance]
2025-10-10 22:04:56.883567 | controller |
2025-10-10 22:04:56.883590 | controller | TASK [operator_build : Get the base module name not empty operator_base_module] ***
2025-10-10 22:04:56.883599 | controller | Friday 10 October 2025  22:04:56 +0000 (0:00:00.033)       0:00:07.097 ********
2025-10-10 22:04:56.883610 | controller | ok: [instance]
2025-10-10 22:04:56.908687 | controller |
2025-10-10 22:04:56.908709 | controller | TASK [operator_build : keystone-operator - Set default api path] ***************
2025-10-10 22:04:56.908718 | controller | Friday 10 October 2025  22:04:56 +0000 (0:00:00.034)       0:00:07.131 ********
2025-10-10 22:04:56.908730 | controller | ok: [instance]
2025-10-10 22:04:56.933586 | controller |
2025-10-10 22:04:56.933609 | controller | TASK [operator_build : keystone-operator - Update the go.mod file in meta operator for provided PR_SHA] ***
2025-10-10 22:04:56.933619 | controller | Friday 10 October 2025  22:04:56 +0000 (0:00:00.025)       0:00:07.157 ********
2025-10-10 22:04:56.933631 | controller | skipping: [instance]
2025-10-10 22:04:57.191461 | controller |
2025-10-10 22:04:57.191482 | controller | TASK [operator_build : keystone-operator - Get latest commit when no PR is provided] ***
2025-10-10 22:04:57.191489 | controller | Friday 10 October 2025  22:04:56 +0000 (0:00:00.024)       0:00:07.181 ********
2025-10-10 22:04:57.191498 | controller | changed: [instance]
2025-10-10 22:04:57.221486 | controller |
2025-10-10 22:04:57.221509 | controller | TASK [operator_build : keystone-operator - Set pr_sha to be used as image tag] ***
2025-10-10 22:04:57.221519 | controller | Friday 10 October 2025  22:04:57 +0000 (0:00:00.258)       0:00:07.439 ********
2025-10-10 22:04:57.221530 | controller | ok: [instance]
2025-10-10 22:04:57.233226 | controller |
2025-10-10 22:04:57.233260 | controller | TASK [operator_build : Skip if operator.pr_sha matches HEAD] *******************
2025-10-10 22:04:57.233268 | controller | Friday 10 October 2025  22:04:57 +0000 (0:00:00.029)       0:00:07.469 ********
2025-10-10 22:04:57.233283 | controller | fatal: [instance]: FAILED! =>
2025-10-10 22:04:57.233307 | controller |   msg: |-
2025-10-10 22:04:57.233315 | controller |     The conditional check 'pr_sha != operator.pr_sha' failed. The error was: error while evaluating conditional (pr_sha != operator.pr_sha): 'dict object' has no attribute 'pr_sha'. 'dict object' has no attribute 'pr_sha'
2025-10-10 22:04:57.233570 | controller |
2025-10-10 22:04:57.233646 | controller |     The error appears to be in '/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/operator_build/tasks/build.yml': line 74, column 3, but may
2025-10-10 22:04:57.233664 | controller |     be elsewhere in the file depending on the exact syntax problem.
2025-10-10 22:04:57.233682 | controller |
2025-10-10 22:04:57.233867 | controller |     The offending line appears to be:
2025-10-10 22:04:57.234096 | controller |
2025-10-10 22:04:57.234111 | controller |
2025-10-10 22:04:57.234149 | controller |     - name: Skip if operator.pr_sha matches HEAD
2025-10-10 22:04:57.234170 | controller |       ^ here
2025-10-10 22:04:57.235252 | controller |
2025-10-10 22:04:57.236082 | controller | PLAY RECAP *********************************************************************
2025-10-10 22:04:57.236160 | controller | instance                   : ok=21   changed=3    unreachable=0    failed=1    skipped=2    rescued=0    ignored=0
2025-10-10 22:04:57.236171 | controller |
2025-10-10 22:04:57.236177 | controller | Friday 10 October 2025  22:04:57 +0000 (0:00:00.014)       0:00:07.484 ********
2025-10-10 22:04:57.236182 | controller | ===============================================================================
2025-10-10 22:04:57.236188 | controller | operator_build : openstack-operator - Clone from github if no code was provided --- 2.58s
2025-10-10 22:04:57.236196 | controller | operator_build : keystone-operator - Clone from github if no code was provided --- 1.41s
2025-10-10 22:04:57.236275 | controller | Get open PR list for mariadb-operator ----------------------------------- 0.91s
2025-10-10 22:04:57.236383 | controller | Gathering Facts --------------------------------------------------------- 0.73s
2025-10-10 22:04:57.236489 | controller | operator_build : Ensure mandatory directories exist --------------------- 0.44s
2025-10-10 22:04:57.236592 | controller | operator_build : keystone-operator - Get latest commit when no PR is provided --- 0.26s
2025-10-10 22:04:57.236700 | controller | operator_build : keystone-operator - Read go.mod file contents of Openstack Operator --- 0.25s
2025-10-10 22:04:57.236834 | controller | operator_build : keystone-operator - Check if src code exists ----------- 0.24s
2025-10-10 22:04:57.236935 | controller | operator_build : openstack-operator - Check if src code exists ---------- 0.18s
2025-10-10 22:04:57.237057 | controller | operator_build : Merge lists of operators ------------------------------- 0.05s
2025-10-10 22:04:57.237168 | controller | operator_build : Building operators ------------------------------------- 0.04s
2025-10-10 22:04:57.237273 | controller | Build keystone-operator ------------------------------------------------- 0.04s
2025-10-10 22:04:57.237377 | controller | operator_build : Adds meta-operator to the list ------------------------- 0.04s
2025-10-10 22:04:57.237480 | controller | Extract latest PR from list --------------------------------------------- 0.04s
2025-10-10 22:04:57.237586 | controller | operator_build : Get meta_operator src dir from operators_list ---------- 0.04s
2025-10-10 22:04:57.237698 | controller | operator_build : Get the base module name not empty operator_base_module --- 0.03s
2025-10-10 22:04:57.237812 | controller | operator_build : keystone-operator - Get base module from go.mod -------- 0.03s
2025-10-10 22:04:57.237915 | controller | operator_build : Clone operator's code when src dir is empty ------------ 0.03s
2025-10-10 22:04:57.238037 | controller | operator_build : keystone-operator - Set pr_sha to be used as image tag --- 0.03s
2025-10-10 22:04:57.238143 | controller | operator_build : keystone-operator - Set default api path --------------- 0.03s
2025-10-10 22:04:57.283884 | controller | CRITICAL Ansible return code was 2, command was: ['ansible-playbook', '--inventory', '/home/zuul/.cache/molecule/operator_build/default/inventory', '--skip-tags', 'molecule-notest,notest', '/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/operator_build/molecule/default/converge.yml']
2025-10-10 22:04:57.284276 | controller | WARNING  An error occurred during the test sequence action: 'converge'. Cleaning up.
2025-10-10 22:04:57.292309 | controller | INFO     Running default > cleanup
2025-10-10 22:04:57.292689 | controller | WARNING  Skipping, cleanup playbook not configured.
2025-10-10 22:04:57.299695 | controller | INFO     Running default > destroy
2025-10-10 22:04:57.300004 | controller | WARNING  Skipping, instances are delegated.
2025-10-10 22:04:57.300300 | controller | INFO     Pruning extra files from scenario ephemeral directory
2025-10-10 22:04:57.301648 | controller | INFO     Writing /tmp/report.html report.
2025-10-10 22:04:57.731177 | controller | ERROR
2025-10-10 22:04:57.731356 | controller | {
2025-10-10 22:04:57.731388 | controller |   "delta": "0:01:28.983433",
2025-10-10 22:04:57.731408 | controller |   "end": "2025-10-10 22:04:57.342850",
2025-10-10 22:04:57.731425 | controller |   "msg": "non-zero return code",
2025-10-10 22:04:57.731445 | controller |   "rc": 1,
2025-10-10 22:04:57.731461 | controller |   "start": "2025-10-10 22:03:28.359417"
2025-10-10 22:04:57.731477 | controller | }
failure
2025-10-10 22:04:57.735050 | 
2025-10-10 22:04:57.735098 | PLAY RECAP
2025-10-10 22:04:57.735139 | controller | ok: 2 changed: 0 unreachable: 0 failed: 1 skipped: 1 rescued: 0 ignored: 0
2025-10-10 22:04:57.735174 | 
2025-10-10 22:04:57.790814 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-10-10 22:04:57.791612 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-10-10 22:04:58.264903 | 
2025-10-10 22:04:58.265014 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-10-10 22:04:58.283259 | 
2025-10-10 22:04:58.283325 | TASK [Filter out host if needed]
2025-10-10 22:04:58.291169 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-10-10 22:04:58.295303 | 
2025-10-10 22:04:58.295364 | TASK [Ensure file is present]
2025-10-10 22:04:58.576103 | controller | ok
2025-10-10 22:04:58.582260 | 
2025-10-10 22:04:58.582323 | TASK [Manage molecule report file]
2025-10-10 22:04:59.105814 | controller | changed
2025-10-10 22:04:59.110634 | 
2025-10-10 22:04:59.110690 | TASK [Check if we get ci-framework-data basedir]
2025-10-10 22:04:59.279243 | controller | ok
2025-10-10 22:04:59.283770 | 
2025-10-10 22:04:59.283826 | TASK [Create ci-framework-data log directory for zuul]
2025-10-10 22:04:59.554830 | controller | changed
2025-10-10 22:04:59.559804 | 
2025-10-10 22:04:59.559868 | TASK [Copy ci-framework interesting files]
2025-10-10 22:04:59.738776 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2025-10-10 22:05:00.084629 | controller | changed
2025-10-10 22:05:00.089437 | 
2025-10-10 22:05:00.089504 | TASK [Get SELinux listing]
2025-10-10 22:05:00.614444 | controller | changed
2025-10-10 22:05:00.619602 | 
2025-10-10 22:05:00.619661 | TASK [Generate log index]
2025-10-10 22:05:01.205056 | controller | changed
2025-10-10 22:05:01.209797 | 
2025-10-10 22:05:01.209858 | TASK [Get some env related data]
2025-10-10 22:05:01.709660 | controller | /home/zuul/.local/bin/ansible
2025-10-10 22:05:02.242787 | controller | changed
2025-10-10 22:05:02.247357 | 
2025-10-10 22:05:02.247420 | TASK [Generate list of logs to collect in home directory]
2025-10-10 22:05:02.518956 | controller | ok: All paths examined
2025-10-10 22:05:02.523928 | 
2025-10-10 22:05:02.523992 | LOOP [Copy logs from home directory]
2025-10-10 22:05:02.793617 | controller | changed:
2025-10-10 22:05:02.793765 | controller | {
2025-10-10 22:05:02.793793 | controller |   "atime": 1760133790.0366666,
2025-10-10 22:05:02.793814 | controller |   "ctime": 1760133806.3585062,
2025-10-10 22:05:02.793830 | controller |   "dev": 64513,
2025-10-10 22:05:02.793852 | controller |   "gid": 1000,
2025-10-10 22:05:02.793870 | controller |   "gr_name": "zuul",
2025-10-10 22:05:02.793887 | controller |   "inode": 4194939,
2025-10-10 22:05:02.793902 | controller |   "isblk": false,
2025-10-10 22:05:02.793939 | controller |   "ischr": false,
2025-10-10 22:05:02.793956 | controller |   "isdir": false,
2025-10-10 22:05:02.793971 | controller |   "isfifo": false,
2025-10-10 22:05:02.793986 | controller |   "isgid": false,
2025-10-10 22:05:02.794000 | controller |   "islnk": false,
2025-10-10 22:05:02.794014 | controller |   "isreg": true,
2025-10-10 22:05:02.794028 | controller |   "issock": false,
2025-10-10 22:05:02.794042 | controller |   "isuid": false,
2025-10-10 22:05:02.794055 | controller |   "mode": "0644",
2025-10-10 22:05:02.794070 | controller |   "mtime": 1760133806.3585062,
2025-10-10 22:05:02.794085 | controller |   "nlink": 1,
2025-10-10 22:05:02.794099 | controller |   "path": "/home/zuul/ansible.log",
2025-10-10 22:05:02.794116 | controller |   "pw_name": "zuul",
2025-10-10 22:05:02.794133 | controller |   "rgrp": true,
2025-10-10 22:05:02.794159 | controller |   "roth": true,
2025-10-10 22:05:02.794178 | controller |   "rusr": true,
2025-10-10 22:05:02.794193 | controller |   "size": 6749,
2025-10-10 22:05:02.794207 | controller |   "uid": 1000,
2025-10-10 22:05:02.794222 | controller |   "wgrp": false,
2025-10-10 22:05:02.794236 | controller |   "woth": false,
2025-10-10 22:05:02.794252 | controller |   "wusr": true,
2025-10-10 22:05:02.794266 | controller |   "xgrp": false,
2025-10-10 22:05:02.794280 | controller |   "xoth": false,
2025-10-10 22:05:02.794293 | controller |   "xusr": false
2025-10-10 22:05:02.794307 | controller | }
2025-10-10 22:05:02.827111 | 
2025-10-10 22:05:02.827220 | TASK [Copy crio stats log file]
2025-10-10 22:05:02.840639 | controller | skipping: Conditional result was False
2025-10-10 22:05:02.845957 | 
2025-10-10 22:05:02.846028 | TASK [Get SELinux related data]
2025-10-10 22:05:03.040682 | controller | <no matches>
2025-10-10 22:05:03.370897 | controller | ERROR
2025-10-10 22:05:03.371114 | controller | {
2025-10-10 22:05:03.371166 | controller |   "delta": "0:00:00.007974",
2025-10-10 22:05:03.371194 | controller |   "end": "2025-10-10 22:05:03.041273",
2025-10-10 22:05:03.371216 | controller |   "msg": "non-zero return code",
2025-10-10 22:05:03.371235 | controller |   "rc": 1,
2025-10-10 22:05:03.371254 | controller |   "start": "2025-10-10 22:05:03.033299"
2025-10-10 22:05:03.371272 | controller | }
2025-10-10 22:05:03.371295 | controller | ERROR: Ignoring Errors
2025-10-10 22:05:03.375979 | 
2025-10-10 22:05:03.376040 | TASK [Create system configuration directory]
2025-10-10 22:05:03.545352 | controller | changed
2025-10-10 22:05:03.549752 | 
2025-10-10 22:05:03.549809 | TASK [Get some of the system configurations]
2025-10-10 22:05:04.074706 | controller | changed
2025-10-10 22:05:04.079339 | 
2025-10-10 22:05:04.079398 | TASK [Copy generated documentation if available]
2025-10-10 22:05:04.092749 | controller | skipping: Conditional result was False
2025-10-10 22:05:04.098057 | 
2025-10-10 22:05:04.098115 | TASK [Copy generated AsciiDoc documentation if available]
2025-10-10 22:05:04.111383 | controller | skipping: Conditional result was False
2025-10-10 22:05:04.116524 | 
2025-10-10 22:05:04.116581 | TASK [Compress logs bigger than 2MB]
2025-10-10 22:05:04.640142 | controller | changed
2025-10-10 22:05:04.644634 | 
2025-10-10 22:05:04.644694 | TASK [Copy files from workspace on node]
2025-10-10 22:05:04.662146 | controller | ok
2025-10-10 22:05:04.682297 | 
2025-10-10 22:05:04.682358 | TASK [fetch-output : Set log path for multiple nodes]
2025-10-10 22:05:04.695868 | controller | skipping: Conditional result was False
2025-10-10 22:05:04.701179 | 
2025-10-10 22:05:04.701246 | TASK [fetch-output : Set log path for single node]
2025-10-10 22:05:04.729194 | controller | ok
2025-10-10 22:05:04.733986 | 
2025-10-10 22:05:04.734052 | LOOP [fetch-output : Ensure local output dirs]
2025-10-10 22:05:04.914790 | controller -> localhost | ok: "/var/lib/zuul/builds/a92840bc01cf4623a0bad38b1a4d922b/work/logs"
2025-10-10 22:05:04.915074 | controller -> localhost | changed: All items complete
2025-10-10 22:05:04.915113 | 
2025-10-10 22:05:05.079873 | controller -> localhost | changed: "/var/lib/zuul/builds/a92840bc01cf4623a0bad38b1a4d922b/work/artifacts"
2025-10-10 22:05:05.246121 | controller -> localhost | changed: "/var/lib/zuul/builds/a92840bc01cf4623a0bad38b1a4d922b/work/docs"
2025-10-10 22:05:05.261528 | 
2025-10-10 22:05:05.261624 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-10-10 22:05:05.777715 | controller | changed:
2025-10-10 22:05:05.777870 | controller | .d..t...... ./
2025-10-10 22:05:05.777897 | controller | >f+++++++++ README.html
2025-10-10 22:05:05.777949 | controller | >f+++++++++ ansible-execution.log
2025-10-10 22:05:05.777973 | controller | >f+++++++++ ansible.log
2025-10-10 22:05:05.777990 | controller | >f+++++++++ dmesg.log
2025-10-10 22:05:05.778006 | controller | >f+++++++++ installed-pkgs.log
2025-10-10 22:05:05.778023 | controller | >f+++++++++ python.log
2025-10-10 22:05:05.778039 | controller | >f+++++++++ registries.conf
2025-10-10 22:05:05.778053 | controller | >f+++++++++ report.html
2025-10-10 22:05:05.778069 | controller | >f+++++++++ selinux-denials.log
2025-10-10 22:05:05.778083 | controller | >f+++++++++ selinux-listing.log
2025-10-10 22:05:05.778098 | controller | cd+++++++++ ci-framework-data/
2025-10-10 22:05:05.778112 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-10-10 22:05:05.778126 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-10-10 22:05:05.778140 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-10-10 22:05:05.778162 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-10-10 22:05:05.778178 | controller | cd+++++++++ ci-framework-data/artifacts/repositories/
2025-10-10 22:05:05.778193 | controller | >f+++++++++ ci-framework-data/artifacts/repositories/delorean.repo.md5
2025-10-10 22:05:05.778206 | controller | cd+++++++++ ci-framework-data/logs/
2025-10-10 22:05:05.778220 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2025-10-10 22:05:05.778233 | controller | cd+++++++++ registries.conf.d/
2025-10-10 22:05:05.778246 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-10-10 22:05:05.778260 | controller | cd+++++++++ system-config/
2025-10-10 22:05:05.778274 | controller | cd+++++++++ system-config/libvirt/
2025-10-10 22:05:05.778287 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-10-10 22:05:05.778301 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-10-10 22:05:06.115039 | controller | changed: .d..t...... ./
2025-10-10 22:05:06.451331 | controller | changed: .d..t...... ./
2025-10-10 22:05:06.472186 | 
2025-10-10 22:05:06.472281 | TASK [Return artifact to Zuul]
2025-10-10 22:05:06.499774 | controller | ok
2025-10-10 22:05:06.516282 | 
2025-10-10 22:05:06.516331 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-10-10 22:05:06.516417 | 
2025-10-10 22:05:06.516442 | PLAY RECAP
2025-10-10 22:05:06.516475 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2025-10-10 22:05:06.516494 | 
2025-10-10 22:05:06.584008 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-10-10 22:05:06.584831 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-10-10 22:05:07.056869 | 
2025-10-10 22:05:07.056981 | PLAY [all]
2025-10-10 22:05:07.073686 | 
2025-10-10 22:05:07.073759 | TASK [include_role : fetch-output]
2025-10-10 22:05:07.102430 | controller | ok
2025-10-10 22:05:07.118695 | 
2025-10-10 22:05:07.118768 | TASK [fetch-output : Set log path for multiple nodes]
2025-10-10 22:05:07.162534 | controller | skipping: Conditional result was False
2025-10-10 22:05:07.167569 | 
2025-10-10 22:05:07.167632 | TASK [fetch-output : Set log path for single node]
2025-10-10 22:05:07.195554 | controller | ok
2025-10-10 22:05:07.200312 | 
2025-10-10 22:05:07.200375 | LOOP [fetch-output : Ensure local output dirs]
2025-10-10 22:05:07.504476 | controller -> localhost | ok: "/var/lib/zuul/builds/a92840bc01cf4623a0bad38b1a4d922b/work/logs"
2025-10-10 22:05:07.675814 | controller -> localhost | ok: "/var/lib/zuul/builds/a92840bc01cf4623a0bad38b1a4d922b/work/artifacts"
2025-10-10 22:05:07.843436 | controller -> localhost | ok: "/var/lib/zuul/builds/a92840bc01cf4623a0bad38b1a4d922b/work/docs"
2025-10-10 22:05:07.857486 | 
2025-10-10 22:05:07.857611 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-10-10 22:05:08.333098 | controller | ok
2025-10-10 22:05:08.333299 | controller | ok: All items complete
2025-10-10 22:05:08.333330 | 
2025-10-10 22:05:08.674673 | controller | ok
2025-10-10 22:05:09.013039 | controller | ok
2025-10-10 22:05:09.023347 | 
2025-10-10 22:05:09.023457 | TASK [include_role : fetch-output-openshift]
2025-10-10 22:05:09.036385 | controller | skipping: Conditional result was False
2025-10-10 22:05:09.041500 | 
2025-10-10 22:05:09.041563 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-10-10 22:05:09.353899 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.006297
2025-10-10 22:05:09.530199 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.006701
2025-10-10 22:05:09.563751 | 
2025-10-10 22:05:09.563829 | PLAY [all]
2025-10-10 22:05:09.576623 | 
2025-10-10 22:05:09.576684 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-10-10 22:05:09.928320 | controller | changed
2025-10-10 22:05:09.949770 | 
2025-10-10 22:05:09.949825 | PLAY RECAP
2025-10-10 22:05:09.949869 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-10-10 22:05:09.949891 | 
2025-10-10 22:05:10.010891 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-10-10 22:05:10.011621 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-10-10 22:05:10.508565 | 
2025-10-10 22:05:10.508659 | PLAY [localhost]
2025-10-10 22:05:10.524600 | 
2025-10-10 22:05:10.524667 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-10-10 22:05:10.794748 | localhost | changed
2025-10-10 22:05:10.798946 | 
2025-10-10 22:05:10.799021 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-10-10 22:05:10.816146 | localhost | ok
2025-10-10 22:05:10.823446 | 
2025-10-10 22:05:10.823517 | TASK [add-fileserver : Create SSH private key tempfile]
2025-10-10 22:05:11.093085 | localhost | changed
2025-10-10 22:05:11.098381 | 
2025-10-10 22:05:11.098452 | TASK [add-fileserver : Create SSH private key from secret]
2025-10-10 22:05:11.591655 | localhost | changed
2025-10-10 22:05:11.596200 | 
2025-10-10 22:05:11.596261 | TASK [add-fileserver : Add fileserver ssh key]
2025-10-10 22:05:11.908685 | localhost | Identity added: /var/lib/zuul/builds/a92840bc01cf4623a0bad38b1a4d922b/work/tmp/ansible.d90v8i2e (/var/lib/zuul/builds/a92840bc01cf4623a0bad38b1a4d922b/work/tmp/ansible.d90v8i2e)
2025-10-10 22:05:11.908818 | localhost | ok: Runtime: 0:00:00.005760
2025-10-10 22:05:11.912699 | 
2025-10-10 22:05:11.912765 | TASK [add-fileserver : Remove SSH private key from disk]
2025-10-10 22:05:12.111320 | localhost | ok: Runtime: 0:00:00.004074
2025-10-10 22:05:12.115504 | 
2025-10-10 22:05:12.115561 | TASK [add-fileserver : Add fileserver to inventory]
2025-10-10 22:05:12.162668 | localhost | changed
2025-10-10 22:05:12.167108 | 
2025-10-10 22:05:12.167174 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-10-10 22:05:12.467466 | localhost | changed
2025-10-10 22:05:12.486110 | 
2025-10-10 22:05:12.486178 | PLAY [localhost]
2025-10-10 22:05:12.496748 | 
2025-10-10 22:05:12.496812 | TASK [Generate bulk log download script]
2025-10-10 22:05:12.514812 | localhost | ok
2025-10-10 22:05:12.525510 | 
2025-10-10 22:05:12.525577 | TASK [local-log-download : Check API endpoint is defined]
2025-10-10 22:05:12.552613 | localhost | ok: All assertions passed
2025-10-10 22:05:12.556644 | 
2025-10-10 22:05:12.556701 | TASK [local-log-download : Create download script]
2025-10-10 22:05:12.894173 | localhost -> localhost | changed
2025-10-10 22:05:12.902305 | 
2025-10-10 22:05:12.902368 | TASK [Register quick-download link]
2025-10-10 22:05:12.919404 | localhost | ok
2025-10-10 22:05:12.951048 | 
2025-10-10 22:05:12.951116 | PLAY [logserver.rdoproject.org]
2025-10-10 22:05:12.960018 | 
2025-10-10 22:05:12.960077 | TASK [Set zuul-log-path fact]
2025-10-10 22:05:12.975490 | logserver.rdoproject.org | ok
2025-10-10 22:05:12.983791 | 
2025-10-10 22:05:12.983857 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-10 22:05:13.000277 | logserver.rdoproject.org | ok
2025-10-10 22:05:13.005327 | 
2025-10-10 22:05:13.005390 | TASK [upload-logs : Create log directories]
2025-10-10 22:05:14.819576 | logserver.rdoproject.org | changed
2025-10-10 22:05:14.822559 | 
2025-10-10 22:05:14.822623 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-10-10 22:05:15.028660 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.004308
2025-10-10 22:05:15.032674 | 
2025-10-10 22:05:15.032733 | TASK [upload-logs : Upload logs to log server]
2025-10-10 22:05:16.501677 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-10-10 22:05:16.504463 | 
2025-10-10 22:05:16.504523 | LOOP [upload-logs : Compress console log and json output]
2025-10-10 22:05:16.539556 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-10 22:05:16.546992 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-10 22:05:16.551612 | 
2025-10-10 22:05:16.551693 | LOOP [upload-logs : Upload compressed console log and json output]
2025-10-10 22:05:16.583574 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-10 22:05:16.583757 | 
2025-10-10 22:05:16.586795 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-10 22:05:16.598455 | 
2025-10-10 22:05:16.598539 | LOOP [upload-logs : Upload console log and json output]