2025-11-27 07:21:25.742649 | Job console starting...
2025-11-27 07:21:25.754373 | Updating repositories
2025-11-27 07:21:26.743593 | Preparing job workspace
2025-11-27 07:21:43.526414 | Running Ansible setup...
2025-11-27 07:21:47.530617 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-11-27 07:21:48.153812 | 
2025-11-27 07:21:48.153920 | PLAY [localhost]
2025-11-27 07:21:48.162669 | 
2025-11-27 07:21:48.162758 | TASK [Gathering Facts]
2025-11-27 07:21:49.162209 | localhost | ok
2025-11-27 07:21:49.198045 | 
2025-11-27 07:21:49.198197 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-11-27 07:21:49.622193 | localhost -> localhost | changed
2025-11-27 07:21:49.634430 | 
2025-11-27 07:21:49.634578 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-11-27 07:21:50.531821 | localhost -> localhost | changed
2025-11-27 07:21:50.550299 | 
2025-11-27 07:21:50.550458 | TASK [Setup log path fact]
2025-11-27 07:21:50.575248 | localhost | ok
2025-11-27 07:21:50.598642 | 
2025-11-27 07:21:50.598779 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-11-27 07:21:50.639759 | localhost | ok
2025-11-27 07:21:50.650606 | 
2025-11-27 07:21:50.650726 | TASK [emit-job-header : Print job information]
2025-11-27 07:21:50.702966 | # Job Information
2025-11-27 07:21:50.703181 | Ansible Version: 2.15.12
2025-11-27 07:21:50.703217 | Job: openstack-k8s-operators-content-provider
2025-11-27 07:21:50.703245 | Pipeline: github-check
2025-11-27 07:21:50.703272 | Executor: ze04.softwarefactory-project.io
2025-11-27 07:21:50.703297 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3505
2025-11-27 07:21:50.703323 | Log URL (when completed): https://logserver.rdoproject.org/fd9/rdoproject.org/fd902e508c9e4e2791bd55d73ca214d6/
2025-11-27 07:21:50.703348 | Event ID: 7a3ab760-cb61-11f0-935e-bbe9329346bf
2025-11-27 07:21:50.709205 | 
2025-11-27 07:21:50.709296 | LOOP [emit-job-header : Print node information]
2025-11-27 07:21:50.823831 | localhost | ok:
2025-11-27 07:21:50.824045 | localhost | # Node Information
2025-11-27 07:21:50.824082 | localhost | Inventory Hostname: controller
2025-11-27 07:21:50.824116 | localhost | Hostname: np0005537497
2025-11-27 07:21:50.824145 | localhost | Username: zuul
2025-11-27 07:21:50.824173 | localhost | Distro: CentOS 9
2025-11-27 07:21:50.824198 | localhost | Provider: vexxhost-nodepool-tripleo
2025-11-27 07:21:50.824223 | localhost | Region: RegionOne
2025-11-27 07:21:50.824246 | localhost | Label: cloud-centos-9-stream-tripleo-vexxhost
2025-11-27 07:21:50.824270 | localhost | Product Name: OpenStack Nova
2025-11-27 07:21:50.824294 | localhost | Interface IP: 38.102.83.103
2025-11-27 07:21:50.853427 | 
2025-11-27 07:21:50.853523 | PLAY [all]
2025-11-27 07:21:50.862984 | 
2025-11-27 07:21:50.863071 | TASK [Gather network facts]
2025-11-27 07:21:51.396375 | controller | ok
2025-11-27 07:21:51.436388 | 
2025-11-27 07:21:51.436474 | TASK [include_role : start-zuul-console]
2025-11-27 07:21:51.459325 | controller | ok
2025-11-27 07:21:51.474680 | 
2025-11-27 07:21:51.474777 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-11-27 07:21:51.979487 | controller | ok
2025-11-27 07:21:52.000019 | 
2025-11-27 07:21:52.000150 | TASK [include_role : add-build-sshkey]
2025-11-27 07:21:52.056020 | controller | ok
2025-11-27 07:21:52.074350 | 
2025-11-27 07:21:52.074441 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-11-27 07:21:52.334122 | controller -> localhost | ok
2025-11-27 07:21:52.342309 | 
2025-11-27 07:21:52.342398 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-11-27 07:21:52.375916 | controller | ok
2025-11-27 07:21:52.395098 | controller | included: /var/lib/zuul/builds/fd902e508c9e4e2791bd55d73ca214d6/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-11-27 07:21:52.403491 | 
2025-11-27 07:21:52.403570 | TASK [add-build-sshkey : Create Temp SSH key]
2025-11-27 07:21:53.028171 | controller -> localhost | Generating public/private rsa key pair.
2025-11-27 07:21:53.028420 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/fd902e508c9e4e2791bd55d73ca214d6/work/fd902e508c9e4e2791bd55d73ca214d6_id_rsa.
2025-11-27 07:21:53.028452 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/fd902e508c9e4e2791bd55d73ca214d6/work/fd902e508c9e4e2791bd55d73ca214d6_id_rsa.pub.
2025-11-27 07:21:53.028473 | controller -> localhost | The key fingerprint is:
2025-11-27 07:21:53.028492 | controller -> localhost | SHA256:8tVLeg8fVe9jg0e5OQShojOH2+0euLyiGWmGwfvzGGw zuul-build-sshkey
2025-11-27 07:21:53.028511 | controller -> localhost | The key's randomart image is:
2025-11-27 07:21:53.028529 | controller -> localhost | +---[RSA 3072]----+
2025-11-27 07:21:53.028546 | controller -> localhost | |             .   |
2025-11-27 07:21:53.028565 | controller -> localhost | |            . .  |
2025-11-27 07:21:53.028582 | controller -> localhost | |         . . .  .|
2025-11-27 07:21:53.028599 | controller -> localhost | |  .     o ..  . +|
2025-11-27 07:21:53.028616 | controller -> localhost | |   o  .=S.. o  +o|
2025-11-27 07:21:53.028633 | controller -> localhost | |    = .o*.oo .+oo|
2025-11-27 07:21:53.028649 | controller -> localhost | |   o E ..o.o+..O.|
2025-11-27 07:21:53.028666 | controller -> localhost | |    =.=.. o..+o.+|
2025-11-27 07:21:53.028701 | controller -> localhost | |     =+..+oo  o  |
2025-11-27 07:21:53.028721 | controller -> localhost | +----[SHA256]-----+
2025-11-27 07:21:53.028767 | controller -> localhost | ok: Runtime: 0:00:00.118332
2025-11-27 07:21:53.035858 | 
2025-11-27 07:21:53.035924 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-11-27 07:21:53.067056 | controller | ok
2025-11-27 07:21:53.076757 | controller | included: /var/lib/zuul/builds/fd902e508c9e4e2791bd55d73ca214d6/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-11-27 07:21:53.084802 | 
2025-11-27 07:21:53.084866 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-11-27 07:21:53.120011 | controller | skipping: Conditional result was False
2025-11-27 07:21:53.130535 | 
2025-11-27 07:21:53.130630 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-11-27 07:21:53.600851 | controller | changed
2025-11-27 07:21:53.612404 | 
2025-11-27 07:21:53.612547 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-11-27 07:21:53.894905 | controller | ok
2025-11-27 07:21:53.907808 | 
2025-11-27 07:21:53.907968 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-11-27 07:21:54.922664 | controller | changed
2025-11-27 07:21:54.934725 | 
2025-11-27 07:21:54.934933 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-11-27 07:21:55.872806 | controller | changed
2025-11-27 07:21:55.884247 | 
2025-11-27 07:21:55.884383 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-11-27 07:21:55.913868 | controller | skipping: Conditional result was False
2025-11-27 07:21:55.925606 | 
2025-11-27 07:21:55.925796 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-11-27 07:21:56.384867 | controller -> localhost | changed
2025-11-27 07:21:56.395273 | 
2025-11-27 07:21:56.395340 | TASK [add-build-sshkey : Add back temp key]
2025-11-27 07:21:56.653129 | controller -> localhost | Identity added: /var/lib/zuul/builds/fd902e508c9e4e2791bd55d73ca214d6/work/fd902e508c9e4e2791bd55d73ca214d6_id_rsa (zuul-build-sshkey)
2025-11-27 07:21:56.653419 | controller -> localhost | ok: Runtime: 0:00:00.010033
2025-11-27 07:21:56.659919 | 
2025-11-27 07:21:56.659982 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-11-27 07:21:57.033878 | controller | ok
2025-11-27 07:21:57.038618 | 
2025-11-27 07:21:57.038693 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-11-27 07:21:57.073109 | controller | skipping: Conditional result was False
2025-11-27 07:21:57.082759 | 
2025-11-27 07:21:57.082829 | TASK [include_role : validate-host]
2025-11-27 07:21:57.112354 | controller | ok
2025-11-27 07:21:57.158830 | 
2025-11-27 07:21:57.158963 | TASK [validate-host : Define zuul_info_dir fact]
2025-11-27 07:21:57.198257 | controller | ok
2025-11-27 07:21:57.203172 | 
2025-11-27 07:21:57.203237 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-11-27 07:21:57.493183 | controller -> localhost | ok
2025-11-27 07:21:57.498874 | 
2025-11-27 07:21:57.498936 | TASK [validate-host : Collect information about the host]
2025-11-27 07:21:58.271470 | controller | ok
2025-11-27 07:21:58.292195 | 
2025-11-27 07:21:58.292327 | TASK [validate-host : Sanitize hostname]
2025-11-27 07:21:58.370291 | controller | ok
2025-11-27 07:21:58.381869 | 
2025-11-27 07:21:58.382006 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-11-27 07:21:58.859199 | controller -> localhost | changed
2025-11-27 07:21:58.870987 | 
2025-11-27 07:21:58.871133 | TASK [validate-host : Collect information about zuul worker]
2025-11-27 07:21:59.338149 | controller | ok
2025-11-27 07:21:59.348540 | 
2025-11-27 07:21:59.348718 | TASK [validate-host : Write out all zuul information for each host]
2025-11-27 07:21:59.877330 | controller -> localhost | changed
2025-11-27 07:21:59.897841 | 
2025-11-27 07:21:59.897973 | TASK [include_role : prepare-workspace-openshift]
2025-11-27 07:21:59.914965 | controller | skipping: Conditional result was False
2025-11-27 07:21:59.925480 | 
2025-11-27 07:21:59.925612 | TASK [include_role : remove-zuul-sshkey]
2025-11-27 07:21:59.942132 | controller | skipping: Conditional result was False
2025-11-27 07:21:59.955640 | 
2025-11-27 07:21:59.955808 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-11-27 07:22:00.237594 | controller | ok: "logs"
2025-11-27 07:22:00.238068 | controller | ok: All items complete
2025-11-27 07:22:00.238150 | 
2025-11-27 07:22:00.478496 | controller | ok: "artifacts"
2025-11-27 07:22:00.736443 | controller | ok: "docs"
2025-11-27 07:22:00.749348 | 
2025-11-27 07:22:00.749532 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-11-27 07:22:01.037721 | controller | changed: "logs"
2025-11-27 07:22:01.290375 | controller | changed: "artifacts"
2025-11-27 07:22:01.532041 | controller | changed: "docs"
2025-11-27 07:22:01.585341 | 
2025-11-27 07:22:01.585476 | PLAY RECAP
2025-11-27 07:22:01.585530 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-11-27 07:22:01.585565 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-11-27 07:22:01.585590 | 
2025-11-27 07:22:01.704269 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-11-27 07:22:01.705058 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-11-27 07:22:02.269829 | 
2025-11-27 07:22:02.269931 | PLAY [all]
2025-11-27 07:22:02.289110 | 
2025-11-27 07:22:02.289189 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-11-27 07:22:02.376384 | controller | ok
2025-11-27 07:22:02.387250 | 
2025-11-27 07:22:02.387404 | TASK [mirror-info-fork : Create /etc/ci]
2025-11-27 07:22:02.906350 | controller | changed
2025-11-27 07:22:02.912149 | 
2025-11-27 07:22:02.912216 | TASK [mirror-info-fork : Install ci_mirror script]
2025-11-27 07:22:04.127251 | controller | changed
2025-11-27 07:22:04.148596 | 
2025-11-27 07:22:04.148796 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-11-27 07:22:04.706267 | controller | changed:
2025-11-27 07:22:04.706593 | controller | {
2025-11-27 07:22:04.706669 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-11-27 07:22:04.707817 | controller | }
2025-11-27 07:22:05.016200 | controller | changed:
2025-11-27 07:22:05.016317 | controller | {
2025-11-27 07:22:05.016351 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-11-27 07:22:05.016378 | controller | }
2025-11-27 07:22:05.289064 | controller | changed:
2025-11-27 07:22:05.289230 | controller | {
2025-11-27 07:22:05.289452 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com"
2025-11-27 07:22:05.289574 | controller | }
2025-11-27 07:22:05.569258 | controller | changed:
2025-11-27 07:22:05.569423 | controller | {
2025-11-27 07:22:05.569477 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-11-27 07:22:05.569517 | controller | }
2025-11-27 07:22:05.882441 | controller | changed:
2025-11-27 07:22:05.882613 | controller | {
2025-11-27 07:22:05.882673 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-11-27 07:22:05.882761 | controller | }
2025-11-27 07:22:06.171218 | controller | changed:
2025-11-27 07:22:06.171599 | controller | {
2025-11-27 07:22:06.171771 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-11-27 07:22:06.171827 | controller | }
2025-11-27 07:22:06.444161 | controller | changed:
2025-11-27 07:22:06.444282 | controller | {
2025-11-27 07:22:06.444321 | 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-11-27 07:22:06.444355 | controller | }
2025-11-27 07:22:06.728843 | controller | changed:
2025-11-27 07:22:06.728954 | controller | {
2025-11-27 07:22:06.728982 | 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-11-27 07:22:06.729005 | controller | }
2025-11-27 07:22:07.025985 | controller | changed:
2025-11-27 07:22:07.026163 | controller | {
2025-11-27 07:22:07.026217 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-11-27 07:22:07.026259 | controller | }
2025-11-27 07:22:07.303599 | controller | changed:
2025-11-27 07:22:07.303802 | controller | {
2025-11-27 07:22:07.303862 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-11-27 07:22:07.303904 | controller | }
2025-11-27 07:22:07.572895 | controller | changed:
2025-11-27 07:22:07.573037 | controller | {
2025-11-27 07:22:07.573087 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-11-27 07:22:07.573127 | controller | }
2025-11-27 07:22:07.868877 | controller | changed:
2025-11-27 07:22:07.869032 | controller | {
2025-11-27 07:22:07.869082 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-11-27 07:22:07.869121 | controller | }
2025-11-27 07:22:08.155652 | controller | changed:
2025-11-27 07:22:08.155815 | controller | {
2025-11-27 07:22:08.155847 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-11-27 07:22:08.155873 | controller | }
2025-11-27 07:22:08.433416 | controller | changed:
2025-11-27 07:22:08.433578 | controller | {
2025-11-27 07:22:08.433629 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-11-27 07:22:08.433721 | controller | }
2025-11-27 07:22:08.674825 | controller | changed:
2025-11-27 07:22:08.674947 | controller | {
2025-11-27 07:22:08.674978 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-11-27 07:22:08.675003 | controller | }
2025-11-27 07:22:08.975420 | controller | changed:
2025-11-27 07:22:08.975570 | controller | {
2025-11-27 07:22:08.975619 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-11-27 07:22:08.975659 | controller | }
2025-11-27 07:22:09.288858 | controller | changed:
2025-11-27 07:22:09.288972 | controller | {
2025-11-27 07:22:09.289002 | 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-11-27 07:22:09.289026 | controller | }
2025-11-27 07:22:09.575958 | controller | changed:
2025-11-27 07:22:09.576106 | controller | {
2025-11-27 07:22:09.576158 | 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-11-27 07:22:09.576217 | controller | }
2025-11-27 07:22:09.859958 | controller | changed:
2025-11-27 07:22:09.860107 | controller | {
2025-11-27 07:22:09.860156 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-11-27 07:22:09.860195 | controller | }
2025-11-27 07:22:10.151878 | controller | changed:
2025-11-27 07:22:10.152383 | controller | {
2025-11-27 07:22:10.152447 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-11-27 07:22:10.152491 | controller | }
2025-11-27 07:22:10.449861 | controller | changed:
2025-11-27 07:22:10.450026 | controller | {
2025-11-27 07:22:10.450127 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-11-27 07:22:10.450150 | controller | }
2025-11-27 07:22:10.725933 | controller | changed:
2025-11-27 07:22:10.726041 | controller | {
2025-11-27 07:22:10.726074 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-11-27 07:22:10.726100 | controller | }
2025-11-27 07:22:11.025934 | controller | changed:
2025-11-27 07:22:11.026081 | controller | {
2025-11-27 07:22:11.026132 | 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-11-27 07:22:11.026172 | controller | }
2025-11-27 07:22:11.289430 | controller | changed:
2025-11-27 07:22:11.289733 | controller | {
2025-11-27 07:22:11.289803 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-11-27 07:22:11.289848 | controller | }
2025-11-27 07:22:11.588796 | controller | changed:
2025-11-27 07:22:11.588933 | controller | {
2025-11-27 07:22:11.588980 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-11-27 07:22:11.589019 | controller | }
2025-11-27 07:22:11.885791 | controller | changed:
2025-11-27 07:22:11.885932 | controller | {
2025-11-27 07:22:11.885981 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-11-27 07:22:11.886020 | controller | }
2025-11-27 07:22:11.907903 | 
2025-11-27 07:22:11.908017 | TASK [Set timezone to UTC]
2025-11-27 07:22:12.610970 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-11-27 07:22:12.618623 | 
2025-11-27 07:22:12.618739 | TASK [Create nodepool directory]
2025-11-27 07:22:12.989992 | controller | changed
2025-11-27 07:22:13.002953 | 
2025-11-27 07:22:13.003088 | TASK [Create nodepool sub_nodes file]
2025-11-27 07:22:13.855849 | controller | changed
2025-11-27 07:22:13.876857 | 
2025-11-27 07:22:13.877049 | TASK [Create nodepool sub_nodes_private file]
2025-11-27 07:22:14.768170 | controller | changed
2025-11-27 07:22:14.779070 | 
2025-11-27 07:22:14.779200 | LOOP [Populate nodepool sub_nodes file]
2025-11-27 07:22:14.839394 | 
2025-11-27 07:22:14.839674 | LOOP [Populate nodepool sub_nodes_private file]
2025-11-27 07:22:14.895237 | 
2025-11-27 07:22:14.895523 | TASK [Create nodepool primary file]
2025-11-27 07:22:14.928964 | controller | skipping: Conditional result was False
2025-11-27 07:22:14.941085 | 
2025-11-27 07:22:14.941203 | TASK [Create nodepool node_private for this node]
2025-11-27 07:22:15.865289 | controller | changed
2025-11-27 07:22:15.875497 | 
2025-11-27 07:22:15.875631 | LOOP [Copy ssh keys to nodepool directory]
2025-11-27 07:22:16.350626 | controller | ok: Item: id_rsa Runtime: 0:00:00.008929
2025-11-27 07:22:16.351840 | 
2025-11-27 07:22:16.598280 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.009508
2025-11-27 07:22:16.612343 | 
2025-11-27 07:22:16.612546 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-11-27 07:22:17.520396 | controller | changed
2025-11-27 07:22:17.525599 | 
2025-11-27 07:22:17.525667 | TASK [Validate sudoers config after edits]
2025-11-27 07:22:17.829475 | controller | /etc/sudoers: parsed OK
2025-11-27 07:22:17.829658 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-11-27 07:22:17.829681 | controller | /etc/sudoers.d/zuul: parsed OK
2025-11-27 07:22:17.829688 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-11-27 07:22:18.058641 | controller | ok: Runtime: 0:00:00.009115
2025-11-27 07:22:18.064372 | 
2025-11-27 07:22:18.064435 | TASK [Show the environment passed in to job shell scripts]
2025-11-27 07:22:18.407637 | controller | SHELL=/bin/bash
2025-11-27 07:22:18.407752 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-11-27 07:22:18.407774 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-11-27 07:22:18.407788 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/05/3505/b4f2ed698d84316d262dfc202a9a3c3293ff14ff
2025-11-27 07:22:18.407801 | controller | PWD=/home/zuul
2025-11-27 07:22:18.407812 | controller | ZUUL_PIPELINE=github-check
2025-11-27 07:22:18.407888 | controller | LOGNAME=zuul
2025-11-27 07:22:18.407901 | controller | XDG_SESSION_TYPE=tty
2025-11-27 07:22:18.407909 | controller | _=/usr/bin/env
2025-11-27 07:22:18.407916 | controller | MOTD_SHOWN=pam
2025-11-27 07:22:18.407922 | controller | HOME=/home/zuul
2025-11-27 07:22:18.407928 | controller | LANG=en_US.UTF-8
2025-11-27 07:22:18.407934 | controller | SSH_CONNECTION=38.102.83.114 54090 38.102.83.103 22
2025-11-27 07:22:18.407940 | 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-11-27 07:22:18.407951 | controller | ZUUL_CHANGE_IDS=3505,b4f2ed698d84316d262dfc202a9a3c3293ff14ff
2025-11-27 07:22:18.407957 | controller | WORKSPACE=/home/zuul/workspace
2025-11-27 07:22:18.407963 | controller | XDG_SESSION_CLASS=user
2025-11-27 07:22:18.407969 | controller | SELINUX_ROLE_REQUESTED=
2025-11-27 07:22:18.407975 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-11-27 07:22:18.407982 | controller | USER=zuul
2025-11-27 07:22:18.407988 | controller | ZUUL_VOTING=True
2025-11-27 07:22:18.407993 | controller | BUILD_TIMEOUT=1800000
2025-11-27 07:22:18.408000 | controller | SELINUX_USE_CURRENT_RANGE=
2025-11-27 07:22:18.408005 | controller | SHLVL=1
2025-11-27 07:22:18.408011 | controller | ZUUL_PATCHSET=b4f2ed698d84316d262dfc202a9a3c3293ff14ff
2025-11-27 07:22:18.408017 | controller | XDG_SESSION_ID=1
2025-11-27 07:22:18.408037 | controller | ZUUL_BRANCH=main
2025-11-27 07:22:18.408043 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-11-27 07:22:18.408050 | controller | SSH_CLIENT=38.102.83.114 54090 22
2025-11-27 07:22:18.408056 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-11-27 07:22:18.408062 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-11-27 07:22:18.408068 | controller | which_declare=declare -f
2025-11-27 07:22:18.408076 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2025-11-27 07:22:18.408082 | controller | SELINUX_LEVEL_REQUESTED=
2025-11-27 07:22:18.408089 | controller | ZUUL_CHANGE=3505
2025-11-27 07:22:18.408095 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-11-27 07:22:18.408101 | controller | ZUUL_UUID=fd902e508c9e4e2791bd55d73ca214d6
2025-11-27 07:22:18.408107 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-11-27 07:22:18.408113 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-11-27 07:22:18.408119 | controller | }
2025-11-27 07:22:18.603121 | controller | ok: Runtime: 0:00:00.009118
2025-11-27 07:22:18.610363 | 
2025-11-27 07:22:18.610442 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-11-27 07:22:18.625005 | controller | skipping: Conditional result was False
2025-11-27 07:22:18.633276 | 
2025-11-27 07:22:18.633359 | TASK [Symlink /home/zuul-worker/workspace]
2025-11-27 07:22:19.197449 | controller | skipping: Conditional result was False
2025-11-27 07:22:19.225441 | 
2025-11-27 07:22:19.225787 | TASK [Ensure legacy workspace directory]
2025-11-27 07:22:19.516574 | controller | changed
2025-11-27 07:22:19.543139 | 
2025-11-27 07:22:19.543278 | PLAY RECAP
2025-11-27 07:22:19.543349 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-11-27 07:22:19.543380 | 
2025-11-27 07:22:19.653164 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-11-27 07:22:19.653897 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/e2e-prepare.yml@main]
2025-11-27 07:22:20.245635 | 
2025-11-27 07:22:20.245759 | PLAY [Run ci/playbooks/e2e-prepare.yml]
2025-11-27 07:22:20.266608 | 
2025-11-27 07:22:20.266701 | TASK [Clone repos in the job workspace]
2025-11-27 07:22:20.287524 | controller | ok
2025-11-27 07:22:20.304046 | 
2025-11-27 07:22:20.304122 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-11-27 07:22:20.705420 | controller | ok
2025-11-27 07:22:20.718296 | 
2025-11-27 07:22:20.718436 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-11-27 07:22:41.965605 | controller | Output suppressed because no_log was given
2025-11-27 07:22:41.987187 | 
2025-11-27 07:22:41.987351 | TASK [Create zuul-output directory]
2025-11-27 07:22:42.433039 | controller | ok
2025-11-27 07:22:42.445205 | 
2025-11-27 07:22:42.445342 | TASK [Install required packages]
2025-11-27 07:23:45.583902 | controller | changed
2025-11-27 07:23:45.589715 | 
2025-11-27 07:23:45.589781 | TASK [Output pip related things]
2025-11-27 07:23:46.581462 | controller | pip 21.3.1 from /usr/lib/python3.9/site-packages/pip (python 3.9)
2025-11-27 07:23:46.656921 | controller | changed
2025-11-27 07:23:46.669177 | 
2025-11-27 07:23:46.669342 | TASK [Install requirements]
2025-11-27 07:24:51.158318 | controller | changed
2025-11-27 07:24:51.178413 | 
2025-11-27 07:24:51.178509 | TASK [Add cronjob to trigger job stats]
2025-11-27 07:24:51.234540 | controller | skipping: Conditional result was False
2025-11-27 07:24:51.242843 | 
2025-11-27 07:24:51.242946 | LOOP [Construct project change list]
2025-11-27 07:24:51.315316 | controller | ok:
2025-11-27 07:24:51.315638 | controller | {
2025-11-27 07:24:51.315727 | controller |   "branch": "main",
2025-11-27 07:24:51.315772 | controller |   "change": "3505",
2025-11-27 07:24:51.315810 | 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.",
2025-11-27 07:24:51.315854 | controller |   "change_url": "https://github.com/openstack-k8s-operators/ci-framework/pull/3505",
2025-11-27 07:24:51.315893 | controller |   "commit_id": "b4f2ed698d84316d262dfc202a9a3c3293ff14ff",
2025-11-27 07:24:51.315931 | controller |   "patchset": "b4f2ed698d84316d262dfc202a9a3c3293ff14ff",
2025-11-27 07:24:51.315967 | controller |   "project": {
2025-11-27 07:24:51.316002 | controller |     "canonical_hostname": "github.com",
2025-11-27 07:24:51.316037 | controller |     "canonical_name": "github.com/openstack-k8s-operators/ci-framework",
2025-11-27 07:24:51.316072 | controller |     "name": "openstack-k8s-operators/ci-framework",
2025-11-27 07:24:51.316106 | controller |     "short_name": "ci-framework",
2025-11-27 07:24:51.316157 | controller |     "src_dir": "src/github.com/openstack-k8s-operators/ci-framework"
2025-11-27 07:24:51.316194 | controller |   },
2025-11-27 07:24:51.316228 | controller |   "topic": null
2025-11-27 07:24:51.316262 | controller | }
2025-11-27 07:24:51.377522 | 
2025-11-27 07:24:51.377651 | PLAY [Run ci/playbooks/build_runner_image.yml]
2025-11-27 07:24:51.398333 | 
2025-11-27 07:24:51.398399 | TASK [Filter out host if needed]
2025-11-27 07:24:51.408315 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-11-27 07:24:51.412898 | 
2025-11-27 07:24:51.412953 | TASK [Get git tag for image tagging]
2025-11-27 07:24:51.946429 | controller | skipping: Conditional result was False
2025-11-27 07:24:51.959250 | 
2025-11-27 07:24:51.959521 | TASK [Set openstack-runner image]
2025-11-27 07:24:51.986567 | controller | skipping: Conditional result was False
2025-11-27 07:24:51.999726 | 
2025-11-27 07:24:51.999867 | TASK [Build openstack-runner image]
2025-11-27 07:24:52.026624 | controller | skipping: Conditional result was False
2025-11-27 07:24:52.039467 | 
2025-11-27 07:24:52.039605 | TASK [Push openstack-runner image]
2025-11-27 07:24:52.066345 | controller | skipping: Conditional result was False
2025-11-27 07:24:52.079220 | 
2025-11-27 07:24:52.079362 | TASK [Make sure ci-framework directory exists]
2025-11-27 07:24:52.106086 | controller | skipping: Conditional result was False
2025-11-27 07:24:52.119733 | 
2025-11-27 07:24:52.119872 | TASK [Create EDPM ansible var file]
2025-11-27 07:24:52.146875 | controller | skipping: Conditional result was False
2025-11-27 07:24:52.185600 | 
2025-11-27 07:24:52.185712 | PLAY RECAP
2025-11-27 07:24:52.185772 | controller | ok: 7 changed: 4 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0
2025-11-27 07:24:52.185800 | 
2025-11-27 07:24:52.295755 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/e2e-prepare.yml@main]
2025-11-27 07:24:52.297254 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-11-27 07:24:52.860550 | 
2025-11-27 07:24:52.860659 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-11-27 07:24:52.882058 | 
2025-11-27 07:24:52.882152 | TASK [Create zuul-output directory]
2025-11-27 07:24:53.314218 | controller | changed
2025-11-27 07:24:53.325735 | 
2025-11-27 07:24:53.325892 | TASK [Slurp Zuul inventory test]
2025-11-27 07:24:53.780726 | controller -> localhost | ok
2025-11-27 07:24:53.794418 | 
2025-11-27 07:24:53.794552 | TASK [Save zuul inventory]
2025-11-27 07:24:54.975632 | controller | changed
2025-11-27 07:24:54.982763 | 
2025-11-27 07:24:54.982844 | TASK [Save zuul vars without the change_message]
2025-11-27 07:24:55.985331 | controller | changed
2025-11-27 07:24:56.026119 | 
2025-11-27 07:24:56.026188 | PLAY RECAP
2025-11-27 07:24:56.026241 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-11-27 07:24:56.026268 | 
2025-11-27 07:24:56.121557 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-11-27 07:24:56.122346 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/content_provider/pre.yml@main]
2025-11-27 07:24:56.667189 | 
2025-11-27 07:24:56.667290 | PLAY [Run ci/playbooks/content_provider/pre.yml]
2025-11-27 07:24:56.687782 | 
2025-11-27 07:24:56.687859 | TASK [Filter out host if needed]
2025-11-27 07:24:56.696707 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-11-27 07:24:56.701242 | 
2025-11-27 07:24:56.701303 | TASK [Clone repos in the job workspace]
2025-11-27 07:24:56.719808 | controller | ok
2025-11-27 07:24:56.736067 | 
2025-11-27 07:24:56.736134 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-11-27 07:24:57.126790 | controller | ok
2025-11-27 07:24:57.139523 | 
2025-11-27 07:24:57.139660 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-11-27 07:24:58.978111 | controller | Output suppressed because no_log was given
2025-11-27 07:24:59.000098 | 
2025-11-27 07:24:59.000239 | TASK [Install ansible-core]
2025-11-27 07:25:03.930388 | controller | changed
2025-11-27 07:25:03.941880 | 
2025-11-27 07:25:03.942018 | TASK [Discover an IPv4 for provider job]
2025-11-27 07:25:04.014510 | controller | ok
2025-11-27 07:25:04.050107 | 
2025-11-27 07:25:04.050220 | PLAY RECAP
2025-11-27 07:25:04.050276 | controller | ok: 4 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-11-27 07:25:04.050305 | 
2025-11-27 07:25:04.154381 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/content_provider/pre.yml@main]
2025-11-27 07:25:04.156102 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/content_provider/run.yml@main]
2025-11-27 07:25:04.785927 | 
2025-11-27 07:25:04.786040 | PLAY [Run ci/playbooks/content_provider/run.yml]
2025-11-27 07:25:04.806575 | 
2025-11-27 07:25:04.806653 | TASK [Filter out host if needed]
2025-11-27 07:25:04.815335 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-11-27 07:25:04.820099 | 
2025-11-27 07:25:04.820165 | TASK [Deploy content provider]
2025-11-27 07:25:06.039542 | controller |
2025-11-27 07:25:07.184237 | controller | PLAY [Bootstrap playbook] ******************************************************
2025-11-27 07:25:07.184298 | controller |
2025-11-27 07:25:07.184315 | controller | TASK [Gathering Facts ] ********************************************************
2025-11-27 07:25:07.184329 | controller | Thursday 27 November 2025  07:25:06 +0000 (0:00:00.039)       0:00:00.039 *****
2025-11-27 07:25:07.184342 | controller | Thursday 27 November 2025  07:25:06 +0000 (0:00:00.037)       0:00:00.037 *****
2025-11-27 07:25:07.184364 | controller | ok: [localhost]
2025-11-27 07:25:07.249261 | controller |
2025-11-27 07:25:07.249310 | 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-11-27 07:25:07.249331 | controller | Thursday 27 November 2025  07:25:07 +0000 (0:00:01.144)       0:00:01.184 *****
2025-11-27 07:25:07.249345 | controller | Thursday 27 November 2025  07:25:07 +0000 (0:00:01.144)       0:00:01.182 *****
2025-11-27 07:25:07.249365 | controller | ok: [localhost]
2025-11-27 07:25:07.343406 | controller |
2025-11-27 07:25:07.343453 | controller | TASK [cifmw_setup : Get customized parameters ci_framework_params={{
2025-11-27 07:25:07.343469 | controller |   hostvars[inventory_hostname] |
2025-11-27 07:25:07.343483 | controller |   dict2items |
2025-11-27 07:25:07.343497 | controller |   selectattr("key", "match",
2025-11-27 07:25:07.343510 | controller |              "^(cifmw|pre|post)_(?!install_yamls|openshift_token|openshift_login|openshift_kubeconfig).*") |
2025-11-27 07:25:07.343524 | controller |   list | items2dict
2025-11-27 07:25:07.343537 | controller | }}] ***
2025-11-27 07:25:07.343550 | controller | Thursday 27 November 2025  07:25:07 +0000 (0:00:00.065)       0:00:01.249 *****
2025-11-27 07:25:07.343563 | controller | Thursday 27 November 2025  07:25:07 +0000 (0:00:00.065)       0:00:01.247 *****
2025-11-27 07:25:07.343584 | controller | ok: [localhost]
2025-11-27 07:25:07.736244 | controller |
2025-11-27 07:25:07.736303 | controller | TASK [install_ca : Ensure target directory exists path={{ cifmw_install_ca_trust_dir }}, state=directory, mode=0755] ***
2025-11-27 07:25:07.736320 | controller | Thursday 27 November 2025  07:25:07 +0000 (0:00:00.093)       0:00:01.343 *****
2025-11-27 07:25:07.736334 | controller | Thursday 27 November 2025  07:25:07 +0000 (0:00:00.093)       0:00:01.341 *****
2025-11-27 07:25:07.736357 | controller | ok: [localhost]
2025-11-27 07:25:07.780971 | controller |
2025-11-27 07:25:07.781022 | 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-11-27 07:25:07.781038 | controller | Thursday 27 November 2025  07:25:07 +0000 (0:00:00.392)       0:00:01.735 *****
2025-11-27 07:25:07.781051 | controller | Thursday 27 November 2025  07:25:07 +0000 (0:00:00.392)       0:00:01.734 *****
2025-11-27 07:25:07.781084 | controller | skipping: [localhost]
2025-11-27 07:25:07.828612 | controller |
2025-11-27 07:25:07.828661 | 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-11-27 07:25:07.828709 | controller | Thursday 27 November 2025  07:25:07 +0000 (0:00:00.044)       0:00:01.780 *****
2025-11-27 07:25:07.828724 | controller | Thursday 27 November 2025  07:25:07 +0000 (0:00:00.044)       0:00:01.779 *****
2025-11-27 07:25:07.828746 | controller | skipping: [localhost]
2025-11-27 07:25:07.879921 | controller |
2025-11-27 07:25:07.879974 | 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-11-27 07:25:07.879992 | controller | Thursday 27 November 2025  07:25:07 +0000 (0:00:00.047)       0:00:01.828 *****
2025-11-27 07:25:07.880006 | controller | Thursday 27 November 2025  07:25:07 +0000 (0:00:00.047)       0:00:01.826 *****
2025-11-27 07:25:07.880028 | controller | skipping: [localhost]
2025-11-27 07:25:09.682340 | controller |
2025-11-27 07:25:09.682422 | controller | TASK [install_ca : Update ca bundle _raw_params=update-ca-trust] ***************
2025-11-27 07:25:09.682439 | controller | Thursday 27 November 2025  07:25:07 +0000 (0:00:00.051)       0:00:01.879 *****
2025-11-27 07:25:09.682473 | controller | Thursday 27 November 2025  07:25:07 +0000 (0:00:00.051)       0:00:01.877 *****
2025-11-27 07:25:09.682505 | controller | changed: [localhost]
2025-11-27 07:25:10.059143 | controller |
2025-11-27 07:25:10.059226 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory paths=/etc/yum.repos.d/, patterns=*.repo, recurse=False] ***
2025-11-27 07:25:10.059243 | controller | Thursday 27 November 2025  07:25:09 +0000 (0:00:01.802)       0:00:03.682 *****
2025-11-27 07:25:10.059257 | controller | Thursday 27 November 2025  07:25:09 +0000 (0:00:01.802)       0:00:03.680 *****
2025-11-27 07:25:10.059284 | controller | ok: [localhost]
2025-11-27 07:25:10.581843 | controller |
2025-11-27 07:25:10.581905 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory path={{ item }}, state=absent] ***
2025-11-27 07:25:10.581922 | controller | Thursday 27 November 2025  07:25:10 +0000 (0:00:00.376)       0:00:04.058 *****
2025-11-27 07:25:10.581936 | controller | Thursday 27 November 2025  07:25:10 +0000 (0:00:00.376)       0:00:04.056 *****
2025-11-27 07:25:10.581960 | controller | changed: [localhost] => (item=/etc/yum.repos.d/centos-addons.repo)
2025-11-27 07:25:11.122281 | controller | changed: [localhost] => (item=/etc/yum.repos.d/centos.repo)
2025-11-27 07:25:11.122360 | controller |
2025-11-27 07:25:11.122376 | controller | TASK [repo_setup : Cleanup existing metadata _raw_params=dnf clean metadata] ***
2025-11-27 07:25:11.122390 | controller | Thursday 27 November 2025  07:25:10 +0000 (0:00:00.522)       0:00:04.581 *****
2025-11-27 07:25:11.122403 | controller | Thursday 27 November 2025  07:25:10 +0000 (0:00:00.522)       0:00:04.579 *****
2025-11-27 07:25:11.122428 | controller | changed: [localhost]
2025-11-27 07:25:11.609869 | controller |
2025-11-27 07:25:11.609938 | 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-11-27 07:25:11.609955 | controller | Thursday 27 November 2025  07:25:11 +0000 (0:00:00.539)       0:00:05.121 *****
2025-11-27 07:25:11.609969 | controller | Thursday 27 November 2025  07:25:11 +0000 (0:00:00.540)       0:00:05.119 *****
2025-11-27 07:25:11.609992 | controller | task path: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/repo_setup/tasks/sync_repos.yml:23
2025-11-27 07:25:11.703818 | controller | fatal: [localhost]: FAILED! =>
2025-11-27 07:25:11.703901 | controller |     changed: false
2025-11-27 07:25:11.703917 | controller |     msg: Source /home/zuul/ci-framework-data/artifacts/repositories/ not found
2025-11-27 07:25:11.703932 | controller |
2025-11-27 07:25:11.703998 | controller | NO MORE HOSTS LEFT *************************************************************
2025-11-27 07:25:11.704012 | controller |
2025-11-27 07:25:11.704193 | controller | PLAY RECAP *********************************************************************
2025-11-27 07:25:11.704221 | controller | localhost                  : ok=8    changed=3    unreachable=0    failed=1    skipped=3    rescued=0    ignored=0
2025-11-27 07:25:11.704240 | controller |
2025-11-27 07:25:11.704258 | controller | Thursday 27 November 2025  07:25:11 +0000 (0:00:00.487)       0:00:05.609 *****
2025-11-27 07:25:11.704276 | controller | ===============================================================================
2025-11-27 07:25:11.704293 | controller | install_ca : Update ca bundle ------------------------------------------- 1.80s
2025-11-27 07:25:11.704310 | controller | Gathering Facts --------------------------------------------------------- 1.14s
2025-11-27 07:25:11.704326 | controller | repo_setup : Cleanup existing metadata ---------------------------------- 0.54s
2025-11-27 07:25:11.704342 | controller | repo_setup : Remove existing repos from /etc/yum.repos.d directory ------ 0.52s
2025-11-27 07:25:11.704358 | controller | repo_setup : Copy generated repos to /etc/yum.repos.d directory --------- 0.49s
2025-11-27 07:25:11.704371 | controller | install_ca : Ensure target directory exists ----------------------------- 0.39s
2025-11-27 07:25:11.704385 | controller | repo_setup : Find existing repos from /etc/yum.repos.d directory -------- 0.38s
2025-11-27 07:25:11.704398 | controller | cifmw_setup : Get customized parameters --------------------------------- 0.09s
2025-11-27 07:25:11.704411 | controller | cifmw_setup : Set custom cifmw PATH reusable fact ----------------------- 0.07s
2025-11-27 07:25:11.704431 | controller | install_ca : Install custom CA bundle from file ------------------------- 0.05s
2025-11-27 07:25:11.704488 | controller | install_ca : Install custom CA bundle from inline ----------------------- 0.05s
2025-11-27 07:25:11.704518 | controller | install_ca : Install internal CA from url ------------------------------- 0.04s
2025-11-27 07:25:11.704536 | controller | Thursday 27 November 2025  07:25:11 +0000 (0:00:00.488)       0:00:05.608 *****
2025-11-27 07:25:11.704553 | controller | ===============================================================================
2025-11-27 07:25:11.704570 | controller | install_ca -------------------------------------------------------------- 2.34s
2025-11-27 07:25:11.704583 | controller | repo_setup -------------------------------------------------------------- 1.93s
2025-11-27 07:25:11.704596 | controller | gather_facts ------------------------------------------------------------ 1.14s
2025-11-27 07:25:11.704610 | controller | cifmw_setup ------------------------------------------------------------- 0.16s
2025-11-27 07:25:11.704623 | controller | ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
2025-11-27 07:25:11.704636 | controller | total ------------------------------------------------------------------- 5.57s
2025-11-27 07:25:11.862187 | controller | ERROR
2025-11-27 07:25:11.862506 | controller | {
2025-11-27 07:25:11.862570 | controller |   "delta": "0:00:06.446897",
2025-11-27 07:25:11.862613 | controller |   "end": "2025-11-27 07:25:11.705370",
2025-11-27 07:25:11.862651 | controller |   "msg": "non-zero return code",
2025-11-27 07:25:11.862748 | controller |   "rc": 2,
2025-11-27 07:25:11.862788 | controller |   "start": "2025-11-27 07:25:05.258473"
2025-11-27 07:25:11.862832 | controller | }
failure
2025-11-27 07:25:11.866898 | 
2025-11-27 07:25:11.867000 | PLAY RECAP
2025-11-27 07:25:11.867076 | controller | ok: 0 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2025-11-27 07:25:11.867120 | 
2025-11-27 07:25:11.982179 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/content_provider/run.yml@main]
2025-11-27 07:25:11.983903 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-11-27 07:25:12.576196 | 
2025-11-27 07:25:12.576309 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-11-27 07:25:12.596637 | 
2025-11-27 07:25:12.596726 | TASK [Filter out host if needed]
2025-11-27 07:25:12.605181 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-11-27 07:25:12.610160 | 
2025-11-27 07:25:12.610221 | TASK [Ensure file is present]
2025-11-27 07:25:13.062500 | controller | ok
2025-11-27 07:25:13.074312 | 
2025-11-27 07:25:13.074449 | TASK [Manage molecule report file]
2025-11-27 07:25:13.623286 | controller | skipping: Conditional result was False
2025-11-27 07:25:13.635871 | 
2025-11-27 07:25:13.636008 | TASK [Check if we get ci-framework-data basedir]
2025-11-27 07:25:13.938784 | controller | ok
2025-11-27 07:25:13.950273 | 
2025-11-27 07:25:13.950406 | TASK [Create ci-framework-data log directory for zuul]
2025-11-27 07:25:14.335179 | controller | changed
2025-11-27 07:25:14.352036 | 
2025-11-27 07:25:14.352228 | TASK [Copy ci-framework interesting files]
2025-11-27 07:25:14.808641 | controller | cp: cannot stat '/home/zuul/ci-framework-data/logs': No such file or directory
2025-11-27 07:25:14.816809 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2025-11-27 07:25:14.901422 | controller | changed
2025-11-27 07:25:14.913249 | 
2025-11-27 07:25:14.913383 | TASK [Get SELinux listing]
2025-11-27 07:25:15.464308 | controller | changed
2025-11-27 07:25:15.477320 | 
2025-11-27 07:25:15.477456 | TASK [Generate log index]
2025-11-27 07:25:16.415842 | controller | changed
2025-11-27 07:25:16.426705 | 
2025-11-27 07:25:16.426835 | TASK [Get some env related data]
2025-11-27 07:25:17.148868 | controller | /home/zuul/.local/bin/ansible
2025-11-27 07:25:17.977705 | controller | changed
2025-11-27 07:25:17.988984 | 
2025-11-27 07:25:17.989141 | TASK [Generate list of logs to collect in home directory]
2025-11-27 07:25:18.411717 | controller | ok: All paths examined
2025-11-27 07:25:18.418753 | 
2025-11-27 07:25:18.418832 | LOOP [Copy logs from home directory]
2025-11-27 07:25:18.854998 | controller | changed:
2025-11-27 07:25:18.855396 | controller | {
2025-11-27 07:25:18.855471 | controller |   "atime": 1764228273.070002,
2025-11-27 07:25:18.855536 | controller |   "ctime": 1764228311.608292,
2025-11-27 07:25:18.855598 | controller |   "dev": 64513,
2025-11-27 07:25:18.855639 | controller |   "gid": 1000,
2025-11-27 07:25:18.855712 | controller |   "gr_name": "zuul",
2025-11-27 07:25:18.855761 | controller |   "inode": 4330236,
2025-11-27 07:25:18.855798 | controller |   "isblk": false,
2025-11-27 07:25:18.855833 | controller |   "ischr": false,
2025-11-27 07:25:18.855867 | controller |   "isdir": false,
2025-11-27 07:25:18.855900 | controller |   "isfifo": false,
2025-11-27 07:25:18.855934 | controller |   "isgid": false,
2025-11-27 07:25:18.855967 | controller |   "islnk": false,
2025-11-27 07:25:18.856000 | controller |   "isreg": true,
2025-11-27 07:25:18.856032 | controller |   "issock": false,
2025-11-27 07:25:18.856064 | controller |   "isuid": false,
2025-11-27 07:25:18.856097 | controller |   "mode": "0644",
2025-11-27 07:25:18.856129 | controller |   "mtime": 1764228311.608292,
2025-11-27 07:25:18.856162 | controller |   "nlink": 1,
2025-11-27 07:25:18.856201 | controller |   "path": "/home/zuul/ansible.log",
2025-11-27 07:25:18.856254 | controller |   "pw_name": "zuul",
2025-11-27 07:25:18.856518 | controller |   "rgrp": true,
2025-11-27 07:25:18.862084 | controller |   "roth": true,
2025-11-27 07:25:18.862145 | controller |   "rusr": true,
2025-11-27 07:25:18.862184 | controller |   "size": 16926,
2025-11-27 07:25:18.862220 | controller |   "uid": 1000,
2025-11-27 07:25:18.862269 | controller |   "wgrp": false,
2025-11-27 07:25:18.862322 | controller |   "woth": false,
2025-11-27 07:25:18.862368 | controller |   "wusr": true,
2025-11-27 07:25:18.862415 | controller |   "xgrp": false,
2025-11-27 07:25:18.862459 | controller |   "xoth": false,
2025-11-27 07:25:18.862503 | controller |   "xusr": false
2025-11-27 07:25:18.862545 | controller | }
2025-11-27 07:25:18.876809 | 
2025-11-27 07:25:18.877001 | TASK [Copy crio stats log file]
2025-11-27 07:25:18.905146 | controller | skipping: Conditional result was False
2025-11-27 07:25:18.918146 | 
2025-11-27 07:25:18.918278 | TASK [Get SELinux related data]
2025-11-27 07:25:19.276310 | controller | <no matches>
2025-11-27 07:25:19.467319 | controller | ERROR
2025-11-27 07:25:19.467664 | controller | {
2025-11-27 07:25:19.467796 | controller |   "delta": "0:00:00.013808",
2025-11-27 07:25:19.467855 | controller |   "end": "2025-11-27 07:25:19.278011",
2025-11-27 07:25:19.467894 | controller |   "msg": "non-zero return code",
2025-11-27 07:25:19.467930 | controller |   "rc": 1,
2025-11-27 07:25:19.467967 | controller |   "start": "2025-11-27 07:25:19.264203"
2025-11-27 07:25:19.468002 | controller | }
2025-11-27 07:25:19.468052 | controller | ERROR: Ignoring Errors
2025-11-27 07:25:19.479452 | 
2025-11-27 07:25:19.479577 | TASK [Create system configuration directory]
2025-11-27 07:25:19.702337 | controller | changed
2025-11-27 07:25:19.714104 | 
2025-11-27 07:25:19.714231 | TASK [Get some of the system configurations]
2025-11-27 07:25:20.264284 | controller | changed
2025-11-27 07:25:20.275220 | 
2025-11-27 07:25:20.275342 | TASK [Copy generated documentation if available]
2025-11-27 07:25:20.302886 | controller | skipping: Conditional result was False
2025-11-27 07:25:20.315202 | 
2025-11-27 07:25:20.315327 | TASK [Copy generated AsciiDoc documentation if available]
2025-11-27 07:25:20.341868 | controller | skipping: Conditional result was False
2025-11-27 07:25:20.354273 | 
2025-11-27 07:25:20.354398 | TASK [Compress logs bigger than 2MB]
2025-11-27 07:25:20.903612 | controller | changed
2025-11-27 07:25:20.915579 | 
2025-11-27 07:25:20.915768 | TASK [Copy files from workspace on node]
2025-11-27 07:25:20.944927 | controller | ok
2025-11-27 07:25:20.989882 | 
2025-11-27 07:25:20.989973 | TASK [fetch-output : Set log path for multiple nodes]
2025-11-27 07:25:21.025126 | controller | skipping: Conditional result was False
2025-11-27 07:25:21.037020 | 
2025-11-27 07:25:21.037155 | TASK [fetch-output : Set log path for single node]
2025-11-27 07:25:21.084466 | controller | ok
2025-11-27 07:25:21.095248 | 
2025-11-27 07:25:21.095379 | LOOP [fetch-output : Ensure local output dirs]
2025-11-27 07:25:21.390589 | controller -> localhost | ok: "/var/lib/zuul/builds/fd902e508c9e4e2791bd55d73ca214d6/work/logs"
2025-11-27 07:25:21.391102 | controller -> localhost | changed: All items complete
2025-11-27 07:25:21.391162 | 
2025-11-27 07:25:21.643163 | controller -> localhost | changed: "/var/lib/zuul/builds/fd902e508c9e4e2791bd55d73ca214d6/work/artifacts"
2025-11-27 07:25:21.898785 | controller -> localhost | changed: "/var/lib/zuul/builds/fd902e508c9e4e2791bd55d73ca214d6/work/docs"
2025-11-27 07:25:21.913133 | 
2025-11-27 07:25:21.913251 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-11-27 07:25:22.889440 | controller | changed:
2025-11-27 07:25:22.889649 | controller | .d..t...... ./
2025-11-27 07:25:22.889701 | controller | >f+++++++++ README.html
2025-11-27 07:25:22.889729 | controller | >f+++++++++ ansible.log
2025-11-27 07:25:22.889754 | controller | >f+++++++++ dmesg.log
2025-11-27 07:25:22.889774 | controller | >f+++++++++ installed-pkgs.log
2025-11-27 07:25:22.889807 | controller | >f+++++++++ python.log
2025-11-27 07:25:22.889835 | controller | >f+++++++++ registries.conf
2025-11-27 07:25:22.889854 | controller | >f+++++++++ selinux-denials.log
2025-11-27 07:25:22.889875 | controller | >f+++++++++ selinux-listing.log
2025-11-27 07:25:22.889898 | controller | cd+++++++++ ci-framework-data/
2025-11-27 07:25:22.889920 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-11-27 07:25:22.889941 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-11-27 07:25:22.890102 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-11-27 07:25:22.890129 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-11-27 07:25:22.890148 | controller | cd+++++++++ registries.conf.d/
2025-11-27 07:25:22.890164 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-11-27 07:25:22.890181 | controller | cd+++++++++ system-config/
2025-11-27 07:25:22.890198 | controller | cd+++++++++ system-config/libvirt/
2025-11-27 07:25:22.890214 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-11-27 07:25:22.890231 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-11-27 07:25:23.410878 | controller | changed: .d..t...... ./
2025-11-27 07:25:23.951848 | controller | changed: .d..t...... ./
2025-11-27 07:25:24.006421 | 
2025-11-27 07:25:24.006618 | TASK [Return artifact to Zuul]
2025-11-27 07:25:24.044356 | controller | skipping: Conditional result was False
2025-11-27 07:25:24.085792 | 
2025-11-27 07:25:24.085879 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-11-27 07:25:24.086018 | 
2025-11-27 07:25:24.086061 | PLAY RECAP
2025-11-27 07:25:24.086118 | controller | ok: 16 changed: 12 unreachable: 0 failed: 0 skipped: 6 rescued: 0 ignored: 1
2025-11-27 07:25:24.086152 | 
2025-11-27 07:25:24.197884 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-11-27 07:25:24.198615 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-11-27 07:25:24.763971 | 
2025-11-27 07:25:24.764072 | PLAY [all]
2025-11-27 07:25:24.783474 | 
2025-11-27 07:25:24.783548 | TASK [include_role : fetch-output]
2025-11-27 07:25:24.822916 | controller | ok
2025-11-27 07:25:24.839068 | 
2025-11-27 07:25:24.839147 | TASK [fetch-output : Set log path for multiple nodes]
2025-11-27 07:25:24.914119 | controller | skipping: Conditional result was False
2025-11-27 07:25:24.920739 | 
2025-11-27 07:25:24.920810 | TASK [fetch-output : Set log path for single node]
2025-11-27 07:25:24.967852 | controller | ok
2025-11-27 07:25:24.979027 | 
2025-11-27 07:25:24.979164 | LOOP [fetch-output : Ensure local output dirs]
2025-11-27 07:25:25.398661 | controller -> localhost | ok: "/var/lib/zuul/builds/fd902e508c9e4e2791bd55d73ca214d6/work/logs"
2025-11-27 07:25:25.655753 | controller -> localhost | ok: "/var/lib/zuul/builds/fd902e508c9e4e2791bd55d73ca214d6/work/artifacts"
2025-11-27 07:25:25.910758 | controller -> localhost | ok: "/var/lib/zuul/builds/fd902e508c9e4e2791bd55d73ca214d6/work/docs"
2025-11-27 07:25:25.925223 | 
2025-11-27 07:25:25.925290 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-11-27 07:25:26.636199 | controller | ok
2025-11-27 07:25:26.636540 | controller | ok: All items complete
2025-11-27 07:25:26.636603 | 
2025-11-27 07:25:27.116625 | controller | ok
2025-11-27 07:25:27.610110 | controller | ok
2025-11-27 07:25:27.622921 | 
2025-11-27 07:25:27.623047 | TASK [include_role : fetch-output-openshift]
2025-11-27 07:25:27.647594 | controller | skipping: Conditional result was False
2025-11-27 07:25:27.653487 | 
2025-11-27 07:25:27.653554 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-11-27 07:25:28.101056 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.012530
2025-11-27 07:25:28.388048 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.012948
2025-11-27 07:25:28.441176 | 
2025-11-27 07:25:28.441319 | PLAY [all]
2025-11-27 07:25:28.461823 | 
2025-11-27 07:25:28.461912 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-11-27 07:25:29.027756 | controller | changed
2025-11-27 07:25:29.075629 | 
2025-11-27 07:25:29.075811 | PLAY RECAP
2025-11-27 07:25:29.075867 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-11-27 07:25:29.075896 | 
2025-11-27 07:25:29.189640 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-11-27 07:25:29.191184 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-11-27 07:25:29.829895 | 
2025-11-27 07:25:29.830003 | PLAY [localhost]
2025-11-27 07:25:29.847167 | 
2025-11-27 07:25:29.847297 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-11-27 07:25:30.296614 | localhost | changed
2025-11-27 07:25:30.306843 | 
2025-11-27 07:25:30.307099 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-11-27 07:25:30.353797 | localhost | ok
2025-11-27 07:25:30.372929 | 
2025-11-27 07:25:30.373067 | TASK [add-fileserver : Create SSH private key tempfile]
2025-11-27 07:25:30.857098 | localhost | changed
2025-11-27 07:25:30.867731 | 
2025-11-27 07:25:30.867875 | TASK [add-fileserver : Create SSH private key from secret]
2025-11-27 07:25:31.728336 | localhost | changed
2025-11-27 07:25:31.739614 | 
2025-11-27 07:25:31.739803 | TASK [add-fileserver : Add fileserver ssh key]
2025-11-27 07:25:32.195858 | localhost | Identity added: /var/lib/zuul/builds/fd902e508c9e4e2791bd55d73ca214d6/work/tmp/ansible.bh8ppot_ (/var/lib/zuul/builds/fd902e508c9e4e2791bd55d73ca214d6/work/tmp/ansible.bh8ppot_)
2025-11-27 07:25:32.196267 | localhost | ok: Runtime: 0:00:00.013100
2025-11-27 07:25:32.208336 | 
2025-11-27 07:25:32.208539 | TASK [add-fileserver : Remove SSH private key from disk]
2025-11-27 07:25:32.494157 | localhost | ok: Runtime: 0:00:00.005091
2025-11-27 07:25:32.499118 | 
2025-11-27 07:25:32.499182 | TASK [add-fileserver : Add fileserver to inventory]
2025-11-27 07:25:32.548612 | localhost | changed
2025-11-27 07:25:32.552976 | 
2025-11-27 07:25:32.553051 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-11-27 07:25:32.924367 | localhost | changed
2025-11-27 07:25:32.964389 | 
2025-11-27 07:25:32.964473 | PLAY [localhost]
2025-11-27 07:25:32.980754 | 
2025-11-27 07:25:32.980866 | TASK [Generate bulk log download script]
2025-11-27 07:25:33.001222 | localhost | ok
2025-11-27 07:25:33.017459 | 
2025-11-27 07:25:33.017544 | TASK [local-log-download : Check API endpoint is defined]
2025-11-27 07:25:33.048256 | localhost | ok: All assertions passed
2025-11-27 07:25:33.054730 | 
2025-11-27 07:25:33.054816 | TASK [local-log-download : Create download script]
2025-11-27 07:25:33.490243 | localhost -> localhost | changed
2025-11-27 07:25:33.551643 | 
2025-11-27 07:25:33.551801 | TASK [Register quick-download link]
2025-11-27 07:25:33.582423 | localhost | ok
2025-11-27 07:25:33.604402 | 
2025-11-27 07:25:33.604482 | PLAY [logserver.rdoproject.org]
2025-11-27 07:25:33.618792 | 
2025-11-27 07:25:33.618874 | TASK [Set zuul-log-path fact]
2025-11-27 07:25:33.637568 | logserver.rdoproject.org | ok
2025-11-27 07:25:33.650614 | 
2025-11-27 07:25:33.650726 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-11-27 07:25:33.679635 | logserver.rdoproject.org | ok
2025-11-27 07:25:33.687065 | 
2025-11-27 07:25:33.687244 | TASK [upload-logs : Create log directories]
2025-11-27 07:25:34.380835 | logserver.rdoproject.org | changed
2025-11-27 07:25:34.387598 | 
2025-11-27 07:25:34.387773 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-11-27 07:25:34.710607 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.007899
2025-11-27 07:25:34.720339 | 
2025-11-27 07:25:34.720469 | TASK [upload-logs : Upload logs to log server]
2025-11-27 07:25:35.480552 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-11-27 07:25:35.487136 | 
2025-11-27 07:25:35.487294 | LOOP [upload-logs : Compress console log and json output]
2025-11-27 07:25:35.554846 | logserver.rdoproject.org | skipping: Conditional result was False
2025-11-27 07:25:35.567518 | logserver.rdoproject.org | skipping: Conditional result was False
2025-11-27 07:25:35.585130 | 
2025-11-27 07:25:35.585475 | LOOP [upload-logs : Upload compressed console log and json output]
2025-11-27 07:25:35.634951 | logserver.rdoproject.org | skipping: Conditional result was False
2025-11-27 07:25:35.635222 | 
2025-11-27 07:25:35.638062 | logserver.rdoproject.org | skipping: Conditional result was False
2025-11-27 07:25:35.650047 | 
2025-11-27 07:25:35.650192 | LOOP [upload-logs : Upload console log and json output]