2026-03-16 16:45:55.459065 | Job console starting...
2026-03-16 16:45:55.681585 | Updating repositories
2026-03-16 16:45:56.549537 | Preparing job workspace
2026-03-16 16:46:06.430936 | Running Ansible setup...
2026-03-16 16:46:12.026956 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2026-03-16 16:46:13.177499 | 
2026-03-16 16:46:13.177597 | PLAY [localhost]
2026-03-16 16:46:13.203869 | 
2026-03-16 16:46:13.203959 | TASK [Gathering Facts]
2026-03-16 16:46:14.793911 | localhost | ok
2026-03-16 16:46:14.818945 | 
2026-03-16 16:46:14.820492 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-03-16 16:46:15.596991 | localhost -> localhost | changed
2026-03-16 16:46:15.613717 | 
2026-03-16 16:46:15.613833 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-03-16 16:46:17.302401 | localhost -> localhost | changed
2026-03-16 16:46:17.314014 | 
2026-03-16 16:46:17.314131 | TASK [Setup log path fact]
2026-03-16 16:46:17.370833 | localhost | ok
2026-03-16 16:46:17.387683 | 
2026-03-16 16:46:17.387764 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-03-16 16:46:17.441603 | localhost | ok
2026-03-16 16:46:17.449843 | 
2026-03-16 16:46:17.449924 | TASK [emit-job-header : Print job information]
2026-03-16 16:46:17.535612 | # Job Information
2026-03-16 16:46:17.535761 | Ansible Version: 2.15.12
2026-03-16 16:46:17.535792 | Job: cifmw-molecule-operator_deploy
2026-03-16 16:46:17.535815 | Pipeline: github-check
2026-03-16 16:46:17.535836 | Executor: ibm-bm4-ze.softwarefactory-project.io
2026-03-16 16:46:17.535856 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3770
2026-03-16 16:46:17.535877 | Log URL (when completed): https://logserver.rdoproject.org/0fb/rdoproject.org/0fb524ae3b5a427fac60332cc0ba85b7/
2026-03-16 16:46:17.535896 | Event ID: 072a8580-2155-11f1-9026-7307b7bf6b98
2026-03-16 16:46:17.539688 | 
2026-03-16 16:46:17.539760 | LOOP [emit-job-header : Print node information]
2026-03-16 16:46:17.701166 | localhost | ok:
2026-03-16 16:46:17.701333 | localhost | # Node Information
2026-03-16 16:46:17.701371 | localhost | Inventory Hostname: controller
2026-03-16 16:46:17.701400 | localhost | Hostname: np0005648942
2026-03-16 16:46:17.701422 | localhost | Username: zuul
2026-03-16 16:46:17.701444 | localhost | Distro: CentOS 9
2026-03-16 16:46:17.701464 | localhost | Provider: ibm-bm4-nodepool
2026-03-16 16:46:17.701482 | localhost | Region: regionOne
2026-03-16 16:46:17.701501 | localhost | Label: centos-9-stream-crc-2-48-0-xl
2026-03-16 16:46:17.701520 | localhost | Product Name: OpenStack Compute
2026-03-16 16:46:17.701539 | localhost | Interface IP: 192.168.26.184
2026-03-16 16:46:17.739453 | 
2026-03-16 16:46:17.739538 | PLAY [all]
2026-03-16 16:46:17.752337 | 
2026-03-16 16:46:17.752412 | TASK [Gather network facts]
2026-03-16 16:46:18.198792 | controller | ok
2026-03-16 16:46:18.229852 | 
2026-03-16 16:46:18.229945 | TASK [include_role : start-zuul-console]
2026-03-16 16:46:18.259143 | controller | ok
2026-03-16 16:46:18.281168 | 
2026-03-16 16:46:18.281240 | TASK [start-zuul-console : Start zuul_console daemon.]
2026-03-16 16:46:18.864202 | controller | ok
2026-03-16 16:46:18.880356 | 
2026-03-16 16:46:18.880456 | TASK [include_role : add-build-sshkey]
2026-03-16 16:46:18.909995 | controller | ok
2026-03-16 16:46:18.933916 | 
2026-03-16 16:46:18.934024 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-03-16 16:46:19.374543 | controller -> localhost | ok
2026-03-16 16:46:19.380272 | 
2026-03-16 16:46:19.380387 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-03-16 16:46:19.428863 | controller | ok
2026-03-16 16:46:19.457784 | controller | included: /var/lib/zuul/builds/0fb524ae3b5a427fac60332cc0ba85b7/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-03-16 16:46:19.488550 | 
2026-03-16 16:46:19.488627 | TASK [add-build-sshkey : Create Temp SSH key]
2026-03-16 16:46:20.374088 | controller -> localhost | Generating public/private rsa key pair.
2026-03-16 16:46:20.374315 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/0fb524ae3b5a427fac60332cc0ba85b7/work/0fb524ae3b5a427fac60332cc0ba85b7_id_rsa.
2026-03-16 16:46:20.374355 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/0fb524ae3b5a427fac60332cc0ba85b7/work/0fb524ae3b5a427fac60332cc0ba85b7_id_rsa.pub.
2026-03-16 16:46:20.374379 | controller -> localhost | The key fingerprint is:
2026-03-16 16:46:20.374398 | controller -> localhost | SHA256:E6XRHrbpasi1GcnTF2Nz5UuF6L/uCJ2oUkJSxsQV1wk zuul-build-sshkey
2026-03-16 16:46:20.374419 | controller -> localhost | The key's randomart image is:
2026-03-16 16:46:20.374435 | controller -> localhost | +---[RSA 3072]----+
2026-03-16 16:46:20.374452 | controller -> localhost | |     +..+oEo o . |
2026-03-16 16:46:20.374469 | controller -> localhost | |      =  =+ + . o|
2026-03-16 16:46:20.374485 | controller -> localhost | |     o  oo =   o.|
2026-03-16 16:46:20.374501 | controller -> localhost | |    . .  .+ * ...|
2026-03-16 16:46:20.374517 | controller -> localhost | |     o .S+ . *. .|
2026-03-16 16:46:20.374532 | controller -> localhost | |      . B.oo.... |
2026-03-16 16:46:20.374557 | controller -> localhost | |     . = *o.o  . |
2026-03-16 16:46:20.374573 | controller -> localhost | |      + =. . ..  |
2026-03-16 16:46:20.374590 | controller -> localhost | |       o.   .oo  |
2026-03-16 16:46:20.374605 | controller -> localhost | +----[SHA256]-----+
2026-03-16 16:46:20.374644 | controller -> localhost | ok: Runtime: 0:00:00.264867
2026-03-16 16:46:20.380935 | 
2026-03-16 16:46:20.381013 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-03-16 16:46:20.412874 | controller | ok
2026-03-16 16:46:20.422573 | controller | included: /var/lib/zuul/builds/0fb524ae3b5a427fac60332cc0ba85b7/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-03-16 16:46:20.439731 | 
2026-03-16 16:46:20.439843 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-03-16 16:46:20.474093 | controller | skipping: Conditional result was False
2026-03-16 16:46:20.480742 | 
2026-03-16 16:46:20.480837 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-03-16 16:46:21.131495 | controller | changed
2026-03-16 16:46:21.136263 | 
2026-03-16 16:46:21.136375 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-03-16 16:46:21.422123 | controller | ok
2026-03-16 16:46:21.427244 | 
2026-03-16 16:46:21.427328 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-03-16 16:46:22.624213 | controller | changed
2026-03-16 16:46:22.631148 | 
2026-03-16 16:46:22.631247 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-03-16 16:46:23.769882 | controller | changed
2026-03-16 16:46:23.776598 | 
2026-03-16 16:46:23.776666 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-03-16 16:46:23.821557 | controller | skipping: Conditional result was False
2026-03-16 16:46:23.827274 | 
2026-03-16 16:46:23.827361 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-03-16 16:46:24.394588 | controller -> localhost | changed
2026-03-16 16:46:24.411908 | 
2026-03-16 16:46:24.411993 | TASK [add-build-sshkey : Add back temp key]
2026-03-16 16:46:24.997707 | controller -> localhost | Identity added: /var/lib/zuul/builds/0fb524ae3b5a427fac60332cc0ba85b7/work/0fb524ae3b5a427fac60332cc0ba85b7_id_rsa (zuul-build-sshkey)
2026-03-16 16:46:24.997979 | controller -> localhost | ok: Runtime: 0:00:00.023059
2026-03-16 16:46:25.003571 | 
2026-03-16 16:46:25.003638 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-03-16 16:46:25.457285 | controller | ok
2026-03-16 16:46:25.463389 | 
2026-03-16 16:46:25.463477 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-03-16 16:46:25.632222 | controller | skipping: Conditional result was False
2026-03-16 16:46:25.651493 | 
2026-03-16 16:46:25.651570 | TASK [include_role : validate-host]
2026-03-16 16:46:25.681041 | controller | ok
2026-03-16 16:46:25.713224 | 
2026-03-16 16:46:25.713346 | TASK [validate-host : Define zuul_info_dir fact]
2026-03-16 16:46:25.762399 | controller | ok
2026-03-16 16:46:25.766915 | 
2026-03-16 16:46:25.766985 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2026-03-16 16:46:26.056083 | controller -> localhost | ok
2026-03-16 16:46:26.065852 | 
2026-03-16 16:46:26.065959 | TASK [validate-host : Collect information about the host]
2026-03-16 16:46:26.730335 | controller | ok
2026-03-16 16:46:26.740451 | 
2026-03-16 16:46:26.740566 | TASK [validate-host : Sanitize hostname]
2026-03-16 16:46:26.802682 | controller | ok
2026-03-16 16:46:26.810532 | 
2026-03-16 16:46:26.810645 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2026-03-16 16:46:27.364119 | controller -> localhost | changed
2026-03-16 16:46:27.370713 | 
2026-03-16 16:46:27.370808 | TASK [validate-host : Collect information about zuul worker]
2026-03-16 16:46:27.816952 | controller | ok
2026-03-16 16:46:27.822849 | 
2026-03-16 16:46:27.822935 | TASK [validate-host : Write out all zuul information for each host]
2026-03-16 16:46:28.403003 | controller -> localhost | changed
2026-03-16 16:46:28.424418 | 
2026-03-16 16:46:28.424507 | TASK [include_role : prepare-workspace-openshift]
2026-03-16 16:46:28.463857 | controller | skipping: Conditional result was False
2026-03-16 16:46:28.469595 | 
2026-03-16 16:46:28.469673 | TASK [include_role : remove-zuul-sshkey]
2026-03-16 16:46:28.504665 | controller | skipping: Conditional result was False
2026-03-16 16:46:28.517137 | 
2026-03-16 16:46:28.517236 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-03-16 16:46:28.797448 | controller | ok: "logs"
2026-03-16 16:46:28.797669 | controller | ok: All items complete
2026-03-16 16:46:28.797701 | 
2026-03-16 16:46:29.049861 | controller | ok: "artifacts"
2026-03-16 16:46:29.300026 | controller | ok: "docs"
2026-03-16 16:46:29.314122 | 
2026-03-16 16:46:29.314215 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-03-16 16:46:29.617713 | controller | changed: "logs"
2026-03-16 16:46:29.894420 | controller | changed: "artifacts"
2026-03-16 16:46:30.154433 | controller | changed: "docs"
2026-03-16 16:46:30.213369 | 
2026-03-16 16:46:30.213445 | PLAY RECAP
2026-03-16 16:46:30.213503 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2026-03-16 16:46:30.213533 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-03-16 16:46:30.213553 | 
2026-03-16 16:46:30.320989 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2026-03-16 16:46:30.321681 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2026-03-16 16:46:31.128274 | 
2026-03-16 16:46:31.128418 | PLAY [all]
2026-03-16 16:46:31.156925 | 
2026-03-16 16:46:31.157025 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2026-03-16 16:46:31.267724 | controller | ok
2026-03-16 16:46:31.273097 | 
2026-03-16 16:46:31.273179 | TASK [mirror-info-fork : Create /etc/ci]
2026-03-16 16:46:31.774804 | controller | changed
2026-03-16 16:46:31.780699 | 
2026-03-16 16:46:31.780775 | TASK [mirror-info-fork : Install ci_mirror script]
2026-03-16 16:46:33.223102 | controller | changed
2026-03-16 16:46:33.234063 | 
2026-03-16 16:46:33.234156 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2026-03-16 16:46:33.733610 | controller | changed:
2026-03-16 16:46:33.733774 | controller | {
2026-03-16 16:46:33.733807 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2026-03-16 16:46:33.733839 | controller | }
2026-03-16 16:46:34.017160 | controller | changed:
2026-03-16 16:46:34.017239 | controller | {
2026-03-16 16:46:34.017270 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2026-03-16 16:46:34.017323 | controller | }
2026-03-16 16:46:34.299186 | controller | changed:
2026-03-16 16:46:34.299262 | controller | {
2026-03-16 16:46:34.299290 | 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:46:34.299453 | controller | }
2026-03-16 16:46:34.588154 | controller | changed:
2026-03-16 16:46:34.588228 | controller | {
2026-03-16 16:46:34.588258 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2026-03-16 16:46:34.588280 | controller | }
2026-03-16 16:46:34.886399 | controller | changed:
2026-03-16 16:46:34.886477 | controller | {
2026-03-16 16:46:34.886506 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2026-03-16 16:46:34.886528 | controller | }
2026-03-16 16:46:35.200454 | controller | changed:
2026-03-16 16:46:35.200540 | controller | {
2026-03-16 16:46:35.200569 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2026-03-16 16:46:35.200593 | controller | }
2026-03-16 16:46:35.485454 | controller | changed:
2026-03-16 16:46:35.485531 | controller | {
2026-03-16 16:46:35.488136 | 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:46:35.488205 | controller | }
2026-03-16 16:46:35.777613 | controller | changed:
2026-03-16 16:46:35.777692 | controller | {
2026-03-16 16:46:35.777721 | 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:46:35.777744 | controller | }
2026-03-16 16:46:36.094405 | controller | changed:
2026-03-16 16:46:36.094588 | controller | {
2026-03-16 16:46:36.094628 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2026-03-16 16:46:36.094654 | controller | }
2026-03-16 16:46:36.364109 | controller | changed:
2026-03-16 16:46:36.364187 | controller | {
2026-03-16 16:46:36.364214 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2026-03-16 16:46:36.364235 | controller | }
2026-03-16 16:46:36.660498 | controller | changed:
2026-03-16 16:46:36.660590 | controller | {
2026-03-16 16:46:36.660621 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2026-03-16 16:46:36.660645 | controller | }
2026-03-16 16:46:36.971048 | controller | changed:
2026-03-16 16:46:36.971151 | controller | {
2026-03-16 16:46:36.971179 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2026-03-16 16:46:36.971200 | controller | }
2026-03-16 16:46:37.262444 | controller | changed:
2026-03-16 16:46:37.262548 | controller | {
2026-03-16 16:46:37.262576 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICWBreHW95Wz2Toz5YwCGQwFcUG8oFYkienDh9tntmDc ralfieri@redhat.com"
2026-03-16 16:46:37.262597 | controller | }
2026-03-16 16:46:37.546236 | controller | changed:
2026-03-16 16:46:37.546343 | controller | {
2026-03-16 16:46:37.546372 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2026-03-16 16:46:37.546394 | controller | }
2026-03-16 16:46:37.847970 | controller | changed:
2026-03-16 16:46:37.848062 | controller | {
2026-03-16 16:46:37.848092 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2026-03-16 16:46:37.848114 | controller | }
2026-03-16 16:46:38.146774 | controller | changed:
2026-03-16 16:46:38.146866 | controller | {
2026-03-16 16:46:38.146896 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2026-03-16 16:46:38.146919 | controller | }
2026-03-16 16:46:38.426097 | controller | changed:
2026-03-16 16:46:38.426176 | controller | {
2026-03-16 16:46:38.426203 | 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:46:38.426225 | controller | }
2026-03-16 16:46:38.715842 | controller | changed:
2026-03-16 16:46:38.715920 | controller | {
2026-03-16 16:46:38.715949 | 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:46:38.715979 | controller | }
2026-03-16 16:46:39.013029 | controller | changed:
2026-03-16 16:46:39.013110 | controller | {
2026-03-16 16:46:39.013137 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2026-03-16 16:46:39.013157 | controller | }
2026-03-16 16:46:39.290590 | controller | changed:
2026-03-16 16:46:39.290671 | controller | {
2026-03-16 16:46:39.290700 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2026-03-16 16:46:39.290721 | controller | }
2026-03-16 16:46:39.568994 | controller | changed:
2026-03-16 16:46:39.569085 | controller | {
2026-03-16 16:46:39.569114 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2026-03-16 16:46:39.569135 | controller | }
2026-03-16 16:46:39.869139 | controller | changed:
2026-03-16 16:46:39.869219 | controller | {
2026-03-16 16:46:39.869247 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2026-03-16 16:46:39.869269 | controller | }
2026-03-16 16:46:40.151495 | controller | changed:
2026-03-16 16:46:40.151591 | controller | {
2026-03-16 16:46:40.151618 | 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:46:40.151639 | controller | }
2026-03-16 16:46:40.446185 | controller | changed:
2026-03-16 16:46:40.446264 | controller | {
2026-03-16 16:46:40.446325 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2026-03-16 16:46:40.446363 | controller | }
2026-03-16 16:46:40.731090 | controller | changed:
2026-03-16 16:46:40.731173 | controller | {
2026-03-16 16:46:40.731202 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2026-03-16 16:46:40.731223 | controller | }
2026-03-16 16:46:41.025090 | controller | changed:
2026-03-16 16:46:41.025168 | controller | {
2026-03-16 16:46:41.025198 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2026-03-16 16:46:41.025230 | controller | }
2026-03-16 16:46:41.057312 | 
2026-03-16 16:46:41.057413 | TASK [Set timezone to UTC]
2026-03-16 16:46:41.595699 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2026-03-16 16:46:41.600469 | 
2026-03-16 16:46:41.600549 | TASK [Create nodepool directory]
2026-03-16 16:46:41.883784 | controller | changed
2026-03-16 16:46:41.889168 | 
2026-03-16 16:46:41.889247 | TASK [Create nodepool sub_nodes file]
2026-03-16 16:46:42.872520 | controller | changed
2026-03-16 16:46:42.881876 | 
2026-03-16 16:46:42.881961 | TASK [Create nodepool sub_nodes_private file]
2026-03-16 16:46:43.831464 | controller | changed
2026-03-16 16:46:43.837316 | 
2026-03-16 16:46:43.837397 | LOOP [Populate nodepool sub_nodes file]
2026-03-16 16:46:43.877162 | 
2026-03-16 16:46:43.877289 | LOOP [Populate nodepool sub_nodes_private file]
2026-03-16 16:46:43.905827 | 
2026-03-16 16:46:43.905948 | TASK [Create nodepool primary file]
2026-03-16 16:46:43.951948 | controller | skipping: Conditional result was False
2026-03-16 16:46:43.957487 | 
2026-03-16 16:46:43.957567 | TASK [Create nodepool node_private for this node]
2026-03-16 16:46:44.963753 | controller | changed
2026-03-16 16:46:44.969503 | 
2026-03-16 16:46:44.969575 | LOOP [Copy ssh keys to nodepool directory]
2026-03-16 16:46:45.371495 | controller | ok: Item: id_rsa Runtime: 0:00:00.004402
2026-03-16 16:46:45.371653 | 
2026-03-16 16:46:45.601483 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.005598
2026-03-16 16:46:45.613628 | 
2026-03-16 16:46:45.613716 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2026-03-16 16:46:46.607494 | controller | changed
2026-03-16 16:46:46.613526 | 
2026-03-16 16:46:46.613691 | TASK [Validate sudoers config after edits]
2026-03-16 16:46:47.000133 | controller | /etc/sudoers: parsed OK
2026-03-16 16:46:47.000192 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2026-03-16 16:46:47.000202 | controller | /etc/sudoers.d/zuul: parsed OK
2026-03-16 16:46:47.000209 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2026-03-16 16:46:47.208346 | controller | ok: Runtime: 0:00:00.005343
2026-03-16 16:46:47.213698 | 
2026-03-16 16:46:47.213764 | TASK [Show the environment passed in to job shell scripts]
2026-03-16 16:46:47.481120 | controller | SHELL=/bin/bash
2026-03-16 16:46:47.481170 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2026-03-16 16:46:47.481181 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2026-03-16 16:46:47.481189 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/70/3770/235f23c012043ef9e6fbd3c71c40da700f09e62a
2026-03-16 16:46:47.481195 | controller | PWD=/home/zuul
2026-03-16 16:46:47.481201 | controller | ZUUL_PIPELINE=github-check
2026-03-16 16:46:47.481207 | controller | LOGNAME=zuul
2026-03-16 16:46:47.481212 | controller | XDG_SESSION_TYPE=tty
2026-03-16 16:46:47.481259 | controller | _=/usr/bin/env
2026-03-16 16:46:47.481267 | controller | MOTD_SHOWN=pam
2026-03-16 16:46:47.481273 | controller | HOME=/home/zuul
2026-03-16 16:46:47.481278 | controller | LANG=en_US.UTF-8
2026-03-16 16:46:47.481283 | controller | SSH_CONNECTION=192.168.26.12 37692 192.168.26.184 22
2026-03-16 16:46:47.481289 | 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:46:47.481298 | controller | ZUUL_CHANGE_IDS=3770,235f23c012043ef9e6fbd3c71c40da700f09e62a
2026-03-16 16:46:47.481304 | controller | WORKSPACE=/home/zuul/workspace
2026-03-16 16:46:47.481309 | controller | XDG_SESSION_CLASS=user
2026-03-16 16:46:47.481315 | controller | SELINUX_ROLE_REQUESTED=
2026-03-16 16:46:47.481320 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2026-03-16 16:46:47.481325 | controller | USER=zuul
2026-03-16 16:46:47.481331 | controller | ZUUL_VOTING=True
2026-03-16 16:46:47.481336 | controller | BUILD_TIMEOUT=1800000
2026-03-16 16:46:47.481341 | controller | SELINUX_USE_CURRENT_RANGE=
2026-03-16 16:46:47.481346 | controller | SHLVL=1
2026-03-16 16:46:47.481352 | controller | ZUUL_PATCHSET=235f23c012043ef9e6fbd3c71c40da700f09e62a
2026-03-16 16:46:47.481357 | controller | XDG_SESSION_ID=1
2026-03-16 16:46:47.481363 | controller | ZUUL_BRANCH=main
2026-03-16 16:46:47.481368 | controller | XDG_RUNTIME_DIR=/run/user/1000
2026-03-16 16:46:47.481373 | controller | SSH_CLIENT=192.168.26.12 37692 22
2026-03-16 16:46:47.481379 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2026-03-16 16:46:47.481384 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2026-03-16 16:46:47.481390 | controller | which_declare=declare -f
2026-03-16 16:46:47.481396 | 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:46:47.481401 | controller | SELINUX_LEVEL_REQUESTED=
2026-03-16 16:46:47.481407 | controller | ZUUL_CHANGE=3770
2026-03-16 16:46:47.481412 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2026-03-16 16:46:47.481417 | controller | ZUUL_UUID=0fb524ae3b5a427fac60332cc0ba85b7
2026-03-16 16:46:47.481422 | controller | BASH_FUNC_which%%=() {  ( alias;
2026-03-16 16:46:47.481428 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2026-03-16 16:46:47.481434 | controller | }
2026-03-16 16:46:47.740335 | controller | ok: Runtime: 0:00:00.005805
2026-03-16 16:46:47.747267 | 
2026-03-16 16:46:47.747376 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2026-03-16 16:46:47.771054 | controller | skipping: Conditional result was False
2026-03-16 16:46:47.776776 | 
2026-03-16 16:46:47.776846 | TASK [Symlink /home/zuul-worker/workspace]
2026-03-16 16:46:48.332471 | controller | skipping: Conditional result was False
2026-03-16 16:46:48.338201 | 
2026-03-16 16:46:48.338278 | TASK [Ensure legacy workspace directory]
2026-03-16 16:46:48.609936 | controller | changed
2026-03-16 16:46:48.641978 | 
2026-03-16 16:46:48.642069 | PLAY RECAP
2026-03-16 16:46:48.642123 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2026-03-16 16:46:48.642154 | 
2026-03-16 16:46:48.716323 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2026-03-16 16:46:48.717032 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2026-03-16 16:46:49.268377 | 
2026-03-16 16:46:49.268474 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2026-03-16 16:46:49.290980 | 
2026-03-16 16:46:49.291073 | TASK [Create zuul-output directory]
2026-03-16 16:46:49.665225 | controller | changed
2026-03-16 16:46:49.674503 | 
2026-03-16 16:46:49.674592 | TASK [Slurp Zuul inventory test]
2026-03-16 16:46:50.218850 | controller -> localhost | ok
2026-03-16 16:46:50.226733 | 
2026-03-16 16:46:50.226819 | TASK [Save zuul inventory]
2026-03-16 16:46:51.533058 | controller | changed
2026-03-16 16:46:51.547762 | 
2026-03-16 16:46:51.547852 | TASK [Save zuul vars without the change_message]
2026-03-16 16:46:52.604570 | controller | changed
2026-03-16 16:46:52.658490 | 
2026-03-16 16:46:52.658573 | PLAY RECAP
2026-03-16 16:46:52.658618 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-03-16 16:46:52.658642 | 
2026-03-16 16:46:52.835516 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2026-03-16 16:46:52.836208 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2026-03-16 16:46:53.765010 | 
2026-03-16 16:46:53.765111 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2026-03-16 16:46:53.793796 | 
2026-03-16 16:46:53.793871 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2026-03-16 16:46:53.812546 | controller | ok
2026-03-16 16:46:53.829736 | 
2026-03-16 16:46:53.829828 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2026-03-16 16:46:53.853370 | controller | skipping: Conditional result was False
2026-03-16 16:46:53.859286 | 
2026-03-16 16:46:53.859387 | TASK [mirror-info-fork : Create /etc/ci]
2026-03-16 16:46:54.399061 | controller | ok
2026-03-16 16:46:54.405532 | 
2026-03-16 16:46:54.405629 | TASK [mirror-info-fork : Install ci_mirror script]
2026-03-16 16:46:55.447467 | controller | ok
2026-03-16 16:46:55.460341 | 
2026-03-16 16:46:55.460431 | TASK [Prepare workspace]
2026-03-16 16:46:55.483249 | controller | ok
2026-03-16 16:46:55.502337 | 
2026-03-16 16:46:55.502421 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-03-16 16:46:55.872901 | controller | ok
2026-03-16 16:46:55.879032 | 
2026-03-16 16:46:55.879116 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-03-16 16:46:58.999149 | controller | Output suppressed because no_log was given
2026-03-16 16:46:59.014868 | 
2026-03-16 16:46:59.014952 | LOOP [Create zuul-output directory]
2026-03-16 16:46:59.294391 | controller | changed: "/home/zuul/ci-framework-data/logs"
2026-03-16 16:46:59.514419 | controller | ok: "/home/zuul/zuul-output/logs"
2026-03-16 16:46:59.521161 | 
2026-03-16 16:46:59.521237 | TASK [Install required packages]
2026-03-16 16:47:16.262751 | controller | ok: Nothing to do
2026-03-16 16:47:16.267993 | 
2026-03-16 16:47:16.268083 | TASK [Install venv]
2026-03-16 16:47:31.130494 | controller | ERROR
2026-03-16 16:47:31.130706 | controller | {
2026-03-16 16:47:31.130741 | controller |   "msg": "gmake: *** [Makefile:56: setup_tests] Error 1",
2026-03-16 16:47:31.130765 | controller |   "rc": 2
2026-03-16 16:47:31.130786 | controller | }
failure
2026-03-16 16:47:31.132969 | 
2026-03-16 16:47:31.133031 | PLAY RECAP
2026-03-16 16:47:31.133075 | controller | ok: 6 changed: 2 unreachable: 0 failed: 1 skipped: 1 rescued: 0 ignored: 0
2026-03-16 16:47:31.133098 | 
2026-03-16 16:47:31.204723 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2026-03-16 16:47:31.205544 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2026-03-16 16:47:31.741224 | 
2026-03-16 16:47:31.741338 | PLAY [Run ci/playbooks/collect-logs.yml]
2026-03-16 16:47:31.761207 | 
2026-03-16 16:47:31.761283 | TASK [Filter out host if needed]
2026-03-16 16:47:31.769672 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2026-03-16 16:47:31.774049 | 
2026-03-16 16:47:31.774115 | TASK [Ensure file is present]
2026-03-16 16:47:32.116862 | controller | ok
2026-03-16 16:47:32.123809 | 
2026-03-16 16:47:32.123899 | TASK [Manage molecule report file]
2026-03-16 16:47:32.649310 | controller | skipping: Conditional result was False
2026-03-16 16:47:32.654891 | 
2026-03-16 16:47:32.654979 | TASK [Check if we get ci-framework-data basedir]
2026-03-16 16:47:32.932049 | controller | ok
2026-03-16 16:47:32.942410 | 
2026-03-16 16:47:32.942500 | TASK [Create ci-framework-data log directory for zuul]
2026-03-16 16:47:33.424045 | controller | changed
2026-03-16 16:47:33.429374 | 
2026-03-16 16:47:33.429458 | TASK [Copy ci-framework interesting files]
2026-03-16 16:47:33.810866 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2026-03-16 16:47:33.953639 | controller | changed
2026-03-16 16:47:33.958747 | 
2026-03-16 16:47:33.958835 | TASK [Get SELinux listing]
2026-03-16 16:47:34.484425 | controller | changed
2026-03-16 16:47:34.490289 | 
2026-03-16 16:47:34.490400 | TASK [Generate log index]
2026-03-16 16:47:35.606600 | controller | changed
2026-03-16 16:47:35.611860 | 
2026-03-16 16:47:35.611948 | TASK [Get some env related data]
2026-03-16 16:47:36.273411 | controller | /usr/bin/ansible
2026-03-16 16:47:37.138360 | controller | changed
2026-03-16 16:47:37.143973 | 
2026-03-16 16:47:37.144068 | TASK [Generate list of logs to collect in home directory]
2026-03-16 16:47:37.498171 | controller | ok: All paths examined
2026-03-16 16:47:37.563043 | 
2026-03-16 16:47:37.563145 | LOOP [Copy logs from home directory]
2026-03-16 16:47:38.165805 | controller | changed:
2026-03-16 16:47:38.172217 | controller | {
2026-03-16 16:47:38.172287 | controller |   "atime": 1772005405.932821,
2026-03-16 16:47:38.172342 | controller |   "ctime": 1772006396.9238591,
2026-03-16 16:47:38.172365 | controller |   "dev": 64513,
2026-03-16 16:47:38.172384 | controller |   "gid": 1000,
2026-03-16 16:47:38.172400 | controller |   "gr_name": "zuul",
2026-03-16 16:47:38.172418 | controller |   "inode": 2773,
2026-03-16 16:47:38.172435 | controller |   "isblk": false,
2026-03-16 16:47:38.172450 | controller |   "ischr": false,
2026-03-16 16:47:38.172465 | controller |   "isdir": false,
2026-03-16 16:47:38.172480 | controller |   "isfifo": false,
2026-03-16 16:47:38.172496 | controller |   "isgid": false,
2026-03-16 16:47:38.172510 | controller |   "islnk": false,
2026-03-16 16:47:38.172525 | controller |   "isreg": true,
2026-03-16 16:47:38.172538 | controller |   "issock": false,
2026-03-16 16:47:38.172552 | controller |   "isuid": false,
2026-03-16 16:47:38.172573 | controller |   "mode": "0644",
2026-03-16 16:47:38.172590 | controller |   "mtime": 1772006396.9238591,
2026-03-16 16:47:38.172606 | controller |   "nlink": 1,
2026-03-16 16:47:38.172621 | controller |   "path": "/home/zuul/crc-setup.log",
2026-03-16 16:47:38.172639 | controller |   "pw_name": "zuul",
2026-03-16 16:47:38.172654 | controller |   "rgrp": true,
2026-03-16 16:47:38.172669 | controller |   "roth": true,
2026-03-16 16:47:38.172685 | controller |   "rusr": true,
2026-03-16 16:47:38.172700 | controller |   "size": 4108,
2026-03-16 16:47:38.172715 | controller |   "uid": 1000,
2026-03-16 16:47:38.172730 | controller |   "wgrp": false,
2026-03-16 16:47:38.172744 | controller |   "woth": false,
2026-03-16 16:47:38.172761 | controller |   "wusr": true,
2026-03-16 16:47:38.172776 | controller |   "xgrp": false,
2026-03-16 16:47:38.172790 | controller |   "xoth": false,
2026-03-16 16:47:38.172805 | controller |   "xusr": false
2026-03-16 16:47:38.172820 | controller | }
2026-03-16 16:47:38.691829 | controller | changed:
2026-03-16 16:47:38.691920 | controller | {
2026-03-16 16:47:38.691949 | controller |   "atime": 1772006398.6759083,
2026-03-16 16:47:38.691970 | controller |   "ctime": 1772007098.5537844,
2026-03-16 16:47:38.691987 | controller |   "dev": 64513,
2026-03-16 16:47:38.692028 | controller |   "gid": 1000,
2026-03-16 16:47:38.692046 | controller |   "gr_name": "zuul",
2026-03-16 16:47:38.692061 | controller |   "inode": 359205,
2026-03-16 16:47:38.692075 | controller |   "isblk": false,
2026-03-16 16:47:38.692089 | controller |   "ischr": false,
2026-03-16 16:47:38.692103 | controller |   "isdir": false,
2026-03-16 16:47:38.692117 | controller |   "isfifo": false,
2026-03-16 16:47:38.692130 | controller |   "isgid": false,
2026-03-16 16:47:38.692144 | controller |   "islnk": false,
2026-03-16 16:47:38.692157 | controller |   "isreg": true,
2026-03-16 16:47:38.692170 | controller |   "issock": false,
2026-03-16 16:47:38.692182 | controller |   "isuid": false,
2026-03-16 16:47:38.692195 | controller |   "mode": "0644",
2026-03-16 16:47:38.692208 | controller |   "mtime": 1772007098.5537844,
2026-03-16 16:47:38.692221 | controller |   "nlink": 1,
2026-03-16 16:47:38.692235 | controller |   "path": "/home/zuul/crc-start.log",
2026-03-16 16:47:38.692248 | controller |   "pw_name": "zuul",
2026-03-16 16:47:38.692261 | controller |   "rgrp": true,
2026-03-16 16:47:38.692275 | controller |   "roth": true,
2026-03-16 16:47:38.692288 | controller |   "rusr": true,
2026-03-16 16:47:38.692344 | controller |   "size": 4654,
2026-03-16 16:47:38.692365 | controller |   "uid": 1000,
2026-03-16 16:47:38.692380 | controller |   "wgrp": false,
2026-03-16 16:47:38.692395 | controller |   "woth": false,
2026-03-16 16:47:38.692408 | controller |   "wusr": true,
2026-03-16 16:47:38.692422 | controller |   "xgrp": false,
2026-03-16 16:47:38.692435 | controller |   "xoth": false,
2026-03-16 16:47:38.692447 | controller |   "xusr": false
2026-03-16 16:47:38.692460 | controller | }
2026-03-16 16:47:38.704931 | 
2026-03-16 16:47:38.705059 | TASK [Copy crio stats log file]
2026-03-16 16:47:38.728715 | controller | skipping: Conditional result was False
2026-03-16 16:47:38.734603 | 
2026-03-16 16:47:38.734682 | TASK [Get SELinux related data]
2026-03-16 16:47:39.017987 | controller | <no matches>
2026-03-16 16:47:39.275106 | controller | ERROR
2026-03-16 16:47:39.275226 | controller | {
2026-03-16 16:47:39.275260 | controller |   "delta": "0:00:00.008311",
2026-03-16 16:47:39.275284 | controller |   "end": "2026-03-16 16:47:39.018841",
2026-03-16 16:47:39.275330 | controller |   "msg": "non-zero return code",
2026-03-16 16:47:39.275353 | controller |   "rc": 1,
2026-03-16 16:47:39.275372 | controller |   "start": "2026-03-16 16:47:39.010530"
2026-03-16 16:47:39.275391 | controller | }
2026-03-16 16:47:39.275428 | controller | ERROR: Ignoring Errors
2026-03-16 16:47:39.280913 | 
2026-03-16 16:47:39.280982 | TASK [Create system configuration directory]
2026-03-16 16:47:39.513151 | controller | changed
2026-03-16 16:47:39.520455 | 
2026-03-16 16:47:39.520544 | TASK [Get some of the system configurations]
2026-03-16 16:47:40.044985 | controller | changed
2026-03-16 16:47:40.050255 | 
2026-03-16 16:47:40.050752 | TASK [Copy generated documentation if available]
2026-03-16 16:47:40.064321 | controller | skipping: Conditional result was False
2026-03-16 16:47:40.070389 | 
2026-03-16 16:47:40.070461 | TASK [Copy generated AsciiDoc documentation if available]
2026-03-16 16:47:40.101875 | controller | skipping: Conditional result was False
2026-03-16 16:47:40.107498 | 
2026-03-16 16:47:40.107592 | TASK [Compress logs bigger than 2MB]
2026-03-16 16:47:40.634277 | controller | changed
2026-03-16 16:47:40.639820 | 
2026-03-16 16:47:40.639904 | TASK [Copy files from workspace on node]
2026-03-16 16:47:40.658707 | controller | ok
2026-03-16 16:47:40.694929 | 
2026-03-16 16:47:40.695043 | TASK [fetch-output : Set log path for multiple nodes]
2026-03-16 16:47:40.728795 | controller | skipping: Conditional result was False
2026-03-16 16:47:40.734685 | 
2026-03-16 16:47:40.734768 | TASK [fetch-output : Set log path for single node]
2026-03-16 16:47:40.783247 | controller | ok
2026-03-16 16:47:40.788234 | 
2026-03-16 16:47:40.798345 | LOOP [fetch-output : Ensure local output dirs]
2026-03-16 16:47:41.028254 | controller -> localhost | ok: "/var/lib/zuul/builds/0fb524ae3b5a427fac60332cc0ba85b7/work/logs"
2026-03-16 16:47:41.028493 | controller -> localhost | changed: All items complete
2026-03-16 16:47:41.028525 | 
2026-03-16 16:47:41.237089 | controller -> localhost | changed: "/var/lib/zuul/builds/0fb524ae3b5a427fac60332cc0ba85b7/work/artifacts"
2026-03-16 16:47:41.431153 | controller -> localhost | changed: "/var/lib/zuul/builds/0fb524ae3b5a427fac60332cc0ba85b7/work/docs"
2026-03-16 16:47:41.447261 | 
2026-03-16 16:47:41.447398 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-03-16 16:47:42.042493 | controller | changed:
2026-03-16 16:47:42.042734 | controller | .d..t...... ./
2026-03-16 16:47:42.042774 | controller | >f+++++++++ README.html
2026-03-16 16:47:42.042798 | controller | >f+++++++++ crc-setup.log
2026-03-16 16:47:42.042819 | controller | >f+++++++++ crc-start.log
2026-03-16 16:47:42.042838 | controller | >f+++++++++ dmesg.log
2026-03-16 16:47:42.042854 | controller | >f+++++++++ installed-pkgs.log
2026-03-16 16:47:42.042869 | controller | >f+++++++++ python.log
2026-03-16 16:47:42.042884 | controller | >f+++++++++ registries.conf
2026-03-16 16:47:42.042898 | controller | >f+++++++++ selinux-denials.log
2026-03-16 16:47:42.042912 | controller | >f+++++++++ selinux-listing.log
2026-03-16 16:47:42.042927 | controller | cd+++++++++ ci-framework-data/
2026-03-16 16:47:42.042940 | controller | cd+++++++++ ci-framework-data/artifacts/
2026-03-16 16:47:42.042955 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2026-03-16 16:47:42.042969 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2026-03-16 16:47:42.042984 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2026-03-16 16:47:42.043012 | controller | cd+++++++++ ci-framework-data/logs/
2026-03-16 16:47:42.043029 | controller | cd+++++++++ registries.conf.d/
2026-03-16 16:47:42.043043 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2026-03-16 16:47:42.043058 | controller | cd+++++++++ system-config/
2026-03-16 16:47:42.043072 | controller | cd+++++++++ system-config/libvirt/
2026-03-16 16:47:42.043088 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2026-03-16 16:47:42.043103 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2026-03-16 16:47:42.043118 | controller | >f+++++++++ system-config/libvirt/libvirtd.conf
2026-03-16 16:47:42.043132 | controller | >f+++++++++ system-config/libvirt/network.conf
2026-03-16 16:47:42.043145 | controller | >f+++++++++ system-config/libvirt/qemu-lockd.conf
2026-03-16 16:47:42.043159 | controller | >f+++++++++ system-config/libvirt/qemu.conf
2026-03-16 16:47:42.043173 | controller | >f+++++++++ system-config/libvirt/virtinterfaced.conf
2026-03-16 16:47:42.043193 | controller | >f+++++++++ system-config/libvirt/virtlockd.conf
2026-03-16 16:47:42.043208 | controller | >f+++++++++ system-config/libvirt/virtlogd.conf
2026-03-16 16:47:42.043222 | controller | >f+++++++++ system-config/libvirt/virtnetworkd.conf
2026-03-16 16:47:42.043235 | controller | >f+++++++++ system-config/libvirt/virtnodedevd.conf
2026-03-16 16:47:42.043250 | controller | >f+++++++++ system-config/libvirt/virtnwfilterd.conf
2026-03-16 16:47:42.043265 | controller | >f+++++++++ system-config/libvirt/virtproxyd.conf
2026-03-16 16:47:42.043279 | controller | >f+++++++++ system-config/libvirt/virtqemud.conf
2026-03-16 16:47:42.043322 | controller | >f+++++++++ system-config/libvirt/virtsecretd.conf
2026-03-16 16:47:42.043349 | controller | >f+++++++++ system-config/libvirt/virtstoraged.conf
2026-03-16 16:47:42.487910 | controller | changed: .d..t...... ./
2026-03-16 16:47:42.913841 | controller | changed: .d..t...... ./
2026-03-16 16:47:42.933257 | 
2026-03-16 16:47:42.933362 | TASK [Return artifact to Zuul]
2026-03-16 16:47:42.957090 | controller | skipping: Conditional result was False
2026-03-16 16:47:42.976544 | 
2026-03-16 16:47:42.976604 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2026-03-16 16:47:42.976697 | 
2026-03-16 16:47:42.976723 | PLAY RECAP
2026-03-16 16:47:42.976760 | controller | ok: 16 changed: 12 unreachable: 0 failed: 0 skipped: 6 rescued: 0 ignored: 1
2026-03-16 16:47:42.976780 | 
2026-03-16 16:47:43.055829 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2026-03-16 16:47:43.056565 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2026-03-16 16:47:43.576953 | 
2026-03-16 16:47:43.577105 | PLAY [all]
2026-03-16 16:47:43.595696 | 
2026-03-16 16:47:43.595770 | TASK [include_role : fetch-output]
2026-03-16 16:47:43.634456 | controller | ok
2026-03-16 16:47:43.650975 | 
2026-03-16 16:47:43.651077 | TASK [fetch-output : Set log path for multiple nodes]
2026-03-16 16:47:43.694553 | controller | skipping: Conditional result was False
2026-03-16 16:47:43.700081 | 
2026-03-16 16:47:43.700157 | TASK [fetch-output : Set log path for single node]
2026-03-16 16:47:43.728210 | controller | ok
2026-03-16 16:47:43.733114 | 
2026-03-16 16:47:43.733185 | LOOP [fetch-output : Ensure local output dirs]
2026-03-16 16:47:44.086230 | controller -> localhost | ok: "/var/lib/zuul/builds/0fb524ae3b5a427fac60332cc0ba85b7/work/logs"
2026-03-16 16:47:44.304814 | controller -> localhost | ok: "/var/lib/zuul/builds/0fb524ae3b5a427fac60332cc0ba85b7/work/artifacts"
2026-03-16 16:47:44.505121 | controller -> localhost | ok: "/var/lib/zuul/builds/0fb524ae3b5a427fac60332cc0ba85b7/work/docs"
2026-03-16 16:47:44.515039 | 
2026-03-16 16:47:44.515146 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-03-16 16:47:45.127521 | controller | ok
2026-03-16 16:47:45.127801 | controller | ok: All items complete
2026-03-16 16:47:45.127839 | 
2026-03-16 16:47:45.618216 | controller | ok
2026-03-16 16:47:46.137529 | controller | ok
2026-03-16 16:47:46.150521 | 
2026-03-16 16:47:46.150631 | TASK [include_role : fetch-output-openshift]
2026-03-16 16:47:46.164116 | controller | skipping: Conditional result was False
2026-03-16 16:47:46.169862 | 
2026-03-16 16:47:46.169936 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-03-16 16:47:46.516618 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.008986
2026-03-16 16:47:46.726622 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.009090
2026-03-16 16:47:46.758384 | 
2026-03-16 16:47:46.758458 | PLAY [all]
2026-03-16 16:47:46.773069 | 
2026-03-16 16:47:46.773142 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-03-16 16:47:47.213099 | controller | changed
2026-03-16 16:47:47.235229 | 
2026-03-16 16:47:47.235325 | PLAY RECAP
2026-03-16 16:47:47.235378 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2026-03-16 16:47:47.235411 | 
2026-03-16 16:47:47.325745 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2026-03-16 16:47:47.326500 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2026-03-16 16:47:47.948022 | 
2026-03-16 16:47:47.948135 | PLAY [localhost]
2026-03-16 16:47:47.976175 | 
2026-03-16 16:47:47.976254 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-03-16 16:47:48.343915 | localhost | changed
2026-03-16 16:47:48.349618 | 
2026-03-16 16:47:48.349723 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-03-16 16:47:48.377088 | localhost | ok
2026-03-16 16:47:48.387036 | 
2026-03-16 16:47:48.387155 | TASK [add-fileserver : Create SSH private key tempfile]
2026-03-16 16:47:48.723856 | localhost | changed
2026-03-16 16:47:48.733731 | 
2026-03-16 16:47:48.733816 | TASK [add-fileserver : Create SSH private key from secret]
2026-03-16 16:47:49.305559 | localhost | changed
2026-03-16 16:47:49.310446 | 
2026-03-16 16:47:49.310526 | TASK [add-fileserver : Add fileserver ssh key]
2026-03-16 16:47:49.732928 | localhost | Identity added: /var/lib/zuul/builds/0fb524ae3b5a427fac60332cc0ba85b7/work/tmp/ansible.41zhisri (/var/lib/zuul/builds/0fb524ae3b5a427fac60332cc0ba85b7/work/tmp/ansible.41zhisri)
2026-03-16 16:47:49.733103 | localhost | ok: Runtime: 0:00:00.006878
2026-03-16 16:47:49.737515 | 
2026-03-16 16:47:49.737587 | TASK [add-fileserver : Remove SSH private key from disk]
2026-03-16 16:47:50.104140 | localhost | ok: Runtime: 0:00:00.027553
2026-03-16 16:47:50.109401 | 
2026-03-16 16:47:50.109482 | TASK [add-fileserver : Add fileserver to inventory]
2026-03-16 16:47:50.207857 | localhost | changed
2026-03-16 16:47:50.212436 | 
2026-03-16 16:47:50.212512 | TASK [add-fileserver : Add fileserver server to known hosts]
2026-03-16 16:47:50.672482 | localhost | changed
2026-03-16 16:47:50.692985 | 
2026-03-16 16:47:50.693068 | PLAY [localhost]
2026-03-16 16:47:50.708547 | 
2026-03-16 16:47:50.708610 | TASK [Generate bulk log download script]
2026-03-16 16:47:50.746135 | localhost | ok
2026-03-16 16:47:50.757269 | 
2026-03-16 16:47:50.757362 | TASK [local-log-download : Check API endpoint is defined]
2026-03-16 16:47:50.783986 | localhost | ok: All assertions passed
2026-03-16 16:47:50.788262 | 
2026-03-16 16:47:50.788343 | TASK [local-log-download : Create download script]
2026-03-16 16:47:51.186606 | localhost -> localhost | changed
2026-03-16 16:47:51.195380 | 
2026-03-16 16:47:51.195461 | TASK [Register quick-download link]
2026-03-16 16:47:51.247808 | localhost | ok
2026-03-16 16:47:51.319177 | 
2026-03-16 16:47:51.319264 | PLAY [logserver.rdoproject.org]
2026-03-16 16:47:51.331287 | 
2026-03-16 16:47:51.331431 | TASK [Set zuul-log-path fact]
2026-03-16 16:47:51.357498 | logserver.rdoproject.org | ok
2026-03-16 16:47:51.366196 | 
2026-03-16 16:47:51.366266 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-03-16 16:47:51.412145 | logserver.rdoproject.org | ok
2026-03-16 16:47:51.417833 | 
2026-03-16 16:47:51.417899 | TASK [upload-logs : Create log directories]
2026-03-16 16:47:53.159734 | logserver.rdoproject.org | changed
2026-03-16 16:47:53.162959 | 
2026-03-16 16:47:53.163049 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-03-16 16:47:53.449752 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.005513
2026-03-16 16:47:53.454574 | 
2026-03-16 16:47:53.454659 | TASK [upload-logs : Upload logs to log server]
2026-03-16 16:47:55.025249 | logserver.rdoproject.org | Output suppressed because no_log was given
2026-03-16 16:47:55.028797 | 
2026-03-16 16:47:55.028871 | LOOP [upload-logs : Compress console log and json output]
2026-03-16 16:47:55.077109 | logserver.rdoproject.org | skipping: Conditional result was False
2026-03-16 16:47:55.077889 | logserver.rdoproject.org | skipping: Conditional result was False
2026-03-16 16:47:55.086938 | 
2026-03-16 16:47:55.087418 | LOOP [upload-logs : Upload compressed console log and json output]
2026-03-16 16:47:55.122141 | logserver.rdoproject.org | skipping: Conditional result was False
2026-03-16 16:47:55.122393 | 
2026-03-16 16:47:55.125644 | logserver.rdoproject.org | skipping: Conditional result was False
2026-03-16 16:47:55.134261 | 
2026-03-16 16:47:55.134374 | LOOP [upload-logs : Upload console log and json output]