2025-10-13 10:34:40.457478 | Job console starting...
2025-10-13 10:34:40.467014 | Updating repositories
2025-10-13 10:34:40.513785 | Preparing job workspace
2025-10-13 10:34:47.924033 | Running Ansible setup...
2025-10-13 10:34:52.052537 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-10-13 10:34:52.664183 | 
2025-10-13 10:34:52.664314 | PLAY [localhost]
2025-10-13 10:34:52.674305 | 
2025-10-13 10:34:52.674443 | TASK [Gathering Facts]
2025-10-13 10:34:53.675933 | localhost | ok
2025-10-13 10:34:53.695097 | 
2025-10-13 10:34:53.695246 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-10-13 10:34:54.070903 | localhost -> localhost | changed
2025-10-13 10:34:54.076229 | 
2025-10-13 10:34:54.076296 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-10-13 10:34:55.009254 | localhost -> localhost | changed
2025-10-13 10:34:55.017743 | 
2025-10-13 10:34:55.017811 | TASK [Setup log path fact]
2025-10-13 10:34:55.039593 | localhost | ok
2025-10-13 10:34:55.051111 | 
2025-10-13 10:34:55.051182 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-13 10:34:55.079299 | localhost | ok
2025-10-13 10:34:55.086911 | 
2025-10-13 10:34:55.086977 | TASK [emit-job-header : Print job information]
2025-10-13 10:34:55.125296 | # Job Information
2025-10-13 10:34:55.125451 | Ansible Version: 2.15.12
2025-10-13 10:34:55.125478 | Job: cifmw-tcib
2025-10-13 10:34:55.125514 | Pipeline: github-check
2025-10-13 10:34:55.125534 | Executor: ze04.softwarefactory-project.io
2025-10-13 10:34:55.125554 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3402
2025-10-13 10:34:55.125574 | Log URL (when completed): https://logserver.rdoproject.org/e1b/rdoproject.org/e1b10af8f1734a3ba1b89e7dd112fb3d/
2025-10-13 10:34:55.125593 | Event ID: c3280dc0-a81f-11f0-8d33-e350c072644d
2025-10-13 10:34:55.129341 | 
2025-10-13 10:34:55.129405 | LOOP [emit-job-header : Print node information]
2025-10-13 10:34:55.218732 | localhost | ok:
2025-10-13 10:34:55.218889 | localhost | # Node Information
2025-10-13 10:34:55.218915 | localhost | Inventory Hostname: controller
2025-10-13 10:34:55.218937 | localhost | Hostname: np0005483600
2025-10-13 10:34:55.218956 | localhost | Username: zuul
2025-10-13 10:34:55.218976 | localhost | Distro: CentOS 9
2025-10-13 10:34:55.218993 | localhost | Provider: vexxhost-nodepool-tripleo
2025-10-13 10:34:55.219010 | localhost | Region: RegionOne
2025-10-13 10:34:55.219026 | localhost | Label: cloud-centos-9-stream-tripleo-vexxhost
2025-10-13 10:34:55.219042 | localhost | Product Name: OpenStack Nova
2025-10-13 10:34:55.219058 | localhost | Interface IP: 38.102.83.200
2025-10-13 10:34:55.245294 | 
2025-10-13 10:34:55.245446 | PLAY [all]
2025-10-13 10:34:55.252890 | 
2025-10-13 10:34:55.252980 | TASK [Gather network facts]
2025-10-13 10:34:55.672154 | controller | ok
2025-10-13 10:34:55.698508 | 
2025-10-13 10:34:55.698645 | TASK [include_role : start-zuul-console]
2025-10-13 10:34:55.719839 | controller | ok
2025-10-13 10:34:55.731568 | 
2025-10-13 10:34:55.731648 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-10-13 10:34:56.116223 | controller | ok
2025-10-13 10:34:56.135015 | 
2025-10-13 10:34:56.135097 | TASK [include_role : add-build-sshkey]
2025-10-13 10:34:56.166007 | controller | ok
2025-10-13 10:34:56.184414 | 
2025-10-13 10:34:56.184507 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-10-13 10:34:56.466036 | controller -> localhost | ok
2025-10-13 10:34:56.479505 | 
2025-10-13 10:34:56.479636 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-10-13 10:34:56.516628 | controller | ok
2025-10-13 10:34:56.543470 | controller | included: /var/lib/zuul/builds/e1b10af8f1734a3ba1b89e7dd112fb3d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-10-13 10:34:56.551588 | 
2025-10-13 10:34:56.551674 | TASK [add-build-sshkey : Create Temp SSH key]
2025-10-13 10:34:57.312119 | controller -> localhost | Generating public/private rsa key pair.
2025-10-13 10:34:57.312303 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/e1b10af8f1734a3ba1b89e7dd112fb3d/work/e1b10af8f1734a3ba1b89e7dd112fb3d_id_rsa.
2025-10-13 10:34:57.312334 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/e1b10af8f1734a3ba1b89e7dd112fb3d/work/e1b10af8f1734a3ba1b89e7dd112fb3d_id_rsa.pub.
2025-10-13 10:34:57.312356 | controller -> localhost | The key fingerprint is:
2025-10-13 10:34:57.312376 | controller -> localhost | SHA256:Xk9jmsxukQDLovpQxV0hQkRhtpUyiozE9KQVIlIkhS8 zuul-build-sshkey
2025-10-13 10:34:57.312396 | controller -> localhost | The key's randomart image is:
2025-10-13 10:34:57.312414 | controller -> localhost | +---[RSA 3072]----+
2025-10-13 10:34:57.312432 | controller -> localhost | |*B+BX.o.o.       |
2025-10-13 10:34:57.312450 | controller -> localhost | |++*+o=oo         |
2025-10-13 10:34:57.312468 | controller -> localhost | |++ o++.o         |
2025-10-13 10:34:57.312486 | controller -> localhost | |E.o.. o .        |
2025-10-13 10:34:57.312504 | controller -> localhost | | ... .  S...+    |
2025-10-13 10:34:57.312521 | controller -> localhost | | ..    . +o* .   |
2025-10-13 10:34:57.312538 | controller -> localhost | |..      . =..    |
2025-10-13 10:34:57.312558 | controller -> localhost | |..       ..      |
2025-10-13 10:34:57.312575 | controller -> localhost | | ..      ..      |
2025-10-13 10:34:57.312592 | controller -> localhost | +----[SHA256]-----+
2025-10-13 10:34:57.312634 | controller -> localhost | ok: Runtime: 0:00:00.282377
2025-10-13 10:34:57.318358 | 
2025-10-13 10:34:57.318420 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-10-13 10:34:57.347292 | controller | ok
2025-10-13 10:34:57.356870 | controller | included: /var/lib/zuul/builds/e1b10af8f1734a3ba1b89e7dd112fb3d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-10-13 10:34:57.365175 | 
2025-10-13 10:34:57.365236 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-10-13 10:34:57.389350 | controller | skipping: Conditional result was False
2025-10-13 10:34:57.394657 | 
2025-10-13 10:34:57.394733 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-10-13 10:34:57.853788 | controller | changed
2025-10-13 10:34:57.861218 | 
2025-10-13 10:34:57.861359 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-10-13 10:34:58.108164 | controller | ok
2025-10-13 10:34:58.113581 | 
2025-10-13 10:34:58.113648 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-10-13 10:34:59.045189 | controller | changed
2025-10-13 10:34:59.058028 | 
2025-10-13 10:34:59.058133 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-10-13 10:34:59.984852 | controller | changed
2025-10-13 10:34:59.997293 | 
2025-10-13 10:34:59.997448 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-10-13 10:35:00.016765 | controller | skipping: Conditional result was False
2025-10-13 10:35:00.030768 | 
2025-10-13 10:35:00.030918 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-10-13 10:35:00.413930 | controller -> localhost | changed
2025-10-13 10:35:00.423842 | 
2025-10-13 10:35:00.423907 | TASK [add-build-sshkey : Add back temp key]
2025-10-13 10:35:00.687655 | controller -> localhost | Identity added: /var/lib/zuul/builds/e1b10af8f1734a3ba1b89e7dd112fb3d/work/e1b10af8f1734a3ba1b89e7dd112fb3d_id_rsa (zuul-build-sshkey)
2025-10-13 10:35:00.687851 | controller -> localhost | ok: Runtime: 0:00:00.008792
2025-10-13 10:35:00.698234 | 
2025-10-13 10:35:00.698330 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-10-13 10:35:01.067060 | controller | ok
2025-10-13 10:35:01.077636 | 
2025-10-13 10:35:01.077809 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-10-13 10:35:01.109933 | controller | skipping: Conditional result was False
2025-10-13 10:35:01.124527 | 
2025-10-13 10:35:01.124618 | TASK [include_role : validate-host]
2025-10-13 10:35:01.155883 | controller | ok
2025-10-13 10:35:01.183903 | 
2025-10-13 10:35:01.183999 | TASK [validate-host : Define zuul_info_dir fact]
2025-10-13 10:35:01.248373 | controller | ok
2025-10-13 10:35:01.260982 | 
2025-10-13 10:35:01.261117 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-10-13 10:35:01.511550 | controller -> localhost | ok
2025-10-13 10:35:01.519427 | 
2025-10-13 10:35:01.519507 | TASK [validate-host : Collect information about the host]
2025-10-13 10:35:02.456031 | controller | ok
2025-10-13 10:35:02.464320 | 
2025-10-13 10:35:02.464383 | TASK [validate-host : Sanitize hostname]
2025-10-13 10:35:02.514121 | controller | ok
2025-10-13 10:35:02.520824 | 
2025-10-13 10:35:02.520885 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-10-13 10:35:02.951959 | controller -> localhost | changed
2025-10-13 10:35:02.958241 | 
2025-10-13 10:35:02.958310 | TASK [validate-host : Collect information about zuul worker]
2025-10-13 10:35:03.413093 | controller | ok
2025-10-13 10:35:03.418446 | 
2025-10-13 10:35:03.418508 | TASK [validate-host : Write out all zuul information for each host]
2025-10-13 10:35:03.819223 | controller -> localhost | changed
2025-10-13 10:35:03.828972 | 
2025-10-13 10:35:03.829044 | TASK [include_role : prepare-workspace-openshift]
2025-10-13 10:35:03.853436 | controller | skipping: Conditional result was False
2025-10-13 10:35:03.859055 | 
2025-10-13 10:35:03.859113 | TASK [include_role : remove-zuul-sshkey]
2025-10-13 10:35:03.884085 | controller | skipping: Conditional result was False
2025-10-13 10:35:03.889795 | 
2025-10-13 10:35:03.889855 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-10-13 10:35:04.197611 | controller | ok: "logs"
2025-10-13 10:35:04.198088 | controller | ok: All items complete
2025-10-13 10:35:04.198148 | 
2025-10-13 10:35:04.458211 | controller | ok: "artifacts"
2025-10-13 10:35:04.715613 | controller | ok: "docs"
2025-10-13 10:35:04.731725 | 
2025-10-13 10:35:04.731927 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-10-13 10:35:05.068930 | controller | changed: "logs"
2025-10-13 10:35:05.349237 | controller | changed: "artifacts"
2025-10-13 10:35:05.636667 | controller | changed: "docs"
2025-10-13 10:35:05.676089 | 
2025-10-13 10:35:05.676321 | PLAY RECAP
2025-10-13 10:35:05.676385 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-10-13 10:35:05.676422 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-13 10:35:05.676448 | 
2025-10-13 10:35:05.798068 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-10-13 10:35:05.799141 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-10-13 10:35:06.421682 | 
2025-10-13 10:35:06.421819 | PLAY [all]
2025-10-13 10:35:06.441920 | 
2025-10-13 10:35:06.441996 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-10-13 10:35:06.501512 | controller | ok
2025-10-13 10:35:06.506448 | 
2025-10-13 10:35:06.506522 | TASK [mirror-info-fork : Create /etc/ci]
2025-10-13 10:35:06.998894 | controller | changed
2025-10-13 10:35:07.004971 | 
2025-10-13 10:35:07.005049 | TASK [mirror-info-fork : Install ci_mirror script]
2025-10-13 10:35:08.280820 | controller | changed
2025-10-13 10:35:08.306350 | 
2025-10-13 10:35:08.306456 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-10-13 10:35:08.810659 | controller | changed:
2025-10-13 10:35:08.810910 | controller | {
2025-10-13 10:35:08.810947 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-10-13 10:35:08.810986 | controller | }
2025-10-13 10:35:09.118984 | controller | changed:
2025-10-13 10:35:09.119082 | controller | {
2025-10-13 10:35:09.119108 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-10-13 10:35:09.119128 | controller | }
2025-10-13 10:35:09.392404 | controller | changed:
2025-10-13 10:35:09.392504 | controller | {
2025-10-13 10:35:09.392529 | 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-13 10:35:09.392548 | controller | }
2025-10-13 10:35:09.663643 | controller | changed:
2025-10-13 10:35:09.663749 | controller | {
2025-10-13 10:35:09.663774 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-10-13 10:35:09.663793 | controller | }
2025-10-13 10:35:09.946318 | controller | changed:
2025-10-13 10:35:09.946476 | controller | {
2025-10-13 10:35:09.946531 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-10-13 10:35:09.946574 | controller | }
2025-10-13 10:35:10.223331 | controller | changed:
2025-10-13 10:35:10.223428 | controller | {
2025-10-13 10:35:10.223454 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-10-13 10:35:10.223473 | controller | }
2025-10-13 10:35:10.522516 | controller | changed:
2025-10-13 10:35:10.522760 | controller | {
2025-10-13 10:35:10.522799 | 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-13 10:35:10.522822 | controller | }
2025-10-13 10:35:10.837956 | controller | changed:
2025-10-13 10:35:10.838213 | controller | {
2025-10-13 10:35:10.838298 | 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-13 10:35:10.838343 | controller | }
2025-10-13 10:35:11.137475 | controller | changed:
2025-10-13 10:35:11.137668 | controller | {
2025-10-13 10:35:11.137773 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-10-13 10:35:11.137796 | controller | }
2025-10-13 10:35:11.492936 | controller | changed:
2025-10-13 10:35:11.493038 | controller | {
2025-10-13 10:35:11.493060 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-10-13 10:35:11.493079 | controller | }
2025-10-13 10:35:11.762938 | controller | changed:
2025-10-13 10:35:11.763275 | controller | {
2025-10-13 10:35:11.763310 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-10-13 10:35:11.763336 | controller | }
2025-10-13 10:35:12.049045 | controller | changed:
2025-10-13 10:35:12.049401 | controller | {
2025-10-13 10:35:12.049490 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-10-13 10:35:12.049547 | controller | }
2025-10-13 10:35:12.348967 | controller | changed:
2025-10-13 10:35:12.349129 | controller | {
2025-10-13 10:35:12.349182 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-10-13 10:35:12.349225 | controller | }
2025-10-13 10:35:12.695793 | controller | changed:
2025-10-13 10:35:12.696295 | controller | {
2025-10-13 10:35:12.696375 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-10-13 10:35:12.696420 | controller | }
2025-10-13 10:35:13.029170 | controller | changed:
2025-10-13 10:35:13.029360 | controller | {
2025-10-13 10:35:13.029392 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-10-13 10:35:13.029418 | controller | }
2025-10-13 10:35:13.365578 | controller | changed:
2025-10-13 10:35:13.373379 | controller | {
2025-10-13 10:35:13.373772 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-10-13 10:35:13.373829 | controller | }
2025-10-13 10:35:13.663325 | controller | changed:
2025-10-13 10:35:13.663661 | controller | {
2025-10-13 10:35:13.663776 | 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-13 10:35:13.663823 | controller | }
2025-10-13 10:35:13.952766 | controller | changed:
2025-10-13 10:35:13.952918 | controller | {
2025-10-13 10:35:13.952951 | 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-13 10:35:13.952977 | controller | }
2025-10-13 10:35:14.272010 | controller | changed:
2025-10-13 10:35:14.272228 | controller | {
2025-10-13 10:35:14.272279 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-10-13 10:35:14.272322 | controller | }
2025-10-13 10:35:14.593674 | controller | changed:
2025-10-13 10:35:14.594095 | controller | {
2025-10-13 10:35:14.594159 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-10-13 10:35:14.594201 | controller | }
2025-10-13 10:35:14.915951 | controller | changed:
2025-10-13 10:35:14.916280 | controller | {
2025-10-13 10:35:14.916371 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-10-13 10:35:14.916422 | controller | }
2025-10-13 10:35:15.175666 | controller | changed:
2025-10-13 10:35:15.175935 | controller | {
2025-10-13 10:35:15.176013 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-10-13 10:35:15.176057 | controller | }
2025-10-13 10:35:15.497031 | controller | changed:
2025-10-13 10:35:15.497229 | controller | {
2025-10-13 10:35:15.497296 | 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-13 10:35:15.497343 | controller | }
2025-10-13 10:35:15.814382 | controller | changed:
2025-10-13 10:35:15.814502 | controller | {
2025-10-13 10:35:15.814528 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-10-13 10:35:15.814546 | controller | }
2025-10-13 10:35:16.146802 | controller | changed:
2025-10-13 10:35:16.146917 | controller | {
2025-10-13 10:35:16.147144 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-10-13 10:35:16.147192 | controller | }
2025-10-13 10:35:16.462291 | controller | changed:
2025-10-13 10:35:16.462396 | controller | {
2025-10-13 10:35:16.462419 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-10-13 10:35:16.462439 | controller | }
2025-10-13 10:35:16.505988 | 
2025-10-13 10:35:16.506131 | TASK [Set timezone to UTC]
2025-10-13 10:35:17.204086 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-10-13 10:35:17.211070 | 
2025-10-13 10:35:17.211137 | TASK [Create nodepool directory]
2025-10-13 10:35:17.544654 | controller | changed
2025-10-13 10:35:17.550356 | 
2025-10-13 10:35:17.550421 | TASK [Create nodepool sub_nodes file]
2025-10-13 10:35:18.477586 | controller | changed
2025-10-13 10:35:18.489520 | 
2025-10-13 10:35:18.489656 | TASK [Create nodepool sub_nodes_private file]
2025-10-13 10:35:19.426341 | controller | changed
2025-10-13 10:35:19.438673 | 
2025-10-13 10:35:19.438841 | LOOP [Populate nodepool sub_nodes file]
2025-10-13 10:35:19.489310 | 
2025-10-13 10:35:19.489593 | LOOP [Populate nodepool sub_nodes_private file]
2025-10-13 10:35:19.539189 | 
2025-10-13 10:35:19.539469 | TASK [Create nodepool primary file]
2025-10-13 10:35:19.566023 | controller | skipping: Conditional result was False
2025-10-13 10:35:19.579832 | 
2025-10-13 10:35:19.579995 | TASK [Create nodepool node_private for this node]
2025-10-13 10:35:20.588605 | controller | changed
2025-10-13 10:35:20.601243 | 
2025-10-13 10:35:20.601398 | LOOP [Copy ssh keys to nodepool directory]
2025-10-13 10:35:21.126080 | controller | ok: Item: id_rsa Runtime: 0:00:00.011910
2025-10-13 10:35:21.126391 | 
2025-10-13 10:35:21.407624 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.007735
2025-10-13 10:35:21.430358 | 
2025-10-13 10:35:21.430515 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-10-13 10:35:22.390664 | controller | changed
2025-10-13 10:35:22.396926 | 
2025-10-13 10:35:22.397003 | TASK [Validate sudoers config after edits]
2025-10-13 10:35:22.735800 | controller | /etc/sudoers: parsed OK
2025-10-13 10:35:22.736064 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-10-13 10:35:22.736095 | controller | /etc/sudoers.d/zuul: parsed OK
2025-10-13 10:35:22.736114 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-10-13 10:35:22.939843 | controller | ok: Runtime: 0:00:00.011871
2025-10-13 10:35:22.952802 | 
2025-10-13 10:35:22.952950 | TASK [Show the environment passed in to job shell scripts]
2025-10-13 10:35:23.286997 | controller | SHELL=/bin/bash
2025-10-13 10:35:23.287107 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-10-13 10:35:23.287127 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-10-13 10:35:23.287142 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/02/3402/c2e3cdb106c3c91ed9e4c69bd61e0ffd9bd88749
2025-10-13 10:35:23.287156 | controller | PWD=/home/zuul
2025-10-13 10:35:23.287170 | controller | ZUUL_PIPELINE=github-check
2025-10-13 10:35:23.287184 | controller | LOGNAME=zuul
2025-10-13 10:35:23.287293 | controller | XDG_SESSION_TYPE=tty
2025-10-13 10:35:23.287311 | controller | _=/usr/bin/env
2025-10-13 10:35:23.287328 | controller | MOTD_SHOWN=pam
2025-10-13 10:35:23.287345 | controller | HOME=/home/zuul
2025-10-13 10:35:23.287362 | controller | LANG=en_US.UTF-8
2025-10-13 10:35:23.287380 | controller | SSH_CONNECTION=38.102.83.114 35778 38.102.83.200 22
2025-10-13 10:35:23.287399 | 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-13 10:35:23.287426 | controller | ZUUL_CHANGE_IDS=3402,c2e3cdb106c3c91ed9e4c69bd61e0ffd9bd88749
2025-10-13 10:35:23.287445 | controller | WORKSPACE=/home/zuul/workspace
2025-10-13 10:35:23.287463 | controller | XDG_SESSION_CLASS=user
2025-10-13 10:35:23.287481 | controller | SELINUX_ROLE_REQUESTED=
2025-10-13 10:35:23.287499 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-10-13 10:35:23.287514 | controller | USER=zuul
2025-10-13 10:35:23.287527 | controller | ZUUL_VOTING=True
2025-10-13 10:35:23.287539 | controller | BUILD_TIMEOUT=3500000
2025-10-13 10:35:23.287552 | controller | SELINUX_USE_CURRENT_RANGE=
2025-10-13 10:35:23.287565 | controller | SHLVL=1
2025-10-13 10:35:23.287579 | controller | ZUUL_PATCHSET=c2e3cdb106c3c91ed9e4c69bd61e0ffd9bd88749
2025-10-13 10:35:23.287592 | controller | XDG_SESSION_ID=1
2025-10-13 10:35:23.287605 | controller | ZUUL_BRANCH=main
2025-10-13 10:35:23.287618 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-10-13 10:35:23.287632 | controller | SSH_CLIENT=38.102.83.114 35778 22
2025-10-13 10:35:23.287645 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-10-13 10:35:23.287658 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-10-13 10:35:23.287675 | controller | which_declare=declare -f
2025-10-13 10:35:23.287689 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2025-10-13 10:35:23.287703 | controller | SELINUX_LEVEL_REQUESTED=
2025-10-13 10:35:23.287716 | controller | ZUUL_CHANGE=3402
2025-10-13 10:35:23.287730 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-10-13 10:35:23.287743 | controller | ZUUL_UUID=e1b10af8f1734a3ba1b89e7dd112fb3d
2025-10-13 10:35:23.287759 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-10-13 10:35:23.287773 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-10-13 10:35:23.287787 | controller | }
2025-10-13 10:35:23.506870 | controller | ok: Runtime: 0:00:00.014142
2025-10-13 10:35:23.519761 | 
2025-10-13 10:35:23.519911 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-10-13 10:35:23.548119 | controller | skipping: Conditional result was False
2025-10-13 10:35:23.561228 | 
2025-10-13 10:35:23.561370 | TASK [Symlink /home/zuul-worker/workspace]
2025-10-13 10:35:24.132968 | controller | skipping: Conditional result was False
2025-10-13 10:35:24.140571 | 
2025-10-13 10:35:24.140664 | TASK [Ensure legacy workspace directory]
2025-10-13 10:35:24.409930 | controller | changed
2025-10-13 10:35:24.434323 | 
2025-10-13 10:35:24.434429 | PLAY RECAP
2025-10-13 10:35:24.434472 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-10-13 10:35:24.434493 | 
2025-10-13 10:35:24.551372 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-10-13 10:35:24.552177 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/crc/podman.yaml@master]
2025-10-13 10:35:25.099594 | 
2025-10-13 10:35:25.099714 | PLAY [all,!compute]
2025-10-13 10:35:25.119084 | 
2025-10-13 10:35:25.119157 | TASK [Enable container-tools rhel-modules for c8/crc node]
2025-10-13 10:35:25.650642 | controller | skipping: Conditional result was False
2025-10-13 10:35:25.663624 | 
2025-10-13 10:35:25.663879 | TASK [Install podman]
2025-10-13 10:35:25.713246 | controller | skipping: Conditional result was False
2025-10-13 10:35:25.719999 | 
2025-10-13 10:35:25.720068 | TASK [Set var name for quay login zuul secret]
2025-10-13 10:35:25.763866 | controller | skipping: Conditional result was False
2025-10-13 10:35:25.770075 | 
2025-10-13 10:35:25.770144 | TASK [Print the username]
2025-10-13 10:35:26.301412 | controller | skipping: Conditional result was False
2025-10-13 10:35:26.313988 | 
2025-10-13 10:35:26.314124 | TASK [Fail when user or password is not set]
2025-10-13 10:35:26.350884 | controller | skipping: Conditional result was False
2025-10-13 10:35:26.358429 | 
2025-10-13 10:35:26.358513 | TASK [Set vars for quay login]
2025-10-13 10:35:26.412226 | 
2025-10-13 10:35:26.412366 | LOOP [Perform container registry login(s) with podman]
2025-10-13 10:35:26.487009 | 
2025-10-13 10:35:26.487157 | LOOP [Perform container registry login(s) with buildah]
2025-10-13 10:35:26.554552 | 
2025-10-13 10:35:26.554750 | TASK [Set Insecure registry for content provider]
2025-10-13 10:35:26.622597 | controller | skipping: Conditional result was False
2025-10-13 10:35:26.648206 | 
2025-10-13 10:35:26.648277 | PLAY RECAP
2025-10-13 10:35:26.648332 | controller | ok: 0 changed: 0 unreachable: 0 failed: 0 skipped: 9 rescued: 0 ignored: 0
2025-10-13 10:35:26.648360 | 
2025-10-13 10:35:26.735335 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/crc/podman.yaml@master]
2025-10-13 10:35:26.736133 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/content_provider/pre.yml@main]
2025-10-13 10:35:27.289832 | 
2025-10-13 10:35:27.289946 | PLAY [Get all repos on all hosts]
2025-10-13 10:35:27.309929 | 
2025-10-13 10:35:27.310020 | TASK [Clone repos in the job workspace]
2025-10-13 10:35:27.329666 | controller | ok
2025-10-13 10:35:27.357481 | 
2025-10-13 10:35:27.357612 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-10-13 10:35:27.764774 | controller | ok
2025-10-13 10:35:27.770881 | 
2025-10-13 10:35:27.770947 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-10-13 10:35:30.561191 | controller | Output suppressed because no_log was given
2025-10-13 10:35:30.613346 | 
2025-10-13 10:35:30.613476 | PLAY [Run ci/playbooks/content_provider/pre.yml]
2025-10-13 10:35:30.632532 | 
2025-10-13 10:35:30.632648 | TASK [Filter out host if needed]
2025-10-13 10:35:30.642508 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-10-13 10:35:30.647445 | 
2025-10-13 10:35:30.647519 | TASK [Install ansible-core]
2025-10-13 10:35:37.964341 | controller | changed
2025-10-13 10:35:37.971803 | 
2025-10-13 10:35:37.971888 | TASK [Discover an IPv4 for provider job]
2025-10-13 10:35:38.048332 | controller | ok
2025-10-13 10:35:38.055737 | 
2025-10-13 10:35:38.055987 | TASK [Read group_vars]
2025-10-13 10:35:38.081965 | controller | ok
2025-10-13 10:35:38.111045 | 
2025-10-13 10:35:38.111171 | TASK [cifmw_helpers : Check directory is available /var/lib/zuul/builds/e1b10af8f1734a3ba1b89e7dd112fb3d/work/src/github.com/openstack-k8s-operators/ci-framework/group_vars]
2025-10-13 10:35:38.423726 | controller | ok
2025-10-13 10:35:38.429355 | 
2025-10-13 10:35:38.429415 | TASK [cifmw_helpers : List available files]
2025-10-13 10:35:38.966363 | controller | skipping: Conditional result was False
2025-10-13 10:35:38.986711 | 
2025-10-13 10:35:38.987132 | LOOP [cifmw_helpers : Read vars]
2025-10-13 10:35:39.037351 | 
2025-10-13 10:35:39.037590 | LOOP [cifmw_helpers : Call task to parse all files as fact]
2025-10-13 10:35:39.108144 | 
2025-10-13 10:35:39.108279 | PLAY RECAP
2025-10-13 10:35:39.108319 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2025-10-13 10:35:39.108338 | 
2025-10-13 10:35:39.210262 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/content_provider/pre.yml@main]
2025-10-13 10:35:39.211811 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/e2e-prepare.yml@main]
2025-10-13 10:35:39.865337 | 
2025-10-13 10:35:39.865448 | PLAY [Get all repos on all hosts]
2025-10-13 10:35:39.886795 | 
2025-10-13 10:35:39.886901 | TASK [Clone repos in the job workspace]
2025-10-13 10:35:39.907138 | controller | ok
2025-10-13 10:35:39.922241 | 
2025-10-13 10:35:39.922323 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-10-13 10:35:40.354771 | controller | ok
2025-10-13 10:35:40.369321 | 
2025-10-13 10:35:40.369505 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-10-13 10:35:41.540052 | controller | Output suppressed because no_log was given
2025-10-13 10:35:41.586337 | 
2025-10-13 10:35:41.586436 | PLAY [Run ci/playbooks/e2e-prepare.yml]
2025-10-13 10:35:41.605778 | 
2025-10-13 10:35:41.605858 | TASK [Read group_vars]
2025-10-13 10:35:41.628727 | controller | ok
2025-10-13 10:35:41.654344 | 
2025-10-13 10:35:41.654446 | TASK [cifmw_helpers : Check directory is available /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/group_vars]
2025-10-13 10:35:42.086900 | controller | ok
2025-10-13 10:35:42.100465 | 
2025-10-13 10:35:42.100627 | TASK [cifmw_helpers : List available files]
2025-10-13 10:35:42.679346 | controller | skipping: Conditional result was False
2025-10-13 10:35:42.693314 | 
2025-10-13 10:35:42.693471 | LOOP [cifmw_helpers : Read vars]
2025-10-13 10:35:42.729371 | 
2025-10-13 10:35:42.729575 | LOOP [cifmw_helpers : Call task to parse all files as fact]
2025-10-13 10:35:42.769508 | 
2025-10-13 10:35:42.769718 | TASK [Read group_vars]
2025-10-13 10:35:42.791578 | controller | ok
2025-10-13 10:35:42.820352 | 
2025-10-13 10:35:42.820495 | TASK [cifmw_helpers : Check directory is available /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/group_vars]
2025-10-13 10:35:43.092267 | controller | ok
2025-10-13 10:35:43.103931 | 
2025-10-13 10:35:43.104071 | TASK [cifmw_helpers : List available files]
2025-10-13 10:35:43.652715 | controller | skipping: Conditional result was False
2025-10-13 10:35:43.665766 | 
2025-10-13 10:35:43.665937 | LOOP [cifmw_helpers : Read vars]
2025-10-13 10:35:43.705616 | 
2025-10-13 10:35:43.705962 | LOOP [cifmw_helpers : Call task to parse all files as fact]
2025-10-13 10:35:43.756097 | 
2025-10-13 10:35:43.756386 | TASK [Create zuul-output directory]
2025-10-13 10:35:44.094657 | controller | ok
2025-10-13 10:35:44.107199 | 
2025-10-13 10:35:44.107367 | TASK [Install required packages]
2025-10-13 10:36:48.744830 | controller | changed
2025-10-13 10:36:48.756872 | 
2025-10-13 10:36:48.757007 | TASK [Output pip related things]
2025-10-13 10:36:49.551728 | controller | pip 21.3.1 from /usr/lib/python3.9/site-packages/pip (python 3.9)
2025-10-13 10:36:49.809134 | controller | changed
2025-10-13 10:36:49.820207 | 
2025-10-13 10:36:49.820339 | TASK [Install requirements]
2025-10-13 10:38:00.735519 | controller | changed
2025-10-13 10:38:00.767184 | 
2025-10-13 10:38:00.767363 | TASK [Add cronjob to trigger job stats]
2025-10-13 10:38:00.890615 | controller | skipping: Conditional result was False
2025-10-13 10:38:00.899316 | 
2025-10-13 10:38:00.899428 | LOOP [Construct project change list]
2025-10-13 10:38:00.980171 | controller | ok:
2025-10-13 10:38:00.980431 | controller | {
2025-10-13 10:38:00.980468 | controller |   "branch": "main",
2025-10-13 10:38:00.980495 | controller |   "change": "3402",
2025-10-13 10:38:00.980520 | controller |   "change_message": "Check global vars",
2025-10-13 10:38:00.980545 | controller |   "change_url": "https://github.com/openstack-k8s-operators/ci-framework/pull/3402",
2025-10-13 10:38:00.980569 | controller |   "commit_id": "c2e3cdb106c3c91ed9e4c69bd61e0ffd9bd88749",
2025-10-13 10:38:00.980592 | controller |   "patchset": "c2e3cdb106c3c91ed9e4c69bd61e0ffd9bd88749",
2025-10-13 10:38:00.980619 | controller |   "project": {
2025-10-13 10:38:00.980644 | controller |     "canonical_hostname": "github.com",
2025-10-13 10:38:00.980668 | controller |     "canonical_name": "github.com/openstack-k8s-operators/ci-framework",
2025-10-13 10:38:00.980725 | controller |     "name": "openstack-k8s-operators/ci-framework",
2025-10-13 10:38:00.980750 | controller |     "short_name": "ci-framework",
2025-10-13 10:38:00.980773 | controller |     "src_dir": "src/github.com/openstack-k8s-operators/ci-framework"
2025-10-13 10:38:00.980796 | controller |   },
2025-10-13 10:38:00.980819 | controller |   "topic": null
2025-10-13 10:38:00.980843 | controller | }
2025-10-13 10:38:00.980882 | 
2025-10-13 10:38:01.046092 | 
2025-10-13 10:38:01.046222 | PLAY [Run ci/playbooks/build_runner_image.yml]
2025-10-13 10:38:01.067958 | 
2025-10-13 10:38:01.068077 | TASK [Filter out host if needed]
2025-10-13 10:38:01.079906 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-10-13 10:38:01.084633 | 
2025-10-13 10:38:01.084713 | TASK [Get git tag for image tagging]
2025-10-13 10:38:01.616192 | controller | skipping: Conditional result was False
2025-10-13 10:38:01.628417 | 
2025-10-13 10:38:01.628552 | TASK [Set openstack-runner image]
2025-10-13 10:38:01.656815 | controller | skipping: Conditional result was False
2025-10-13 10:38:01.670390 | 
2025-10-13 10:38:01.670544 | TASK [Build openstack-runner image]
2025-10-13 10:38:01.697580 | controller | skipping: Conditional result was False
2025-10-13 10:38:01.712460 | 
2025-10-13 10:38:01.712626 | TASK [Push openstack-runner image]
2025-10-13 10:38:01.740419 | controller | skipping: Conditional result was False
2025-10-13 10:38:01.754450 | 
2025-10-13 10:38:01.754618 | TASK [Make sure ci-framework directory exists]
2025-10-13 10:38:01.772890 | controller | skipping: Conditional result was False
2025-10-13 10:38:01.787017 | 
2025-10-13 10:38:01.787163 | TASK [Create EDPM ansible var file]
2025-10-13 10:38:01.805199 | controller | skipping: Conditional result was False
2025-10-13 10:38:01.840801 | 
2025-10-13 10:38:01.840882 | PLAY RECAP
2025-10-13 10:38:01.840938 | controller | ok: 9 changed: 3 unreachable: 0 failed: 0 skipped: 13 rescued: 0 ignored: 0
2025-10-13 10:38:01.840966 | 
2025-10-13 10:38:01.952009 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/e2e-prepare.yml@main]
2025-10-13 10:38:01.953540 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-10-13 10:38:02.548597 | 
2025-10-13 10:38:02.548747 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-10-13 10:38:02.569177 | 
2025-10-13 10:38:02.569269 | TASK [Create zuul-output directory]
2025-10-13 10:38:03.084530 | controller | changed
2025-10-13 10:38:03.096462 | 
2025-10-13 10:38:03.096610 | TASK [Slurp Zuul inventory test]
2025-10-13 10:38:03.479251 | controller -> localhost | ok
2025-10-13 10:38:03.485913 | 
2025-10-13 10:38:03.485997 | TASK [Save zuul inventory]
2025-10-13 10:38:04.581360 | controller | changed
2025-10-13 10:38:04.593787 | 
2025-10-13 10:38:04.593922 | TASK [Save zuul vars without the change_message]
2025-10-13 10:38:05.382316 | controller | changed
2025-10-13 10:38:05.408012 | 
2025-10-13 10:38:05.408100 | PLAY RECAP
2025-10-13 10:38:05.408143 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-13 10:38:05.408166 | 
2025-10-13 10:38:05.511197 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-10-13 10:38:05.512243 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/tcib/run.yml@main]
2025-10-13 10:38:06.091326 | 
2025-10-13 10:38:06.091437 | PLAY [Run ci/playbooks/tcib/run.yml]
2025-10-13 10:38:06.112051 | 
2025-10-13 10:38:06.112147 | TASK [Read group_vars]
2025-10-13 10:38:06.131406 | controller | ok
2025-10-13 10:38:06.148351 | 
2025-10-13 10:38:06.148453 | TASK [cifmw_helpers : Check directory is available /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/group_vars]
2025-10-13 10:38:06.533787 | controller | ok
2025-10-13 10:38:06.548880 | 
2025-10-13 10:38:06.549045 | TASK [cifmw_helpers : List available files]
2025-10-13 10:38:07.097953 | controller | skipping: Conditional result was False
2025-10-13 10:38:07.105002 | 
2025-10-13 10:38:07.105110 | LOOP [cifmw_helpers : Read vars]
2025-10-13 10:38:07.137052 | 
2025-10-13 10:38:07.137212 | LOOP [cifmw_helpers : Call task to parse all files as fact]
2025-10-13 10:38:07.173341 | 
2025-10-13 10:38:07.173497 | TASK [Filter out host if needed]
2025-10-13 10:38:07.183519 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-10-13 10:38:07.188374 | 
2025-10-13 10:38:07.188441 | TASK [Discover the host ip]
2025-10-13 10:38:07.257221 | controller | ok
2025-10-13 10:38:07.268364 | 
2025-10-13 10:38:07.268542 | TASK [Run tcib playbook]
2025-10-13 10:38:17.826821 | controller | ERROR
2025-10-13 10:38:17.827060 | controller | {
2025-10-13 10:38:17.827093 | controller |   "msg": "The task includes an option with an undefined variable. The error was: 'cifmw_project_dir_absolute' is undefined. 'cifmw_project_dir_absolute' is undefined\n\nThe error appears to be in '/var/lib/zuul/builds/e1b10af8f1734a3ba1b89e7dd112fb3d/untrusted/project_0/github.com/openstack-k8s-operators/ci-framework/ci/playbooks/tcib/run.yml': line 33, column 7, but may\nbe elsewhere in the file depending on the exact syntax problem.\n\nThe offending line appears to be:\n\n\n    - name: Run tcib playbook\n      ^ here\n"
2025-10-13 10:38:17.827120 | controller | }
failure
2025-10-13 10:38:17.830069 | 
2025-10-13 10:38:17.830121 | PLAY RECAP
2025-10-13 10:38:17.830165 | controller | ok: 2 changed: 0 unreachable: 0 failed: 1 skipped: 3 rescued: 0 ignored: 0
2025-10-13 10:38:17.830186 | 
2025-10-13 10:38:17.923793 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/tcib/run.yml@main]
2025-10-13 10:38:17.924644 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-10-13 10:38:18.490931 | 
2025-10-13 10:38:18.491091 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-10-13 10:38:18.513086 | 
2025-10-13 10:38:18.513190 | TASK [Filter out host if needed]
2025-10-13 10:38:18.522243 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-10-13 10:38:18.527046 | 
2025-10-13 10:38:18.527112 | TASK [Ensure file is present]
2025-10-13 10:38:18.900220 | controller | ok
2025-10-13 10:38:18.915436 | 
2025-10-13 10:38:18.915600 | TASK [Manage molecule report file]
2025-10-13 10:38:19.469302 | controller | skipping: Conditional result was False
2025-10-13 10:38:19.482800 | 
2025-10-13 10:38:19.482951 | TASK [Check if we get ci-framework-data basedir]
2025-10-13 10:38:19.737786 | controller | ok
2025-10-13 10:38:19.750022 | 
2025-10-13 10:38:19.750158 | TASK [Create ci-framework-data log directory for zuul]
2025-10-13 10:38:20.177354 | controller | changed
2025-10-13 10:38:20.189433 | 
2025-10-13 10:38:20.189573 | TASK [Copy ci-framework interesting files]
2025-10-13 10:38:20.619186 | controller | cp: cannot stat '/home/zuul/ci-framework-data/logs': No such file or directory
2025-10-13 10:38:20.629398 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2025-10-13 10:38:20.739668 | controller | changed
2025-10-13 10:38:20.751453 | 
2025-10-13 10:38:20.751593 | TASK [Get SELinux listing]
2025-10-13 10:38:21.304162 | controller | changed
2025-10-13 10:38:21.317227 | 
2025-10-13 10:38:21.317370 | TASK [Generate log index]
2025-10-13 10:38:22.289079 | controller | changed
2025-10-13 10:38:22.301403 | 
2025-10-13 10:38:22.301576 | TASK [Get some env related data]
2025-10-13 10:38:23.033381 | controller | /home/zuul/.local/bin/ansible
2025-10-13 10:38:23.855728 | controller | changed
2025-10-13 10:38:23.868144 | 
2025-10-13 10:38:23.868290 | TASK [Generate list of logs to collect in home directory]
2025-10-13 10:38:24.236214 | controller | ok: All paths examined
2025-10-13 10:38:24.250003 | 
2025-10-13 10:38:24.250146 | LOOP [Copy logs from home directory]
2025-10-13 10:38:24.688550 | controller | changed:
2025-10-13 10:38:24.688745 | controller | {
2025-10-13 10:38:24.688785 | controller |   "atime": 1760351857.883022,
2025-10-13 10:38:24.688811 | controller |   "ctime": 1760351880.1438708,
2025-10-13 10:38:24.688835 | controller |   "dev": 64513,
2025-10-13 10:38:24.688858 | controller |   "gid": 1000,
2025-10-13 10:38:24.688880 | controller |   "gr_name": "zuul",
2025-10-13 10:38:24.688902 | controller |   "inode": 4480725,
2025-10-13 10:38:24.688923 | controller |   "isblk": false,
2025-10-13 10:38:24.688944 | controller |   "ischr": false,
2025-10-13 10:38:24.688965 | controller |   "isdir": false,
2025-10-13 10:38:24.688986 | controller |   "isfifo": false,
2025-10-13 10:38:24.689007 | controller |   "isgid": false,
2025-10-13 10:38:24.689027 | controller |   "islnk": false,
2025-10-13 10:38:24.689047 | controller |   "isreg": true,
2025-10-13 10:38:24.689067 | controller |   "issock": false,
2025-10-13 10:38:24.689087 | controller |   "isuid": false,
2025-10-13 10:38:24.689108 | controller |   "mode": "0644",
2025-10-13 10:38:24.689128 | controller |   "mtime": 1760351880.1438708,
2025-10-13 10:38:24.689148 | controller |   "nlink": 1,
2025-10-13 10:38:24.689170 | controller |   "path": "/home/zuul/ansible.log",
2025-10-13 10:38:24.689191 | controller |   "pw_name": "zuul",
2025-10-13 10:38:24.689212 | controller |   "rgrp": true,
2025-10-13 10:38:24.689234 | controller |   "roth": true,
2025-10-13 10:38:24.689255 | controller |   "rusr": true,
2025-10-13 10:38:24.689276 | controller |   "size": 6749,
2025-10-13 10:38:24.689296 | controller |   "uid": 1000,
2025-10-13 10:38:24.689316 | controller |   "wgrp": false,
2025-10-13 10:38:24.689340 | controller |   "woth": false,
2025-10-13 10:38:24.689361 | controller |   "wusr": true,
2025-10-13 10:38:24.689383 | controller |   "xgrp": false,
2025-10-13 10:38:24.689403 | controller |   "xoth": false,
2025-10-13 10:38:24.689424 | controller |   "xusr": false
2025-10-13 10:38:24.689444 | controller | }
2025-10-13 10:38:24.708867 | 
2025-10-13 10:38:24.708996 | TASK [Copy crio stats log file]
2025-10-13 10:38:24.736010 | controller | skipping: Conditional result was False
2025-10-13 10:38:24.750139 | 
2025-10-13 10:38:24.750289 | TASK [Get SELinux related data]
2025-10-13 10:38:25.079953 | controller | <no matches>
2025-10-13 10:38:25.301251 | controller | ERROR
2025-10-13 10:38:25.301493 | controller | {
2025-10-13 10:38:25.301555 | controller |   "delta": "0:00:00.011553",
2025-10-13 10:38:25.301598 | controller |   "end": "2025-10-13 10:38:25.081286",
2025-10-13 10:38:25.301636 | controller |   "msg": "non-zero return code",
2025-10-13 10:38:25.301673 | controller |   "rc": 1,
2025-10-13 10:38:25.301753 | controller |   "start": "2025-10-13 10:38:25.069733"
2025-10-13 10:38:25.301790 | controller | }
2025-10-13 10:38:25.301840 | controller | ERROR: Ignoring Errors
2025-10-13 10:38:25.313661 | 
2025-10-13 10:38:25.313829 | TASK [Create system configuration directory]
2025-10-13 10:38:25.567555 | controller | changed
2025-10-13 10:38:25.578934 | 
2025-10-13 10:38:25.579067 | TASK [Get some of the system configurations]
2025-10-13 10:38:26.129776 | controller | changed
2025-10-13 10:38:26.142211 | 
2025-10-13 10:38:26.142363 | TASK [Copy generated documentation if available]
2025-10-13 10:38:26.168804 | controller | skipping: Conditional result was False
2025-10-13 10:38:26.181885 | 
2025-10-13 10:38:26.182028 | TASK [Copy generated AsciiDoc documentation if available]
2025-10-13 10:38:26.208982 | controller | skipping: Conditional result was False
2025-10-13 10:38:26.223017 | 
2025-10-13 10:38:26.223183 | TASK [Compress logs bigger than 2MB]
2025-10-13 10:38:26.770084 | controller | changed
2025-10-13 10:38:26.778170 | 
2025-10-13 10:38:26.778246 | TASK [Copy files from workspace on node]
2025-10-13 10:38:26.800926 | controller | ok
2025-10-13 10:38:26.831285 | 
2025-10-13 10:38:26.831385 | TASK [fetch-output : Set log path for multiple nodes]
2025-10-13 10:38:26.856060 | controller | skipping: Conditional result was False
2025-10-13 10:38:26.864234 | 
2025-10-13 10:38:26.864327 | TASK [fetch-output : Set log path for single node]
2025-10-13 10:38:26.896288 | controller | ok
2025-10-13 10:38:26.905494 | 
2025-10-13 10:38:26.905580 | LOOP [fetch-output : Ensure local output dirs]
2025-10-13 10:38:27.172673 | controller -> localhost | ok: "/var/lib/zuul/builds/e1b10af8f1734a3ba1b89e7dd112fb3d/work/logs"
2025-10-13 10:38:27.172952 | controller -> localhost | changed: All items complete
2025-10-13 10:38:27.172981 | 
2025-10-13 10:38:27.380946 | controller -> localhost | changed: "/var/lib/zuul/builds/e1b10af8f1734a3ba1b89e7dd112fb3d/work/artifacts"
2025-10-13 10:38:27.627336 | controller -> localhost | changed: "/var/lib/zuul/builds/e1b10af8f1734a3ba1b89e7dd112fb3d/work/docs"
2025-10-13 10:38:27.644643 | 
2025-10-13 10:38:27.644805 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-10-13 10:38:28.384260 | controller | changed:
2025-10-13 10:38:28.384570 | controller | .d..t...... ./
2025-10-13 10:38:28.384627 | controller | >f+++++++++ README.html
2025-10-13 10:38:28.384671 | controller | >f+++++++++ ansible.log
2025-10-13 10:38:28.384757 | controller | >f+++++++++ dmesg.log
2025-10-13 10:38:28.384817 | controller | >f+++++++++ installed-pkgs.log
2025-10-13 10:38:28.385087 | controller | >f+++++++++ python.log
2025-10-13 10:38:28.385192 | controller | >f+++++++++ registries.conf
2025-10-13 10:38:28.385239 | controller | >f+++++++++ selinux-denials.log
2025-10-13 10:38:28.385283 | controller | >f+++++++++ selinux-listing.log
2025-10-13 10:38:28.385322 | controller | cd+++++++++ ci-framework-data/
2025-10-13 10:38:28.385360 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-10-13 10:38:28.385397 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-10-13 10:38:28.385435 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-10-13 10:38:28.385472 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-10-13 10:38:28.385508 | controller | cd+++++++++ registries.conf.d/
2025-10-13 10:38:28.385544 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-10-13 10:38:28.385579 | controller | cd+++++++++ system-config/
2025-10-13 10:38:28.385614 | controller | cd+++++++++ system-config/libvirt/
2025-10-13 10:38:28.385650 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-10-13 10:38:28.385756 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-10-13 10:38:28.868362 | controller | changed: .d..t...... ./
2025-10-13 10:38:29.382809 | controller | changed: .d..t...... ./
2025-10-13 10:38:29.409955 | 
2025-10-13 10:38:29.410102 | TASK [Return artifact to Zuul]
2025-10-13 10:38:29.447126 | controller | skipping: Conditional result was False
2025-10-13 10:38:29.487158 | 
2025-10-13 10:38:29.487253 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-10-13 10:38:29.487377 | 
2025-10-13 10:38:29.487412 | PLAY RECAP
2025-10-13 10:38:29.487461 | controller | ok: 16 changed: 12 unreachable: 0 failed: 0 skipped: 6 rescued: 0 ignored: 1
2025-10-13 10:38:29.487487 | 
2025-10-13 10:38:29.591327 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-10-13 10:38:29.592131 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/dlrn/dlrn-report.yaml@master]
2025-10-13 10:38:30.190575 | 
2025-10-13 10:38:30.190710 | PLAY [Report DLRN results after job run]
2025-10-13 10:38:30.211168 | 
2025-10-13 10:38:30.211288 | TASK [Report job status to DLRN]
2025-10-13 10:38:30.231695 | controller | ok
2025-10-13 10:38:30.256849 | 
2025-10-13 10:38:30.256978 | TASK [dlrn_report : Warning if DLRN is not running]
2025-10-13 10:38:30.321951 | controller | skipping: Conditional result was False
2025-10-13 10:38:30.330210 | 
2025-10-13 10:38:30.330310 | TASK [dlrn_report : Install dlrnapi-client shyaml package]
2025-10-13 10:38:30.385331 | controller | skipping: Conditional result was False
2025-10-13 10:38:30.394737 | 
2025-10-13 10:38:30.394854 | TASK [dlrn_report : Install kinit related package]
2025-10-13 10:38:30.440438 | controller | skipping: Conditional result was False
2025-10-13 10:38:30.448826 | 
2025-10-13 10:38:30.448936 | TASK [dlrn_report : Install dlrn kerberos related packages]
2025-10-13 10:38:30.494132 | controller | skipping: Conditional result was False
2025-10-13 10:38:30.505313 | 
2025-10-13 10:38:30.505526 | TASK [Set zuul-log-path fact]
2025-10-13 10:38:30.556231 | controller | skipping: Conditional result was False
2025-10-13 10:38:30.569518 | 
2025-10-13 10:38:30.569671 | TASK [dlrn_report : Set the the value of cifmw_repo_setup_promotion when multiple dlrn tags are used]
2025-10-13 10:38:30.617256 | controller | skipping: Conditional result was False
2025-10-13 10:38:30.625656 | 
2025-10-13 10:38:30.625779 | TASK [Get hash related data from repo_setup role]
2025-10-13 10:38:30.671065 | controller | skipping: Conditional result was False
2025-10-13 10:38:30.685295 | 
2025-10-13 10:38:30.685439 | TASK [dlrn_report : Perform kinit for DLRN kerberos authentication]
2025-10-13 10:38:31.235170 | controller | skipping: Conditional result was False
2025-10-13 10:38:31.248503 | 
2025-10-13 10:38:31.248649 | TASK [dlrn_report : Set empty value for dlrnapi password]
2025-10-13 10:38:31.326593 | controller | skipping: Conditional result was False
2025-10-13 10:38:31.341156 | 
2025-10-13 10:38:31.341339 | TASK [dlrn_report : Report results to dlrn for the tested hash]
2025-10-13 10:38:31.896539 | controller | skipping: Conditional result was False
2025-10-13 10:38:31.956062 | 
2025-10-13 10:38:31.956205 | PLAY RECAP
2025-10-13 10:38:31.956309 | controller | ok: 0 changed: 0 unreachable: 0 failed: 0 skipped: 10 rescued: 0 ignored: 0
2025-10-13 10:38:31.956365 | 
2025-10-13 10:38:32.059472 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/dlrn/dlrn-report.yaml@master]
2025-10-13 10:38:32.061027 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-10-13 10:38:32.718990 | 
2025-10-13 10:38:32.719107 | PLAY [all]
2025-10-13 10:38:32.738626 | 
2025-10-13 10:38:32.738750 | TASK [include_role : fetch-output]
2025-10-13 10:38:32.777838 | controller | ok
2025-10-13 10:38:32.794584 | 
2025-10-13 10:38:32.794694 | TASK [fetch-output : Set log path for multiple nodes]
2025-10-13 10:38:32.859191 | controller | skipping: Conditional result was False
2025-10-13 10:38:32.867135 | 
2025-10-13 10:38:32.867231 | TASK [fetch-output : Set log path for single node]
2025-10-13 10:38:32.907903 | controller | ok
2025-10-13 10:38:32.914816 | 
2025-10-13 10:38:32.914905 | LOOP [fetch-output : Ensure local output dirs]
2025-10-13 10:38:33.305137 | controller -> localhost | ok: "/var/lib/zuul/builds/e1b10af8f1734a3ba1b89e7dd112fb3d/work/logs"
2025-10-13 10:38:33.517313 | controller -> localhost | ok: "/var/lib/zuul/builds/e1b10af8f1734a3ba1b89e7dd112fb3d/work/artifacts"
2025-10-13 10:38:33.744186 | controller -> localhost | ok: "/var/lib/zuul/builds/e1b10af8f1734a3ba1b89e7dd112fb3d/work/docs"
2025-10-13 10:38:33.757128 | 
2025-10-13 10:38:33.757285 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-10-13 10:38:34.437152 | controller | ok
2025-10-13 10:38:34.437614 | controller | ok: All items complete
2025-10-13 10:38:34.437730 | 
2025-10-13 10:38:34.892058 | controller | ok
2025-10-13 10:38:35.359593 | controller | ok
2025-10-13 10:38:35.372432 | 
2025-10-13 10:38:35.372573 | TASK [include_role : fetch-output-openshift]
2025-10-13 10:38:35.387779 | controller | skipping: Conditional result was False
2025-10-13 10:38:35.394534 | 
2025-10-13 10:38:35.394610 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-10-13 10:38:35.817281 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.007749
2025-10-13 10:38:36.048517 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.012290
2025-10-13 10:38:36.094447 | 
2025-10-13 10:38:36.094618 | PLAY [all]
2025-10-13 10:38:36.112559 | 
2025-10-13 10:38:36.112702 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-10-13 10:38:36.596723 | controller | changed
2025-10-13 10:38:36.645984 | 
2025-10-13 10:38:36.646095 | PLAY RECAP
2025-10-13 10:38:36.646149 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-10-13 10:38:36.646177 | 
2025-10-13 10:38:36.752150 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-10-13 10:38:36.753007 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-10-13 10:38:37.394803 | 
2025-10-13 10:38:37.394930 | PLAY [localhost]
2025-10-13 10:38:37.413876 | 
2025-10-13 10:38:37.413983 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-10-13 10:38:37.838096 | localhost | changed
2025-10-13 10:38:37.851619 | 
2025-10-13 10:38:37.851849 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-10-13 10:38:37.900137 | localhost | ok
2025-10-13 10:38:37.920974 | 
2025-10-13 10:38:37.921186 | TASK [add-fileserver : Create SSH private key tempfile]
2025-10-13 10:38:38.350309 | localhost | changed
2025-10-13 10:38:38.355161 | 
2025-10-13 10:38:38.355228 | TASK [add-fileserver : Create SSH private key from secret]
2025-10-13 10:38:39.122190 | localhost | changed
2025-10-13 10:38:39.133254 | 
2025-10-13 10:38:39.133387 | TASK [add-fileserver : Add fileserver ssh key]
2025-10-13 10:38:39.608880 | localhost | Identity added: /var/lib/zuul/builds/e1b10af8f1734a3ba1b89e7dd112fb3d/work/tmp/ansible.czmglax9 (/var/lib/zuul/builds/e1b10af8f1734a3ba1b89e7dd112fb3d/work/tmp/ansible.czmglax9)
2025-10-13 10:38:39.609195 | localhost | ok: Runtime: 0:00:00.012940
2025-10-13 10:38:39.618887 | 
2025-10-13 10:38:39.619030 | TASK [add-fileserver : Remove SSH private key from disk]
2025-10-13 10:38:39.952016 | localhost | ok: Runtime: 0:00:00.008808
2025-10-13 10:38:39.963855 | 
2025-10-13 10:38:39.964004 | TASK [add-fileserver : Add fileserver to inventory]
2025-10-13 10:38:40.052717 | localhost | changed
2025-10-13 10:38:40.063498 | 
2025-10-13 10:38:40.063644 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-10-13 10:38:40.518789 | localhost | changed
2025-10-13 10:38:40.559246 | 
2025-10-13 10:38:40.559328 | PLAY [localhost]
2025-10-13 10:38:40.576359 | 
2025-10-13 10:38:40.576445 | TASK [Generate bulk log download script]
2025-10-13 10:38:40.598838 | localhost | ok
2025-10-13 10:38:40.615223 | 
2025-10-13 10:38:40.615326 | TASK [local-log-download : Check API endpoint is defined]
2025-10-13 10:38:40.669032 | localhost | ok: All assertions passed
2025-10-13 10:38:40.679214 | 
2025-10-13 10:38:40.679362 | TASK [local-log-download : Create download script]
2025-10-13 10:38:41.144289 | localhost -> localhost | changed
2025-10-13 10:38:41.153576 | 
2025-10-13 10:38:41.153638 | TASK [Register quick-download link]
2025-10-13 10:38:41.203928 | localhost | ok
2025-10-13 10:38:41.219268 | 
2025-10-13 10:38:41.219334 | PLAY [logserver.rdoproject.org]
2025-10-13 10:38:41.228872 | 
2025-10-13 10:38:41.228931 | TASK [Set zuul-log-path fact]
2025-10-13 10:38:41.246720 | logserver.rdoproject.org | ok
2025-10-13 10:38:41.255309 | 
2025-10-13 10:38:41.255372 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-13 10:38:41.291994 | logserver.rdoproject.org | ok
2025-10-13 10:38:41.297196 | 
2025-10-13 10:38:41.297255 | TASK [upload-logs : Create log directories]
2025-10-13 10:38:41.993119 | logserver.rdoproject.org | changed
2025-10-13 10:38:42.010208 | 
2025-10-13 10:38:42.010392 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-10-13 10:38:42.353916 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.009467
2025-10-13 10:38:42.358488 | 
2025-10-13 10:38:42.358793 | TASK [upload-logs : Upload logs to log server]
2025-10-13 10:38:43.088010 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-10-13 10:38:43.091159 | 
2025-10-13 10:38:43.091222 | LOOP [upload-logs : Compress console log and json output]
2025-10-13 10:38:43.159891 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-13 10:38:43.172261 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-13 10:38:43.184377 | 
2025-10-13 10:38:43.184496 | LOOP [upload-logs : Upload compressed console log and json output]
2025-10-13 10:38:43.228906 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-13 10:38:43.229416 | 
2025-10-13 10:38:43.231891 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-13 10:38:43.243091 | 
2025-10-13 10:38:43.243236 | LOOP [upload-logs : Upload console log and json output]