2025-12-05 23:36:28.431835 | Job console starting...
2025-12-05 23:36:28.647502 | Updating repositories
2025-12-05 23:36:29.490736 | Preparing job workspace
2025-12-05 23:36:44.491072 | Running Ansible setup...
2025-12-05 23:36:50.869133 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-12-05 23:36:52.398339 | 
2025-12-05 23:36:52.398450 | PLAY [localhost]
2025-12-05 23:36:52.432096 | 
2025-12-05 23:36:52.432167 | TASK [Gathering Facts]
2025-12-05 23:36:54.983995 | localhost | ok
2025-12-05 23:36:55.030470 | 
2025-12-05 23:36:55.030577 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-12-05 23:36:56.536473 | localhost -> localhost | changed
2025-12-05 23:36:56.543521 | 
2025-12-05 23:36:56.543587 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-12-05 23:36:59.442325 | localhost -> localhost | changed
2025-12-05 23:36:59.467368 | 
2025-12-05 23:36:59.467466 | TASK [Setup log path fact]
2025-12-05 23:36:59.534455 | localhost | ok
2025-12-05 23:36:59.570002 | 
2025-12-05 23:36:59.570088 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-12-05 23:36:59.661458 | localhost | ok
2025-12-05 23:36:59.677746 | 
2025-12-05 23:36:59.677816 | TASK [emit-job-header : Print job information]
2025-12-05 23:36:59.770001 | # Job Information
2025-12-05 23:36:59.770130 | Ansible Version: 2.15.12
2025-12-05 23:36:59.770161 | Job: cifmw-molecule-copy_container
2025-12-05 23:36:59.770186 | Pipeline: github-check
2025-12-05 23:36:59.770207 | Executor: ibm-bm3-ze.softwarefactory-project.io
2025-12-05 23:36:59.770241 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3539
2025-12-05 23:36:59.770263 | Log URL (when completed): https://logserver.rdoproject.org/671/rdoproject.org/671d8a9f68d14617af5bf1cc84d77627/
2025-12-05 23:36:59.770283 | Event ID: 0eecf260-d233-11f0-9ab7-da45df476e85
2025-12-05 23:36:59.779604 | 
2025-12-05 23:36:59.779731 | LOOP [emit-job-header : Print node information]
2025-12-05 23:37:00.168069 | localhost | ok:
2025-12-05 23:37:00.168247 | localhost | # Node Information
2025-12-05 23:37:00.168282 | localhost | Inventory Hostname: controller
2025-12-05 23:37:00.168308 | localhost | Hostname: np0005548206
2025-12-05 23:37:00.168330 | localhost | Username: zuul
2025-12-05 23:37:00.168352 | localhost | Distro: CentOS 9
2025-12-05 23:37:00.168371 | localhost | Provider: ibm-bm3-nodepool
2025-12-05 23:37:00.168389 | localhost | Region: regionOne
2025-12-05 23:37:00.168412 | localhost | Label: cloud-centos-9-stream-tripleo-ibm
2025-12-05 23:37:00.168433 | localhost | Product Name: OpenStack Compute
2025-12-05 23:37:00.168462 | localhost | Interface IP: 192.168.25.199
2025-12-05 23:37:00.244001 | 
2025-12-05 23:37:00.244084 | PLAY [all]
2025-12-05 23:37:00.266306 | 
2025-12-05 23:37:00.266381 | TASK [Gather network facts]
2025-12-05 23:37:00.730290 | controller | ok
2025-12-05 23:37:00.800102 | 
2025-12-05 23:37:00.800191 | TASK [include_role : start-zuul-console]
2025-12-05 23:37:00.893513 | controller | ok
2025-12-05 23:37:00.938617 | 
2025-12-05 23:37:00.971792 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-12-05 23:37:01.798018 | controller | ok
2025-12-05 23:37:01.834401 | 
2025-12-05 23:37:01.834496 | TASK [include_role : add-build-sshkey]
2025-12-05 23:37:01.953908 | controller | ok
2025-12-05 23:37:01.972723 | 
2025-12-05 23:37:01.972832 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-12-05 23:37:02.839782 | controller -> localhost | ok
2025-12-05 23:37:02.845439 | 
2025-12-05 23:37:02.845526 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-12-05 23:37:02.956889 | controller | ok
2025-12-05 23:37:02.974787 | controller | included: /var/lib/zuul/builds/671d8a9f68d14617af5bf1cc84d77627/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-12-05 23:37:02.980602 | 
2025-12-05 23:37:02.980696 | TASK [add-build-sshkey : Create Temp SSH key]
2025-12-05 23:37:04.741300 | controller -> localhost | Generating public/private rsa key pair.
2025-12-05 23:37:04.741470 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/671d8a9f68d14617af5bf1cc84d77627/work/671d8a9f68d14617af5bf1cc84d77627_id_rsa.
2025-12-05 23:37:04.741507 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/671d8a9f68d14617af5bf1cc84d77627/work/671d8a9f68d14617af5bf1cc84d77627_id_rsa.pub.
2025-12-05 23:37:04.741533 | controller -> localhost | The key fingerprint is:
2025-12-05 23:37:04.741555 | controller -> localhost | SHA256:ea04vRMZjyIdXolgWBr2DSiusJeTdFk6vYsdbMOWq3c zuul-build-sshkey
2025-12-05 23:37:04.741576 | controller -> localhost | The key's randomart image is:
2025-12-05 23:37:04.741595 | controller -> localhost | +---[RSA 3072]----+
2025-12-05 23:37:04.741613 | controller -> localhost | |    o+o          |
2025-12-05 23:37:04.741632 | controller -> localhost | |  ..o++o         |
2025-12-05 23:37:04.741666 | controller -> localhost | | . ..*.... .     |
2025-12-05 23:37:04.741694 | controller -> localhost | |. o = . o.+.     |
2025-12-05 23:37:04.741714 | controller -> localhost | |.+ + + =So.=.    |
2025-12-05 23:37:04.741733 | controller -> localhost | |o =   @ +++..    |
2025-12-05 23:37:04.741752 | controller -> localhost | | . . = *o.o.     |
2025-12-05 23:37:04.741773 | controller -> localhost | |    . = E...     |
2025-12-05 23:37:04.741792 | controller -> localhost | |    .o .  ..     |
2025-12-05 23:37:04.741811 | controller -> localhost | +----[SHA256]-----+
2025-12-05 23:37:04.741855 | controller -> localhost | ok: Runtime: 0:00:00.442286
2025-12-05 23:37:04.747369 | 
2025-12-05 23:37:04.747431 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-12-05 23:37:04.846202 | controller | ok
2025-12-05 23:37:04.867151 | controller | included: /var/lib/zuul/builds/671d8a9f68d14617af5bf1cc84d77627/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-12-05 23:37:04.901229 | 
2025-12-05 23:37:04.901298 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-12-05 23:37:05.001521 | controller | skipping: Conditional result was False
2025-12-05 23:37:05.006689 | 
2025-12-05 23:37:05.006760 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-12-05 23:37:05.830683 | controller | changed
2025-12-05 23:37:05.835542 | 
2025-12-05 23:37:05.835612 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-12-05 23:37:06.135329 | controller | ok
2025-12-05 23:37:06.140269 | 
2025-12-05 23:37:06.140342 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-12-05 23:37:06.985225 | controller | changed
2025-12-05 23:37:06.990384 | 
2025-12-05 23:37:06.990466 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-12-05 23:37:07.906718 | controller | changed
2025-12-05 23:37:07.912497 | 
2025-12-05 23:37:07.912571 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-12-05 23:37:07.999767 | controller | skipping: Conditional result was False
2025-12-05 23:37:08.005841 | 
2025-12-05 23:37:08.005918 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-12-05 23:37:08.880537 | controller -> localhost | changed
2025-12-05 23:37:08.896272 | 
2025-12-05 23:37:08.896355 | TASK [add-build-sshkey : Add back temp key]
2025-12-05 23:37:09.871951 | controller -> localhost | Identity added: /var/lib/zuul/builds/671d8a9f68d14617af5bf1cc84d77627/work/671d8a9f68d14617af5bf1cc84d77627_id_rsa (zuul-build-sshkey)
2025-12-05 23:37:09.872148 | controller -> localhost | ok: Runtime: 0:00:00.033447
2025-12-05 23:37:09.877370 | 
2025-12-05 23:37:09.877440 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-12-05 23:37:10.464452 | controller | ok
2025-12-05 23:37:10.469000 | 
2025-12-05 23:37:10.469066 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-12-05 23:37:10.568199 | controller | skipping: Conditional result was False
2025-12-05 23:37:10.579436 | 
2025-12-05 23:37:10.579532 | TASK [include_role : validate-host]
2025-12-05 23:37:10.661016 | controller | ok
2025-12-05 23:37:10.742917 | 
2025-12-05 23:37:10.743022 | TASK [validate-host : Define zuul_info_dir fact]
2025-12-05 23:37:10.860298 | controller | ok
2025-12-05 23:37:10.864717 | 
2025-12-05 23:37:10.864780 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-12-05 23:37:12.047017 | controller -> localhost | ok
2025-12-05 23:37:12.054010 | 
2025-12-05 23:37:12.054098 | TASK [validate-host : Collect information about the host]
2025-12-05 23:37:12.811181 | controller | ok
2025-12-05 23:37:12.854992 | 
2025-12-05 23:37:12.855083 | TASK [validate-host : Sanitize hostname]
2025-12-05 23:37:13.055778 | controller | ok
2025-12-05 23:37:13.061529 | 
2025-12-05 23:37:13.061608 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-12-05 23:37:14.781965 | controller -> localhost | changed
2025-12-05 23:37:14.787564 | 
2025-12-05 23:37:14.787684 | TASK [validate-host : Collect information about zuul worker]
2025-12-05 23:37:15.682243 | controller | ok
2025-12-05 23:37:15.687251 | 
2025-12-05 23:37:15.687328 | TASK [validate-host : Write out all zuul information for each host]
2025-12-05 23:37:17.268937 | controller -> localhost | changed
2025-12-05 23:37:17.278852 | 
2025-12-05 23:37:17.278927 | TASK [include_role : prepare-workspace-openshift]
2025-12-05 23:37:17.337531 | controller | skipping: Conditional result was False
2025-12-05 23:37:17.347113 | 
2025-12-05 23:37:17.347203 | TASK [include_role : remove-zuul-sshkey]
2025-12-05 23:37:17.393101 | controller | skipping: Conditional result was False
2025-12-05 23:37:17.400324 | 
2025-12-05 23:37:17.400407 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-12-05 23:37:17.766988 | controller | ok: "logs"
2025-12-05 23:37:17.767182 | controller | ok: All items complete
2025-12-05 23:37:17.767212 | 
2025-12-05 23:37:17.955705 | controller | ok: "artifacts"
2025-12-05 23:37:18.155094 | controller | ok: "docs"
2025-12-05 23:37:18.181076 | 
2025-12-05 23:37:18.181173 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-12-05 23:37:18.481140 | controller | changed: "logs"
2025-12-05 23:37:18.700097 | controller | changed: "artifacts"
2025-12-05 23:37:18.907098 | controller | changed: "docs"
2025-12-05 23:37:18.989388 | 
2025-12-05 23:37:18.989479 | PLAY RECAP
2025-12-05 23:37:18.993099 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-12-05 23:37:18.993158 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-12-05 23:37:18.993186 | 
2025-12-05 23:37:19.497134 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-12-05 23:37:19.497909 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-12-05 23:37:21.474030 | 
2025-12-05 23:37:21.474163 | PLAY [all]
2025-12-05 23:37:21.537989 | 
2025-12-05 23:37:21.538083 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-12-05 23:37:21.669608 | controller | ok
2025-12-05 23:37:21.684382 | 
2025-12-05 23:37:21.684505 | TASK [mirror-info-fork : Create /etc/ci]
2025-12-05 23:37:22.406245 | controller | changed
2025-12-05 23:37:22.411711 | 
2025-12-05 23:37:22.411788 | TASK [mirror-info-fork : Install ci_mirror script]
2025-12-05 23:37:24.336862 | controller | changed
2025-12-05 23:37:24.359971 | 
2025-12-05 23:37:24.360064 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-12-05 23:37:25.189308 | controller | changed:
2025-12-05 23:37:25.189489 | controller | {
2025-12-05 23:37:25.189535 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-12-05 23:37:25.190726 | controller | }
2025-12-05 23:37:25.434174 | controller | changed:
2025-12-05 23:37:25.434265 | controller | {
2025-12-05 23:37:25.434296 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-12-05 23:37:25.434319 | controller | }
2025-12-05 23:37:25.702000 | controller | changed:
2025-12-05 23:37:25.702726 | controller | {
2025-12-05 23:37:25.702759 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com"
2025-12-05 23:37:25.702782 | controller | }
2025-12-05 23:37:25.966046 | controller | changed:
2025-12-05 23:37:25.967847 | controller | {
2025-12-05 23:37:25.967897 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-12-05 23:37:25.967925 | controller | }
2025-12-05 23:37:26.230509 | controller | changed:
2025-12-05 23:37:26.230612 | controller | {
2025-12-05 23:37:26.230640 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-12-05 23:37:26.230684 | controller | }
2025-12-05 23:37:26.497432 | controller | changed:
2025-12-05 23:37:26.497524 | controller | {
2025-12-05 23:37:26.497554 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-12-05 23:37:26.497577 | controller | }
2025-12-05 23:37:26.770627 | controller | changed:
2025-12-05 23:37:26.770735 | controller | {
2025-12-05 23:37:26.770765 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDLOQd4ZLtkZXQGY6UwAr/06ppWQK4fDO3HaqxPk98csyOCBXsliSKK39Bso828+5srIXiW7aI6aC9P5mwi4mUZlGPfJlQbfrcGvY+b/SocuvaGK+1RrHLoJCT52LBhwgrzlXio2jeksZeein8iaTrhsPrOAs7KggIL/rB9hEiB3NaOPWhhoCP4vlW6MEMExGcqB/1FVxXFBPnLkEyW0Lk7ycVflZl2ocRxbfjZi0+tI1Wlinp8PvSQSc/WVrAcDgKjc/mB4ODPOyYy3G8FHgfMsrXSDEyjBKgLKMsdCrAUcqJQWjkqXleXSYOV4q3pzL+9umK+q/e3P/bIoSFQzmJKTU1eDfuvPXmow9F5H54fii/Da7ezlMJ+wPGHJrRAkmzvMbALy7xwswLhZMkOGNtRcPqaKYRmIBKpw3o6bCTtcNUHOtOQnzwY8JzrM2eBWJBXAANYw+9/ho80JIiwhg29CFNpVBuHbql2YxJQNrnl90guN65rYNpDxdIluweyUf8= anbanerj@kaermorhen"
2025-12-05 23:37:26.770788 | controller | }
2025-12-05 23:37:27.018022 | controller | changed:
2025-12-05 23:37:27.018115 | controller | {
2025-12-05 23:37:27.018143 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQC3VwV8Im9kRm49lt3tM36hj4Zv27FxGo4C1Q/0jqhzFmHY7RHbmeRr8ObhwWoHjXSozKWg8FL5ER0z3hTwL0W6lez3sL7hUaCmSuZmG5Hnl3x4vTSxDI9JZ/Y65rtYiiWQo2fC5xJhU/4+0e5e/pseCm8cKRSu+SaxhO+sd6FDojA2x1BzOzKiQRDy/1zWGp/cZkxcEuB1wHI5LMzN03c67vmbu+fhZRAUO4dQkvcnj2LrhQtpa+ytvnSjr8icMDosf1OsbSffwZFyHB/hfWGAfe0eIeSA2XPraxiPknXxiPKx2MJsaUTYbsZcm3EjFdHBBMumw5rBI74zLrMRvCO9GwBEmGT4rFng1nP+yw5DB8sn2zqpOsPg1LYRwCPOUveC13P6pgsZZPh812e8v5EKnETct+5XI3dVpdw6CnNiLwAyVAF15DJvBGT/u1k0Myg/bQn+Gv9k2MSj6LvQmf6WbZu2Wgjm30z3FyCneBqTL7mLF19YXzeC0ufHz5pnO1E= dasm@fedora"
2025-12-05 23:37:27.018165 | controller | }
2025-12-05 23:37:27.289918 | controller | changed:
2025-12-05 23:37:27.290011 | controller | {
2025-12-05 23:37:27.290041 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-12-05 23:37:27.290062 | controller | }
2025-12-05 23:37:27.557517 | controller | changed:
2025-12-05 23:37:27.557595 | controller | {
2025-12-05 23:37:27.557623 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-12-05 23:37:27.557644 | controller | }
2025-12-05 23:37:27.799256 | controller | changed:
2025-12-05 23:37:27.799348 | controller | {
2025-12-05 23:37:27.799375 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-12-05 23:37:27.799396 | controller | }
2025-12-05 23:37:28.031837 | controller | changed:
2025-12-05 23:37:28.031939 | controller | {
2025-12-05 23:37:28.031974 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-12-05 23:37:28.031996 | controller | }
2025-12-05 23:37:28.279243 | controller | changed:
2025-12-05 23:37:28.279386 | controller | {
2025-12-05 23:37:28.279415 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-12-05 23:37:28.279436 | controller | }
2025-12-05 23:37:28.524512 | controller | changed:
2025-12-05 23:37:28.524592 | controller | {
2025-12-05 23:37:28.524621 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-12-05 23:37:28.524643 | controller | }
2025-12-05 23:37:28.769909 | controller | changed:
2025-12-05 23:37:28.769998 | controller | {
2025-12-05 23:37:28.770026 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-12-05 23:37:28.770048 | controller | }
2025-12-05 23:37:29.019010 | controller | changed:
2025-12-05 23:37:29.019095 | controller | {
2025-12-05 23:37:29.019122 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-12-05 23:37:29.019143 | controller | }
2025-12-05 23:37:29.295473 | controller | changed:
2025-12-05 23:37:29.295553 | controller | {
2025-12-05 23:37:29.295580 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQCfNtF7NvKl915TGsGGoseUb06Hj8L/S4toWf0hExeY+F00woL6NvBlJD0nDct+P5a22I4EhvoQCRQ8reaPCm1lybR3uiRIJsj+8zkVvLwby9LXzfZorlNG9ofjd00FEmB09uW/YvTl6Q9XwwwX6tInzIOv3TMqTHHGOL74ibbj8J/FJR0cFEyj0z4WQRvtkh32xAHl83gbuINryMt0sqRI+clj2381NKL55DRLQrVw0gsfqqxiHAnXg21qWmc4J+b9e9kiuAFQjcjwTVkwJCcg3xbPwC/qokYRby/Y5S40UUd7/jEARGXT7RZgpzTuDd1oZiCVrnrqJNPaMNdVv5MLeFdf1B7iIe5aa/fGouX7AO4SdKhZUdnJmCFAGvjC6S3JMZ2wAcUl+OHnssfmdj7XL50cLo27vjuzMtLAgSqi6N99m92WCF2s8J9aVzszX7Xz9OKZCeGsiVJp3/NdABKzSEAyM9xBD/5Vho894Sav+otpySHe3p6RUTgbB5Zu8VyZRZ/UtB3ueXxyo764yrc6qWIDqrehm84Xm9g+/jpIBzGPl07NUNJpdt/6Sgf9RIKXw/7XypO5yZfUcuFNGTxLfqjTNrtgLZNcjfav6sSdVXVcMPL//XNuRdKmVFaO76eV/oGMQGr1fGcCD+N+CpI7+Q+fCNB6VFWG4nZFuI/Iuw== averdagu@redhat.com"
2025-12-05 23:37:29.295602 | controller | }
2025-12-05 23:37:29.535876 | controller | changed:
2025-12-05 23:37:29.535959 | controller | {
2025-12-05 23:37:29.535988 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDq8l27xI+QlQVdS4djp9ogSoyrNE2+Ox6vKPdhSNL1J3PE5w+WCSvMz9A5gnNuH810zwbekEApbxTze/gLQJwBHA52CChfURpXrFaxY7ePXRElwKAL3mJfzBWY/c5jnNL9TCVmFJTGZkFZP3Nh+BMgZvL6xBkt3WKm6Uq18qzd9XeKcZusrA+O+uLv1fVeQnadY9RIqOCyeFYCzLWrUfTyE8x/XG0hAWIM7qpnF2cALQS2h9n4hW5ybiUN790H08wf9hFwEf5nxY9Z9dVkPFQiTSGKNBzmnCXU9skxS/xhpFjJ5duGSZdtAHe9O+nGZm9c67hxgtf8e5PDuqAdXEv2cf6e3VBAt+Bz8EKI3yosTj0oZHfwr42Yzb1l/SKy14Rggsrc9KAQlrGXan6+u2jcQqqx7l+SWmnpFiWTV9u5cWj2IgOhApOitmRBPYqk9rE2usfO0hLn/Pj/R/Nau4803e1/EikdLE7Ps95s9mX5jRDjAoUa2JwFF5RsVFyL910= ashigupt@ashigupt.remote.csb"
2025-12-05 23:37:29.536017 | controller | }
2025-12-05 23:37:29.822125 | controller | changed:
2025-12-05 23:37:29.822297 | controller | {
2025-12-05 23:37:29.822335 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-12-05 23:37:29.822357 | controller | }
2025-12-05 23:37:30.094602 | controller | changed:
2025-12-05 23:37:30.094747 | controller | {
2025-12-05 23:37:30.094779 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-12-05 23:37:30.094803 | controller | }
2025-12-05 23:37:30.337358 | controller | changed:
2025-12-05 23:37:30.337444 | controller | {
2025-12-05 23:37:30.337484 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-12-05 23:37:30.337505 | controller | }
2025-12-05 23:37:30.580209 | controller | changed:
2025-12-05 23:37:30.580380 | controller | {
2025-12-05 23:37:30.580415 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-12-05 23:37:30.580439 | controller | }
2025-12-05 23:37:30.825902 | controller | changed:
2025-12-05 23:37:30.825989 | controller | {
2025-12-05 23:37:30.826017 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQDZdI7t1cxYx65heVI24HTV4F7oQLW1zyfxHreL2TIJKxjyrUUKIFEUmTutcBlJRLNT2Eoix6x1sOw9YrchloCLcn//SGfTElr9mSc5jbjb7QXEU+zJMhtxyEJ1Po3CUGnj7ckiIXw7wcawZtrEOAQ9pH3ExYCJcEMiyNjRQZCxT3tPK+S4B95EWh5Fsrz9CkwpjNRPPH7LigCeQTM3Wc7r97utAslBUUvYceDSLA7rMgkitJE38b7rZBeYzsGQ8YYUBjTCtehqQXxCRjizbHWaaZkBU+N3zkKB6n/iCNGIO690NK7A/qb6msTijiz1PeuM8ThOsi9qXnbX5v0PoTpcFSojV7NHAQ71f0XXuS43FhZctT+Dcx44dT8Fb5vJu2cJGrk+qF8ZgJYNpRS7gPg0EG2EqjK7JMf9ULdjSu0r+KlqIAyLvtzT4eOnQipoKlb/WG5D/0ohKv7OMQ352ggfkBFIQsRXyyTCT98Ft9juqPuahi3CAQmP4H9dyE+7+Kz437PEtsxLmfm6naNmWi7Ee1DqWPwS8rEajsm4sNM4wW9gdBboJQtc0uZw0DfLj1I9r3Mc8Ol0jYtz0yNQDSzVLrGCaJlC311trU70tZ+ZkAVV6Mn8lOhSbj1cK0lvSr6ZK4dgqGl3I1eTZJJhbLNdg7UOVaiRx9543+C/p/As7w== brjackma@redhat.com"
2025-12-05 23:37:30.826040 | controller | }
2025-12-05 23:37:31.078023 | controller | changed:
2025-12-05 23:37:31.078106 | controller | {
2025-12-05 23:37:31.078133 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-12-05 23:37:31.078155 | controller | }
2025-12-05 23:37:31.308289 | controller | changed:
2025-12-05 23:37:31.308362 | controller | {
2025-12-05 23:37:31.308390 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-12-05 23:37:31.308412 | controller | }
2025-12-05 23:37:31.547027 | controller | changed:
2025-12-05 23:37:31.547122 | controller | {
2025-12-05 23:37:31.547151 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-12-05 23:37:31.547172 | controller | }
2025-12-05 23:37:31.575975 | 
2025-12-05 23:37:31.576080 | TASK [Set timezone to UTC]
2025-12-05 23:37:32.294990 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-12-05 23:37:32.303398 | 
2025-12-05 23:37:32.303484 | TASK [Create nodepool directory]
2025-12-05 23:37:32.584314 | controller | changed
2025-12-05 23:37:32.589888 | 
2025-12-05 23:37:32.589973 | TASK [Create nodepool sub_nodes file]
2025-12-05 23:37:33.370057 | controller | changed
2025-12-05 23:37:33.380074 | 
2025-12-05 23:37:33.380163 | TASK [Create nodepool sub_nodes_private file]
2025-12-05 23:37:34.162788 | controller | changed
2025-12-05 23:37:34.168553 | 
2025-12-05 23:37:34.168627 | LOOP [Populate nodepool sub_nodes file]
2025-12-05 23:37:34.243790 | 
2025-12-05 23:37:34.243927 | LOOP [Populate nodepool sub_nodes_private file]
2025-12-05 23:37:34.305996 | 
2025-12-05 23:37:34.306234 | TASK [Create nodepool primary file]
2025-12-05 23:37:34.363221 | controller | skipping: Conditional result was False
2025-12-05 23:37:34.375115 | 
2025-12-05 23:37:34.375203 | TASK [Create nodepool node_private for this node]
2025-12-05 23:37:35.241813 | controller | changed
2025-12-05 23:37:35.260055 | 
2025-12-05 23:37:35.260127 | LOOP [Copy ssh keys to nodepool directory]
2025-12-05 23:37:36.047677 | controller | ok: Item: id_rsa Runtime: 0:00:00.006721
2025-12-05 23:37:36.047833 | 
2025-12-05 23:37:36.275125 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.006897
2025-12-05 23:37:36.282442 | 
2025-12-05 23:37:36.282522 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-12-05 23:37:37.140851 | controller | changed
2025-12-05 23:37:37.156573 | 
2025-12-05 23:37:37.156685 | TASK [Validate sudoers config after edits]
2025-12-05 23:37:37.534474 | controller | /etc/sudoers: parsed OK
2025-12-05 23:37:37.534530 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-12-05 23:37:37.534539 | controller | /etc/sudoers.d/zuul: parsed OK
2025-12-05 23:37:37.534546 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-12-05 23:37:37.732328 | controller | ok: Runtime: 0:00:00.005833
2025-12-05 23:37:37.737887 | 
2025-12-05 23:37:37.737957 | TASK [Show the environment passed in to job shell scripts]
2025-12-05 23:37:38.091325 | controller | SHELL=/bin/bash
2025-12-05 23:37:38.091376 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-12-05 23:37:38.091387 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-12-05 23:37:38.091394 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/39/3539/ef8821f44d461626a5ab46002cd514823bd56c63
2025-12-05 23:37:38.091400 | controller | PWD=/home/zuul
2025-12-05 23:37:38.091406 | controller | ZUUL_PIPELINE=github-check
2025-12-05 23:37:38.091411 | controller | LOGNAME=zuul
2025-12-05 23:37:38.091416 | controller | XDG_SESSION_TYPE=tty
2025-12-05 23:37:38.091422 | controller | _=/usr/bin/env
2025-12-05 23:37:38.091427 | controller | MOTD_SHOWN=pam
2025-12-05 23:37:38.091432 | controller | HOME=/home/zuul
2025-12-05 23:37:38.091437 | controller | LANG=en_US.UTF-8
2025-12-05 23:37:38.091443 | controller | SSH_CONNECTION=192.168.25.12 34264 192.168.25.199 22
2025-12-05 23:37:38.091481 | controller | TOX_TESTENV_PASSENV=ZUUL_UUID ZUUL_PROJECT ZUUL_SHORT_PROJECT_NAME ZUUL_PIPELINE ZUUL_VOTING WORKSPACE BUILD_TIMEOUT ZUUL_BRANCH ZUUL_CHANGES ZUUL_CHANGE_IDS ZUUL_CHANGE ZUUL_PATCHSET
2025-12-05 23:37:38.091492 | controller | ZUUL_CHANGE_IDS=3539,ef8821f44d461626a5ab46002cd514823bd56c63
2025-12-05 23:37:38.091498 | controller | WORKSPACE=/home/zuul/workspace
2025-12-05 23:37:38.091504 | controller | XDG_SESSION_CLASS=user
2025-12-05 23:37:38.091509 | controller | SELINUX_ROLE_REQUESTED=
2025-12-05 23:37:38.091515 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-12-05 23:37:38.091520 | controller | USER=zuul
2025-12-05 23:37:38.091525 | controller | ZUUL_VOTING=True
2025-12-05 23:37:38.091531 | controller | BUILD_TIMEOUT=1800000
2025-12-05 23:37:38.091536 | controller | SELINUX_USE_CURRENT_RANGE=
2025-12-05 23:37:38.091541 | controller | SHLVL=1
2025-12-05 23:37:38.091546 | controller | ZUUL_PATCHSET=ef8821f44d461626a5ab46002cd514823bd56c63
2025-12-05 23:37:38.091552 | controller | XDG_SESSION_ID=1
2025-12-05 23:37:38.091557 | controller | ZUUL_BRANCH=main
2025-12-05 23:37:38.091562 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-12-05 23:37:38.091567 | controller | SSH_CLIENT=192.168.25.12 34264 22
2025-12-05 23:37:38.091573 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-12-05 23:37:38.091578 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-12-05 23:37:38.091583 | controller | which_declare=declare -f
2025-12-05 23:37:38.091589 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2025-12-05 23:37:38.091594 | controller | SELINUX_LEVEL_REQUESTED=
2025-12-05 23:37:38.091600 | controller | ZUUL_CHANGE=3539
2025-12-05 23:37:38.091605 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-12-05 23:37:38.091610 | controller | ZUUL_UUID=671d8a9f68d14617af5bf1cc84d77627
2025-12-05 23:37:38.091616 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-12-05 23:37:38.091621 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-12-05 23:37:38.091626 | controller | }
2025-12-05 23:37:38.319368 | controller | ok: Runtime: 0:00:00.007871
2025-12-05 23:37:38.324832 | 
2025-12-05 23:37:38.324920 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-12-05 23:37:38.399109 | controller | skipping: Conditional result was False
2025-12-05 23:37:38.404782 | 
2025-12-05 23:37:38.404865 | TASK [Symlink /home/zuul-worker/workspace]
2025-12-05 23:37:39.135439 | controller | skipping: Conditional result was False
2025-12-05 23:37:39.141159 | 
2025-12-05 23:37:39.141238 | TASK [Ensure legacy workspace directory]
2025-12-05 23:37:39.385378 | controller | changed
2025-12-05 23:37:39.474142 | 
2025-12-05 23:37:39.474231 | PLAY RECAP
2025-12-05 23:37:39.475586 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-12-05 23:37:39.475637 | 
2025-12-05 23:37:39.740630 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-12-05 23:37:39.741306 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-12-05 23:37:41.129964 | 
2025-12-05 23:37:41.130061 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-12-05 23:37:41.192280 | 
2025-12-05 23:37:41.192362 | TASK [Create zuul-output directory]
2025-12-05 23:37:41.922949 | controller | changed
2025-12-05 23:37:41.932479 | 
2025-12-05 23:37:41.932568 | TASK [Slurp Zuul inventory test]
2025-12-05 23:37:42.743371 | controller -> localhost | ok
2025-12-05 23:37:42.749908 | 
2025-12-05 23:37:42.749992 | TASK [Save zuul inventory]
2025-12-05 23:37:44.302602 | controller | changed
2025-12-05 23:37:44.308552 | 
2025-12-05 23:37:44.308634 | TASK [Save zuul vars without the change_message]
2025-12-05 23:37:45.219993 | controller | changed
2025-12-05 23:37:45.326150 | 
2025-12-05 23:37:45.326238 | PLAY RECAP
2025-12-05 23:37:45.326283 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-12-05 23:37:45.326307 | 
2025-12-05 23:37:45.639253 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-12-05 23:37:45.640035 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-12-05 23:37:47.334126 | 
2025-12-05 23:37:47.334229 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2025-12-05 23:37:47.388509 | 
2025-12-05 23:37:47.388620 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2025-12-05 23:37:47.433401 | controller | ok
2025-12-05 23:37:47.465007 | 
2025-12-05 23:37:47.465101 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-12-05 23:37:47.499842 | controller | skipping: Conditional result was False
2025-12-05 23:37:47.506239 | 
2025-12-05 23:37:47.506311 | TASK [mirror-info-fork : Create /etc/ci]
2025-12-05 23:37:48.164376 | controller | ok
2025-12-05 23:37:48.180051 | 
2025-12-05 23:37:48.180142 | TASK [mirror-info-fork : Install ci_mirror script]
2025-12-05 23:37:49.255056 | controller | ok
2025-12-05 23:37:49.273326 | 
2025-12-05 23:37:49.273395 | TASK [Prepare workspace]
2025-12-05 23:37:49.323288 | controller | ok
2025-12-05 23:37:49.367529 | 
2025-12-05 23:37:49.367607 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-12-05 23:37:49.913670 | controller | ok
2025-12-05 23:37:49.927725 | 
2025-12-05 23:37:49.927794 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-12-05 23:37:54.886764 | controller | Output suppressed because no_log was given
2025-12-05 23:37:54.903608 | 
2025-12-05 23:37:54.903707 | LOOP [Create zuul-output directory]
2025-12-05 23:37:55.200502 | controller | changed: "/home/zuul/ci-framework-data/logs"
2025-12-05 23:37:55.373019 | controller | ok: "/home/zuul/zuul-output/logs"
2025-12-05 23:37:55.388035 | 
2025-12-05 23:37:55.388130 | TASK [Install required packages]
2025-12-05 23:38:44.538042 | controller | changed
2025-12-05 23:38:44.543295 | 
2025-12-05 23:38:44.543358 | TASK [Install venv]
2025-12-05 23:39:00.018128 | controller | ERROR
2025-12-05 23:39:00.018449 | controller | {
2025-12-05 23:39:00.018506 | controller |   "msg": "gmake: *** [Makefile:56: setup_tests] Error 1",
2025-12-05 23:39:00.018531 | controller |   "rc": 2
2025-12-05 23:39:00.018551 | controller | }
failure
2025-12-05 23:39:00.023281 | 
2025-12-05 23:39:00.023346 | PLAY RECAP
2025-12-05 23:39:00.023400 | controller | ok: 6 changed: 3 unreachable: 0 failed: 1 skipped: 1 rescued: 0 ignored: 0
2025-12-05 23:39:00.023425 | 
2025-12-05 23:39:00.134813 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-12-05 23:39:00.135751 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-12-05 23:39:00.956964 | 
2025-12-05 23:39:00.957063 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-12-05 23:39:00.979490 | 
2025-12-05 23:39:00.979576 | TASK [Filter out host if needed]
2025-12-05 23:39:00.988904 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-12-05 23:39:00.993489 | 
2025-12-05 23:39:00.993559 | TASK [Ensure file is present]
2025-12-05 23:39:01.317879 | controller | ok
2025-12-05 23:39:01.324861 | 
2025-12-05 23:39:01.324950 | TASK [Manage molecule report file]
2025-12-05 23:39:01.851924 | controller | skipping: Conditional result was False
2025-12-05 23:39:01.857339 | 
2025-12-05 23:39:01.857428 | TASK [Check if we get ci-framework-data basedir]
2025-12-05 23:39:02.076051 | controller | ok
2025-12-05 23:39:02.082734 | 
2025-12-05 23:39:02.082810 | TASK [Create ci-framework-data log directory for zuul]
2025-12-05 23:39:02.515333 | controller | changed
2025-12-05 23:39:02.521488 | 
2025-12-05 23:39:02.521579 | TASK [Copy ci-framework interesting files]
2025-12-05 23:39:02.978449 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2025-12-05 23:39:03.094545 | controller | changed
2025-12-05 23:39:03.100007 | 
2025-12-05 23:39:03.100093 | TASK [Get SELinux listing]
2025-12-05 23:39:03.655393 | controller | changed
2025-12-05 23:39:03.661352 | 
2025-12-05 23:39:03.661436 | TASK [Generate log index]
2025-12-05 23:39:04.480525 | controller | changed
2025-12-05 23:39:04.485558 | 
2025-12-05 23:39:04.485624 | TASK [Get some env related data]
2025-12-05 23:39:05.521151 | controller | changed
2025-12-05 23:39:05.526671 | 
2025-12-05 23:39:05.526748 | TASK [Generate list of logs to collect in home directory]
2025-12-05 23:39:05.889794 | controller | ok: All paths examined
2025-12-05 23:39:05.894595 | 
2025-12-05 23:39:05.894687 | LOOP [Copy logs from home directory]
2025-12-05 23:39:05.913510 | 
2025-12-05 23:39:05.913648 | TASK [Copy crio stats log file]
2025-12-05 23:39:05.936545 | controller | skipping: Conditional result was False
2025-12-05 23:39:05.945077 | 
2025-12-05 23:39:05.945201 | TASK [Get SELinux related data]
2025-12-05 23:39:06.202391 | controller | <no matches>
2025-12-05 23:39:06.518963 | controller | ERROR
2025-12-05 23:39:06.519096 | controller | {
2025-12-05 23:39:06.519131 | controller |   "delta": "0:00:00.011494",
2025-12-05 23:39:06.519154 | controller |   "end": "2025-12-05 23:39:06.203950",
2025-12-05 23:39:06.519173 | controller |   "msg": "non-zero return code",
2025-12-05 23:39:06.519190 | controller |   "rc": 1,
2025-12-05 23:39:06.519207 | controller |   "start": "2025-12-05 23:39:06.192456"
2025-12-05 23:39:06.519224 | controller | }
2025-12-05 23:39:06.519248 | controller | ERROR: Ignoring Errors
2025-12-05 23:39:06.524556 | 
2025-12-05 23:39:06.524625 | TASK [Create system configuration directory]
2025-12-05 23:39:06.779173 | controller | changed
2025-12-05 23:39:06.784321 | 
2025-12-05 23:39:06.784404 | TASK [Get some of the system configurations]
2025-12-05 23:39:07.383333 | controller | changed
2025-12-05 23:39:07.389017 | 
2025-12-05 23:39:07.389101 | TASK [Copy generated documentation if available]
2025-12-05 23:39:07.494890 | controller | skipping: Conditional result was False
2025-12-05 23:39:07.502198 | 
2025-12-05 23:39:07.502289 | TASK [Copy generated AsciiDoc documentation if available]
2025-12-05 23:39:07.588993 | controller | skipping: Conditional result was False
2025-12-05 23:39:07.595033 | 
2025-12-05 23:39:07.595126 | TASK [Compress logs bigger than 2MB]
2025-12-05 23:39:08.200850 | controller | changed
2025-12-05 23:39:08.210914 | 
2025-12-05 23:39:08.211002 | TASK [Copy files from workspace on node]
2025-12-05 23:39:08.263923 | controller | ok
2025-12-05 23:39:08.319038 | 
2025-12-05 23:39:08.319142 | TASK [fetch-output : Set log path for multiple nodes]
2025-12-05 23:39:08.374088 | controller | skipping: Conditional result was False
2025-12-05 23:39:08.382494 | 
2025-12-05 23:39:08.382575 | TASK [fetch-output : Set log path for single node]
2025-12-05 23:39:08.452136 | controller | ok
2025-12-05 23:39:08.460743 | 
2025-12-05 23:39:08.460815 | LOOP [fetch-output : Ensure local output dirs]
2025-12-05 23:39:08.934214 | controller -> localhost | ok: "/var/lib/zuul/builds/671d8a9f68d14617af5bf1cc84d77627/work/logs"
2025-12-05 23:39:08.934438 | 
2025-12-05 23:39:09.385155 | controller -> localhost | changed: "/var/lib/zuul/builds/671d8a9f68d14617af5bf1cc84d77627/work/artifacts"
2025-12-05 23:39:09.655928 | controller -> localhost | changed: "/var/lib/zuul/builds/671d8a9f68d14617af5bf1cc84d77627/work/docs"
2025-12-05 23:39:09.671134 | 
2025-12-05 23:39:09.671222 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-12-05 23:39:10.590373 | controller | changed:
2025-12-05 23:39:10.590557 | controller | .d..t...... ./
2025-12-05 23:39:10.590591 | controller | >f+++++++++ README.html
2025-12-05 23:39:10.590614 | controller | >f+++++++++ dmesg.log
2025-12-05 23:39:10.590634 | controller | >f+++++++++ installed-pkgs.log
2025-12-05 23:39:10.590669 | controller | >f+++++++++ python.log
2025-12-05 23:39:10.590697 | controller | >f+++++++++ registries.conf
2025-12-05 23:39:10.590716 | controller | >f+++++++++ selinux-denials.log
2025-12-05 23:39:10.590735 | controller | >f+++++++++ selinux-listing.log
2025-12-05 23:39:10.590752 | controller | cd+++++++++ ci-framework-data/
2025-12-05 23:39:10.590769 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-12-05 23:39:10.590786 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-12-05 23:39:10.590805 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-12-05 23:39:10.590823 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-12-05 23:39:10.590840 | controller | cd+++++++++ ci-framework-data/logs/
2025-12-05 23:39:10.590857 | controller | cd+++++++++ registries.conf.d/
2025-12-05 23:39:10.590873 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-12-05 23:39:10.590890 | controller | cd+++++++++ system-config/
2025-12-05 23:39:10.590905 | controller | cd+++++++++ system-config/libvirt/
2025-12-05 23:39:10.590921 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-12-05 23:39:10.590936 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-12-05 23:39:11.545978 | controller | changed: .d..t...... ./
2025-12-05 23:39:12.171058 | controller | changed: .d..t...... ./
2025-12-05 23:39:12.185538 | 
2025-12-05 23:39:12.185633 | TASK [Return artifact to Zuul]
2025-12-05 23:39:12.211882 | controller | skipping: Conditional result was False
2025-12-05 23:39:12.255793 | 
2025-12-05 23:39:12.255867 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-12-05 23:39:12.255969 | 
2025-12-05 23:39:12.255998 | PLAY RECAP
2025-12-05 23:39:12.256038 | controller | ok: 15 changed: 11 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 1
2025-12-05 23:39:12.256060 | 
2025-12-05 23:39:12.584889 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-12-05 23:39:12.585618 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-12-05 23:39:13.698831 | 
2025-12-05 23:39:13.698932 | PLAY [all]
2025-12-05 23:39:13.745345 | 
2025-12-05 23:39:13.745434 | TASK [include_role : fetch-output]
2025-12-05 23:39:13.846922 | controller | ok
2025-12-05 23:39:13.881362 | 
2025-12-05 23:39:13.881487 | TASK [fetch-output : Set log path for multiple nodes]
2025-12-05 23:39:13.972920 | controller | skipping: Conditional result was False
2025-12-05 23:39:13.978726 | 
2025-12-05 23:39:13.978812 | TASK [fetch-output : Set log path for single node]
2025-12-05 23:39:14.068906 | controller | ok
2025-12-05 23:39:14.074394 | 
2025-12-05 23:39:14.074495 | LOOP [fetch-output : Ensure local output dirs]
2025-12-05 23:39:15.016458 | controller -> localhost | ok: "/var/lib/zuul/builds/671d8a9f68d14617af5bf1cc84d77627/work/logs"
2025-12-05 23:39:15.376203 | controller -> localhost | ok: "/var/lib/zuul/builds/671d8a9f68d14617af5bf1cc84d77627/work/artifacts"
2025-12-05 23:39:15.912970 | controller -> localhost | ok: "/var/lib/zuul/builds/671d8a9f68d14617af5bf1cc84d77627/work/docs"
2025-12-05 23:39:15.922285 | 
2025-12-05 23:39:15.922356 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-12-05 23:39:17.088319 | controller | ok
2025-12-05 23:39:17.088519 | controller | ok: All items complete
2025-12-05 23:39:17.088552 | 
2025-12-05 23:39:17.747243 | controller | ok
2025-12-05 23:39:18.541809 | controller | ok
2025-12-05 23:39:18.559847 | 
2025-12-05 23:39:18.559945 | TASK [include_role : fetch-output-openshift]
2025-12-05 23:39:18.622348 | controller | skipping: Conditional result was False
2025-12-05 23:39:18.628323 | 
2025-12-05 23:39:18.628456 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-12-05 23:39:20.034279 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.049114
2025-12-05 23:39:20.602742 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.051111
2025-12-05 23:39:20.695152 | 
2025-12-05 23:39:20.695244 | PLAY [all]
2025-12-05 23:39:20.736111 | 
2025-12-05 23:39:20.736220 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-12-05 23:39:21.366953 | controller | changed
2025-12-05 23:39:21.452502 | 
2025-12-05 23:39:21.452586 | PLAY RECAP
2025-12-05 23:39:21.452631 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-12-05 23:39:21.452674 | 
2025-12-05 23:39:21.664573 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-12-05 23:39:21.671978 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-12-05 23:39:23.542585 | 
2025-12-05 23:39:23.542750 | PLAY [localhost]
2025-12-05 23:39:23.589411 | 
2025-12-05 23:39:23.589547 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-12-05 23:39:24.704179 | localhost | changed
2025-12-05 23:39:24.708914 | 
2025-12-05 23:39:24.708998 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-12-05 23:39:24.783045 | localhost | ok
2025-12-05 23:39:24.798976 | 
2025-12-05 23:39:24.799082 | TASK [add-fileserver : Create SSH private key tempfile]
2025-12-05 23:39:25.704116 | localhost | changed
2025-12-05 23:39:25.708936 | 
2025-12-05 23:39:25.709026 | TASK [add-fileserver : Create SSH private key from secret]
2025-12-05 23:39:27.630619 | localhost | changed
2025-12-05 23:39:27.636302 | 
2025-12-05 23:39:27.636375 | TASK [add-fileserver : Add fileserver ssh key]
2025-12-05 23:39:29.008923 | localhost | Identity added: /var/lib/zuul/builds/671d8a9f68d14617af5bf1cc84d77627/work/tmp/ansible.s4r3hxnu (/var/lib/zuul/builds/671d8a9f68d14617af5bf1cc84d77627/work/tmp/ansible.s4r3hxnu)
2025-12-05 23:39:29.015051 | localhost | ok: Runtime: 0:00:00.014713
2025-12-05 23:39:29.025144 | 
2025-12-05 23:39:29.025231 | TASK [add-fileserver : Remove SSH private key from disk]
2025-12-05 23:39:29.913922 | localhost | ok: Runtime: 0:00:00.017007
2025-12-05 23:39:29.919046 | 
2025-12-05 23:39:29.919115 | TASK [add-fileserver : Add fileserver to inventory]
2025-12-05 23:39:30.135221 | localhost | changed
2025-12-05 23:39:30.139510 | 
2025-12-05 23:39:30.139581 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-12-05 23:39:31.180944 | localhost | changed
2025-12-05 23:39:31.257462 | 
2025-12-05 23:39:31.257565 | PLAY [localhost]
2025-12-05 23:39:31.339591 | 
2025-12-05 23:39:31.339696 | TASK [Generate bulk log download script]
2025-12-05 23:39:31.406341 | localhost | ok
2025-12-05 23:39:31.432001 | 
2025-12-05 23:39:31.432089 | TASK [local-log-download : Check API endpoint is defined]
2025-12-05 23:39:31.521801 | localhost | ok: All assertions passed
2025-12-05 23:39:31.526527 | 
2025-12-05 23:39:31.526593 | TASK [local-log-download : Create download script]
2025-12-05 23:39:32.859807 | localhost -> localhost | changed
2025-12-05 23:39:32.868482 | 
2025-12-05 23:39:32.868562 | TASK [Register quick-download link]
2025-12-05 23:39:32.953520 | localhost | ok
2025-12-05 23:39:33.019697 | 
2025-12-05 23:39:33.019767 | PLAY [logserver.rdoproject.org]
2025-12-05 23:39:33.068080 | 
2025-12-05 23:39:33.068153 | TASK [Set zuul-log-path fact]
2025-12-05 23:39:33.123933 | logserver.rdoproject.org | ok
2025-12-05 23:39:33.133152 | 
2025-12-05 23:39:33.133224 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-12-05 23:39:33.226427 | logserver.rdoproject.org | ok
2025-12-05 23:39:33.232236 | 
2025-12-05 23:39:33.232305 | TASK [upload-logs : Create log directories]
2025-12-05 23:39:35.208887 | logserver.rdoproject.org | changed
2025-12-05 23:39:35.212296 | 
2025-12-05 23:39:35.212360 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-12-05 23:39:36.231849 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.027268
2025-12-05 23:39:36.236826 | 
2025-12-05 23:39:36.236909 | TASK [upload-logs : Upload logs to log server]
2025-12-05 23:39:38.893493 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-12-05 23:39:38.902124 | 
2025-12-05 23:39:38.902206 | LOOP [upload-logs : Compress console log and json output]
2025-12-05 23:39:39.089148 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-05 23:39:39.140324 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-05 23:39:39.151723 | 
2025-12-05 23:39:39.151833 | LOOP [upload-logs : Upload compressed console log and json output]
2025-12-05 23:39:39.302155 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-05 23:39:39.302367 | 
2025-12-05 23:39:39.302950 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-05 23:39:39.309665 | 
2025-12-05 23:39:39.309769 | LOOP [upload-logs : Upload console log and json output]