2026-03-16 16:36:16.779403 | Job console starting...
2026-03-16 16:36:17.070710 | Updating repositories
2026-03-16 16:36:17.873310 | Preparing job workspace
2026-03-16 16:36:30.819434 | Running Ansible setup...
2026-03-16 16:36:35.486249 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2026-03-16 16:36:36.395895 | 
2026-03-16 16:36:36.396008 | PLAY [localhost]
2026-03-16 16:36:36.429154 | 
2026-03-16 16:36:36.429241 | TASK [Gathering Facts]
2026-03-16 16:36:38.144425 | localhost | ok
2026-03-16 16:36:38.179402 | 
2026-03-16 16:36:38.179523 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-03-16 16:36:39.089972 | localhost -> localhost | changed
2026-03-16 16:36:39.095993 | 
2026-03-16 16:36:39.096091 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-03-16 16:36:40.763678 | localhost -> localhost | changed
2026-03-16 16:36:40.772010 | 
2026-03-16 16:36:40.772113 | TASK [Setup log path fact]
2026-03-16 16:36:40.803638 | localhost | ok
2026-03-16 16:36:40.831663 | 
2026-03-16 16:36:40.831738 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-03-16 16:36:40.922233 | localhost | ok
2026-03-16 16:36:40.929926 | 
2026-03-16 16:36:40.929998 | TASK [emit-job-header : Print job information]
2026-03-16 16:36:41.007178 | # Job Information
2026-03-16 16:36:41.007344 | Ansible Version: 2.15.12
2026-03-16 16:36:41.007385 | Job: cifmw-molecule-hive
2026-03-16 16:36:41.007411 | Pipeline: github-check
2026-03-16 16:36:41.007432 | Executor: ibm-bm4-ze.softwarefactory-project.io
2026-03-16 16:36:41.007452 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3770
2026-03-16 16:36:41.007473 | Log URL (when completed): https://logserver.rdoproject.org/090/rdoproject.org/090e2d575d8d43e2a831e236cba94d58/
2026-03-16 16:36:41.007493 | Event ID: 072a8580-2155-11f1-9026-7307b7bf6b98
2026-03-16 16:36:41.011126 | 
2026-03-16 16:36:41.011191 | LOOP [emit-job-header : Print node information]
2026-03-16 16:36:41.160427 | localhost | ok:
2026-03-16 16:36:41.160665 | localhost | # Node Information
2026-03-16 16:36:41.160700 | localhost | Inventory Hostname: controller
2026-03-16 16:36:41.160730 | localhost | Hostname: np0005648824
2026-03-16 16:36:41.160753 | localhost | Username: zuul
2026-03-16 16:36:41.160783 | localhost | Distro: CentOS 9
2026-03-16 16:36:41.160803 | localhost | Provider: ibm-bm4-nodepool
2026-03-16 16:36:41.160823 | localhost | Region: regionOne
2026-03-16 16:36:41.160841 | localhost | Label: cloud-centos-9-stream-tripleo-ibm
2026-03-16 16:36:41.160860 | localhost | Product Name: OpenStack Compute
2026-03-16 16:36:41.160878 | localhost | Interface IP: 192.168.26.30
2026-03-16 16:36:41.211230 | 
2026-03-16 16:36:41.211365 | PLAY [all]
2026-03-16 16:36:41.231034 | 
2026-03-16 16:36:41.231119 | TASK [Gather network facts]
2026-03-16 16:36:41.631845 | controller | ok
2026-03-16 16:36:41.660906 | 
2026-03-16 16:36:41.660992 | TASK [include_role : start-zuul-console]
2026-03-16 16:36:41.679408 | controller | ok
2026-03-16 16:36:41.719448 | 
2026-03-16 16:36:41.719545 | TASK [start-zuul-console : Start zuul_console daemon.]
2026-03-16 16:36:42.189820 | controller | ok
2026-03-16 16:36:42.203245 | 
2026-03-16 16:36:42.203340 | TASK [include_role : add-build-sshkey]
2026-03-16 16:36:42.260506 | controller | ok
2026-03-16 16:36:42.298486 | 
2026-03-16 16:36:42.298570 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-03-16 16:36:42.585072 | controller -> localhost | ok
2026-03-16 16:36:42.591791 | 
2026-03-16 16:36:42.591875 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-03-16 16:36:42.661263 | controller | ok
2026-03-16 16:36:42.676054 | controller | included: /var/lib/zuul/builds/090e2d575d8d43e2a831e236cba94d58/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-03-16 16:36:42.689363 | 
2026-03-16 16:36:42.689442 | TASK [add-build-sshkey : Create Temp SSH key]
2026-03-16 16:36:43.294960 | controller -> localhost | Generating public/private rsa key pair.
2026-03-16 16:36:43.295170 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/090e2d575d8d43e2a831e236cba94d58/work/090e2d575d8d43e2a831e236cba94d58_id_rsa.
2026-03-16 16:36:43.295208 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/090e2d575d8d43e2a831e236cba94d58/work/090e2d575d8d43e2a831e236cba94d58_id_rsa.pub.
2026-03-16 16:36:43.295234 | controller -> localhost | The key fingerprint is:
2026-03-16 16:36:43.295255 | controller -> localhost | SHA256:1Bo1qGd5EWp461HtC1PGVUtlSIDJUul25j0ZnzVJvDo zuul-build-sshkey
2026-03-16 16:36:43.295276 | controller -> localhost | The key's randomart image is:
2026-03-16 16:36:43.295318 | controller -> localhost | +---[RSA 3072]----+
2026-03-16 16:36:43.295347 | controller -> localhost | |         +==.o++=|
2026-03-16 16:36:43.295369 | controller -> localhost | |       .o+*+ .o+.|
2026-03-16 16:36:43.295389 | controller -> localhost | |      ..*+o.= ..o|
2026-03-16 16:36:43.295409 | controller -> localhost | |      .++=++o .+.|
2026-03-16 16:36:43.295428 | controller -> localhost | |       oSoo+...++|
2026-03-16 16:36:43.295447 | controller -> localhost | |       . . o.E+..|
2026-03-16 16:36:43.295465 | controller -> localhost | |        .   . .. |
2026-03-16 16:36:43.295486 | controller -> localhost | |                 |
2026-03-16 16:36:43.295506 | controller -> localhost | |                 |
2026-03-16 16:36:43.295525 | controller -> localhost | +----[SHA256]-----+
2026-03-16 16:36:43.295577 | controller -> localhost | ok: Runtime: 0:00:00.126114
2026-03-16 16:36:43.301428 | 
2026-03-16 16:36:43.301492 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-03-16 16:36:43.362650 | controller | ok
2026-03-16 16:36:43.374643 | controller | included: /var/lib/zuul/builds/090e2d575d8d43e2a831e236cba94d58/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-03-16 16:36:43.384624 | 
2026-03-16 16:36:43.384702 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-03-16 16:36:43.429804 | controller | skipping: Conditional result was False
2026-03-16 16:36:43.435439 | 
2026-03-16 16:36:43.435532 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-03-16 16:36:43.870156 | controller | changed
2026-03-16 16:36:43.877079 | 
2026-03-16 16:36:43.877170 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-03-16 16:36:44.102268 | controller | ok
2026-03-16 16:36:44.107196 | 
2026-03-16 16:36:44.107268 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-03-16 16:36:44.822548 | controller | changed
2026-03-16 16:36:44.836342 | 
2026-03-16 16:36:44.836439 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-03-16 16:36:45.597967 | controller | changed
2026-03-16 16:36:45.603241 | 
2026-03-16 16:36:45.603357 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-03-16 16:36:45.658038 | controller | skipping: Conditional result was False
2026-03-16 16:36:45.664239 | 
2026-03-16 16:36:45.664367 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-03-16 16:36:46.651693 | controller -> localhost | changed
2026-03-16 16:36:46.661656 | 
2026-03-16 16:36:46.661738 | TASK [add-build-sshkey : Add back temp key]
2026-03-16 16:36:47.341910 | controller -> localhost | Identity added: /var/lib/zuul/builds/090e2d575d8d43e2a831e236cba94d58/work/090e2d575d8d43e2a831e236cba94d58_id_rsa (zuul-build-sshkey)
2026-03-16 16:36:47.342117 | controller -> localhost | ok: Runtime: 0:00:00.011619
2026-03-16 16:36:47.348374 | 
2026-03-16 16:36:47.348439 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-03-16 16:36:47.754431 | controller | ok
2026-03-16 16:36:47.759205 | 
2026-03-16 16:36:47.759274 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-03-16 16:36:47.851515 | controller | skipping: Conditional result was False
2026-03-16 16:36:47.867235 | 
2026-03-16 16:36:47.867329 | TASK [include_role : validate-host]
2026-03-16 16:36:47.988493 | controller | ok
2026-03-16 16:36:48.033351 | 
2026-03-16 16:36:48.033458 | TASK [validate-host : Define zuul_info_dir fact]
2026-03-16 16:36:48.086691 | controller | ok
2026-03-16 16:36:48.097443 | 
2026-03-16 16:36:48.097518 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2026-03-16 16:36:48.712529 | controller -> localhost | ok
2026-03-16 16:36:48.718656 | 
2026-03-16 16:36:48.718733 | TASK [validate-host : Collect information about the host]
2026-03-16 16:36:49.382602 | controller | ok
2026-03-16 16:36:49.417502 | 
2026-03-16 16:36:49.417609 | TASK [validate-host : Sanitize hostname]
2026-03-16 16:36:49.524429 | controller | ok
2026-03-16 16:36:49.540001 | 
2026-03-16 16:36:49.540114 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2026-03-16 16:36:50.445219 | controller -> localhost | changed
2026-03-16 16:36:50.453650 | 
2026-03-16 16:36:50.453739 | TASK [validate-host : Collect information about zuul worker]
2026-03-16 16:36:51.006929 | controller | ok
2026-03-16 16:36:51.015190 | 
2026-03-16 16:36:51.015273 | TASK [validate-host : Write out all zuul information for each host]
2026-03-16 16:36:51.828870 | controller -> localhost | changed
2026-03-16 16:36:51.858989 | 
2026-03-16 16:36:51.859107 | TASK [include_role : prepare-workspace-openshift]
2026-03-16 16:36:51.900704 | controller | skipping: Conditional result was False
2026-03-16 16:36:51.905853 | 
2026-03-16 16:36:51.905922 | TASK [include_role : remove-zuul-sshkey]
2026-03-16 16:36:51.929609 | controller | skipping: Conditional result was False
2026-03-16 16:36:51.934984 | 
2026-03-16 16:36:51.935074 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-03-16 16:36:52.186343 | controller | ok: "logs"
2026-03-16 16:36:52.186577 | controller | ok: All items complete
2026-03-16 16:36:52.186613 | 
2026-03-16 16:36:52.353987 | controller | ok: "artifacts"
2026-03-16 16:36:52.524333 | controller | ok: "docs"
2026-03-16 16:36:52.535912 | 
2026-03-16 16:36:52.536016 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-03-16 16:36:52.765516 | controller | changed: "logs"
2026-03-16 16:36:52.941516 | controller | changed: "artifacts"
2026-03-16 16:36:53.123074 | controller | changed: "docs"
2026-03-16 16:36:53.174430 | 
2026-03-16 16:36:53.174532 | PLAY RECAP
2026-03-16 16:36:53.174576 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2026-03-16 16:36:53.174604 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-03-16 16:36:53.174622 | 
2026-03-16 16:36:53.290332 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2026-03-16 16:36:53.291087 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2026-03-16 16:36:54.134619 | 
2026-03-16 16:36:54.134729 | PLAY [all]
2026-03-16 16:36:54.178248 | 
2026-03-16 16:36:54.181374 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2026-03-16 16:36:54.252244 | controller | ok
2026-03-16 16:36:54.260461 | 
2026-03-16 16:36:54.260557 | TASK [mirror-info-fork : Create /etc/ci]
2026-03-16 16:36:54.717524 | controller | changed
2026-03-16 16:36:54.738088 | 
2026-03-16 16:36:54.738232 | TASK [mirror-info-fork : Install ci_mirror script]
2026-03-16 16:36:55.946651 | controller | changed
2026-03-16 16:36:55.963894 | 
2026-03-16 16:36:55.963986 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2026-03-16 16:36:56.564019 | controller | changed:
2026-03-16 16:36:56.564200 | controller | {
2026-03-16 16:36:56.564234 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2026-03-16 16:36:56.564264 | controller | }
2026-03-16 16:36:56.773975 | controller | changed:
2026-03-16 16:36:56.774093 | controller | {
2026-03-16 16:36:56.774125 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2026-03-16 16:36:56.774148 | controller | }
2026-03-16 16:36:57.000171 | controller | changed:
2026-03-16 16:36:57.000260 | controller | {
2026-03-16 16:36:57.000287 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com"
2026-03-16 16:36:57.000334 | controller | }
2026-03-16 16:36:57.217144 | controller | changed:
2026-03-16 16:36:57.217229 | controller | {
2026-03-16 16:36:57.217257 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2026-03-16 16:36:57.217280 | controller | }
2026-03-16 16:36:57.441490 | controller | changed:
2026-03-16 16:36:57.441611 | controller | {
2026-03-16 16:36:57.441641 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2026-03-16 16:36:57.441662 | controller | }
2026-03-16 16:36:57.650283 | controller | changed:
2026-03-16 16:36:57.650414 | controller | {
2026-03-16 16:36:57.650451 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2026-03-16 16:36:57.650479 | controller | }
2026-03-16 16:36:57.864487 | controller | changed:
2026-03-16 16:36:57.864665 | controller | {
2026-03-16 16:36:57.864709 | 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"
2026-03-16 16:36:57.864736 | controller | }
2026-03-16 16:36:58.116880 | controller | changed:
2026-03-16 16:36:58.116972 | controller | {
2026-03-16 16:36:58.120022 | 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"
2026-03-16 16:36:58.123391 | controller | }
2026-03-16 16:36:58.305146 | controller | changed:
2026-03-16 16:36:58.305248 | controller | {
2026-03-16 16:36:58.305277 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2026-03-16 16:36:58.306877 | controller | }
2026-03-16 16:36:58.530704 | controller | changed:
2026-03-16 16:36:58.530787 | controller | {
2026-03-16 16:36:58.530815 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2026-03-16 16:36:58.530836 | controller | }
2026-03-16 16:36:58.785315 | controller | changed:
2026-03-16 16:36:58.785397 | controller | {
2026-03-16 16:36:58.785426 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2026-03-16 16:36:58.785447 | controller | }
2026-03-16 16:36:58.948610 | controller | changed:
2026-03-16 16:36:58.948697 | controller | {
2026-03-16 16:36:58.948727 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2026-03-16 16:36:58.948749 | controller | }
2026-03-16 16:36:59.152909 | controller | changed:
2026-03-16 16:36:59.152993 | controller | {
2026-03-16 16:36:59.153023 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICWBreHW95Wz2Toz5YwCGQwFcUG8oFYkienDh9tntmDc ralfieri@redhat.com"
2026-03-16 16:36:59.153065 | controller | }
2026-03-16 16:36:59.365668 | controller | changed:
2026-03-16 16:36:59.365754 | controller | {
2026-03-16 16:36:59.365785 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2026-03-16 16:36:59.365806 | controller | }
2026-03-16 16:36:59.572379 | controller | changed:
2026-03-16 16:36:59.572455 | controller | {
2026-03-16 16:36:59.572482 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2026-03-16 16:36:59.572504 | controller | }
2026-03-16 16:36:59.781494 | controller | changed:
2026-03-16 16:36:59.781568 | controller | {
2026-03-16 16:36:59.781595 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2026-03-16 16:36:59.781615 | controller | }
2026-03-16 16:37:00.019243 | controller | changed:
2026-03-16 16:37:00.019362 | controller | {
2026-03-16 16:37:00.019395 | 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"
2026-03-16 16:37:00.019418 | controller | }
2026-03-16 16:37:00.242457 | controller | changed:
2026-03-16 16:37:00.242546 | controller | {
2026-03-16 16:37:00.242574 | 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"
2026-03-16 16:37:00.242595 | controller | }
2026-03-16 16:37:00.454486 | controller | changed:
2026-03-16 16:37:00.454576 | controller | {
2026-03-16 16:37:00.454605 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2026-03-16 16:37:00.454626 | controller | }
2026-03-16 16:37:00.671726 | controller | changed:
2026-03-16 16:37:00.671809 | controller | {
2026-03-16 16:37:00.671838 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2026-03-16 16:37:00.671861 | controller | }
2026-03-16 16:37:00.887835 | controller | changed:
2026-03-16 16:37:00.887919 | controller | {
2026-03-16 16:37:00.887947 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2026-03-16 16:37:00.887969 | controller | }
2026-03-16 16:37:01.114950 | controller | changed:
2026-03-16 16:37:01.115037 | controller | {
2026-03-16 16:37:01.115082 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2026-03-16 16:37:01.115104 | controller | }
2026-03-16 16:37:01.336331 | controller | changed:
2026-03-16 16:37:01.336416 | controller | {
2026-03-16 16:37:01.336443 | 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"
2026-03-16 16:37:01.336464 | controller | }
2026-03-16 16:37:01.551013 | controller | changed:
2026-03-16 16:37:01.551106 | controller | {
2026-03-16 16:37:01.551133 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2026-03-16 16:37:01.551153 | controller | }
2026-03-16 16:37:01.777027 | controller | changed:
2026-03-16 16:37:01.777125 | controller | {
2026-03-16 16:37:01.777153 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2026-03-16 16:37:01.777173 | controller | }
2026-03-16 16:37:02.030962 | controller | changed:
2026-03-16 16:37:02.031061 | controller | {
2026-03-16 16:37:02.031093 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2026-03-16 16:37:02.031114 | controller | }
2026-03-16 16:37:02.078890 | 
2026-03-16 16:37:02.078991 | TASK [Set timezone to UTC]
2026-03-16 16:37:02.687059 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2026-03-16 16:37:02.693574 | 
2026-03-16 16:37:02.693654 | TASK [Create nodepool directory]
2026-03-16 16:37:02.915030 | controller | changed
2026-03-16 16:37:02.927680 | 
2026-03-16 16:37:02.927811 | TASK [Create nodepool sub_nodes file]
2026-03-16 16:37:03.680833 | controller | changed
2026-03-16 16:37:03.687750 | 
2026-03-16 16:37:03.687841 | TASK [Create nodepool sub_nodes_private file]
2026-03-16 16:37:04.412226 | controller | changed
2026-03-16 16:37:04.439488 | 
2026-03-16 16:37:04.439607 | LOOP [Populate nodepool sub_nodes file]
2026-03-16 16:37:04.479672 | 
2026-03-16 16:37:04.479859 | LOOP [Populate nodepool sub_nodes_private file]
2026-03-16 16:37:04.546845 | 
2026-03-16 16:37:04.546996 | TASK [Create nodepool primary file]
2026-03-16 16:37:04.573267 | controller | skipping: Conditional result was False
2026-03-16 16:37:04.578882 | 
2026-03-16 16:37:04.578973 | TASK [Create nodepool node_private for this node]
2026-03-16 16:37:05.147538 | controller | changed
2026-03-16 16:37:05.154648 | 
2026-03-16 16:37:05.154713 | LOOP [Copy ssh keys to nodepool directory]
2026-03-16 16:37:05.734465 | controller | ok: Item: id_rsa Runtime: 0:00:00.004737
2026-03-16 16:37:05.734645 | 
2026-03-16 16:37:05.906087 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.005101
2026-03-16 16:37:05.918337 | 
2026-03-16 16:37:05.918444 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2026-03-16 16:37:06.627235 | controller | changed
2026-03-16 16:37:06.644537 | 
2026-03-16 16:37:06.644623 | TASK [Validate sudoers config after edits]
2026-03-16 16:37:06.951364 | controller | /etc/sudoers: parsed OK
2026-03-16 16:37:06.951467 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2026-03-16 16:37:06.951479 | controller | /etc/sudoers.d/zuul: parsed OK
2026-03-16 16:37:06.951486 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2026-03-16 16:37:07.199879 | controller | ok: Runtime: 0:00:00.005562
2026-03-16 16:37:07.205603 | 
2026-03-16 16:37:07.205668 | TASK [Show the environment passed in to job shell scripts]
2026-03-16 16:37:07.502785 | controller | SHELL=/bin/bash
2026-03-16 16:37:07.502838 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2026-03-16 16:37:07.502848 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2026-03-16 16:37:07.502857 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/70/3770/235f23c012043ef9e6fbd3c71c40da700f09e62a
2026-03-16 16:37:07.502863 | controller | PWD=/home/zuul
2026-03-16 16:37:07.502869 | controller | ZUUL_PIPELINE=github-check
2026-03-16 16:37:07.502874 | controller | LOGNAME=zuul
2026-03-16 16:37:07.502925 | controller | XDG_SESSION_TYPE=tty
2026-03-16 16:37:07.502933 | controller | _=/usr/bin/env
2026-03-16 16:37:07.502939 | controller | MOTD_SHOWN=pam
2026-03-16 16:37:07.502944 | controller | HOME=/home/zuul
2026-03-16 16:37:07.502950 | controller | LANG=en_US.UTF-8
2026-03-16 16:37:07.502955 | controller | SSH_CONNECTION=192.168.26.12 56590 192.168.26.30 22
2026-03-16 16:37:07.502961 | 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
2026-03-16 16:37:07.502969 | controller | ZUUL_CHANGE_IDS=3770,235f23c012043ef9e6fbd3c71c40da700f09e62a
2026-03-16 16:37:07.502975 | controller | WORKSPACE=/home/zuul/workspace
2026-03-16 16:37:07.502980 | controller | XDG_SESSION_CLASS=user
2026-03-16 16:37:07.502986 | controller | SELINUX_ROLE_REQUESTED=
2026-03-16 16:37:07.502991 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2026-03-16 16:37:07.502997 | controller | USER=zuul
2026-03-16 16:37:07.503002 | controller | ZUUL_VOTING=True
2026-03-16 16:37:07.503007 | controller | BUILD_TIMEOUT=1800000
2026-03-16 16:37:07.503013 | controller | SELINUX_USE_CURRENT_RANGE=
2026-03-16 16:37:07.503018 | controller | SHLVL=1
2026-03-16 16:37:07.503024 | controller | ZUUL_PATCHSET=235f23c012043ef9e6fbd3c71c40da700f09e62a
2026-03-16 16:37:07.503029 | controller | XDG_SESSION_ID=1
2026-03-16 16:37:07.503034 | controller | ZUUL_BRANCH=main
2026-03-16 16:37:07.503040 | controller | XDG_RUNTIME_DIR=/run/user/1000
2026-03-16 16:37:07.503045 | controller | SSH_CLIENT=192.168.26.12 56590 22
2026-03-16 16:37:07.503051 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2026-03-16 16:37:07.503056 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2026-03-16 16:37:07.503062 | controller | which_declare=declare -f
2026-03-16 16:37:07.503068 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2026-03-16 16:37:07.503073 | controller | SELINUX_LEVEL_REQUESTED=
2026-03-16 16:37:07.503079 | controller | ZUUL_CHANGE=3770
2026-03-16 16:37:07.503084 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2026-03-16 16:37:07.503089 | controller | ZUUL_UUID=090e2d575d8d43e2a831e236cba94d58
2026-03-16 16:37:07.503095 | controller | BASH_FUNC_which%%=() {  ( alias;
2026-03-16 16:37:07.503100 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2026-03-16 16:37:07.503106 | controller | }
2026-03-16 16:37:07.765632 | controller | ok: Runtime: 0:00:00.005827
2026-03-16 16:37:07.772389 | 
2026-03-16 16:37:07.772534 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2026-03-16 16:37:07.806750 | controller | skipping: Conditional result was False
2026-03-16 16:37:07.814336 | 
2026-03-16 16:37:07.814480 | TASK [Symlink /home/zuul-worker/workspace]
2026-03-16 16:37:08.419981 | controller | skipping: Conditional result was False
2026-03-16 16:37:08.436352 | 
2026-03-16 16:37:08.436488 | TASK [Ensure legacy workspace directory]
2026-03-16 16:37:08.707718 | controller | changed
2026-03-16 16:37:08.962717 | 
2026-03-16 16:37:08.962855 | PLAY RECAP
2026-03-16 16:37:08.962908 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2026-03-16 16:37:08.962933 | 
2026-03-16 16:37:09.266710 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2026-03-16 16:37:09.267485 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2026-03-16 16:37:10.536117 | 
2026-03-16 16:37:10.536238 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2026-03-16 16:37:10.609897 | 
2026-03-16 16:37:10.610022 | TASK [Create zuul-output directory]
2026-03-16 16:37:11.302627 | controller | changed
2026-03-16 16:37:11.316533 | 
2026-03-16 16:37:11.316715 | TASK [Slurp Zuul inventory test]
2026-03-16 16:37:11.768987 | controller -> localhost | ok
2026-03-16 16:37:11.778282 | 
2026-03-16 16:37:11.778391 | TASK [Save zuul inventory]
2026-03-16 16:37:12.786709 | controller | changed
2026-03-16 16:37:12.792387 | 
2026-03-16 16:37:12.792466 | TASK [Save zuul vars without the change_message]
2026-03-16 16:37:13.577349 | controller | changed
2026-03-16 16:37:13.641817 | 
2026-03-16 16:37:13.641899 | PLAY RECAP
2026-03-16 16:37:13.641942 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-03-16 16:37:13.641966 | 
2026-03-16 16:37:13.851687 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2026-03-16 16:37:13.852432 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2026-03-16 16:37:14.993725 | 
2026-03-16 16:37:14.993829 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2026-03-16 16:37:15.046131 | 
2026-03-16 16:37:15.046245 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2026-03-16 16:37:15.096709 | controller | ok
2026-03-16 16:37:15.130156 | 
2026-03-16 16:37:15.130269 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2026-03-16 16:37:15.186779 | controller | skipping: Conditional result was False
2026-03-16 16:37:15.193122 | 
2026-03-16 16:37:15.193203 | TASK [mirror-info-fork : Create /etc/ci]
2026-03-16 16:37:15.645179 | controller | ok
2026-03-16 16:37:15.655924 | 
2026-03-16 16:37:15.656036 | TASK [mirror-info-fork : Install ci_mirror script]
2026-03-16 16:37:16.647244 | controller | ok
2026-03-16 16:37:16.690758 | 
2026-03-16 16:37:16.690896 | TASK [Prepare workspace]
2026-03-16 16:37:16.745821 | controller | ok
2026-03-16 16:37:16.815990 | 
2026-03-16 16:37:16.816120 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-03-16 16:37:17.405554 | controller | ok
2026-03-16 16:37:17.411789 | 
2026-03-16 16:37:17.411884 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-03-16 16:37:21.530548 | controller | Output suppressed because no_log was given
2026-03-16 16:37:21.563349 | 
2026-03-16 16:37:21.563449 | LOOP [Create zuul-output directory]
2026-03-16 16:37:21.779067 | controller | changed: "/home/zuul/ci-framework-data/logs"
2026-03-16 16:37:21.954133 | controller | ok: "/home/zuul/zuul-output/logs"
2026-03-16 16:37:21.971674 | 
2026-03-16 16:37:21.971781 | TASK [Install required packages]
2026-03-16 16:37:58.242405 | controller | changed
2026-03-16 16:37:58.247713 | 
2026-03-16 16:37:58.247804 | TASK [Install venv]
2026-03-16 16:38:13.154488 | controller | ERROR
2026-03-16 16:38:13.154704 | controller | {
2026-03-16 16:38:13.154741 | controller |   "msg": "gmake: *** [Makefile:56: setup_tests] Error 1",
2026-03-16 16:38:13.154765 | controller |   "rc": 2
2026-03-16 16:38:13.154786 | controller | }
failure
2026-03-16 16:38:13.166272 | 
2026-03-16 16:38:13.166352 | PLAY RECAP
2026-03-16 16:38:13.166400 | controller | ok: 6 changed: 3 unreachable: 0 failed: 1 skipped: 1 rescued: 0 ignored: 0
2026-03-16 16:38:13.166424 | 
2026-03-16 16:38:13.401514 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2026-03-16 16:38:13.402409 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2026-03-16 16:38:14.630884 | 
2026-03-16 16:38:14.631003 | PLAY [Run ci/playbooks/collect-logs.yml]
2026-03-16 16:38:14.708482 | 
2026-03-16 16:38:14.708610 | TASK [Filter out host if needed]
2026-03-16 16:38:14.737155 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2026-03-16 16:38:14.755055 | 
2026-03-16 16:38:14.755146 | TASK [Ensure file is present]
2026-03-16 16:38:15.344252 | controller | ok
2026-03-16 16:38:15.358682 | 
2026-03-16 16:38:15.358767 | TASK [Manage molecule report file]
2026-03-16 16:38:15.908106 | controller | skipping: Conditional result was False
2026-03-16 16:38:15.915086 | 
2026-03-16 16:38:15.915172 | TASK [Check if we get ci-framework-data basedir]
2026-03-16 16:38:16.164644 | controller | ok
2026-03-16 16:38:16.172020 | 
2026-03-16 16:38:16.172121 | TASK [Create ci-framework-data log directory for zuul]
2026-03-16 16:38:16.741994 | controller | changed
2026-03-16 16:38:16.750177 | 
2026-03-16 16:38:16.750253 | TASK [Copy ci-framework interesting files]
2026-03-16 16:38:17.389449 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2026-03-16 16:38:17.828119 | controller | changed
2026-03-16 16:38:17.834704 | 
2026-03-16 16:38:17.834781 | TASK [Get SELinux listing]
2026-03-16 16:38:18.394921 | controller | changed
2026-03-16 16:38:18.400799 | 
2026-03-16 16:38:18.400976 | TASK [Generate log index]
2026-03-16 16:38:19.397465 | controller | changed
2026-03-16 16:38:19.403131 | 
2026-03-16 16:38:19.403209 | TASK [Get some env related data]
2026-03-16 16:38:20.431553 | controller | changed
2026-03-16 16:38:20.437125 | 
2026-03-16 16:38:20.437210 | TASK [Generate list of logs to collect in home directory]
2026-03-16 16:38:21.255348 | controller | ok: All paths examined
2026-03-16 16:38:21.348724 | 
2026-03-16 16:38:21.348817 | LOOP [Copy logs from home directory]
2026-03-16 16:38:21.395161 | 
2026-03-16 16:38:21.395317 | TASK [Copy crio stats log file]
2026-03-16 16:38:21.459436 | controller | skipping: Conditional result was False
2026-03-16 16:38:21.464963 | 
2026-03-16 16:38:21.465028 | TASK [Get SELinux related data]
2026-03-16 16:38:21.709581 | controller | <no matches>
2026-03-16 16:38:22.013892 | controller | ERROR
2026-03-16 16:38:22.014087 | controller | {
2026-03-16 16:38:22.014125 | controller |   "delta": "0:00:00.009783",
2026-03-16 16:38:22.014149 | controller |   "end": "2026-03-16 16:38:21.710517",
2026-03-16 16:38:22.014169 | controller |   "msg": "non-zero return code",
2026-03-16 16:38:22.014187 | controller |   "rc": 1,
2026-03-16 16:38:22.014205 | controller |   "start": "2026-03-16 16:38:21.700734"
2026-03-16 16:38:22.014223 | controller | }
2026-03-16 16:38:22.014249 | controller | ERROR: Ignoring Errors
2026-03-16 16:38:22.019711 | 
2026-03-16 16:38:22.019776 | TASK [Create system configuration directory]
2026-03-16 16:38:22.251081 | controller | changed
2026-03-16 16:38:22.256538 | 
2026-03-16 16:38:22.256621 | TASK [Get some of the system configurations]
2026-03-16 16:38:22.868554 | controller | changed
2026-03-16 16:38:22.873957 | 
2026-03-16 16:38:22.874052 | TASK [Copy generated documentation if available]
2026-03-16 16:38:22.928287 | controller | skipping: Conditional result was False
2026-03-16 16:38:22.939971 | 
2026-03-16 16:38:22.940068 | TASK [Copy generated AsciiDoc documentation if available]
2026-03-16 16:38:23.003772 | controller | skipping: Conditional result was False
2026-03-16 16:38:23.010678 | 
2026-03-16 16:38:23.010759 | TASK [Compress logs bigger than 2MB]
2026-03-16 16:38:23.603216 | controller | changed
2026-03-16 16:38:23.609432 | 
2026-03-16 16:38:23.609516 | TASK [Copy files from workspace on node]
2026-03-16 16:38:23.668398 | controller | ok
2026-03-16 16:38:23.739441 | 
2026-03-16 16:38:23.739536 | TASK [fetch-output : Set log path for multiple nodes]
2026-03-16 16:38:23.813069 | controller | skipping: Conditional result was False
2026-03-16 16:38:23.819611 | 
2026-03-16 16:38:23.819699 | TASK [fetch-output : Set log path for single node]
2026-03-16 16:38:23.857164 | controller | ok
2026-03-16 16:38:23.875227 | 
2026-03-16 16:38:23.875330 | LOOP [fetch-output : Ensure local output dirs]
2026-03-16 16:38:24.751459 | controller -> localhost | ok: "/var/lib/zuul/builds/090e2d575d8d43e2a831e236cba94d58/work/logs"
2026-03-16 16:38:24.751715 | 
2026-03-16 16:38:25.311076 | controller -> localhost | changed: "/var/lib/zuul/builds/090e2d575d8d43e2a831e236cba94d58/work/artifacts"
2026-03-16 16:38:26.004112 | controller -> localhost | changed: "/var/lib/zuul/builds/090e2d575d8d43e2a831e236cba94d58/work/docs"
2026-03-16 16:38:26.012725 | 
2026-03-16 16:38:26.012817 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-03-16 16:38:27.131256 | controller | changed:
2026-03-16 16:38:27.131458 | controller | .d..t...... ./
2026-03-16 16:38:27.131495 | controller | >f+++++++++ README.html
2026-03-16 16:38:27.131520 | controller | >f+++++++++ dmesg.log
2026-03-16 16:38:27.131542 | controller | >f+++++++++ installed-pkgs.log
2026-03-16 16:38:27.131561 | controller | >f+++++++++ python.log
2026-03-16 16:38:27.131580 | controller | >f+++++++++ registries.conf
2026-03-16 16:38:27.131598 | controller | >f+++++++++ selinux-denials.log
2026-03-16 16:38:27.131616 | controller | >f+++++++++ selinux-listing.log
2026-03-16 16:38:27.131633 | controller | cd+++++++++ ci-framework-data/
2026-03-16 16:38:27.131651 | controller | cd+++++++++ ci-framework-data/artifacts/
2026-03-16 16:38:27.131670 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2026-03-16 16:38:27.131689 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2026-03-16 16:38:27.131708 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2026-03-16 16:38:27.131726 | controller | cd+++++++++ ci-framework-data/logs/
2026-03-16 16:38:27.131744 | controller | cd+++++++++ registries.conf.d/
2026-03-16 16:38:27.131762 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2026-03-16 16:38:27.131779 | controller | cd+++++++++ system-config/
2026-03-16 16:38:27.131796 | controller | cd+++++++++ system-config/libvirt/
2026-03-16 16:38:27.131813 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2026-03-16 16:38:27.131829 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2026-03-16 16:38:28.064022 | controller | changed: .d..t...... ./
2026-03-16 16:38:28.700719 | controller | changed: .d..t...... ./
2026-03-16 16:38:28.733745 | 
2026-03-16 16:38:28.733842 | TASK [Return artifact to Zuul]
2026-03-16 16:38:28.819654 | controller | skipping: Conditional result was False
2026-03-16 16:38:28.860769 | 
2026-03-16 16:38:28.860855 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2026-03-16 16:38:28.860967 | 
2026-03-16 16:38:28.860996 | PLAY RECAP
2026-03-16 16:38:28.861047 | controller | ok: 15 changed: 11 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 1
2026-03-16 16:38:28.861072 | 
2026-03-16 16:38:29.056074 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2026-03-16 16:38:29.057027 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2026-03-16 16:38:30.358565 | 
2026-03-16 16:38:30.358673 | PLAY [all]
2026-03-16 16:38:30.421734 | 
2026-03-16 16:38:30.421860 | TASK [include_role : fetch-output]
2026-03-16 16:38:30.484552 | controller | ok
2026-03-16 16:38:30.519415 | 
2026-03-16 16:38:30.519529 | TASK [fetch-output : Set log path for multiple nodes]
2026-03-16 16:38:30.648707 | controller | skipping: Conditional result was False
2026-03-16 16:38:30.654742 | 
2026-03-16 16:38:30.654829 | TASK [fetch-output : Set log path for single node]
2026-03-16 16:38:30.723956 | controller | ok
2026-03-16 16:38:30.734105 | 
2026-03-16 16:38:30.734195 | LOOP [fetch-output : Ensure local output dirs]
2026-03-16 16:38:31.884638 | controller -> localhost | ok: "/var/lib/zuul/builds/090e2d575d8d43e2a831e236cba94d58/work/logs"
2026-03-16 16:38:32.574209 | controller -> localhost | ok: "/var/lib/zuul/builds/090e2d575d8d43e2a831e236cba94d58/work/artifacts"
2026-03-16 16:38:33.413905 | controller -> localhost | ok: "/var/lib/zuul/builds/090e2d575d8d43e2a831e236cba94d58/work/docs"
2026-03-16 16:38:33.431506 | 
2026-03-16 16:38:33.431606 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-03-16 16:38:34.688475 | controller | ok
2026-03-16 16:38:34.688658 | controller | ok: All items complete
2026-03-16 16:38:34.688693 | 
2026-03-16 16:38:35.581888 | controller | ok
2026-03-16 16:38:36.431430 | controller | ok
2026-03-16 16:38:36.449116 | 
2026-03-16 16:38:36.449235 | TASK [include_role : fetch-output-openshift]
2026-03-16 16:38:36.517402 | controller | skipping: Conditional result was False
2026-03-16 16:38:36.524207 | 
2026-03-16 16:38:36.524292 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-03-16 16:38:37.579403 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.065700
2026-03-16 16:38:38.332722 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.059135
2026-03-16 16:38:38.417664 | 
2026-03-16 16:38:38.417765 | PLAY [all]
2026-03-16 16:38:38.464733 | 
2026-03-16 16:38:38.464832 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-03-16 16:38:39.328567 | controller | changed
2026-03-16 16:38:39.406168 | 
2026-03-16 16:38:39.406244 | PLAY RECAP
2026-03-16 16:38:39.406292 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2026-03-16 16:38:39.406339 | 
2026-03-16 16:38:39.605898 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2026-03-16 16:38:39.606650 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2026-03-16 16:38:41.349388 | 
2026-03-16 16:38:41.349494 | PLAY [localhost]
2026-03-16 16:38:41.375158 | 
2026-03-16 16:38:41.375244 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-03-16 16:38:42.565917 | localhost | changed
2026-03-16 16:38:42.570632 | 
2026-03-16 16:38:42.570729 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-03-16 16:38:42.630704 | localhost | ok
2026-03-16 16:38:42.664523 | 
2026-03-16 16:38:42.664614 | TASK [add-fileserver : Create SSH private key tempfile]
2026-03-16 16:38:43.653110 | localhost | changed
2026-03-16 16:38:43.658368 | 
2026-03-16 16:38:43.658439 | TASK [add-fileserver : Create SSH private key from secret]
2026-03-16 16:38:45.929082 | localhost | changed
2026-03-16 16:38:45.936471 | 
2026-03-16 16:38:45.936557 | TASK [add-fileserver : Add fileserver ssh key]
2026-03-16 16:38:47.117374 | localhost | Identity added: /var/lib/zuul/builds/090e2d575d8d43e2a831e236cba94d58/work/tmp/ansible.e6ham63z (/var/lib/zuul/builds/090e2d575d8d43e2a831e236cba94d58/work/tmp/ansible.e6ham63z)
2026-03-16 16:38:47.117583 | localhost | ok: Runtime: 0:00:00.043514
2026-03-16 16:38:47.122249 | 
2026-03-16 16:38:47.123055 | TASK [add-fileserver : Remove SSH private key from disk]
2026-03-16 16:38:48.079856 | localhost | ok: Runtime: 0:00:00.011301
2026-03-16 16:38:48.085084 | 
2026-03-16 16:38:48.085163 | TASK [add-fileserver : Add fileserver to inventory]
2026-03-16 16:38:48.223374 | localhost | changed
2026-03-16 16:38:48.228370 | 
2026-03-16 16:38:48.228453 | TASK [add-fileserver : Add fileserver server to known hosts]
2026-03-16 16:38:49.631131 | localhost | changed
2026-03-16 16:38:49.747161 | 
2026-03-16 16:38:49.747237 | PLAY [localhost]
2026-03-16 16:38:49.776367 | 
2026-03-16 16:38:49.776449 | TASK [Generate bulk log download script]
2026-03-16 16:38:49.819114 | localhost | ok
2026-03-16 16:38:49.842757 | 
2026-03-16 16:38:49.847572 | TASK [local-log-download : Check API endpoint is defined]
2026-03-16 16:38:49.976142 | localhost | ok: All assertions passed
2026-03-16 16:38:49.980736 | 
2026-03-16 16:38:49.980809 | TASK [local-log-download : Create download script]
2026-03-16 16:38:51.020103 | localhost -> localhost | changed
2026-03-16 16:38:51.029255 | 
2026-03-16 16:38:51.029359 | TASK [Register quick-download link]
2026-03-16 16:38:51.091622 | localhost | ok
2026-03-16 16:38:51.132471 | 
2026-03-16 16:38:51.132545 | PLAY [logserver.rdoproject.org]
2026-03-16 16:38:51.176814 | 
2026-03-16 16:38:51.176882 | TASK [Set zuul-log-path fact]
2026-03-16 16:38:51.225905 | logserver.rdoproject.org | ok
2026-03-16 16:38:51.255546 | 
2026-03-16 16:38:51.255623 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-03-16 16:38:51.322121 | logserver.rdoproject.org | ok
2026-03-16 16:38:51.327774 | 
2026-03-16 16:38:51.327839 | TASK [upload-logs : Create log directories]
2026-03-16 16:38:52.953344 | logserver.rdoproject.org | changed
2026-03-16 16:38:52.957093 | 
2026-03-16 16:38:52.957253 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-03-16 16:38:53.770078 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.040609
2026-03-16 16:38:53.775185 | 
2026-03-16 16:38:53.775261 | TASK [upload-logs : Upload logs to log server]
2026-03-16 16:38:55.934692 | logserver.rdoproject.org | Output suppressed because no_log was given
2026-03-16 16:38:55.937825 | 
2026-03-16 16:38:55.937902 | LOOP [upload-logs : Compress console log and json output]
2026-03-16 16:38:56.070458 | logserver.rdoproject.org | skipping: Conditional result was False
2026-03-16 16:38:56.093140 | logserver.rdoproject.org | skipping: Conditional result was False
2026-03-16 16:38:56.107478 | 
2026-03-16 16:38:56.107592 | LOOP [upload-logs : Upload compressed console log and json output]
2026-03-16 16:38:56.218601 | logserver.rdoproject.org | skipping: Conditional result was False
2026-03-16 16:38:56.218837 | 
2026-03-16 16:38:56.223005 | logserver.rdoproject.org | skipping: Conditional result was False
2026-03-16 16:38:56.231417 | 
2026-03-16 16:38:56.231536 | LOOP [upload-logs : Upload console log and json output]