2025-12-01 11:00:41.291927 | Job console starting...
2025-12-01 11:00:41.317781 | Updating repositories
2025-12-01 11:00:41.390527 | Preparing job workspace
2025-12-01 11:00:50.073294 | Running Ansible setup...
2025-12-01 11:00:53.927349 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-12-01 11:00:54.520409 | 
2025-12-01 11:00:54.520522 | PLAY [localhost]
2025-12-01 11:00:54.531740 | 
2025-12-01 11:00:54.531834 | TASK [Gathering Facts]
2025-12-01 11:00:55.462536 | localhost | ok
2025-12-01 11:00:55.486009 | 
2025-12-01 11:00:55.486196 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-12-01 11:00:55.919393 | localhost -> localhost | changed
2025-12-01 11:00:55.928450 | 
2025-12-01 11:00:55.928575 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-12-01 11:00:56.815317 | localhost -> localhost | changed
2025-12-01 11:00:56.835685 | 
2025-12-01 11:00:56.835866 | TASK [Setup log path fact]
2025-12-01 11:00:56.859322 | localhost | ok
2025-12-01 11:00:56.877274 | 
2025-12-01 11:00:56.877431 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-12-01 11:00:56.908919 | localhost | ok
2025-12-01 11:00:56.920376 | 
2025-12-01 11:00:56.920702 | TASK [emit-job-header : Print job information]
2025-12-01 11:00:56.949943 | # Job Information
2025-12-01 11:00:56.950128 | Ansible Version: 2.15.12
2025-12-01 11:00:56.950161 | Job: cifmw-tcib
2025-12-01 11:00:56.950182 | Pipeline: github-check
2025-12-01 11:00:56.950206 | Executor: ze03.softwarefactory-project.io
2025-12-01 11:00:56.950230 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3505
2025-12-01 11:00:56.950254 | Log URL (when completed): https://logserver.rdoproject.org/bf6/rdoproject.org/bf65e6278f824e108530af6c58a03f29/
2025-12-01 11:00:56.950279 | Event ID: c7559b00-cea4-11f0-8bbe-0b02e3b2e2af
2025-12-01 11:00:56.954115 | 
2025-12-01 11:00:56.954186 | LOOP [emit-job-header : Print node information]
2025-12-01 11:00:57.062480 | localhost | ok:
2025-12-01 11:00:57.062777 | localhost | # Node Information
2025-12-01 11:00:57.062821 | localhost | Inventory Hostname: controller
2025-12-01 11:00:57.062854 | localhost | Hostname: np0005540949
2025-12-01 11:00:57.062881 | localhost | Username: zuul
2025-12-01 11:00:57.062910 | localhost | Distro: CentOS 9
2025-12-01 11:00:57.062937 | localhost | Provider: vexxhost-nodepool-tripleo
2025-12-01 11:00:57.062964 | localhost | Region: RegionOne
2025-12-01 11:00:57.062989 | localhost | Label: cloud-centos-9-stream-tripleo-vexxhost
2025-12-01 11:00:57.063014 | localhost | Product Name: OpenStack Nova
2025-12-01 11:00:57.063038 | localhost | Interface IP: 38.102.83.200
2025-12-01 11:00:57.097711 | 
2025-12-01 11:00:57.097847 | PLAY [all]
2025-12-01 11:00:57.104555 | 
2025-12-01 11:00:57.104629 | TASK [Gather network facts]
2025-12-01 11:00:57.541792 | controller | ok
2025-12-01 11:00:57.558416 | 
2025-12-01 11:00:57.558506 | TASK [include_role : start-zuul-console]
2025-12-01 11:00:57.577139 | controller | ok
2025-12-01 11:00:57.588423 | 
2025-12-01 11:00:57.588499 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-12-01 11:00:57.971711 | controller | ok
2025-12-01 11:00:57.981034 | 
2025-12-01 11:00:57.981111 | TASK [include_role : add-build-sshkey]
2025-12-01 11:00:58.011116 | controller | ok
2025-12-01 11:00:58.025479 | 
2025-12-01 11:00:58.025582 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-12-01 11:00:58.317403 | controller -> localhost | ok
2025-12-01 11:00:58.324782 | 
2025-12-01 11:00:58.324857 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-12-01 11:00:58.354990 | controller | ok
2025-12-01 11:00:58.376205 | controller | included: /var/lib/zuul/builds/bf65e6278f824e108530af6c58a03f29/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-12-01 11:00:58.382748 | 
2025-12-01 11:00:58.382819 | TASK [add-build-sshkey : Create Temp SSH key]
2025-12-01 11:00:59.148152 | controller -> localhost | Generating public/private rsa key pair.
2025-12-01 11:00:59.148360 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/bf65e6278f824e108530af6c58a03f29/work/bf65e6278f824e108530af6c58a03f29_id_rsa.
2025-12-01 11:00:59.148403 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/bf65e6278f824e108530af6c58a03f29/work/bf65e6278f824e108530af6c58a03f29_id_rsa.pub.
2025-12-01 11:00:59.148431 | controller -> localhost | The key fingerprint is:
2025-12-01 11:00:59.148452 | controller -> localhost | SHA256:UM7UOPmxPsRJ9olXbkaCtAf/MMO7eymnhpH4xejZa3M zuul-build-sshkey
2025-12-01 11:00:59.148471 | controller -> localhost | The key's randomart image is:
2025-12-01 11:00:59.148489 | controller -> localhost | +---[RSA 3072]----+
2025-12-01 11:00:59.148506 | controller -> localhost | |        o=o.     |
2025-12-01 11:00:59.148524 | controller -> localhost | |       =+ *=. o  |
2025-12-01 11:00:59.148542 | controller -> localhost | |      . o*.*B*   |
2025-12-01 11:00:59.148577 | controller -> localhost | |       .  B.+*+  |
2025-12-01 11:00:59.148597 | controller -> localhost | |        S+ =.o.  |
2025-12-01 11:00:59.148614 | controller -> localhost | |        . * o.   |
2025-12-01 11:00:59.148633 | controller -> localhost | |         o B.  . |
2025-12-01 11:00:59.148661 | controller -> localhost | |          = *.E  |
2025-12-01 11:00:59.148684 | controller -> localhost | |           o+O   |
2025-12-01 11:00:59.148702 | controller -> localhost | +----[SHA256]-----+
2025-12-01 11:00:59.148768 | controller -> localhost | ok: Runtime: 0:00:00.307396
2025-12-01 11:00:59.158052 | 
2025-12-01 11:00:59.158167 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-12-01 11:00:59.196900 | controller | ok
2025-12-01 11:00:59.206892 | controller | included: /var/lib/zuul/builds/bf65e6278f824e108530af6c58a03f29/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-12-01 11:00:59.216573 | 
2025-12-01 11:00:59.216663 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-12-01 11:00:59.240511 | controller | skipping: Conditional result was False
2025-12-01 11:00:59.246120 | 
2025-12-01 11:00:59.246187 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-12-01 11:00:59.761781 | controller | changed
2025-12-01 11:00:59.769144 | 
2025-12-01 11:00:59.769213 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-12-01 11:01:00.036635 | controller | ok
2025-12-01 11:01:00.050392 | 
2025-12-01 11:01:00.050534 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-12-01 11:01:00.968453 | controller | changed
2025-12-01 11:01:00.974816 | 
2025-12-01 11:01:00.974918 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-12-01 11:01:01.812347 | controller | changed
2025-12-01 11:01:01.819262 | 
2025-12-01 11:01:01.819425 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-12-01 11:01:01.844063 | controller | skipping: Conditional result was False
2025-12-01 11:01:01.850494 | 
2025-12-01 11:01:01.850598 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-12-01 11:01:02.255447 | controller -> localhost | changed
2025-12-01 11:01:02.266410 | 
2025-12-01 11:01:02.266511 | TASK [add-build-sshkey : Add back temp key]
2025-12-01 11:01:02.548490 | controller -> localhost | Identity added: /var/lib/zuul/builds/bf65e6278f824e108530af6c58a03f29/work/bf65e6278f824e108530af6c58a03f29_id_rsa (zuul-build-sshkey)
2025-12-01 11:01:02.548880 | controller -> localhost | ok: Runtime: 0:00:00.008752
2025-12-01 11:01:02.559020 | 
2025-12-01 11:01:02.563189 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-12-01 11:01:02.974971 | controller | ok
2025-12-01 11:01:02.983102 | 
2025-12-01 11:01:02.984144 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-12-01 11:01:03.018942 | controller | skipping: Conditional result was False
2025-12-01 11:01:03.030309 | 
2025-12-01 11:01:03.030418 | TASK [include_role : validate-host]
2025-12-01 11:01:03.061649 | controller | ok
2025-12-01 11:01:03.085601 | 
2025-12-01 11:01:03.085747 | TASK [validate-host : Define zuul_info_dir fact]
2025-12-01 11:01:03.116057 | controller | ok
2025-12-01 11:01:03.122121 | 
2025-12-01 11:01:03.122249 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-12-01 11:01:03.431874 | controller -> localhost | ok
2025-12-01 11:01:03.437838 | 
2025-12-01 11:01:03.437903 | TASK [validate-host : Collect information about the host]
2025-12-01 11:01:04.451143 | controller | ok
2025-12-01 11:01:04.469414 | 
2025-12-01 11:01:04.469556 | TASK [validate-host : Sanitize hostname]
2025-12-01 11:01:04.542205 | controller | ok
2025-12-01 11:01:04.548769 | 
2025-12-01 11:01:04.548854 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-12-01 11:01:05.001487 | controller -> localhost | changed
2025-12-01 11:01:05.007596 | 
2025-12-01 11:01:05.007665 | TASK [validate-host : Collect information about zuul worker]
2025-12-01 11:01:05.451298 | controller | ok
2025-12-01 11:01:05.457812 | 
2025-12-01 11:01:05.457912 | TASK [validate-host : Write out all zuul information for each host]
2025-12-01 11:01:05.973706 | controller -> localhost | changed
2025-12-01 11:01:05.985853 | 
2025-12-01 11:01:05.985950 | TASK [include_role : prepare-workspace-openshift]
2025-12-01 11:01:06.014099 | controller | skipping: Conditional result was False
2025-12-01 11:01:06.023528 | 
2025-12-01 11:01:06.023701 | TASK [include_role : remove-zuul-sshkey]
2025-12-01 11:01:06.073260 | controller | skipping: Conditional result was False
2025-12-01 11:01:06.082471 | 
2025-12-01 11:01:06.082605 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-12-01 11:01:06.351180 | controller | ok: "logs"
2025-12-01 11:01:06.351535 | controller | ok: All items complete
2025-12-01 11:01:06.351582 | 
2025-12-01 11:01:06.561180 | controller | ok: "artifacts"
2025-12-01 11:01:06.783071 | controller | ok: "docs"
2025-12-01 11:01:06.799821 | 
2025-12-01 11:01:06.799939 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-12-01 11:01:07.100644 | controller | changed: "logs"
2025-12-01 11:01:07.338202 | controller | changed: "artifacts"
2025-12-01 11:01:07.547236 | controller | changed: "docs"
2025-12-01 11:01:07.578528 | 
2025-12-01 11:01:07.578627 | PLAY RECAP
2025-12-01 11:01:07.578674 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-12-01 11:01:07.578761 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-12-01 11:01:07.578790 | 
2025-12-01 11:01:07.715698 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-12-01 11:01:07.716494 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-12-01 11:01:08.336803 | 
2025-12-01 11:01:08.336970 | PLAY [all]
2025-12-01 11:01:08.360642 | 
2025-12-01 11:01:08.360756 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-12-01 11:01:08.440491 | controller | ok
2025-12-01 11:01:08.445529 | 
2025-12-01 11:01:08.445605 | TASK [mirror-info-fork : Create /etc/ci]
2025-12-01 11:01:08.901398 | controller | changed
2025-12-01 11:01:08.910928 | 
2025-12-01 11:01:08.911024 | TASK [mirror-info-fork : Install ci_mirror script]
2025-12-01 11:01:10.127626 | controller | changed
2025-12-01 11:01:10.155390 | 
2025-12-01 11:01:10.155598 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-12-01 11:01:10.658147 | controller | changed:
2025-12-01 11:01:10.658371 | controller | {
2025-12-01 11:01:10.658409 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-12-01 11:01:10.658448 | controller | }
2025-12-01 11:01:10.901012 | controller | changed:
2025-12-01 11:01:10.901225 | controller | {
2025-12-01 11:01:10.901287 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-12-01 11:01:10.901332 | controller | }
2025-12-01 11:01:11.147112 | controller | changed:
2025-12-01 11:01:11.147210 | controller | {
2025-12-01 11:01:11.147234 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com"
2025-12-01 11:01:11.147254 | controller | }
2025-12-01 11:01:11.429257 | controller | changed:
2025-12-01 11:01:11.429633 | controller | {
2025-12-01 11:01:11.429710 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-12-01 11:01:11.429799 | controller | }
2025-12-01 11:01:11.705651 | controller | changed:
2025-12-01 11:01:11.705902 | controller | {
2025-12-01 11:01:11.705931 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-12-01 11:01:11.705950 | controller | }
2025-12-01 11:01:11.959639 | controller | changed:
2025-12-01 11:01:11.959838 | controller | {
2025-12-01 11:01:11.959896 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-12-01 11:01:11.959951 | controller | }
2025-12-01 11:01:12.261561 | controller | changed:
2025-12-01 11:01:12.261753 | controller | {
2025-12-01 11:01:12.261829 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDLOQd4ZLtkZXQGY6UwAr/06ppWQK4fDO3HaqxPk98csyOCBXsliSKK39Bso828+5srIXiW7aI6aC9P5mwi4mUZlGPfJlQbfrcGvY+b/SocuvaGK+1RrHLoJCT52LBhwgrzlXio2jeksZeein8iaTrhsPrOAs7KggIL/rB9hEiB3NaOPWhhoCP4vlW6MEMExGcqB/1FVxXFBPnLkEyW0Lk7ycVflZl2ocRxbfjZi0+tI1Wlinp8PvSQSc/WVrAcDgKjc/mB4ODPOyYy3G8FHgfMsrXSDEyjBKgLKMsdCrAUcqJQWjkqXleXSYOV4q3pzL+9umK+q/e3P/bIoSFQzmJKTU1eDfuvPXmow9F5H54fii/Da7ezlMJ+wPGHJrRAkmzvMbALy7xwswLhZMkOGNtRcPqaKYRmIBKpw3o6bCTtcNUHOtOQnzwY8JzrM2eBWJBXAANYw+9/ho80JIiwhg29CFNpVBuHbql2YxJQNrnl90guN65rYNpDxdIluweyUf8= anbanerj@kaermorhen"
2025-12-01 11:01:12.261885 | controller | }
2025-12-01 11:01:12.516518 | controller | changed:
2025-12-01 11:01:12.516866 | controller | {
2025-12-01 11:01:12.516949 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQC3VwV8Im9kRm49lt3tM36hj4Zv27FxGo4C1Q/0jqhzFmHY7RHbmeRr8ObhwWoHjXSozKWg8FL5ER0z3hTwL0W6lez3sL7hUaCmSuZmG5Hnl3x4vTSxDI9JZ/Y65rtYiiWQo2fC5xJhU/4+0e5e/pseCm8cKRSu+SaxhO+sd6FDojA2x1BzOzKiQRDy/1zWGp/cZkxcEuB1wHI5LMzN03c67vmbu+fhZRAUO4dQkvcnj2LrhQtpa+ytvnSjr8icMDosf1OsbSffwZFyHB/hfWGAfe0eIeSA2XPraxiPknXxiPKx2MJsaUTYbsZcm3EjFdHBBMumw5rBI74zLrMRvCO9GwBEmGT4rFng1nP+yw5DB8sn2zqpOsPg1LYRwCPOUveC13P6pgsZZPh812e8v5EKnETct+5XI3dVpdw6CnNiLwAyVAF15DJvBGT/u1k0Myg/bQn+Gv9k2MSj6LvQmf6WbZu2Wgjm30z3FyCneBqTL7mLF19YXzeC0ufHz5pnO1E= dasm@fedora"
2025-12-01 11:01:12.516997 | controller | }
2025-12-01 11:01:12.784424 | controller | changed:
2025-12-01 11:01:12.784579 | controller | {
2025-12-01 11:01:12.784621 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-12-01 11:01:12.784656 | controller | }
2025-12-01 11:01:13.187290 | controller | changed:
2025-12-01 11:01:13.187463 | controller | {
2025-12-01 11:01:13.187499 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-12-01 11:01:13.187525 | controller | }
2025-12-01 11:01:13.386013 | controller | changed:
2025-12-01 11:01:13.386164 | controller | {
2025-12-01 11:01:13.386207 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-12-01 11:01:13.386242 | controller | }
2025-12-01 11:01:13.636142 | controller | changed:
2025-12-01 11:01:13.636376 | controller | {
2025-12-01 11:01:13.636417 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-12-01 11:01:13.636447 | controller | }
2025-12-01 11:01:13.885818 | controller | changed:
2025-12-01 11:01:13.885944 | controller | {
2025-12-01 11:01:13.885981 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-12-01 11:01:13.886009 | controller | }
2025-12-01 11:01:14.135246 | controller | changed:
2025-12-01 11:01:14.135360 | controller | {
2025-12-01 11:01:14.135385 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-12-01 11:01:14.135403 | controller | }
2025-12-01 11:01:14.398809 | controller | changed:
2025-12-01 11:01:14.399146 | controller | {
2025-12-01 11:01:14.399210 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-12-01 11:01:14.399251 | controller | }
2025-12-01 11:01:14.680836 | controller | changed:
2025-12-01 11:01:14.680962 | controller | {
2025-12-01 11:01:14.680987 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-12-01 11:01:14.681007 | controller | }
2025-12-01 11:01:14.945268 | controller | changed:
2025-12-01 11:01:14.945376 | controller | {
2025-12-01 11:01:14.945400 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQCfNtF7NvKl915TGsGGoseUb06Hj8L/S4toWf0hExeY+F00woL6NvBlJD0nDct+P5a22I4EhvoQCRQ8reaPCm1lybR3uiRIJsj+8zkVvLwby9LXzfZorlNG9ofjd00FEmB09uW/YvTl6Q9XwwwX6tInzIOv3TMqTHHGOL74ibbj8J/FJR0cFEyj0z4WQRvtkh32xAHl83gbuINryMt0sqRI+clj2381NKL55DRLQrVw0gsfqqxiHAnXg21qWmc4J+b9e9kiuAFQjcjwTVkwJCcg3xbPwC/qokYRby/Y5S40UUd7/jEARGXT7RZgpzTuDd1oZiCVrnrqJNPaMNdVv5MLeFdf1B7iIe5aa/fGouX7AO4SdKhZUdnJmCFAGvjC6S3JMZ2wAcUl+OHnssfmdj7XL50cLo27vjuzMtLAgSqi6N99m92WCF2s8J9aVzszX7Xz9OKZCeGsiVJp3/NdABKzSEAyM9xBD/5Vho894Sav+otpySHe3p6RUTgbB5Zu8VyZRZ/UtB3ueXxyo764yrc6qWIDqrehm84Xm9g+/jpIBzGPl07NUNJpdt/6Sgf9RIKXw/7XypO5yZfUcuFNGTxLfqjTNrtgLZNcjfav6sSdVXVcMPL//XNuRdKmVFaO76eV/oGMQGr1fGcCD+N+CpI7+Q+fCNB6VFWG4nZFuI/Iuw== averdagu@redhat.com"
2025-12-01 11:01:14.945421 | controller | }
2025-12-01 11:01:15.202712 | controller | changed:
2025-12-01 11:01:15.202833 | controller | {
2025-12-01 11:01:15.202858 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDq8l27xI+QlQVdS4djp9ogSoyrNE2+Ox6vKPdhSNL1J3PE5w+WCSvMz9A5gnNuH810zwbekEApbxTze/gLQJwBHA52CChfURpXrFaxY7ePXRElwKAL3mJfzBWY/c5jnNL9TCVmFJTGZkFZP3Nh+BMgZvL6xBkt3WKm6Uq18qzd9XeKcZusrA+O+uLv1fVeQnadY9RIqOCyeFYCzLWrUfTyE8x/XG0hAWIM7qpnF2cALQS2h9n4hW5ybiUN790H08wf9hFwEf5nxY9Z9dVkPFQiTSGKNBzmnCXU9skxS/xhpFjJ5duGSZdtAHe9O+nGZm9c67hxgtf8e5PDuqAdXEv2cf6e3VBAt+Bz8EKI3yosTj0oZHfwr42Yzb1l/SKy14Rggsrc9KAQlrGXan6+u2jcQqqx7l+SWmnpFiWTV9u5cWj2IgOhApOitmRBPYqk9rE2usfO0hLn/Pj/R/Nau4803e1/EikdLE7Ps95s9mX5jRDjAoUa2JwFF5RsVFyL910= ashigupt@ashigupt.remote.csb"
2025-12-01 11:01:15.202877 | controller | }
2025-12-01 11:01:15.485998 | controller | changed:
2025-12-01 11:01:15.486104 | controller | {
2025-12-01 11:01:15.486129 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-12-01 11:01:15.486149 | controller | }
2025-12-01 11:01:15.779218 | controller | changed:
2025-12-01 11:01:15.779322 | controller | {
2025-12-01 11:01:15.779354 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-12-01 11:01:15.779380 | controller | }
2025-12-01 11:01:16.079031 | controller | changed:
2025-12-01 11:01:16.079161 | controller | {
2025-12-01 11:01:16.079202 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-12-01 11:01:16.079229 | controller | }
2025-12-01 11:01:16.339161 | controller | changed:
2025-12-01 11:01:16.339276 | controller | {
2025-12-01 11:01:16.339299 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-12-01 11:01:16.339317 | controller | }
2025-12-01 11:01:16.629250 | controller | changed:
2025-12-01 11:01:16.629360 | controller | {
2025-12-01 11:01:16.629383 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQDZdI7t1cxYx65heVI24HTV4F7oQLW1zyfxHreL2TIJKxjyrUUKIFEUmTutcBlJRLNT2Eoix6x1sOw9YrchloCLcn//SGfTElr9mSc5jbjb7QXEU+zJMhtxyEJ1Po3CUGnj7ckiIXw7wcawZtrEOAQ9pH3ExYCJcEMiyNjRQZCxT3tPK+S4B95EWh5Fsrz9CkwpjNRPPH7LigCeQTM3Wc7r97utAslBUUvYceDSLA7rMgkitJE38b7rZBeYzsGQ8YYUBjTCtehqQXxCRjizbHWaaZkBU+N3zkKB6n/iCNGIO690NK7A/qb6msTijiz1PeuM8ThOsi9qXnbX5v0PoTpcFSojV7NHAQ71f0XXuS43FhZctT+Dcx44dT8Fb5vJu2cJGrk+qF8ZgJYNpRS7gPg0EG2EqjK7JMf9ULdjSu0r+KlqIAyLvtzT4eOnQipoKlb/WG5D/0ohKv7OMQ352ggfkBFIQsRXyyTCT98Ft9juqPuahi3CAQmP4H9dyE+7+Kz437PEtsxLmfm6naNmWi7Ee1DqWPwS8rEajsm4sNM4wW9gdBboJQtc0uZw0DfLj1I9r3Mc8Ol0jYtz0yNQDSzVLrGCaJlC311trU70tZ+ZkAVV6Mn8lOhSbj1cK0lvSr6ZK4dgqGl3I1eTZJJhbLNdg7UOVaiRx9543+C/p/As7w== brjackma@redhat.com"
2025-12-01 11:01:16.629402 | controller | }
2025-12-01 11:01:16.881808 | controller | changed:
2025-12-01 11:01:16.881911 | controller | {
2025-12-01 11:01:16.881934 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-12-01 11:01:16.881951 | controller | }
2025-12-01 11:01:17.147178 | controller | changed:
2025-12-01 11:01:17.147363 | controller | {
2025-12-01 11:01:17.147395 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-12-01 11:01:17.147414 | controller | }
2025-12-01 11:01:17.453487 | controller | changed:
2025-12-01 11:01:17.453595 | controller | {
2025-12-01 11:01:17.453617 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-12-01 11:01:17.453635 | controller | }
2025-12-01 11:01:17.480485 | 
2025-12-01 11:01:17.480614 | TASK [Set timezone to UTC]
2025-12-01 11:01:18.088000 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-12-01 11:01:18.093859 | 
2025-12-01 11:01:18.094011 | TASK [Create nodepool directory]
2025-12-01 11:01:18.367551 | controller | changed
2025-12-01 11:01:18.381024 | 
2025-12-01 11:01:18.381181 | TASK [Create nodepool sub_nodes file]
2025-12-01 11:01:19.147708 | controller | changed
2025-12-01 11:01:19.158902 | 
2025-12-01 11:01:19.159069 | TASK [Create nodepool sub_nodes_private file]
2025-12-01 11:01:19.882987 | controller | changed
2025-12-01 11:01:19.895103 | 
2025-12-01 11:01:19.895235 | LOOP [Populate nodepool sub_nodes file]
2025-12-01 11:01:19.957905 | 
2025-12-01 11:01:19.958433 | LOOP [Populate nodepool sub_nodes_private file]
2025-12-01 11:01:20.025229 | 
2025-12-01 11:01:20.025574 | TASK [Create nodepool primary file]
2025-12-01 11:01:20.062633 | controller | skipping: Conditional result was False
2025-12-01 11:01:20.071420 | 
2025-12-01 11:01:20.071529 | TASK [Create nodepool node_private for this node]
2025-12-01 11:01:20.884502 | controller | changed
2025-12-01 11:01:20.901575 | 
2025-12-01 11:01:20.901817 | LOOP [Copy ssh keys to nodepool directory]
2025-12-01 11:01:21.393555 | controller | ok: Item: id_rsa Runtime: 0:00:00.007163
2025-12-01 11:01:21.393837 | 
2025-12-01 11:01:21.603516 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.007124
2025-12-01 11:01:21.621000 | 
2025-12-01 11:01:21.621164 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-12-01 11:01:22.554897 | controller | changed
2025-12-01 11:01:22.563885 | 
2025-12-01 11:01:22.564022 | TASK [Validate sudoers config after edits]
2025-12-01 11:01:22.858516 | controller | /etc/sudoers: parsed OK
2025-12-01 11:01:22.858618 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-12-01 11:01:22.858629 | controller | /etc/sudoers.d/zuul: parsed OK
2025-12-01 11:01:22.858635 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-12-01 11:01:23.106655 | controller | ok: Runtime: 0:00:00.005890
2025-12-01 11:01:23.122331 | 
2025-12-01 11:01:23.122576 | TASK [Show the environment passed in to job shell scripts]
2025-12-01 11:01:23.403929 | controller | SHELL=/bin/bash
2025-12-01 11:01:23.404120 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-12-01 11:01:23.404146 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-12-01 11:01:23.404161 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/23/3523/9dca7030e1689ab350d0cc525f1944d26503b3cc^openstack-k8s-operators/ci-framework:main:refs/changes/05/3505/4fd568413e9fbcb9b5c62cc50f536ea9eafcd481
2025-12-01 11:01:23.404182 | controller | PWD=/home/zuul
2025-12-01 11:01:23.404196 | controller | ZUUL_PIPELINE=github-check
2025-12-01 11:01:23.404210 | controller | LOGNAME=zuul
2025-12-01 11:01:23.404224 | controller | XDG_SESSION_TYPE=tty
2025-12-01 11:01:23.404238 | controller | _=/usr/bin/env
2025-12-01 11:01:23.404251 | controller | MOTD_SHOWN=pam
2025-12-01 11:01:23.404264 | controller | HOME=/home/zuul
2025-12-01 11:01:23.404277 | controller | LANG=en_US.UTF-8
2025-12-01 11:01:23.404292 | controller | SSH_CONNECTION=38.102.83.114 40588 38.102.83.200 22
2025-12-01 11:01:23.404310 | controller | TOX_TESTENV_PASSENV=ZUUL_UUID ZUUL_PROJECT ZUUL_SHORT_PROJECT_NAME ZUUL_PIPELINE ZUUL_VOTING WORKSPACE BUILD_TIMEOUT ZUUL_BRANCH ZUUL_CHANGES ZUUL_CHANGE_IDS ZUUL_CHANGE ZUUL_PATCHSET
2025-12-01 11:01:23.404329 | controller | ZUUL_CHANGE_IDS=3523,9dca7030e1689ab350d0cc525f1944d26503b3cc 3505,4fd568413e9fbcb9b5c62cc50f536ea9eafcd481
2025-12-01 11:01:23.404347 | controller | WORKSPACE=/home/zuul/workspace
2025-12-01 11:01:23.404365 | controller | XDG_SESSION_CLASS=user
2025-12-01 11:01:23.404383 | controller | SELINUX_ROLE_REQUESTED=
2025-12-01 11:01:23.404399 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-12-01 11:01:23.404412 | controller | USER=zuul
2025-12-01 11:01:23.404426 | controller | ZUUL_VOTING=True
2025-12-01 11:01:23.404439 | controller | BUILD_TIMEOUT=3500000
2025-12-01 11:01:23.404452 | controller | SELINUX_USE_CURRENT_RANGE=
2025-12-01 11:01:23.404465 | controller | SHLVL=1
2025-12-01 11:01:23.404479 | controller | ZUUL_PATCHSET=4fd568413e9fbcb9b5c62cc50f536ea9eafcd481
2025-12-01 11:01:23.404492 | controller | XDG_SESSION_ID=1
2025-12-01 11:01:23.404506 | controller | ZUUL_BRANCH=main
2025-12-01 11:01:23.404520 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-12-01 11:01:23.404547 | controller | SSH_CLIENT=38.102.83.114 40588 22
2025-12-01 11:01:23.404561 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-12-01 11:01:23.404574 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-12-01 11:01:23.404588 | controller | which_declare=declare -f
2025-12-01 11:01:23.404602 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2025-12-01 11:01:23.404617 | controller | SELINUX_LEVEL_REQUESTED=
2025-12-01 11:01:23.404630 | controller | ZUUL_CHANGE=3505
2025-12-01 11:01:23.404644 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-12-01 11:01:23.404657 | controller | ZUUL_UUID=bf65e6278f824e108530af6c58a03f29
2025-12-01 11:01:23.404671 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-12-01 11:01:23.404684 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-12-01 11:01:23.404698 | controller | }
2025-12-01 11:01:23.681881 | controller | ok: Runtime: 0:00:00.006835
2025-12-01 11:01:23.695218 | 
2025-12-01 11:01:23.695361 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-12-01 11:01:23.723688 | controller | skipping: Conditional result was False
2025-12-01 11:01:23.739496 | 
2025-12-01 11:01:23.739715 | TASK [Symlink /home/zuul-worker/workspace]
2025-12-01 11:01:24.286684 | controller | skipping: Conditional result was False
2025-12-01 11:01:24.292093 | 
2025-12-01 11:01:24.292161 | TASK [Ensure legacy workspace directory]
2025-12-01 11:01:24.594446 | controller | changed
2025-12-01 11:01:24.679987 | 
2025-12-01 11:01:24.680138 | PLAY RECAP
2025-12-01 11:01:24.680215 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-12-01 11:01:24.680258 | 
2025-12-01 11:01:24.785237 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-12-01 11:01:24.786280 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/crc/podman.yaml@master]
2025-12-01 11:01:25.328351 | 
2025-12-01 11:01:25.328504 | PLAY [all,!compute]
2025-12-01 11:01:25.348054 | 
2025-12-01 11:01:25.348142 | TASK [Enable container-tools rhel-modules for c8/crc node]
2025-12-01 11:01:25.878659 | controller | skipping: Conditional result was False
2025-12-01 11:01:25.887288 | 
2025-12-01 11:01:25.887431 | TASK [Install podman]
2025-12-01 11:01:25.934213 | controller | skipping: Conditional result was False
2025-12-01 11:01:25.941513 | 
2025-12-01 11:01:25.941637 | TASK [Set var name for quay login zuul secret]
2025-12-01 11:01:25.986442 | controller | skipping: Conditional result was False
2025-12-01 11:01:25.992520 | 
2025-12-01 11:01:25.992596 | TASK [Print the username]
2025-12-01 11:01:26.534282 | controller | skipping: Conditional result was False
2025-12-01 11:01:26.548498 | 
2025-12-01 11:01:26.548678 | TASK [Fail when user or password is not set]
2025-12-01 11:01:26.607516 | controller | skipping: Conditional result was False
2025-12-01 11:01:26.619643 | 
2025-12-01 11:01:26.620130 | TASK [Set vars for quay login]
2025-12-01 11:01:26.677172 | 
2025-12-01 11:01:26.677517 | LOOP [Perform container registry login(s) with podman]
2025-12-01 11:01:26.746807 | 
2025-12-01 11:01:26.746998 | LOOP [Perform container registry login(s) with buildah]
2025-12-01 11:01:26.811685 | 
2025-12-01 11:01:26.811881 | TASK [Set Insecure registry for content provider]
2025-12-01 11:01:26.848017 | controller | skipping: Conditional result was False
2025-12-01 11:01:26.882902 | 
2025-12-01 11:01:26.883014 | PLAY RECAP
2025-12-01 11:01:26.883068 | controller | ok: 0 changed: 0 unreachable: 0 failed: 0 skipped: 9 rescued: 0 ignored: 0
2025-12-01 11:01:26.883097 | 
2025-12-01 11:01:26.985554 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/crc/podman.yaml@master]
2025-12-01 11:01:26.986238 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/content_provider/pre.yml@main]
2025-12-01 11:01:27.570010 | 
2025-12-01 11:01:27.570148 | PLAY [Run ci/playbooks/content_provider/pre.yml]
2025-12-01 11:01:27.592872 | 
2025-12-01 11:01:27.592997 | TASK [Filter out host if needed]
2025-12-01 11:01:27.601930 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-12-01 11:01:27.606442 | 
2025-12-01 11:01:27.606503 | TASK [Clone repos in the job workspace]
2025-12-01 11:01:27.645601 | controller | ok
2025-12-01 11:01:27.661411 | 
2025-12-01 11:01:27.661479 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-12-01 11:01:28.059105 | controller | ok
2025-12-01 11:01:28.064926 | 
2025-12-01 11:01:28.064990 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-12-01 11:01:30.899779 | controller | Output suppressed because no_log was given
2025-12-01 11:01:30.919831 | 
2025-12-01 11:01:30.919972 | TASK [Install ansible-core]
2025-12-01 11:01:47.380708 | controller | changed
2025-12-01 11:01:47.398284 | 
2025-12-01 11:01:47.398423 | TASK [Discover an IPv4 for provider job]
2025-12-01 11:01:47.453461 | controller | ok
2025-12-01 11:01:47.479427 | 
2025-12-01 11:01:47.479549 | PLAY RECAP
2025-12-01 11:01:47.479599 | controller | ok: 4 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-12-01 11:01:47.479624 | 
2025-12-01 11:01:47.601239 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/content_provider/pre.yml@main]
2025-12-01 11:01:47.602080 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/e2e-prepare.yml@main]
2025-12-01 11:01:48.222074 | 
2025-12-01 11:01:48.222190 | PLAY [Run ci/playbooks/e2e-prepare.yml]
2025-12-01 11:01:48.244055 | 
2025-12-01 11:01:48.244169 | TASK [Clone repos in the job workspace]
2025-12-01 11:01:48.265037 | controller | ok
2025-12-01 11:01:48.281452 | 
2025-12-01 11:01:48.281559 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-12-01 11:01:48.677351 | controller | ok
2025-12-01 11:01:48.684012 | 
2025-12-01 11:01:48.684143 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-12-01 11:01:49.754419 | controller | Output suppressed because no_log was given
2025-12-01 11:01:49.776479 | 
2025-12-01 11:01:49.776618 | TASK [Create zuul-output directory]
2025-12-01 11:01:50.186994 | controller | ok
2025-12-01 11:01:50.194641 | 
2025-12-01 11:01:50.194792 | TASK [Install required packages]
2025-12-01 11:03:01.720608 | controller | changed
2025-12-01 11:03:01.728727 | 
2025-12-01 11:03:01.728837 | TASK [Output pip related things]
2025-12-01 11:03:02.381200 | controller | pip 21.3.1 from /usr/lib/python3.9/site-packages/pip (python 3.9)
2025-12-01 11:03:02.771403 | controller | changed
2025-12-01 11:03:02.784826 | 
2025-12-01 11:03:02.784996 | TASK [Install requirements]
2025-12-01 11:04:19.038449 | controller | changed
2025-12-01 11:04:19.059237 | 
2025-12-01 11:04:19.059434 | TASK [Add cronjob to trigger job stats]
2025-12-01 11:04:19.095982 | controller | skipping: Conditional result was False
2025-12-01 11:04:19.105804 | 
2025-12-01 11:04:19.105950 | LOOP [Construct project change list]
2025-12-01 11:04:19.168930 | controller | ok:
2025-12-01 11:04:19.169199 | controller | {
2025-12-01 11:04:19.169254 | controller |   "branch": "main",
2025-12-01 11:04:19.169295 | controller |   "change": "3523",
2025-12-01 11:04:19.169333 | controller |   "change_message": "[cifmw_helpers] Add another task to parse inventory file\n\nIn some cases, especially when ansible-inventory tool was used to convert the ini inventory into the yaml, parsing the inventory file was just skipped.\r\nThis commit adds another task that is properly parsing the inventory file.",
2025-12-01 11:04:19.169380 | controller |   "change_url": "https://github.com/openstack-k8s-operators/ci-framework/pull/3523",
2025-12-01 11:04:19.169419 | controller |   "commit_id": "9dca7030e1689ab350d0cc525f1944d26503b3cc",
2025-12-01 11:04:19.169454 | controller |   "patchset": "9dca7030e1689ab350d0cc525f1944d26503b3cc",
2025-12-01 11:04:19.169489 | controller |   "project": {
2025-12-01 11:04:19.169523 | controller |     "canonical_hostname": "github.com",
2025-12-01 11:04:19.169557 | controller |     "canonical_name": "github.com/openstack-k8s-operators/ci-framework",
2025-12-01 11:04:19.169590 | controller |     "name": "openstack-k8s-operators/ci-framework",
2025-12-01 11:04:19.169623 | controller |     "short_name": "ci-framework",
2025-12-01 11:04:19.169657 | controller |     "src_dir": "src/github.com/openstack-k8s-operators/ci-framework"
2025-12-01 11:04:19.169691 | controller |   },
2025-12-01 11:04:19.169756 | controller |   "topic": null
2025-12-01 11:04:19.169818 | controller | }
2025-12-01 11:04:19.180680 | controller | ok:
2025-12-01 11:04:19.180852 | controller | {
2025-12-01 11:04:19.180904 | controller |   "branch": "main",
2025-12-01 11:04:19.180942 | controller |   "change": "3505",
2025-12-01 11:04:19.180976 | controller |   "change_message": "WIP Prepare cifmw to run with Zironic\n\nThe Zironic tool would pre-deploy OCP cluster before CI job will \"pick\" the baremetal host.\r\n\r\nDepends-On: https://github.com/openstack-k8s-operators/ci-framework/pull/3523",
2025-12-01 11:04:19.181011 | controller |   "change_url": "https://github.com/openstack-k8s-operators/ci-framework/pull/3505",
2025-12-01 11:04:19.181045 | controller |   "commit_id": "4fd568413e9fbcb9b5c62cc50f536ea9eafcd481",
2025-12-01 11:04:19.181079 | controller |   "patchset": "4fd568413e9fbcb9b5c62cc50f536ea9eafcd481",
2025-12-01 11:04:19.181111 | controller |   "project": {
2025-12-01 11:04:19.181144 | controller |     "canonical_hostname": "github.com",
2025-12-01 11:04:19.181177 | controller |     "canonical_name": "github.com/openstack-k8s-operators/ci-framework",
2025-12-01 11:04:19.181212 | controller |     "name": "openstack-k8s-operators/ci-framework",
2025-12-01 11:04:19.181246 | controller |     "short_name": "ci-framework",
2025-12-01 11:04:19.181280 | controller |     "src_dir": "src/github.com/openstack-k8s-operators/ci-framework"
2025-12-01 11:04:19.181326 | controller |   },
2025-12-01 11:04:19.181379 | controller |   "topic": null
2025-12-01 11:04:19.181432 | controller | }
2025-12-01 11:04:19.241288 | 
2025-12-01 11:04:19.241424 | PLAY [Run ci/playbooks/build_runner_image.yml]
2025-12-01 11:04:19.261927 | 
2025-12-01 11:04:19.262015 | TASK [Filter out host if needed]
2025-12-01 11:04:19.276578 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-12-01 11:04:19.283851 | 
2025-12-01 11:04:19.283951 | TASK [Get git tag for image tagging]
2025-12-01 11:04:19.822197 | controller | skipping: Conditional result was False
2025-12-01 11:04:19.835124 | 
2025-12-01 11:04:19.835292 | TASK [Set openstack-runner image]
2025-12-01 11:04:19.864676 | controller | skipping: Conditional result was False
2025-12-01 11:04:19.879078 | 
2025-12-01 11:04:19.879226 | TASK [Build openstack-runner image]
2025-12-01 11:04:19.957561 | controller | skipping: Conditional result was False
2025-12-01 11:04:19.994059 | 
2025-12-01 11:04:19.994166 | TASK [Push openstack-runner image]
2025-12-01 11:04:20.028199 | controller | skipping: Conditional result was False
2025-12-01 11:04:20.039052 | 
2025-12-01 11:04:20.039167 | TASK [Make sure ci-framework directory exists]
2025-12-01 11:04:20.065154 | controller | skipping: Conditional result was False
2025-12-01 11:04:20.075996 | 
2025-12-01 11:04:20.076122 | TASK [Create EDPM ansible var file]
2025-12-01 11:04:20.101214 | controller | skipping: Conditional result was False
2025-12-01 11:04:20.132973 | 
2025-12-01 11:04:20.133101 | PLAY RECAP
2025-12-01 11:04:20.133157 | controller | ok: 7 changed: 3 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0
2025-12-01 11:04:20.133186 | 
2025-12-01 11:04:20.262565 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/e2e-prepare.yml@main]
2025-12-01 11:04:20.264456 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-12-01 11:04:20.905865 | 
2025-12-01 11:04:20.906029 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-12-01 11:04:20.928207 | 
2025-12-01 11:04:20.928368 | TASK [Create zuul-output directory]
2025-12-01 11:04:21.439484 | controller | changed
2025-12-01 11:04:21.444972 | 
2025-12-01 11:04:21.445047 | TASK [Slurp Zuul inventory test]
2025-12-01 11:04:21.827159 | controller -> localhost | ok
2025-12-01 11:04:21.835907 | 
2025-12-01 11:04:21.836050 | TASK [Save zuul inventory]
2025-12-01 11:04:22.814687 | controller | changed
2025-12-01 11:04:22.820939 | 
2025-12-01 11:04:22.821070 | TASK [Save zuul vars without the change_message]
2025-12-01 11:04:24.107742 | controller | changed
2025-12-01 11:04:24.142096 | 
2025-12-01 11:04:24.142324 | PLAY RECAP
2025-12-01 11:04:24.142431 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-12-01 11:04:24.142480 | 
2025-12-01 11:04:24.265143 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-12-01 11:04:24.266982 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/tcib/run.yml@main]
2025-12-01 11:04:24.888704 | 
2025-12-01 11:04:24.888840 | PLAY [Run ci/playbooks/tcib/run.yml]
2025-12-01 11:04:24.910008 | 
2025-12-01 11:04:24.910142 | TASK [Filter out host if needed]
2025-12-01 11:04:24.919028 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-12-01 11:04:24.923785 | 
2025-12-01 11:04:24.923847 | TASK [Discover the host ip]
2025-12-01 11:04:24.963137 | controller | ok
2025-12-01 11:04:24.969143 | 
2025-12-01 11:04:24.969205 | TASK [Run tcib playbook]
2025-12-01 11:04:26.122311 | controller |
2025-12-01 11:04:27.125426 | controller | PLAY [Bootstrap playbook] ******************************************************
2025-12-01 11:04:27.125564 | controller |
2025-12-01 11:04:27.125574 | controller | TASK [Gathering Facts ] ********************************************************
2025-12-01 11:04:27.125582 | controller | Monday 01 December 2025  11:04:26 +0000 (0:00:00.036)       0:00:00.036 *******
2025-12-01 11:04:27.125589 | controller | Monday 01 December 2025  11:04:26 +0000 (0:00:00.034)       0:00:00.034 *******
2025-12-01 11:04:27.125609 | controller | ok: [localhost]
2025-12-01 11:04:27.162766 | controller |
2025-12-01 11:04:27.162824 | controller | TASK [cifmw_setup : Set custom cifmw PATH reusable fact cifmw_path={{ ansible_user_dir }}/.crc/bin:{{ ansible_user_dir }}/.crc/bin/oc:{{ ansible_user_dir }}/bin:{{ ansible_env.PATH }}, cacheable=True] ***
2025-12-01 11:04:27.162835 | controller | Monday 01 December 2025  11:04:27 +0000 (0:00:01.003)       0:00:01.039 *******
2025-12-01 11:04:27.162842 | controller | Monday 01 December 2025  11:04:27 +0000 (0:00:01.003)       0:00:01.038 *******
2025-12-01 11:04:27.162857 | controller | ok: [localhost]
2025-12-01 11:04:27.218719 | controller |
2025-12-01 11:04:27.218795 | controller | TASK [cifmw_setup : Get customized parameters ci_framework_params={{
2025-12-01 11:04:27.218805 | controller |   hostvars[inventory_hostname] |
2025-12-01 11:04:27.218822 | controller |   dict2items |
2025-12-01 11:04:27.218830 | controller |   selectattr("key", "match",
2025-12-01 11:04:27.218837 | controller |              "^(cifmw|pre|post)_(?!install_yamls|openshift_token|openshift_login|openshift_kubeconfig).*") |
2025-12-01 11:04:27.218845 | controller |   list | items2dict
2025-12-01 11:04:27.218853 | controller | }}] ***
2025-12-01 11:04:27.218860 | controller | Monday 01 December 2025  11:04:27 +0000 (0:00:00.037)       0:00:01.076 *******
2025-12-01 11:04:27.218868 | controller | Monday 01 December 2025  11:04:27 +0000 (0:00:00.037)       0:00:01.075 *******
2025-12-01 11:04:27.218888 | controller | ok: [localhost]
2025-12-01 11:04:27.511659 | controller |
2025-12-01 11:04:27.511782 | controller | TASK [install_ca : Ensure target directory exists path={{ cifmw_install_ca_trust_dir }}, state=directory, mode=0755] ***
2025-12-01 11:04:27.511794 | controller | Monday 01 December 2025  11:04:27 +0000 (0:00:00.055)       0:00:01.132 *******
2025-12-01 11:04:27.511803 | controller | Monday 01 December 2025  11:04:27 +0000 (0:00:00.055)       0:00:01.131 *******
2025-12-01 11:04:27.511832 | controller | ok: [localhost]
2025-12-01 11:04:27.537156 | controller |
2025-12-01 11:04:27.537311 | controller | TASK [install_ca : Install internal CA from url url={{ cifmw_install_ca_url }}, dest={{ cifmw_install_ca_trust_dir }}, validate_certs={{ cifmw_install_ca_url_validate_certs | default(omit) }}, mode=0644] ***
2025-12-01 11:04:27.537335 | controller | Monday 01 December 2025  11:04:27 +0000 (0:00:00.292)       0:00:01.425 *******
2025-12-01 11:04:27.537354 | controller | Monday 01 December 2025  11:04:27 +0000 (0:00:00.292)       0:00:01.424 *******
2025-12-01 11:04:27.537420 | controller | skipping: [localhost]
2025-12-01 11:04:27.560483 | controller |
2025-12-01 11:04:27.560614 | controller | TASK [install_ca : Install custom CA bundle from inline dest={{ cifmw_install_ca_trust_dir }}/cifmw_inline_ca_bundle.crt, content={{ cifmw_install_ca_bundle_inline }}, mode=0644] ***
2025-12-01 11:04:27.560632 | controller | Monday 01 December 2025  11:04:27 +0000 (0:00:00.025)       0:00:01.451 *******
2025-12-01 11:04:27.560647 | controller | Monday 01 December 2025  11:04:27 +0000 (0:00:00.025)       0:00:01.449 *******
2025-12-01 11:04:27.560683 | controller | skipping: [localhost]
2025-12-01 11:04:27.583613 | controller |
2025-12-01 11:04:27.583742 | controller | TASK [install_ca : Install custom CA bundle from file dest={{ cifmw_install_ca_trust_dir }}/{{ cifmw_install_ca_bundle_src | basename }}, src={{ cifmw_install_ca_bundle_src }}, mode=0644] ***
2025-12-01 11:04:27.583758 | controller | Monday 01 December 2025  11:04:27 +0000 (0:00:00.023)       0:00:01.474 *******
2025-12-01 11:04:27.583768 | controller | Monday 01 December 2025  11:04:27 +0000 (0:00:00.023)       0:00:01.473 *******
2025-12-01 11:04:27.583798 | controller | skipping: [localhost]
2025-12-01 11:04:30.026169 | controller |
2025-12-01 11:04:30.026290 | controller | TASK [install_ca : Update ca bundle _raw_params=update-ca-trust] ***************
2025-12-01 11:04:30.026299 | controller | Monday 01 December 2025  11:04:27 +0000 (0:00:00.023)       0:00:01.497 *******
2025-12-01 11:04:30.026335 | controller | Monday 01 December 2025  11:04:27 +0000 (0:00:00.023)       0:00:01.496 *******
2025-12-01 11:04:30.026360 | controller | changed: [localhost]
2025-12-01 11:04:30.356122 | controller |
2025-12-01 11:04:30.356261 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory paths=/etc/yum.repos.d/, patterns=*.repo, recurse=False] ***
2025-12-01 11:04:30.356281 | controller | Monday 01 December 2025  11:04:30 +0000 (0:00:02.441)       0:00:03.939 *******
2025-12-01 11:04:30.356310 | controller | Monday 01 December 2025  11:04:30 +0000 (0:00:02.441)       0:00:03.938 *******
2025-12-01 11:04:30.356347 | controller | ok: [localhost]
2025-12-01 11:04:30.752142 | controller |
2025-12-01 11:04:30.752197 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory path={{ item }}, state=absent] ***
2025-12-01 11:04:30.752205 | controller | Monday 01 December 2025  11:04:30 +0000 (0:00:00.330)       0:00:04.270 *******
2025-12-01 11:04:30.752212 | controller | Monday 01 December 2025  11:04:30 +0000 (0:00:00.330)       0:00:04.268 *******
2025-12-01 11:04:30.752225 | controller | changed: [localhost] => (item=/etc/yum.repos.d/centos-addons.repo)
2025-12-01 11:04:31.244660 | controller | changed: [localhost] => (item=/etc/yum.repos.d/centos.repo)
2025-12-01 11:04:31.244727 | controller |
2025-12-01 11:04:31.244737 | controller | TASK [repo_setup : Cleanup existing metadata _raw_params=dnf clean metadata] ***
2025-12-01 11:04:31.244745 | controller | Monday 01 December 2025  11:04:30 +0000 (0:00:00.395)       0:00:04.665 *******
2025-12-01 11:04:31.244753 | controller | Monday 01 December 2025  11:04:30 +0000 (0:00:00.395)       0:00:04.664 *******
2025-12-01 11:04:31.244772 | controller | changed: [localhost]
2025-12-01 11:04:31.615603 | controller |
2025-12-01 11:04:31.615664 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory mode=0755, remote_src=True, src={{ cifmw_repo_setup_output }}/, dest=/etc/yum.repos.d] ***
2025-12-01 11:04:31.615681 | controller | Monday 01 December 2025  11:04:31 +0000 (0:00:00.493)       0:00:05.158 *******
2025-12-01 11:04:31.615695 | controller | Monday 01 December 2025  11:04:31 +0000 (0:00:00.493)       0:00:05.157 *******
2025-12-01 11:04:31.615718 | controller | task path: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/repo_setup/tasks/sync_repos.yml:23
2025-12-01 11:04:31.686752 | controller | fatal: [localhost]: FAILED! =>
2025-12-01 11:04:31.686830 | controller |     changed: false
2025-12-01 11:04:31.686963 | controller |     msg: Source /home/zuul/ci-framework-data/artifacts/repositories/ not found
2025-12-01 11:04:31.687009 | controller |
2025-12-01 11:04:31.687030 | controller | NO MORE HOSTS LEFT *************************************************************
2025-12-01 11:04:31.687044 | controller |
2025-12-01 11:04:31.687057 | controller | PLAY RECAP *********************************************************************
2025-12-01 11:04:31.687072 | controller | localhost                  : ok=8    changed=3    unreachable=0    failed=1    skipped=3    rescued=0    ignored=0
2025-12-01 11:04:31.687086 | controller |
2025-12-01 11:04:31.687099 | controller | Monday 01 December 2025  11:04:31 +0000 (0:00:00.370)       0:00:05.529 *******
2025-12-01 11:04:31.687113 | controller | ===============================================================================
2025-12-01 11:04:31.687126 | controller | install_ca : Update ca bundle ------------------------------------------- 2.44s
2025-12-01 11:04:31.687140 | controller | Gathering Facts --------------------------------------------------------- 1.00s
2025-12-01 11:04:31.687153 | controller | repo_setup : Cleanup existing metadata ---------------------------------- 0.49s
2025-12-01 11:04:31.687167 | controller | repo_setup : Remove existing repos from /etc/yum.repos.d directory ------ 0.40s
2025-12-01 11:04:31.687180 | controller | repo_setup : Copy generated repos to /etc/yum.repos.d directory --------- 0.37s
2025-12-01 11:04:31.687193 | controller | repo_setup : Find existing repos from /etc/yum.repos.d directory -------- 0.33s
2025-12-01 11:04:31.687207 | controller | install_ca : Ensure target directory exists ----------------------------- 0.29s
2025-12-01 11:04:31.687220 | controller | cifmw_setup : Get customized parameters --------------------------------- 0.06s
2025-12-01 11:04:31.687233 | controller | cifmw_setup : Set custom cifmw PATH reusable fact ----------------------- 0.04s
2025-12-01 11:04:31.687247 | controller | install_ca : Install internal CA from url ------------------------------- 0.03s
2025-12-01 11:04:31.687280 | controller | install_ca : Install custom CA bundle from inline ----------------------- 0.02s
2025-12-01 11:04:31.687294 | controller | install_ca : Install custom CA bundle from file ------------------------- 0.02s
2025-12-01 11:04:31.687308 | controller | Monday 01 December 2025  11:04:31 +0000 (0:00:00.371)       0:00:05.528 *******
2025-12-01 11:04:31.687322 | controller | ===============================================================================
2025-12-01 11:04:31.687335 | controller | install_ca -------------------------------------------------------------- 2.81s
2025-12-01 11:04:31.687348 | controller | repo_setup -------------------------------------------------------------- 1.59s
2025-12-01 11:04:31.687362 | controller | gather_facts ------------------------------------------------------------ 1.00s
2025-12-01 11:04:31.687375 | controller | cifmw_setup ------------------------------------------------------------- 0.09s
2025-12-01 11:04:31.687388 | controller | ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
2025-12-01 11:04:31.687402 | controller | total ------------------------------------------------------------------- 5.49s
2025-12-01 11:04:32.011278 | controller | ERROR
2025-12-01 11:04:32.011628 | controller | {
2025-12-01 11:04:32.011691 | controller |   "delta": "0:00:06.339034",
2025-12-01 11:04:32.011772 | controller |   "end": "2025-12-01 11:04:31.687744",
2025-12-01 11:04:32.011814 | controller |   "msg": "non-zero return code",
2025-12-01 11:04:32.011851 | controller |   "rc": 2,
2025-12-01 11:04:32.011895 | controller |   "start": "2025-12-01 11:04:25.348710"
2025-12-01 11:04:32.011931 | controller | }
failure
2025-12-01 11:04:32.016836 | 
2025-12-01 11:04:32.016953 | PLAY RECAP
2025-12-01 11:04:32.017039 | controller | ok: 1 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2025-12-01 11:04:32.017084 | 
2025-12-01 11:04:32.149257 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/tcib/run.yml@main]
2025-12-01 11:04:32.150100 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-12-01 11:04:32.763873 | 
2025-12-01 11:04:32.763985 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-12-01 11:04:32.785230 | 
2025-12-01 11:04:32.785338 | TASK [Filter out host if needed]
2025-12-01 11:04:32.794521 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-12-01 11:04:32.799120 | 
2025-12-01 11:04:32.799184 | TASK [Ensure file is present]
2025-12-01 11:04:33.268157 | controller | ok
2025-12-01 11:04:33.282835 | 
2025-12-01 11:04:33.282983 | TASK [Manage molecule report file]
2025-12-01 11:04:33.824228 | controller | skipping: Conditional result was False
2025-12-01 11:04:33.838073 | 
2025-12-01 11:04:33.838271 | TASK [Check if we get ci-framework-data basedir]
2025-12-01 11:04:34.069221 | controller | ok
2025-12-01 11:04:34.077002 | 
2025-12-01 11:04:34.077082 | TASK [Create ci-framework-data log directory for zuul]
2025-12-01 11:04:34.507398 | controller | changed
2025-12-01 11:04:34.515719 | 
2025-12-01 11:04:34.515862 | TASK [Copy ci-framework interesting files]
2025-12-01 11:04:34.932178 | controller | cp: cannot stat '/home/zuul/ci-framework-data/logs': No such file or directory
2025-12-01 11:04:34.937897 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2025-12-01 11:04:35.073814 | controller | changed
2025-12-01 11:04:35.085654 | 
2025-12-01 11:04:35.085848 | TASK [Get SELinux listing]
2025-12-01 11:04:35.639448 | controller | changed
2025-12-01 11:04:35.652619 | 
2025-12-01 11:04:35.652860 | TASK [Generate log index]
2025-12-01 11:04:36.469235 | controller | changed
2025-12-01 11:04:36.474547 | 
2025-12-01 11:04:36.474621 | TASK [Get some env related data]
2025-12-01 11:04:37.140049 | controller | /home/zuul/.local/bin/ansible
2025-12-01 11:04:38.007828 | controller | changed
2025-12-01 11:04:38.015947 | 
2025-12-01 11:04:38.016025 | TASK [Generate list of logs to collect in home directory]
2025-12-01 11:04:38.524573 | controller | ok: All paths examined
2025-12-01 11:04:38.538580 | 
2025-12-01 11:04:38.538847 | LOOP [Copy logs from home directory]
2025-12-01 11:04:38.959409 | controller | changed:
2025-12-01 11:04:38.959566 | controller | {
2025-12-01 11:04:38.959603 | controller |   "atime": 1764587039.173741,
2025-12-01 11:04:38.959631 | controller |   "ctime": 1764587071.6146455,
2025-12-01 11:04:38.959656 | controller |   "dev": 64513,
2025-12-01 11:04:38.959681 | controller |   "gid": 1000,
2025-12-01 11:04:38.959706 | controller |   "gr_name": "zuul",
2025-12-01 11:04:38.959761 | controller |   "inode": 4334511,
2025-12-01 11:04:38.959788 | controller |   "isblk": false,
2025-12-01 11:04:38.959812 | controller |   "ischr": false,
2025-12-01 11:04:38.959836 | controller |   "isdir": false,
2025-12-01 11:04:38.959860 | controller |   "isfifo": false,
2025-12-01 11:04:38.959882 | controller |   "isgid": false,
2025-12-01 11:04:38.959905 | controller |   "islnk": false,
2025-12-01 11:04:38.959928 | controller |   "isreg": true,
2025-12-01 11:04:38.959950 | controller |   "issock": false,
2025-12-01 11:04:38.959973 | controller |   "isuid": false,
2025-12-01 11:04:38.959995 | controller |   "mode": "0644",
2025-12-01 11:04:38.960018 | controller |   "mtime": 1764587071.6146455,
2025-12-01 11:04:38.960041 | controller |   "nlink": 1,
2025-12-01 11:04:38.960065 | controller |   "path": "/home/zuul/ansible.log",
2025-12-01 11:04:38.960090 | controller |   "pw_name": "zuul",
2025-12-01 11:04:38.960114 | controller |   "rgrp": true,
2025-12-01 11:04:38.960137 | controller |   "roth": true,
2025-12-01 11:04:38.960161 | controller |   "rusr": true,
2025-12-01 11:04:38.960196 | controller |   "size": 16926,
2025-12-01 11:04:38.960220 | controller |   "uid": 1000,
2025-12-01 11:04:38.960242 | controller |   "wgrp": false,
2025-12-01 11:04:38.960268 | controller |   "woth": false,
2025-12-01 11:04:38.960292 | controller |   "wusr": true,
2025-12-01 11:04:38.960316 | controller |   "xgrp": false,
2025-12-01 11:04:38.960339 | controller |   "xoth": false,
2025-12-01 11:04:38.960362 | controller |   "xusr": false
2025-12-01 11:04:38.960384 | controller | }
2025-12-01 11:04:38.975717 | 
2025-12-01 11:04:38.975894 | TASK [Copy crio stats log file]
2025-12-01 11:04:39.010400 | controller | skipping: Conditional result was False
2025-12-01 11:04:39.015998 | 
2025-12-01 11:04:39.016130 | TASK [Get SELinux related data]
2025-12-01 11:04:39.240467 | controller | <no matches>
2025-12-01 11:04:39.547088 | controller | ERROR
2025-12-01 11:04:39.547357 | controller | {
2025-12-01 11:04:39.547390 | controller |   "delta": "0:00:00.014370",
2025-12-01 11:04:39.547409 | controller |   "end": "2025-12-01 11:04:39.242754",
2025-12-01 11:04:39.547427 | controller |   "msg": "non-zero return code",
2025-12-01 11:04:39.547442 | controller |   "rc": 1,
2025-12-01 11:04:39.547459 | controller |   "start": "2025-12-01 11:04:39.228384"
2025-12-01 11:04:39.547474 | controller | }
2025-12-01 11:04:39.547497 | controller | ERROR: Ignoring Errors
2025-12-01 11:04:39.553043 | 
2025-12-01 11:04:39.553144 | TASK [Create system configuration directory]
2025-12-01 11:04:39.786194 | controller | changed
2025-12-01 11:04:39.791580 | 
2025-12-01 11:04:39.791706 | TASK [Get some of the system configurations]
2025-12-01 11:04:40.323099 | controller | changed
2025-12-01 11:04:40.337525 | 
2025-12-01 11:04:40.337767 | TASK [Copy generated documentation if available]
2025-12-01 11:04:40.355332 | controller | skipping: Conditional result was False
2025-12-01 11:04:40.369305 | 
2025-12-01 11:04:40.369492 | TASK [Copy generated AsciiDoc documentation if available]
2025-12-01 11:04:40.400497 | controller | skipping: Conditional result was False
2025-12-01 11:04:40.410193 | 
2025-12-01 11:04:40.410305 | TASK [Compress logs bigger than 2MB]
2025-12-01 11:04:40.951549 | controller | changed
2025-12-01 11:04:40.959613 | 
2025-12-01 11:04:40.959758 | TASK [Copy files from workspace on node]
2025-12-01 11:04:40.983102 | controller | ok
2025-12-01 11:04:41.017467 | 
2025-12-01 11:04:41.017560 | TASK [fetch-output : Set log path for multiple nodes]
2025-12-01 11:04:41.052749 | controller | skipping: Conditional result was False
2025-12-01 11:04:41.058948 | 
2025-12-01 11:04:41.059029 | TASK [fetch-output : Set log path for single node]
2025-12-01 11:04:41.099376 | controller | ok
2025-12-01 11:04:41.104228 | 
2025-12-01 11:04:41.104291 | LOOP [fetch-output : Ensure local output dirs]
2025-12-01 11:04:41.344785 | controller -> localhost | ok: "/var/lib/zuul/builds/bf65e6278f824e108530af6c58a03f29/work/logs"
2025-12-01 11:04:41.345034 | controller -> localhost | changed: All items complete
2025-12-01 11:04:41.345066 | 
2025-12-01 11:04:41.623395 | controller -> localhost | changed: "/var/lib/zuul/builds/bf65e6278f824e108530af6c58a03f29/work/artifacts"
2025-12-01 11:04:41.849924 | controller -> localhost | changed: "/var/lib/zuul/builds/bf65e6278f824e108530af6c58a03f29/work/docs"
2025-12-01 11:04:41.873505 | 
2025-12-01 11:04:41.873685 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-12-01 11:04:42.556263 | controller | changed:
2025-12-01 11:04:42.556465 | controller | .d..t...... ./
2025-12-01 11:04:42.556492 | controller | >f+++++++++ README.html
2025-12-01 11:04:42.556513 | controller | >f+++++++++ ansible.log
2025-12-01 11:04:42.556533 | controller | >f+++++++++ dmesg.log
2025-12-01 11:04:42.556553 | controller | >f+++++++++ installed-pkgs.log
2025-12-01 11:04:42.556575 | controller | >f+++++++++ python.log
2025-12-01 11:04:42.556598 | controller | >f+++++++++ registries.conf
2025-12-01 11:04:42.556617 | controller | >f+++++++++ selinux-denials.log
2025-12-01 11:04:42.556639 | controller | >f+++++++++ selinux-listing.log
2025-12-01 11:04:42.556668 | controller | cd+++++++++ ci-framework-data/
2025-12-01 11:04:42.556688 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-12-01 11:04:42.556707 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-12-01 11:04:42.556740 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-12-01 11:04:42.556762 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-12-01 11:04:42.556780 | controller | cd+++++++++ registries.conf.d/
2025-12-01 11:04:42.556798 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-12-01 11:04:42.556815 | controller | cd+++++++++ system-config/
2025-12-01 11:04:42.556832 | controller | cd+++++++++ system-config/libvirt/
2025-12-01 11:04:42.556848 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-12-01 11:04:42.556865 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-12-01 11:04:43.089158 | controller | changed: .d..t...... ./
2025-12-01 11:04:43.536907 | controller | changed: .d..t...... ./
2025-12-01 11:04:43.549851 | 
2025-12-01 11:04:43.550011 | TASK [Return artifact to Zuul]
2025-12-01 11:04:43.574179 | controller | skipping: Conditional result was False
2025-12-01 11:04:43.596955 | 
2025-12-01 11:04:43.597065 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-12-01 11:04:43.597168 | 
2025-12-01 11:04:43.597196 | PLAY RECAP
2025-12-01 11:04:43.597233 | controller | ok: 16 changed: 12 unreachable: 0 failed: 0 skipped: 6 rescued: 0 ignored: 1
2025-12-01 11:04:43.597253 | 
2025-12-01 11:04:43.751486 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-12-01 11:04:43.752692 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/dlrn/dlrn-report.yaml@master]
2025-12-01 11:04:44.319715 | 
2025-12-01 11:04:44.319861 | PLAY [Report DLRN results after job run]
2025-12-01 11:04:44.341564 | 
2025-12-01 11:04:44.341687 | TASK [Report job status to DLRN]
2025-12-01 11:04:44.361824 | controller | ok
2025-12-01 11:04:44.386025 | 
2025-12-01 11:04:44.386130 | TASK [dlrn_report : Warning if DLRN is not running]
2025-12-01 11:04:44.450907 | controller | skipping: Conditional result was False
2025-12-01 11:04:44.457269 | 
2025-12-01 11:04:44.457349 | TASK [dlrn_report : Install dlrnapi-client shyaml package]
2025-12-01 11:04:44.511793 | controller | skipping: Conditional result was False
2025-12-01 11:04:44.525694 | 
2025-12-01 11:04:44.525891 | TASK [dlrn_report : Install kinit related package]
2025-12-01 11:04:44.603516 | controller | skipping: Conditional result was False
2025-12-01 11:04:44.617240 | 
2025-12-01 11:04:44.617391 | TASK [dlrn_report : Install dlrn kerberos related packages]
2025-12-01 11:04:44.684797 | controller | skipping: Conditional result was False
2025-12-01 11:04:44.692348 | 
2025-12-01 11:04:44.692442 | TASK [Set zuul-log-path fact]
2025-12-01 11:04:44.737467 | controller | skipping: Conditional result was False
2025-12-01 11:04:44.743844 | 
2025-12-01 11:04:44.743917 | TASK [dlrn_report : Set the the value of cifmw_repo_setup_promotion when multiple dlrn tags are used]
2025-12-01 11:04:44.788324 | controller | skipping: Conditional result was False
2025-12-01 11:04:44.794140 | 
2025-12-01 11:04:44.794222 | TASK [Get hash related data from repo_setup role]
2025-12-01 11:04:44.839143 | controller | skipping: Conditional result was False
2025-12-01 11:04:44.852305 | 
2025-12-01 11:04:44.852444 | TASK [dlrn_report : Perform kinit for DLRN kerberos authentication]
2025-12-01 11:04:45.404706 | controller | skipping: Conditional result was False
2025-12-01 11:04:45.417683 | 
2025-12-01 11:04:45.417877 | TASK [dlrn_report : Set empty value for dlrnapi password]
2025-12-01 11:04:45.485948 | controller | skipping: Conditional result was False
2025-12-01 11:04:45.499465 | 
2025-12-01 11:04:45.499647 | TASK [dlrn_report : Report results to dlrn for the tested hash]
2025-12-01 11:04:46.051314 | controller | skipping: Conditional result was False
2025-12-01 11:04:46.109283 | 
2025-12-01 11:04:46.109405 | PLAY RECAP
2025-12-01 11:04:46.109462 | controller | ok: 0 changed: 0 unreachable: 0 failed: 0 skipped: 10 rescued: 0 ignored: 0
2025-12-01 11:04:46.109494 | 
2025-12-01 11:04:46.231679 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/dlrn/dlrn-report.yaml@master]
2025-12-01 11:04:46.232506 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-12-01 11:04:46.845012 | 
2025-12-01 11:04:46.845123 | PLAY [all]
2025-12-01 11:04:46.864844 | 
2025-12-01 11:04:46.864957 | TASK [include_role : fetch-output]
2025-12-01 11:04:46.907661 | controller | ok
2025-12-01 11:04:46.929962 | 
2025-12-01 11:04:46.930087 | TASK [fetch-output : Set log path for multiple nodes]
2025-12-01 11:04:46.995289 | controller | skipping: Conditional result was False
2025-12-01 11:04:47.007532 | 
2025-12-01 11:04:47.007685 | TASK [fetch-output : Set log path for single node]
2025-12-01 11:04:47.054629 | controller | ok
2025-12-01 11:04:47.066160 | 
2025-12-01 11:04:47.066312 | LOOP [fetch-output : Ensure local output dirs]
2025-12-01 11:04:47.470572 | controller -> localhost | ok: "/var/lib/zuul/builds/bf65e6278f824e108530af6c58a03f29/work/logs"
2025-12-01 11:04:47.706031 | controller -> localhost | ok: "/var/lib/zuul/builds/bf65e6278f824e108530af6c58a03f29/work/artifacts"
2025-12-01 11:04:48.000971 | controller -> localhost | ok: "/var/lib/zuul/builds/bf65e6278f824e108530af6c58a03f29/work/docs"
2025-12-01 11:04:48.018298 | 
2025-12-01 11:04:48.018474 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-12-01 11:04:48.722885 | controller | ok
2025-12-01 11:04:48.723253 | controller | ok: All items complete
2025-12-01 11:04:48.723334 | 
2025-12-01 11:04:49.221793 | controller | ok
2025-12-01 11:04:49.688085 | controller | ok
2025-12-01 11:04:49.723346 | 
2025-12-01 11:04:49.723554 | TASK [include_role : fetch-output-openshift]
2025-12-01 11:04:49.750162 | controller | skipping: Conditional result was False
2025-12-01 11:04:49.759065 | 
2025-12-01 11:04:49.759172 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-12-01 11:04:50.184298 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.008422
2025-12-01 11:04:50.474148 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.009515
2025-12-01 11:04:50.511071 | 
2025-12-01 11:04:50.511185 | PLAY [all]
2025-12-01 11:04:50.525399 | 
2025-12-01 11:04:50.525472 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-12-01 11:04:51.029055 | controller | changed
2025-12-01 11:04:51.069588 | 
2025-12-01 11:04:51.069719 | PLAY RECAP
2025-12-01 11:04:51.069801 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-12-01 11:04:51.069832 | 
2025-12-01 11:04:51.191400 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-12-01 11:04:51.192189 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-12-01 11:04:51.832811 | 
2025-12-01 11:04:51.832936 | PLAY [localhost]
2025-12-01 11:04:51.852181 | 
2025-12-01 11:04:51.852314 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-12-01 11:04:52.289087 | localhost | changed
2025-12-01 11:04:52.293716 | 
2025-12-01 11:04:52.293812 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-12-01 11:04:52.332165 | localhost | ok
2025-12-01 11:04:52.340091 | 
2025-12-01 11:04:52.340172 | TASK [add-fileserver : Create SSH private key tempfile]
2025-12-01 11:04:52.766256 | localhost | changed
2025-12-01 11:04:52.771191 | 
2025-12-01 11:04:52.771259 | TASK [add-fileserver : Create SSH private key from secret]
2025-12-01 11:04:53.533797 | localhost | changed
2025-12-01 11:04:53.539060 | 
2025-12-01 11:04:53.539127 | TASK [add-fileserver : Add fileserver ssh key]
2025-12-01 11:04:54.005014 | localhost | Identity added: /var/lib/zuul/builds/bf65e6278f824e108530af6c58a03f29/work/tmp/ansible.3_fx13bg (/var/lib/zuul/builds/bf65e6278f824e108530af6c58a03f29/work/tmp/ansible.3_fx13bg)
2025-12-01 11:04:54.005206 | localhost | ok: Runtime: 0:00:00.013776
2025-12-01 11:04:54.009982 | 
2025-12-01 11:04:54.010099 | TASK [add-fileserver : Remove SSH private key from disk]
2025-12-01 11:04:54.315925 | localhost | ok: Runtime: 0:00:00.008393
2025-12-01 11:04:54.324240 | 
2025-12-01 11:04:54.324349 | TASK [add-fileserver : Add fileserver to inventory]
2025-12-01 11:04:54.406678 | localhost | changed
2025-12-01 11:04:54.413351 | 
2025-12-01 11:04:54.413444 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-12-01 11:04:54.846052 | localhost | changed
2025-12-01 11:04:54.887959 | 
2025-12-01 11:04:54.888112 | PLAY [localhost]
2025-12-01 11:04:54.906476 | 
2025-12-01 11:04:54.906598 | TASK [Generate bulk log download script]
2025-12-01 11:04:54.938868 | localhost | ok
2025-12-01 11:04:54.956027 | 
2025-12-01 11:04:54.956168 | TASK [local-log-download : Check API endpoint is defined]
2025-12-01 11:04:55.008219 | localhost | ok: All assertions passed
2025-12-01 11:04:55.015254 | 
2025-12-01 11:04:55.015407 | TASK [local-log-download : Create download script]
2025-12-01 11:04:55.541690 | localhost -> localhost | changed
2025-12-01 11:04:55.573550 | 
2025-12-01 11:04:55.573829 | TASK [Register quick-download link]
2025-12-01 11:04:55.670304 | localhost | ok
2025-12-01 11:04:55.687305 | 
2025-12-01 11:04:55.687416 | PLAY [logserver.rdoproject.org]
2025-12-01 11:04:55.698960 | 
2025-12-01 11:04:55.699138 | TASK [Set zuul-log-path fact]
2025-12-01 11:04:55.719934 | logserver.rdoproject.org | ok
2025-12-01 11:04:55.730033 | 
2025-12-01 11:04:55.730107 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-12-01 11:04:55.769008 | logserver.rdoproject.org | ok
2025-12-01 11:04:55.775434 | 
2025-12-01 11:04:55.775525 | TASK [upload-logs : Create log directories]
2025-12-01 11:04:56.449255 | logserver.rdoproject.org | changed
2025-12-01 11:04:56.452525 | 
2025-12-01 11:04:56.452594 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-12-01 11:04:56.797346 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.010677
2025-12-01 11:04:56.802319 | 
2025-12-01 11:04:56.802438 | TASK [upload-logs : Upload logs to log server]
2025-12-01 11:04:57.420082 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-12-01 11:04:57.423118 | 
2025-12-01 11:04:57.423182 | LOOP [upload-logs : Compress console log and json output]
2025-12-01 11:04:57.498271 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-01 11:04:57.510623 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-01 11:04:57.522680 | 
2025-12-01 11:04:57.522825 | LOOP [upload-logs : Upload compressed console log and json output]
2025-12-01 11:04:57.570537 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-01 11:04:57.571021 | 
2025-12-01 11:04:57.573534 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-01 11:04:57.581746 | 
2025-12-01 11:04:57.581888 | LOOP [upload-logs : Upload console log and json output]