2025-10-02 14:13:07.151388 | Job console starting...
2025-10-02 14:13:07.642657 | Updating repositories
2025-10-02 14:13:08.485603 | Preparing job workspace
2025-10-02 14:13:26.981518 | Running Ansible setup...
2025-10-02 14:13:37.656869 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-10-02 14:13:40.790423 | 
2025-10-02 14:13:40.790558 | PLAY [localhost]
2025-10-02 14:13:40.839990 | 
2025-10-02 14:13:40.840118 | TASK [Gathering Facts]
2025-10-02 14:13:43.917288 | localhost | ok
2025-10-02 14:13:43.967097 | 
2025-10-02 14:13:43.967246 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-10-02 14:13:45.772185 | localhost -> localhost | changed
2025-10-02 14:13:45.778020 | 
2025-10-02 14:13:45.778121 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-10-02 14:13:50.310380 | localhost -> localhost | changed
2025-10-02 14:13:50.319555 | 
2025-10-02 14:13:50.319636 | TASK [Setup log path fact]
2025-10-02 14:13:50.441664 | localhost | ok
2025-10-02 14:13:50.495664 | 
2025-10-02 14:13:50.495791 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-02 14:13:50.577233 | localhost | ok
2025-10-02 14:13:50.634872 | 
2025-10-02 14:13:50.638039 | TASK [emit-job-header : Print job information]
2025-10-02 14:13:50.735667 | # Job Information
2025-10-02 14:13:50.735832 | Ansible Version: 2.15.12
2025-10-02 14:13:50.735863 | Job: cifmw-molecule-edpm_build_images
2025-10-02 14:13:50.735888 | Pipeline: github-check
2025-10-02 14:13:50.735940 | Executor: ibm-bm4-ze.softwarefactory-project.io
2025-10-02 14:13:50.735973 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3362
2025-10-02 14:13:50.735997 | Log URL (when completed): https://logserver.rdoproject.org/a67/rdoproject.org/a678b8f9c931463884833124dbf2020b/
2025-10-02 14:13:50.736018 | Event ID: be9565c0-9f99-11f0-9e8d-1085adf9bc04
2025-10-02 14:13:50.742067 | 
2025-10-02 14:13:50.742147 | LOOP [emit-job-header : Print node information]
2025-10-02 14:13:51.077702 | localhost | ok:
2025-10-02 14:13:51.077924 | localhost | # Node Information
2025-10-02 14:13:51.077963 | localhost | Inventory Hostname: controller
2025-10-02 14:13:51.077990 | localhost | Hostname: np0005466359
2025-10-02 14:13:51.078012 | localhost | Username: zuul
2025-10-02 14:13:51.078035 | localhost | Distro: CentOS 9
2025-10-02 14:13:51.078055 | localhost | Provider: ibm-bm4-nodepool
2025-10-02 14:13:51.078074 | localhost | Region: regionOne
2025-10-02 14:13:51.078092 | localhost | Label: cloud-centos-9-stream-tripleo-ibm
2025-10-02 14:13:51.078111 | localhost | Product Name: OpenStack Compute
2025-10-02 14:13:51.078142 | localhost | Interface IP: 192.168.26.38
2025-10-02 14:13:51.178163 | 
2025-10-02 14:13:51.178277 | PLAY [all]
2025-10-02 14:13:51.193083 | 
2025-10-02 14:13:51.193163 | TASK [Gather network facts]
2025-10-02 14:13:51.665116 | controller | ok
2025-10-02 14:13:51.732478 | 
2025-10-02 14:13:51.732579 | TASK [include_role : start-zuul-console]
2025-10-02 14:13:51.808888 | controller | ok
2025-10-02 14:13:51.869604 | 
2025-10-02 14:13:51.869710 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-10-02 14:13:52.889629 | controller | ok
2025-10-02 14:13:52.912228 | 
2025-10-02 14:13:52.912360 | TASK [include_role : add-build-sshkey]
2025-10-02 14:13:52.964224 | controller | ok
2025-10-02 14:13:53.028771 | 
2025-10-02 14:13:53.028875 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-10-02 14:13:53.911219 | controller -> localhost | ok
2025-10-02 14:13:53.917431 | 
2025-10-02 14:13:53.917521 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-10-02 14:13:54.013612 | controller | ok
2025-10-02 14:13:54.074071 | controller | included: /var/lib/zuul/builds/a678b8f9c931463884833124dbf2020b/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-10-02 14:13:54.109210 | 
2025-10-02 14:13:54.109323 | TASK [add-build-sshkey : Create Temp SSH key]
2025-10-02 14:13:56.491024 | controller -> localhost | Generating public/private rsa key pair.
2025-10-02 14:13:56.491254 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/a678b8f9c931463884833124dbf2020b/work/a678b8f9c931463884833124dbf2020b_id_rsa.
2025-10-02 14:13:56.491290 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/a678b8f9c931463884833124dbf2020b/work/a678b8f9c931463884833124dbf2020b_id_rsa.pub.
2025-10-02 14:13:56.491315 | controller -> localhost | The key fingerprint is:
2025-10-02 14:13:56.491337 | controller -> localhost | SHA256:Bi5ZsI5u1o610ccKKIaHR5FFxUsQjBGBfvmu2NPPFcc zuul-build-sshkey
2025-10-02 14:13:56.491357 | controller -> localhost | The key's randomart image is:
2025-10-02 14:13:56.491378 | controller -> localhost | +---[RSA 3072]----+
2025-10-02 14:13:56.491397 | controller -> localhost | | .+B*=.          |
2025-10-02 14:13:56.491416 | controller -> localhost | |. .o.oo          |
2025-10-02 14:13:56.491436 | controller -> localhost | |. o o.o.         |
2025-10-02 14:13:56.491454 | controller -> localhost | | . * +..  .      |
2025-10-02 14:13:56.491472 | controller -> localhost | |  + = . S. E     |
2025-10-02 14:13:56.491491 | controller -> localhost | |.+ o + o  o      |
2025-10-02 14:13:56.491509 | controller -> localhost | |+.B B . o.       |
2025-10-02 14:13:56.491528 | controller -> localhost | |.*o= *.o.        |
2025-10-02 14:13:56.491546 | controller -> localhost | | ..o= oo         |
2025-10-02 14:13:56.491564 | controller -> localhost | +----[SHA256]-----+
2025-10-02 14:13:56.491613 | controller -> localhost | ok: Runtime: 0:00:00.384515
2025-10-02 14:13:56.498033 | 
2025-10-02 14:13:56.498103 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-10-02 14:13:56.620544 | controller | ok
2025-10-02 14:13:56.651852 | controller | included: /var/lib/zuul/builds/a678b8f9c931463884833124dbf2020b/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-10-02 14:13:56.689855 | 
2025-10-02 14:13:56.689976 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-10-02 14:13:56.812760 | controller | skipping: Conditional result was False
2025-10-02 14:13:56.819522 | 
2025-10-02 14:13:56.819629 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-10-02 14:13:57.914104 | controller | changed
2025-10-02 14:13:57.919032 | 
2025-10-02 14:13:57.919096 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-10-02 14:13:58.245086 | controller | ok
2025-10-02 14:13:58.258637 | 
2025-10-02 14:13:58.258715 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-10-02 14:13:59.259452 | controller | changed
2025-10-02 14:13:59.272926 | 
2025-10-02 14:13:59.273026 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-10-02 14:14:00.174033 | controller | changed
2025-10-02 14:14:00.186415 | 
2025-10-02 14:14:00.186514 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-10-02 14:14:00.295612 | controller | skipping: Conditional result was False
2025-10-02 14:14:00.302295 | 
2025-10-02 14:14:00.303207 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-10-02 14:14:01.714844 | controller -> localhost | changed
2025-10-02 14:14:01.726555 | 
2025-10-02 14:14:01.726656 | TASK [add-build-sshkey : Add back temp key]
2025-10-02 14:14:03.360394 | controller -> localhost | Identity added: /var/lib/zuul/builds/a678b8f9c931463884833124dbf2020b/work/a678b8f9c931463884833124dbf2020b_id_rsa (zuul-build-sshkey)
2025-10-02 14:14:03.360636 | controller -> localhost | ok: Runtime: 0:00:00.212057
2025-10-02 14:14:03.368279 | 
2025-10-02 14:14:03.368560 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-10-02 14:14:03.891554 | controller | ok
2025-10-02 14:14:03.902369 | 
2025-10-02 14:14:03.902457 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-10-02 14:14:04.006448 | controller | skipping: Conditional result was False
2025-10-02 14:14:04.025734 | 
2025-10-02 14:14:04.025841 | TASK [include_role : validate-host]
2025-10-02 14:14:04.109762 | controller | ok
2025-10-02 14:14:04.241606 | 
2025-10-02 14:14:04.241735 | TASK [validate-host : Define zuul_info_dir fact]
2025-10-02 14:14:04.307627 | controller | ok
2025-10-02 14:14:04.327499 | 
2025-10-02 14:14:04.327745 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-10-02 14:14:05.510902 | controller -> localhost | ok
2025-10-02 14:14:05.517213 | 
2025-10-02 14:14:05.517298 | TASK [validate-host : Collect information about the host]
2025-10-02 14:14:06.207192 | controller | ok
2025-10-02 14:14:06.243514 | 
2025-10-02 14:14:06.243640 | TASK [validate-host : Sanitize hostname]
2025-10-02 14:14:06.401804 | controller | ok
2025-10-02 14:14:06.407198 | 
2025-10-02 14:14:06.407280 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-10-02 14:14:08.686606 | controller -> localhost | changed
2025-10-02 14:14:08.692324 | 
2025-10-02 14:14:08.692413 | TASK [validate-host : Collect information about zuul worker]
2025-10-02 14:14:09.880607 | controller | ok
2025-10-02 14:14:09.885363 | 
2025-10-02 14:14:09.885437 | TASK [validate-host : Write out all zuul information for each host]
2025-10-02 14:14:11.643821 | controller -> localhost | changed
2025-10-02 14:14:11.658215 | 
2025-10-02 14:14:11.658305 | TASK [include_role : prepare-workspace-openshift]
2025-10-02 14:14:11.768662 | controller | skipping: Conditional result was False
2025-10-02 14:14:11.774737 | 
2025-10-02 14:14:11.774829 | TASK [include_role : remove-zuul-sshkey]
2025-10-02 14:14:11.854778 | controller | skipping: Conditional result was False
2025-10-02 14:14:11.861760 | 
2025-10-02 14:14:11.861867 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-10-02 14:14:12.205411 | controller | ok: "logs"
2025-10-02 14:14:12.205672 | controller | ok: All items complete
2025-10-02 14:14:12.205709 | 
2025-10-02 14:14:12.390067 | controller | ok: "artifacts"
2025-10-02 14:14:12.572342 | controller | ok: "docs"
2025-10-02 14:14:12.583569 | 
2025-10-02 14:14:12.583684 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-10-02 14:14:12.949626 | controller | changed: "logs"
2025-10-02 14:14:13.140496 | controller | changed: "artifacts"
2025-10-02 14:14:13.343370 | controller | changed: "docs"
2025-10-02 14:14:13.455113 | 
2025-10-02 14:14:13.455244 | PLAY RECAP
2025-10-02 14:14:13.455298 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-10-02 14:14:13.455329 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-02 14:14:13.455350 | 
2025-10-02 14:14:14.088947 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-10-02 14:14:14.089706 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-10-02 14:14:16.894437 | 
2025-10-02 14:14:16.894563 | PLAY [all]
2025-10-02 14:14:16.973760 | 
2025-10-02 14:14:16.973893 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-10-02 14:14:17.169294 | controller | ok
2025-10-02 14:14:17.177801 | 
2025-10-02 14:14:17.177939 | TASK [mirror-info-fork : Create /etc/ci]
2025-10-02 14:14:18.344229 | controller | changed
2025-10-02 14:14:18.355312 | 
2025-10-02 14:14:18.355390 | TASK [mirror-info-fork : Install ci_mirror script]
2025-10-02 14:14:20.138887 | controller | changed
2025-10-02 14:14:20.177399 | 
2025-10-02 14:14:20.177493 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-10-02 14:14:21.097161 | controller | changed:
2025-10-02 14:14:21.097331 | controller | {
2025-10-02 14:14:21.097365 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-10-02 14:14:21.097401 | controller | }
2025-10-02 14:14:21.320265 | controller | changed:
2025-10-02 14:14:21.320361 | controller | {
2025-10-02 14:14:21.320392 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-10-02 14:14:21.320415 | controller | }
2025-10-02 14:14:21.535013 | controller | changed:
2025-10-02 14:14:21.535099 | controller | {
2025-10-02 14:14:21.535138 | 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-02 14:14:21.535164 | controller | }
2025-10-02 14:14:21.775510 | controller | changed:
2025-10-02 14:14:21.775653 | controller | {
2025-10-02 14:14:21.775681 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-10-02 14:14:21.775702 | controller | }
2025-10-02 14:14:22.013166 | controller | changed:
2025-10-02 14:14:22.013280 | controller | {
2025-10-02 14:14:22.013314 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-10-02 14:14:22.013336 | controller | }
2025-10-02 14:14:22.244450 | controller | changed:
2025-10-02 14:14:22.244554 | controller | {
2025-10-02 14:14:22.244582 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-10-02 14:14:22.244605 | controller | }
2025-10-02 14:14:22.463904 | controller | changed:
2025-10-02 14:14:22.464021 | controller | {
2025-10-02 14:14:22.464050 | 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-02 14:14:22.464072 | controller | }
2025-10-02 14:14:22.802824 | controller | changed:
2025-10-02 14:14:22.802962 | controller | {
2025-10-02 14:14:22.802993 | 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-02 14:14:22.803015 | controller | }
2025-10-02 14:14:22.918776 | controller | changed:
2025-10-02 14:14:22.918869 | controller | {
2025-10-02 14:14:22.918898 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-10-02 14:14:22.918946 | controller | }
2025-10-02 14:14:23.149164 | controller | changed:
2025-10-02 14:14:23.149272 | controller | {
2025-10-02 14:14:23.149299 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-10-02 14:14:23.149320 | controller | }
2025-10-02 14:14:23.374204 | controller | changed:
2025-10-02 14:14:23.374293 | controller | {
2025-10-02 14:14:23.374320 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-10-02 14:14:23.374341 | controller | }
2025-10-02 14:14:23.601714 | controller | changed:
2025-10-02 14:14:23.601809 | controller | {
2025-10-02 14:14:23.601837 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-10-02 14:14:23.601859 | controller | }
2025-10-02 14:14:23.828242 | controller | changed:
2025-10-02 14:14:23.828453 | controller | {
2025-10-02 14:14:23.828490 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-10-02 14:14:23.828516 | controller | }
2025-10-02 14:14:24.058744 | controller | changed:
2025-10-02 14:14:24.058844 | controller | {
2025-10-02 14:14:24.058873 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-10-02 14:14:24.058895 | controller | }
2025-10-02 14:14:24.296516 | controller | changed:
2025-10-02 14:14:24.296629 | controller | {
2025-10-02 14:14:24.296657 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-10-02 14:14:24.296679 | controller | }
2025-10-02 14:14:24.531020 | controller | changed:
2025-10-02 14:14:24.531102 | controller | {
2025-10-02 14:14:24.531139 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-10-02 14:14:24.531162 | controller | }
2025-10-02 14:14:24.760357 | controller | changed:
2025-10-02 14:14:24.760452 | controller | {
2025-10-02 14:14:24.760481 | 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-02 14:14:24.760505 | controller | }
2025-10-02 14:14:24.990409 | controller | changed:
2025-10-02 14:14:24.990504 | controller | {
2025-10-02 14:14:24.990532 | 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-02 14:14:24.990563 | controller | }
2025-10-02 14:14:25.230063 | controller | changed:
2025-10-02 14:14:25.230153 | controller | {
2025-10-02 14:14:25.230183 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-10-02 14:14:25.230205 | controller | }
2025-10-02 14:14:25.491726 | controller | changed:
2025-10-02 14:14:25.491824 | controller | {
2025-10-02 14:14:25.491852 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-10-02 14:14:25.491874 | controller | }
2025-10-02 14:14:25.721230 | controller | changed:
2025-10-02 14:14:25.721324 | controller | {
2025-10-02 14:14:25.721351 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-10-02 14:14:25.721371 | controller | }
2025-10-02 14:14:25.947080 | controller | changed:
2025-10-02 14:14:25.947180 | controller | {
2025-10-02 14:14:25.947211 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-10-02 14:14:25.947233 | controller | }
2025-10-02 14:14:26.178875 | controller | changed:
2025-10-02 14:14:26.178977 | controller | {
2025-10-02 14:14:26.179006 | 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-02 14:14:26.179030 | controller | }
2025-10-02 14:14:26.399077 | controller | changed:
2025-10-02 14:14:26.399176 | controller | {
2025-10-02 14:14:26.399206 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-10-02 14:14:26.399228 | controller | }
2025-10-02 14:14:26.619202 | controller | changed:
2025-10-02 14:14:26.619294 | controller | {
2025-10-02 14:14:26.619322 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-10-02 14:14:26.619343 | controller | }
2025-10-02 14:14:26.850114 | controller | changed:
2025-10-02 14:14:26.850217 | controller | {
2025-10-02 14:14:26.850247 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-10-02 14:14:26.850270 | controller | }
2025-10-02 14:14:26.891868 | 
2025-10-02 14:14:26.894932 | TASK [Set timezone to UTC]
2025-10-02 14:14:27.807539 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-10-02 14:14:27.819185 | 
2025-10-02 14:14:27.819266 | TASK [Create nodepool directory]
2025-10-02 14:14:28.094592 | controller | changed
2025-10-02 14:14:28.114493 | 
2025-10-02 14:14:28.114576 | TASK [Create nodepool sub_nodes file]
2025-10-02 14:14:28.964380 | controller | changed
2025-10-02 14:14:28.974203 | 
2025-10-02 14:14:28.974293 | TASK [Create nodepool sub_nodes_private file]
2025-10-02 14:14:29.784327 | controller | changed
2025-10-02 14:14:29.794859 | 
2025-10-02 14:14:29.794974 | LOOP [Populate nodepool sub_nodes file]
2025-10-02 14:14:29.895146 | 
2025-10-02 14:14:29.895304 | LOOP [Populate nodepool sub_nodes_private file]
2025-10-02 14:14:30.000349 | 
2025-10-02 14:14:30.000510 | TASK [Create nodepool primary file]
2025-10-02 14:14:30.095060 | controller | skipping: Conditional result was False
2025-10-02 14:14:30.101147 | 
2025-10-02 14:14:30.101249 | TASK [Create nodepool node_private for this node]
2025-10-02 14:14:30.936394 | controller | changed
2025-10-02 14:14:30.944246 | 
2025-10-02 14:14:30.944331 | LOOP [Copy ssh keys to nodepool directory]
2025-10-02 14:14:31.517149 | controller | ok: Item: id_rsa Runtime: 0:00:00.005495
2025-10-02 14:14:31.517341 | 
2025-10-02 14:14:31.701387 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.004425
2025-10-02 14:14:31.727634 | 
2025-10-02 14:14:31.727741 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-10-02 14:14:32.469723 | controller | changed
2025-10-02 14:14:32.492739 | 
2025-10-02 14:14:32.492823 | TASK [Validate sudoers config after edits]
2025-10-02 14:14:32.866065 | controller | /etc/sudoers: parsed OK
2025-10-02 14:14:32.866158 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-10-02 14:14:32.866172 | controller | /etc/sudoers.d/zuul: parsed OK
2025-10-02 14:14:32.866179 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-10-02 14:14:33.042448 | controller | ok: Runtime: 0:00:00.005022
2025-10-02 14:14:33.049381 | 
2025-10-02 14:14:33.049447 | TASK [Show the environment passed in to job shell scripts]
2025-10-02 14:14:33.337600 | controller | SHELL=/bin/bash
2025-10-02 14:14:33.337652 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-10-02 14:14:33.337662 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-10-02 14:14:33.337669 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/62/3362/41f2edaa0ac45cd85919f70775e84cf472326a6e
2025-10-02 14:14:33.337675 | controller | PWD=/home/zuul
2025-10-02 14:14:33.337681 | controller | ZUUL_PIPELINE=github-check
2025-10-02 14:14:33.337686 | controller | LOGNAME=zuul
2025-10-02 14:14:33.337734 | controller | XDG_SESSION_TYPE=tty
2025-10-02 14:14:33.337742 | controller | _=/usr/bin/env
2025-10-02 14:14:33.337748 | controller | MOTD_SHOWN=pam
2025-10-02 14:14:33.337753 | controller | HOME=/home/zuul
2025-10-02 14:14:33.337759 | controller | LANG=en_US.UTF-8
2025-10-02 14:14:33.337764 | controller | SSH_CONNECTION=192.168.26.12 35988 192.168.26.38 22
2025-10-02 14:14:33.337770 | 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-02 14:14:33.337779 | controller | ZUUL_CHANGE_IDS=3362,41f2edaa0ac45cd85919f70775e84cf472326a6e
2025-10-02 14:14:33.337785 | controller | WORKSPACE=/home/zuul/workspace
2025-10-02 14:14:33.337790 | controller | XDG_SESSION_CLASS=user
2025-10-02 14:14:33.337796 | controller | SELINUX_ROLE_REQUESTED=
2025-10-02 14:14:33.337801 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-10-02 14:14:33.337806 | controller | USER=zuul
2025-10-02 14:14:33.337812 | controller | ZUUL_VOTING=True
2025-10-02 14:14:33.337817 | controller | BUILD_TIMEOUT=1800000
2025-10-02 14:14:33.337822 | controller | SELINUX_USE_CURRENT_RANGE=
2025-10-02 14:14:33.337827 | controller | SHLVL=1
2025-10-02 14:14:33.337833 | controller | ZUUL_PATCHSET=41f2edaa0ac45cd85919f70775e84cf472326a6e
2025-10-02 14:14:33.337838 | controller | XDG_SESSION_ID=1
2025-10-02 14:14:33.337844 | controller | ZUUL_BRANCH=main
2025-10-02 14:14:33.337849 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-10-02 14:14:33.337854 | controller | SSH_CLIENT=192.168.26.12 35988 22
2025-10-02 14:14:33.337860 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-10-02 14:14:33.337865 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-10-02 14:14:33.337871 | controller | which_declare=declare -f
2025-10-02 14:14:33.337877 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2025-10-02 14:14:33.337882 | controller | SELINUX_LEVEL_REQUESTED=
2025-10-02 14:14:33.337888 | controller | ZUUL_CHANGE=3362
2025-10-02 14:14:33.337893 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-10-02 14:14:33.337898 | controller | ZUUL_UUID=a678b8f9c931463884833124dbf2020b
2025-10-02 14:14:33.337905 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-10-02 14:14:33.337911 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-10-02 14:14:33.337917 | controller | }
2025-10-02 14:14:33.588198 | controller | ok: Runtime: 0:00:00.005452
2025-10-02 14:14:33.594465 | 
2025-10-02 14:14:33.594554 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-10-02 14:14:33.649801 | controller | skipping: Conditional result was False
2025-10-02 14:14:33.656158 | 
2025-10-02 14:14:33.656237 | TASK [Symlink /home/zuul-worker/workspace]
2025-10-02 14:14:34.269060 | controller | skipping: Conditional result was False
2025-10-02 14:14:34.275435 | 
2025-10-02 14:14:34.275532 | TASK [Ensure legacy workspace directory]
2025-10-02 14:14:34.502286 | controller | changed
2025-10-02 14:14:34.540938 | 
2025-10-02 14:14:34.541102 | PLAY RECAP
2025-10-02 14:14:34.541171 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-10-02 14:14:34.541199 | 
2025-10-02 14:14:34.809836 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-10-02 14:14:34.810594 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-10-02 14:14:35.945657 | 
2025-10-02 14:14:35.945791 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-10-02 14:14:36.037072 | 
2025-10-02 14:14:36.037643 | TASK [Create zuul-output directory]
2025-10-02 14:14:37.006387 | controller | changed
2025-10-02 14:14:37.016256 | 
2025-10-02 14:14:37.016351 | TASK [Slurp Zuul inventory test]
2025-10-02 14:14:38.092219 | controller -> localhost | ok
2025-10-02 14:14:38.099740 | 
2025-10-02 14:14:38.099809 | TASK [Save zuul inventory]
2025-10-02 14:14:39.200802 | controller | changed
2025-10-02 14:14:39.208463 | 
2025-10-02 14:14:39.208711 | TASK [Save zuul vars without the change_message]
2025-10-02 14:14:39.913073 | controller | changed
2025-10-02 14:14:39.982964 | 
2025-10-02 14:14:39.983081 | PLAY RECAP
2025-10-02 14:14:39.983148 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-02 14:14:39.983174 | 
2025-10-02 14:14:40.162537 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-10-02 14:14:40.163425 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-10-02 14:14:41.023224 | 
2025-10-02 14:14:41.023360 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2025-10-02 14:14:41.044923 | 
2025-10-02 14:14:41.045068 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2025-10-02 14:14:41.076188 | controller | ok
2025-10-02 14:14:41.096741 | 
2025-10-02 14:14:41.096856 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-10-02 14:14:41.143298 | controller | skipping: Conditional result was False
2025-10-02 14:14:41.150334 | 
2025-10-02 14:14:41.150423 | TASK [mirror-info-fork : Create /etc/ci]
2025-10-02 14:14:41.635546 | controller | ok
2025-10-02 14:14:41.645875 | 
2025-10-02 14:14:41.645971 | TASK [mirror-info-fork : Install ci_mirror script]
2025-10-02 14:14:42.463014 | controller | ok
2025-10-02 14:14:42.490059 | 
2025-10-02 14:14:42.490179 | TASK [Prepare workspace]
2025-10-02 14:14:42.526982 | controller | ok
2025-10-02 14:14:42.553718 | 
2025-10-02 14:14:42.553847 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-10-02 14:14:42.923398 | controller | ok
2025-10-02 14:14:42.930998 | 
2025-10-02 14:14:42.933955 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-10-02 14:14:45.500316 | controller | Output suppressed because no_log was given
2025-10-02 14:14:45.509924 | 
2025-10-02 14:14:45.509996 | LOOP [Create zuul-output directory]
2025-10-02 14:14:45.701688 | controller | changed: "/home/zuul/ci-framework-data/logs"
2025-10-02 14:14:45.863712 | controller | ok: "/home/zuul/zuul-output/logs"
2025-10-02 14:14:45.882102 | 
2025-10-02 14:14:45.882215 | TASK [Install required packages]
2025-10-02 14:15:35.606760 | controller | changed
2025-10-02 14:15:35.612118 | 
2025-10-02 14:15:35.612217 | TASK [Install venv]
2025-10-02 14:16:37.557897 | controller | changed
2025-10-02 14:16:37.584804 | 
2025-10-02 14:16:37.584879 | PLAY RECAP
2025-10-02 14:16:37.584953 | controller | ok: 7 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-10-02 14:16:37.584983 | 
2025-10-02 14:16:37.680559 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-10-02 14:16:37.681444 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-10-02 14:16:38.305769 | 
2025-10-02 14:16:38.305869 | PLAY [Run ci/playbooks/molecule-test.yml]
2025-10-02 14:16:38.327181 | 
2025-10-02 14:16:38.327255 | TASK [Gather required facts]
2025-10-02 14:16:38.827165 | controller | ok
2025-10-02 14:16:38.832684 | 
2025-10-02 14:16:38.832786 | TASK [Load environment var if instructed to]
2025-10-02 14:16:38.857788 | controller | skipping: Conditional result was False
2025-10-02 14:16:38.863468 | 
2025-10-02 14:16:38.863807 | TASK [Run molecule]
2025-10-02 14:16:39.797733 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2025-10-02 14:16:39.866056 | controller | INFO     Performing prerun with role_name_check=0...
2025-10-02 14:16:59.237412 | controller | WARNING  Another version of '@NAMESPACE@.@NAME@' 3.1.4 was found installed in /home/zuul/.cache/ansible-compat/63f49b/collections/ansible_collections, only the first one will be used, 3.1.4 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-02 14:16:59.237799 | controller | WARNING  Another version of 'ansible.netcommon' 7.1.0 was found installed in /home/zuul/.cache/ansible-compat/63f49b/collections/ansible_collections, only the first one will be used, 7.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-02 14:16:59.238284 | controller | WARNING  Another version of 'ansible.posix' 1.6.2 was found installed in /home/zuul/.cache/ansible-compat/63f49b/collections/ansible_collections, only the first one will be used, 1.6.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-02 14:16:59.238728 | controller | WARNING  Another version of 'ansible.utils' 5.1.2 was found installed in /home/zuul/.cache/ansible-compat/63f49b/collections/ansible_collections, only the first one will be used, 5.1.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-02 14:16:59.239243 | controller | WARNING  Another version of 'cifmw.general' 1.0.0+41f2edaa was found installed in /home/zuul/.cache/ansible-compat/63f49b/collections/ansible_collections, only the first one will be used, 1.0.0+41f2edaa (/home/zuul/.ansible/collections/ansible_collections).
2025-10-02 14:16:59.239693 | controller | WARNING  Another version of 'cisco.ios' 9.0.3 was found installed in /home/zuul/.cache/ansible-compat/63f49b/collections/ansible_collections, only the first one will be used, 9.0.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-02 14:16:59.240158 | controller | WARNING  Another version of 'community.crypto' 2.22.3 was found installed in /home/zuul/.cache/ansible-compat/63f49b/collections/ansible_collections, only the first one will be used, 2.22.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-02 14:16:59.240603 | controller | WARNING  Another version of 'community.general' 10.0.1 was found installed in /home/zuul/.cache/ansible-compat/63f49b/collections/ansible_collections, only the first one will be used, 10.0.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-02 14:16:59.241044 | controller | WARNING  Another version of 'community.libvirt' 1.3.0 was found installed in /home/zuul/.cache/ansible-compat/63f49b/collections/ansible_collections, only the first one will be used, 1.3.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-02 14:16:59.241503 | controller | WARNING  Another version of 'community.okd' 4.0.0 was found installed in /home/zuul/.cache/ansible-compat/63f49b/collections/ansible_collections, only the first one will be used, 4.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-02 14:16:59.241940 | controller | WARNING  Another version of 'containers.podman' 1.16.2 was found installed in /home/zuul/.cache/ansible-compat/63f49b/collections/ansible_collections, only the first one will be used, 1.16.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-02 14:16:59.242422 | controller | WARNING  Another version of 'junipernetworks.junos' 9.1.0 was found installed in /home/zuul/.cache/ansible-compat/63f49b/collections/ansible_collections, only the first one will be used, 9.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-02 14:16:59.242860 | controller | WARNING  Another version of 'kubernetes.core' 5.0.0 was found installed in /home/zuul/.cache/ansible-compat/63f49b/collections/ansible_collections, only the first one will be used, 5.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-02 14:16:59.243357 | controller | WARNING  Another version of 'mellanox.onyx' 1.0.0 was found installed in /home/zuul/.cache/ansible-compat/63f49b/collections/ansible_collections, only the first one will be used, 1.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-02 14:16:59.243798 | controller | WARNING  Another version of 'openstack.config_template' 2.1.1 was found installed in /home/zuul/.cache/ansible-compat/63f49b/collections/ansible_collections, only the first one will be used, 2.1.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-02 14:16:59.253710 | controller | INFO     Running default > prepare
2025-10-02 14:16:59.920997 | controller |
2025-10-02 14:16:59.921133 | controller | PLAY [Prepare] *****************************************************************
2025-10-02 14:16:59.921231 | controller |
2025-10-02 14:16:59.921349 | controller | TASK [Gathering Facts] *********************************************************
2025-10-02 14:16:59.921454 | controller | Thursday 02 October 2025  14:16:59 +0000 (0:00:00.012)       0:00:00.012 ******
2025-10-02 14:17:00.735230 | controller | ok: [instance]
2025-10-02 14:17:00.735329 | controller |
2025-10-02 14:17:00.735475 | controller | TASK [test_deps : Gather facts if they don't exist] ****************************
2025-10-02 14:17:00.735609 | controller | Thursday 02 October 2025  14:17:00 +0000 (0:00:00.814)       0:00:00.827 ******
2025-10-02 14:17:00.753141 | controller | skipping: [instance]
2025-10-02 14:17:00.753217 | controller |
2025-10-02 14:17:00.753324 | controller | TASK [test_deps : Gather variables for each operating system] ******************
2025-10-02 14:17:00.753429 | controller | Thursday 02 October 2025  14:17:00 +0000 (0:00:00.018)       0:00:00.845 ******
2025-10-02 14:17:00.795305 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/test_deps/vars/centos-9.yml)
2025-10-02 14:17:00.795396 | controller |
2025-10-02 14:17:00.795521 | controller | TASK [test_deps : Detect mirrors file] *****************************************
2025-10-02 14:17:00.795654 | controller | Thursday 02 October 2025  14:17:00 +0000 (0:00:00.042)       0:00:00.887 ******
2025-10-02 14:17:01.080459 | controller | ok: [instance]
2025-10-02 14:17:01.080535 | controller |
2025-10-02 14:17:01.080647 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] ***********
2025-10-02 14:17:01.080762 | controller | Thursday 02 October 2025  14:17:01 +0000 (0:00:00.285)       0:00:01.172 ******
2025-10-02 14:17:01.393066 | controller | ok: [instance]
2025-10-02 14:17:01.393164 | controller |
2025-10-02 14:17:01.393286 | controller | TASK [test_deps : Disable ubi 9 repos] *****************************************
2025-10-02 14:17:01.393390 | controller | Thursday 02 October 2025  14:17:01 +0000 (0:00:00.312)       0:00:01.484 ******
2025-10-02 14:17:02.085491 | controller | ok: [instance] => (item=ubi-9-appstream)
2025-10-02 14:17:02.085572 | controller | ok: [instance] => (item=ubi-9-baseos)
2025-10-02 14:17:02.085689 | controller | ok: [instance] => (item=ubi-9-codeready-builder)
2025-10-02 14:17:02.085795 | controller |
2025-10-02 14:17:02.085907 | controller | TASK [Deploy repo-setup] *******************************************************
2025-10-02 14:17:02.086007 | controller | Thursday 02 October 2025  14:17:02 +0000 (0:00:00.692)       0:00:02.177 ******
2025-10-02 14:17:02.143632 | controller |
2025-10-02 14:17:02.143687 | controller | TASK [repo_setup : Ensure directories are present] *****************************
2025-10-02 14:17:02.143849 | controller | Thursday 02 October 2025  14:17:02 +0000 (0:00:00.058)       0:00:02.235 ******
2025-10-02 14:17:02.697689 | controller | changed: [instance] => (item=tmp)
2025-10-02 14:17:02.697745 | controller | changed: [instance] => (item=artifacts/repositories)
2025-10-02 14:17:02.697861 | controller | changed: [instance] => (item=venv/repo_setup)
2025-10-02 14:17:02.697955 | controller |
2025-10-02 14:17:02.698067 | controller | TASK [repo_setup : Make sure git-core package is installed] ********************
2025-10-02 14:17:02.698191 | controller | Thursday 02 October 2025  14:17:02 +0000 (0:00:00.553)       0:00:02.789 ******
2025-10-02 14:17:03.479821 | controller | ok: [instance]
2025-10-02 14:17:03.479889 | controller |
2025-10-02 14:17:03.480153 | controller | TASK [repo_setup : Get repo-setup repository] **********************************
2025-10-02 14:17:04.743208 | controller | Thursday 02 October 2025  14:17:03 +0000 (0:00:00.782)       0:00:03.571 ******
2025-10-02 14:17:04.743248 | controller | changed: [instance]
2025-10-02 14:17:12.786574 | controller |
2025-10-02 14:17:12.786616 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************
2025-10-02 14:17:12.786630 | controller | Thursday 02 October 2025  14:17:04 +0000 (0:00:01.262)       0:00:04.834 ******
2025-10-02 14:17:12.786641 | controller | changed: [instance]
2025-10-02 14:17:13.501698 | controller |
2025-10-02 14:17:13.501735 | controller | TASK [repo_setup : Install repo-setup package] *********************************
2025-10-02 14:17:13.501743 | controller | Thursday 02 October 2025  14:17:12 +0000 (0:00:08.043)       0:00:12.877 ******
2025-10-02 14:17:13.501755 | controller | changed: [instance]
2025-10-02 14:17:13.523153 | controller |
2025-10-02 14:17:13.523177 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
2025-10-02 14:17:13.523185 | controller | Thursday 02 October 2025  14:17:13 +0000 (0:00:00.715)       0:00:13.593 ******
2025-10-02 14:17:13.523196 | controller | skipping: [instance]
2025-10-02 14:17:14.355255 | controller |
2025-10-02 14:17:14.355298 | controller | TASK [repo_setup : Run repo-setup] *********************************************
2025-10-02 14:17:14.355306 | controller | Thursday 02 October 2025  14:17:13 +0000 (0:00:00.021)       0:00:13.614 ******
2025-10-02 14:17:14.355318 | controller | changed: [instance]
2025-10-02 14:17:14.391244 | controller |
2025-10-02 14:17:14.391276 | controller | TASK [repo_setup : Get component repo] *****************************************
2025-10-02 14:17:14.391285 | controller | Thursday 02 October 2025  14:17:14 +0000 (0:00:00.831)       0:00:14.446 ******
2025-10-02 14:17:14.391297 | controller | skipping: [instance]
2025-10-02 14:17:14.427113 | controller |
2025-10-02 14:17:14.427143 | controller | TASK [repo_setup : Rename component repo] **************************************
2025-10-02 14:17:14.427151 | controller | Thursday 02 October 2025  14:17:14 +0000 (0:00:00.036)       0:00:14.482 ******
2025-10-02 14:17:14.427160 | controller | skipping: [instance]
2025-10-02 14:17:14.463784 | controller |
2025-10-02 14:17:14.463805 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
2025-10-02 14:17:14.463813 | controller | Thursday 02 October 2025  14:17:14 +0000 (0:00:00.035)       0:00:14.518 ******
2025-10-02 14:17:14.463823 | controller | skipping: [instance]
2025-10-02 14:17:15.045210 | controller |
2025-10-02 14:17:15.045242 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************
2025-10-02 14:17:15.045251 | controller | Thursday 02 October 2025  14:17:14 +0000 (0:00:00.037)       0:00:14.555 ******
2025-10-02 14:17:15.045262 | controller | changed: [instance]
2025-10-02 14:17:15.045286 | controller |
2025-10-02 14:17:15.045525 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
2025-10-02 14:17:15.476134 | controller | Thursday 02 October 2025  14:17:15 +0000 (0:00:00.581)       0:00:15.137 ******
2025-10-02 14:17:15.476169 | controller | changed: [instance]
2025-10-02 14:17:15.503159 | controller |
2025-10-02 14:17:15.503184 | controller | TASK [repo_setup : Dump current-podified hash] *********************************
2025-10-02 14:17:15.503192 | controller | Thursday 02 October 2025  14:17:15 +0000 (0:00:00.430)       0:00:15.567 ******
2025-10-02 14:17:15.503202 | controller | skipping: [instance]
2025-10-02 14:17:15.503426 | controller |
2025-10-02 14:17:15.503447 | controller | TASK [repo_setup : Slurp current podified hash] ********************************
2025-10-02 14:17:15.561640 | controller | Thursday 02 October 2025  14:17:15 +0000 (0:00:00.027)       0:00:15.595 ******
2025-10-02 14:17:15.561678 | controller | skipping: [instance]
2025-10-02 14:17:15.590327 | controller |
2025-10-02 14:17:15.590357 | controller | TASK [repo_setup : Update the value of full_hash] ******************************
2025-10-02 14:17:15.590365 | controller | Thursday 02 October 2025  14:17:15 +0000 (0:00:00.058)       0:00:15.653 ******
2025-10-02 14:17:15.590376 | controller | skipping: [instance]
2025-10-02 14:17:15.629843 | controller |
2025-10-02 14:17:15.629869 | controller | TASK [repo_setup : Export hashes facts for further use] ************************
2025-10-02 14:17:15.629877 | controller | Thursday 02 October 2025  14:17:15 +0000 (0:00:00.028)       0:00:15.681 ******
2025-10-02 14:17:15.629887 | controller | ok: [instance]
2025-10-02 14:17:15.657181 | controller |
2025-10-02 14:17:15.657221 | controller | TASK [repo_setup : Create download directory] **********************************
2025-10-02 14:17:15.657230 | controller | Thursday 02 October 2025  14:17:15 +0000 (0:00:00.039)       0:00:15.721 ******
2025-10-02 14:17:15.657240 | controller | skipping: [instance]
2025-10-02 14:17:15.657258 | controller |
2025-10-02 14:17:15.657266 | controller | TASK [repo_setup : Print the URL to request] ***********************************
2025-10-02 14:17:15.657358 | controller | Thursday 02 October 2025  14:17:15 +0000 (0:00:00.027)       0:00:15.749 ******
2025-10-02 14:17:15.684717 | controller | skipping: [instance]
2025-10-02 14:17:15.711158 | controller |
2025-10-02 14:17:15.711183 | controller | TASK [Download the RPM] ********************************************************
2025-10-02 14:17:15.711191 | controller | Thursday 02 October 2025  14:17:15 +0000 (0:00:00.027)       0:00:15.776 ******
2025-10-02 14:17:15.711201 | controller | skipping: [instance]
2025-10-02 14:17:15.711225 | controller |
2025-10-02 14:17:15.711232 | controller | TASK [repo_setup : Install RHOS Release tool] **********************************
2025-10-02 14:17:15.711240 | controller | Thursday 02 October 2025  14:17:15 +0000 (0:00:00.026)       0:00:15.802 ******
2025-10-02 14:17:15.737512 | controller | skipping: [instance]
2025-10-02 14:17:15.763778 | controller |
2025-10-02 14:17:15.763804 | controller | TASK [repo_setup : Get rhos-release tool version] ******************************
2025-10-02 14:17:15.763812 | controller | Thursday 02 October 2025  14:17:15 +0000 (0:00:00.026)       0:00:15.829 ******
2025-10-02 14:17:15.763822 | controller | skipping: [instance]
2025-10-02 14:17:15.791432 | controller |
2025-10-02 14:17:15.791456 | controller | TASK [repo_setup : Print rhos-release tool version] ****************************
2025-10-02 14:17:15.791464 | controller | Thursday 02 October 2025  14:17:15 +0000 (0:00:00.026)       0:00:15.855 ******
2025-10-02 14:17:15.791473 | controller | skipping: [instance]
2025-10-02 14:17:15.819965 | controller |
2025-10-02 14:17:15.819992 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
2025-10-02 14:17:15.820001 | controller | Thursday 02 October 2025  14:17:15 +0000 (0:00:00.027)       0:00:15.883 ******
2025-10-02 14:17:15.820013 | controller | skipping: [instance]
2025-10-02 14:17:16.006177 | controller |
2025-10-02 14:17:16.006205 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
2025-10-02 14:17:16.006214 | controller | Thursday 02 October 2025  14:17:15 +0000 (0:00:00.028)       0:00:15.911 ******
2025-10-02 14:17:16.006224 | controller | ok: [instance]
2025-10-02 14:17:16.006316 | controller |
2025-10-02 14:17:16.006346 | controller | TASK [repo_setup : Use RDO proxy mirrors] **************************************
2025-10-02 14:17:16.006358 | controller | Thursday 02 October 2025  14:17:16 +0000 (0:00:00.186)       0:00:16.097 ******
2025-10-02 14:17:16.228707 | controller | changed: [instance]
2025-10-02 14:17:16.450210 | controller |
2025-10-02 14:17:16.450244 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
2025-10-02 14:17:16.450254 | controller | Thursday 02 October 2025  14:17:16 +0000 (0:00:00.222)       0:00:16.320 ******
2025-10-02 14:17:16.450266 | controller | changed: [instance]
2025-10-02 14:17:16.450295 | controller |
2025-10-02 14:17:16.450305 | controller | TASK [repo_setup : Check for gating.repo file on content provider] *************
2025-10-02 14:17:16.450448 | controller | Thursday 02 October 2025  14:17:16 +0000 (0:00:00.221)       0:00:16.541 ******
2025-10-02 14:17:16.472205 | controller | skipping: [instance]
2025-10-02 14:17:16.472237 | controller |
2025-10-02 14:17:16.472247 | controller | TASK [repo_setup : Populate gating repo from content provider ip] **************
2025-10-02 14:17:16.472257 | controller | Thursday 02 October 2025  14:17:16 +0000 (0:00:00.022)       0:00:16.564 ******
2025-10-02 14:17:16.493602 | controller | skipping: [instance]
2025-10-02 14:17:16.515774 | controller |
2025-10-02 14:17:16.515796 | controller | TASK [repo_setup : Check for DLRN repo at the destination] *********************
2025-10-02 14:17:16.515804 | controller | Thursday 02 October 2025  14:17:16 +0000 (0:00:00.021)       0:00:16.585 ******
2025-10-02 14:17:16.515813 | controller | skipping: [instance]
2025-10-02 14:17:16.538073 | controller |
2025-10-02 14:17:16.538118 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
2025-10-02 14:17:16.538131 | controller | Thursday 02 October 2025  14:17:16 +0000 (0:00:00.022)       0:00:16.607 ******
2025-10-02 14:17:16.538142 | controller | skipping: [instance]
2025-10-02 14:17:16.560536 | controller |
2025-10-02 14:17:16.560558 | controller | TASK [repo_setup : Check for DLRN component repo] ******************************
2025-10-02 14:17:16.560573 | controller | Thursday 02 October 2025  14:17:16 +0000 (0:00:00.022)       0:00:16.629 ******
2025-10-02 14:17:16.560584 | controller | skipping: [instance]
2025-10-02 14:17:16.582032 | controller |
2025-10-02 14:17:16.582065 | controller | TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] ***
2025-10-02 14:17:16.582082 | controller | Thursday 02 October 2025  14:17:16 +0000 (0:00:00.022)       0:00:16.652 ******
2025-10-02 14:17:16.582091 | controller | skipping: [instance]
2025-10-02 14:17:16.598543 | controller |
2025-10-02 14:17:16.598563 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
2025-10-02 14:17:16.598572 | controller | Thursday 02 October 2025  14:17:16 +0000 (0:00:00.021)       0:00:16.673 ******
2025-10-02 14:17:16.598582 | controller | skipping: [instance]
2025-10-02 14:17:16.626985 | controller |
2025-10-02 14:17:16.627007 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
2025-10-02 14:17:16.627015 | controller | Thursday 02 October 2025  14:17:16 +0000 (0:00:00.016)       0:00:16.690 ******
2025-10-02 14:17:16.627026 | controller | skipping: [instance]
2025-10-02 14:17:16.627242 | controller |
2025-10-02 14:17:16.627422 | controller | TASK [repo_setup : Cleanup existing metadata] **********************************
2025-10-02 14:17:16.627593 | controller | Thursday 02 October 2025  14:17:16 +0000 (0:00:00.028)       0:00:16.719 ******
2025-10-02 14:17:16.643518 | controller | skipping: [instance]
2025-10-02 14:17:16.643694 | controller |
2025-10-02 14:17:16.643864 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
2025-10-02 14:17:16.644016 | controller | Thursday 02 October 2025  14:17:16 +0000 (0:00:00.016)       0:00:16.735 ******
2025-10-02 14:17:16.663926 | controller | skipping: [instance]
2025-10-02 14:17:16.664092 | controller |
2025-10-02 14:17:16.664266 | controller | TASK [test_deps : Look for redhat-release rpm] *********************************
2025-10-02 14:17:16.664408 | controller | Thursday 02 October 2025  14:17:16 +0000 (0:00:00.020)       0:00:16.756 ******
2025-10-02 14:17:16.693729 | controller | skipping: [instance]
2025-10-02 14:17:16.693908 | controller |
2025-10-02 14:17:16.694086 | controller | TASK [test_deps : Remove redhat-release] ***************************************
2025-10-02 14:17:16.694256 | controller | Thursday 02 October 2025  14:17:16 +0000 (0:00:00.029)       0:00:16.785 ******
2025-10-02 14:17:16.726226 | controller | skipping: [instance]
2025-10-02 14:17:16.726393 | controller |
2025-10-02 14:17:16.726542 | controller | TASK [test_deps : Install centos-stream-release] *******************************
2025-10-02 14:17:16.726682 | controller | Thursday 02 October 2025  14:17:16 +0000 (0:00:00.032)       0:00:16.818 ******
2025-10-02 14:17:16.756082 | controller | skipping: [instance]
2025-10-02 14:17:16.756277 | controller |
2025-10-02 14:17:16.756429 | controller | TASK [test_deps : Install additional packages] *********************************
2025-10-02 14:17:16.756569 | controller | Thursday 02 October 2025  14:17:16 +0000 (0:00:00.029)       0:00:16.848 ******
2025-10-02 14:17:16.786257 | controller | skipping: [instance]
2025-10-02 14:18:11.251970 | controller |
2025-10-02 14:18:11.252003 | controller | TASK [test_deps : Install selinux python libs] *********************************
2025-10-02 14:18:11.252011 | controller | Thursday 02 October 2025  14:17:16 +0000 (0:00:00.029)       0:00:16.877 ******
2025-10-02 14:18:11.252021 | controller | ok: [instance]
2025-10-02 14:18:12.413368 | controller |
2025-10-02 14:18:12.413399 | controller | TASK [test_deps : Install python yaml libs] ************************************
2025-10-02 14:18:12.413406 | controller | Thursday 02 October 2025  14:18:11 +0000 (0:00:54.465)       0:01:11.343 ******
2025-10-02 14:18:12.413416 | controller | ok: [instance]
2025-10-02 14:18:12.456253 | controller |
2025-10-02 14:18:12.456295 | controller | TASK [test_deps : Install extra packages] **************************************
2025-10-02 14:18:12.456305 | controller | Thursday 02 October 2025  14:18:12 +0000 (0:00:01.161)       0:01:12.504 ******
2025-10-02 14:18:12.456318 | controller | skipping: [instance]
2025-10-02 14:18:12.456351 | controller |
2025-10-02 14:18:12.456359 | controller | PLAY RECAP *********************************************************************
2025-10-02 14:18:12.456369 | controller | instance                   : ok=19   changed=9    unreachable=0    failed=0    skipped=30   rescued=0    ignored=0
2025-10-02 14:18:12.458491 | controller |
2025-10-02 14:18:12.458521 | controller | Thursday 02 October 2025  14:18:12 +0000 (0:00:00.042)       0:01:12.547 ******
2025-10-02 14:18:12.514367 | controller | ===============================================================================
2025-10-02 14:18:12.514396 | controller | test_deps : Install selinux python libs -------------------------------- 54.47s
2025-10-02 14:18:12.514409 | controller | repo_setup : Initialize python venv and install requirements ------------ 8.04s
2025-10-02 14:18:12.514415 | controller | repo_setup : Get repo-setup repository ---------------------------------- 1.26s
2025-10-02 14:18:12.514421 | controller | test_deps : Install python yaml libs ------------------------------------ 1.16s
2025-10-02 14:18:12.514430 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.83s
2025-10-02 14:18:12.514436 | controller | Gathering Facts --------------------------------------------------------- 0.81s
2025-10-02 14:18:12.514441 | controller | repo_setup : Make sure git-core package is installed -------------------- 0.78s
2025-10-02 14:18:12.514446 | controller | repo_setup : Install repo-setup package --------------------------------- 0.72s
2025-10-02 14:18:12.514452 | controller | test_deps : Disable ubi 9 repos ----------------------------------------- 0.69s
2025-10-02 14:18:12.514457 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.58s
2025-10-02 14:18:12.514462 | controller | repo_setup : Ensure directories are present ----------------------------- 0.55s
2025-10-02 14:18:12.514467 | controller | repo_setup : Dump full hash in delorean.repo.md5 file ------------------- 0.43s
2025-10-02 14:18:12.514472 | controller | test_deps : Disable ubi host subscription-manager integration ----------- 0.31s
2025-10-02 14:18:12.514478 | controller | test_deps : Detect mirrors file ----------------------------------------- 0.29s
2025-10-02 14:18:12.514483 | controller | repo_setup : Use RDO proxy mirrors -------------------------------------- 0.22s
2025-10-02 14:18:12.514488 | controller | repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist) --- 0.22s
2025-10-02 14:18:12.514493 | controller | repo_setup : Check for /etc/ci/mirror_info.sh --------------------------- 0.19s
2025-10-02 14:18:12.514499 | controller | Deploy repo-setup ------------------------------------------------------- 0.06s
2025-10-02 14:18:12.514504 | controller | repo_setup : Slurp current podified hash -------------------------------- 0.06s
2025-10-02 14:18:12.514509 | controller | test_deps : Install extra packages -------------------------------------- 0.04s
2025-10-02 14:18:12.514519 | controller | INFO     Running default > converge
2025-10-02 14:18:12.965657 | controller |
2025-10-02 14:18:13.765034 | controller | PLAY [Converge] ****************************************************************
2025-10-02 14:18:13.765069 | controller |
2025-10-02 14:18:13.765079 | controller | TASK [Gathering Facts] *********************************************************
2025-10-02 14:18:13.765086 | controller | Thursday 02 October 2025  14:18:12 +0000 (0:00:00.018)       0:00:00.018 ******
2025-10-02 14:18:13.765114 | controller | ok: [instance]
2025-10-02 14:18:13.782599 | controller |
2025-10-02 14:18:13.782622 | controller | TASK [edpm_build_images : Add edpm-image-builder to the list of packages to be installed on host] ***
2025-10-02 14:18:13.782631 | controller | Thursday 02 October 2025  14:18:13 +0000 (0:00:00.799)       0:00:00.818 ******
2025-10-02 14:18:13.782643 | controller | skipping: [instance]
2025-10-02 14:18:13.799179 | controller |
2025-10-02 14:18:13.799225 | controller | TASK [edpm_build_images : Install required packages] ***************************
2025-10-02 14:18:13.799234 | controller | Thursday 02 October 2025  14:18:13 +0000 (0:00:00.017)       0:00:00.836 ******
2025-10-02 14:18:13.799245 | controller | skipping: [instance]
2025-10-02 14:18:14.104187 | controller |
2025-10-02 14:18:14.104227 | controller | TASK [edpm_build_images : Ensure logs/edpm_images directory] *******************
2025-10-02 14:18:14.104235 | controller | Thursday 02 October 2025  14:18:13 +0000 (0:00:00.016)       0:00:00.853 ******
2025-10-02 14:18:14.104247 | controller | changed: [instance]
2025-10-02 14:18:14.104401 | controller |
2025-10-02 14:18:14.104434 | controller | TASK [discover_latest_image : Get latest image] ********************************
2025-10-02 14:18:14.126480 | controller | Thursday 02 October 2025  14:18:14 +0000 (0:00:00.305)       0:00:01.158 ******
2025-10-02 14:18:14.126510 | controller | skipping: [instance]
2025-10-02 14:18:14.152068 | controller |
2025-10-02 14:18:14.152087 | controller | TASK [discover_latest_image : Export facts accordingly] ************************
2025-10-02 14:18:14.152095 | controller | Thursday 02 October 2025  14:18:14 +0000 (0:00:00.022)       0:00:01.180 ******
2025-10-02 14:18:14.152127 | controller | skipping: [instance]
2025-10-02 14:18:14.167948 | controller |
2025-10-02 14:18:14.167966 | controller | TASK [edpm_build_images : Download TripleO source image] ***********************
2025-10-02 14:18:14.167973 | controller | Thursday 02 October 2025  14:18:14 +0000 (0:00:00.025)       0:00:01.206 ******
2025-10-02 14:18:14.167981 | controller | skipping: [instance]
2025-10-02 14:18:14.194202 | controller |
2025-10-02 14:18:14.194235 | controller | TASK [edpm_build_images : Check if cert exits] *********************************
2025-10-02 14:18:14.194243 | controller | Thursday 02 October 2025  14:18:14 +0000 (0:00:00.015)       0:00:01.222 ******
2025-10-02 14:18:14.194258 | controller | skipping: [instance]
2025-10-02 14:18:14.194283 | controller |
2025-10-02 14:18:14.194291 | controller | TASK [edpm_build_images : Read the certificates] *******************************
2025-10-02 14:18:14.194357 | controller | Thursday 02 October 2025  14:18:14 +0000 (0:00:00.026)       0:00:01.248 ******
2025-10-02 14:18:14.220949 | controller | skipping: [instance]
2025-10-02 14:18:14.246887 | controller |
2025-10-02 14:18:14.246912 | controller | TASK [edpm_build_images : Dump the certificates] *******************************
2025-10-02 14:18:14.246920 | controller | Thursday 02 October 2025  14:18:14 +0000 (0:00:00.026)       0:00:01.275 ******
2025-10-02 14:18:14.246930 | controller | skipping: [instance]
2025-10-02 14:18:14.274138 | controller |
2025-10-02 14:18:14.274183 | controller | TASK [edpm_build_images : Install libguestfs packages] *************************
2025-10-02 14:18:14.274191 | controller | Thursday 02 October 2025  14:18:14 +0000 (0:00:00.025)       0:00:01.301 ******
2025-10-02 14:18:14.274201 | controller | skipping: [instance]
2025-10-02 14:18:14.300194 | controller |
2025-10-02 14:18:14.300251 | controller | TASK [edpm_build_images : Add cert if it exists] *******************************
2025-10-02 14:18:14.300262 | controller | Thursday 02 October 2025  14:18:14 +0000 (0:00:00.026)       0:00:01.327 ******
2025-10-02 14:18:14.300277 | controller | skipping: [instance]
2025-10-02 14:18:14.327215 | controller |
2025-10-02 14:18:14.327242 | controller | TASK [edpm_build_images : Build EDPM hardened uefi image] **********************
2025-10-02 14:18:14.327250 | controller | Thursday 02 October 2025  14:18:14 +0000 (0:00:00.026)       0:00:01.354 ******
2025-10-02 14:18:14.327262 | controller | skipping: [instance]
2025-10-02 14:18:14.353830 | controller |
2025-10-02 14:18:14.353854 | controller | TASK [edpm_build_images : Build ironic-python-agent image] *********************
2025-10-02 14:18:14.353862 | controller | Thursday 02 October 2025  14:18:14 +0000 (0:00:00.027)       0:00:01.381 ******
2025-10-02 14:18:14.353881 | controller | skipping: [instance]
2025-10-02 14:18:14.353906 | controller |
2025-10-02 14:18:14.354031 | controller | TASK [edpm_build_images : Package edpm-hardened-uefi image inside container image] ***
2025-10-02 14:18:14.354139 | controller | Thursday 02 October 2025  14:18:14 +0000 (0:00:00.026)       0:00:01.408 ******
2025-10-02 14:18:14.383042 | controller | skipping: [instance]
2025-10-02 14:18:14.383092 | controller |
2025-10-02 14:18:14.383223 | controller | TASK [edpm_build_images : Package ironic-python-agent image inside container image] ***
2025-10-02 14:18:14.383329 | controller | Thursday 02 October 2025  14:18:14 +0000 (0:00:00.029)       0:00:01.437 ******
2025-10-02 14:18:14.413952 | controller | skipping: [instance]
2025-10-02 14:18:14.414016 | controller |
2025-10-02 14:18:14.414059 | controller | TASK [edpm_build_images : Push images to registry with tag latest] *************
2025-10-02 14:18:14.414250 | controller | Thursday 02 October 2025  14:18:14 +0000 (0:00:00.030)       0:00:01.468 ******
2025-10-02 14:18:14.446413 | controller | skipping: [instance] => (item=edpm-hardened-uefi)
2025-10-02 14:18:14.446471 | controller | skipping: [instance] => (item=ironic-python-agent)
2025-10-02 14:18:14.446580 | controller | skipping: [instance]
2025-10-02 14:18:14.446673 | controller |
2025-10-02 14:18:14.446776 | controller | TASK [edpm_build_images : Retag the images with podified-ci-testing tag] *******
2025-10-02 14:18:14.446881 | controller | Thursday 02 October 2025  14:18:14 +0000 (0:00:00.032)       0:00:01.500 ******
2025-10-02 14:18:14.476335 | controller | skipping: [instance] => (item=edpm-hardened-uefi)
2025-10-02 14:18:14.476393 | controller | skipping: [instance] => (item=ironic-python-agent)
2025-10-02 14:18:14.476487 | controller | skipping: [instance]
2025-10-02 14:18:14.476580 | controller |
2025-10-02 14:18:14.476688 | controller | TASK [edpm_build_images : Push images to registry with podified-ci-testing tag] ***
2025-10-02 14:18:14.476778 | controller | Thursday 02 October 2025  14:18:14 +0000 (0:00:00.030)       0:00:01.530 ******
2025-10-02 14:18:14.506313 | controller | skipping: [instance] => (item=edpm-hardened-uefi)
2025-10-02 14:18:14.506379 | controller | skipping: [instance] => (item=ironic-python-agent)
2025-10-02 14:18:14.506522 | controller | skipping: [instance]
2025-10-02 14:18:14.506631 | controller |
2025-10-02 14:18:14.506778 | controller | TASK [edpm_build_images : Dump edpm container images in the file] **************
2025-10-02 14:18:14.506844 | controller | Thursday 02 October 2025  14:18:14 +0000 (0:00:00.029)       0:00:01.560 ******
2025-10-02 14:18:14.547933 | controller | skipping: [instance]
2025-10-02 14:18:14.547990 | controller |
2025-10-02 14:18:14.548181 | controller | PLAY RECAP *********************************************************************
2025-10-02 14:18:14.548317 | controller | instance                   : ok=2    changed=1    unreachable=0    failed=0    skipped=18   rescued=0    ignored=0
2025-10-02 14:18:14.548432 | controller |
2025-10-02 14:18:14.548560 | controller | Thursday 02 October 2025  14:18:14 +0000 (0:00:00.041)       0:00:01.602 ******
2025-10-02 14:18:14.548678 | controller | ===============================================================================
2025-10-02 14:18:14.548773 | controller | Gathering Facts --------------------------------------------------------- 0.80s
2025-10-02 14:18:14.548870 | controller | edpm_build_images : Ensure logs/edpm_images directory ------------------- 0.31s
2025-10-02 14:18:14.548969 | controller | edpm_build_images : Dump edpm container images in the file -------------- 0.04s
2025-10-02 14:18:14.549065 | controller | edpm_build_images : Push images to registry with tag latest ------------- 0.03s
2025-10-02 14:18:14.549214 | controller | edpm_build_images : Package ironic-python-agent image inside container image --- 0.03s
2025-10-02 14:18:14.549314 | controller | edpm_build_images : Retag the images with podified-ci-testing tag ------- 0.03s
2025-10-02 14:18:14.549404 | controller | edpm_build_images : Push images to registry with podified-ci-testing tag --- 0.03s
2025-10-02 14:18:14.549506 | controller | edpm_build_images : Package edpm-hardened-uefi image inside container image --- 0.03s
2025-10-02 14:18:14.549603 | controller | edpm_build_images : Build EDPM hardened uefi image ---------------------- 0.03s
2025-10-02 14:18:14.549697 | controller | edpm_build_images : Build ironic-python-agent image --------------------- 0.03s
2025-10-02 14:18:14.549791 | controller | edpm_build_images : Install libguestfs packages ------------------------- 0.03s
2025-10-02 14:18:14.549883 | controller | edpm_build_images : Read the certificates ------------------------------- 0.03s
2025-10-02 14:18:14.549975 | controller | edpm_build_images : Check if cert exits --------------------------------- 0.03s
2025-10-02 14:18:14.550067 | controller | edpm_build_images : Add cert if it exists ------------------------------- 0.03s
2025-10-02 14:18:14.550211 | controller | edpm_build_images : Dump the certificates ------------------------------- 0.03s
2025-10-02 14:18:14.550320 | controller | discover_latest_image : Export facts accordingly ------------------------ 0.03s
2025-10-02 14:18:14.550416 | controller | discover_latest_image : Get latest image -------------------------------- 0.02s
2025-10-02 14:18:14.550517 | controller | edpm_build_images : Add edpm-image-builder to the list of packages to be installed on host --- 0.02s
2025-10-02 14:18:14.550640 | controller | edpm_build_images : Install required packages --------------------------- 0.02s
2025-10-02 14:18:14.551004 | controller | edpm_build_images : Download TripleO source image ----------------------- 0.02s
2025-10-02 14:18:14.615008 | controller | INFO     Running default > cleanup
2025-10-02 14:18:14.615456 | controller | WARNING  Skipping, cleanup playbook not configured.
2025-10-02 14:18:14.615994 | controller | INFO     Writing /tmp/report.html report.
2025-10-02 14:18:15.007308 | controller | changed
2025-10-02 14:18:15.060401 | 
2025-10-02 14:18:15.060473 | PLAY RECAP
2025-10-02 14:18:15.060514 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-10-02 14:18:15.060538 | 
2025-10-02 14:18:15.333311 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-10-02 14:18:15.334145 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-10-02 14:18:16.195672 | 
2025-10-02 14:18:16.195793 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-10-02 14:18:16.241841 | 
2025-10-02 14:18:16.241990 | TASK [Filter out host if needed]
2025-10-02 14:18:16.257424 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-10-02 14:18:16.262049 | 
2025-10-02 14:18:16.262120 | TASK [Ensure file is present]
2025-10-02 14:18:16.687345 | controller | ok
2025-10-02 14:18:16.694588 | 
2025-10-02 14:18:16.694672 | TASK [Manage molecule report file]
2025-10-02 14:18:17.260022 | controller | changed
2025-10-02 14:18:17.266024 | 
2025-10-02 14:18:17.266134 | TASK [Check if we get ci-framework-data basedir]
2025-10-02 14:18:17.489022 | controller | ok
2025-10-02 14:18:17.498382 | 
2025-10-02 14:18:17.498491 | TASK [Create ci-framework-data log directory for zuul]
2025-10-02 14:18:17.811862 | controller | changed
2025-10-02 14:18:17.817207 | 
2025-10-02 14:18:17.817280 | TASK [Copy ci-framework interesting files]
2025-10-02 14:18:18.016044 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2025-10-02 14:18:18.345264 | controller | changed
2025-10-02 14:18:18.350520 | 
2025-10-02 14:18:18.350585 | TASK [Get SELinux listing]
2025-10-02 14:18:18.895038 | controller | changed
2025-10-02 14:18:18.900625 | 
2025-10-02 14:18:18.900688 | TASK [Generate log index]
2025-10-02 14:18:19.926102 | controller | changed
2025-10-02 14:18:19.933119 | 
2025-10-02 14:18:19.933190 | TASK [Get some env related data]
2025-10-02 14:18:20.534207 | controller | /home/zuul/.local/bin/ansible
2025-10-02 14:18:21.499494 | controller | changed
2025-10-02 14:18:21.504631 | 
2025-10-02 14:18:21.504695 | TASK [Generate list of logs to collect in home directory]
2025-10-02 14:18:22.021682 | controller | ok: All paths examined
2025-10-02 14:18:22.090209 | 
2025-10-02 14:18:22.090318 | LOOP [Copy logs from home directory]
2025-10-02 14:18:22.510746 | controller | changed:
2025-10-02 14:18:22.510967 | controller | {
2025-10-02 14:18:22.511000 | controller |   "atime": 1759414580.4343295,
2025-10-02 14:18:22.511028 | controller |   "ctime": 1759414597.1464937,
2025-10-02 14:18:22.511048 | controller |   "dev": 64513,
2025-10-02 14:18:22.511067 | controller |   "gid": 1000,
2025-10-02 14:18:22.511084 | controller |   "gr_name": "zuul",
2025-10-02 14:18:22.511102 | controller |   "inode": 4452427,
2025-10-02 14:18:22.511118 | controller |   "isblk": false,
2025-10-02 14:18:22.511151 | controller |   "ischr": false,
2025-10-02 14:18:22.511168 | controller |   "isdir": false,
2025-10-02 14:18:22.511184 | controller |   "isfifo": false,
2025-10-02 14:18:22.511200 | controller |   "isgid": false,
2025-10-02 14:18:22.511216 | controller |   "islnk": false,
2025-10-02 14:18:22.511231 | controller |   "isreg": true,
2025-10-02 14:18:22.511245 | controller |   "issock": false,
2025-10-02 14:18:22.511260 | controller |   "isuid": false,
2025-10-02 14:18:22.511276 | controller |   "mode": "0644",
2025-10-02 14:18:22.511290 | controller |   "mtime": 1759414597.1464937,
2025-10-02 14:18:22.511307 | controller |   "nlink": 1,
2025-10-02 14:18:22.511324 | controller |   "path": "/home/zuul/ansible.log",
2025-10-02 14:18:22.511343 | controller |   "pw_name": "zuul",
2025-10-02 14:18:22.511360 | controller |   "rgrp": true,
2025-10-02 14:18:22.511376 | controller |   "roth": true,
2025-10-02 14:18:22.511392 | controller |   "rusr": true,
2025-10-02 14:18:22.511407 | controller |   "size": 6749,
2025-10-02 14:18:22.511422 | controller |   "uid": 1000,
2025-10-02 14:18:22.511438 | controller |   "wgrp": false,
2025-10-02 14:18:22.511454 | controller |   "woth": false,
2025-10-02 14:18:22.511472 | controller |   "wusr": true,
2025-10-02 14:18:22.511490 | controller |   "xgrp": false,
2025-10-02 14:18:22.511505 | controller |   "xoth": false,
2025-10-02 14:18:22.511521 | controller |   "xusr": false
2025-10-02 14:18:22.511536 | controller | }
2025-10-02 14:18:22.526485 | 
2025-10-02 14:18:22.526592 | TASK [Copy crio stats log file]
2025-10-02 14:18:22.571490 | controller | skipping: Conditional result was False
2025-10-02 14:18:22.578647 | 
2025-10-02 14:18:22.578748 | TASK [Get SELinux related data]
2025-10-02 14:18:22.920144 | controller | <no matches>
2025-10-02 14:18:23.154066 | controller | ERROR
2025-10-02 14:18:23.154234 | controller | {
2025-10-02 14:18:23.154272 | controller |   "delta": "0:00:00.008943",
2025-10-02 14:18:23.154296 | controller |   "end": "2025-10-02 14:18:22.921077",
2025-10-02 14:18:23.154316 | controller |   "msg": "non-zero return code",
2025-10-02 14:18:23.154333 | controller |   "rc": 1,
2025-10-02 14:18:23.154351 | controller |   "start": "2025-10-02 14:18:22.912134"
2025-10-02 14:18:23.154367 | controller | }
2025-10-02 14:18:23.154392 | controller | ERROR: Ignoring Errors
2025-10-02 14:18:23.161951 | 
2025-10-02 14:18:23.162019 | TASK [Create system configuration directory]
2025-10-02 14:18:23.394745 | controller | changed
2025-10-02 14:18:23.399667 | 
2025-10-02 14:18:23.399747 | TASK [Get some of the system configurations]
2025-10-02 14:18:24.003230 | controller | changed
2025-10-02 14:18:24.010180 | 
2025-10-02 14:18:24.010260 | TASK [Copy generated documentation if available]
2025-10-02 14:18:24.034141 | controller | skipping: Conditional result was False
2025-10-02 14:18:24.041218 | 
2025-10-02 14:18:24.041294 | TASK [Copy generated AsciiDoc documentation if available]
2025-10-02 14:18:24.065257 | controller | skipping: Conditional result was False
2025-10-02 14:18:24.071276 | 
2025-10-02 14:18:24.071364 | TASK [Compress logs bigger than 2MB]
2025-10-02 14:18:24.604256 | controller | changed
2025-10-02 14:18:24.609621 | 
2025-10-02 14:18:24.609690 | TASK [Copy files from workspace on node]
2025-10-02 14:18:24.644220 | controller | ok
2025-10-02 14:18:24.690833 | 
2025-10-02 14:18:24.690952 | TASK [fetch-output : Set log path for multiple nodes]
2025-10-02 14:18:24.746543 | controller | skipping: Conditional result was False
2025-10-02 14:18:24.754050 | 
2025-10-02 14:18:24.754153 | TASK [fetch-output : Set log path for single node]
2025-10-02 14:18:24.796572 | controller | ok
2025-10-02 14:18:24.805681 | 
2025-10-02 14:18:24.805770 | LOOP [fetch-output : Ensure local output dirs]
2025-10-02 14:18:25.184971 | controller -> localhost | ok: "/var/lib/zuul/builds/a678b8f9c931463884833124dbf2020b/work/logs"
2025-10-02 14:18:25.185221 | controller -> localhost | changed: All items complete
2025-10-02 14:18:25.185253 | 
2025-10-02 14:18:25.565488 | controller -> localhost | changed: "/var/lib/zuul/builds/a678b8f9c931463884833124dbf2020b/work/artifacts"
2025-10-02 14:18:25.890112 | controller -> localhost | changed: "/var/lib/zuul/builds/a678b8f9c931463884833124dbf2020b/work/docs"
2025-10-02 14:18:25.902592 | 
2025-10-02 14:18:25.902670 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-10-02 14:18:26.837397 | controller | changed:
2025-10-02 14:18:26.837560 | controller | .d..t...... ./
2025-10-02 14:18:26.837591 | controller | >f+++++++++ README.html
2025-10-02 14:18:26.837614 | controller | >f+++++++++ ansible-execution.log
2025-10-02 14:18:26.837635 | controller | >f+++++++++ ansible.log
2025-10-02 14:18:26.837654 | controller | >f+++++++++ dmesg.log
2025-10-02 14:18:26.837674 | controller | >f+++++++++ installed-pkgs.log
2025-10-02 14:18:26.837695 | controller | >f+++++++++ python.log
2025-10-02 14:18:26.837716 | controller | >f+++++++++ registries.conf
2025-10-02 14:18:26.837736 | controller | >f+++++++++ report.html
2025-10-02 14:18:26.837757 | controller | >f+++++++++ selinux-denials.log
2025-10-02 14:18:26.837776 | controller | >f+++++++++ selinux-listing.log
2025-10-02 14:18:26.837794 | controller | cd+++++++++ ci-framework-data/
2025-10-02 14:18:26.837812 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-10-02 14:18:26.837850 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-10-02 14:18:26.837872 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-10-02 14:18:26.837891 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-10-02 14:18:26.838019 | controller | cd+++++++++ ci-framework-data/artifacts/repositories/
2025-10-02 14:18:26.838055 | controller | >f+++++++++ ci-framework-data/artifacts/repositories/delorean.repo.md5
2025-10-02 14:18:26.838080 | controller | cd+++++++++ ci-framework-data/logs/
2025-10-02 14:18:26.838099 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2025-10-02 14:18:26.838131 | controller | cd+++++++++ ci-framework-data/logs/edpm_images/
2025-10-02 14:18:26.838154 | controller | cd+++++++++ registries.conf.d/
2025-10-02 14:18:26.838174 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-10-02 14:18:26.838193 | controller | cd+++++++++ system-config/
2025-10-02 14:18:26.838211 | controller | cd+++++++++ system-config/libvirt/
2025-10-02 14:18:26.838228 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-10-02 14:18:26.838246 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-10-02 14:18:27.966810 | controller | changed: .d..t...... ./
2025-10-02 14:18:28.804402 | controller | changed: .d..t...... ./
2025-10-02 14:18:28.828695 | 
2025-10-02 14:18:28.828798 | TASK [Return artifact to Zuul]
2025-10-02 14:18:28.928785 | controller | ok
2025-10-02 14:18:28.990656 | 
2025-10-02 14:18:28.990738 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-10-02 14:18:28.990838 | 
2025-10-02 14:18:28.990866 | PLAY RECAP
2025-10-02 14:18:28.990902 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2025-10-02 14:18:28.990953 | 
2025-10-02 14:18:29.192299 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-10-02 14:18:29.193068 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-10-02 14:18:31.048404 | 
2025-10-02 14:18:31.048517 | PLAY [all]
2025-10-02 14:18:31.112547 | 
2025-10-02 14:18:31.120442 | TASK [include_role : fetch-output]
2025-10-02 14:18:31.194349 | controller | ok
2025-10-02 14:18:31.224786 | 
2025-10-02 14:18:31.224934 | TASK [fetch-output : Set log path for multiple nodes]
2025-10-02 14:18:31.312580 | controller | skipping: Conditional result was False
2025-10-02 14:18:31.320731 | 
2025-10-02 14:18:31.320839 | TASK [fetch-output : Set log path for single node]
2025-10-02 14:18:31.412282 | controller | ok
2025-10-02 14:18:31.429335 | 
2025-10-02 14:18:31.429459 | LOOP [fetch-output : Ensure local output dirs]
2025-10-02 14:18:32.377201 | controller -> localhost | ok: "/var/lib/zuul/builds/a678b8f9c931463884833124dbf2020b/work/logs"
2025-10-02 14:18:32.978480 | controller -> localhost | ok: "/var/lib/zuul/builds/a678b8f9c931463884833124dbf2020b/work/artifacts"
2025-10-02 14:18:33.449776 | controller -> localhost | ok: "/var/lib/zuul/builds/a678b8f9c931463884833124dbf2020b/work/docs"
2025-10-02 14:18:33.474744 | 
2025-10-02 14:18:33.474862 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-10-02 14:18:34.533442 | controller | ok
2025-10-02 14:18:34.533643 | controller | ok: All items complete
2025-10-02 14:18:34.533674 | 
2025-10-02 14:18:35.349733 | controller | ok
2025-10-02 14:18:35.956299 | controller | ok
2025-10-02 14:18:35.977674 | 
2025-10-02 14:18:35.977777 | TASK [include_role : fetch-output-openshift]
2025-10-02 14:18:36.014260 | controller | skipping: Conditional result was False
2025-10-02 14:18:36.026277 | 
2025-10-02 14:18:36.026369 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-10-02 14:18:37.151610 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.052725
2025-10-02 14:18:37.762722 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.016708
2025-10-02 14:18:37.843290 | 
2025-10-02 14:18:37.843384 | PLAY [all]
2025-10-02 14:18:37.866515 | 
2025-10-02 14:18:37.866612 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-10-02 14:18:38.665334 | controller | changed
2025-10-02 14:18:38.712332 | 
2025-10-02 14:18:38.712420 | PLAY RECAP
2025-10-02 14:18:38.712462 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-10-02 14:18:38.712486 | 
2025-10-02 14:18:39.052852 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-10-02 14:18:39.053677 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-10-02 14:18:40.275728 | 
2025-10-02 14:18:40.275834 | PLAY [localhost]
2025-10-02 14:18:40.330333 | 
2025-10-02 14:18:40.330445 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-10-02 14:18:41.431587 | localhost | changed
2025-10-02 14:18:41.436644 | 
2025-10-02 14:18:41.436728 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-10-02 14:18:41.510778 | localhost | ok
2025-10-02 14:18:41.545004 | 
2025-10-02 14:18:41.545099 | TASK [add-fileserver : Create SSH private key tempfile]
2025-10-02 14:18:42.670196 | localhost | changed
2025-10-02 14:18:42.675697 | 
2025-10-02 14:18:42.675769 | TASK [add-fileserver : Create SSH private key from secret]
2025-10-02 14:18:44.206734 | localhost | changed
2025-10-02 14:18:44.214315 | 
2025-10-02 14:18:44.214392 | TASK [add-fileserver : Add fileserver ssh key]
2025-10-02 14:18:45.100613 | localhost | Identity added: /var/lib/zuul/builds/a678b8f9c931463884833124dbf2020b/work/tmp/ansible.fp7rklwz (/var/lib/zuul/builds/a678b8f9c931463884833124dbf2020b/work/tmp/ansible.fp7rklwz)
2025-10-02 14:18:45.100793 | localhost | ok: Runtime: 0:00:00.019870
2025-10-02 14:18:45.105550 | 
2025-10-02 14:18:45.105619 | TASK [add-fileserver : Remove SSH private key from disk]
2025-10-02 14:18:45.748987 | localhost | ok: Runtime: 0:00:00.022579
2025-10-02 14:18:45.753717 | 
2025-10-02 14:18:45.753790 | TASK [add-fileserver : Add fileserver to inventory]
2025-10-02 14:18:45.814251 | localhost | changed
2025-10-02 14:18:45.819178 | 
2025-10-02 14:18:45.819249 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-10-02 14:18:46.285309 | localhost | changed
2025-10-02 14:18:46.312102 | 
2025-10-02 14:18:46.312193 | PLAY [localhost]
2025-10-02 14:18:46.325726 | 
2025-10-02 14:18:46.325797 | TASK [Generate bulk log download script]
2025-10-02 14:18:46.385357 | localhost | ok
2025-10-02 14:18:46.486181 | 
2025-10-02 14:18:46.486280 | TASK [local-log-download : Check API endpoint is defined]
2025-10-02 14:18:46.524830 | localhost | ok: All assertions passed
2025-10-02 14:18:46.532262 | 
2025-10-02 14:18:46.532335 | TASK [local-log-download : Create download script]
2025-10-02 14:18:47.341614 | localhost -> localhost | changed
2025-10-02 14:18:47.350238 | 
2025-10-02 14:18:47.350303 | TASK [Register quick-download link]
2025-10-02 14:18:47.404905 | localhost | ok
2025-10-02 14:18:47.457180 | 
2025-10-02 14:18:47.457260 | PLAY [logserver.rdoproject.org]
2025-10-02 14:18:47.496150 | 
2025-10-02 14:18:47.496231 | TASK [Set zuul-log-path fact]
2025-10-02 14:18:47.540787 | logserver.rdoproject.org | ok
2025-10-02 14:18:47.557324 | 
2025-10-02 14:18:47.557401 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-02 14:18:47.604579 | logserver.rdoproject.org | ok
2025-10-02 14:18:47.610331 | 
2025-10-02 14:18:47.610403 | TASK [upload-logs : Create log directories]
2025-10-02 14:18:49.618467 | logserver.rdoproject.org | changed
2025-10-02 14:18:49.621827 | 
2025-10-02 14:18:49.621895 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-10-02 14:18:50.324524 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.048753
2025-10-02 14:18:50.329202 | 
2025-10-02 14:18:50.329269 | TASK [upload-logs : Upload logs to log server]
2025-10-02 14:18:52.878984 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-10-02 14:18:52.882072 | 
2025-10-02 14:18:52.882167 | LOOP [upload-logs : Compress console log and json output]
2025-10-02 14:18:52.960510 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-02 14:18:52.970226 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-02 14:18:52.976455 | 
2025-10-02 14:18:52.976563 | LOOP [upload-logs : Upload compressed console log and json output]
2025-10-02 14:18:53.068641 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-02 14:18:53.068855 | 
2025-10-02 14:18:53.071725 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-02 14:18:53.085638 | 
2025-10-02 14:18:53.085741 | LOOP [upload-logs : Upload console log and json output]