2025-12-01 11:00:24.888466 | Job console starting...
2025-12-01 11:00:25.105713 | Updating repositories
2025-12-01 11:00:25.877797 | Preparing job workspace
2025-12-01 11:00:34.956831 | Running Ansible setup...
2025-12-01 11:00:39.248053 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-12-01 11:00:40.036399 | 
2025-12-01 11:00:40.036504 | PLAY [localhost]
2025-12-01 11:00:40.056540 | 
2025-12-01 11:00:40.056623 | TASK [Gathering Facts]
2025-12-01 11:00:41.390045 | localhost | ok
2025-12-01 11:00:41.416411 | 
2025-12-01 11:00:41.416516 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-12-01 11:00:42.194139 | localhost -> localhost | changed
2025-12-01 11:00:42.199688 | 
2025-12-01 11:00:42.199759 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-12-01 11:00:43.671246 | localhost -> localhost | changed
2025-12-01 11:00:43.683298 | 
2025-12-01 11:00:43.683383 | TASK [Setup log path fact]
2025-12-01 11:00:43.787527 | localhost | ok
2025-12-01 11:00:43.808538 | 
2025-12-01 11:00:43.808628 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-12-01 11:00:43.869735 | localhost | ok
2025-12-01 11:00:43.883271 | 
2025-12-01 11:00:43.883345 | TASK [emit-job-header : Print job information]
2025-12-01 11:00:43.972284 | # Job Information
2025-12-01 11:00:43.972424 | Ansible Version: 2.15.12
2025-12-01 11:00:43.972452 | Job: cifmw-molecule-cert_manager
2025-12-01 11:00:43.972474 | Pipeline: github-check
2025-12-01 11:00:43.972494 | Executor: ibm-bm4-ze.softwarefactory-project.io
2025-12-01 11:00:43.972512 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3505
2025-12-01 11:00:43.972530 | Log URL (when completed): https://logserver.rdoproject.org/172/rdoproject.org/172dee43876544e5b18d0d0eeab7bf9f/
2025-12-01 11:00:43.972548 | Event ID: c7559b00-cea4-11f0-8bbe-0b02e3b2e2af
2025-12-01 11:00:43.976238 | 
2025-12-01 11:00:43.976302 | LOOP [emit-job-header : Print node information]
2025-12-01 11:00:44.184077 | localhost | ok:
2025-12-01 11:00:44.184341 | localhost | # Node Information
2025-12-01 11:00:44.184378 | localhost | Inventory Hostname: controller
2025-12-01 11:00:44.184404 | localhost | Hostname: np0005540965
2025-12-01 11:00:44.184426 | localhost | Username: zuul
2025-12-01 11:00:44.184447 | localhost | Distro: CentOS 9
2025-12-01 11:00:44.184466 | localhost | Provider: ibm-bm4-nodepool
2025-12-01 11:00:44.184483 | localhost | Region: regionOne
2025-12-01 11:00:44.184501 | localhost | Label: centos-9-stream-crc-2-48-0-xxl-ibm
2025-12-01 11:00:44.184517 | localhost | Product Name: OpenStack Compute
2025-12-01 11:00:44.184534 | localhost | Interface IP: 192.168.26.192
2025-12-01 11:00:44.249014 | 
2025-12-01 11:00:44.249081 | PLAY [all]
2025-12-01 11:00:44.262136 | 
2025-12-01 11:00:44.262203 | TASK [Gather network facts]
2025-12-01 11:00:44.797318 | controller | ok
2025-12-01 11:00:44.819555 | 
2025-12-01 11:00:44.819683 | TASK [include_role : start-zuul-console]
2025-12-01 11:00:44.872669 | controller | ok
2025-12-01 11:00:44.894009 | 
2025-12-01 11:00:44.894091 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-12-01 11:00:45.386107 | controller | ok
2025-12-01 11:00:45.421558 | 
2025-12-01 11:00:45.421670 | TASK [include_role : add-build-sshkey]
2025-12-01 11:00:45.505619 | controller | ok
2025-12-01 11:00:45.551988 | 
2025-12-01 11:00:45.558986 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-12-01 11:00:46.345433 | controller -> localhost | ok
2025-12-01 11:00:46.351147 | 
2025-12-01 11:00:46.351233 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-12-01 11:00:46.430722 | controller | ok
2025-12-01 11:00:46.466498 | controller | included: /var/lib/zuul/builds/172dee43876544e5b18d0d0eeab7bf9f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-12-01 11:00:46.482160 | 
2025-12-01 11:00:46.482249 | TASK [add-build-sshkey : Create Temp SSH key]
2025-12-01 11:00:48.099642 | controller -> localhost | Generating public/private rsa key pair.
2025-12-01 11:00:48.099864 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/172dee43876544e5b18d0d0eeab7bf9f/work/172dee43876544e5b18d0d0eeab7bf9f_id_rsa.
2025-12-01 11:00:48.099899 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/172dee43876544e5b18d0d0eeab7bf9f/work/172dee43876544e5b18d0d0eeab7bf9f_id_rsa.pub.
2025-12-01 11:00:48.099944 | controller -> localhost | The key fingerprint is:
2025-12-01 11:00:48.099968 | controller -> localhost | SHA256:Ol/tM+zrOLumUlPAQXmgretPszYeqEfhX1oGRqtnGUo zuul-build-sshkey
2025-12-01 11:00:48.099988 | controller -> localhost | The key's randomart image is:
2025-12-01 11:00:48.100006 | controller -> localhost | +---[RSA 3072]----+
2025-12-01 11:00:48.100025 | controller -> localhost | |       o++       |
2025-12-01 11:00:48.100043 | controller -> localhost | |       o= .      |
2025-12-01 11:00:48.100062 | controller -> localhost | |      ...+       |
2025-12-01 11:00:48.100080 | controller -> localhost | |      E.= .      |
2025-12-01 11:00:48.100098 | controller -> localhost | |     o.=S=       |
2025-12-01 11:00:48.100116 | controller -> localhost | |      =+* +.     |
2025-12-01 11:00:48.100133 | controller -> localhost | |     .==+*...    |
2025-12-01 11:00:48.100151 | controller -> localhost | |     oo+==oo+    |
2025-12-01 11:00:48.100168 | controller -> localhost | |    ...=*++*=+   |
2025-12-01 11:00:48.100186 | controller -> localhost | +----[SHA256]-----+
2025-12-01 11:00:48.100229 | controller -> localhost | ok: Runtime: 0:00:00.211129
2025-12-01 11:00:48.106186 | 
2025-12-01 11:00:48.106252 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-12-01 11:00:48.168816 | controller | ok
2025-12-01 11:00:48.177896 | controller | included: /var/lib/zuul/builds/172dee43876544e5b18d0d0eeab7bf9f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-12-01 11:00:48.189724 | 
2025-12-01 11:00:48.189789 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-12-01 11:00:48.233577 | controller | skipping: Conditional result was False
2025-12-01 11:00:48.238442 | 
2025-12-01 11:00:48.238510 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-12-01 11:00:48.852522 | controller | changed
2025-12-01 11:00:48.862169 | 
2025-12-01 11:00:48.862241 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-12-01 11:00:49.126845 | controller | ok
2025-12-01 11:00:49.135003 | 
2025-12-01 11:00:49.135085 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-12-01 11:00:50.693853 | controller | changed
2025-12-01 11:00:50.705880 | 
2025-12-01 11:00:50.706021 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-12-01 11:00:52.139155 | controller | changed
2025-12-01 11:00:52.146626 | 
2025-12-01 11:00:52.146704 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-12-01 11:00:52.250260 | controller | skipping: Conditional result was False
2025-12-01 11:00:52.257634 | 
2025-12-01 11:00:52.257727 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-12-01 11:00:52.963693 | controller -> localhost | changed
2025-12-01 11:00:52.977934 | 
2025-12-01 11:00:52.978024 | TASK [add-build-sshkey : Add back temp key]
2025-12-01 11:00:53.665129 | controller -> localhost | Identity added: /var/lib/zuul/builds/172dee43876544e5b18d0d0eeab7bf9f/work/172dee43876544e5b18d0d0eeab7bf9f_id_rsa (zuul-build-sshkey)
2025-12-01 11:00:53.665263 | controller -> localhost | ok: Runtime: 0:00:00.026803
2025-12-01 11:00:53.670235 | 
2025-12-01 11:00:53.670301 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-12-01 11:00:54.252819 | controller | ok
2025-12-01 11:00:54.258013 | 
2025-12-01 11:00:54.258089 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-12-01 11:00:54.386623 | controller | skipping: Conditional result was False
2025-12-01 11:00:54.396849 | 
2025-12-01 11:00:54.396957 | TASK [include_role : validate-host]
2025-12-01 11:00:54.494792 | controller | ok
2025-12-01 11:00:54.559637 | 
2025-12-01 11:00:54.559742 | TASK [validate-host : Define zuul_info_dir fact]
2025-12-01 11:00:54.659849 | controller | ok
2025-12-01 11:00:54.664667 | 
2025-12-01 11:00:54.664732 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-12-01 11:00:55.140895 | controller -> localhost | ok
2025-12-01 11:00:55.146771 | 
2025-12-01 11:00:55.146852 | TASK [validate-host : Collect information about the host]
2025-12-01 11:00:55.882555 | controller | ok
2025-12-01 11:00:55.914390 | 
2025-12-01 11:00:55.914509 | TASK [validate-host : Sanitize hostname]
2025-12-01 11:00:56.141863 | controller | ok
2025-12-01 11:00:56.154549 | 
2025-12-01 11:00:56.154639 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-12-01 11:00:57.641084 | controller -> localhost | changed
2025-12-01 11:00:57.647307 | 
2025-12-01 11:00:57.647375 | TASK [validate-host : Collect information about zuul worker]
2025-12-01 11:00:58.110529 | controller | ok
2025-12-01 11:00:58.140251 | 
2025-12-01 11:00:58.140454 | TASK [validate-host : Write out all zuul information for each host]
2025-12-01 11:00:59.436831 | controller -> localhost | changed
2025-12-01 11:00:59.446792 | 
2025-12-01 11:00:59.446873 | TASK [include_role : prepare-workspace-openshift]
2025-12-01 11:00:59.506576 | controller | skipping: Conditional result was False
2025-12-01 11:00:59.512206 | 
2025-12-01 11:00:59.512280 | TASK [include_role : remove-zuul-sshkey]
2025-12-01 11:00:59.589265 | controller | skipping: Conditional result was False
2025-12-01 11:00:59.594656 | 
2025-12-01 11:00:59.594732 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-12-01 11:00:59.933782 | controller | ok: "logs"
2025-12-01 11:00:59.934023 | controller | ok: All items complete
2025-12-01 11:00:59.934061 | 
2025-12-01 11:01:00.177237 | controller | ok: "artifacts"
2025-12-01 11:01:00.423958 | controller | ok: "docs"
2025-12-01 11:01:00.439129 | 
2025-12-01 11:01:00.439202 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-12-01 11:01:00.809798 | controller | changed: "logs"
2025-12-01 11:01:01.067656 | controller | changed: "artifacts"
2025-12-01 11:01:01.326002 | controller | changed: "docs"
2025-12-01 11:01:01.382164 | 
2025-12-01 11:01:01.382234 | PLAY RECAP
2025-12-01 11:01:01.382278 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-12-01 11:01:01.382306 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-12-01 11:01:01.382326 | 
2025-12-01 11:01:01.977692 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-12-01 11:01:01.979191 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-12-01 11:01:04.449637 | 
2025-12-01 11:01:04.449740 | PLAY [all]
2025-12-01 11:01:04.520657 | 
2025-12-01 11:01:04.520779 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-12-01 11:01:04.747116 | controller | ok
2025-12-01 11:01:04.752355 | 
2025-12-01 11:01:04.752436 | TASK [mirror-info-fork : Create /etc/ci]
2025-12-01 11:01:05.514062 | controller | changed
2025-12-01 11:01:05.537784 | 
2025-12-01 11:01:05.537880 | TASK [mirror-info-fork : Install ci_mirror script]
2025-12-01 11:01:07.955148 | controller | changed
2025-12-01 11:01:08.018956 | 
2025-12-01 11:01:08.019308 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-12-01 11:01:09.601863 | controller | changed:
2025-12-01 11:01:09.602154 | controller | {
2025-12-01 11:01:09.602191 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-12-01 11:01:09.602222 | controller | }
2025-12-01 11:01:09.929684 | controller | changed:
2025-12-01 11:01:09.929773 | controller | {
2025-12-01 11:01:09.929802 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-12-01 11:01:09.929827 | controller | }
2025-12-01 11:01:10.251225 | controller | changed:
2025-12-01 11:01:10.251307 | controller | {
2025-12-01 11:01:10.251336 | 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-01 11:01:10.251357 | controller | }
2025-12-01 11:01:10.558370 | controller | changed:
2025-12-01 11:01:10.558498 | controller | {
2025-12-01 11:01:10.558530 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-12-01 11:01:10.559399 | controller | }
2025-12-01 11:01:10.857346 | controller | changed:
2025-12-01 11:01:10.857433 | controller | {
2025-12-01 11:01:10.857462 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-12-01 11:01:10.857485 | controller | }
2025-12-01 11:01:11.208892 | controller | changed:
2025-12-01 11:01:11.208993 | controller | {
2025-12-01 11:01:11.209022 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-12-01 11:01:11.209044 | controller | }
2025-12-01 11:01:11.529445 | controller | changed:
2025-12-01 11:01:11.531965 | controller | {
2025-12-01 11:01:11.532052 | 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-01 11:01:11.532084 | controller | }
2025-12-01 11:01:11.777892 | controller | changed:
2025-12-01 11:01:11.778189 | controller | {
2025-12-01 11:01:11.778226 | 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-01 11:01:11.778248 | controller | }
2025-12-01 11:01:12.079734 | controller | changed:
2025-12-01 11:01:12.079863 | controller | {
2025-12-01 11:01:12.079890 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-12-01 11:01:12.079929 | controller | }
2025-12-01 11:01:12.379884 | controller | changed:
2025-12-01 11:01:12.380026 | controller | {
2025-12-01 11:01:12.380055 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-12-01 11:01:12.380077 | controller | }
2025-12-01 11:01:12.673716 | controller | changed:
2025-12-01 11:01:12.673854 | controller | {
2025-12-01 11:01:12.673882 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-12-01 11:01:12.673923 | controller | }
2025-12-01 11:01:12.975122 | controller | changed:
2025-12-01 11:01:12.977952 | controller | {
2025-12-01 11:01:12.978030 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-12-01 11:01:12.978061 | controller | }
2025-12-01 11:01:13.291822 | controller | changed:
2025-12-01 11:01:13.292000 | controller | {
2025-12-01 11:01:13.292029 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-12-01 11:01:13.292053 | controller | }
2025-12-01 11:01:13.616566 | controller | changed:
2025-12-01 11:01:13.616730 | controller | {
2025-12-01 11:01:13.616758 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-12-01 11:01:13.616779 | controller | }
2025-12-01 11:01:13.929404 | controller | changed:
2025-12-01 11:01:13.929535 | controller | {
2025-12-01 11:01:13.929572 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-12-01 11:01:13.929596 | controller | }
2025-12-01 11:01:14.229717 | controller | changed:
2025-12-01 11:01:14.230402 | controller | {
2025-12-01 11:01:14.230468 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-12-01 11:01:14.230494 | controller | }
2025-12-01 11:01:14.558842 | controller | changed:
2025-12-01 11:01:14.559025 | controller | {
2025-12-01 11:01:14.559056 | 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-01 11:01:14.559080 | controller | }
2025-12-01 11:01:14.862734 | controller | changed:
2025-12-01 11:01:14.862846 | controller | {
2025-12-01 11:01:14.862877 | 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-01 11:01:14.862930 | controller | }
2025-12-01 11:01:15.183315 | controller | changed:
2025-12-01 11:01:15.183445 | controller | {
2025-12-01 11:01:15.183474 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-12-01 11:01:15.183495 | controller | }
2025-12-01 11:01:15.505370 | controller | changed:
2025-12-01 11:01:15.505459 | controller | {
2025-12-01 11:01:15.505489 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-12-01 11:01:15.505512 | controller | }
2025-12-01 11:01:15.835823 | controller | changed:
2025-12-01 11:01:15.835929 | controller | {
2025-12-01 11:01:15.835964 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-12-01 11:01:15.835988 | controller | }
2025-12-01 11:01:16.170445 | controller | changed:
2025-12-01 11:01:16.170635 | controller | {
2025-12-01 11:01:16.170673 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-12-01 11:01:16.170698 | controller | }
2025-12-01 11:01:16.488495 | controller | changed:
2025-12-01 11:01:16.488606 | controller | {
2025-12-01 11:01:16.488635 | 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-01 11:01:16.488658 | controller | }
2025-12-01 11:01:16.796226 | controller | changed:
2025-12-01 11:01:16.796369 | controller | {
2025-12-01 11:01:16.796401 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-12-01 11:01:16.796423 | controller | }
2025-12-01 11:01:17.129222 | controller | changed:
2025-12-01 11:01:17.129304 | controller | {
2025-12-01 11:01:17.129332 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-12-01 11:01:17.129353 | controller | }
2025-12-01 11:01:17.443326 | controller | changed:
2025-12-01 11:01:17.443417 | controller | {
2025-12-01 11:01:17.443444 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-12-01 11:01:17.443465 | controller | }
2025-12-01 11:01:17.471732 | 
2025-12-01 11:01:17.471804 | TASK [Set timezone to UTC]
2025-12-01 11:01:18.284471 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-12-01 11:01:18.299619 | 
2025-12-01 11:01:18.299708 | TASK [Create nodepool directory]
2025-12-01 11:01:18.646051 | controller | changed
2025-12-01 11:01:18.660441 | 
2025-12-01 11:01:18.662071 | TASK [Create nodepool sub_nodes file]
2025-12-01 11:01:19.947971 | controller | changed
2025-12-01 11:01:19.964033 | 
2025-12-01 11:01:19.964135 | TASK [Create nodepool sub_nodes_private file]
2025-12-01 11:01:21.225757 | controller | changed
2025-12-01 11:01:21.230935 | 
2025-12-01 11:01:21.230993 | LOOP [Populate nodepool sub_nodes file]
2025-12-01 11:01:21.291822 | 
2025-12-01 11:01:21.297112 | LOOP [Populate nodepool sub_nodes_private file]
2025-12-01 11:01:21.429440 | 
2025-12-01 11:01:21.429724 | TASK [Create nodepool primary file]
2025-12-01 11:01:21.505169 | controller | skipping: Conditional result was False
2025-12-01 11:01:21.511252 | 
2025-12-01 11:01:21.511347 | TASK [Create nodepool node_private for this node]
2025-12-01 11:01:22.818370 | controller | changed
2025-12-01 11:01:22.824011 | 
2025-12-01 11:01:22.824077 | LOOP [Copy ssh keys to nodepool directory]
2025-12-01 11:01:23.805487 | controller | ok: Item: id_rsa Runtime: 0:00:00.004422
2025-12-01 11:01:23.805690 | 
2025-12-01 11:01:24.084315 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.004157
2025-12-01 11:01:24.099032 | 
2025-12-01 11:01:24.099128 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-12-01 11:01:25.298062 | controller | changed
2025-12-01 11:01:25.322298 | 
2025-12-01 11:01:25.322384 | TASK [Validate sudoers config after edits]
2025-12-01 11:01:25.772715 | controller | /etc/sudoers: parsed OK
2025-12-01 11:01:25.772782 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-12-01 11:01:25.772792 | controller | /etc/sudoers.d/zuul: parsed OK
2025-12-01 11:01:25.772798 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-12-01 11:01:25.904431 | controller | ok: Runtime: 0:00:00.006026
2025-12-01 11:01:25.912407 | 
2025-12-01 11:01:25.912481 | TASK [Show the environment passed in to job shell scripts]
2025-12-01 11:01:26.456994 | controller | SHELL=/bin/bash
2025-12-01 11:01:26.457046 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-12-01 11:01:26.457057 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-12-01 11:01:26.457063 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/23/3523/9dca7030e1689ab350d0cc525f1944d26503b3cc^openstack-k8s-operators/ci-framework:main:refs/changes/05/3505/4fd568413e9fbcb9b5c62cc50f536ea9eafcd481
2025-12-01 11:01:26.457071 | controller | PWD=/home/zuul
2025-12-01 11:01:26.457076 | controller | ZUUL_PIPELINE=github-check
2025-12-01 11:01:26.457081 | controller | LOGNAME=zuul
2025-12-01 11:01:26.457086 | controller | XDG_SESSION_TYPE=tty
2025-12-01 11:01:26.457091 | controller | _=/usr/bin/env
2025-12-01 11:01:26.457133 | controller | MOTD_SHOWN=pam
2025-12-01 11:01:26.457141 | controller | HOME=/home/zuul
2025-12-01 11:01:26.457147 | controller | LANG=en_US.UTF-8
2025-12-01 11:01:26.457152 | controller | SSH_CONNECTION=192.168.26.12 52000 192.168.26.192 22
2025-12-01 11:01:26.457157 | 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-01 11:01:26.457162 | controller | ZUUL_CHANGE_IDS=3523,9dca7030e1689ab350d0cc525f1944d26503b3cc 3505,4fd568413e9fbcb9b5c62cc50f536ea9eafcd481
2025-12-01 11:01:26.457167 | controller | WORKSPACE=/home/zuul/workspace
2025-12-01 11:01:26.457172 | controller | XDG_SESSION_CLASS=user
2025-12-01 11:01:26.457178 | controller | SELINUX_ROLE_REQUESTED=
2025-12-01 11:01:26.457183 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-12-01 11:01:26.457188 | controller | USER=zuul
2025-12-01 11:01:26.457193 | controller | ZUUL_VOTING=True
2025-12-01 11:01:26.457198 | controller | BUILD_TIMEOUT=1800000
2025-12-01 11:01:26.457203 | controller | SELINUX_USE_CURRENT_RANGE=
2025-12-01 11:01:26.457208 | controller | SHLVL=1
2025-12-01 11:01:26.457213 | controller | ZUUL_PATCHSET=4fd568413e9fbcb9b5c62cc50f536ea9eafcd481
2025-12-01 11:01:26.457218 | controller | XDG_SESSION_ID=1
2025-12-01 11:01:26.457224 | controller | ZUUL_BRANCH=main
2025-12-01 11:01:26.457229 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-12-01 11:01:26.457234 | controller | SSH_CLIENT=192.168.26.12 52000 22
2025-12-01 11:01:26.457239 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-12-01 11:01:26.457244 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-12-01 11:01:26.457249 | controller | which_declare=declare -f
2025-12-01 11:01:26.457254 | 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-01 11:01:26.457259 | controller | SELINUX_LEVEL_REQUESTED=
2025-12-01 11:01:26.457265 | controller | ZUUL_CHANGE=3505
2025-12-01 11:01:26.457270 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-12-01 11:01:26.457275 | controller | ZUUL_UUID=172dee43876544e5b18d0d0eeab7bf9f
2025-12-01 11:01:26.457282 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-12-01 11:01:26.457287 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-12-01 11:01:26.457293 | controller | }
2025-12-01 11:01:26.650864 | controller | ok: Runtime: 0:00:00.006301
2025-12-01 11:01:26.657051 | 
2025-12-01 11:01:26.657118 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-12-01 11:01:26.714185 | controller | skipping: Conditional result was False
2025-12-01 11:01:26.719652 | 
2025-12-01 11:01:26.719726 | TASK [Symlink /home/zuul-worker/workspace]
2025-12-01 11:01:27.305010 | controller | skipping: Conditional result was False
2025-12-01 11:01:27.314263 | 
2025-12-01 11:01:27.314340 | TASK [Ensure legacy workspace directory]
2025-12-01 11:01:27.701177 | controller | changed
2025-12-01 11:01:27.770396 | 
2025-12-01 11:01:27.770491 | PLAY RECAP
2025-12-01 11:01:27.770542 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-12-01 11:01:27.770583 | 
2025-12-01 11:01:28.197663 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-12-01 11:01:28.198699 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-12-01 11:01:30.672929 | 
2025-12-01 11:01:30.673050 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-12-01 11:01:30.761854 | 
2025-12-01 11:01:30.767529 | TASK [Create zuul-output directory]
2025-12-01 11:01:31.638066 | controller | changed
2025-12-01 11:01:31.649751 | 
2025-12-01 11:01:31.649853 | TASK [Slurp Zuul inventory test]
2025-12-01 11:01:32.703072 | controller -> localhost | ok
2025-12-01 11:01:32.710164 | 
2025-12-01 11:01:32.710259 | TASK [Save zuul inventory]
2025-12-01 11:01:34.827483 | controller | changed
2025-12-01 11:01:34.836631 | 
2025-12-01 11:01:34.836724 | TASK [Save zuul vars without the change_message]
2025-12-01 11:01:36.322009 | controller | changed
2025-12-01 11:01:36.398529 | 
2025-12-01 11:01:36.398621 | PLAY RECAP
2025-12-01 11:01:36.398675 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-12-01 11:01:36.398700 | 
2025-12-01 11:01:36.845992 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-12-01 11:01:36.846689 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-12-01 11:01:38.941865 | 
2025-12-01 11:01:38.942017 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2025-12-01 11:01:39.030138 | 
2025-12-01 11:01:39.030267 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2025-12-01 11:01:39.145687 | controller | ok
2025-12-01 11:01:39.195684 | 
2025-12-01 11:01:39.195815 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-12-01 11:01:39.306704 | controller | skipping: Conditional result was False
2025-12-01 11:01:39.319185 | 
2025-12-01 11:01:39.319283 | TASK [mirror-info-fork : Create /etc/ci]
2025-12-01 11:01:40.016151 | controller | ok
2025-12-01 11:01:40.022156 | 
2025-12-01 11:01:40.022241 | TASK [mirror-info-fork : Install ci_mirror script]
2025-12-01 11:01:41.751970 | controller | ok
2025-12-01 11:01:41.792670 | 
2025-12-01 11:01:41.792826 | TASK [Prepare workspace]
2025-12-01 11:01:41.862734 | controller | ok
2025-12-01 11:01:41.964146 | 
2025-12-01 11:01:41.964271 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-12-01 11:01:42.817855 | controller | ok
2025-12-01 11:01:42.826317 | 
2025-12-01 11:01:42.826388 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-12-01 11:01:48.614424 | controller | Output suppressed because no_log was given
2025-12-01 11:01:48.665702 | 
2025-12-01 11:01:48.665819 | LOOP [Create zuul-output directory]
2025-12-01 11:01:49.022574 | controller | changed: "/home/zuul/ci-framework-data/logs"
2025-12-01 11:01:49.271152 | controller | ok: "/home/zuul/zuul-output/logs"
2025-12-01 11:01:49.283233 | 
2025-12-01 11:01:49.283331 | TASK [Install required packages]
2025-12-01 11:02:08.447685 | controller | ok: Nothing to do
2025-12-01 11:02:08.455565 | 
2025-12-01 11:02:08.455642 | TASK [Install venv]
2025-12-01 11:03:09.393252 | controller | changed
2025-12-01 11:03:09.418119 | 
2025-12-01 11:03:09.418199 | PLAY RECAP
2025-12-01 11:03:09.418242 | controller | ok: 7 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-12-01 11:03:09.418265 | 
2025-12-01 11:03:09.502092 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-12-01 11:03:09.502890 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-12-01 11:03:10.039782 | 
2025-12-01 11:03:10.039896 | PLAY [Run ci/playbooks/molecule-test.yml]
2025-12-01 11:03:10.061002 | 
2025-12-01 11:03:10.061072 | TASK [Gather required facts]
2025-12-01 11:03:10.678251 | controller | ok
2025-12-01 11:03:10.683824 | 
2025-12-01 11:03:10.683933 | TASK [Load environment var if instructed to]
2025-12-01 11:03:10.708294 | controller | skipping: Conditional result was False
2025-12-01 11:03:10.714298 | 
2025-12-01 11:03:10.714380 | TASK [Ensure group_vars dir exists]
2025-12-01 11:03:11.131583 | controller | ok
2025-12-01 11:03:11.137373 | 
2025-12-01 11:03:11.137469 | TASK [Print related variables]
2025-12-01 11:03:11.167446 | controller | ok:
2025-12-01 11:03:11.167688 | controller | mol_config_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config_local.yml
2025-12-01 11:03:11.167721 | controller | roles_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/cert_manager
2025-12-01 11:03:11.172842 | 
2025-12-01 11:03:11.172929 | TASK [Run molecule]
2025-12-01 11:03:12.234009 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2025-12-01 11:03:12.304867 | controller | INFO     Performing prerun with role_name_check=0...
2025-12-01 11:03:29.675882 | controller | WARNING  Another version of '@NAMESPACE@.@NAME@' 3.1.4 was found installed in /home/zuul/.cache/ansible-compat/d7ac15/collections/ansible_collections, only the first one will be used, 3.1.4 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-01 11:03:29.676327 | controller | WARNING  Another version of 'ansible.netcommon' 7.1.0 was found installed in /home/zuul/.cache/ansible-compat/d7ac15/collections/ansible_collections, only the first one will be used, 7.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-01 11:03:29.676795 | controller | WARNING  Another version of 'ansible.posix' 1.6.2 was found installed in /home/zuul/.cache/ansible-compat/d7ac15/collections/ansible_collections, only the first one will be used, 1.6.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-01 11:03:29.677251 | controller | WARNING  Another version of 'ansible.utils' 5.1.2 was found installed in /home/zuul/.cache/ansible-compat/d7ac15/collections/ansible_collections, only the first one will be used, 5.1.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-01 11:03:29.677715 | controller | WARNING  Another version of 'cifmw.general' 1.0.0+3fae496b was found installed in /home/zuul/.cache/ansible-compat/d7ac15/collections/ansible_collections, only the first one will be used, 1.0.0+3fae496b (/home/zuul/.ansible/collections/ansible_collections).
2025-12-01 11:03:29.678164 | controller | WARNING  Another version of 'cisco.ios' 9.0.3 was found installed in /home/zuul/.cache/ansible-compat/d7ac15/collections/ansible_collections, only the first one will be used, 9.0.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-01 11:03:29.678599 | controller | WARNING  Another version of 'community.crypto' 2.22.3 was found installed in /home/zuul/.cache/ansible-compat/d7ac15/collections/ansible_collections, only the first one will be used, 2.22.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-01 11:03:29.679039 | controller | WARNING  Another version of 'community.general' 10.0.1 was found installed in /home/zuul/.cache/ansible-compat/d7ac15/collections/ansible_collections, only the first one will be used, 10.0.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-01 11:03:29.679469 | controller | WARNING  Another version of 'community.libvirt' 1.3.0 was found installed in /home/zuul/.cache/ansible-compat/d7ac15/collections/ansible_collections, only the first one will be used, 1.3.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-01 11:03:29.679916 | controller | WARNING  Another version of 'community.okd' 4.0.0 was found installed in /home/zuul/.cache/ansible-compat/d7ac15/collections/ansible_collections, only the first one will be used, 4.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-01 11:03:29.680364 | controller | WARNING  Another version of 'containers.podman' 1.16.2 was found installed in /home/zuul/.cache/ansible-compat/d7ac15/collections/ansible_collections, only the first one will be used, 1.16.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-01 11:03:29.680833 | controller | WARNING  Another version of 'junipernetworks.junos' 9.1.0 was found installed in /home/zuul/.cache/ansible-compat/d7ac15/collections/ansible_collections, only the first one will be used, 9.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-01 11:03:29.681281 | controller | WARNING  Another version of 'kubernetes.core' 5.0.0 was found installed in /home/zuul/.cache/ansible-compat/d7ac15/collections/ansible_collections, only the first one will be used, 5.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-01 11:03:29.681724 | controller | WARNING  Another version of 'mellanox.onyx' 1.0.0 was found installed in /home/zuul/.cache/ansible-compat/d7ac15/collections/ansible_collections, only the first one will be used, 1.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-01 11:03:29.682181 | controller | WARNING  Another version of 'openstack.config_template' 2.1.1 was found installed in /home/zuul/.cache/ansible-compat/d7ac15/collections/ansible_collections, only the first one will be used, 2.1.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-01 11:03:29.691931 | controller | INFO     Running default > prepare
2025-12-01 11:03:30.417765 | controller |
2025-12-01 11:03:30.417916 | controller | PLAY [Prepare] *****************************************************************
2025-12-01 11:03:30.418052 | controller |
2025-12-01 11:03:30.418061 | controller | TASK [Gathering Facts] *********************************************************
2025-12-01 11:03:30.418070 | controller | Monday 01 December 2025  11:03:30 +0000 (0:00:00.014)       0:00:00.014 *******
2025-12-01 11:03:31.325147 | controller | ok: [instance]
2025-12-01 11:03:31.325189 | controller |
2025-12-01 11:03:31.325353 | controller | TASK [test_deps : Gather facts if they don't exist] ****************************
2025-12-01 11:03:31.325471 | controller | Monday 01 December 2025  11:03:31 +0000 (0:00:00.908)       0:00:00.922 *******
2025-12-01 11:03:31.345505 | controller | skipping: [instance]
2025-12-01 11:03:31.345531 | controller |
2025-12-01 11:03:31.345673 | controller | TASK [test_deps : Gather variables for each operating system] ******************
2025-12-01 11:03:31.345786 | controller | Monday 01 December 2025  11:03:31 +0000 (0:00:00.020)       0:00:00.943 *******
2025-12-01 11:03:31.390927 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/test_deps/vars/centos-9.yml)
2025-12-01 11:03:31.390968 | controller |
2025-12-01 11:03:31.391132 | controller | TASK [test_deps : Detect mirrors file] *****************************************
2025-12-01 11:03:31.391252 | controller | Monday 01 December 2025  11:03:31 +0000 (0:00:00.045)       0:00:00.988 *******
2025-12-01 11:03:31.696937 | controller | ok: [instance]
2025-12-01 11:03:31.696978 | controller |
2025-12-01 11:03:31.697113 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] ***********
2025-12-01 11:03:31.697224 | controller | Monday 01 December 2025  11:03:31 +0000 (0:00:00.305)       0:00:01.294 *******
2025-12-01 11:03:32.032598 | controller | ok: [instance]
2025-12-01 11:03:32.032651 | controller |
2025-12-01 11:03:32.032766 | controller | TASK [test_deps : Disable ubi 9 repos] *****************************************
2025-12-01 11:03:32.032867 | controller | Monday 01 December 2025  11:03:32 +0000 (0:00:00.335)       0:00:01.630 *******
2025-12-01 11:03:32.775459 | controller | ok: [instance] => (item=ubi-9-appstream)
2025-12-01 11:03:32.775552 | controller | ok: [instance] => (item=ubi-9-baseos)
2025-12-01 11:03:32.775692 | controller | ok: [instance] => (item=ubi-9-codeready-builder)
2025-12-01 11:03:32.775793 | controller |
2025-12-01 11:03:32.775900 | controller | TASK [Deploy repo-setup] *******************************************************
2025-12-01 11:03:32.776001 | controller | Monday 01 December 2025  11:03:32 +0000 (0:00:00.742)       0:00:02.373 *******
2025-12-01 11:03:32.840187 | controller |
2025-12-01 11:03:32.840263 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
2025-12-01 11:03:32.840432 | controller | Monday 01 December 2025  11:03:32 +0000 (0:00:00.064)       0:00:02.438 *******
2025-12-01 11:03:32.862853 | controller | skipping: [instance]
2025-12-01 11:03:32.862931 | controller |
2025-12-01 11:03:32.863114 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
2025-12-01 11:03:32.863221 | controller | Monday 01 December 2025  11:03:32 +0000 (0:00:00.022)       0:00:02.460 *******
2025-12-01 11:03:32.900891 | controller | skipping: [instance]
2025-12-01 11:03:32.900994 | controller |
2025-12-01 11:03:32.901148 | controller | TASK [repo_setup : Cleanup existing metadata] **********************************
2025-12-01 11:03:32.901278 | controller | Monday 01 December 2025  11:03:32 +0000 (0:00:00.037)       0:00:02.498 *******
2025-12-01 11:03:32.921497 | controller | skipping: [instance]
2025-12-01 11:03:32.921613 | controller |
2025-12-01 11:03:32.921773 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
2025-12-01 11:03:32.921908 | controller | Monday 01 December 2025  11:03:32 +0000 (0:00:00.020)       0:00:02.519 *******
2025-12-01 11:03:32.940765 | controller | skipping: [instance]
2025-12-01 11:03:32.940879 | controller |
2025-12-01 11:03:32.941004 | controller | TASK [repo_setup : Ensure directories are present] *****************************
2025-12-01 11:03:32.941148 | controller | Monday 01 December 2025  11:03:32 +0000 (0:00:00.019)       0:00:02.538 *******
2025-12-01 11:03:33.510939 | controller | changed: [instance] => (item=tmp)
2025-12-01 11:03:33.511042 | controller | changed: [instance] => (item=artifacts/repositories)
2025-12-01 11:03:33.511180 | controller | changed: [instance] => (item=venv/repo_setup)
2025-12-01 11:03:33.511294 | controller |
2025-12-01 11:03:33.511416 | controller | TASK [repo_setup : Make sure git-core package is installed] ********************
2025-12-01 11:03:33.511536 | controller | Monday 01 December 2025  11:03:33 +0000 (0:00:00.569)       0:00:03.108 *******
2025-12-01 11:03:34.368929 | controller | ok: [instance]
2025-12-01 11:03:34.368988 | controller |
2025-12-01 11:03:34.369253 | controller | TASK [repo_setup : Get repo-setup repository] **********************************
2025-12-01 11:03:35.498355 | controller | Monday 01 December 2025  11:03:34 +0000 (0:00:00.858)       0:00:03.966 *******
2025-12-01 11:03:35.498392 | controller | changed: [instance]
2025-12-01 11:03:43.634751 | controller |
2025-12-01 11:03:43.634796 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************
2025-12-01 11:03:43.634805 | controller | Monday 01 December 2025  11:03:35 +0000 (0:00:01.129)       0:00:05.095 *******
2025-12-01 11:03:43.634815 | controller | changed: [instance]
2025-12-01 11:03:43.634835 | controller |
2025-12-01 11:03:43.634978 | controller | TASK [repo_setup : Install repo-setup package] *********************************
2025-12-01 11:03:43.634997 | controller | Monday 01 December 2025  11:03:43 +0000 (0:00:08.135)       0:00:13.231 *******
2025-12-01 11:03:44.387733 | controller | changed: [instance]
2025-12-01 11:03:44.388619 | controller |
2025-12-01 11:03:44.388657 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
2025-12-01 11:03:44.388671 | controller | Monday 01 December 2025  11:03:44 +0000 (0:00:00.753)       0:00:13.985 *******
2025-12-01 11:03:44.411797 | controller | skipping: [instance]
2025-12-01 11:03:44.411859 | controller |
2025-12-01 11:03:44.411872 | controller | TASK [repo_setup : Run repo-setup] *********************************************
2025-12-01 11:03:44.411969 | controller | Monday 01 December 2025  11:03:44 +0000 (0:00:00.024)       0:00:14.009 *******
2025-12-01 11:03:45.282816 | controller | changed: [instance]
2025-12-01 11:03:45.282916 | controller |
2025-12-01 11:03:45.282929 | controller | TASK [repo_setup : Get component repo] *****************************************
2025-12-01 11:03:45.283133 | controller | Monday 01 December 2025  11:03:45 +0000 (0:00:00.870)       0:00:14.880 *******
2025-12-01 11:03:45.341217 | controller | skipping: [instance]
2025-12-01 11:03:45.383187 | controller |
2025-12-01 11:03:45.383206 | controller | TASK [repo_setup : Rename component repo] **************************************
2025-12-01 11:03:45.383214 | controller | Monday 01 December 2025  11:03:45 +0000 (0:00:00.058)       0:00:14.938 *******
2025-12-01 11:03:45.383223 | controller | skipping: [instance]
2025-12-01 11:03:45.422478 | controller |
2025-12-01 11:03:45.422494 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
2025-12-01 11:03:45.422502 | controller | Monday 01 December 2025  11:03:45 +0000 (0:00:00.042)       0:00:14.980 *******
2025-12-01 11:03:45.422517 | controller | skipping: [instance]
2025-12-01 11:03:45.992438 | controller |
2025-12-01 11:03:45.992486 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************
2025-12-01 11:03:45.992495 | controller | Monday 01 December 2025  11:03:45 +0000 (0:00:00.039)       0:00:15.020 *******
2025-12-01 11:03:45.992508 | controller | changed: [instance]
2025-12-01 11:03:46.481639 | controller |
2025-12-01 11:03:46.481676 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
2025-12-01 11:03:46.481685 | controller | Monday 01 December 2025  11:03:45 +0000 (0:00:00.569)       0:00:15.589 *******
2025-12-01 11:03:46.481697 | controller | changed: [instance]
2025-12-01 11:03:46.513776 | controller |
2025-12-01 11:03:46.513826 | controller | TASK [repo_setup : Dump current-podified hash] *********************************
2025-12-01 11:03:46.513837 | controller | Monday 01 December 2025  11:03:46 +0000 (0:00:00.489)       0:00:16.078 *******
2025-12-01 11:03:46.513851 | controller | skipping: [instance]
2025-12-01 11:03:46.553254 | controller |
2025-12-01 11:03:46.553277 | controller | TASK [repo_setup : Slurp current podified hash] ********************************
2025-12-01 11:03:46.553286 | controller | Monday 01 December 2025  11:03:46 +0000 (0:00:00.032)       0:00:16.111 *******
2025-12-01 11:03:46.553310 | controller | skipping: [instance]
2025-12-01 11:03:46.588359 | controller |
2025-12-01 11:03:46.588382 | controller | TASK [repo_setup : Update the value of full_hash] ******************************
2025-12-01 11:03:46.588391 | controller | Monday 01 December 2025  11:03:46 +0000 (0:00:00.039)       0:00:16.150 *******
2025-12-01 11:03:46.588402 | controller | skipping: [instance]
2025-12-01 11:03:46.639768 | controller |
2025-12-01 11:03:46.639806 | controller | TASK [repo_setup : Export hashes facts for further use] ************************
2025-12-01 11:03:46.639816 | controller | Monday 01 December 2025  11:03:46 +0000 (0:00:00.035)       0:00:16.186 *******
2025-12-01 11:03:46.639828 | controller | ok: [instance]
2025-12-01 11:03:46.639861 | controller |
2025-12-01 11:03:46.639871 | controller | TASK [repo_setup : Create download directory] **********************************
2025-12-01 11:03:46.639969 | controller | Monday 01 December 2025  11:03:46 +0000 (0:00:00.051)       0:00:16.237 *******
2025-12-01 11:03:46.673191 | controller | skipping: [instance]
2025-12-01 11:03:46.712092 | controller |
2025-12-01 11:03:46.712111 | controller | TASK [repo_setup : Print the URL to request] ***********************************
2025-12-01 11:03:46.712119 | controller | Monday 01 December 2025  11:03:46 +0000 (0:00:00.033)       0:00:16.270 *******
2025-12-01 11:03:46.712127 | controller | skipping: [instance]
2025-12-01 11:03:46.712271 | controller |
2025-12-01 11:03:46.712410 | controller | TASK [Download the RPM] ********************************************************
2025-12-01 11:03:46.712543 | controller | Monday 01 December 2025  11:03:46 +0000 (0:00:00.039)       0:00:16.310 *******
2025-12-01 11:03:46.749757 | controller | skipping: [instance]
2025-12-01 11:03:46.749942 | controller |
2025-12-01 11:03:46.750136 | controller | TASK [repo_setup : Install RHOS Release tool] **********************************
2025-12-01 11:03:46.750302 | controller | Monday 01 December 2025  11:03:46 +0000 (0:00:00.037)       0:00:16.347 *******
2025-12-01 11:03:46.787495 | controller | skipping: [instance]
2025-12-01 11:03:46.787710 | controller |
2025-12-01 11:03:46.787872 | controller | TASK [repo_setup : Get rhos-release tool version] ******************************
2025-12-01 11:03:46.788009 | controller | Monday 01 December 2025  11:03:46 +0000 (0:00:00.037)       0:00:16.385 *******
2025-12-01 11:03:46.821538 | controller | skipping: [instance]
2025-12-01 11:03:46.821753 | controller |
2025-12-01 11:03:46.821920 | controller | TASK [repo_setup : Print rhos-release tool version] ****************************
2025-12-01 11:03:46.822074 | controller | Monday 01 December 2025  11:03:46 +0000 (0:00:00.034)       0:00:16.419 *******
2025-12-01 11:03:46.862869 | controller | skipping: [instance]
2025-12-01 11:03:46.863047 | controller |
2025-12-01 11:03:46.863203 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
2025-12-01 11:03:46.863343 | controller | Monday 01 December 2025  11:03:46 +0000 (0:00:00.041)       0:00:16.461 *******
2025-12-01 11:03:46.894445 | controller | skipping: [instance]
2025-12-01 11:03:46.894659 | controller |
2025-12-01 11:03:46.894841 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
2025-12-01 11:03:46.895022 | controller | Monday 01 December 2025  11:03:46 +0000 (0:00:00.031)       0:00:16.492 *******
2025-12-01 11:03:47.106550 | controller | ok: [instance]
2025-12-01 11:03:47.106807 | controller |
2025-12-01 11:03:47.106988 | controller | TASK [repo_setup : Use RDO proxy mirrors] **************************************
2025-12-01 11:03:47.107181 | controller | Monday 01 December 2025  11:03:47 +0000 (0:00:00.211)       0:00:16.704 *******
2025-12-01 11:03:47.347770 | controller | changed: [instance]
2025-12-01 11:03:47.347967 | controller |
2025-12-01 11:03:47.347994 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
2025-12-01 11:03:47.348006 | controller | Monday 01 December 2025  11:03:47 +0000 (0:00:00.240)       0:00:16.945 *******
2025-12-01 11:03:47.588710 | controller | changed: [instance]
2025-12-01 11:03:47.588767 | controller |
2025-12-01 11:03:47.588778 | controller | TASK [repo_setup : Check for gating.repo file on content provider] *************
2025-12-01 11:03:47.588857 | controller | Monday 01 December 2025  11:03:47 +0000 (0:00:00.241)       0:00:17.186 *******
2025-12-01 11:03:47.610156 | controller | skipping: [instance]
2025-12-01 11:03:47.631577 | controller |
2025-12-01 11:03:47.631610 | controller | TASK [repo_setup : Populate gating repo from content provider ip] **************
2025-12-01 11:03:47.631624 | controller | Monday 01 December 2025  11:03:47 +0000 (0:00:00.021)       0:00:17.207 *******
2025-12-01 11:03:47.631633 | controller | skipping: [instance]
2025-12-01 11:03:47.653766 | controller |
2025-12-01 11:03:47.653804 | controller | TASK [repo_setup : Check for DLRN repo at the destination] *********************
2025-12-01 11:03:47.653814 | controller | Monday 01 December 2025  11:03:47 +0000 (0:00:00.021)       0:00:17.229 *******
2025-12-01 11:03:47.653826 | controller | skipping: [instance]
2025-12-01 11:03:47.653901 | controller |
2025-12-01 11:03:47.653922 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
2025-12-01 11:03:47.653935 | controller | Monday 01 December 2025  11:03:47 +0000 (0:00:00.022)       0:00:17.251 *******
2025-12-01 11:03:47.677615 | controller | skipping: [instance]
2025-12-01 11:03:47.700673 | controller |
2025-12-01 11:03:47.700713 | controller | TASK [repo_setup : Check for DLRN component repo] ******************************
2025-12-01 11:03:47.700724 | controller | Monday 01 December 2025  11:03:47 +0000 (0:00:00.023)       0:00:17.275 *******
2025-12-01 11:03:47.700737 | controller | skipping: [instance]
2025-12-01 11:03:47.727345 | controller |
2025-12-01 11:03:47.727367 | controller | TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] ***
2025-12-01 11:03:47.727375 | controller | Monday 01 December 2025  11:03:47 +0000 (0:00:00.023)       0:00:17.298 *******
2025-12-01 11:03:47.727385 | controller | skipping: [instance]
2025-12-01 11:03:47.757739 | controller |
2025-12-01 11:03:47.757770 | controller | TASK [test_deps : Look for redhat-release rpm] *********************************
2025-12-01 11:03:47.757783 | controller | Monday 01 December 2025  11:03:47 +0000 (0:00:00.026)       0:00:17.325 *******
2025-12-01 11:03:47.757797 | controller | skipping: [instance]
2025-12-01 11:03:47.758057 | controller |
2025-12-01 11:03:47.758335 | controller | TASK [test_deps : Remove redhat-release] ***************************************
2025-12-01 11:03:47.758558 | controller | Monday 01 December 2025  11:03:47 +0000 (0:00:00.030)       0:00:17.355 *******
2025-12-01 11:03:47.790435 | controller | skipping: [instance]
2025-12-01 11:03:47.790723 | controller |
2025-12-01 11:03:47.790870 | controller | TASK [test_deps : Install centos-stream-release] *******************************
2025-12-01 11:03:47.791007 | controller | Monday 01 December 2025  11:03:47 +0000 (0:00:00.032)       0:00:17.388 *******
2025-12-01 11:03:47.822391 | controller | skipping: [instance]
2025-12-01 11:03:47.822706 | controller |
2025-12-01 11:03:47.822896 | controller | TASK [test_deps : Install additional packages] *********************************
2025-12-01 11:03:47.823054 | controller | Monday 01 December 2025  11:03:47 +0000 (0:00:00.031)       0:00:17.420 *******
2025-12-01 11:03:47.854119 | controller | skipping: [instance]
2025-12-01 11:03:47.854270 | controller |
2025-12-01 11:03:47.854412 | controller | TASK [test_deps : Install selinux python libs] *********************************
2025-12-01 11:03:47.854549 | controller | Monday 01 December 2025  11:03:47 +0000 (0:00:00.031)       0:00:17.452 *******
2025-12-01 11:04:45.317288 | controller | ok: [instance]
2025-12-01 11:04:46.591578 | controller |
2025-12-01 11:04:46.591636 | controller | TASK [test_deps : Install python yaml libs] ************************************
2025-12-01 11:04:46.591644 | controller | Monday 01 December 2025  11:04:45 +0000 (0:00:57.462)       0:01:14.914 *******
2025-12-01 11:04:46.591654 | controller | ok: [instance]
2025-12-01 11:04:46.626604 | controller |
2025-12-01 11:04:46.626638 | controller | TASK [test_deps : Install extra packages] **************************************
2025-12-01 11:04:46.626648 | controller | Monday 01 December 2025  11:04:46 +0000 (0:00:01.274)       0:01:16.189 *******
2025-12-01 11:04:46.626660 | controller | skipping: [instance]
2025-12-01 11:07:33.006342 | controller |
2025-12-01 11:07:33.006418 | controller | TASK [Ensure CRC is started] ***************************************************
2025-12-01 11:07:33.006431 | controller | Monday 01 December 2025  11:04:46 +0000 (0:00:00.035)       0:01:16.224 *******
2025-12-01 11:07:33.006448 | controller | changed: [instance]
2025-12-01 11:07:33.006518 | controller |
2025-12-01 11:07:33.006530 | controller | PLAY RECAP *********************************************************************
2025-12-01 11:07:33.006757 | controller | instance                   : ok=20   changed=10   unreachable=0    failed=0    skipped=30   rescued=0    ignored=0
2025-12-01 11:07:33.006824 | controller |
2025-12-01 11:07:33.006856 | controller | Monday 01 December 2025  11:07:33 +0000 (0:02:46.379)       0:04:02.603 *******
2025-12-01 11:07:33.006899 | controller | ===============================================================================
2025-12-01 11:07:33.007174 | controller | Ensure CRC is started ------------------------------------------------- 166.38s
2025-12-01 11:07:33.007197 | controller | test_deps : Install selinux python libs -------------------------------- 57.46s
2025-12-01 11:07:33.007238 | controller | repo_setup : Initialize python venv and install requirements ------------ 8.14s
2025-12-01 11:07:33.007244 | controller | test_deps : Install python yaml libs ------------------------------------ 1.27s
2025-12-01 11:07:33.007250 | controller | repo_setup : Get repo-setup repository ---------------------------------- 1.13s
2025-12-01 11:07:33.007419 | controller | Gathering Facts --------------------------------------------------------- 0.91s
2025-12-01 11:07:33.008704 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.87s
2025-12-01 11:07:33.008775 | controller | repo_setup : Make sure git-core package is installed -------------------- 0.86s
2025-12-01 11:07:33.090096 | controller | repo_setup : Install repo-setup package --------------------------------- 0.75s
2025-12-01 11:07:33.090125 | controller | test_deps : Disable ubi 9 repos ----------------------------------------- 0.74s
2025-12-01 11:07:33.090133 | controller | repo_setup : Ensure directories are present ----------------------------- 0.57s
2025-12-01 11:07:33.090139 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.57s
2025-12-01 11:07:33.090145 | controller | repo_setup : Dump full hash in delorean.repo.md5 file ------------------- 0.49s
2025-12-01 11:07:33.090150 | controller | test_deps : Disable ubi host subscription-manager integration ----------- 0.34s
2025-12-01 11:07:33.090156 | controller | test_deps : Detect mirrors file ----------------------------------------- 0.31s
2025-12-01 11:07:33.090161 | controller | repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist) --- 0.24s
2025-12-01 11:07:33.090168 | controller | repo_setup : Use RDO proxy mirrors -------------------------------------- 0.24s
2025-12-01 11:07:33.090174 | controller | repo_setup : Check for /etc/ci/mirror_info.sh --------------------------- 0.21s
2025-12-01 11:07:33.090179 | controller | Deploy repo-setup ------------------------------------------------------- 0.06s
2025-12-01 11:07:33.090185 | controller | repo_setup : Get component repo ----------------------------------------- 0.06s
2025-12-01 11:07:33.090195 | controller | INFO     Running default > converge
2025-12-01 11:07:33.559888 | controller |
2025-12-01 11:07:34.580290 | controller | PLAY [Converge] ****************************************************************
2025-12-01 11:07:34.580355 | controller |
2025-12-01 11:07:34.580363 | controller | TASK [Gathering Facts] *********************************************************
2025-12-01 11:07:34.580369 | controller | Monday 01 December 2025  11:07:33 +0000 (0:00:00.014)       0:00:00.014 *******
2025-12-01 11:07:34.580382 | controller | ok: [instance]
2025-12-01 11:07:34.619186 | controller |
2025-12-01 11:07:34.619222 | controller | TASK [Deploy cert-manager role] ************************************************
2025-12-01 11:07:34.619231 | controller | Monday 01 December 2025  11:07:34 +0000 (0:00:01.020)       0:00:01.035 *******
2025-12-01 11:07:34.619242 | controller |
2025-12-01 11:07:34.978039 | controller | TASK [cert_manager : Create role needed directories] ***************************
2025-12-01 11:07:34.978082 | controller | Monday 01 December 2025  11:07:34 +0000 (0:00:00.039)       0:00:01.075 *******
2025-12-01 11:07:34.978096 | controller | changed: [instance]
2025-12-01 11:07:35.830184 | controller |
2025-12-01 11:07:35.830233 | controller | TASK [cert_manager : Create the cifmw_cert_manager_operator_namespace namespace"] ***
2025-12-01 11:07:35.830242 | controller | Monday 01 December 2025  11:07:34 +0000 (0:00:00.358)       0:00:01.433 *******
2025-12-01 11:07:35.830255 | controller | changed: [instance]
2025-12-01 11:07:35.873290 | controller |
2025-12-01 11:07:35.873323 | controller | TASK [cert_manager : Install from Release Manifest] ****************************
2025-12-01 11:07:35.873332 | controller | Monday 01 December 2025  11:07:35 +0000 (0:00:00.851)       0:00:02.285 *******
2025-12-01 11:07:35.873344 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/cert_manager/tasks/release_manifest.yml for instance
2025-12-01 11:07:36.552713 | controller |
2025-12-01 11:07:36.552760 | controller | TASK [cert_manager : Download release manifests] *******************************
2025-12-01 11:07:36.552784 | controller | Monday 01 December 2025  11:07:35 +0000 (0:00:00.043)       0:00:02.329 *******
2025-12-01 11:07:36.552797 | controller | changed: [instance]
2025-12-01 11:07:36.553213 | controller |
2025-12-01 11:07:39.663384 | controller | TASK [cert_manager : Install cert-manager from release manifest] ***************
2025-12-01 11:07:39.663449 | controller | Monday 01 December 2025  11:07:36 +0000 (0:00:00.679)       0:00:03.008 *******
2025-12-01 11:07:39.663469 | controller | changed: [instance]
2025-12-01 11:07:39.705988 | controller |
2025-12-01 11:07:39.706055 | controller | TASK [cert_manager : Install from OLM Manifest] ********************************
2025-12-01 11:07:39.706065 | controller | Monday 01 December 2025  11:07:39 +0000 (0:00:03.109)       0:00:06.118 *******
2025-12-01 11:07:39.706079 | controller | skipping: [instance]
2025-12-01 11:07:40.510304 | controller |
2025-12-01 11:07:40.510380 | controller | TASK [cert_manager : Check for cert-manager namspeace existance] ***************
2025-12-01 11:07:40.510389 | controller | Monday 01 December 2025  11:07:39 +0000 (0:00:00.043)       0:00:06.161 *******
2025-12-01 11:07:40.510407 | controller | ok: [instance]
2025-12-01 11:07:40.510502 | controller |
2025-12-01 11:07:40.510571 | controller | TASK [cert_manager : Wait for cert-manager pods to be ready] *******************
2025-12-01 11:07:40.510620 | controller | Monday 01 December 2025  11:07:40 +0000 (0:00:00.804)       0:00:06.966 *******
2025-12-01 11:11:33.293623 | controller | ok: [instance] => (item=cainjector)
2025-12-01 11:11:33.521307 | controller | ok: [instance] => (item=webhook)
2025-12-01 11:11:33.521356 | controller | ok: [instance] => (item=cert-manager)
2025-12-01 11:11:33.521364 | controller |
2025-12-01 11:11:33.521371 | controller | TASK [cert_manager : Create $HOME/bin dir] *************************************
2025-12-01 11:11:33.521377 | controller | Monday 01 December 2025  11:11:33 +0000 (0:03:52.780)       0:03:59.746 *******
2025-12-01 11:11:33.521388 | controller | changed: [instance]
2025-12-01 11:11:35.121728 | controller |
2025-12-01 11:11:35.121763 | controller | TASK [cert_manager : Install cert-manager cmctl CLI] ***************************
2025-12-01 11:11:35.121771 | controller | Monday 01 December 2025  11:11:33 +0000 (0:00:00.229)       0:03:59.976 *******
2025-12-01 11:11:35.121782 | controller | changed: [instance]
2025-12-01 11:11:35.650826 | controller |
2025-12-01 11:11:35.650860 | controller | TASK [cert_manager : Verify cert_manager api] **********************************
2025-12-01 11:11:35.650868 | controller | Monday 01 December 2025  11:11:35 +0000 (0:00:01.600)       0:04:01.577 *******
2025-12-01 11:11:35.650878 | controller | changed: [instance]
2025-12-01 11:11:36.245816 | controller |
2025-12-01 11:11:36.245856 | controller | TASK [Store self signed ca file] ***********************************************
2025-12-01 11:11:36.245865 | controller | Monday 01 December 2025  11:11:35 +0000 (0:00:00.528)       0:04:02.106 *******
2025-12-01 11:11:36.245875 | controller | changed: [instance]
2025-12-01 11:11:36.246225 | controller |
2025-12-01 11:11:37.014770 | controller | TASK [Apply cmtest.yaml] *******************************************************
2025-12-01 11:11:37.014817 | controller | Monday 01 December 2025  11:11:36 +0000 (0:00:00.595)       0:04:02.701 *******
2025-12-01 11:11:37.014832 | controller | changed: [instance]
2025-12-01 11:11:37.014903 | controller |
2025-12-01 11:11:37.014920 | controller | TASK [Get clusterissuer my-ca-issuer] ******************************************
2025-12-01 11:11:37.015000 | controller | Monday 01 December 2025  11:11:37 +0000 (0:00:00.769)       0:04:03.470 *******
2025-12-01 11:11:37.630491 | controller | ok: [instance]
2025-12-01 11:11:37.630540 | controller |
2025-12-01 11:11:37.630551 | controller | TASK [Assert the length of returned resources] *********************************
2025-12-01 11:11:37.630617 | controller | Monday 01 December 2025  11:11:37 +0000 (0:00:00.615)       0:04:04.086 *******
2025-12-01 11:11:37.678721 | controller | ok: [instance] => changed=false
2025-12-01 11:11:37.678810 | controller |   msg: All assertions passed
2025-12-01 11:11:37.678923 | controller |
2025-12-01 11:11:37.679022 | controller | TASK [Verify the presence of my-ca-issuer] *************************************
2025-12-01 11:11:37.679116 | controller | Monday 01 December 2025  11:11:37 +0000 (0:00:00.048)       0:04:04.134 *******
2025-12-01 11:11:37.708951 | controller | ok: [instance] => changed=false
2025-12-01 11:11:37.709090 | controller |   msg: All assertions passed
2025-12-01 11:11:37.709242 | controller |
2025-12-01 11:11:37.709391 | controller | TASK [Cleanup cert manager resources] ******************************************
2025-12-01 11:11:37.709533 | controller | Monday 01 December 2025  11:11:37 +0000 (0:00:00.030)       0:04:04.165 *******
2025-12-01 11:11:37.750076 | controller |
2025-12-01 11:11:37.750110 | controller | TASK [cert_manager : Delete deployment of cert-manager] ************************
2025-12-01 11:11:37.750252 | controller | Monday 01 December 2025  11:11:37 +0000 (0:00:00.041)       0:04:04.206 *******
2025-12-01 11:11:38.430797 | controller | fatal: [instance]: FAILED! => changed=false
2025-12-01 11:11:38.430878 | controller |   msg: |-
2025-12-01 11:11:38.430983 | controller |     500
2025-12-01 11:11:38.431035 | controller |     Reason: Internal Server Error
2025-12-01 11:11:38.431598 | controller |     HTTP response headers: HTTPHeaderDict({'Audit-Id': 'c8e92ebf-d689-4f1a-bf64-2a71e5615c4a, c8e92ebf-d689-4f1a-bf64-2a71e5615c4a', 'Cache-Control': 'no-cache, private, no-store', 'Content-Length': '199', 'Content-Type': 'text/plain; charset=utf-8', 'Date': 'Mon, 01 Dec 2025 11:11:38 GMT', 'Strict-Transport-Security': 'max-age=31536000; includeSubDomains; preload', 'X-Content-Type-Options': 'nosniff', 'X-Kubernetes-Pf-Flowschema-Uid': '3204feb0-8454-4607-b656-24cf9c2a675e', 'X-Kubernetes-Pf-Prioritylevel-Uid': 'c06cc31a-a727-49b8-92eb-835edb90994f'})
2025-12-01 11:11:38.431954 | controller |     HTTP response body: b'Internal Server Error: "/apis/apps.openshift.io/v1": Post "https://10.217.4.1:443/apis/authorization.k8s.io/v1/subjectaccessreviews?timeout=10s": dial tcp 10.217.4.1:443: connect: connection refused\n'
2025-12-01 11:11:38.431984 | controller |     Original traceback:
2025-12-01 11:11:38.432103 | controller |       File "/home/zuul/.local/lib/python3.9/site-packages/kubernetes/dynamic/client.py", line 55, in inner
2025-12-01 11:11:38.432118 | controller |         resp = func(self, *args, **kwargs)
2025-12-01 11:11:38.432200 | controller |
2025-12-01 11:11:38.432209 | controller |       File "/home/zuul/.local/lib/python3.9/site-packages/kubernetes/dynamic/client.py", line 273, in request
2025-12-01 11:11:38.432217 | controller |         api_response = self.client.call_api(
2025-12-01 11:11:38.433145 | controller |
2025-12-01 11:11:38.433234 | controller |       File "/home/zuul/.local/lib/python3.9/site-packages/kubernetes/client/api_client.py", line 348, in call_api
2025-12-01 11:11:38.433242 | controller |         return self.__call_api(resource_path, method,
2025-12-01 11:11:38.433259 | controller |
2025-12-01 11:11:38.433264 | controller |       File "/home/zuul/.local/lib/python3.9/site-packages/kubernetes/client/api_client.py", line 180, in __call_api
2025-12-01 11:11:38.433269 | controller |         response_data = self.request(
2025-12-01 11:11:38.433275 | controller |
2025-12-01 11:11:38.433283 | controller |       File "/home/zuul/.local/lib/python3.9/site-packages/kubernetes/client/api_client.py", line 373, in request
2025-12-01 11:11:38.433406 | controller |         return self.rest_client.GET(url,
2025-12-01 11:11:38.433417 | controller |
2025-12-01 11:11:38.433544 | controller |       File "/home/zuul/.local/lib/python3.9/site-packages/kubernetes/client/rest.py", line 244, in GET
2025-12-01 11:11:38.433628 | controller |         return self.request("GET", url,
2025-12-01 11:11:38.433721 | controller |
2025-12-01 11:11:38.433835 | controller |       File "/home/zuul/.local/lib/python3.9/site-packages/kubernetes/client/rest.py", line 238, in request
2025-12-01 11:11:38.433928 | controller |         raise ApiException(http_resp=r)
2025-12-01 11:11:38.434534 | controller |
2025-12-01 11:11:38.434999 | controller | PLAY RECAP *********************************************************************
2025-12-01 11:11:38.435118 | controller | instance                   : ok=16   changed=9    unreachable=0    failed=1    skipped=1    rescued=0    ignored=0
2025-12-01 11:11:38.435230 | controller |
2025-12-01 11:11:38.435329 | controller | Monday 01 December 2025  11:11:38 +0000 (0:00:00.684)       0:04:04.890 *******
2025-12-01 11:11:38.435436 | controller | ===============================================================================
2025-12-01 11:11:38.435531 | controller | cert_manager : Wait for cert-manager pods to be ready ----------------- 232.78s
2025-12-01 11:11:38.435662 | controller | cert_manager : Install cert-manager from release manifest --------------- 3.11s
2025-12-01 11:11:38.436023 | controller | cert_manager : Install cert-manager cmctl CLI --------------------------- 1.60s
2025-12-01 11:11:38.436127 | controller | Gathering Facts --------------------------------------------------------- 1.02s
2025-12-01 11:11:38.436153 | controller | cert_manager : Create the cifmw_cert_manager_operator_namespace namespace" --- 0.85s
2025-12-01 11:11:38.436163 | controller | cert_manager : Check for cert-manager namspeace existance --------------- 0.80s
2025-12-01 11:11:38.436194 | controller | Apply cmtest.yaml ------------------------------------------------------- 0.77s
2025-12-01 11:11:38.436299 | controller | cert_manager : Delete deployment of cert-manager ------------------------ 0.68s
2025-12-01 11:11:38.436400 | controller | cert_manager : Download release manifests ------------------------------- 0.68s
2025-12-01 11:11:38.436508 | controller | Get clusterissuer my-ca-issuer ------------------------------------------ 0.62s
2025-12-01 11:11:38.436626 | controller | Store self signed ca file ----------------------------------------------- 0.60s
2025-12-01 11:11:38.436732 | controller | cert_manager : Verify cert_manager api ---------------------------------- 0.53s
2025-12-01 11:11:38.436833 | controller | cert_manager : Create role needed directories --------------------------- 0.36s
2025-12-01 11:11:38.436931 | controller | cert_manager : Create $HOME/bin dir ------------------------------------- 0.23s
2025-12-01 11:11:38.437039 | controller | Assert the length of returned resources --------------------------------- 0.05s
2025-12-01 11:11:38.437169 | controller | cert_manager : Install from Release Manifest ---------------------------- 0.04s
2025-12-01 11:11:38.437308 | controller | cert_manager : Install from OLM Manifest -------------------------------- 0.04s
2025-12-01 11:11:38.437363 | controller | Cleanup cert manager resources ------------------------------------------ 0.04s
2025-12-01 11:11:38.437459 | controller | Deploy cert-manager role ------------------------------------------------ 0.04s
2025-12-01 11:11:38.437561 | controller | Verify the presence of my-ca-issuer ------------------------------------- 0.03s
2025-12-01 11:11:38.514329 | controller | CRITICAL Ansible return code was 2, command was: ['ansible-playbook', '--inventory', '/home/zuul/.cache/molecule/cert_manager/default/inventory', '--skip-tags', 'molecule-notest,notest', '/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/cert_manager/molecule/default/converge.yml']
2025-12-01 11:11:38.514786 | controller | WARNING  An error occurred during the test sequence action: 'converge'. Cleaning up.
2025-12-01 11:11:38.524245 | controller | INFO     Running default > cleanup
2025-12-01 11:11:38.524710 | controller | WARNING  Skipping, cleanup playbook not configured.
2025-12-01 11:11:38.532383 | controller | INFO     Running default > destroy
2025-12-01 11:11:38.532921 | controller | WARNING  Skipping, instances are delegated.
2025-12-01 11:11:38.533262 | controller | INFO     Pruning extra files from scenario ephemeral directory
2025-12-01 11:11:38.534945 | controller | INFO     Writing /tmp/report.html report.
2025-12-01 11:11:38.902390 | controller | ERROR
2025-12-01 11:11:38.902545 | controller | {
2025-12-01 11:11:38.902579 | controller |   "delta": "0:08:27.011453",
2025-12-01 11:11:38.902601 | controller |   "end": "2025-12-01 11:11:38.584462",
2025-12-01 11:11:38.902618 | controller |   "msg": "non-zero return code",
2025-12-01 11:11:38.902635 | controller |   "rc": 1,
2025-12-01 11:11:38.902651 | controller |   "start": "2025-12-01 11:03:11.573009"
2025-12-01 11:11:38.902665 | controller | }
failure
2025-12-01 11:11:38.906876 | 
2025-12-01 11:11:38.906940 | PLAY RECAP
2025-12-01 11:11:38.906983 | controller | ok: 3 changed: 0 unreachable: 0 failed: 1 skipped: 1 rescued: 0 ignored: 0
2025-12-01 11:11:38.907004 | 
2025-12-01 11:11:38.967437 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-12-01 11:11:38.968186 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-12-01 11:11:39.483214 | 
2025-12-01 11:11:39.483315 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-12-01 11:11:39.503093 | 
2025-12-01 11:11:39.503167 | TASK [Filter out host if needed]
2025-12-01 11:11:39.511492 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-12-01 11:11:39.515952 | 
2025-12-01 11:11:39.516018 | TASK [Ensure file is present]
2025-12-01 11:11:39.919634 | controller | ok
2025-12-01 11:11:39.926274 | 
2025-12-01 11:11:39.926338 | TASK [Manage molecule report file]
2025-12-01 11:11:40.451723 | controller | changed
2025-12-01 11:11:40.456553 | 
2025-12-01 11:11:40.456617 | TASK [Check if we get ci-framework-data basedir]
2025-12-01 11:11:40.739147 | controller | ok
2025-12-01 11:11:40.744125 | 
2025-12-01 11:11:40.744186 | TASK [Create ci-framework-data log directory for zuul]
2025-12-01 11:11:41.128059 | controller | changed
2025-12-01 11:11:41.133635 | 
2025-12-01 11:11:41.133700 | TASK [Copy ci-framework interesting files]
2025-12-01 11:11:41.410008 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2025-12-01 11:11:41.659889 | controller | changed
2025-12-01 11:11:41.664872 | 
2025-12-01 11:11:41.664951 | TASK [Get SELinux listing]
2025-12-01 11:11:42.190712 | controller | changed
2025-12-01 11:11:42.196268 | 
2025-12-01 11:11:42.196333 | TASK [Generate log index]
2025-12-01 11:11:43.643766 | controller | changed
2025-12-01 11:11:43.648565 | 
2025-12-01 11:11:43.648628 | TASK [Get some env related data]
2025-12-01 11:11:44.378572 | controller | /home/zuul/.local/bin/ansible
2025-12-01 11:11:45.179924 | controller | changed
2025-12-01 11:11:45.203358 | 
2025-12-01 11:11:45.203464 | TASK [Generate list of logs to collect in home directory]
2025-12-01 11:11:45.576567 | controller | ok: All paths examined
2025-12-01 11:11:45.581945 | 
2025-12-01 11:11:45.582007 | LOOP [Copy logs from home directory]
2025-12-01 11:11:46.335794 | controller | changed:
2025-12-01 11:11:46.336008 | controller | {
2025-12-01 11:11:46.336037 | controller |   "atime": 1743544925.4788878,
2025-12-01 11:11:46.336057 | controller |   "ctime": 1743545329.1409318,
2025-12-01 11:11:46.336073 | controller |   "dev": 64513,
2025-12-01 11:11:46.336089 | controller |   "gid": 1000,
2025-12-01 11:11:46.336105 | controller |   "gr_name": "zuul",
2025-12-01 11:11:46.336120 | controller |   "inode": 4518807,
2025-12-01 11:11:46.336134 | controller |   "isblk": false,
2025-12-01 11:11:46.336148 | controller |   "ischr": false,
2025-12-01 11:11:46.336162 | controller |   "isdir": false,
2025-12-01 11:11:46.336176 | controller |   "isfifo": false,
2025-12-01 11:11:46.336190 | controller |   "isgid": false,
2025-12-01 11:11:46.336203 | controller |   "islnk": false,
2025-12-01 11:11:46.336216 | controller |   "isreg": true,
2025-12-01 11:11:46.336230 | controller |   "issock": false,
2025-12-01 11:11:46.336243 | controller |   "isuid": false,
2025-12-01 11:11:46.336257 | controller |   "mode": "0644",
2025-12-01 11:11:46.336272 | controller |   "mtime": 1743545329.1409318,
2025-12-01 11:11:46.336286 | controller |   "nlink": 1,
2025-12-01 11:11:46.336300 | controller |   "path": "/home/zuul/crc-setup.log",
2025-12-01 11:11:46.336315 | controller |   "pw_name": "zuul",
2025-12-01 11:11:46.336329 | controller |   "rgrp": true,
2025-12-01 11:11:46.336343 | controller |   "roth": true,
2025-12-01 11:11:46.336356 | controller |   "rusr": true,
2025-12-01 11:11:46.336370 | controller |   "size": 4108,
2025-12-01 11:11:46.336383 | controller |   "uid": 1000,
2025-12-01 11:11:46.336396 | controller |   "wgrp": false,
2025-12-01 11:11:46.336424 | controller |   "woth": false,
2025-12-01 11:11:46.336524 | controller |   "wusr": true,
2025-12-01 11:11:46.336554 | controller |   "xgrp": false,
2025-12-01 11:11:46.336578 | controller |   "xoth": false,
2025-12-01 11:11:46.336599 | controller |   "xusr": false
2025-12-01 11:11:46.336619 | controller | }
2025-12-01 11:11:47.045869 | controller | changed:
2025-12-01 11:11:47.045974 | controller | {
2025-12-01 11:11:47.046002 | controller |   "atime": 1743545331.0429637,
2025-12-01 11:11:47.046020 | controller |   "ctime": 1743545842.0111232,
2025-12-01 11:11:47.046037 | controller |   "dev": 64513,
2025-12-01 11:11:47.046052 | controller |   "gid": 1000,
2025-12-01 11:11:47.046066 | controller |   "gr_name": "zuul",
2025-12-01 11:11:47.046080 | controller |   "inode": 4194437,
2025-12-01 11:11:47.046096 | controller |   "isblk": false,
2025-12-01 11:11:47.046111 | controller |   "ischr": false,
2025-12-01 11:11:47.046126 | controller |   "isdir": false,
2025-12-01 11:11:47.046140 | controller |   "isfifo": false,
2025-12-01 11:11:47.046154 | controller |   "isgid": false,
2025-12-01 11:11:47.046167 | controller |   "islnk": false,
2025-12-01 11:11:47.046181 | controller |   "isreg": true,
2025-12-01 11:11:47.046195 | controller |   "issock": false,
2025-12-01 11:11:47.046208 | controller |   "isuid": false,
2025-12-01 11:11:47.046221 | controller |   "mode": "0644",
2025-12-01 11:11:47.046235 | controller |   "mtime": 1743545842.0111232,
2025-12-01 11:11:47.046248 | controller |   "nlink": 1,
2025-12-01 11:11:47.046261 | controller |   "path": "/home/zuul/crc-start.log",
2025-12-01 11:11:47.046279 | controller |   "pw_name": "zuul",
2025-12-01 11:11:47.046293 | controller |   "rgrp": true,
2025-12-01 11:11:47.046306 | controller |   "roth": true,
2025-12-01 11:11:47.046320 | controller |   "rusr": true,
2025-12-01 11:11:47.046333 | controller |   "size": 4023,
2025-12-01 11:11:47.046346 | controller |   "uid": 1000,
2025-12-01 11:11:47.046359 | controller |   "wgrp": false,
2025-12-01 11:11:47.046372 | controller |   "woth": false,
2025-12-01 11:11:47.046384 | controller |   "wusr": true,
2025-12-01 11:11:47.046397 | controller |   "xgrp": false,
2025-12-01 11:11:47.046424 | controller |   "xoth": false,
2025-12-01 11:11:47.046439 | controller |   "xusr": false
2025-12-01 11:11:47.046453 | controller | }
2025-12-01 11:11:47.805169 | controller | changed:
2025-12-01 11:11:47.805262 | controller | {
2025-12-01 11:11:47.805288 | controller |   "atime": 1764586972.719463,
2025-12-01 11:11:47.805306 | controller |   "ctime": 1764586988.90261,
2025-12-01 11:11:47.805322 | controller |   "dev": 64513,
2025-12-01 11:11:47.805338 | controller |   "gid": 1000,
2025-12-01 11:11:47.805352 | controller |   "gr_name": "zuul",
2025-12-01 11:11:47.805366 | controller |   "inode": 4233424,
2025-12-01 11:11:47.805380 | controller |   "isblk": false,
2025-12-01 11:11:47.805393 | controller |   "ischr": false,
2025-12-01 11:11:47.805418 | controller |   "isdir": false,
2025-12-01 11:11:47.805434 | controller |   "isfifo": false,
2025-12-01 11:11:47.805448 | controller |   "isgid": false,
2025-12-01 11:11:47.805463 | controller |   "islnk": false,
2025-12-01 11:11:47.805477 | controller |   "isreg": true,
2025-12-01 11:11:47.805492 | controller |   "issock": false,
2025-12-01 11:11:47.805506 | controller |   "isuid": false,
2025-12-01 11:11:47.805521 | controller |   "mode": "0644",
2025-12-01 11:11:47.805536 | controller |   "mtime": 1764586988.90261,
2025-12-01 11:11:47.805550 | controller |   "nlink": 1,
2025-12-01 11:11:47.805564 | controller |   "path": "/home/zuul/ansible.log",
2025-12-01 11:11:47.805578 | controller |   "pw_name": "zuul",
2025-12-01 11:11:47.805593 | controller |   "rgrp": true,
2025-12-01 11:11:47.805607 | controller |   "roth": true,
2025-12-01 11:11:47.805623 | controller |   "rusr": true,
2025-12-01 11:11:47.805638 | controller |   "size": 6749,
2025-12-01 11:11:47.805651 | controller |   "uid": 1000,
2025-12-01 11:11:47.805665 | controller |   "wgrp": false,
2025-12-01 11:11:47.805679 | controller |   "woth": false,
2025-12-01 11:11:47.805692 | controller |   "wusr": true,
2025-12-01 11:11:47.805705 | controller |   "xgrp": false,
2025-12-01 11:11:47.805718 | controller |   "xoth": false,
2025-12-01 11:11:47.805730 | controller |   "xusr": false
2025-12-01 11:11:47.805743 | controller | }
2025-12-01 11:11:47.816361 | 
2025-12-01 11:11:47.816474 | TASK [Copy crio stats log file]
2025-12-01 11:11:47.829764 | controller | skipping: Conditional result was False
2025-12-01 11:11:47.834811 | 
2025-12-01 11:11:47.834876 | TASK [Get SELinux related data]
2025-12-01 11:11:48.271400 | controller | <no matches>
2025-12-01 11:11:48.360808 | controller | ERROR
2025-12-01 11:11:48.360984 | controller | {
2025-12-01 11:11:48.361024 | controller |   "delta": "0:00:00.020216",
2025-12-01 11:11:48.361047 | controller |   "end": "2025-12-01 11:11:48.273063",
2025-12-01 11:11:48.361065 | controller |   "msg": "non-zero return code",
2025-12-01 11:11:48.361081 | controller |   "rc": 1,
2025-12-01 11:11:48.361097 | controller |   "start": "2025-12-01 11:11:48.252847"
2025-12-01 11:11:48.361112 | controller | }
2025-12-01 11:11:48.361135 | controller | ERROR: Ignoring Errors
2025-12-01 11:11:48.366306 | 
2025-12-01 11:11:48.366370 | TASK [Create system configuration directory]
2025-12-01 11:11:48.780482 | controller | changed
2025-12-01 11:11:48.795407 | 
2025-12-01 11:11:48.795475 | TASK [Get some of the system configurations]
2025-12-01 11:11:49.321762 | controller | changed
2025-12-01 11:11:49.326622 | 
2025-12-01 11:11:49.326684 | TASK [Copy generated documentation if available]
2025-12-01 11:11:49.340007 | controller | skipping: Conditional result was False
2025-12-01 11:11:49.345133 | 
2025-12-01 11:11:49.345202 | TASK [Copy generated AsciiDoc documentation if available]
2025-12-01 11:11:49.357940 | controller | skipping: Conditional result was False
2025-12-01 11:11:49.363035 | 
2025-12-01 11:11:49.363098 | TASK [Compress logs bigger than 2MB]
2025-12-01 11:11:49.889299 | controller | changed
2025-12-01 11:11:49.894187 | 
2025-12-01 11:11:49.894248 | TASK [Copy files from workspace on node]
2025-12-01 11:11:49.912258 | controller | ok
2025-12-01 11:11:49.933761 | 
2025-12-01 11:11:49.933825 | TASK [fetch-output : Set log path for multiple nodes]
2025-12-01 11:11:49.947125 | controller | skipping: Conditional result was False
2025-12-01 11:11:49.952395 | 
2025-12-01 11:11:49.952472 | TASK [fetch-output : Set log path for single node]
2025-12-01 11:11:49.979836 | controller | ok
2025-12-01 11:11:49.984681 | 
2025-12-01 11:11:49.984750 | LOOP [fetch-output : Ensure local output dirs]
2025-12-01 11:11:50.173687 | controller -> localhost | ok: "/var/lib/zuul/builds/172dee43876544e5b18d0d0eeab7bf9f/work/logs"
2025-12-01 11:11:50.173930 | controller -> localhost | changed: All items complete
2025-12-01 11:11:50.173969 | 
2025-12-01 11:11:50.346325 | controller -> localhost | changed: "/var/lib/zuul/builds/172dee43876544e5b18d0d0eeab7bf9f/work/artifacts"
2025-12-01 11:11:50.521361 | controller -> localhost | changed: "/var/lib/zuul/builds/172dee43876544e5b18d0d0eeab7bf9f/work/docs"
2025-12-01 11:11:50.532341 | 
2025-12-01 11:11:50.532419 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-12-01 11:11:51.251798 | controller | changed:
2025-12-01 11:11:51.251986 | controller | .d..t...... ./
2025-12-01 11:11:51.252018 | controller | >f+++++++++ README.html
2025-12-01 11:11:51.252039 | controller | >f+++++++++ ansible-execution.log
2025-12-01 11:11:51.252059 | controller | >f+++++++++ ansible.log
2025-12-01 11:11:51.252078 | controller | >f+++++++++ crc-setup.log
2025-12-01 11:11:51.252094 | controller | >f+++++++++ crc-start.log
2025-12-01 11:11:51.252111 | controller | >f+++++++++ dmesg.log
2025-12-01 11:11:51.252131 | controller | >f+++++++++ installed-pkgs.log
2025-12-01 11:11:51.252146 | controller | >f+++++++++ python.log
2025-12-01 11:11:51.252161 | controller | >f+++++++++ registries.conf
2025-12-01 11:11:51.252176 | controller | >f+++++++++ report.html
2025-12-01 11:11:51.252190 | controller | >f+++++++++ selinux-denials.log
2025-12-01 11:11:51.252205 | controller | >f+++++++++ selinux-listing.log
2025-12-01 11:11:51.252219 | controller | cd+++++++++ ci-framework-data/
2025-12-01 11:11:51.252233 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-12-01 11:11:51.252248 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-12-01 11:11:51.252263 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/
2025-12-01 11:11:51.252277 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/cert-manager/
2025-12-01 11:11:51.252291 | controller | >f+++++++++ ci-framework-data/artifacts/manifests/cert-manager/cert_manager_manifest.yml
2025-12-01 11:11:51.252305 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-12-01 11:11:51.252319 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-12-01 11:11:51.252333 | controller | cd+++++++++ ci-framework-data/artifacts/repositories/
2025-12-01 11:11:51.252348 | controller | >f+++++++++ ci-framework-data/artifacts/repositories/delorean.repo.md5
2025-12-01 11:11:51.252362 | controller | cd+++++++++ ci-framework-data/logs/
2025-12-01 11:11:51.252376 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2025-12-01 11:11:51.252390 | controller | cd+++++++++ registries.conf.d/
2025-12-01 11:11:51.252416 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-12-01 11:11:51.252433 | controller | cd+++++++++ system-config/
2025-12-01 11:11:51.252448 | controller | cd+++++++++ system-config/libvirt/
2025-12-01 11:11:51.252463 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-12-01 11:11:51.252483 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-12-01 11:11:51.252498 | controller | >f+++++++++ system-config/libvirt/libvirtd.conf
2025-12-01 11:11:51.252513 | controller | >f+++++++++ system-config/libvirt/network.conf
2025-12-01 11:11:51.252527 | controller | >f+++++++++ system-config/libvirt/qemu-lockd.conf
2025-12-01 11:11:51.252541 | controller | >f+++++++++ system-config/libvirt/qemu.conf
2025-12-01 11:11:51.252555 | controller | >f+++++++++ system-config/libvirt/virtinterfaced.conf
2025-12-01 11:11:51.252569 | controller | >f+++++++++ system-config/libvirt/virtlockd.conf
2025-12-01 11:11:51.252582 | controller | >f+++++++++ system-config/libvirt/virtlogd.conf
2025-12-01 11:11:51.252595 | controller | >f+++++++++ system-config/libvirt/virtnetworkd.conf
2025-12-01 11:11:51.252608 | controller | >f+++++++++ system-config/libvirt/virtnodedevd.conf
2025-12-01 11:11:51.252621 | controller | >f+++++++++ system-config/libvirt/virtnwfilterd.conf
2025-12-01 11:11:51.252634 | controller | >f+++++++++ system-config/libvirt/virtproxyd.conf
2025-12-01 11:11:51.252648 | controller | >f+++++++++ system-config/libvirt/virtqemud.conf
2025-12-01 11:11:51.252661 | controller | >f+++++++++ system-config/libvirt/virtsecretd.conf
2025-12-01 11:11:51.252674 | controller | >f+++++++++ system-config/libvirt/virtstoraged.conf
2025-12-01 11:11:51.845068 | controller | changed: .d..t...... ./
2025-12-01 11:11:52.413308 | controller | changed: .d..t...... ./
2025-12-01 11:11:52.430249 | 
2025-12-01 11:11:52.430354 | TASK [Return artifact to Zuul]
2025-12-01 11:11:52.458682 | controller | ok
2025-12-01 11:11:52.477767 | 
2025-12-01 11:11:52.477821 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-12-01 11:11:52.477929 | 
2025-12-01 11:11:52.477962 | PLAY RECAP
2025-12-01 11:11:52.478000 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2025-12-01 11:11:52.478021 | 
2025-12-01 11:11:52.552189 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-12-01 11:11:52.553412 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-12-01 11:11:53.051638 | 
2025-12-01 11:11:53.051732 | PLAY [all]
2025-12-01 11:11:53.070632 | 
2025-12-01 11:11:53.070706 | TASK [include_role : fetch-output]
2025-12-01 11:11:53.099539 | controller | ok
2025-12-01 11:11:53.115700 | 
2025-12-01 11:11:53.115778 | TASK [fetch-output : Set log path for multiple nodes]
2025-12-01 11:11:53.159622 | controller | skipping: Conditional result was False
2025-12-01 11:11:53.165136 | 
2025-12-01 11:11:53.165205 | TASK [fetch-output : Set log path for single node]
2025-12-01 11:11:53.193287 | controller | ok
2025-12-01 11:11:53.198106 | 
2025-12-01 11:11:53.198172 | LOOP [fetch-output : Ensure local output dirs]
2025-12-01 11:11:53.511524 | controller -> localhost | ok: "/var/lib/zuul/builds/172dee43876544e5b18d0d0eeab7bf9f/work/logs"
2025-12-01 11:11:53.689737 | controller -> localhost | ok: "/var/lib/zuul/builds/172dee43876544e5b18d0d0eeab7bf9f/work/artifacts"
2025-12-01 11:11:53.867208 | controller -> localhost | ok: "/var/lib/zuul/builds/172dee43876544e5b18d0d0eeab7bf9f/work/docs"
2025-12-01 11:11:53.876929 | 
2025-12-01 11:11:53.877039 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-12-01 11:11:54.534532 | controller | ok
2025-12-01 11:11:54.534722 | controller | ok: All items complete
2025-12-01 11:11:54.534751 | 
2025-12-01 11:11:55.066734 | controller | ok
2025-12-01 11:11:55.542994 | controller | ok
2025-12-01 11:11:55.563067 | 
2025-12-01 11:11:55.563170 | TASK [include_role : fetch-output-openshift]
2025-12-01 11:11:55.576636 | controller | skipping: Conditional result was False
2025-12-01 11:11:55.582320 | 
2025-12-01 11:11:55.582386 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-12-01 11:11:55.909303 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.006158
2025-12-01 11:11:56.096521 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.006208
2025-12-01 11:11:56.127070 | 
2025-12-01 11:11:56.127156 | PLAY [all]
2025-12-01 11:11:56.141181 | 
2025-12-01 11:11:56.141250 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-12-01 11:11:56.616391 | controller | changed
2025-12-01 11:11:56.637652 | 
2025-12-01 11:11:56.637721 | PLAY RECAP
2025-12-01 11:11:56.637766 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-12-01 11:11:56.637788 | 
2025-12-01 11:11:56.705551 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-12-01 11:11:56.706240 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-12-01 11:11:57.243097 | 
2025-12-01 11:11:57.243197 | PLAY [localhost]
2025-12-01 11:11:57.260348 | 
2025-12-01 11:11:57.260433 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-12-01 11:11:57.551203 | localhost | changed
2025-12-01 11:11:57.555619 | 
2025-12-01 11:11:57.555696 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-12-01 11:11:57.573700 | localhost | ok
2025-12-01 11:11:57.581471 | 
2025-12-01 11:11:57.581538 | TASK [add-fileserver : Create SSH private key tempfile]
2025-12-01 11:11:57.872332 | localhost | changed
2025-12-01 11:11:57.877782 | 
2025-12-01 11:11:57.877850 | TASK [add-fileserver : Create SSH private key from secret]
2025-12-01 11:11:58.401350 | localhost | changed
2025-12-01 11:11:58.406228 | 
2025-12-01 11:11:58.406304 | TASK [add-fileserver : Add fileserver ssh key]
2025-12-01 11:11:58.727302 | localhost | Identity added: /var/lib/zuul/builds/172dee43876544e5b18d0d0eeab7bf9f/work/tmp/ansible.v36cu82l (/var/lib/zuul/builds/172dee43876544e5b18d0d0eeab7bf9f/work/tmp/ansible.v36cu82l)
2025-12-01 11:11:58.727480 | localhost | ok: Runtime: 0:00:00.006239
2025-12-01 11:11:58.731680 | 
2025-12-01 11:11:58.731746 | TASK [add-fileserver : Remove SSH private key from disk]
2025-12-01 11:11:58.941759 | localhost | ok: Runtime: 0:00:00.004172
2025-12-01 11:11:58.946354 | 
2025-12-01 11:11:58.946434 | TASK [add-fileserver : Add fileserver to inventory]
2025-12-01 11:11:58.994318 | localhost | changed
2025-12-01 11:11:58.999162 | 
2025-12-01 11:11:58.999227 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-12-01 11:11:59.310034 | localhost | changed
2025-12-01 11:11:59.328866 | 
2025-12-01 11:11:59.328937 | PLAY [localhost]
2025-12-01 11:11:59.340010 | 
2025-12-01 11:11:59.340070 | TASK [Generate bulk log download script]
2025-12-01 11:11:59.358124 | localhost | ok
2025-12-01 11:11:59.369243 | 
2025-12-01 11:11:59.369305 | TASK [local-log-download : Check API endpoint is defined]
2025-12-01 11:11:59.396463 | localhost | ok: All assertions passed
2025-12-01 11:11:59.400812 | 
2025-12-01 11:11:59.400874 | TASK [local-log-download : Create download script]
2025-12-01 11:11:59.748346 | localhost -> localhost | changed
2025-12-01 11:11:59.756806 | 
2025-12-01 11:11:59.756873 | TASK [Register quick-download link]
2025-12-01 11:11:59.774327 | localhost | ok
2025-12-01 11:11:59.806938 | 
2025-12-01 11:11:59.807008 | PLAY [logserver.rdoproject.org]
2025-12-01 11:11:59.816427 | 
2025-12-01 11:11:59.816488 | TASK [Set zuul-log-path fact]
2025-12-01 11:11:59.832384 | logserver.rdoproject.org | ok
2025-12-01 11:11:59.841407 | 
2025-12-01 11:11:59.841472 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-12-01 11:11:59.867512 | logserver.rdoproject.org | ok
2025-12-01 11:11:59.873014 | 
2025-12-01 11:11:59.873078 | TASK [upload-logs : Create log directories]
2025-12-01 11:12:01.594343 | logserver.rdoproject.org | changed
2025-12-01 11:12:01.597575 | 
2025-12-01 11:12:01.597639 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-12-01 11:12:01.814035 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.003964
2025-12-01 11:12:01.818594 | 
2025-12-01 11:12:01.818661 | TASK [upload-logs : Upload logs to log server]
2025-12-01 11:12:03.256578 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-12-01 11:12:03.259279 | 
2025-12-01 11:12:03.259344 | LOOP [upload-logs : Compress console log and json output]
2025-12-01 11:12:03.294724 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-01 11:12:03.303214 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-01 11:12:03.306997 | 
2025-12-01 11:12:03.307063 | LOOP [upload-logs : Upload compressed console log and json output]
2025-12-01 11:12:03.341937 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-01 11:12:03.342163 | 
2025-12-01 11:12:03.345417 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-01 11:12:03.353207 | 
2025-12-01 11:12:03.353273 | LOOP [upload-logs : Upload console log and json output]