2026-03-16 16:41:16.570741 | Job console starting...
2026-03-16 16:41:16.788183 | Updating repositories
2026-03-16 16:41:17.531740 | Preparing job workspace
2026-03-16 16:41:27.398680 | Running Ansible setup...
2026-03-16 16:41:33.790956 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2026-03-16 16:41:34.837035 | 
2026-03-16 16:41:34.837131 | PLAY [localhost]
2026-03-16 16:41:34.866968 | 
2026-03-16 16:41:34.867058 | TASK [Gathering Facts]
2026-03-16 16:41:36.954576 | localhost | ok
2026-03-16 16:41:36.972964 | 
2026-03-16 16:41:36.974923 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-03-16 16:41:38.407201 | localhost -> localhost | changed
2026-03-16 16:41:38.438816 | 
2026-03-16 16:41:38.438911 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-03-16 16:41:40.476985 | localhost -> localhost | changed
2026-03-16 16:41:40.486079 | 
2026-03-16 16:41:40.486169 | TASK [Setup log path fact]
2026-03-16 16:41:40.527581 | localhost | ok
2026-03-16 16:41:40.567286 | 
2026-03-16 16:41:40.567442 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-03-16 16:41:40.622815 | localhost | ok
2026-03-16 16:41:40.641668 | 
2026-03-16 16:41:40.641761 | TASK [emit-job-header : Print job information]
2026-03-16 16:41:40.709494 | # Job Information
2026-03-16 16:41:40.709636 | Ansible Version: 2.15.12
2026-03-16 16:41:40.709668 | Job: cifmw-molecule-openshift_login
2026-03-16 16:41:40.709702 | Pipeline: github-check
2026-03-16 16:41:40.709727 | Executor: ibm-bm4-ze.softwarefactory-project.io
2026-03-16 16:41:40.709748 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3770
2026-03-16 16:41:40.709769 | Log URL (when completed): https://logserver.rdoproject.org/f64/rdoproject.org/f64ac7a214b14f99b6de88c519280f3c/
2026-03-16 16:41:40.709789 | Event ID: 072a8580-2155-11f1-9026-7307b7bf6b98
2026-03-16 16:41:40.713801 | 
2026-03-16 16:41:40.713875 | LOOP [emit-job-header : Print node information]
2026-03-16 16:41:41.022143 | localhost | ok:
2026-03-16 16:41:41.022316 | localhost | # Node Information
2026-03-16 16:41:41.022355 | localhost | Inventory Hostname: controller
2026-03-16 16:41:41.022384 | localhost | Hostname: np0005648877
2026-03-16 16:41:41.022407 | localhost | Username: zuul
2026-03-16 16:41:41.022429 | localhost | Distro: CentOS 9
2026-03-16 16:41:41.022448 | localhost | Provider: ibm-bm4-nodepool
2026-03-16 16:41:41.022467 | localhost | Region: regionOne
2026-03-16 16:41:41.022484 | localhost | Label: centos-9-stream-crc-2-48-0-xl-ibm
2026-03-16 16:41:41.022502 | localhost | Product Name: OpenStack Compute
2026-03-16 16:41:41.022519 | localhost | Interface IP: 192.168.26.99
2026-03-16 16:41:41.053089 | 
2026-03-16 16:41:41.053172 | PLAY [all]
2026-03-16 16:41:41.070945 | 
2026-03-16 16:41:41.071073 | TASK [Gather network facts]
2026-03-16 16:41:41.487405 | controller | ok
2026-03-16 16:41:41.517857 | 
2026-03-16 16:41:41.517960 | TASK [include_role : start-zuul-console]
2026-03-16 16:41:41.538145 | controller | ok
2026-03-16 16:41:41.552436 | 
2026-03-16 16:41:41.552518 | TASK [start-zuul-console : Start zuul_console daemon.]
2026-03-16 16:41:42.013284 | controller | ok
2026-03-16 16:41:42.027946 | 
2026-03-16 16:41:42.028043 | TASK [include_role : add-build-sshkey]
2026-03-16 16:41:42.056955 | controller | ok
2026-03-16 16:41:42.084112 | 
2026-03-16 16:41:42.084204 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-03-16 16:41:42.565465 | controller -> localhost | ok
2026-03-16 16:41:42.571978 | 
2026-03-16 16:41:42.572075 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-03-16 16:41:42.624673 | controller | ok
2026-03-16 16:41:42.649746 | controller | included: /var/lib/zuul/builds/f64ac7a214b14f99b6de88c519280f3c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-03-16 16:41:42.661759 | 
2026-03-16 16:41:42.661855 | TASK [add-build-sshkey : Create Temp SSH key]
2026-03-16 16:41:43.897656 | controller -> localhost | Generating public/private rsa key pair.
2026-03-16 16:41:43.897841 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/f64ac7a214b14f99b6de88c519280f3c/work/f64ac7a214b14f99b6de88c519280f3c_id_rsa.
2026-03-16 16:41:43.897877 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/f64ac7a214b14f99b6de88c519280f3c/work/f64ac7a214b14f99b6de88c519280f3c_id_rsa.pub.
2026-03-16 16:41:43.897904 | controller -> localhost | The key fingerprint is:
2026-03-16 16:41:43.897926 | controller -> localhost | SHA256:lK8kODShIfclgO4g+XyN0Zh3X0QsZ7lFGvGXVj2DM8E zuul-build-sshkey
2026-03-16 16:41:43.897947 | controller -> localhost | The key's randomart image is:
2026-03-16 16:41:43.897967 | controller -> localhost | +---[RSA 3072]----+
2026-03-16 16:41:43.897996 | controller -> localhost | |..+.o .    o==+ o|
2026-03-16 16:41:43.898015 | controller -> localhost | |.o + +   .. *E.o+|
2026-03-16 16:41:43.898050 | controller -> localhost | |... ++  o  =.o+o+|
2026-03-16 16:41:43.898071 | controller -> localhost | |+. .+oo...  o .. |
2026-03-16 16:41:43.898089 | controller -> localhost | |+o  o=..S...     |
2026-03-16 16:41:43.898107 | controller -> localhost | | .o o..o ..      |
2026-03-16 16:41:43.898126 | controller -> localhost | |   .    .        |
2026-03-16 16:41:43.898147 | controller -> localhost | |                 |
2026-03-16 16:41:43.898166 | controller -> localhost | |                 |
2026-03-16 16:41:43.898184 | controller -> localhost | +----[SHA256]-----+
2026-03-16 16:41:43.898227 | controller -> localhost | ok: Runtime: 0:00:00.404700
2026-03-16 16:41:43.907011 | 
2026-03-16 16:41:43.907093 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-03-16 16:41:43.973794 | controller | ok
2026-03-16 16:41:43.995228 | controller | included: /var/lib/zuul/builds/f64ac7a214b14f99b6de88c519280f3c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-03-16 16:41:44.027418 | 
2026-03-16 16:41:44.027508 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-03-16 16:41:44.064597 | controller | skipping: Conditional result was False
2026-03-16 16:41:44.070247 | 
2026-03-16 16:41:44.070358 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-03-16 16:41:44.690406 | controller | changed
2026-03-16 16:41:44.701322 | 
2026-03-16 16:41:44.701419 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-03-16 16:41:45.041417 | controller | ok
2026-03-16 16:41:45.046383 | 
2026-03-16 16:41:45.046455 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-03-16 16:41:46.270521 | controller | changed
2026-03-16 16:41:46.276782 | 
2026-03-16 16:41:46.276869 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-03-16 16:41:47.529723 | controller | changed
2026-03-16 16:41:47.535048 | 
2026-03-16 16:41:47.535123 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-03-16 16:41:47.591578 | controller | skipping: Conditional result was False
2026-03-16 16:41:47.596911 | 
2026-03-16 16:41:47.596986 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-03-16 16:41:48.355698 | controller -> localhost | changed
2026-03-16 16:41:48.366581 | 
2026-03-16 16:41:48.366668 | TASK [add-build-sshkey : Add back temp key]
2026-03-16 16:41:48.800507 | controller -> localhost | Identity added: /var/lib/zuul/builds/f64ac7a214b14f99b6de88c519280f3c/work/f64ac7a214b14f99b6de88c519280f3c_id_rsa (zuul-build-sshkey)
2026-03-16 16:41:48.800689 | controller -> localhost | ok: Runtime: 0:00:00.012462
2026-03-16 16:41:48.806680 | 
2026-03-16 16:41:48.806749 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-03-16 16:41:49.295589 | controller | ok
2026-03-16 16:41:49.304803 | 
2026-03-16 16:41:49.304886 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-03-16 16:41:49.335208 | controller | skipping: Conditional result was False
2026-03-16 16:41:49.368860 | 
2026-03-16 16:41:49.368978 | TASK [include_role : validate-host]
2026-03-16 16:41:49.438771 | controller | ok
2026-03-16 16:41:49.475832 | 
2026-03-16 16:41:49.475917 | TASK [validate-host : Define zuul_info_dir fact]
2026-03-16 16:41:49.529665 | controller | ok
2026-03-16 16:41:49.534510 | 
2026-03-16 16:41:49.534576 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2026-03-16 16:41:49.955140 | controller -> localhost | ok
2026-03-16 16:41:49.960843 | 
2026-03-16 16:41:49.960994 | TASK [validate-host : Collect information about the host]
2026-03-16 16:41:50.666657 | controller | ok
2026-03-16 16:41:50.689776 | 
2026-03-16 16:41:50.689861 | TASK [validate-host : Sanitize hostname]
2026-03-16 16:41:50.831986 | controller | ok
2026-03-16 16:41:50.839683 | 
2026-03-16 16:41:50.839806 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2026-03-16 16:41:51.788940 | controller -> localhost | changed
2026-03-16 16:41:51.794354 | 
2026-03-16 16:41:51.794434 | TASK [validate-host : Collect information about zuul worker]
2026-03-16 16:41:52.230677 | controller | ok
2026-03-16 16:41:52.235696 | 
2026-03-16 16:41:52.235787 | TASK [validate-host : Write out all zuul information for each host]
2026-03-16 16:41:53.109255 | controller -> localhost | changed
2026-03-16 16:41:53.122579 | 
2026-03-16 16:41:53.122660 | TASK [include_role : prepare-workspace-openshift]
2026-03-16 16:41:53.161750 | controller | skipping: Conditional result was False
2026-03-16 16:41:53.166919 | 
2026-03-16 16:41:53.166989 | TASK [include_role : remove-zuul-sshkey]
2026-03-16 16:41:53.221201 | controller | skipping: Conditional result was False
2026-03-16 16:41:53.226670 | 
2026-03-16 16:41:53.226740 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-03-16 16:41:53.563257 | controller | ok: "logs"
2026-03-16 16:41:53.563495 | controller | ok: All items complete
2026-03-16 16:41:53.563527 | 
2026-03-16 16:41:53.804611 | controller | ok: "artifacts"
2026-03-16 16:41:54.046832 | controller | ok: "docs"
2026-03-16 16:41:54.056180 | 
2026-03-16 16:41:54.056264 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-03-16 16:41:54.348893 | controller | changed: "logs"
2026-03-16 16:41:54.588984 | controller | changed: "artifacts"
2026-03-16 16:41:54.830239 | controller | changed: "docs"
2026-03-16 16:41:54.898413 | 
2026-03-16 16:41:54.898485 | PLAY RECAP
2026-03-16 16:41:54.898533 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2026-03-16 16:41:54.898562 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-03-16 16:41:54.898582 | 
2026-03-16 16:41:55.044461 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2026-03-16 16:41:55.045155 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2026-03-16 16:41:55.930678 | 
2026-03-16 16:41:55.930769 | PLAY [all]
2026-03-16 16:41:55.985015 | 
2026-03-16 16:41:55.985144 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2026-03-16 16:41:56.106196 | controller | ok
2026-03-16 16:41:56.111228 | 
2026-03-16 16:41:56.111329 | TASK [mirror-info-fork : Create /etc/ci]
2026-03-16 16:41:56.745122 | controller | changed
2026-03-16 16:41:56.750805 | 
2026-03-16 16:41:56.750878 | TASK [mirror-info-fork : Install ci_mirror script]
2026-03-16 16:41:58.339735 | controller | changed
2026-03-16 16:41:58.357236 | 
2026-03-16 16:41:58.357345 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2026-03-16 16:41:58.979199 | controller | changed:
2026-03-16 16:41:58.979891 | controller | {
2026-03-16 16:41:58.979938 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2026-03-16 16:41:58.979972 | controller | }
2026-03-16 16:41:59.264739 | controller | changed:
2026-03-16 16:41:59.264814 | controller | {
2026-03-16 16:41:59.264843 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2026-03-16 16:41:59.264864 | controller | }
2026-03-16 16:41:59.557558 | controller | changed:
2026-03-16 16:41:59.557633 | controller | {
2026-03-16 16:41:59.557665 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com"
2026-03-16 16:41:59.557689 | controller | }
2026-03-16 16:41:59.848628 | controller | changed:
2026-03-16 16:41:59.848703 | controller | {
2026-03-16 16:41:59.848732 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2026-03-16 16:41:59.848754 | controller | }
2026-03-16 16:42:00.131919 | controller | changed:
2026-03-16 16:42:00.132015 | controller | {
2026-03-16 16:42:00.132061 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2026-03-16 16:42:00.132084 | controller | }
2026-03-16 16:42:00.419445 | controller | changed:
2026-03-16 16:42:00.419551 | controller | {
2026-03-16 16:42:00.419581 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2026-03-16 16:42:00.419604 | controller | }
2026-03-16 16:42:00.709241 | controller | changed:
2026-03-16 16:42:00.709361 | controller | {
2026-03-16 16:42:00.709392 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDLOQd4ZLtkZXQGY6UwAr/06ppWQK4fDO3HaqxPk98csyOCBXsliSKK39Bso828+5srIXiW7aI6aC9P5mwi4mUZlGPfJlQbfrcGvY+b/SocuvaGK+1RrHLoJCT52LBhwgrzlXio2jeksZeein8iaTrhsPrOAs7KggIL/rB9hEiB3NaOPWhhoCP4vlW6MEMExGcqB/1FVxXFBPnLkEyW0Lk7ycVflZl2ocRxbfjZi0+tI1Wlinp8PvSQSc/WVrAcDgKjc/mB4ODPOyYy3G8FHgfMsrXSDEyjBKgLKMsdCrAUcqJQWjkqXleXSYOV4q3pzL+9umK+q/e3P/bIoSFQzmJKTU1eDfuvPXmow9F5H54fii/Da7ezlMJ+wPGHJrRAkmzvMbALy7xwswLhZMkOGNtRcPqaKYRmIBKpw3o6bCTtcNUHOtOQnzwY8JzrM2eBWJBXAANYw+9/ho80JIiwhg29CFNpVBuHbql2YxJQNrnl90guN65rYNpDxdIluweyUf8= anbanerj@kaermorhen"
2026-03-16 16:42:00.709420 | controller | }
2026-03-16 16:42:00.997216 | controller | changed:
2026-03-16 16:42:00.997319 | controller | {
2026-03-16 16:42:00.997359 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQC3VwV8Im9kRm49lt3tM36hj4Zv27FxGo4C1Q/0jqhzFmHY7RHbmeRr8ObhwWoHjXSozKWg8FL5ER0z3hTwL0W6lez3sL7hUaCmSuZmG5Hnl3x4vTSxDI9JZ/Y65rtYiiWQo2fC5xJhU/4+0e5e/pseCm8cKRSu+SaxhO+sd6FDojA2x1BzOzKiQRDy/1zWGp/cZkxcEuB1wHI5LMzN03c67vmbu+fhZRAUO4dQkvcnj2LrhQtpa+ytvnSjr8icMDosf1OsbSffwZFyHB/hfWGAfe0eIeSA2XPraxiPknXxiPKx2MJsaUTYbsZcm3EjFdHBBMumw5rBI74zLrMRvCO9GwBEmGT4rFng1nP+yw5DB8sn2zqpOsPg1LYRwCPOUveC13P6pgsZZPh812e8v5EKnETct+5XI3dVpdw6CnNiLwAyVAF15DJvBGT/u1k0Myg/bQn+Gv9k2MSj6LvQmf6WbZu2Wgjm30z3FyCneBqTL7mLF19YXzeC0ufHz5pnO1E= dasm@fedora"
2026-03-16 16:42:00.997385 | controller | }
2026-03-16 16:42:01.303630 | controller | changed:
2026-03-16 16:42:01.303712 | controller | {
2026-03-16 16:42:01.303740 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2026-03-16 16:42:01.303761 | controller | }
2026-03-16 16:42:01.578255 | controller | changed:
2026-03-16 16:42:01.578362 | controller | {
2026-03-16 16:42:01.578394 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2026-03-16 16:42:01.578416 | controller | }
2026-03-16 16:42:01.880387 | controller | changed:
2026-03-16 16:42:01.880465 | controller | {
2026-03-16 16:42:01.880495 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2026-03-16 16:42:01.880517 | controller | }
2026-03-16 16:42:02.158490 | controller | changed:
2026-03-16 16:42:02.158564 | controller | {
2026-03-16 16:42:02.158601 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2026-03-16 16:42:02.158630 | controller | }
2026-03-16 16:42:02.441810 | controller | changed:
2026-03-16 16:42:02.441895 | controller | {
2026-03-16 16:42:02.441924 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICWBreHW95Wz2Toz5YwCGQwFcUG8oFYkienDh9tntmDc ralfieri@redhat.com"
2026-03-16 16:42:02.441945 | controller | }
2026-03-16 16:42:02.741013 | controller | changed:
2026-03-16 16:42:02.741107 | controller | {
2026-03-16 16:42:02.741136 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2026-03-16 16:42:02.741158 | controller | }
2026-03-16 16:42:03.027104 | controller | changed:
2026-03-16 16:42:03.027188 | controller | {
2026-03-16 16:42:03.027217 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2026-03-16 16:42:03.027239 | controller | }
2026-03-16 16:42:03.312794 | controller | changed:
2026-03-16 16:42:03.312876 | controller | {
2026-03-16 16:42:03.312905 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2026-03-16 16:42:03.312926 | controller | }
2026-03-16 16:42:03.599940 | controller | changed:
2026-03-16 16:42:03.600042 | controller | {
2026-03-16 16:42:03.600073 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQCfNtF7NvKl915TGsGGoseUb06Hj8L/S4toWf0hExeY+F00woL6NvBlJD0nDct+P5a22I4EhvoQCRQ8reaPCm1lybR3uiRIJsj+8zkVvLwby9LXzfZorlNG9ofjd00FEmB09uW/YvTl6Q9XwwwX6tInzIOv3TMqTHHGOL74ibbj8J/FJR0cFEyj0z4WQRvtkh32xAHl83gbuINryMt0sqRI+clj2381NKL55DRLQrVw0gsfqqxiHAnXg21qWmc4J+b9e9kiuAFQjcjwTVkwJCcg3xbPwC/qokYRby/Y5S40UUd7/jEARGXT7RZgpzTuDd1oZiCVrnrqJNPaMNdVv5MLeFdf1B7iIe5aa/fGouX7AO4SdKhZUdnJmCFAGvjC6S3JMZ2wAcUl+OHnssfmdj7XL50cLo27vjuzMtLAgSqi6N99m92WCF2s8J9aVzszX7Xz9OKZCeGsiVJp3/NdABKzSEAyM9xBD/5Vho894Sav+otpySHe3p6RUTgbB5Zu8VyZRZ/UtB3ueXxyo764yrc6qWIDqrehm84Xm9g+/jpIBzGPl07NUNJpdt/6Sgf9RIKXw/7XypO5yZfUcuFNGTxLfqjTNrtgLZNcjfav6sSdVXVcMPL//XNuRdKmVFaO76eV/oGMQGr1fGcCD+N+CpI7+Q+fCNB6VFWG4nZFuI/Iuw== averdagu@redhat.com"
2026-03-16 16:42:03.600098 | controller | }
2026-03-16 16:42:03.873880 | controller | changed:
2026-03-16 16:42:03.873962 | controller | {
2026-03-16 16:42:03.873990 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDq8l27xI+QlQVdS4djp9ogSoyrNE2+Ox6vKPdhSNL1J3PE5w+WCSvMz9A5gnNuH810zwbekEApbxTze/gLQJwBHA52CChfURpXrFaxY7ePXRElwKAL3mJfzBWY/c5jnNL9TCVmFJTGZkFZP3Nh+BMgZvL6xBkt3WKm6Uq18qzd9XeKcZusrA+O+uLv1fVeQnadY9RIqOCyeFYCzLWrUfTyE8x/XG0hAWIM7qpnF2cALQS2h9n4hW5ybiUN790H08wf9hFwEf5nxY9Z9dVkPFQiTSGKNBzmnCXU9skxS/xhpFjJ5duGSZdtAHe9O+nGZm9c67hxgtf8e5PDuqAdXEv2cf6e3VBAt+Bz8EKI3yosTj0oZHfwr42Yzb1l/SKy14Rggsrc9KAQlrGXan6+u2jcQqqx7l+SWmnpFiWTV9u5cWj2IgOhApOitmRBPYqk9rE2usfO0hLn/Pj/R/Nau4803e1/EikdLE7Ps95s9mX5jRDjAoUa2JwFF5RsVFyL910= ashigupt@ashigupt.remote.csb"
2026-03-16 16:42:03.874028 | controller | }
2026-03-16 16:42:04.155003 | controller | changed:
2026-03-16 16:42:04.155204 | controller | {
2026-03-16 16:42:04.156205 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2026-03-16 16:42:04.156242 | controller | }
2026-03-16 16:42:04.435748 | controller | changed:
2026-03-16 16:42:04.435828 | controller | {
2026-03-16 16:42:04.435858 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2026-03-16 16:42:04.435880 | controller | }
2026-03-16 16:42:04.711854 | controller | changed:
2026-03-16 16:42:04.711940 | controller | {
2026-03-16 16:42:04.711968 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2026-03-16 16:42:04.711989 | controller | }
2026-03-16 16:42:04.998997 | controller | changed:
2026-03-16 16:42:04.999097 | controller | {
2026-03-16 16:42:04.999124 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2026-03-16 16:42:04.999145 | controller | }
2026-03-16 16:42:05.291437 | controller | changed:
2026-03-16 16:42:05.291527 | controller | {
2026-03-16 16:42:05.291556 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQDZdI7t1cxYx65heVI24HTV4F7oQLW1zyfxHreL2TIJKxjyrUUKIFEUmTutcBlJRLNT2Eoix6x1sOw9YrchloCLcn//SGfTElr9mSc5jbjb7QXEU+zJMhtxyEJ1Po3CUGnj7ckiIXw7wcawZtrEOAQ9pH3ExYCJcEMiyNjRQZCxT3tPK+S4B95EWh5Fsrz9CkwpjNRPPH7LigCeQTM3Wc7r97utAslBUUvYceDSLA7rMgkitJE38b7rZBeYzsGQ8YYUBjTCtehqQXxCRjizbHWaaZkBU+N3zkKB6n/iCNGIO690NK7A/qb6msTijiz1PeuM8ThOsi9qXnbX5v0PoTpcFSojV7NHAQ71f0XXuS43FhZctT+Dcx44dT8Fb5vJu2cJGrk+qF8ZgJYNpRS7gPg0EG2EqjK7JMf9ULdjSu0r+KlqIAyLvtzT4eOnQipoKlb/WG5D/0ohKv7OMQ352ggfkBFIQsRXyyTCT98Ft9juqPuahi3CAQmP4H9dyE+7+Kz437PEtsxLmfm6naNmWi7Ee1DqWPwS8rEajsm4sNM4wW9gdBboJQtc0uZw0DfLj1I9r3Mc8Ol0jYtz0yNQDSzVLrGCaJlC311trU70tZ+ZkAVV6Mn8lOhSbj1cK0lvSr6ZK4dgqGl3I1eTZJJhbLNdg7UOVaiRx9543+C/p/As7w== brjackma@redhat.com"
2026-03-16 16:42:05.291579 | controller | }
2026-03-16 16:42:05.594150 | controller | changed:
2026-03-16 16:42:05.595608 | controller | {
2026-03-16 16:42:05.595654 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2026-03-16 16:42:05.595678 | controller | }
2026-03-16 16:42:05.888230 | controller | changed:
2026-03-16 16:42:05.888337 | controller | {
2026-03-16 16:42:05.888374 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2026-03-16 16:42:05.888404 | controller | }
2026-03-16 16:42:06.179769 | controller | changed:
2026-03-16 16:42:06.179948 | controller | {
2026-03-16 16:42:06.179989 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2026-03-16 16:42:06.180025 | controller | }
2026-03-16 16:42:06.222702 | 
2026-03-16 16:42:06.222803 | TASK [Set timezone to UTC]
2026-03-16 16:42:06.970401 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2026-03-16 16:42:06.976229 | 
2026-03-16 16:42:06.976343 | TASK [Create nodepool directory]
2026-03-16 16:42:07.286360 | controller | changed
2026-03-16 16:42:07.292637 | 
2026-03-16 16:42:07.292752 | TASK [Create nodepool sub_nodes file]
2026-03-16 16:42:08.286176 | controller | changed
2026-03-16 16:42:08.294266 | 
2026-03-16 16:42:08.294403 | TASK [Create nodepool sub_nodes_private file]
2026-03-16 16:42:09.320731 | controller | changed
2026-03-16 16:42:09.333927 | 
2026-03-16 16:42:09.334259 | LOOP [Populate nodepool sub_nodes file]
2026-03-16 16:42:09.401747 | 
2026-03-16 16:42:09.401895 | LOOP [Populate nodepool sub_nodes_private file]
2026-03-16 16:42:09.430608 | 
2026-03-16 16:42:09.430784 | TASK [Create nodepool primary file]
2026-03-16 16:42:09.454699 | controller | skipping: Conditional result was False
2026-03-16 16:42:09.460364 | 
2026-03-16 16:42:09.460441 | TASK [Create nodepool node_private for this node]
2026-03-16 16:42:10.548143 | controller | changed
2026-03-16 16:42:10.554623 | 
2026-03-16 16:42:10.555029 | LOOP [Copy ssh keys to nodepool directory]
2026-03-16 16:42:11.044705 | controller | ok: Item: id_rsa Runtime: 0:00:00.004355
2026-03-16 16:42:11.044887 | 
2026-03-16 16:42:11.262683 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.005217
2026-03-16 16:42:11.276800 | 
2026-03-16 16:42:11.276906 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2026-03-16 16:42:12.237247 | controller | changed
2026-03-16 16:42:12.242399 | 
2026-03-16 16:42:12.242473 | TASK [Validate sudoers config after edits]
2026-03-16 16:42:12.569651 | controller | /etc/sudoers: parsed OK
2026-03-16 16:42:12.569723 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2026-03-16 16:42:12.569734 | controller | /etc/sudoers.d/zuul: parsed OK
2026-03-16 16:42:12.569741 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2026-03-16 16:42:12.794590 | controller | ok: Runtime: 0:00:00.005580
2026-03-16 16:42:12.800084 | 
2026-03-16 16:42:12.800147 | TASK [Show the environment passed in to job shell scripts]
2026-03-16 16:42:13.083714 | controller | SHELL=/bin/bash
2026-03-16 16:42:13.083768 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2026-03-16 16:42:13.083810 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2026-03-16 16:42:13.083821 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/70/3770/235f23c012043ef9e6fbd3c71c40da700f09e62a
2026-03-16 16:42:13.083828 | controller | PWD=/home/zuul
2026-03-16 16:42:13.083833 | controller | ZUUL_PIPELINE=github-check
2026-03-16 16:42:13.083839 | controller | LOGNAME=zuul
2026-03-16 16:42:13.083844 | controller | XDG_SESSION_TYPE=tty
2026-03-16 16:42:13.083850 | controller | _=/usr/bin/env
2026-03-16 16:42:13.083855 | controller | MOTD_SHOWN=pam
2026-03-16 16:42:13.083860 | controller | HOME=/home/zuul
2026-03-16 16:42:13.083865 | controller | LANG=en_US.UTF-8
2026-03-16 16:42:13.083871 | controller | SSH_CONNECTION=192.168.26.12 41078 192.168.26.99 22
2026-03-16 16:42:13.083876 | controller | TOX_TESTENV_PASSENV=ZUUL_UUID ZUUL_PROJECT ZUUL_SHORT_PROJECT_NAME ZUUL_PIPELINE ZUUL_VOTING WORKSPACE BUILD_TIMEOUT ZUUL_BRANCH ZUUL_CHANGES ZUUL_CHANGE_IDS ZUUL_CHANGE ZUUL_PATCHSET
2026-03-16 16:42:13.083885 | controller | ZUUL_CHANGE_IDS=3770,235f23c012043ef9e6fbd3c71c40da700f09e62a
2026-03-16 16:42:13.083890 | controller | WORKSPACE=/home/zuul/workspace
2026-03-16 16:42:13.083895 | controller | XDG_SESSION_CLASS=user
2026-03-16 16:42:13.083901 | controller | SELINUX_ROLE_REQUESTED=
2026-03-16 16:42:13.083906 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2026-03-16 16:42:13.083913 | controller | USER=zuul
2026-03-16 16:42:13.083918 | controller | ZUUL_VOTING=True
2026-03-16 16:42:13.083923 | controller | BUILD_TIMEOUT=1800000
2026-03-16 16:42:13.083929 | controller | SELINUX_USE_CURRENT_RANGE=
2026-03-16 16:42:13.083934 | controller | SHLVL=1
2026-03-16 16:42:13.083940 | controller | ZUUL_PATCHSET=235f23c012043ef9e6fbd3c71c40da700f09e62a
2026-03-16 16:42:13.083945 | controller | XDG_SESSION_ID=1
2026-03-16 16:42:13.083951 | controller | ZUUL_BRANCH=main
2026-03-16 16:42:13.083956 | controller | XDG_RUNTIME_DIR=/run/user/1000
2026-03-16 16:42:13.083961 | controller | SSH_CLIENT=192.168.26.12 41078 22
2026-03-16 16:42:13.083968 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2026-03-16 16:42:13.083974 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2026-03-16 16:42:13.083979 | controller | which_declare=declare -f
2026-03-16 16:42:13.083985 | controller | PATH=/home/zuul/.crc/bin/oc:/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2026-03-16 16:42:13.083991 | controller | SELINUX_LEVEL_REQUESTED=
2026-03-16 16:42:13.083996 | controller | ZUUL_CHANGE=3770
2026-03-16 16:42:13.084001 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2026-03-16 16:42:13.084006 | controller | ZUUL_UUID=f64ac7a214b14f99b6de88c519280f3c
2026-03-16 16:42:13.084012 | controller | BASH_FUNC_which%%=() {  ( alias;
2026-03-16 16:42:13.084017 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2026-03-16 16:42:13.084023 | controller | }
2026-03-16 16:42:13.346383 | controller | ok: Runtime: 0:00:00.005568
2026-03-16 16:42:13.351955 | 
2026-03-16 16:42:13.352038 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2026-03-16 16:42:13.405757 | controller | skipping: Conditional result was False
2026-03-16 16:42:13.412288 | 
2026-03-16 16:42:13.412413 | TASK [Symlink /home/zuul-worker/workspace]
2026-03-16 16:42:14.018482 | controller | skipping: Conditional result was False
2026-03-16 16:42:14.030202 | 
2026-03-16 16:42:14.030289 | TASK [Ensure legacy workspace directory]
2026-03-16 16:42:14.307028 | controller | changed
2026-03-16 16:42:14.367177 | 
2026-03-16 16:42:14.367255 | PLAY RECAP
2026-03-16 16:42:14.367412 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2026-03-16 16:42:14.367453 | 
2026-03-16 16:42:14.527918 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2026-03-16 16:42:14.528665 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2026-03-16 16:42:15.360094 | 
2026-03-16 16:42:15.360198 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2026-03-16 16:42:15.390197 | 
2026-03-16 16:42:15.390319 | TASK [Create zuul-output directory]
2026-03-16 16:42:15.816631 | controller | changed
2026-03-16 16:42:15.823071 | 
2026-03-16 16:42:15.823247 | TASK [Slurp Zuul inventory test]
2026-03-16 16:42:16.228757 | controller -> localhost | ok
2026-03-16 16:42:16.236583 | 
2026-03-16 16:42:16.236667 | TASK [Save zuul inventory]
2026-03-16 16:42:17.554237 | controller | changed
2026-03-16 16:42:17.560192 | 
2026-03-16 16:42:17.560274 | TASK [Save zuul vars without the change_message]
2026-03-16 16:42:18.679671 | controller | changed
2026-03-16 16:42:18.725851 | 
2026-03-16 16:42:18.725927 | PLAY RECAP
2026-03-16 16:42:18.725975 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-03-16 16:42:18.725999 | 
2026-03-16 16:42:18.887749 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2026-03-16 16:42:18.888796 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2026-03-16 16:42:19.610616 | 
2026-03-16 16:42:19.610719 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2026-03-16 16:42:19.633095 | 
2026-03-16 16:42:19.633218 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2026-03-16 16:42:19.662736 | controller | ok
2026-03-16 16:42:19.696942 | 
2026-03-16 16:42:19.697049 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2026-03-16 16:42:19.731780 | controller | skipping: Conditional result was False
2026-03-16 16:42:19.737441 | 
2026-03-16 16:42:19.737519 | TASK [mirror-info-fork : Create /etc/ci]
2026-03-16 16:42:20.142936 | controller | ok
2026-03-16 16:42:20.152064 | 
2026-03-16 16:42:20.152148 | TASK [mirror-info-fork : Install ci_mirror script]
2026-03-16 16:42:21.316076 | controller | ok
2026-03-16 16:42:21.355349 | 
2026-03-16 16:42:21.355449 | TASK [Prepare workspace]
2026-03-16 16:42:21.389582 | controller | ok
2026-03-16 16:42:21.428377 | 
2026-03-16 16:42:21.428477 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-03-16 16:42:22.036042 | controller | ok
2026-03-16 16:42:22.044398 | 
2026-03-16 16:42:22.044476 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-03-16 16:42:25.125861 | controller | Output suppressed because no_log was given
2026-03-16 16:42:25.135795 | 
2026-03-16 16:42:25.135900 | LOOP [Create zuul-output directory]
2026-03-16 16:42:25.417920 | controller | changed: "/home/zuul/ci-framework-data/logs"
2026-03-16 16:42:25.675850 | controller | ok: "/home/zuul/zuul-output/logs"
2026-03-16 16:42:25.688052 | 
2026-03-16 16:42:25.688158 | TASK [Install required packages]
2026-03-16 16:42:49.847009 | controller | ok: Nothing to do
2026-03-16 16:42:49.856235 | 
2026-03-16 16:42:49.856346 | TASK [Install venv]
2026-03-16 16:43:03.396622 | controller | ERROR
2026-03-16 16:43:03.396870 | controller | {
2026-03-16 16:43:03.396908 | controller |   "msg": "gmake: *** [Makefile:56: setup_tests] Error 1",
2026-03-16 16:43:03.396934 | controller |   "rc": 2
2026-03-16 16:43:03.396956 | controller | }
failure
2026-03-16 16:43:03.410050 | 
2026-03-16 16:43:03.410105 | PLAY RECAP
2026-03-16 16:43:03.410153 | controller | ok: 6 changed: 2 unreachable: 0 failed: 1 skipped: 1 rescued: 0 ignored: 0
2026-03-16 16:43:03.410177 | 
2026-03-16 16:43:03.742230 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2026-03-16 16:43:03.744048 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2026-03-16 16:43:04.594406 | 
2026-03-16 16:43:04.594537 | PLAY [Run ci/playbooks/collect-logs.yml]
2026-03-16 16:43:04.631777 | 
2026-03-16 16:43:04.631875 | TASK [Filter out host if needed]
2026-03-16 16:43:04.641111 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2026-03-16 16:43:04.645619 | 
2026-03-16 16:43:04.645689 | TASK [Ensure file is present]
2026-03-16 16:43:05.009022 | controller | ok
2026-03-16 16:43:05.015332 | 
2026-03-16 16:43:05.015398 | TASK [Manage molecule report file]
2026-03-16 16:43:05.576518 | controller | skipping: Conditional result was False
2026-03-16 16:43:05.582156 | 
2026-03-16 16:43:05.582241 | TASK [Check if we get ci-framework-data basedir]
2026-03-16 16:43:05.855549 | controller | ok
2026-03-16 16:43:05.861555 | 
2026-03-16 16:43:05.861631 | TASK [Create ci-framework-data log directory for zuul]
2026-03-16 16:43:06.303880 | controller | changed
2026-03-16 16:43:06.309368 | 
2026-03-16 16:43:06.309442 | TASK [Copy ci-framework interesting files]
2026-03-16 16:43:06.835938 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2026-03-16 16:43:06.876655 | controller | changed
2026-03-16 16:43:06.881517 | 
2026-03-16 16:43:06.881581 | TASK [Get SELinux listing]
2026-03-16 16:43:07.446395 | controller | changed
2026-03-16 16:43:07.452264 | 
2026-03-16 16:43:07.452346 | TASK [Generate log index]
2026-03-16 16:43:08.870994 | controller | changed
2026-03-16 16:43:08.879812 | 
2026-03-16 16:43:08.879892 | TASK [Get some env related data]
2026-03-16 16:43:09.548160 | controller | /usr/bin/ansible
2026-03-16 16:43:10.428668 | controller | changed
2026-03-16 16:43:10.434586 | 
2026-03-16 16:43:10.434665 | TASK [Generate list of logs to collect in home directory]
2026-03-16 16:43:10.939580 | controller | ok: All paths examined
2026-03-16 16:43:10.944895 | 
2026-03-16 16:43:10.944958 | LOOP [Copy logs from home directory]
2026-03-16 16:43:11.537114 | controller | changed:
2026-03-16 16:43:11.537348 | controller | {
2026-03-16 16:43:11.537389 | controller |   "atime": 1772005405.932821,
2026-03-16 16:43:11.537412 | controller |   "ctime": 1772006396.9238591,
2026-03-16 16:43:11.537431 | controller |   "dev": 64513,
2026-03-16 16:43:11.537449 | controller |   "gid": 1000,
2026-03-16 16:43:11.537465 | controller |   "gr_name": "zuul",
2026-03-16 16:43:11.537482 | controller |   "inode": 2773,
2026-03-16 16:43:11.537498 | controller |   "isblk": false,
2026-03-16 16:43:11.537513 | controller |   "ischr": false,
2026-03-16 16:43:11.537528 | controller |   "isdir": false,
2026-03-16 16:43:11.537543 | controller |   "isfifo": false,
2026-03-16 16:43:11.537557 | controller |   "isgid": false,
2026-03-16 16:43:11.537571 | controller |   "islnk": false,
2026-03-16 16:43:11.537585 | controller |   "isreg": true,
2026-03-16 16:43:11.537600 | controller |   "issock": false,
2026-03-16 16:43:11.537615 | controller |   "isuid": false,
2026-03-16 16:43:11.537639 | controller |   "mode": "0644",
2026-03-16 16:43:11.537656 | controller |   "mtime": 1772006396.9238591,
2026-03-16 16:43:11.537673 | controller |   "nlink": 1,
2026-03-16 16:43:11.537689 | controller |   "path": "/home/zuul/crc-setup.log",
2026-03-16 16:43:11.537707 | controller |   "pw_name": "zuul",
2026-03-16 16:43:11.537724 | controller |   "rgrp": true,
2026-03-16 16:43:11.537740 | controller |   "roth": true,
2026-03-16 16:43:11.537756 | controller |   "rusr": true,
2026-03-16 16:43:11.537771 | controller |   "size": 4108,
2026-03-16 16:43:11.537786 | controller |   "uid": 1000,
2026-03-16 16:43:11.537801 | controller |   "wgrp": false,
2026-03-16 16:43:11.537816 | controller |   "woth": false,
2026-03-16 16:43:11.537833 | controller |   "wusr": true,
2026-03-16 16:43:11.537849 | controller |   "xgrp": false,
2026-03-16 16:43:11.537866 | controller |   "xoth": false,
2026-03-16 16:43:11.537882 | controller |   "xusr": false
2026-03-16 16:43:11.537898 | controller | }
2026-03-16 16:43:12.163973 | controller | changed:
2026-03-16 16:43:12.164090 | controller | {
2026-03-16 16:43:12.164116 | controller |   "atime": 1772006398.6759083,
2026-03-16 16:43:12.164135 | controller |   "ctime": 1772007098.5537844,
2026-03-16 16:43:12.164151 | controller |   "dev": 64513,
2026-03-16 16:43:12.164166 | controller |   "gid": 1000,
2026-03-16 16:43:12.164181 | controller |   "gr_name": "zuul",
2026-03-16 16:43:12.164195 | controller |   "inode": 359205,
2026-03-16 16:43:12.164209 | controller |   "isblk": false,
2026-03-16 16:43:12.164223 | controller |   "ischr": false,
2026-03-16 16:43:12.164236 | controller |   "isdir": false,
2026-03-16 16:43:12.164249 | controller |   "isfifo": false,
2026-03-16 16:43:12.164264 | controller |   "isgid": false,
2026-03-16 16:43:12.164278 | controller |   "islnk": false,
2026-03-16 16:43:12.164308 | controller |   "isreg": true,
2026-03-16 16:43:12.164332 | controller |   "issock": false,
2026-03-16 16:43:12.164353 | controller |   "isuid": false,
2026-03-16 16:43:12.164369 | controller |   "mode": "0644",
2026-03-16 16:43:12.164384 | controller |   "mtime": 1772007098.5537844,
2026-03-16 16:43:12.164399 | controller |   "nlink": 1,
2026-03-16 16:43:12.164414 | controller |   "path": "/home/zuul/crc-start.log",
2026-03-16 16:43:12.164429 | controller |   "pw_name": "zuul",
2026-03-16 16:43:12.164445 | controller |   "rgrp": true,
2026-03-16 16:43:12.164459 | controller |   "roth": true,
2026-03-16 16:43:12.164473 | controller |   "rusr": true,
2026-03-16 16:43:12.164487 | controller |   "size": 4654,
2026-03-16 16:43:12.164501 | controller |   "uid": 1000,
2026-03-16 16:43:12.164514 | controller |   "wgrp": false,
2026-03-16 16:43:12.164527 | controller |   "woth": false,
2026-03-16 16:43:12.164540 | controller |   "wusr": true,
2026-03-16 16:43:12.164554 | controller |   "xgrp": false,
2026-03-16 16:43:12.164567 | controller |   "xoth": false,
2026-03-16 16:43:12.164580 | controller |   "xusr": false
2026-03-16 16:43:12.164594 | controller | }
2026-03-16 16:43:12.184710 | 
2026-03-16 16:43:12.184813 | TASK [Copy crio stats log file]
2026-03-16 16:43:12.233799 | controller | skipping: Conditional result was False
2026-03-16 16:43:12.240149 | 
2026-03-16 16:43:12.240250 | TASK [Get SELinux related data]
2026-03-16 16:43:12.578262 | controller | <no matches>
2026-03-16 16:43:12.788090 | controller | ERROR
2026-03-16 16:43:12.788257 | controller | {
2026-03-16 16:43:12.788315 | controller |   "delta": "0:00:00.011208",
2026-03-16 16:43:12.788348 | controller |   "end": "2026-03-16 16:43:12.579532",
2026-03-16 16:43:12.788370 | controller |   "msg": "non-zero return code",
2026-03-16 16:43:12.788389 | controller |   "rc": 1,
2026-03-16 16:43:12.788408 | controller |   "start": "2026-03-16 16:43:12.568324"
2026-03-16 16:43:12.788426 | controller | }
2026-03-16 16:43:12.788450 | controller | ERROR: Ignoring Errors
2026-03-16 16:43:12.804095 | 
2026-03-16 16:43:12.804167 | TASK [Create system configuration directory]
2026-03-16 16:43:13.067096 | controller | changed
2026-03-16 16:43:13.076734 | 
2026-03-16 16:43:13.076840 | TASK [Get some of the system configurations]
2026-03-16 16:43:13.629739 | controller | changed
2026-03-16 16:43:13.635544 | 
2026-03-16 16:43:13.635631 | TASK [Copy generated documentation if available]
2026-03-16 16:43:13.700197 | controller | skipping: Conditional result was False
2026-03-16 16:43:13.706505 | 
2026-03-16 16:43:13.706589 | TASK [Copy generated AsciiDoc documentation if available]
2026-03-16 16:43:13.784907 | controller | skipping: Conditional result was False
2026-03-16 16:43:13.790987 | 
2026-03-16 16:43:13.791082 | TASK [Compress logs bigger than 2MB]
2026-03-16 16:43:14.379372 | controller | changed
2026-03-16 16:43:14.385380 | 
2026-03-16 16:43:14.385462 | TASK [Copy files from workspace on node]
2026-03-16 16:43:14.435838 | controller | ok
2026-03-16 16:43:14.463180 | 
2026-03-16 16:43:14.463262 | TASK [fetch-output : Set log path for multiple nodes]
2026-03-16 16:43:14.509750 | controller | skipping: Conditional result was False
2026-03-16 16:43:14.515380 | 
2026-03-16 16:43:14.515451 | TASK [fetch-output : Set log path for single node]
2026-03-16 16:43:14.607049 | controller | ok
2026-03-16 16:43:14.612135 | 
2026-03-16 16:43:14.612206 | LOOP [fetch-output : Ensure local output dirs]
2026-03-16 16:43:15.215111 | controller -> localhost | ok: "/var/lib/zuul/builds/f64ac7a214b14f99b6de88c519280f3c/work/logs"
2026-03-16 16:43:15.215377 | controller -> localhost | changed: All items complete
2026-03-16 16:43:15.215411 | 
2026-03-16 16:43:15.638839 | controller -> localhost | changed: "/var/lib/zuul/builds/f64ac7a214b14f99b6de88c519280f3c/work/artifacts"
2026-03-16 16:43:16.167792 | controller -> localhost | changed: "/var/lib/zuul/builds/f64ac7a214b14f99b6de88c519280f3c/work/docs"
2026-03-16 16:43:16.181799 | 
2026-03-16 16:43:16.181884 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-03-16 16:43:17.071367 | controller | changed:
2026-03-16 16:43:17.071548 | controller | .d..t...... ./
2026-03-16 16:43:17.071580 | controller | >f+++++++++ README.html
2026-03-16 16:43:17.071603 | controller | >f+++++++++ crc-setup.log
2026-03-16 16:43:17.071627 | controller | >f+++++++++ crc-start.log
2026-03-16 16:43:17.071646 | controller | >f+++++++++ dmesg.log
2026-03-16 16:43:17.071666 | controller | >f+++++++++ installed-pkgs.log
2026-03-16 16:43:17.071684 | controller | >f+++++++++ python.log
2026-03-16 16:43:17.071702 | controller | >f+++++++++ registries.conf
2026-03-16 16:43:17.071719 | controller | >f+++++++++ selinux-denials.log
2026-03-16 16:43:17.071736 | controller | >f+++++++++ selinux-listing.log
2026-03-16 16:43:17.071753 | controller | cd+++++++++ ci-framework-data/
2026-03-16 16:43:17.071770 | controller | cd+++++++++ ci-framework-data/artifacts/
2026-03-16 16:43:17.071787 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2026-03-16 16:43:17.071804 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2026-03-16 16:43:17.071820 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2026-03-16 16:43:17.071837 | controller | cd+++++++++ ci-framework-data/logs/
2026-03-16 16:43:17.071853 | controller | cd+++++++++ registries.conf.d/
2026-03-16 16:43:17.071871 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2026-03-16 16:43:17.071888 | controller | cd+++++++++ system-config/
2026-03-16 16:43:17.071905 | controller | cd+++++++++ system-config/libvirt/
2026-03-16 16:43:17.071921 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2026-03-16 16:43:17.071937 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2026-03-16 16:43:17.071954 | controller | >f+++++++++ system-config/libvirt/libvirtd.conf
2026-03-16 16:43:17.071970 | controller | >f+++++++++ system-config/libvirt/network.conf
2026-03-16 16:43:17.071987 | controller | >f+++++++++ system-config/libvirt/qemu-lockd.conf
2026-03-16 16:43:17.072003 | controller | >f+++++++++ system-config/libvirt/qemu.conf
2026-03-16 16:43:17.072037 | controller | >f+++++++++ system-config/libvirt/virtinterfaced.conf
2026-03-16 16:43:17.072066 | controller | >f+++++++++ system-config/libvirt/virtlockd.conf
2026-03-16 16:43:17.072087 | controller | >f+++++++++ system-config/libvirt/virtlogd.conf
2026-03-16 16:43:17.072105 | controller | >f+++++++++ system-config/libvirt/virtnetworkd.conf
2026-03-16 16:43:17.072122 | controller | >f+++++++++ system-config/libvirt/virtnodedevd.conf
2026-03-16 16:43:17.072139 | controller | >f+++++++++ system-config/libvirt/virtnwfilterd.conf
2026-03-16 16:43:17.072156 | controller | >f+++++++++ system-config/libvirt/virtproxyd.conf
2026-03-16 16:43:17.072173 | controller | >f+++++++++ system-config/libvirt/virtqemud.conf
2026-03-16 16:43:17.072189 | controller | >f+++++++++ system-config/libvirt/virtsecretd.conf
2026-03-16 16:43:17.072206 | controller | >f+++++++++ system-config/libvirt/virtstoraged.conf
2026-03-16 16:43:17.824881 | controller | changed: .d..t...... ./
2026-03-16 16:43:18.445084 | controller | changed: .d..t...... ./
2026-03-16 16:43:18.470481 | 
2026-03-16 16:43:18.470571 | TASK [Return artifact to Zuul]
2026-03-16 16:43:18.520956 | controller | skipping: Conditional result was False
2026-03-16 16:43:18.557461 | 
2026-03-16 16:43:18.557529 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2026-03-16 16:43:18.557631 | 
2026-03-16 16:43:18.557699 | PLAY RECAP
2026-03-16 16:43:18.557770 | controller | ok: 16 changed: 12 unreachable: 0 failed: 0 skipped: 6 rescued: 0 ignored: 1
2026-03-16 16:43:18.557794 | 
2026-03-16 16:43:18.779990 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2026-03-16 16:43:18.780909 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2026-03-16 16:43:19.576741 | 
2026-03-16 16:43:19.576835 | PLAY [all]
2026-03-16 16:43:19.609291 | 
2026-03-16 16:43:19.609433 | TASK [include_role : fetch-output]
2026-03-16 16:43:19.680989 | controller | ok
2026-03-16 16:43:19.698397 | 
2026-03-16 16:43:19.698530 | TASK [fetch-output : Set log path for multiple nodes]
2026-03-16 16:43:19.753103 | controller | skipping: Conditional result was False
2026-03-16 16:43:19.758983 | 
2026-03-16 16:43:19.759076 | TASK [fetch-output : Set log path for single node]
2026-03-16 16:43:19.807549 | controller | ok
2026-03-16 16:43:19.814699 | 
2026-03-16 16:43:19.814779 | LOOP [fetch-output : Ensure local output dirs]
2026-03-16 16:43:20.334974 | controller -> localhost | ok: "/var/lib/zuul/builds/f64ac7a214b14f99b6de88c519280f3c/work/logs"
2026-03-16 16:43:20.624414 | controller -> localhost | ok: "/var/lib/zuul/builds/f64ac7a214b14f99b6de88c519280f3c/work/artifacts"
2026-03-16 16:43:21.006535 | controller -> localhost | ok: "/var/lib/zuul/builds/f64ac7a214b14f99b6de88c519280f3c/work/docs"
2026-03-16 16:43:21.024607 | 
2026-03-16 16:43:21.024704 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-03-16 16:43:21.779794 | controller | ok
2026-03-16 16:43:21.779995 | controller | ok: All items complete
2026-03-16 16:43:21.780042 | 
2026-03-16 16:43:22.397475 | controller | ok
2026-03-16 16:43:23.084801 | controller | ok
2026-03-16 16:43:23.105556 | 
2026-03-16 16:43:23.105648 | TASK [include_role : fetch-output-openshift]
2026-03-16 16:43:23.139997 | controller | skipping: Conditional result was False
2026-03-16 16:43:23.147408 | 
2026-03-16 16:43:23.147491 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-03-16 16:43:23.919878 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.045175
2026-03-16 16:43:24.537731 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.054567
2026-03-16 16:43:24.631640 | 
2026-03-16 16:43:24.631727 | PLAY [all]
2026-03-16 16:43:24.687543 | 
2026-03-16 16:43:24.687654 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-03-16 16:43:25.259983 | controller | changed
2026-03-16 16:43:25.325665 | 
2026-03-16 16:43:25.325753 | PLAY RECAP
2026-03-16 16:43:25.325802 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2026-03-16 16:43:25.325834 | 
2026-03-16 16:43:25.509692 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2026-03-16 16:43:25.510420 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2026-03-16 16:43:26.638252 | 
2026-03-16 16:43:26.638417 | PLAY [localhost]
2026-03-16 16:43:26.666639 | 
2026-03-16 16:43:26.666765 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-03-16 16:43:27.275629 | localhost | changed
2026-03-16 16:43:27.280780 | 
2026-03-16 16:43:27.280877 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-03-16 16:43:27.332309 | localhost | ok
2026-03-16 16:43:27.340592 | 
2026-03-16 16:43:27.340669 | TASK [add-fileserver : Create SSH private key tempfile]
2026-03-16 16:43:27.976745 | localhost | changed
2026-03-16 16:43:27.982198 | 
2026-03-16 16:43:27.982286 | TASK [add-fileserver : Create SSH private key from secret]
2026-03-16 16:43:29.416722 | localhost | changed
2026-03-16 16:43:29.421828 | 
2026-03-16 16:43:29.421912 | TASK [add-fileserver : Add fileserver ssh key]
2026-03-16 16:43:30.098189 | localhost | Identity added: /var/lib/zuul/builds/f64ac7a214b14f99b6de88c519280f3c/work/tmp/ansible.qc41e2ec (/var/lib/zuul/builds/f64ac7a214b14f99b6de88c519280f3c/work/tmp/ansible.qc41e2ec)
2026-03-16 16:43:30.098383 | localhost | ok: Runtime: 0:00:00.012940
2026-03-16 16:43:30.102746 | 
2026-03-16 16:43:30.102822 | TASK [add-fileserver : Remove SSH private key from disk]
2026-03-16 16:43:30.459823 | localhost | ok: Runtime: 0:00:00.015532
2026-03-16 16:43:30.464547 | 
2026-03-16 16:43:30.464616 | TASK [add-fileserver : Add fileserver to inventory]
2026-03-16 16:43:30.539747 | localhost | changed
2026-03-16 16:43:30.545330 | 
2026-03-16 16:43:30.545411 | TASK [add-fileserver : Add fileserver server to known hosts]
2026-03-16 16:43:31.170332 | localhost | changed
2026-03-16 16:43:31.227588 | 
2026-03-16 16:43:31.227694 | PLAY [localhost]
2026-03-16 16:43:31.268575 | 
2026-03-16 16:43:31.268670 | TASK [Generate bulk log download script]
2026-03-16 16:43:31.319529 | localhost | ok
2026-03-16 16:43:31.344729 | 
2026-03-16 16:43:31.350168 | TASK [local-log-download : Check API endpoint is defined]
2026-03-16 16:43:31.417450 | localhost | ok: All assertions passed
2026-03-16 16:43:31.429989 | 
2026-03-16 16:43:31.435436 | TASK [local-log-download : Create download script]
2026-03-16 16:43:32.014675 | localhost -> localhost | changed
2026-03-16 16:43:32.023355 | 
2026-03-16 16:43:32.023426 | TASK [Register quick-download link]
2026-03-16 16:43:32.047690 | localhost | ok
2026-03-16 16:43:32.082838 | 
2026-03-16 16:43:32.082930 | PLAY [logserver.rdoproject.org]
2026-03-16 16:43:32.100681 | 
2026-03-16 16:43:32.100758 | TASK [Set zuul-log-path fact]
2026-03-16 16:43:32.150152 | logserver.rdoproject.org | ok
2026-03-16 16:43:32.169043 | 
2026-03-16 16:43:32.169130 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-03-16 16:43:32.196095 | logserver.rdoproject.org | ok
2026-03-16 16:43:32.202974 | 
2026-03-16 16:43:32.203064 | TASK [upload-logs : Create log directories]
2026-03-16 16:43:35.381977 | logserver.rdoproject.org | changed
2026-03-16 16:43:35.385377 | 
2026-03-16 16:43:35.385456 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-03-16 16:43:35.912173 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.012085
2026-03-16 16:43:35.922355 | 
2026-03-16 16:43:35.922447 | TASK [upload-logs : Upload logs to log server]
2026-03-16 16:43:37.731678 | logserver.rdoproject.org | Output suppressed because no_log was given
2026-03-16 16:43:37.740500 | 
2026-03-16 16:43:37.740588 | LOOP [upload-logs : Compress console log and json output]
2026-03-16 16:43:37.814442 | logserver.rdoproject.org | skipping: Conditional result was False
2026-03-16 16:43:37.817117 | logserver.rdoproject.org | skipping: Conditional result was False
2026-03-16 16:43:37.829274 | 
2026-03-16 16:43:37.829395 | LOOP [upload-logs : Upload compressed console log and json output]
2026-03-16 16:43:37.912694 | logserver.rdoproject.org | skipping: Conditional result was False
2026-03-16 16:43:37.912930 | 
2026-03-16 16:43:37.916862 | logserver.rdoproject.org | skipping: Conditional result was False
2026-03-16 16:43:37.929217 | 
2026-03-16 16:43:37.929565 | LOOP [upload-logs : Upload console log and json output]