2025-12-05 23:36:22.344004 | Job console starting...
2025-12-05 23:36:22.552905 | Updating repositories
2025-12-05 23:36:23.355799 | Preparing job workspace
2025-12-05 23:36:31.480807 | Running Ansible setup...
2025-12-05 23:36:36.210704 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-12-05 23:36:37.055303 | 
2025-12-05 23:36:37.055385 | PLAY [localhost]
2025-12-05 23:36:37.071053 | 
2025-12-05 23:36:37.071117 | TASK [Gathering Facts]
2025-12-05 23:36:38.084006 | localhost | ok
2025-12-05 23:36:38.127468 | 
2025-12-05 23:36:38.127557 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-12-05 23:36:39.026959 | localhost -> localhost | changed
2025-12-05 23:36:39.032191 | 
2025-12-05 23:36:39.032263 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-12-05 23:36:41.768542 | localhost -> localhost | changed
2025-12-05 23:36:41.779088 | 
2025-12-05 23:36:41.779160 | TASK [Setup log path fact]
2025-12-05 23:36:41.816888 | localhost | ok
2025-12-05 23:36:41.831342 | 
2025-12-05 23:36:41.831412 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-12-05 23:36:41.858068 | localhost | ok
2025-12-05 23:36:41.891455 | 
2025-12-05 23:36:41.891533 | TASK [emit-job-header : Print job information]
2025-12-05 23:36:41.934811 | # Job Information
2025-12-05 23:36:41.934950 | Ansible Version: 2.15.12
2025-12-05 23:36:41.934984 | Job: cifmw-molecule-cert_manager
2025-12-05 23:36:41.935009 | Pipeline: github-check
2025-12-05 23:36:41.935030 | Executor: ibm-bm4-ze.softwarefactory-project.io
2025-12-05 23:36:41.935050 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3539
2025-12-05 23:36:41.935070 | Log URL (when completed): https://logserver.rdoproject.org/337/rdoproject.org/3379f393edb34e4f93a06fb27f2cfca3/
2025-12-05 23:36:41.935091 | Event ID: 0eecf260-d233-11f0-9ab7-da45df476e85
2025-12-05 23:36:41.938585 | 
2025-12-05 23:36:41.938648 | LOOP [emit-job-header : Print node information]
2025-12-05 23:36:42.161634 | localhost | ok:
2025-12-05 23:36:42.163010 | localhost | # Node Information
2025-12-05 23:36:42.163085 | localhost | Inventory Hostname: controller
2025-12-05 23:36:42.163114 | localhost | Hostname: np0005548181
2025-12-05 23:36:42.163137 | localhost | Username: zuul
2025-12-05 23:36:42.163159 | localhost | Distro: CentOS 9
2025-12-05 23:36:42.163178 | localhost | Provider: ibm-bm4-nodepool
2025-12-05 23:36:42.163196 | localhost | Region: regionOne
2025-12-05 23:36:42.163214 | localhost | Label: centos-9-stream-crc-2-48-0-xxl-ibm
2025-12-05 23:36:42.163237 | localhost | Product Name: OpenStack Compute
2025-12-05 23:36:42.163256 | localhost | Interface IP: 192.168.26.190
2025-12-05 23:36:42.219854 | 
2025-12-05 23:36:42.219957 | PLAY [all]
2025-12-05 23:36:42.236973 | 
2025-12-05 23:36:42.237996 | TASK [Gather network facts]
2025-12-05 23:36:42.740283 | controller | ok
2025-12-05 23:36:42.847278 | 
2025-12-05 23:36:42.847370 | TASK [include_role : start-zuul-console]
2025-12-05 23:36:42.886815 | controller | ok
2025-12-05 23:36:42.961361 | 
2025-12-05 23:36:42.961453 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-12-05 23:36:43.585761 | controller | ok
2025-12-05 23:36:43.609455 | 
2025-12-05 23:36:43.609533 | TASK [include_role : add-build-sshkey]
2025-12-05 23:36:43.670374 | controller | ok
2025-12-05 23:36:43.696631 | 
2025-12-05 23:36:43.696702 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-12-05 23:36:44.156070 | controller -> localhost | ok
2025-12-05 23:36:44.164345 | 
2025-12-05 23:36:44.164767 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-12-05 23:36:44.231805 | controller | ok
2025-12-05 23:36:44.265733 | controller | included: /var/lib/zuul/builds/3379f393edb34e4f93a06fb27f2cfca3/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-12-05 23:36:44.284487 | 
2025-12-05 23:36:44.284558 | TASK [add-build-sshkey : Create Temp SSH key]
2025-12-05 23:36:45.707490 | controller -> localhost | Generating public/private rsa key pair.
2025-12-05 23:36:45.707727 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/3379f393edb34e4f93a06fb27f2cfca3/work/3379f393edb34e4f93a06fb27f2cfca3_id_rsa.
2025-12-05 23:36:45.707790 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/3379f393edb34e4f93a06fb27f2cfca3/work/3379f393edb34e4f93a06fb27f2cfca3_id_rsa.pub.
2025-12-05 23:36:45.707819 | controller -> localhost | The key fingerprint is:
2025-12-05 23:36:45.707859 | controller -> localhost | SHA256:MhqKISCuZZR0wkW60A1tGPNiI9Orkjl5VU5URmYE9Ao zuul-build-sshkey
2025-12-05 23:36:45.707885 | controller -> localhost | The key's randomart image is:
2025-12-05 23:36:45.707905 | controller -> localhost | +---[RSA 3072]----+
2025-12-05 23:36:45.707978 | controller -> localhost | | .**+.+=B        |
2025-12-05 23:36:45.708002 | controller -> localhost | | +oXo. =         |
2025-12-05 23:36:45.708041 | controller -> localhost | |* X.E o .        |
2025-12-05 23:36:45.708065 | controller -> localhost | |+* = = .         |
2025-12-05 23:36:45.708084 | controller -> localhost | |o.= o = S        |
2025-12-05 23:36:45.708102 | controller -> localhost | |oX o o o         |
2025-12-05 23:36:45.708119 | controller -> localhost | |X o .            |
2025-12-05 23:36:45.708138 | controller -> localhost | |.o               |
2025-12-05 23:36:45.708157 | controller -> localhost | |                 |
2025-12-05 23:36:45.708197 | controller -> localhost | +----[SHA256]-----+
2025-12-05 23:36:45.708242 | controller -> localhost | ok: Runtime: 0:00:00.935293
2025-12-05 23:36:45.713382 | 
2025-12-05 23:36:45.713468 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-12-05 23:36:45.761570 | controller | ok
2025-12-05 23:36:45.780856 | controller | included: /var/lib/zuul/builds/3379f393edb34e4f93a06fb27f2cfca3/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-12-05 23:36:45.806698 | 
2025-12-05 23:36:45.806762 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-12-05 23:36:45.871740 | controller | skipping: Conditional result was False
2025-12-05 23:36:45.878060 | 
2025-12-05 23:36:45.878130 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-12-05 23:36:46.641172 | controller | changed
2025-12-05 23:36:46.646316 | 
2025-12-05 23:36:46.646392 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-12-05 23:36:46.973392 | controller | ok
2025-12-05 23:36:46.978465 | 
2025-12-05 23:36:46.978532 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-12-05 23:36:48.344148 | controller | changed
2025-12-05 23:36:48.368500 | 
2025-12-05 23:36:48.368585 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-12-05 23:36:49.762322 | controller | changed
2025-12-05 23:36:49.772061 | 
2025-12-05 23:36:49.772131 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-12-05 23:36:49.844148 | controller | skipping: Conditional result was False
2025-12-05 23:36:49.849606 | 
2025-12-05 23:36:49.849672 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-12-05 23:36:50.926061 | controller -> localhost | changed
2025-12-05 23:36:50.934952 | 
2025-12-05 23:36:50.935021 | TASK [add-build-sshkey : Add back temp key]
2025-12-05 23:36:51.614275 | controller -> localhost | Identity added: /var/lib/zuul/builds/3379f393edb34e4f93a06fb27f2cfca3/work/3379f393edb34e4f93a06fb27f2cfca3_id_rsa (zuul-build-sshkey)
2025-12-05 23:36:51.614444 | controller -> localhost | ok: Runtime: 0:00:00.022471
2025-12-05 23:36:51.619927 | 
2025-12-05 23:36:51.619997 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-12-05 23:36:52.175672 | controller | ok
2025-12-05 23:36:52.186028 | 
2025-12-05 23:36:52.186099 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-12-05 23:36:52.245520 | controller | skipping: Conditional result was False
2025-12-05 23:36:52.271208 | 
2025-12-05 23:36:52.271285 | TASK [include_role : validate-host]
2025-12-05 23:36:52.317877 | controller | ok
2025-12-05 23:36:52.366534 | 
2025-12-05 23:36:52.371317 | TASK [validate-host : Define zuul_info_dir fact]
2025-12-05 23:36:52.427276 | controller | ok
2025-12-05 23:36:52.436583 | 
2025-12-05 23:36:52.436653 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-12-05 23:36:53.249758 | controller -> localhost | ok
2025-12-05 23:36:53.263360 | 
2025-12-05 23:36:53.263431 | TASK [validate-host : Collect information about the host]
2025-12-05 23:36:54.076369 | controller | ok
2025-12-05 23:36:54.105950 | 
2025-12-05 23:36:54.106030 | TASK [validate-host : Sanitize hostname]
2025-12-05 23:36:54.229867 | controller | ok
2025-12-05 23:36:54.234627 | 
2025-12-05 23:36:54.234693 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-12-05 23:36:55.916161 | controller -> localhost | changed
2025-12-05 23:36:55.921956 | 
2025-12-05 23:36:55.922025 | TASK [validate-host : Collect information about zuul worker]
2025-12-05 23:36:56.664898 | controller | ok
2025-12-05 23:36:56.675571 | 
2025-12-05 23:36:56.675651 | TASK [validate-host : Write out all zuul information for each host]
2025-12-05 23:36:58.243286 | controller -> localhost | changed
2025-12-05 23:36:58.274117 | 
2025-12-05 23:36:58.274211 | TASK [include_role : prepare-workspace-openshift]
2025-12-05 23:36:58.334254 | controller | skipping: Conditional result was False
2025-12-05 23:36:58.339368 | 
2025-12-05 23:36:58.339461 | TASK [include_role : remove-zuul-sshkey]
2025-12-05 23:36:58.446435 | controller | skipping: Conditional result was False
2025-12-05 23:36:58.460026 | 
2025-12-05 23:36:58.460099 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-12-05 23:36:58.871990 | controller | ok: "logs"
2025-12-05 23:36:58.872219 | controller | ok: All items complete
2025-12-05 23:36:58.872250 | 
2025-12-05 23:36:59.139982 | controller | ok: "artifacts"
2025-12-05 23:36:59.429725 | controller | ok: "docs"
2025-12-05 23:36:59.446626 | 
2025-12-05 23:36:59.446720 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-12-05 23:36:59.776719 | controller | changed: "logs"
2025-12-05 23:37:00.050659 | controller | changed: "artifacts"
2025-12-05 23:37:00.317995 | controller | changed: "docs"
2025-12-05 23:37:00.343270 | 
2025-12-05 23:37:00.343360 | PLAY RECAP
2025-12-05 23:37:00.343406 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-12-05 23:37:00.343436 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-12-05 23:37:00.343457 | 
2025-12-05 23:37:00.669256 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-12-05 23:37:00.670323 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-12-05 23:37:03.150729 | 
2025-12-05 23:37:03.150838 | PLAY [all]
2025-12-05 23:37:03.215566 | 
2025-12-05 23:37:03.215665 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-12-05 23:37:03.554581 | controller | ok
2025-12-05 23:37:03.560278 | 
2025-12-05 23:37:03.560379 | TASK [mirror-info-fork : Create /etc/ci]
2025-12-05 23:37:04.891771 | controller | changed
2025-12-05 23:37:04.900119 | 
2025-12-05 23:37:04.900196 | TASK [mirror-info-fork : Install ci_mirror script]
2025-12-05 23:37:07.107091 | controller | changed
2025-12-05 23:37:07.129208 | 
2025-12-05 23:37:07.129300 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-12-05 23:37:08.058069 | controller | changed:
2025-12-05 23:37:08.058339 | controller | {
2025-12-05 23:37:08.058383 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-12-05 23:37:08.058417 | controller | }
2025-12-05 23:37:08.379277 | controller | changed:
2025-12-05 23:37:08.379353 | controller | {
2025-12-05 23:37:08.379382 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-12-05 23:37:08.379405 | controller | }
2025-12-05 23:37:08.715350 | controller | changed:
2025-12-05 23:37:08.715452 | controller | {
2025-12-05 23:37:08.715480 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com"
2025-12-05 23:37:08.715502 | controller | }
2025-12-05 23:37:09.068198 | controller | changed:
2025-12-05 23:37:09.068269 | controller | {
2025-12-05 23:37:09.068297 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-12-05 23:37:09.068319 | controller | }
2025-12-05 23:37:09.392378 | controller | changed:
2025-12-05 23:37:09.392450 | controller | {
2025-12-05 23:37:09.392478 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-12-05 23:37:09.392499 | controller | }
2025-12-05 23:37:09.719455 | controller | changed:
2025-12-05 23:37:09.719560 | controller | {
2025-12-05 23:37:09.719589 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-12-05 23:37:09.719612 | controller | }
2025-12-05 23:37:10.048295 | controller | changed:
2025-12-05 23:37:10.048379 | controller | {
2025-12-05 23:37:10.048407 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDLOQd4ZLtkZXQGY6UwAr/06ppWQK4fDO3HaqxPk98csyOCBXsliSKK39Bso828+5srIXiW7aI6aC9P5mwi4mUZlGPfJlQbfrcGvY+b/SocuvaGK+1RrHLoJCT52LBhwgrzlXio2jeksZeein8iaTrhsPrOAs7KggIL/rB9hEiB3NaOPWhhoCP4vlW6MEMExGcqB/1FVxXFBPnLkEyW0Lk7ycVflZl2ocRxbfjZi0+tI1Wlinp8PvSQSc/WVrAcDgKjc/mB4ODPOyYy3G8FHgfMsrXSDEyjBKgLKMsdCrAUcqJQWjkqXleXSYOV4q3pzL+9umK+q/e3P/bIoSFQzmJKTU1eDfuvPXmow9F5H54fii/Da7ezlMJ+wPGHJrRAkmzvMbALy7xwswLhZMkOGNtRcPqaKYRmIBKpw3o6bCTtcNUHOtOQnzwY8JzrM2eBWJBXAANYw+9/ho80JIiwhg29CFNpVBuHbql2YxJQNrnl90guN65rYNpDxdIluweyUf8= anbanerj@kaermorhen"
2025-12-05 23:37:10.048431 | controller | }
2025-12-05 23:37:10.389374 | controller | changed:
2025-12-05 23:37:10.389456 | controller | {
2025-12-05 23:37:10.389485 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQC3VwV8Im9kRm49lt3tM36hj4Zv27FxGo4C1Q/0jqhzFmHY7RHbmeRr8ObhwWoHjXSozKWg8FL5ER0z3hTwL0W6lez3sL7hUaCmSuZmG5Hnl3x4vTSxDI9JZ/Y65rtYiiWQo2fC5xJhU/4+0e5e/pseCm8cKRSu+SaxhO+sd6FDojA2x1BzOzKiQRDy/1zWGp/cZkxcEuB1wHI5LMzN03c67vmbu+fhZRAUO4dQkvcnj2LrhQtpa+ytvnSjr8icMDosf1OsbSffwZFyHB/hfWGAfe0eIeSA2XPraxiPknXxiPKx2MJsaUTYbsZcm3EjFdHBBMumw5rBI74zLrMRvCO9GwBEmGT4rFng1nP+yw5DB8sn2zqpOsPg1LYRwCPOUveC13P6pgsZZPh812e8v5EKnETct+5XI3dVpdw6CnNiLwAyVAF15DJvBGT/u1k0Myg/bQn+Gv9k2MSj6LvQmf6WbZu2Wgjm30z3FyCneBqTL7mLF19YXzeC0ufHz5pnO1E= dasm@fedora"
2025-12-05 23:37:10.389508 | controller | }
2025-12-05 23:37:10.710096 | controller | changed:
2025-12-05 23:37:10.710190 | controller | {
2025-12-05 23:37:10.710219 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-12-05 23:37:10.710240 | controller | }
2025-12-05 23:37:11.030691 | controller | changed:
2025-12-05 23:37:11.030764 | controller | {
2025-12-05 23:37:11.030807 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-12-05 23:37:11.030830 | controller | }
2025-12-05 23:37:11.365663 | controller | changed:
2025-12-05 23:37:11.365736 | controller | {
2025-12-05 23:37:11.365764 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-12-05 23:37:11.365802 | controller | }
2025-12-05 23:37:11.729838 | controller | changed:
2025-12-05 23:37:11.729924 | controller | {
2025-12-05 23:37:11.729958 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-12-05 23:37:11.729981 | controller | }
2025-12-05 23:37:12.066397 | controller | changed:
2025-12-05 23:37:12.066516 | controller | {
2025-12-05 23:37:12.066547 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-12-05 23:37:12.066570 | controller | }
2025-12-05 23:37:12.396960 | controller | changed:
2025-12-05 23:37:12.397032 | controller | {
2025-12-05 23:37:12.397060 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-12-05 23:37:12.397082 | controller | }
2025-12-05 23:37:12.727418 | controller | changed:
2025-12-05 23:37:12.727491 | controller | {
2025-12-05 23:37:12.727519 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-12-05 23:37:12.727540 | controller | }
2025-12-05 23:37:13.068947 | controller | changed:
2025-12-05 23:37:13.069107 | controller | {
2025-12-05 23:37:13.069141 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-12-05 23:37:13.069163 | controller | }
2025-12-05 23:37:13.402596 | controller | changed:
2025-12-05 23:37:13.402667 | controller | {
2025-12-05 23:37:13.402696 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQCfNtF7NvKl915TGsGGoseUb06Hj8L/S4toWf0hExeY+F00woL6NvBlJD0nDct+P5a22I4EhvoQCRQ8reaPCm1lybR3uiRIJsj+8zkVvLwby9LXzfZorlNG9ofjd00FEmB09uW/YvTl6Q9XwwwX6tInzIOv3TMqTHHGOL74ibbj8J/FJR0cFEyj0z4WQRvtkh32xAHl83gbuINryMt0sqRI+clj2381NKL55DRLQrVw0gsfqqxiHAnXg21qWmc4J+b9e9kiuAFQjcjwTVkwJCcg3xbPwC/qokYRby/Y5S40UUd7/jEARGXT7RZgpzTuDd1oZiCVrnrqJNPaMNdVv5MLeFdf1B7iIe5aa/fGouX7AO4SdKhZUdnJmCFAGvjC6S3JMZ2wAcUl+OHnssfmdj7XL50cLo27vjuzMtLAgSqi6N99m92WCF2s8J9aVzszX7Xz9OKZCeGsiVJp3/NdABKzSEAyM9xBD/5Vho894Sav+otpySHe3p6RUTgbB5Zu8VyZRZ/UtB3ueXxyo764yrc6qWIDqrehm84Xm9g+/jpIBzGPl07NUNJpdt/6Sgf9RIKXw/7XypO5yZfUcuFNGTxLfqjTNrtgLZNcjfav6sSdVXVcMPL//XNuRdKmVFaO76eV/oGMQGr1fGcCD+N+CpI7+Q+fCNB6VFWG4nZFuI/Iuw== averdagu@redhat.com"
2025-12-05 23:37:13.402719 | controller | }
2025-12-05 23:37:13.745290 | controller | changed:
2025-12-05 23:37:13.745372 | controller | {
2025-12-05 23:37:13.745399 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDq8l27xI+QlQVdS4djp9ogSoyrNE2+Ox6vKPdhSNL1J3PE5w+WCSvMz9A5gnNuH810zwbekEApbxTze/gLQJwBHA52CChfURpXrFaxY7ePXRElwKAL3mJfzBWY/c5jnNL9TCVmFJTGZkFZP3Nh+BMgZvL6xBkt3WKm6Uq18qzd9XeKcZusrA+O+uLv1fVeQnadY9RIqOCyeFYCzLWrUfTyE8x/XG0hAWIM7qpnF2cALQS2h9n4hW5ybiUN790H08wf9hFwEf5nxY9Z9dVkPFQiTSGKNBzmnCXU9skxS/xhpFjJ5duGSZdtAHe9O+nGZm9c67hxgtf8e5PDuqAdXEv2cf6e3VBAt+Bz8EKI3yosTj0oZHfwr42Yzb1l/SKy14Rggsrc9KAQlrGXan6+u2jcQqqx7l+SWmnpFiWTV9u5cWj2IgOhApOitmRBPYqk9rE2usfO0hLn/Pj/R/Nau4803e1/EikdLE7Ps95s9mX5jRDjAoUa2JwFF5RsVFyL910= ashigupt@ashigupt.remote.csb"
2025-12-05 23:37:13.745429 | controller | }
2025-12-05 23:37:14.109444 | controller | changed:
2025-12-05 23:37:14.109535 | controller | {
2025-12-05 23:37:14.109564 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-12-05 23:37:14.109585 | controller | }
2025-12-05 23:37:14.440202 | controller | changed:
2025-12-05 23:37:14.440275 | controller | {
2025-12-05 23:37:14.440396 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-12-05 23:37:14.440428 | controller | }
2025-12-05 23:37:14.787064 | controller | changed:
2025-12-05 23:37:14.787153 | controller | {
2025-12-05 23:37:14.787188 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-12-05 23:37:14.787212 | controller | }
2025-12-05 23:37:15.123864 | controller | changed:
2025-12-05 23:37:15.123968 | controller | {
2025-12-05 23:37:15.123997 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-12-05 23:37:15.124018 | controller | }
2025-12-05 23:37:15.470646 | controller | changed:
2025-12-05 23:37:15.470721 | controller | {
2025-12-05 23:37:15.470749 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQDZdI7t1cxYx65heVI24HTV4F7oQLW1zyfxHreL2TIJKxjyrUUKIFEUmTutcBlJRLNT2Eoix6x1sOw9YrchloCLcn//SGfTElr9mSc5jbjb7QXEU+zJMhtxyEJ1Po3CUGnj7ckiIXw7wcawZtrEOAQ9pH3ExYCJcEMiyNjRQZCxT3tPK+S4B95EWh5Fsrz9CkwpjNRPPH7LigCeQTM3Wc7r97utAslBUUvYceDSLA7rMgkitJE38b7rZBeYzsGQ8YYUBjTCtehqQXxCRjizbHWaaZkBU+N3zkKB6n/iCNGIO690NK7A/qb6msTijiz1PeuM8ThOsi9qXnbX5v0PoTpcFSojV7NHAQ71f0XXuS43FhZctT+Dcx44dT8Fb5vJu2cJGrk+qF8ZgJYNpRS7gPg0EG2EqjK7JMf9ULdjSu0r+KlqIAyLvtzT4eOnQipoKlb/WG5D/0ohKv7OMQ352ggfkBFIQsRXyyTCT98Ft9juqPuahi3CAQmP4H9dyE+7+Kz437PEtsxLmfm6naNmWi7Ee1DqWPwS8rEajsm4sNM4wW9gdBboJQtc0uZw0DfLj1I9r3Mc8Ol0jYtz0yNQDSzVLrGCaJlC311trU70tZ+ZkAVV6Mn8lOhSbj1cK0lvSr6ZK4dgqGl3I1eTZJJhbLNdg7UOVaiRx9543+C/p/As7w== brjackma@redhat.com"
2025-12-05 23:37:15.470771 | controller | }
2025-12-05 23:37:15.829472 | controller | changed:
2025-12-05 23:37:15.829549 | controller | {
2025-12-05 23:37:15.829577 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-12-05 23:37:15.829599 | controller | }
2025-12-05 23:37:16.178572 | controller | changed:
2025-12-05 23:37:16.178658 | controller | {
2025-12-05 23:37:16.178685 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-12-05 23:37:16.178707 | controller | }
2025-12-05 23:37:16.528191 | controller | changed:
2025-12-05 23:37:16.528272 | controller | {
2025-12-05 23:37:16.528300 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-12-05 23:37:16.528321 | controller | }
2025-12-05 23:37:16.573135 | 
2025-12-05 23:37:16.573238 | TASK [Set timezone to UTC]
2025-12-05 23:37:17.512513 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-12-05 23:37:17.517480 | 
2025-12-05 23:37:17.517553 | TASK [Create nodepool directory]
2025-12-05 23:37:17.908274 | controller | changed
2025-12-05 23:37:17.913849 | 
2025-12-05 23:37:17.913943 | TASK [Create nodepool sub_nodes file]
2025-12-05 23:37:19.223827 | controller | changed
2025-12-05 23:37:19.244640 | 
2025-12-05 23:37:19.244725 | TASK [Create nodepool sub_nodes_private file]
2025-12-05 23:37:20.521998 | controller | changed
2025-12-05 23:37:20.529754 | 
2025-12-05 23:37:20.529832 | LOOP [Populate nodepool sub_nodes file]
2025-12-05 23:37:20.624690 | 
2025-12-05 23:37:20.624841 | LOOP [Populate nodepool sub_nodes_private file]
2025-12-05 23:37:20.697433 | 
2025-12-05 23:37:20.697589 | TASK [Create nodepool primary file]
2025-12-05 23:37:20.751158 | controller | skipping: Conditional result was False
2025-12-05 23:37:20.761672 | 
2025-12-05 23:37:20.761753 | TASK [Create nodepool node_private for this node]
2025-12-05 23:37:22.118692 | controller | changed
2025-12-05 23:37:22.129300 | 
2025-12-05 23:37:22.129371 | LOOP [Copy ssh keys to nodepool directory]
2025-12-05 23:37:23.083943 | controller | ok: Item: id_rsa Runtime: 0:00:00.006049
2025-12-05 23:37:23.084110 | 
2025-12-05 23:37:23.360088 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.005977
2025-12-05 23:37:23.374218 | 
2025-12-05 23:37:23.374327 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-12-05 23:37:24.756439 | controller | changed
2025-12-05 23:37:24.764544 | 
2025-12-05 23:37:24.764619 | TASK [Validate sudoers config after edits]
2025-12-05 23:37:25.132581 | controller | /etc/sudoers: parsed OK
2025-12-05 23:37:25.132644 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-12-05 23:37:25.132653 | controller | /etc/sudoers.d/zuul: parsed OK
2025-12-05 23:37:25.132659 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-12-05 23:37:25.303761 | controller | ok: Runtime: 0:00:00.006363
2025-12-05 23:37:25.309615 | 
2025-12-05 23:37:25.309678 | TASK [Show the environment passed in to job shell scripts]
2025-12-05 23:37:25.712915 | controller | SHELL=/bin/bash
2025-12-05 23:37:25.712963 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-12-05 23:37:25.712973 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-12-05 23:37:25.712980 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/39/3539/ef8821f44d461626a5ab46002cd514823bd56c63
2025-12-05 23:37:25.712986 | controller | PWD=/home/zuul
2025-12-05 23:37:25.712992 | controller | ZUUL_PIPELINE=github-check
2025-12-05 23:37:25.712997 | controller | LOGNAME=zuul
2025-12-05 23:37:25.713002 | controller | XDG_SESSION_TYPE=tty
2025-12-05 23:37:25.713007 | controller | _=/usr/bin/env
2025-12-05 23:37:25.713013 | controller | MOTD_SHOWN=pam
2025-12-05 23:37:25.713068 | controller | HOME=/home/zuul
2025-12-05 23:37:25.713076 | controller | LANG=en_US.UTF-8
2025-12-05 23:37:25.713082 | controller | SSH_CONNECTION=192.168.26.12 47378 192.168.26.190 22
2025-12-05 23:37:25.713088 | controller | TOX_TESTENV_PASSENV=ZUUL_UUID ZUUL_PROJECT ZUUL_SHORT_PROJECT_NAME ZUUL_PIPELINE ZUUL_VOTING WORKSPACE BUILD_TIMEOUT ZUUL_BRANCH ZUUL_CHANGES ZUUL_CHANGE_IDS ZUUL_CHANGE ZUUL_PATCHSET
2025-12-05 23:37:25.713097 | controller | ZUUL_CHANGE_IDS=3539,ef8821f44d461626a5ab46002cd514823bd56c63
2025-12-05 23:37:25.713102 | controller | WORKSPACE=/home/zuul/workspace
2025-12-05 23:37:25.713108 | controller | XDG_SESSION_CLASS=user
2025-12-05 23:37:25.713113 | controller | SELINUX_ROLE_REQUESTED=
2025-12-05 23:37:25.713118 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-12-05 23:37:25.713123 | controller | USER=zuul
2025-12-05 23:37:25.713129 | controller | ZUUL_VOTING=True
2025-12-05 23:37:25.713134 | controller | BUILD_TIMEOUT=1800000
2025-12-05 23:37:25.713139 | controller | SELINUX_USE_CURRENT_RANGE=
2025-12-05 23:37:25.713144 | controller | SHLVL=1
2025-12-05 23:37:25.713150 | controller | ZUUL_PATCHSET=ef8821f44d461626a5ab46002cd514823bd56c63
2025-12-05 23:37:25.713155 | controller | XDG_SESSION_ID=1
2025-12-05 23:37:25.713160 | controller | ZUUL_BRANCH=main
2025-12-05 23:37:25.713178 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-12-05 23:37:25.713187 | controller | SSH_CLIENT=192.168.26.12 47378 22
2025-12-05 23:37:25.713193 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-12-05 23:37:25.713198 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-12-05 23:37:25.713204 | controller | which_declare=declare -f
2025-12-05 23:37:25.713209 | 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 23:37:25.713215 | controller | SELINUX_LEVEL_REQUESTED=
2025-12-05 23:37:25.713220 | controller | ZUUL_CHANGE=3539
2025-12-05 23:37:25.713225 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-12-05 23:37:25.713230 | controller | ZUUL_UUID=3379f393edb34e4f93a06fb27f2cfca3
2025-12-05 23:37:25.713236 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-12-05 23:37:25.713241 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-12-05 23:37:25.713247 | controller | }
2025-12-05 23:37:25.961593 | controller | ok: Runtime: 0:00:00.006642
2025-12-05 23:37:25.989056 | 
2025-12-05 23:37:25.989151 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-12-05 23:37:26.058639 | controller | skipping: Conditional result was False
2025-12-05 23:37:26.064653 | 
2025-12-05 23:37:26.064722 | TASK [Symlink /home/zuul-worker/workspace]
2025-12-05 23:37:26.697698 | controller | skipping: Conditional result was False
2025-12-05 23:37:26.703092 | 
2025-12-05 23:37:26.703159 | TASK [Ensure legacy workspace directory]
2025-12-05 23:37:27.010255 | controller | changed
2025-12-05 23:37:27.080411 | 
2025-12-05 23:37:27.080512 | PLAY RECAP
2025-12-05 23:37:27.080560 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-12-05 23:37:27.080584 | 
2025-12-05 23:37:27.412815 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-12-05 23:37:27.413570 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-12-05 23:37:29.930739 | 
2025-12-05 23:37:29.930863 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-12-05 23:37:30.027020 | 
2025-12-05 23:37:30.027123 | TASK [Create zuul-output directory]
2025-12-05 23:37:30.913635 | controller | changed
2025-12-05 23:37:30.919092 | 
2025-12-05 23:37:30.919170 | TASK [Slurp Zuul inventory test]
2025-12-05 23:37:32.160245 | controller -> localhost | ok
2025-12-05 23:37:32.167417 | 
2025-12-05 23:37:32.167487 | TASK [Save zuul inventory]
2025-12-05 23:37:34.621882 | controller | changed
2025-12-05 23:37:34.645190 | 
2025-12-05 23:37:34.645275 | TASK [Save zuul vars without the change_message]
2025-12-05 23:37:36.083015 | controller | changed
2025-12-05 23:37:36.171313 | 
2025-12-05 23:37:36.171392 | PLAY RECAP
2025-12-05 23:37:36.171438 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-12-05 23:37:36.171464 | 
2025-12-05 23:37:36.517535 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-12-05 23:37:36.518250 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-12-05 23:37:38.434764 | 
2025-12-05 23:37:38.434895 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2025-12-05 23:37:38.520269 | 
2025-12-05 23:37:38.520392 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2025-12-05 23:37:38.567560 | controller | ok
2025-12-05 23:37:38.622527 | 
2025-12-05 23:37:38.622634 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-12-05 23:37:38.697942 | controller | skipping: Conditional result was False
2025-12-05 23:37:38.704522 | 
2025-12-05 23:37:38.704594 | TASK [mirror-info-fork : Create /etc/ci]
2025-12-05 23:37:39.286232 | controller | ok
2025-12-05 23:37:39.294165 | 
2025-12-05 23:37:39.294237 | TASK [mirror-info-fork : Install ci_mirror script]
2025-12-05 23:37:40.462409 | controller | ok
2025-12-05 23:37:40.495416 | 
2025-12-05 23:37:40.495521 | TASK [Prepare workspace]
2025-12-05 23:37:40.523745 | controller | ok
2025-12-05 23:37:40.559491 | 
2025-12-05 23:37:40.559570 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-12-05 23:37:41.148726 | controller | ok
2025-12-05 23:37:41.160860 | 
2025-12-05 23:37:41.160978 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-12-05 23:37:47.187624 | controller | Output suppressed because no_log was given
2025-12-05 23:37:47.215444 | 
2025-12-05 23:37:47.215532 | LOOP [Create zuul-output directory]
2025-12-05 23:37:47.539105 | controller | changed: "/home/zuul/ci-framework-data/logs"
2025-12-05 23:37:47.816840 | controller | ok: "/home/zuul/zuul-output/logs"
2025-12-05 23:37:47.851174 | 
2025-12-05 23:37:47.851271 | TASK [Install required packages]
2025-12-05 23:38:13.562135 | controller | ok: Nothing to do
2025-12-05 23:38:13.570038 | 
2025-12-05 23:38:13.570109 | TASK [Install venv]
2025-12-05 23:38:27.751154 | controller | ERROR
2025-12-05 23:38:27.751463 | controller | {
2025-12-05 23:38:27.751510 | controller |   "msg": "gmake: *** [Makefile:56: setup_tests] Error 1",
2025-12-05 23:38:27.751537 | controller |   "rc": 2
2025-12-05 23:38:27.751557 | controller | }
failure
2025-12-05 23:38:27.754775 | 
2025-12-05 23:38:27.754850 | PLAY RECAP
2025-12-05 23:38:27.754896 | controller | ok: 6 changed: 2 unreachable: 0 failed: 1 skipped: 1 rescued: 0 ignored: 0
2025-12-05 23:38:27.754948 | 
2025-12-05 23:38:27.828243 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-12-05 23:38:27.829095 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-12-05 23:38:28.371071 | 
2025-12-05 23:38:28.371189 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-12-05 23:38:28.392723 | 
2025-12-05 23:38:28.392840 | TASK [Filter out host if needed]
2025-12-05 23:38:28.401754 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-12-05 23:38:28.406381 | 
2025-12-05 23:38:28.406449 | TASK [Ensure file is present]
2025-12-05 23:38:28.759710 | controller | ok
2025-12-05 23:38:28.766346 | 
2025-12-05 23:38:28.766430 | TASK [Manage molecule report file]
2025-12-05 23:38:29.293271 | controller | skipping: Conditional result was False
2025-12-05 23:38:29.299468 | 
2025-12-05 23:38:29.299539 | TASK [Check if we get ci-framework-data basedir]
2025-12-05 23:38:29.565470 | controller | ok
2025-12-05 23:38:29.571703 | 
2025-12-05 23:38:29.571796 | TASK [Create ci-framework-data log directory for zuul]
2025-12-05 23:38:29.989836 | controller | changed
2025-12-05 23:38:29.997079 | 
2025-12-05 23:38:29.997154 | TASK [Copy ci-framework interesting files]
2025-12-05 23:38:30.384239 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2025-12-05 23:38:30.522543 | controller | changed
2025-12-05 23:38:30.528107 | 
2025-12-05 23:38:30.528180 | TASK [Get SELinux listing]
2025-12-05 23:38:31.056363 | controller | changed
2025-12-05 23:38:31.062573 | 
2025-12-05 23:38:31.062660 | TASK [Generate log index]
2025-12-05 23:38:32.149351 | controller | changed
2025-12-05 23:38:32.154606 | 
2025-12-05 23:38:32.154670 | TASK [Get some env related data]
2025-12-05 23:38:32.756901 | controller | /usr/bin/ansible
2025-12-05 23:38:33.680673 | controller | changed
2025-12-05 23:38:33.685927 | 
2025-12-05 23:38:33.685991 | TASK [Generate list of logs to collect in home directory]
2025-12-05 23:38:34.020009 | controller | ok: All paths examined
2025-12-05 23:38:34.025649 | 
2025-12-05 23:38:34.025725 | LOOP [Copy logs from home directory]
2025-12-05 23:38:34.616599 | controller | changed:
2025-12-05 23:38:34.616774 | controller | {
2025-12-05 23:38:34.616804 | controller |   "atime": 1743544925.4788878,
2025-12-05 23:38:34.616851 | controller |   "ctime": 1743545329.1409318,
2025-12-05 23:38:34.616873 | controller |   "dev": 64513,
2025-12-05 23:38:34.616889 | controller |   "gid": 1000,
2025-12-05 23:38:34.616905 | controller |   "gr_name": "zuul",
2025-12-05 23:38:34.616949 | controller |   "inode": 4518807,
2025-12-05 23:38:34.616970 | controller |   "isblk": false,
2025-12-05 23:38:34.616988 | controller |   "ischr": false,
2025-12-05 23:38:34.617005 | controller |   "isdir": false,
2025-12-05 23:38:34.617022 | controller |   "isfifo": false,
2025-12-05 23:38:34.617038 | controller |   "isgid": false,
2025-12-05 23:38:34.617053 | controller |   "islnk": false,
2025-12-05 23:38:34.617068 | controller |   "isreg": true,
2025-12-05 23:38:34.617082 | controller |   "issock": false,
2025-12-05 23:38:34.617096 | controller |   "isuid": false,
2025-12-05 23:38:34.617118 | controller |   "mode": "0644",
2025-12-05 23:38:34.617133 | controller |   "mtime": 1743545329.1409318,
2025-12-05 23:38:34.617147 | controller |   "nlink": 1,
2025-12-05 23:38:34.617162 | controller |   "path": "/home/zuul/crc-setup.log",
2025-12-05 23:38:34.617178 | controller |   "pw_name": "zuul",
2025-12-05 23:38:34.617193 | controller |   "rgrp": true,
2025-12-05 23:38:34.617208 | controller |   "roth": true,
2025-12-05 23:38:34.617223 | controller |   "rusr": true,
2025-12-05 23:38:34.617237 | controller |   "size": 4108,
2025-12-05 23:38:34.617251 | controller |   "uid": 1000,
2025-12-05 23:38:34.617265 | controller |   "wgrp": false,
2025-12-05 23:38:34.617279 | controller |   "woth": false,
2025-12-05 23:38:34.617296 | controller |   "wusr": true,
2025-12-05 23:38:34.617311 | controller |   "xgrp": false,
2025-12-05 23:38:34.617326 | controller |   "xoth": false,
2025-12-05 23:38:34.617342 | controller |   "xusr": false
2025-12-05 23:38:34.617356 | controller | }
2025-12-05 23:38:35.144478 | controller | changed:
2025-12-05 23:38:35.144632 | controller | {
2025-12-05 23:38:35.144667 | controller |   "atime": 1743545331.0429637,
2025-12-05 23:38:35.144691 | controller |   "ctime": 1743545842.0111232,
2025-12-05 23:38:35.144710 | controller |   "dev": 64513,
2025-12-05 23:38:35.144727 | controller |   "gid": 1000,
2025-12-05 23:38:35.144745 | controller |   "gr_name": "zuul",
2025-12-05 23:38:35.144761 | controller |   "inode": 4194437,
2025-12-05 23:38:35.144776 | controller |   "isblk": false,
2025-12-05 23:38:35.144792 | controller |   "ischr": false,
2025-12-05 23:38:35.144806 | controller |   "isdir": false,
2025-12-05 23:38:35.144837 | controller |   "isfifo": false,
2025-12-05 23:38:35.144852 | controller |   "isgid": false,
2025-12-05 23:38:35.144867 | controller |   "islnk": false,
2025-12-05 23:38:35.144882 | controller |   "isreg": true,
2025-12-05 23:38:35.144898 | controller |   "issock": false,
2025-12-05 23:38:35.144931 | controller |   "isuid": false,
2025-12-05 23:38:35.144951 | controller |   "mode": "0644",
2025-12-05 23:38:35.144967 | controller |   "mtime": 1743545842.0111232,
2025-12-05 23:38:35.144983 | controller |   "nlink": 1,
2025-12-05 23:38:35.144998 | controller |   "path": "/home/zuul/crc-start.log",
2025-12-05 23:38:35.145014 | controller |   "pw_name": "zuul",
2025-12-05 23:38:35.145029 | controller |   "rgrp": true,
2025-12-05 23:38:35.145045 | controller |   "roth": true,
2025-12-05 23:38:35.145061 | controller |   "rusr": true,
2025-12-05 23:38:35.145077 | controller |   "size": 4023,
2025-12-05 23:38:35.145092 | controller |   "uid": 1000,
2025-12-05 23:38:35.145107 | controller |   "wgrp": false,
2025-12-05 23:38:35.145122 | controller |   "woth": false,
2025-12-05 23:38:35.145202 | controller |   "wusr": true,
2025-12-05 23:38:35.145232 | controller |   "xgrp": false,
2025-12-05 23:38:35.145252 | controller |   "xoth": false,
2025-12-05 23:38:35.145269 | controller |   "xusr": false
2025-12-05 23:38:35.145286 | controller | }
2025-12-05 23:38:35.159170 | 
2025-12-05 23:38:35.159230 | TASK [Copy crio stats log file]
2025-12-05 23:38:35.172658 | controller | skipping: Conditional result was False
2025-12-05 23:38:35.178386 | 
2025-12-05 23:38:35.178515 | TASK [Get SELinux related data]
2025-12-05 23:38:35.461374 | controller | <no matches>
2025-12-05 23:38:35.706098 | controller | ERROR
2025-12-05 23:38:35.706268 | controller | {
2025-12-05 23:38:35.706301 | controller |   "delta": "0:00:00.010255",
2025-12-05 23:38:35.706323 | controller |   "end": "2025-12-05 23:38:35.462302",
2025-12-05 23:38:35.706341 | controller |   "msg": "non-zero return code",
2025-12-05 23:38:35.706357 | controller |   "rc": 1,
2025-12-05 23:38:35.706373 | controller |   "start": "2025-12-05 23:38:35.452047"
2025-12-05 23:38:35.706389 | controller | }
2025-12-05 23:38:35.706410 | controller | ERROR: Ignoring Errors
2025-12-05 23:38:35.712196 | 
2025-12-05 23:38:35.712258 | TASK [Create system configuration directory]
2025-12-05 23:38:35.984288 | controller | changed
2025-12-05 23:38:35.989639 | 
2025-12-05 23:38:35.989701 | TASK [Get some of the system configurations]
2025-12-05 23:38:36.515015 | controller | changed
2025-12-05 23:38:36.520167 | 
2025-12-05 23:38:36.520231 | TASK [Copy generated documentation if available]
2025-12-05 23:38:36.538367 | controller | skipping: Conditional result was False
2025-12-05 23:38:36.543958 | 
2025-12-05 23:38:36.544027 | TASK [Copy generated AsciiDoc documentation if available]
2025-12-05 23:38:36.566807 | controller | skipping: Conditional result was False
2025-12-05 23:38:36.572458 | 
2025-12-05 23:38:36.572521 | TASK [Compress logs bigger than 2MB]
2025-12-05 23:38:37.101006 | controller | changed
2025-12-05 23:38:37.106240 | 
2025-12-05 23:38:37.106306 | TASK [Copy files from workspace on node]
2025-12-05 23:38:37.124440 | controller | ok
2025-12-05 23:38:37.147220 | 
2025-12-05 23:38:37.147287 | TASK [fetch-output : Set log path for multiple nodes]
2025-12-05 23:38:37.160980 | controller | skipping: Conditional result was False
2025-12-05 23:38:37.166563 | 
2025-12-05 23:38:37.166632 | TASK [fetch-output : Set log path for single node]
2025-12-05 23:38:37.195076 | controller | ok
2025-12-05 23:38:37.200212 | 
2025-12-05 23:38:37.200274 | LOOP [fetch-output : Ensure local output dirs]
2025-12-05 23:38:37.397192 | controller -> localhost | ok: "/var/lib/zuul/builds/3379f393edb34e4f93a06fb27f2cfca3/work/logs"
2025-12-05 23:38:37.397413 | controller -> localhost | changed: All items complete
2025-12-05 23:38:37.397446 | 
2025-12-05 23:38:37.580234 | controller -> localhost | changed: "/var/lib/zuul/builds/3379f393edb34e4f93a06fb27f2cfca3/work/artifacts"
2025-12-05 23:38:37.766002 | controller -> localhost | changed: "/var/lib/zuul/builds/3379f393edb34e4f93a06fb27f2cfca3/work/docs"
2025-12-05 23:38:37.777833 | 
2025-12-05 23:38:37.777934 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-12-05 23:38:38.433565 | controller | changed:
2025-12-05 23:38:38.433742 | controller | .d..t...... ./
2025-12-05 23:38:38.433773 | controller | >f+++++++++ README.html
2025-12-05 23:38:38.433797 | controller | >f+++++++++ crc-setup.log
2025-12-05 23:38:38.433840 | controller | >f+++++++++ crc-start.log
2025-12-05 23:38:38.433862 | controller | >f+++++++++ dmesg.log
2025-12-05 23:38:38.433881 | controller | >f+++++++++ installed-pkgs.log
2025-12-05 23:38:38.433900 | controller | >f+++++++++ python.log
2025-12-05 23:38:38.433941 | controller | >f+++++++++ registries.conf
2025-12-05 23:38:38.433964 | controller | >f+++++++++ selinux-denials.log
2025-12-05 23:38:38.433984 | controller | >f+++++++++ selinux-listing.log
2025-12-05 23:38:38.434003 | controller | cd+++++++++ ci-framework-data/
2025-12-05 23:38:38.434021 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-12-05 23:38:38.434039 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-12-05 23:38:38.434057 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-12-05 23:38:38.434076 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-12-05 23:38:38.434094 | controller | cd+++++++++ ci-framework-data/logs/
2025-12-05 23:38:38.434111 | controller | cd+++++++++ registries.conf.d/
2025-12-05 23:38:38.434128 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-12-05 23:38:38.434145 | controller | cd+++++++++ system-config/
2025-12-05 23:38:38.434162 | controller | cd+++++++++ system-config/libvirt/
2025-12-05 23:38:38.434179 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-12-05 23:38:38.434196 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-12-05 23:38:38.434214 | controller | >f+++++++++ system-config/libvirt/libvirtd.conf
2025-12-05 23:38:38.434231 | controller | >f+++++++++ system-config/libvirt/network.conf
2025-12-05 23:38:38.434248 | controller | >f+++++++++ system-config/libvirt/qemu-lockd.conf
2025-12-05 23:38:38.434266 | controller | >f+++++++++ system-config/libvirt/qemu.conf
2025-12-05 23:38:38.434283 | controller | >f+++++++++ system-config/libvirt/virtinterfaced.conf
2025-12-05 23:38:38.434308 | controller | >f+++++++++ system-config/libvirt/virtlockd.conf
2025-12-05 23:38:38.434327 | controller | >f+++++++++ system-config/libvirt/virtlogd.conf
2025-12-05 23:38:38.434345 | controller | >f+++++++++ system-config/libvirt/virtnetworkd.conf
2025-12-05 23:38:38.434364 | controller | >f+++++++++ system-config/libvirt/virtnodedevd.conf
2025-12-05 23:38:38.434382 | controller | >f+++++++++ system-config/libvirt/virtnwfilterd.conf
2025-12-05 23:38:38.434400 | controller | >f+++++++++ system-config/libvirt/virtproxyd.conf
2025-12-05 23:38:38.434419 | controller | >f+++++++++ system-config/libvirt/virtqemud.conf
2025-12-05 23:38:38.434437 | controller | >f+++++++++ system-config/libvirt/virtsecretd.conf
2025-12-05 23:38:38.434453 | controller | >f+++++++++ system-config/libvirt/virtstoraged.conf
2025-12-05 23:38:38.976163 | controller | changed: .d..t...... ./
2025-12-05 23:38:39.481703 | controller | changed: .d..t...... ./
2025-12-05 23:38:39.499569 | 
2025-12-05 23:38:39.499668 | TASK [Return artifact to Zuul]
2025-12-05 23:38:39.523952 | controller | skipping: Conditional result was False
2025-12-05 23:38:39.545639 | 
2025-12-05 23:38:39.545712 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-12-05 23:38:39.545825 | 
2025-12-05 23:38:39.545856 | PLAY RECAP
2025-12-05 23:38:39.545895 | controller | ok: 16 changed: 12 unreachable: 0 failed: 0 skipped: 6 rescued: 0 ignored: 1
2025-12-05 23:38:39.545945 | 
2025-12-05 23:38:39.636460 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-12-05 23:38:39.637195 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-12-05 23:38:40.179513 | 
2025-12-05 23:38:40.179644 | PLAY [all]
2025-12-05 23:38:40.198004 | 
2025-12-05 23:38:40.198079 | TASK [include_role : fetch-output]
2025-12-05 23:38:40.236440 | controller | ok
2025-12-05 23:38:40.253121 | 
2025-12-05 23:38:40.253217 | TASK [fetch-output : Set log path for multiple nodes]
2025-12-05 23:38:40.296799 | controller | skipping: Conditional result was False
2025-12-05 23:38:40.302549 | 
2025-12-05 23:38:40.302623 | TASK [fetch-output : Set log path for single node]
2025-12-05 23:38:40.331176 | controller | ok
2025-12-05 23:38:40.336519 | 
2025-12-05 23:38:40.336595 | LOOP [fetch-output : Ensure local output dirs]
2025-12-05 23:38:40.682960 | controller -> localhost | ok: "/var/lib/zuul/builds/3379f393edb34e4f93a06fb27f2cfca3/work/logs"
2025-12-05 23:38:40.863715 | controller -> localhost | ok: "/var/lib/zuul/builds/3379f393edb34e4f93a06fb27f2cfca3/work/artifacts"
2025-12-05 23:38:41.061028 | controller -> localhost | ok: "/var/lib/zuul/builds/3379f393edb34e4f93a06fb27f2cfca3/work/docs"
2025-12-05 23:38:41.070200 | 
2025-12-05 23:38:41.070301 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-12-05 23:38:41.700491 | controller | ok
2025-12-05 23:38:41.700679 | controller | ok: All items complete
2025-12-05 23:38:41.700709 | 
2025-12-05 23:38:42.165849 | controller | ok
2025-12-05 23:38:42.620400 | controller | ok
2025-12-05 23:38:42.640052 | 
2025-12-05 23:38:42.640124 | TASK [include_role : fetch-output-openshift]
2025-12-05 23:38:42.653514 | controller | skipping: Conditional result was False
2025-12-05 23:38:42.660019 | 
2025-12-05 23:38:42.660090 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-12-05 23:38:43.037418 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.005608
2025-12-05 23:38:43.251792 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.006838
2025-12-05 23:38:43.312095 | 
2025-12-05 23:38:43.312183 | PLAY [all]
2025-12-05 23:38:43.334796 | 
2025-12-05 23:38:43.334878 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-12-05 23:38:43.852247 | controller | changed
2025-12-05 23:38:43.904023 | 
2025-12-05 23:38:43.904087 | PLAY RECAP
2025-12-05 23:38:43.904130 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-12-05 23:38:43.904163 | 
2025-12-05 23:38:43.997318 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-12-05 23:38:43.998068 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-12-05 23:38:44.586264 | 
2025-12-05 23:38:44.586372 | PLAY [localhost]
2025-12-05 23:38:44.603628 | 
2025-12-05 23:38:44.603703 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-12-05 23:38:44.922492 | localhost | changed
2025-12-05 23:38:44.927348 | 
2025-12-05 23:38:44.927426 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-12-05 23:38:44.945542 | localhost | ok
2025-12-05 23:38:44.954354 | 
2025-12-05 23:38:44.956291 | TASK [add-fileserver : Create SSH private key tempfile]
2025-12-05 23:38:45.258108 | localhost | changed
2025-12-05 23:38:45.263631 | 
2025-12-05 23:38:45.263708 | TASK [add-fileserver : Create SSH private key from secret]
2025-12-05 23:38:45.817895 | localhost | changed
2025-12-05 23:38:45.823422 | 
2025-12-05 23:38:45.823513 | TASK [add-fileserver : Add fileserver ssh key]
2025-12-05 23:38:46.168368 | localhost | Identity added: /var/lib/zuul/builds/3379f393edb34e4f93a06fb27f2cfca3/work/tmp/ansible.qc7qg62w (/var/lib/zuul/builds/3379f393edb34e4f93a06fb27f2cfca3/work/tmp/ansible.qc7qg62w)
2025-12-05 23:38:46.168533 | localhost | ok: Runtime: 0:00:00.005527
2025-12-05 23:38:46.173538 | 
2025-12-05 23:38:46.173608 | TASK [add-fileserver : Remove SSH private key from disk]
2025-12-05 23:38:46.392980 | localhost | ok: Runtime: 0:00:00.003883
2025-12-05 23:38:46.397751 | 
2025-12-05 23:38:46.397833 | TASK [add-fileserver : Add fileserver to inventory]
2025-12-05 23:38:46.446443 | localhost | changed
2025-12-05 23:38:46.451440 | 
2025-12-05 23:38:46.451518 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-12-05 23:38:46.824072 | localhost | changed
2025-12-05 23:38:46.846519 | 
2025-12-05 23:38:46.846608 | PLAY [localhost]
2025-12-05 23:38:46.858053 | 
2025-12-05 23:38:46.858117 | TASK [Generate bulk log download script]
2025-12-05 23:38:46.876281 | localhost | ok
2025-12-05 23:38:46.887473 | 
2025-12-05 23:38:46.887545 | TASK [local-log-download : Check API endpoint is defined]
2025-12-05 23:38:46.914191 | localhost | ok: All assertions passed
2025-12-05 23:38:46.918973 | 
2025-12-05 23:38:46.919041 | TASK [local-log-download : Create download script]
2025-12-05 23:38:47.280899 | localhost -> localhost | changed
2025-12-05 23:38:47.291484 | 
2025-12-05 23:38:47.291556 | TASK [Register quick-download link]
2025-12-05 23:38:47.309359 | localhost | ok
2025-12-05 23:38:47.348453 | 
2025-12-05 23:38:47.348529 | PLAY [logserver.rdoproject.org]
2025-12-05 23:38:47.357785 | 
2025-12-05 23:38:47.357865 | TASK [Set zuul-log-path fact]
2025-12-05 23:38:47.373867 | logserver.rdoproject.org | ok
2025-12-05 23:38:47.382732 | 
2025-12-05 23:38:47.382798 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-12-05 23:38:47.408595 | logserver.rdoproject.org | ok
2025-12-05 23:38:47.414153 | 
2025-12-05 23:38:47.414220 | TASK [upload-logs : Create log directories]
2025-12-05 23:38:48.944008 | logserver.rdoproject.org | changed
2025-12-05 23:38:48.947786 | 
2025-12-05 23:38:48.947864 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-12-05 23:38:49.214863 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.004705
2025-12-05 23:38:49.219513 | 
2025-12-05 23:38:49.219587 | TASK [upload-logs : Upload logs to log server]
2025-12-05 23:38:50.791293 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-12-05 23:38:50.794429 | 
2025-12-05 23:38:50.794494 | LOOP [upload-logs : Compress console log and json output]
2025-12-05 23:38:50.846347 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-05 23:38:50.857360 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-05 23:38:50.862726 | 
2025-12-05 23:38:50.862808 | LOOP [upload-logs : Upload compressed console log and json output]
2025-12-05 23:38:50.901518 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-05 23:38:50.901705 | 
2025-12-05 23:38:50.906285 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-05 23:38:50.912033 | 
2025-12-05 23:38:50.912101 | LOOP [upload-logs : Upload console log and json output]