2025-12-05 15:47:23.991610 | Job console starting...
2025-12-05 15:47:24.207330 | Updating repositories
2025-12-05 15:47:24.955677 | Preparing job workspace
2025-12-05 15:47:32.533378 | Running Ansible setup...
2025-12-05 15:47:36.123305 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-12-05 15:47:36.655532 | 
2025-12-05 15:47:36.655636 | PLAY [localhost]
2025-12-05 15:47:36.664475 | 
2025-12-05 15:47:36.664549 | TASK [Gathering Facts]
2025-12-05 15:47:37.492571 | localhost | ok
2025-12-05 15:47:37.506549 | 
2025-12-05 15:47:37.506632 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-12-05 15:47:37.890613 | localhost -> localhost | changed
2025-12-05 15:47:37.896126 | 
2025-12-05 15:47:37.896209 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-12-05 15:47:38.652750 | localhost -> localhost | changed
2025-12-05 15:47:38.666614 | 
2025-12-05 15:47:38.666724 | TASK [Setup log path fact]
2025-12-05 15:47:38.684000 | localhost | ok
2025-12-05 15:47:38.694921 | 
2025-12-05 15:47:38.694997 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-12-05 15:47:38.712092 | localhost | ok
2025-12-05 15:47:38.721666 | 
2025-12-05 15:47:38.721739 | TASK [emit-job-header : Print job information]
2025-12-05 15:47:38.748492 | # Job Information
2025-12-05 15:47:38.748603 | Ansible Version: 2.15.12
2025-12-05 15:47:38.748634 | Job: cifmw-molecule-openshift_obs
2025-12-05 15:47:38.748658 | Pipeline: github-check
2025-12-05 15:47:38.748680 | Executor: ibm-bm4-ze.softwarefactory-project.io
2025-12-05 15:47:38.748700 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3539
2025-12-05 15:47:38.748720 | Log URL (when completed): https://logserver.rdoproject.org/cc9/rdoproject.org/cc9e539dfe1c4b65918736d6186a0a1c/
2025-12-05 15:47:38.748739 | Event ID: 757b9e30-d1ef-11f0-97eb-f505e6ea5a00
2025-12-05 15:47:38.752223 | 
2025-12-05 15:47:38.752288 | LOOP [emit-job-header : Print node information]
2025-12-05 15:47:38.847081 | localhost | ok:
2025-12-05 15:47:38.847312 | localhost | # Node Information
2025-12-05 15:47:38.847350 | localhost | Inventory Hostname: controller
2025-12-05 15:47:38.847378 | localhost | Hostname: np0005547631
2025-12-05 15:47:38.847399 | localhost | Username: zuul
2025-12-05 15:47:38.847421 | localhost | Distro: CentOS 9
2025-12-05 15:47:38.847440 | localhost | Provider: ibm-bm4-nodepool
2025-12-05 15:47:38.847459 | localhost | Region: regionOne
2025-12-05 15:47:38.847476 | localhost | Label: centos-9-stream-crc-2-48-0-xxl-ibm
2025-12-05 15:47:38.847499 | localhost | Product Name: OpenStack Compute
2025-12-05 15:47:38.847517 | localhost | Interface IP: 192.168.26.197
2025-12-05 15:47:38.875779 | 
2025-12-05 15:47:38.875854 | PLAY [all]
2025-12-05 15:47:38.882177 | 
2025-12-05 15:47:38.882279 | TASK [Gather network facts]
2025-12-05 15:47:39.305589 | controller | ok
2025-12-05 15:47:39.320079 | 
2025-12-05 15:47:39.320143 | TASK [include_role : start-zuul-console]
2025-12-05 15:47:39.338136 | controller | ok
2025-12-05 15:47:39.349553 | 
2025-12-05 15:47:39.349624 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-12-05 15:47:39.702899 | controller | ok
2025-12-05 15:47:39.711397 | 
2025-12-05 15:47:39.711464 | TASK [include_role : add-build-sshkey]
2025-12-05 15:47:39.729493 | controller | ok
2025-12-05 15:47:39.742210 | 
2025-12-05 15:47:39.742290 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-12-05 15:47:39.948305 | controller -> localhost | ok
2025-12-05 15:47:39.953943 | 
2025-12-05 15:47:39.954032 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-12-05 15:47:39.973340 | controller | ok
2025-12-05 15:47:39.986983 | controller | included: /var/lib/zuul/builds/cc9e539dfe1c4b65918736d6186a0a1c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-12-05 15:47:39.992479 | 
2025-12-05 15:47:39.992543 | TASK [add-build-sshkey : Create Temp SSH key]
2025-12-05 15:47:40.575074 | controller -> localhost | Generating public/private rsa key pair.
2025-12-05 15:47:40.575276 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/cc9e539dfe1c4b65918736d6186a0a1c/work/cc9e539dfe1c4b65918736d6186a0a1c_id_rsa.
2025-12-05 15:47:40.575312 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/cc9e539dfe1c4b65918736d6186a0a1c/work/cc9e539dfe1c4b65918736d6186a0a1c_id_rsa.pub.
2025-12-05 15:47:40.575337 | controller -> localhost | The key fingerprint is:
2025-12-05 15:47:40.575357 | controller -> localhost | SHA256:dCc8jH49F+du+KeZbDEhQC5RzfcCRczMu1mGTFYseRI zuul-build-sshkey
2025-12-05 15:47:40.575377 | controller -> localhost | The key's randomart image is:
2025-12-05 15:47:40.575394 | controller -> localhost | +---[RSA 3072]----+
2025-12-05 15:47:40.575413 | controller -> localhost | |        .ooo BE=.|
2025-12-05 15:47:40.575431 | controller -> localhost | |         *. + % o|
2025-12-05 15:47:40.575449 | controller -> localhost | |        + B..*.B.|
2025-12-05 15:47:40.575467 | controller -> localhost | |       o o =. *++|
2025-12-05 15:47:40.575484 | controller -> localhost | |        S . o..B.|
2025-12-05 15:47:40.575501 | controller -> localhost | |         .   o=o |
2025-12-05 15:47:40.575519 | controller -> localhost | |              .oo|
2025-12-05 15:47:40.575537 | controller -> localhost | |             ..=.|
2025-12-05 15:47:40.575553 | controller -> localhost | |             .=.o|
2025-12-05 15:47:40.575569 | controller -> localhost | +----[SHA256]-----+
2025-12-05 15:47:40.575616 | controller -> localhost | ok: Runtime: 0:00:00.218834
2025-12-05 15:47:40.581294 | 
2025-12-05 15:47:40.581358 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-12-05 15:47:40.599414 | controller | ok
2025-12-05 15:47:40.609052 | controller | included: /var/lib/zuul/builds/cc9e539dfe1c4b65918736d6186a0a1c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-12-05 15:47:40.617583 | 
2025-12-05 15:47:40.617671 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-12-05 15:47:40.631156 | controller | skipping: Conditional result was False
2025-12-05 15:47:40.636891 | 
2025-12-05 15:47:40.636989 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-12-05 15:47:41.107666 | controller | changed
2025-12-05 15:47:41.112294 | 
2025-12-05 15:47:41.112354 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-12-05 15:47:41.394365 | controller | ok
2025-12-05 15:47:41.400165 | 
2025-12-05 15:47:41.400246 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-12-05 15:47:42.492313 | controller | changed
2025-12-05 15:47:42.504494 | 
2025-12-05 15:47:42.504564 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-12-05 15:47:43.614078 | controller | changed
2025-12-05 15:47:43.618828 | 
2025-12-05 15:47:43.619648 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-12-05 15:47:43.643225 | controller | skipping: Conditional result was False
2025-12-05 15:47:43.649262 | 
2025-12-05 15:47:43.649335 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-12-05 15:47:43.990506 | controller -> localhost | changed
2025-12-05 15:47:44.011812 | 
2025-12-05 15:47:44.011951 | TASK [add-build-sshkey : Add back temp key]
2025-12-05 15:47:44.250637 | controller -> localhost | Identity added: /var/lib/zuul/builds/cc9e539dfe1c4b65918736d6186a0a1c/work/cc9e539dfe1c4b65918736d6186a0a1c_id_rsa (zuul-build-sshkey)
2025-12-05 15:47:44.250893 | controller -> localhost | ok: Runtime: 0:00:00.007344
2025-12-05 15:47:44.256338 | 
2025-12-05 15:47:44.256400 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-12-05 15:47:44.635077 | controller | ok
2025-12-05 15:47:44.639858 | 
2025-12-05 15:47:44.639949 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-12-05 15:47:44.682023 | controller | skipping: Conditional result was False
2025-12-05 15:47:44.691629 | 
2025-12-05 15:47:44.691721 | TASK [include_role : validate-host]
2025-12-05 15:47:44.709528 | controller | ok
2025-12-05 15:47:44.728100 | 
2025-12-05 15:47:44.728186 | TASK [validate-host : Define zuul_info_dir fact]
2025-12-05 15:47:44.768969 | controller | ok
2025-12-05 15:47:44.773862 | 
2025-12-05 15:47:44.773951 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-12-05 15:47:44.985116 | controller -> localhost | ok
2025-12-05 15:47:44.990892 | 
2025-12-05 15:47:44.990974 | TASK [validate-host : Collect information about the host]
2025-12-05 15:47:45.727309 | controller | ok
2025-12-05 15:47:45.736028 | 
2025-12-05 15:47:45.736112 | TASK [validate-host : Sanitize hostname]
2025-12-05 15:47:45.786331 | controller | ok
2025-12-05 15:47:45.793711 | 
2025-12-05 15:47:45.793795 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-12-05 15:47:46.359573 | controller -> localhost | changed
2025-12-05 15:47:46.365447 | 
2025-12-05 15:47:46.365522 | TASK [validate-host : Collect information about zuul worker]
2025-12-05 15:47:46.807453 | controller | ok
2025-12-05 15:47:46.816061 | 
2025-12-05 15:47:46.816135 | TASK [validate-host : Write out all zuul information for each host]
2025-12-05 15:47:47.309393 | controller -> localhost | changed
2025-12-05 15:47:47.319066 | 
2025-12-05 15:47:47.319132 | TASK [include_role : prepare-workspace-openshift]
2025-12-05 15:47:47.332403 | controller | skipping: Conditional result was False
2025-12-05 15:47:47.337008 | 
2025-12-05 15:47:47.337070 | TASK [include_role : remove-zuul-sshkey]
2025-12-05 15:47:47.349988 | controller | skipping: Conditional result was False
2025-12-05 15:47:47.354975 | 
2025-12-05 15:47:47.355040 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-12-05 15:47:47.641214 | controller | ok: "logs"
2025-12-05 15:47:47.641432 | controller | ok: All items complete
2025-12-05 15:47:47.641467 | 
2025-12-05 15:47:47.876858 | controller | ok: "artifacts"
2025-12-05 15:47:48.121470 | controller | ok: "docs"
2025-12-05 15:47:48.137989 | 
2025-12-05 15:47:48.138096 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-12-05 15:47:48.408492 | controller | changed: "logs"
2025-12-05 15:47:48.663070 | controller | changed: "artifacts"
2025-12-05 15:47:48.921122 | controller | changed: "docs"
2025-12-05 15:47:48.956569 | 
2025-12-05 15:47:48.956645 | PLAY RECAP
2025-12-05 15:47:48.956691 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-12-05 15:47:48.956720 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-12-05 15:47:48.956740 | 
2025-12-05 15:47:49.043965 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-12-05 15:47:49.044643 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-12-05 15:47:50.066593 | 
2025-12-05 15:47:50.066702 | PLAY [all]
2025-12-05 15:47:50.118471 | 
2025-12-05 15:47:50.118593 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-12-05 15:47:50.208748 | controller | ok
2025-12-05 15:47:50.214093 | 
2025-12-05 15:47:50.214202 | TASK [mirror-info-fork : Create /etc/ci]
2025-12-05 15:47:50.691153 | controller | changed
2025-12-05 15:47:50.696982 | 
2025-12-05 15:47:50.697064 | TASK [mirror-info-fork : Install ci_mirror script]
2025-12-05 15:47:52.043415 | controller | changed
2025-12-05 15:47:52.053498 | 
2025-12-05 15:47:52.053574 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-12-05 15:47:52.512400 | controller | changed:
2025-12-05 15:47:52.512594 | controller | {
2025-12-05 15:47:52.512630 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-12-05 15:47:52.512661 | controller | }
2025-12-05 15:47:52.806192 | controller | changed:
2025-12-05 15:47:52.806276 | controller | {
2025-12-05 15:47:52.806306 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-12-05 15:47:52.806329 | controller | }
2025-12-05 15:47:53.103059 | controller | changed:
2025-12-05 15:47:53.103145 | controller | {
2025-12-05 15:47:53.103175 | 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 15:47:53.103198 | controller | }
2025-12-05 15:47:53.398804 | controller | changed:
2025-12-05 15:47:53.399051 | controller | {
2025-12-05 15:47:53.399092 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-12-05 15:47:53.399117 | controller | }
2025-12-05 15:47:53.679450 | controller | changed:
2025-12-05 15:47:53.679536 | controller | {
2025-12-05 15:47:53.679564 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-12-05 15:47:53.679587 | controller | }
2025-12-05 15:47:53.972781 | controller | changed:
2025-12-05 15:47:53.972881 | controller | {
2025-12-05 15:47:53.972925 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-12-05 15:47:53.972956 | controller | }
2025-12-05 15:47:54.271190 | controller | changed:
2025-12-05 15:47:54.271273 | controller | {
2025-12-05 15:47:54.271302 | 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 15:47:54.271326 | controller | }
2025-12-05 15:47:54.582077 | controller | changed:
2025-12-05 15:47:54.582165 | controller | {
2025-12-05 15:47:54.582194 | 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 15:47:54.582217 | controller | }
2025-12-05 15:47:54.890435 | controller | changed:
2025-12-05 15:47:54.890537 | controller | {
2025-12-05 15:47:54.890565 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-12-05 15:47:54.890586 | controller | }
2025-12-05 15:47:55.219714 | controller | changed:
2025-12-05 15:47:55.219796 | controller | {
2025-12-05 15:47:55.219824 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-12-05 15:47:55.219844 | controller | }
2025-12-05 15:47:55.541467 | controller | changed:
2025-12-05 15:47:55.541556 | controller | {
2025-12-05 15:47:55.541584 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-12-05 15:47:55.541607 | controller | }
2025-12-05 15:47:55.854492 | controller | changed:
2025-12-05 15:47:55.854635 | controller | {
2025-12-05 15:47:55.854672 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-12-05 15:47:55.854695 | controller | }
2025-12-05 15:47:56.172545 | controller | changed:
2025-12-05 15:47:56.172637 | controller | {
2025-12-05 15:47:56.172665 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-12-05 15:47:56.172687 | controller | }
2025-12-05 15:47:56.481356 | controller | changed:
2025-12-05 15:47:56.481519 | controller | {
2025-12-05 15:47:56.481553 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-12-05 15:47:56.481586 | controller | }
2025-12-05 15:47:56.793514 | controller | changed:
2025-12-05 15:47:56.793609 | controller | {
2025-12-05 15:47:56.793636 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-12-05 15:47:56.793658 | controller | }
2025-12-05 15:47:57.140794 | controller | changed:
2025-12-05 15:47:57.140899 | controller | {
2025-12-05 15:47:57.140967 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-12-05 15:47:57.140991 | controller | }
2025-12-05 15:47:57.474455 | controller | changed:
2025-12-05 15:47:57.474548 | controller | {
2025-12-05 15:47:57.474577 | 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 15:47:57.474600 | controller | }
2025-12-05 15:47:57.804495 | controller | changed:
2025-12-05 15:47:57.804573 | controller | {
2025-12-05 15:47:57.804603 | 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 15:47:57.804633 | controller | }
2025-12-05 15:47:58.141883 | controller | changed:
2025-12-05 15:47:58.141973 | controller | {
2025-12-05 15:47:58.142003 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-12-05 15:47:58.142025 | controller | }
2025-12-05 15:47:58.472252 | controller | changed:
2025-12-05 15:47:58.472397 | controller | {
2025-12-05 15:47:58.472429 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-12-05 15:47:58.472452 | controller | }
2025-12-05 15:47:58.784952 | controller | changed:
2025-12-05 15:47:58.785042 | controller | {
2025-12-05 15:47:58.785070 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-12-05 15:47:58.785092 | controller | }
2025-12-05 15:47:59.108812 | controller | changed:
2025-12-05 15:47:59.108898 | controller | {
2025-12-05 15:47:59.108952 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-12-05 15:47:59.108977 | controller | }
2025-12-05 15:47:59.406790 | controller | changed:
2025-12-05 15:47:59.406892 | controller | {
2025-12-05 15:47:59.408569 | 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 15:47:59.408616 | controller | }
2025-12-05 15:47:59.705390 | controller | changed:
2025-12-05 15:47:59.705477 | controller | {
2025-12-05 15:47:59.705506 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-12-05 15:47:59.705526 | controller | }
2025-12-05 15:47:59.997466 | controller | changed:
2025-12-05 15:47:59.997546 | controller | {
2025-12-05 15:47:59.997576 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-12-05 15:47:59.997598 | controller | }
2025-12-05 15:48:00.297926 | controller | changed:
2025-12-05 15:48:00.298026 | controller | {
2025-12-05 15:48:00.298055 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-12-05 15:48:00.298078 | controller | }
2025-12-05 15:48:00.323481 | 
2025-12-05 15:48:00.323560 | TASK [Set timezone to UTC]
2025-12-05 15:48:01.043264 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-12-05 15:48:01.055195 | 
2025-12-05 15:48:01.055284 | TASK [Create nodepool directory]
2025-12-05 15:48:01.352362 | controller | changed
2025-12-05 15:48:01.361674 | 
2025-12-05 15:48:01.361758 | TASK [Create nodepool sub_nodes file]
2025-12-05 15:48:02.379293 | controller | changed
2025-12-05 15:48:02.384298 | 
2025-12-05 15:48:02.384372 | TASK [Create nodepool sub_nodes_private file]
2025-12-05 15:48:03.433097 | controller | changed
2025-12-05 15:48:03.439281 | 
2025-12-05 15:48:03.439349 | LOOP [Populate nodepool sub_nodes file]
2025-12-05 15:48:03.494986 | 
2025-12-05 15:48:03.495194 | LOOP [Populate nodepool sub_nodes_private file]
2025-12-05 15:48:03.583782 | 
2025-12-05 15:48:03.583976 | TASK [Create nodepool primary file]
2025-12-05 15:48:03.638469 | controller | skipping: Conditional result was False
2025-12-05 15:48:03.647488 | 
2025-12-05 15:48:03.647569 | TASK [Create nodepool node_private for this node]
2025-12-05 15:48:04.727852 | controller | changed
2025-12-05 15:48:04.733294 | 
2025-12-05 15:48:04.733357 | LOOP [Copy ssh keys to nodepool directory]
2025-12-05 15:48:05.270102 | controller | ok: Item: id_rsa Runtime: 0:00:00.004416
2025-12-05 15:48:05.270288 | 
2025-12-05 15:48:05.505364 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.005048
2025-12-05 15:48:05.521817 | 
2025-12-05 15:48:05.521973 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-12-05 15:48:06.580005 | controller | changed
2025-12-05 15:48:06.586382 | 
2025-12-05 15:48:06.586465 | TASK [Validate sudoers config after edits]
2025-12-05 15:48:06.915164 | controller | /etc/sudoers: parsed OK
2025-12-05 15:48:06.915223 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-12-05 15:48:06.915233 | controller | /etc/sudoers.d/zuul: parsed OK
2025-12-05 15:48:06.915239 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-12-05 15:48:07.141067 | controller | ok: Runtime: 0:00:00.005289
2025-12-05 15:48:07.146798 | 
2025-12-05 15:48:07.146866 | TASK [Show the environment passed in to job shell scripts]
2025-12-05 15:48:07.500889 | controller | SHELL=/bin/bash
2025-12-05 15:48:07.500945 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-12-05 15:48:07.500955 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-12-05 15:48:07.500962 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/39/3539/d69732b2a1625f2c30dfa731f6f764b0a55cbde9
2025-12-05 15:48:07.500968 | controller | PWD=/home/zuul
2025-12-05 15:48:07.501018 | controller | ZUUL_PIPELINE=github-check
2025-12-05 15:48:07.501027 | controller | LOGNAME=zuul
2025-12-05 15:48:07.501032 | controller | XDG_SESSION_TYPE=tty
2025-12-05 15:48:07.501038 | controller | _=/usr/bin/env
2025-12-05 15:48:07.501043 | controller | MOTD_SHOWN=pam
2025-12-05 15:48:07.501048 | controller | HOME=/home/zuul
2025-12-05 15:48:07.501053 | controller | LANG=en_US.UTF-8
2025-12-05 15:48:07.501059 | controller | SSH_CONNECTION=192.168.26.12 48946 192.168.26.197 22
2025-12-05 15:48:07.501064 | 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 15:48:07.501073 | controller | ZUUL_CHANGE_IDS=3539,d69732b2a1625f2c30dfa731f6f764b0a55cbde9
2025-12-05 15:48:07.501079 | controller | WORKSPACE=/home/zuul/workspace
2025-12-05 15:48:07.501084 | controller | XDG_SESSION_CLASS=user
2025-12-05 15:48:07.501090 | controller | SELINUX_ROLE_REQUESTED=
2025-12-05 15:48:07.501095 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-12-05 15:48:07.501100 | controller | USER=zuul
2025-12-05 15:48:07.501106 | controller | ZUUL_VOTING=True
2025-12-05 15:48:07.501111 | controller | BUILD_TIMEOUT=3600000
2025-12-05 15:48:07.501116 | controller | SELINUX_USE_CURRENT_RANGE=
2025-12-05 15:48:07.501122 | controller | SHLVL=1
2025-12-05 15:48:07.501127 | controller | ZUUL_PATCHSET=d69732b2a1625f2c30dfa731f6f764b0a55cbde9
2025-12-05 15:48:07.501132 | controller | XDG_SESSION_ID=1
2025-12-05 15:48:07.501138 | controller | ZUUL_BRANCH=main
2025-12-05 15:48:07.501143 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-12-05 15:48:07.501148 | controller | SSH_CLIENT=192.168.26.12 48946 22
2025-12-05 15:48:07.501154 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-12-05 15:48:07.501159 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-12-05 15:48:07.501166 | controller | which_declare=declare -f
2025-12-05 15:48:07.501172 | controller | PATH=/home/zuul/.crc/bin/oc:/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2025-12-05 15:48:07.501177 | controller | SELINUX_LEVEL_REQUESTED=
2025-12-05 15:48:07.501182 | controller | ZUUL_CHANGE=3539
2025-12-05 15:48:07.501188 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-12-05 15:48:07.501193 | controller | ZUUL_UUID=cc9e539dfe1c4b65918736d6186a0a1c
2025-12-05 15:48:07.501198 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-12-05 15:48:07.501204 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-12-05 15:48:07.501209 | controller | }
2025-12-05 15:48:07.707499 | controller | ok: Runtime: 0:00:00.006166
2025-12-05 15:48:07.713251 | 
2025-12-05 15:48:07.713318 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-12-05 15:48:07.764680 | controller | skipping: Conditional result was False
2025-12-05 15:48:07.770811 | 
2025-12-05 15:48:07.770899 | TASK [Symlink /home/zuul-worker/workspace]
2025-12-05 15:48:08.406461 | controller | skipping: Conditional result was False
2025-12-05 15:48:08.413164 | 
2025-12-05 15:48:08.413250 | TASK [Ensure legacy workspace directory]
2025-12-05 15:48:08.712512 | controller | changed
2025-12-05 15:48:08.748717 | 
2025-12-05 15:48:08.748824 | PLAY RECAP
2025-12-05 15:48:08.748872 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-12-05 15:48:08.748931 | 
2025-12-05 15:48:09.004734 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-12-05 15:48:09.005495 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-12-05 15:48:10.311788 | 
2025-12-05 15:48:10.311931 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-12-05 15:48:10.363008 | 
2025-12-05 15:48:10.363105 | TASK [Create zuul-output directory]
2025-12-05 15:48:10.801969 | controller | changed
2025-12-05 15:48:10.811343 | 
2025-12-05 15:48:10.811445 | TASK [Slurp Zuul inventory test]
2025-12-05 15:48:11.559509 | controller -> localhost | ok
2025-12-05 15:48:11.565857 | 
2025-12-05 15:48:11.565973 | TASK [Save zuul inventory]
2025-12-05 15:48:13.155846 | controller | changed
2025-12-05 15:48:13.160774 | 
2025-12-05 15:48:13.160839 | TASK [Save zuul vars without the change_message]
2025-12-05 15:48:14.395690 | controller | changed
2025-12-05 15:48:14.433674 | 
2025-12-05 15:48:14.433773 | PLAY RECAP
2025-12-05 15:48:14.433827 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-12-05 15:48:14.433852 | 
2025-12-05 15:48:14.550020 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-12-05 15:48:14.550716 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-12-05 15:48:15.510431 | 
2025-12-05 15:48:15.510547 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2025-12-05 15:48:15.536823 | 
2025-12-05 15:48:15.538813 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2025-12-05 15:48:15.568817 | controller | ok
2025-12-05 15:48:15.594611 | 
2025-12-05 15:48:15.594694 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-12-05 15:48:15.655110 | controller | skipping: Conditional result was False
2025-12-05 15:48:15.660747 | 
2025-12-05 15:48:15.660816 | TASK [mirror-info-fork : Create /etc/ci]
2025-12-05 15:48:16.199306 | controller | ok
2025-12-05 15:48:16.205218 | 
2025-12-05 15:48:16.205288 | TASK [mirror-info-fork : Install ci_mirror script]
2025-12-05 15:48:17.398192 | controller | ok
2025-12-05 15:48:17.426143 | 
2025-12-05 15:48:17.426237 | TASK [Prepare workspace]
2025-12-05 15:48:17.487858 | controller | ok
2025-12-05 15:48:17.536488 | 
2025-12-05 15:48:17.539045 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-12-05 15:48:18.048028 | controller | ok
2025-12-05 15:48:18.055181 | 
2025-12-05 15:48:18.055266 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-12-05 15:48:22.476226 | controller | Output suppressed because no_log was given
2025-12-05 15:48:22.498757 | 
2025-12-05 15:48:22.498847 | LOOP [Create zuul-output directory]
2025-12-05 15:48:22.816972 | controller | changed: "/home/zuul/ci-framework-data/logs"
2025-12-05 15:48:23.055727 | controller | ok: "/home/zuul/zuul-output/logs"
2025-12-05 15:48:23.072495 | 
2025-12-05 15:48:23.072612 | TASK [Install required packages]
2025-12-05 15:48:42.569859 | controller | ok: Nothing to do
2025-12-05 15:48:42.575056 | 
2025-12-05 15:48:42.575117 | TASK [Install venv]
2025-12-05 15:49:10.043893 | controller | ERROR
2025-12-05 15:49:10.044109 | controller | {
2025-12-05 15:49:10.044145 | controller |   "msg": "gmake: *** [Makefile:60: setup_molecule] Error 1",
2025-12-05 15:49:10.044169 | controller |   "rc": 2
2025-12-05 15:49:10.044189 | controller | }
failure
2025-12-05 15:49:10.059337 | 
2025-12-05 15:49:10.059391 | PLAY RECAP
2025-12-05 15:49:10.059437 | controller | ok: 6 changed: 2 unreachable: 0 failed: 1 skipped: 1 rescued: 0 ignored: 0
2025-12-05 15:49:10.059460 | 
2025-12-05 15:49:10.231806 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-12-05 15:49:10.233220 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-12-05 15:49:11.012197 | 
2025-12-05 15:49:11.012315 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-12-05 15:49:11.074425 | 
2025-12-05 15:49:11.074548 | TASK [Filter out host if needed]
2025-12-05 15:49:11.097984 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-12-05 15:49:11.103211 | 
2025-12-05 15:49:11.103301 | TASK [Ensure file is present]
2025-12-05 15:49:11.544164 | controller | ok
2025-12-05 15:49:11.552842 | 
2025-12-05 15:49:11.552951 | TASK [Manage molecule report file]
2025-12-05 15:49:12.177885 | controller | skipping: Conditional result was False
2025-12-05 15:49:12.183749 | 
2025-12-05 15:49:12.183836 | TASK [Check if we get ci-framework-data basedir]
2025-12-05 15:49:12.519207 | controller | ok
2025-12-05 15:49:12.533855 | 
2025-12-05 15:49:12.533960 | TASK [Create ci-framework-data log directory for zuul]
2025-12-05 15:49:13.131752 | controller | changed
2025-12-05 15:49:13.136490 | 
2025-12-05 15:49:13.136557 | TASK [Copy ci-framework interesting files]
2025-12-05 15:49:13.638997 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2025-12-05 15:49:13.698957 | controller | changed
2025-12-05 15:49:13.705276 | 
2025-12-05 15:49:13.705372 | TASK [Get SELinux listing]
2025-12-05 15:49:14.248083 | controller | changed
2025-12-05 15:49:14.253967 | 
2025-12-05 15:49:14.254048 | TASK [Generate log index]
2025-12-05 15:49:15.640757 | controller | changed
2025-12-05 15:49:15.652245 | 
2025-12-05 15:49:15.652307 | TASK [Get some env related data]
2025-12-05 15:49:16.369472 | controller | /home/zuul/.local/bin/ansible
2025-12-05 15:49:17.222370 | controller | changed
2025-12-05 15:49:17.285100 | 
2025-12-05 15:49:17.285186 | TASK [Generate list of logs to collect in home directory]
2025-12-05 15:49:17.807671 | controller | ok: All paths examined
2025-12-05 15:49:17.812958 | 
2025-12-05 15:49:17.813029 | LOOP [Copy logs from home directory]
2025-12-05 15:49:18.341605 | controller | changed:
2025-12-05 15:49:18.341792 | controller | {
2025-12-05 15:49:18.341822 | controller |   "atime": 1743544925.4788878,
2025-12-05 15:49:18.341843 | controller |   "ctime": 1743545329.1409318,
2025-12-05 15:49:18.341861 | controller |   "dev": 64513,
2025-12-05 15:49:18.341973 | controller |   "gid": 1000,
2025-12-05 15:49:18.342009 | controller |   "gr_name": "zuul",
2025-12-05 15:49:18.342031 | controller |   "inode": 4518807,
2025-12-05 15:49:18.342050 | controller |   "isblk": false,
2025-12-05 15:49:18.342067 | controller |   "ischr": false,
2025-12-05 15:49:18.342083 | controller |   "isdir": false,
2025-12-05 15:49:18.342098 | controller |   "isfifo": false,
2025-12-05 15:49:18.342114 | controller |   "isgid": false,
2025-12-05 15:49:18.342130 | controller |   "islnk": false,
2025-12-05 15:49:18.342147 | controller |   "isreg": true,
2025-12-05 15:49:18.342162 | controller |   "issock": false,
2025-12-05 15:49:18.342177 | controller |   "isuid": false,
2025-12-05 15:49:18.342199 | controller |   "mode": "0644",
2025-12-05 15:49:18.342216 | controller |   "mtime": 1743545329.1409318,
2025-12-05 15:49:18.342232 | controller |   "nlink": 1,
2025-12-05 15:49:18.342247 | controller |   "path": "/home/zuul/crc-setup.log",
2025-12-05 15:49:18.342268 | controller |   "pw_name": "zuul",
2025-12-05 15:49:18.342286 | controller |   "rgrp": true,
2025-12-05 15:49:18.342302 | controller |   "roth": true,
2025-12-05 15:49:18.342317 | controller |   "rusr": true,
2025-12-05 15:49:18.342332 | controller |   "size": 4108,
2025-12-05 15:49:18.342346 | controller |   "uid": 1000,
2025-12-05 15:49:18.342361 | controller |   "wgrp": false,
2025-12-05 15:49:18.342375 | controller |   "woth": false,
2025-12-05 15:49:18.342392 | controller |   "wusr": true,
2025-12-05 15:49:18.342408 | controller |   "xgrp": false,
2025-12-05 15:49:18.342423 | controller |   "xoth": false,
2025-12-05 15:49:18.342437 | controller |   "xusr": false
2025-12-05 15:49:18.342451 | controller | }
2025-12-05 15:49:18.846995 | controller | changed:
2025-12-05 15:49:18.847082 | controller | {
2025-12-05 15:49:18.847110 | controller |   "atime": 1743545331.0429637,
2025-12-05 15:49:18.847131 | controller |   "ctime": 1743545842.0111232,
2025-12-05 15:49:18.847150 | controller |   "dev": 64513,
2025-12-05 15:49:18.847168 | controller |   "gid": 1000,
2025-12-05 15:49:18.847185 | controller |   "gr_name": "zuul",
2025-12-05 15:49:18.847202 | controller |   "inode": 4194437,
2025-12-05 15:49:18.847218 | controller |   "isblk": false,
2025-12-05 15:49:18.847234 | controller |   "ischr": false,
2025-12-05 15:49:18.847250 | controller |   "isdir": false,
2025-12-05 15:49:18.847265 | controller |   "isfifo": false,
2025-12-05 15:49:18.847280 | controller |   "isgid": false,
2025-12-05 15:49:18.847296 | controller |   "islnk": false,
2025-12-05 15:49:18.847311 | controller |   "isreg": true,
2025-12-05 15:49:18.847326 | controller |   "issock": false,
2025-12-05 15:49:18.847341 | controller |   "isuid": false,
2025-12-05 15:49:18.847356 | controller |   "mode": "0644",
2025-12-05 15:49:18.847371 | controller |   "mtime": 1743545842.0111232,
2025-12-05 15:49:18.847386 | controller |   "nlink": 1,
2025-12-05 15:49:18.847402 | controller |   "path": "/home/zuul/crc-start.log",
2025-12-05 15:49:18.847418 | controller |   "pw_name": "zuul",
2025-12-05 15:49:18.847434 | controller |   "rgrp": true,
2025-12-05 15:49:18.847449 | controller |   "roth": true,
2025-12-05 15:49:18.847465 | controller |   "rusr": true,
2025-12-05 15:49:18.847481 | controller |   "size": 4023,
2025-12-05 15:49:18.847496 | controller |   "uid": 1000,
2025-12-05 15:49:18.847512 | controller |   "wgrp": false,
2025-12-05 15:49:18.847527 | controller |   "woth": false,
2025-12-05 15:49:18.847543 | controller |   "wusr": true,
2025-12-05 15:49:18.847559 | controller |   "xgrp": false,
2025-12-05 15:49:18.847574 | controller |   "xoth": false,
2025-12-05 15:49:18.847589 | controller |   "xusr": false
2025-12-05 15:49:18.847605 | controller | }
2025-12-05 15:49:18.861841 | 
2025-12-05 15:49:18.861934 | TASK [Copy crio stats log file]
2025-12-05 15:49:18.920015 | controller | skipping: Conditional result was False
2025-12-05 15:49:18.925605 | 
2025-12-05 15:49:18.925668 | TASK [Get SELinux related data]
2025-12-05 15:49:19.284407 | controller | <no matches>
2025-12-05 15:49:19.478869 | controller | ERROR
2025-12-05 15:49:19.479073 | controller | {
2025-12-05 15:49:19.479107 | controller |   "delta": "0:00:00.009538",
2025-12-05 15:49:19.479130 | controller |   "end": "2025-12-05 15:49:19.285589",
2025-12-05 15:49:19.479150 | controller |   "msg": "non-zero return code",
2025-12-05 15:49:19.479168 | controller |   "rc": 1,
2025-12-05 15:49:19.479186 | controller |   "start": "2025-12-05 15:49:19.276051"
2025-12-05 15:49:19.479203 | controller | }
2025-12-05 15:49:19.479228 | controller | ERROR: Ignoring Errors
2025-12-05 15:49:19.484483 | 
2025-12-05 15:49:19.484542 | TASK [Create system configuration directory]
2025-12-05 15:49:19.788579 | controller | changed
2025-12-05 15:49:19.794829 | 
2025-12-05 15:49:19.794931 | TASK [Get some of the system configurations]
2025-12-05 15:49:20.353399 | controller | changed
2025-12-05 15:49:20.358776 | 
2025-12-05 15:49:20.358839 | TASK [Copy generated documentation if available]
2025-12-05 15:49:20.392549 | controller | skipping: Conditional result was False
2025-12-05 15:49:20.398371 | 
2025-12-05 15:49:20.398436 | TASK [Copy generated AsciiDoc documentation if available]
2025-12-05 15:49:20.432077 | controller | skipping: Conditional result was False
2025-12-05 15:49:20.438519 | 
2025-12-05 15:49:20.438585 | TASK [Compress logs bigger than 2MB]
2025-12-05 15:49:21.020726 | controller | changed
2025-12-05 15:49:21.026188 | 
2025-12-05 15:49:21.026252 | TASK [Copy files from workspace on node]
2025-12-05 15:49:21.044072 | controller | ok
2025-12-05 15:49:21.074635 | 
2025-12-05 15:49:21.074744 | TASK [fetch-output : Set log path for multiple nodes]
2025-12-05 15:49:21.098321 | controller | skipping: Conditional result was False
2025-12-05 15:49:21.103903 | 
2025-12-05 15:49:21.104002 | TASK [fetch-output : Set log path for single node]
2025-12-05 15:49:21.133457 | controller | ok
2025-12-05 15:49:21.140734 | 
2025-12-05 15:49:21.140804 | LOOP [fetch-output : Ensure local output dirs]
2025-12-05 15:49:21.369696 | controller -> localhost | ok: "/var/lib/zuul/builds/cc9e539dfe1c4b65918736d6186a0a1c/work/logs"
2025-12-05 15:49:21.369950 | controller -> localhost | changed: All items complete
2025-12-05 15:49:21.369986 | 
2025-12-05 15:49:21.551330 | controller -> localhost | changed: "/var/lib/zuul/builds/cc9e539dfe1c4b65918736d6186a0a1c/work/artifacts"
2025-12-05 15:49:21.818222 | controller -> localhost | changed: "/var/lib/zuul/builds/cc9e539dfe1c4b65918736d6186a0a1c/work/docs"
2025-12-05 15:49:21.832928 | 
2025-12-05 15:49:21.833033 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-12-05 15:49:23.031230 | controller | changed:
2025-12-05 15:49:23.031401 | controller | .d..t...... ./
2025-12-05 15:49:23.031434 | controller | >f+++++++++ README.html
2025-12-05 15:49:23.031458 | controller | >f+++++++++ crc-setup.log
2025-12-05 15:49:23.031480 | controller | >f+++++++++ crc-start.log
2025-12-05 15:49:23.031501 | controller | >f+++++++++ dmesg.log
2025-12-05 15:49:23.031521 | controller | >f+++++++++ installed-pkgs.log
2025-12-05 15:49:23.031540 | controller | >f+++++++++ python.log
2025-12-05 15:49:23.031559 | controller | >f+++++++++ registries.conf
2025-12-05 15:49:23.031577 | controller | >f+++++++++ selinux-denials.log
2025-12-05 15:49:23.031595 | controller | >f+++++++++ selinux-listing.log
2025-12-05 15:49:23.031612 | controller | cd+++++++++ ci-framework-data/
2025-12-05 15:49:23.031630 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-12-05 15:49:23.031647 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-12-05 15:49:23.031665 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-12-05 15:49:23.031682 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-12-05 15:49:23.031700 | controller | cd+++++++++ ci-framework-data/logs/
2025-12-05 15:49:23.031717 | controller | cd+++++++++ registries.conf.d/
2025-12-05 15:49:23.031734 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-12-05 15:49:23.031751 | controller | cd+++++++++ system-config/
2025-12-05 15:49:23.031768 | controller | cd+++++++++ system-config/libvirt/
2025-12-05 15:49:23.031785 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-12-05 15:49:23.031802 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-12-05 15:49:23.031818 | controller | >f+++++++++ system-config/libvirt/libvirtd.conf
2025-12-05 15:49:23.031835 | controller | >f+++++++++ system-config/libvirt/network.conf
2025-12-05 15:49:23.031852 | controller | >f+++++++++ system-config/libvirt/qemu-lockd.conf
2025-12-05 15:49:23.031869 | controller | >f+++++++++ system-config/libvirt/qemu.conf
2025-12-05 15:49:23.031886 | controller | >f+++++++++ system-config/libvirt/virtinterfaced.conf
2025-12-05 15:49:23.031942 | controller | >f+++++++++ system-config/libvirt/virtlockd.conf
2025-12-05 15:49:23.031975 | controller | >f+++++++++ system-config/libvirt/virtlogd.conf
2025-12-05 15:49:23.031997 | controller | >f+++++++++ system-config/libvirt/virtnetworkd.conf
2025-12-05 15:49:23.032028 | controller | >f+++++++++ system-config/libvirt/virtnodedevd.conf
2025-12-05 15:49:23.032048 | controller | >f+++++++++ system-config/libvirt/virtnwfilterd.conf
2025-12-05 15:49:23.032067 | controller | >f+++++++++ system-config/libvirt/virtproxyd.conf
2025-12-05 15:49:23.032084 | controller | >f+++++++++ system-config/libvirt/virtqemud.conf
2025-12-05 15:49:23.032103 | controller | >f+++++++++ system-config/libvirt/virtsecretd.conf
2025-12-05 15:49:23.032121 | controller | >f+++++++++ system-config/libvirt/virtstoraged.conf
2025-12-05 15:49:23.624175 | controller | changed: .d..t...... ./
2025-12-05 15:49:24.404240 | controller | changed: .d..t...... ./
2025-12-05 15:49:24.419113 | 
2025-12-05 15:49:24.419201 | TASK [Return artifact to Zuul]
2025-12-05 15:49:24.463650 | controller | skipping: Conditional result was False
2025-12-05 15:49:24.521372 | 
2025-12-05 15:49:24.521443 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-12-05 15:49:24.521548 | 
2025-12-05 15:49:24.521577 | PLAY RECAP
2025-12-05 15:49:24.521617 | controller | ok: 16 changed: 12 unreachable: 0 failed: 0 skipped: 6 rescued: 0 ignored: 1
2025-12-05 15:49:24.521639 | 
2025-12-05 15:49:24.670324 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-12-05 15:49:24.671080 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-12-05 15:49:25.307494 | 
2025-12-05 15:49:25.307597 | PLAY [all]
2025-12-05 15:49:25.326642 | 
2025-12-05 15:49:25.326716 | TASK [include_role : fetch-output]
2025-12-05 15:49:25.376716 | controller | ok
2025-12-05 15:49:25.393070 | 
2025-12-05 15:49:25.393162 | TASK [fetch-output : Set log path for multiple nodes]
2025-12-05 15:49:25.436801 | controller | skipping: Conditional result was False
2025-12-05 15:49:25.449827 | 
2025-12-05 15:49:25.449898 | TASK [fetch-output : Set log path for single node]
2025-12-05 15:49:25.479415 | controller | ok
2025-12-05 15:49:25.484515 | 
2025-12-05 15:49:25.484582 | LOOP [fetch-output : Ensure local output dirs]
2025-12-05 15:49:26.060426 | controller -> localhost | ok: "/var/lib/zuul/builds/cc9e539dfe1c4b65918736d6186a0a1c/work/logs"
2025-12-05 15:49:26.504238 | controller -> localhost | ok: "/var/lib/zuul/builds/cc9e539dfe1c4b65918736d6186a0a1c/work/artifacts"
2025-12-05 15:49:27.145193 | controller -> localhost | ok: "/var/lib/zuul/builds/cc9e539dfe1c4b65918736d6186a0a1c/work/docs"
2025-12-05 15:49:27.158949 | 
2025-12-05 15:49:27.159041 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-12-05 15:49:28.297707 | controller | ok
2025-12-05 15:49:28.297889 | controller | ok: All items complete
2025-12-05 15:49:28.297940 | 
2025-12-05 15:49:29.092443 | controller | ok
2025-12-05 15:49:29.673707 | controller | ok
2025-12-05 15:49:29.686645 | 
2025-12-05 15:49:29.686757 | TASK [include_role : fetch-output-openshift]
2025-12-05 15:49:29.702394 | controller | skipping: Conditional result was False
2025-12-05 15:49:29.710266 | 
2025-12-05 15:49:29.710333 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-12-05 15:49:30.324142 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.017823
2025-12-05 15:49:30.842523 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.041429
2025-12-05 15:49:30.885248 | 
2025-12-05 15:49:30.885323 | PLAY [all]
2025-12-05 15:49:30.918993 | 
2025-12-05 15:49:30.919089 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-12-05 15:49:31.454250 | controller | changed
2025-12-05 15:49:31.521875 | 
2025-12-05 15:49:31.521971 | PLAY RECAP
2025-12-05 15:49:31.522019 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-12-05 15:49:31.522061 | 
2025-12-05 15:49:31.594932 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-12-05 15:49:31.595672 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-12-05 15:49:32.357010 | 
2025-12-05 15:49:32.357120 | PLAY [localhost]
2025-12-05 15:49:32.380711 | 
2025-12-05 15:49:32.380786 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-12-05 15:49:32.701970 | localhost | changed
2025-12-05 15:49:32.706496 | 
2025-12-05 15:49:32.706590 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-12-05 15:49:32.730262 | localhost | ok
2025-12-05 15:49:32.738166 | 
2025-12-05 15:49:32.738244 | TASK [add-fileserver : Create SSH private key tempfile]
2025-12-05 15:49:33.161160 | localhost | changed
2025-12-05 15:49:33.166706 | 
2025-12-05 15:49:33.166775 | TASK [add-fileserver : Create SSH private key from secret]
2025-12-05 15:49:33.965798 | localhost | changed
2025-12-05 15:49:33.970779 | 
2025-12-05 15:49:33.970845 | TASK [add-fileserver : Add fileserver ssh key]
2025-12-05 15:49:34.398725 | localhost | Identity added: /var/lib/zuul/builds/cc9e539dfe1c4b65918736d6186a0a1c/work/tmp/ansible.lloizbqa (/var/lib/zuul/builds/cc9e539dfe1c4b65918736d6186a0a1c/work/tmp/ansible.lloizbqa)
2025-12-05 15:49:34.398933 | localhost | ok: Runtime: 0:00:00.008960
2025-12-05 15:49:34.403243 | 
2025-12-05 15:49:34.403309 | TASK [add-fileserver : Remove SSH private key from disk]
2025-12-05 15:49:34.645210 | localhost | ok: Runtime: 0:00:00.004455
2025-12-05 15:49:34.653445 | 
2025-12-05 15:49:34.653517 | TASK [add-fileserver : Add fileserver to inventory]
2025-12-05 15:49:34.723711 | localhost | changed
2025-12-05 15:49:34.729898 | 
2025-12-05 15:49:34.729986 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-12-05 15:49:35.187492 | localhost | changed
2025-12-05 15:49:35.209962 | 
2025-12-05 15:49:35.210041 | PLAY [localhost]
2025-12-05 15:49:35.222106 | 
2025-12-05 15:49:35.222180 | TASK [Generate bulk log download script]
2025-12-05 15:49:35.240084 | localhost | ok
2025-12-05 15:49:35.251943 | 
2025-12-05 15:49:35.252038 | TASK [local-log-download : Check API endpoint is defined]
2025-12-05 15:49:35.274229 | localhost | ok: All assertions passed
2025-12-05 15:49:35.283404 | 
2025-12-05 15:49:35.283496 | TASK [local-log-download : Create download script]
2025-12-05 15:49:35.709683 | localhost -> localhost | changed
2025-12-05 15:49:35.718051 | 
2025-12-05 15:49:35.718115 | TASK [Register quick-download link]
2025-12-05 15:49:35.752001 | localhost | ok
2025-12-05 15:49:35.817849 | 
2025-12-05 15:49:35.817940 | PLAY [logserver.rdoproject.org]
2025-12-05 15:49:35.832549 | 
2025-12-05 15:49:35.832611 | TASK [Set zuul-log-path fact]
2025-12-05 15:49:35.875017 | logserver.rdoproject.org | ok
2025-12-05 15:49:35.889000 | 
2025-12-05 15:49:35.889094 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-12-05 15:49:35.916737 | logserver.rdoproject.org | ok
2025-12-05 15:49:35.928494 | 
2025-12-05 15:49:35.928565 | TASK [upload-logs : Create log directories]
2025-12-05 15:49:37.616782 | logserver.rdoproject.org | changed
2025-12-05 15:49:37.620726 | 
2025-12-05 15:49:37.620790 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-12-05 15:49:37.859696 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.007780
2025-12-05 15:49:37.864592 | 
2025-12-05 15:49:37.864673 | TASK [upload-logs : Upload logs to log server]
2025-12-05 15:49:39.476360 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-12-05 15:49:39.479304 | 
2025-12-05 15:49:39.479371 | LOOP [upload-logs : Compress console log and json output]
2025-12-05 15:49:39.550978 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-05 15:49:39.551755 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-05 15:49:39.561305 | 
2025-12-05 15:49:39.561401 | LOOP [upload-logs : Upload compressed console log and json output]
2025-12-05 15:49:39.635486 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-05 15:49:39.635834 | 
2025-12-05 15:49:39.636647 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-05 15:49:39.649708 | 
2025-12-05 15:49:39.649822 | LOOP [upload-logs : Upload console log and json output]