2025-10-13 19:20:31.498893 | Job console starting...
2025-10-13 19:20:31.513406 | Updating repositories
2025-10-13 19:20:31.540821 | Preparing job workspace
2025-10-13 19:20:38.322208 | Running Ansible setup...
2025-10-13 19:20:42.872619 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-10-13 19:20:43.553089 | 
2025-10-13 19:20:43.553253 | PLAY [localhost]
2025-10-13 19:20:43.564803 | 
2025-10-13 19:20:43.564919 | TASK [Gathering Facts]
2025-10-13 19:20:44.600666 | localhost | ok
2025-10-13 19:20:44.625705 | 
2025-10-13 19:20:44.626016 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-10-13 19:20:45.033099 | localhost -> localhost | changed
2025-10-13 19:20:45.040281 | 
2025-10-13 19:20:45.040351 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-10-13 19:20:45.934934 | localhost -> localhost | changed
2025-10-13 19:20:45.947146 | 
2025-10-13 19:20:45.947295 | TASK [Setup log path fact]
2025-10-13 19:20:45.969643 | localhost | ok
2025-10-13 19:20:45.985313 | 
2025-10-13 19:20:45.985450 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-13 19:20:46.017911 | localhost | ok
2025-10-13 19:20:46.025804 | 
2025-10-13 19:20:46.026006 | TASK [emit-job-header : Print job information]
2025-10-13 19:20:46.068639 | # Job Information
2025-10-13 19:20:46.068819 | Ansible Version: 2.15.12
2025-10-13 19:20:46.068848 | Job: build-push-container-cifmw-client
2025-10-13 19:20:46.068869 | Pipeline: github-check
2025-10-13 19:20:46.068887 | Executor: ze03.softwarefactory-project.io
2025-10-13 19:20:46.068904 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3402
2025-10-13 19:20:46.068923 | Log URL (when completed): https://logserver.rdoproject.org/86c/rdoproject.org/86cf2a09720f435088aab28176e3fe8d/
2025-10-13 19:20:46.068942 | Event ID: 8207d830-a869-11f0-8ae9-c38a08a1225e
2025-10-13 19:20:46.072577 | 
2025-10-13 19:20:46.072654 | LOOP [emit-job-header : Print node information]
2025-10-13 19:20:46.173380 | localhost | ok:
2025-10-13 19:20:46.173608 | localhost | # Node Information
2025-10-13 19:20:46.173653 | localhost | Inventory Hostname: controller
2025-10-13 19:20:46.173677 | localhost | Hostname: np0005486076
2025-10-13 19:20:46.173695 | localhost | Username: zuul
2025-10-13 19:20:46.173714 | localhost | Distro: CentOS 9
2025-10-13 19:20:46.173754 | localhost | Provider: vexxhost-nodepool-tripleo
2025-10-13 19:20:46.173771 | localhost | Region: RegionOne
2025-10-13 19:20:46.173787 | localhost | Label: cloud-centos-9-stream-tripleo
2025-10-13 19:20:46.173802 | localhost | Product Name: OpenStack Nova
2025-10-13 19:20:46.173817 | localhost | Interface IP: 38.102.83.45
2025-10-13 19:20:46.205859 | 
2025-10-13 19:20:46.206050 | PLAY [all]
2025-10-13 19:20:46.215105 | 
2025-10-13 19:20:46.215193 | TASK [Gather network facts]
2025-10-13 19:20:46.759105 | controller | ok
2025-10-13 19:20:46.789353 | 
2025-10-13 19:20:46.789489 | TASK [include_role : start-zuul-console]
2025-10-13 19:20:46.820663 | controller | ok
2025-10-13 19:20:46.848771 | 
2025-10-13 19:20:46.848920 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-10-13 19:20:47.382819 | controller | ok
2025-10-13 19:20:47.395534 | 
2025-10-13 19:20:47.395676 | TASK [include_role : add-build-sshkey]
2025-10-13 19:20:47.426691 | controller | ok
2025-10-13 19:20:47.442859 | 
2025-10-13 19:20:47.442990 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-10-13 19:20:47.685276 | controller -> localhost | ok
2025-10-13 19:20:47.692750 | 
2025-10-13 19:20:47.692838 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-10-13 19:20:47.734771 | controller | ok
2025-10-13 19:20:47.747807 | controller | included: /var/lib/zuul/builds/86cf2a09720f435088aab28176e3fe8d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-10-13 19:20:47.756916 | 
2025-10-13 19:20:47.757078 | TASK [add-build-sshkey : Create Temp SSH key]
2025-10-13 19:20:48.543853 | controller -> localhost | Generating public/private rsa key pair.
2025-10-13 19:20:48.544079 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/86cf2a09720f435088aab28176e3fe8d/work/86cf2a09720f435088aab28176e3fe8d_id_rsa.
2025-10-13 19:20:48.544111 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/86cf2a09720f435088aab28176e3fe8d/work/86cf2a09720f435088aab28176e3fe8d_id_rsa.pub.
2025-10-13 19:20:48.544135 | controller -> localhost | The key fingerprint is:
2025-10-13 19:20:48.544156 | controller -> localhost | SHA256:vsE+qouY2LQmTP0LHe1T5g0BwDhjhg3Oc3VyBnzdu44 zuul-build-sshkey
2025-10-13 19:20:48.544178 | controller -> localhost | The key's randomart image is:
2025-10-13 19:20:48.544198 | controller -> localhost | +---[RSA 3072]----+
2025-10-13 19:20:48.544218 | controller -> localhost | | .+ +=o=. .      |
2025-10-13 19:20:48.544238 | controller -> localhost | |o. B.o=... .     |
2025-10-13 19:20:48.544257 | controller -> localhost | | +o.o .  .  .    |
2025-10-13 19:20:48.544276 | controller -> localhost | |  o   .   ..     |
2025-10-13 19:20:48.544295 | controller -> localhost | |  .  . .S+  .    |
2025-10-13 19:20:48.544314 | controller -> localhost | | . .. oo+ o.     |
2025-10-13 19:20:48.544348 | controller -> localhost | |o .... o+.o.     |
2025-10-13 19:20:48.544367 | controller -> localhost | |o*.o.. .oE .     |
2025-10-13 19:20:48.544385 | controller -> localhost | |+o+ o+o.o.       |
2025-10-13 19:20:48.544403 | controller -> localhost | +----[SHA256]-----+
2025-10-13 19:20:48.544446 | controller -> localhost | ok: Runtime: 0:00:00.303691
2025-10-13 19:20:48.550490 | 
2025-10-13 19:20:48.550626 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-10-13 19:20:48.569462 | controller | ok
2025-10-13 19:20:48.579499 | controller | included: /var/lib/zuul/builds/86cf2a09720f435088aab28176e3fe8d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-10-13 19:20:48.589172 | 
2025-10-13 19:20:48.589326 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-10-13 19:20:48.613692 | controller | skipping: Conditional result was False
2025-10-13 19:20:48.619232 | 
2025-10-13 19:20:48.619345 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-10-13 19:20:49.146649 | controller | changed
2025-10-13 19:20:49.159379 | 
2025-10-13 19:20:49.159601 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-10-13 19:20:49.475174 | controller | ok
2025-10-13 19:20:49.486949 | 
2025-10-13 19:20:49.487114 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-10-13 19:20:50.426961 | controller | changed
2025-10-13 19:20:50.437407 | 
2025-10-13 19:20:50.437549 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-10-13 19:20:51.319003 | controller | changed
2025-10-13 19:20:51.324114 | 
2025-10-13 19:20:51.324178 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-10-13 19:20:51.348531 | controller | skipping: Conditional result was False
2025-10-13 19:20:51.354646 | 
2025-10-13 19:20:51.354767 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-10-13 19:20:51.817076 | controller -> localhost | changed
2025-10-13 19:20:51.837351 | 
2025-10-13 19:20:51.837486 | TASK [add-build-sshkey : Add back temp key]
2025-10-13 19:20:52.111283 | controller -> localhost | Identity added: /var/lib/zuul/builds/86cf2a09720f435088aab28176e3fe8d/work/86cf2a09720f435088aab28176e3fe8d_id_rsa (zuul-build-sshkey)
2025-10-13 19:20:52.111473 | controller -> localhost | ok: Runtime: 0:00:00.019325
2025-10-13 19:20:52.117930 | 
2025-10-13 19:20:52.117996 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-10-13 19:20:52.556798 | controller | ok
2025-10-13 19:20:52.562335 | 
2025-10-13 19:20:52.562398 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-10-13 19:20:52.589571 | controller | skipping: Conditional result was False
2025-10-13 19:20:52.599637 | 
2025-10-13 19:20:52.599710 | TASK [include_role : validate-host]
2025-10-13 19:20:52.618761 | controller | ok
2025-10-13 19:20:52.640302 | 
2025-10-13 19:20:52.640411 | TASK [validate-host : Define zuul_info_dir fact]
2025-10-13 19:20:52.668608 | controller | ok
2025-10-13 19:20:52.673718 | 
2025-10-13 19:20:52.673824 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-10-13 19:20:52.945570 | controller -> localhost | ok
2025-10-13 19:20:53.013203 | 
2025-10-13 19:20:53.013328 | TASK [validate-host : Collect information about the host]
2025-10-13 19:20:53.820748 | controller | ok
2025-10-13 19:20:53.838272 | 
2025-10-13 19:20:53.838424 | TASK [validate-host : Sanitize hostname]
2025-10-13 19:20:53.951991 | controller | ok
2025-10-13 19:20:53.958297 | 
2025-10-13 19:20:53.958413 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-10-13 19:20:54.499816 | controller -> localhost | changed
2025-10-13 19:20:54.508503 | 
2025-10-13 19:20:54.508633 | TASK [validate-host : Collect information about zuul worker]
2025-10-13 19:20:54.967943 | controller | ok
2025-10-13 19:20:54.979908 | 
2025-10-13 19:20:54.980016 | TASK [validate-host : Write out all zuul information for each host]
2025-10-13 19:20:55.536368 | controller -> localhost | changed
2025-10-13 19:20:55.546206 | 
2025-10-13 19:20:55.546275 | TASK [include_role : prepare-workspace-openshift]
2025-10-13 19:20:55.569967 | controller | skipping: Conditional result was False
2025-10-13 19:20:55.575532 | 
2025-10-13 19:20:55.575600 | TASK [include_role : remove-zuul-sshkey]
2025-10-13 19:20:55.590145 | controller | skipping: Conditional result was False
2025-10-13 19:20:55.597265 | 
2025-10-13 19:20:55.597367 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-10-13 19:20:55.935250 | controller | ok: "logs"
2025-10-13 19:20:55.935489 | controller | ok: All items complete
2025-10-13 19:20:55.935516 | 
2025-10-13 19:20:56.281121 | controller | ok: "artifacts"
2025-10-13 19:20:56.481554 | controller | ok: "docs"
2025-10-13 19:20:56.495096 | 
2025-10-13 19:20:56.495229 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-10-13 19:20:56.743391 | controller | changed: "logs"
2025-10-13 19:20:56.967519 | controller | changed: "artifacts"
2025-10-13 19:20:57.199019 | controller | changed: "docs"
2025-10-13 19:20:57.241223 | 
2025-10-13 19:20:57.241362 | PLAY RECAP
2025-10-13 19:20:57.241412 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-10-13 19:20:57.241438 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-13 19:20:57.241479 | 
2025-10-13 19:20:57.417998 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-10-13 19:20:57.419035 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-10-13 19:20:58.181824 | 
2025-10-13 19:20:58.181945 | PLAY [all]
2025-10-13 19:20:58.209209 | 
2025-10-13 19:20:58.209334 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-10-13 19:20:58.289909 | controller | ok
2025-10-13 19:20:58.295194 | 
2025-10-13 19:20:58.295273 | TASK [mirror-info-fork : Create /etc/ci]
2025-10-13 19:20:58.801249 | controller | changed
2025-10-13 19:20:58.814838 | 
2025-10-13 19:20:58.815060 | TASK [mirror-info-fork : Install ci_mirror script]
2025-10-13 19:21:00.142957 | controller | changed
2025-10-13 19:21:00.157640 | 
2025-10-13 19:21:00.157778 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-10-13 19:21:00.681028 | controller | changed:
2025-10-13 19:21:00.681209 | controller | {
2025-10-13 19:21:00.681239 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-10-13 19:21:00.681270 | controller | }
2025-10-13 19:21:00.985167 | controller | changed:
2025-10-13 19:21:00.985273 | controller | {
2025-10-13 19:21:00.985302 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-10-13 19:21:00.985322 | controller | }
2025-10-13 19:21:01.240178 | controller | changed:
2025-10-13 19:21:01.240262 | controller | {
2025-10-13 19:21:01.240285 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com"
2025-10-13 19:21:01.240304 | controller | }
2025-10-13 19:21:01.562706 | controller | changed:
2025-10-13 19:21:01.562836 | controller | {
2025-10-13 19:21:01.562860 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-10-13 19:21:01.562879 | controller | }
2025-10-13 19:21:01.865008 | controller | changed:
2025-10-13 19:21:01.865127 | controller | {
2025-10-13 19:21:01.865150 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-10-13 19:21:01.865169 | controller | }
2025-10-13 19:21:02.155036 | controller | changed:
2025-10-13 19:21:02.155132 | controller | {
2025-10-13 19:21:02.155160 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-10-13 19:21:02.155184 | controller | }
2025-10-13 19:21:02.441893 | controller | changed:
2025-10-13 19:21:02.442004 | controller | {
2025-10-13 19:21:02.442028 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDLOQd4ZLtkZXQGY6UwAr/06ppWQK4fDO3HaqxPk98csyOCBXsliSKK39Bso828+5srIXiW7aI6aC9P5mwi4mUZlGPfJlQbfrcGvY+b/SocuvaGK+1RrHLoJCT52LBhwgrzlXio2jeksZeein8iaTrhsPrOAs7KggIL/rB9hEiB3NaOPWhhoCP4vlW6MEMExGcqB/1FVxXFBPnLkEyW0Lk7ycVflZl2ocRxbfjZi0+tI1Wlinp8PvSQSc/WVrAcDgKjc/mB4ODPOyYy3G8FHgfMsrXSDEyjBKgLKMsdCrAUcqJQWjkqXleXSYOV4q3pzL+9umK+q/e3P/bIoSFQzmJKTU1eDfuvPXmow9F5H54fii/Da7ezlMJ+wPGHJrRAkmzvMbALy7xwswLhZMkOGNtRcPqaKYRmIBKpw3o6bCTtcNUHOtOQnzwY8JzrM2eBWJBXAANYw+9/ho80JIiwhg29CFNpVBuHbql2YxJQNrnl90guN65rYNpDxdIluweyUf8= anbanerj@kaermorhen"
2025-10-13 19:21:02.442046 | controller | }
2025-10-13 19:21:02.761842 | controller | changed:
2025-10-13 19:21:02.761938 | controller | {
2025-10-13 19:21:02.761964 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQC3VwV8Im9kRm49lt3tM36hj4Zv27FxGo4C1Q/0jqhzFmHY7RHbmeRr8ObhwWoHjXSozKWg8FL5ER0z3hTwL0W6lez3sL7hUaCmSuZmG5Hnl3x4vTSxDI9JZ/Y65rtYiiWQo2fC5xJhU/4+0e5e/pseCm8cKRSu+SaxhO+sd6FDojA2x1BzOzKiQRDy/1zWGp/cZkxcEuB1wHI5LMzN03c67vmbu+fhZRAUO4dQkvcnj2LrhQtpa+ytvnSjr8icMDosf1OsbSffwZFyHB/hfWGAfe0eIeSA2XPraxiPknXxiPKx2MJsaUTYbsZcm3EjFdHBBMumw5rBI74zLrMRvCO9GwBEmGT4rFng1nP+yw5DB8sn2zqpOsPg1LYRwCPOUveC13P6pgsZZPh812e8v5EKnETct+5XI3dVpdw6CnNiLwAyVAF15DJvBGT/u1k0Myg/bQn+Gv9k2MSj6LvQmf6WbZu2Wgjm30z3FyCneBqTL7mLF19YXzeC0ufHz5pnO1E= dasm@fedora"
2025-10-13 19:21:02.761984 | controller | }
2025-10-13 19:21:03.049613 | controller | changed:
2025-10-13 19:21:03.049699 | controller | {
2025-10-13 19:21:03.049750 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-10-13 19:21:03.049780 | controller | }
2025-10-13 19:21:03.429196 | controller | changed:
2025-10-13 19:21:03.429282 | controller | {
2025-10-13 19:21:03.429306 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-10-13 19:21:03.429324 | controller | }
2025-10-13 19:21:03.657195 | controller | changed:
2025-10-13 19:21:03.657325 | controller | {
2025-10-13 19:21:03.657350 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-10-13 19:21:03.657369 | controller | }
2025-10-13 19:21:03.964159 | controller | changed:
2025-10-13 19:21:03.964254 | controller | {
2025-10-13 19:21:03.964278 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-10-13 19:21:03.964297 | controller | }
2025-10-13 19:21:04.213154 | controller | changed:
2025-10-13 19:21:04.213249 | controller | {
2025-10-13 19:21:04.213273 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-10-13 19:21:04.213292 | controller | }
2025-10-13 19:21:04.469645 | controller | changed:
2025-10-13 19:21:04.469817 | controller | {
2025-10-13 19:21:04.469847 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-10-13 19:21:04.469868 | controller | }
2025-10-13 19:21:04.778073 | controller | changed:
2025-10-13 19:21:04.778320 | controller | {
2025-10-13 19:21:04.778351 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-10-13 19:21:04.778372 | controller | }
2025-10-13 19:21:05.035125 | controller | changed:
2025-10-13 19:21:05.035216 | controller | {
2025-10-13 19:21:05.035239 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-10-13 19:21:05.035257 | controller | }
2025-10-13 19:21:05.322309 | controller | changed:
2025-10-13 19:21:05.322398 | controller | {
2025-10-13 19:21:05.322422 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQCfNtF7NvKl915TGsGGoseUb06Hj8L/S4toWf0hExeY+F00woL6NvBlJD0nDct+P5a22I4EhvoQCRQ8reaPCm1lybR3uiRIJsj+8zkVvLwby9LXzfZorlNG9ofjd00FEmB09uW/YvTl6Q9XwwwX6tInzIOv3TMqTHHGOL74ibbj8J/FJR0cFEyj0z4WQRvtkh32xAHl83gbuINryMt0sqRI+clj2381NKL55DRLQrVw0gsfqqxiHAnXg21qWmc4J+b9e9kiuAFQjcjwTVkwJCcg3xbPwC/qokYRby/Y5S40UUd7/jEARGXT7RZgpzTuDd1oZiCVrnrqJNPaMNdVv5MLeFdf1B7iIe5aa/fGouX7AO4SdKhZUdnJmCFAGvjC6S3JMZ2wAcUl+OHnssfmdj7XL50cLo27vjuzMtLAgSqi6N99m92WCF2s8J9aVzszX7Xz9OKZCeGsiVJp3/NdABKzSEAyM9xBD/5Vho894Sav+otpySHe3p6RUTgbB5Zu8VyZRZ/UtB3ueXxyo764yrc6qWIDqrehm84Xm9g+/jpIBzGPl07NUNJpdt/6Sgf9RIKXw/7XypO5yZfUcuFNGTxLfqjTNrtgLZNcjfav6sSdVXVcMPL//XNuRdKmVFaO76eV/oGMQGr1fGcCD+N+CpI7+Q+fCNB6VFWG4nZFuI/Iuw== averdagu@redhat.com"
2025-10-13 19:21:05.322442 | controller | }
2025-10-13 19:21:05.634773 | controller | changed:
2025-10-13 19:21:05.634871 | controller | {
2025-10-13 19:21:05.634897 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDq8l27xI+QlQVdS4djp9ogSoyrNE2+Ox6vKPdhSNL1J3PE5w+WCSvMz9A5gnNuH810zwbekEApbxTze/gLQJwBHA52CChfURpXrFaxY7ePXRElwKAL3mJfzBWY/c5jnNL9TCVmFJTGZkFZP3Nh+BMgZvL6xBkt3WKm6Uq18qzd9XeKcZusrA+O+uLv1fVeQnadY9RIqOCyeFYCzLWrUfTyE8x/XG0hAWIM7qpnF2cALQS2h9n4hW5ybiUN790H08wf9hFwEf5nxY9Z9dVkPFQiTSGKNBzmnCXU9skxS/xhpFjJ5duGSZdtAHe9O+nGZm9c67hxgtf8e5PDuqAdXEv2cf6e3VBAt+Bz8EKI3yosTj0oZHfwr42Yzb1l/SKy14Rggsrc9KAQlrGXan6+u2jcQqqx7l+SWmnpFiWTV9u5cWj2IgOhApOitmRBPYqk9rE2usfO0hLn/Pj/R/Nau4803e1/EikdLE7Ps95s9mX5jRDjAoUa2JwFF5RsVFyL910= ashigupt@ashigupt.remote.csb"
2025-10-13 19:21:05.634925 | controller | }
2025-10-13 19:21:05.932479 | controller | changed:
2025-10-13 19:21:05.932618 | controller | {
2025-10-13 19:21:05.932652 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-10-13 19:21:05.932678 | controller | }
2025-10-13 19:21:06.228364 | controller | changed:
2025-10-13 19:21:06.228479 | controller | {
2025-10-13 19:21:06.228504 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-10-13 19:21:06.228524 | controller | }
2025-10-13 19:21:06.551812 | controller | changed:
2025-10-13 19:21:06.551982 | controller | {
2025-10-13 19:21:06.552007 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-10-13 19:21:06.552025 | controller | }
2025-10-13 19:21:06.842979 | controller | changed:
2025-10-13 19:21:06.843081 | controller | {
2025-10-13 19:21:06.843104 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-10-13 19:21:06.843122 | controller | }
2025-10-13 19:21:07.174007 | controller | changed:
2025-10-13 19:21:07.174101 | controller | {
2025-10-13 19:21:07.174126 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQDZdI7t1cxYx65heVI24HTV4F7oQLW1zyfxHreL2TIJKxjyrUUKIFEUmTutcBlJRLNT2Eoix6x1sOw9YrchloCLcn//SGfTElr9mSc5jbjb7QXEU+zJMhtxyEJ1Po3CUGnj7ckiIXw7wcawZtrEOAQ9pH3ExYCJcEMiyNjRQZCxT3tPK+S4B95EWh5Fsrz9CkwpjNRPPH7LigCeQTM3Wc7r97utAslBUUvYceDSLA7rMgkitJE38b7rZBeYzsGQ8YYUBjTCtehqQXxCRjizbHWaaZkBU+N3zkKB6n/iCNGIO690NK7A/qb6msTijiz1PeuM8ThOsi9qXnbX5v0PoTpcFSojV7NHAQ71f0XXuS43FhZctT+Dcx44dT8Fb5vJu2cJGrk+qF8ZgJYNpRS7gPg0EG2EqjK7JMf9ULdjSu0r+KlqIAyLvtzT4eOnQipoKlb/WG5D/0ohKv7OMQ352ggfkBFIQsRXyyTCT98Ft9juqPuahi3CAQmP4H9dyE+7+Kz437PEtsxLmfm6naNmWi7Ee1DqWPwS8rEajsm4sNM4wW9gdBboJQtc0uZw0DfLj1I9r3Mc8Ol0jYtz0yNQDSzVLrGCaJlC311trU70tZ+ZkAVV6Mn8lOhSbj1cK0lvSr6ZK4dgqGl3I1eTZJJhbLNdg7UOVaiRx9543+C/p/As7w== brjackma@redhat.com"
2025-10-13 19:21:07.174151 | controller | }
2025-10-13 19:21:07.483125 | controller | changed:
2025-10-13 19:21:07.483205 | controller | {
2025-10-13 19:21:07.483230 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-10-13 19:21:07.483249 | controller | }
2025-10-13 19:21:07.763834 | controller | changed:
2025-10-13 19:21:07.763939 | controller | {
2025-10-13 19:21:07.763962 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-10-13 19:21:07.763980 | controller | }
2025-10-13 19:21:08.013020 | controller | changed:
2025-10-13 19:21:08.013116 | controller | {
2025-10-13 19:21:08.013138 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-10-13 19:21:08.013157 | controller | }
2025-10-13 19:21:08.061268 | 
2025-10-13 19:21:08.061400 | TASK [Set timezone to UTC]
2025-10-13 19:21:08.928604 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-10-13 19:21:08.933503 | 
2025-10-13 19:21:08.933566 | TASK [Create nodepool directory]
2025-10-13 19:21:09.290907 | controller | changed
2025-10-13 19:21:09.300745 | 
2025-10-13 19:21:09.300825 | TASK [Create nodepool sub_nodes file]
2025-10-13 19:21:10.172494 | controller | changed
2025-10-13 19:21:10.180162 | 
2025-10-13 19:21:10.180241 | TASK [Create nodepool sub_nodes_private file]
2025-10-13 19:21:11.077759 | controller | changed
2025-10-13 19:21:11.090504 | 
2025-10-13 19:21:11.090671 | LOOP [Populate nodepool sub_nodes file]
2025-10-13 19:21:11.162512 | 
2025-10-13 19:21:11.162670 | LOOP [Populate nodepool sub_nodes_private file]
2025-10-13 19:21:11.228464 | 
2025-10-13 19:21:11.228680 | TASK [Create nodepool primary file]
2025-10-13 19:21:11.289882 | controller | skipping: Conditional result was False
2025-10-13 19:21:11.296071 | 
2025-10-13 19:21:11.296176 | TASK [Create nodepool node_private for this node]
2025-10-13 19:21:12.256972 | controller | changed
2025-10-13 19:21:12.272976 | 
2025-10-13 19:21:12.273101 | LOOP [Copy ssh keys to nodepool directory]
2025-10-13 19:21:12.723591 | controller | ok: Item: id_rsa Runtime: 0:00:00.011826
2025-10-13 19:21:12.723816 | 
2025-10-13 19:21:12.970265 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.006876
2025-10-13 19:21:12.986245 | 
2025-10-13 19:21:12.986360 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-10-13 19:21:13.962570 | controller | changed
2025-10-13 19:21:13.967796 | 
2025-10-13 19:21:13.967866 | TASK [Validate sudoers config after edits]
2025-10-13 19:21:14.243821 | controller | /etc/sudoers: parsed OK
2025-10-13 19:21:14.244063 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-10-13 19:21:14.244088 | controller | /etc/sudoers.d/zuul: parsed OK
2025-10-13 19:21:14.244102 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-10-13 19:21:14.503236 | controller | ok: Runtime: 0:00:00.010630
2025-10-13 19:21:14.509126 | 
2025-10-13 19:21:14.509213 | TASK [Show the environment passed in to job shell scripts]
2025-10-13 19:21:14.788991 | controller | SHELL=/bin/bash
2025-10-13 19:21:14.789074 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-10-13 19:21:14.789084 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-10-13 19:21:14.789092 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/04/3404/9f142a3066b32ef704ae4f0d5fc5f7189e41a1f8^openstack-k8s-operators/ci-framework:main:refs/changes/06/3406/3b7e5d87cb08f8ceea89c6e58502f20dfffec667^openstack-k8s-operators/ci-framework:main:refs/changes/02/3402/a301e7c15ccc9d964f35eb738c51dc015730d4ed
2025-10-13 19:21:14.789099 | controller | PWD=/home/zuul
2025-10-13 19:21:14.789105 | controller | ZUUL_PIPELINE=github-check
2025-10-13 19:21:14.789112 | controller | LOGNAME=zuul
2025-10-13 19:21:14.789118 | controller | XDG_SESSION_TYPE=tty
2025-10-13 19:21:14.789124 | controller | _=/usr/bin/env
2025-10-13 19:21:14.789144 | controller | MOTD_SHOWN=pam
2025-10-13 19:21:14.789209 | controller | HOME=/home/zuul
2025-10-13 19:21:14.789218 | controller | LANG=en_US.UTF-8
2025-10-13 19:21:14.789224 | controller | SSH_CONNECTION=38.102.83.114 43158 38.102.83.45 22
2025-10-13 19:21:14.789230 | controller | TOX_TESTENV_PASSENV=ZUUL_UUID ZUUL_PROJECT ZUUL_SHORT_PROJECT_NAME ZUUL_PIPELINE ZUUL_VOTING WORKSPACE BUILD_TIMEOUT ZUUL_BRANCH ZUUL_CHANGES ZUUL_CHANGE_IDS ZUUL_CHANGE ZUUL_PATCHSET
2025-10-13 19:21:14.789241 | controller | ZUUL_CHANGE_IDS=3404,9f142a3066b32ef704ae4f0d5fc5f7189e41a1f8 3406,3b7e5d87cb08f8ceea89c6e58502f20dfffec667 3402,a301e7c15ccc9d964f35eb738c51dc015730d4ed
2025-10-13 19:21:14.789248 | controller | WORKSPACE=/home/zuul/workspace
2025-10-13 19:21:14.789255 | controller | XDG_SESSION_CLASS=user
2025-10-13 19:21:14.789261 | controller | SELINUX_ROLE_REQUESTED=
2025-10-13 19:21:14.789267 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-10-13 19:21:14.789274 | controller | USER=zuul
2025-10-13 19:21:14.789280 | controller | ZUUL_VOTING=True
2025-10-13 19:21:14.789286 | controller | BUILD_TIMEOUT=5400000
2025-10-13 19:21:14.789291 | controller | SELINUX_USE_CURRENT_RANGE=
2025-10-13 19:21:14.789297 | controller | SHLVL=1
2025-10-13 19:21:14.789303 | controller | ZUUL_PATCHSET=a301e7c15ccc9d964f35eb738c51dc015730d4ed
2025-10-13 19:21:14.789310 | controller | XDG_SESSION_ID=1
2025-10-13 19:21:14.789316 | controller | ZUUL_BRANCH=main
2025-10-13 19:21:14.789322 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-10-13 19:21:14.789328 | controller | SSH_CLIENT=38.102.83.114 43158 22
2025-10-13 19:21:14.789334 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-10-13 19:21:14.789340 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-10-13 19:21:14.789346 | controller | which_declare=declare -f
2025-10-13 19:21:14.789353 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2025-10-13 19:21:14.789360 | controller | SELINUX_LEVEL_REQUESTED=
2025-10-13 19:21:14.789367 | controller | ZUUL_CHANGE=3402
2025-10-13 19:21:14.789373 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-10-13 19:21:14.789379 | controller | ZUUL_UUID=86cf2a09720f435088aab28176e3fe8d
2025-10-13 19:21:14.789386 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-10-13 19:21:14.789393 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-10-13 19:21:14.789400 | controller | }
2025-10-13 19:21:15.059513 | controller | ok: Runtime: 0:00:00.009017
2025-10-13 19:21:15.065449 | 
2025-10-13 19:21:15.065522 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-10-13 19:21:15.110305 | controller | skipping: Conditional result was False
2025-10-13 19:21:15.115817 | 
2025-10-13 19:21:15.115885 | TASK [Symlink /home/zuul-worker/workspace]
2025-10-13 19:21:15.744631 | controller | skipping: Conditional result was False
2025-10-13 19:21:15.751241 | 
2025-10-13 19:21:15.751448 | TASK [Ensure legacy workspace directory]
2025-10-13 19:21:16.048745 | controller | changed
2025-10-13 19:21:16.100130 | 
2025-10-13 19:21:16.100225 | PLAY RECAP
2025-10-13 19:21:16.100270 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-10-13 19:21:16.100292 | 
2025-10-13 19:21:16.222912 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-10-13 19:21:16.223958 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/crc/podman.yaml@master]
2025-10-13 19:21:16.841130 | 
2025-10-13 19:21:16.841257 | PLAY [all,!compute]
2025-10-13 19:21:16.879503 | 
2025-10-13 19:21:16.879656 | TASK [Enable container-tools rhel-modules for c8/crc node]
2025-10-13 19:21:17.417987 | controller | skipping: Conditional result was False
2025-10-13 19:21:17.423917 | 
2025-10-13 19:21:17.424021 | TASK [Install podman]
2025-10-13 19:22:21.666677 | controller | changed
2025-10-13 19:22:21.679815 | 
2025-10-13 19:22:21.679995 | TASK [Set var name for quay login zuul secret]
2025-10-13 19:22:21.757922 | controller | skipping: Conditional result was False
2025-10-13 19:22:21.769713 | 
2025-10-13 19:22:21.769970 | TASK [Print the username]
2025-10-13 19:22:22.203907 | controller | openstack-k8s-operators+cirobot
2025-10-13 19:22:22.323989 | controller | ok: Runtime: 0:00:00.004979
2025-10-13 19:22:22.335541 | 
2025-10-13 19:22:22.335707 | TASK [Fail when user or password is not set]
2025-10-13 19:22:22.414464 | controller | skipping: Conditional result was False
2025-10-13 19:22:22.428520 | 
2025-10-13 19:22:22.428816 | TASK [Set vars for quay login]
2025-10-13 19:22:22.493111 | controller | Output suppressed because no_log was given
2025-10-13 19:22:22.498773 | 
2025-10-13 19:22:22.498918 | LOOP [Perform container registry login(s) with podman]
2025-10-13 19:22:23.276579 | controller | Output suppressed because no_log was given
2025-10-13 19:22:23.284190 | 
2025-10-13 19:22:23.284280 | LOOP [Perform container registry login(s) with buildah]
2025-10-13 19:22:23.859026 | controller | Output suppressed because no_log was given
2025-10-13 19:22:23.866180 | 
2025-10-13 19:22:23.866284 | TASK [Set Insecure registry for content provider]
2025-10-13 19:22:23.922526 | controller | skipping: Conditional result was False
2025-10-13 19:22:23.950230 | 
2025-10-13 19:22:23.950345 | PLAY RECAP
2025-10-13 19:22:23.950400 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0
2025-10-13 19:22:23.950430 | 
2025-10-13 19:22:24.052902 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/crc/podman.yaml@master]
2025-10-13 19:22:24.055673 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-10-13 19:22:24.724353 | 
2025-10-13 19:22:24.724491 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2025-10-13 19:22:24.747245 | 
2025-10-13 19:22:24.747368 | TASK [Read group_vars]
2025-10-13 19:22:24.776675 | controller | ok
2025-10-13 19:22:24.795480 | 
2025-10-13 19:22:24.795631 | TASK [cifmw_helpers : Check directory is available]
2025-10-13 19:22:25.157532 | controller | ok
2025-10-13 19:22:25.168947 | 
2025-10-13 19:22:25.169081 | TASK [cifmw_helpers : Find yaml files]
2025-10-13 19:22:25.196542 | controller | skipping: Conditional result was False
2025-10-13 19:22:25.209367 | 
2025-10-13 19:22:25.209512 | TASK [cifmw_helpers : Print available yaml files]
2025-10-13 19:22:25.237187 | controller | skipping: Conditional result was False
2025-10-13 19:22:25.249952 | 
2025-10-13 19:22:25.250101 | LOOP [cifmw_helpers : Create files on localhost and use include_vars]
2025-10-13 19:22:25.302328 | 
2025-10-13 19:22:25.302586 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2025-10-13 19:22:25.336976 | controller | ok
2025-10-13 19:22:25.366747 | 
2025-10-13 19:22:25.366902 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-10-13 19:22:25.413685 | controller | skipping: Conditional result was False
2025-10-13 19:22:25.426463 | 
2025-10-13 19:22:25.426691 | TASK [mirror-info-fork : Create /etc/ci]
2025-10-13 19:22:25.872981 | controller | ok
2025-10-13 19:22:25.887659 | 
2025-10-13 19:22:25.887900 | TASK [mirror-info-fork : Install ci_mirror script]
2025-10-13 19:22:26.699394 | controller | ok
2025-10-13 19:22:26.723159 | 
2025-10-13 19:22:26.723329 | TASK [Prepare workspace]
2025-10-13 19:22:26.752214 | controller | ok
2025-10-13 19:22:26.821198 | 
2025-10-13 19:22:26.821338 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-10-13 19:22:27.212059 | controller | ok
2025-10-13 19:22:27.225177 | 
2025-10-13 19:22:27.225341 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-10-13 19:22:30.503306 | controller | Output suppressed because no_log was given
2025-10-13 19:22:30.516852 | 
2025-10-13 19:22:30.516994 | LOOP [Create zuul-output directory]
2025-10-13 19:22:30.763854 | controller | changed: "/home/zuul/ci-framework-data/logs"
2025-10-13 19:22:30.764232 | 
2025-10-13 19:22:30.970915 | controller | ok: "/home/zuul/zuul-output/logs"
2025-10-13 19:22:30.983313 | 
2025-10-13 19:22:30.983469 | TASK [Install required packages]
2025-10-13 19:22:32.018083 | controller | ok: Nothing to do
2025-10-13 19:22:32.028279 | 
2025-10-13 19:22:32.028422 | TASK [Install venv]
2025-10-13 19:23:39.446314 | controller | changed
2025-10-13 19:23:39.500090 | 
2025-10-13 19:23:39.500216 | PLAY RECAP
2025-10-13 19:23:39.500270 | controller | ok: 8 changed: 3 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0
2025-10-13 19:23:39.500299 | 
2025-10-13 19:23:39.613443 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-10-13 19:23:39.615589 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-10-13 19:23:40.241136 | 
2025-10-13 19:23:40.241252 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-10-13 19:23:40.262331 | 
2025-10-13 19:23:40.262456 | TASK [Create zuul-output directory]
2025-10-13 19:23:40.717697 | controller | changed
2025-10-13 19:23:40.729966 | 
2025-10-13 19:23:40.730126 | TASK [Slurp Zuul inventory test]
2025-10-13 19:23:41.092564 | controller -> localhost | ok
2025-10-13 19:23:41.098992 | 
2025-10-13 19:23:41.099054 | TASK [Save zuul inventory]
2025-10-13 19:23:42.188702 | controller | changed
2025-10-13 19:23:42.210827 | 
2025-10-13 19:23:42.211022 | TASK [Save zuul vars without the change_message]
2025-10-13 19:23:43.145213 | controller | changed
2025-10-13 19:23:43.186688 | 
2025-10-13 19:23:43.186851 | PLAY RECAP
2025-10-13 19:23:43.186910 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-13 19:23:43.186938 | 
2025-10-13 19:23:43.298372 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-10-13 19:23:43.299494 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/build_push_container_runner.yml@main]
2025-10-13 19:23:43.954910 | 
2025-10-13 19:23:43.955046 | PLAY [Run build_push_container role]
2025-10-13 19:23:43.977238 | 
2025-10-13 19:23:43.977369 | TASK [Read group_vars]
2025-10-13 19:23:43.997240 | controller | ok
2025-10-13 19:23:44.013910 | 
2025-10-13 19:23:44.014025 | TASK [cifmw_helpers : Check directory is available]
2025-10-13 19:23:44.437905 | controller | ok
2025-10-13 19:23:44.451389 | 
2025-10-13 19:23:44.451563 | TASK [cifmw_helpers : Find yaml files]
2025-10-13 19:23:44.910195 | controller | ok: All paths examined
2025-10-13 19:23:44.924314 | 
2025-10-13 19:23:44.924537 | TASK [cifmw_helpers : Print available yaml files]
2025-10-13 19:23:44.973631 | controller | ok: Found yaml files to parse: ['/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/group_vars/all.yml']
2025-10-13 19:23:44.984980 | 
2025-10-13 19:23:44.985186 | LOOP [cifmw_helpers : Create files on localhost and use include_vars]
2025-10-13 19:23:45.015117 | controller | Output suppressed because no_log was given
2025-10-13 19:23:45.047618 | controller | included: /var/lib/zuul/builds/86cf2a09720f435088aab28176e3fe8d/untrusted/project_0/github.com/openstack-k8s-operators/ci-framework/roles/cifmw_helpers/tasks/include_file.yml
2025-10-13 19:23:45.061390 | 
2025-10-13 19:23:45.061580 | TASK [cifmw_helpers : Fail if file is not yaml or yml extension]
2025-10-13 19:23:45.088784 | controller | skipping: Conditional result was False
2025-10-13 19:23:45.102271 | 
2025-10-13 19:23:45.102490 | TASK [cifmw_helpers : Check if file is available]
2025-10-13 19:23:45.393405 | controller | ok
2025-10-13 19:23:45.401638 | 
2025-10-13 19:23:45.401831 | TASK [cifmw_helpers : Create temporary directory]
2025-10-13 19:23:45.888545 | controller -> localhost | changed
2025-10-13 19:23:45.904550 | 
2025-10-13 19:23:45.904860 | TASK [cifmw_helpers : Read vars]
2025-10-13 19:23:46.334605 | controller | ok
2025-10-13 19:23:46.344235 | 
2025-10-13 19:23:46.344372 | TASK [cifmw_helpers : Create new variable files with content]
2025-10-13 19:23:47.053155 | controller -> localhost | changed
2025-10-13 19:23:47.066833 | 
2025-10-13 19:23:47.067030 | TASK [cifmw_helpers : Include vars]
2025-10-13 19:23:47.125562 | controller -> localhost | ok
2025-10-13 19:23:47.150263 | 
2025-10-13 19:23:47.150441 | TASK [Run build_push_container role]
2025-10-13 19:23:48.102797 | controller |
2025-10-13 19:23:49.163867 | controller | PLAY [Run build_push_container role] *******************************************
2025-10-13 19:23:49.163961 | controller |
2025-10-13 19:23:49.163983 | controller | TASK [Gathering Facts ] ********************************************************
2025-10-13 19:23:49.164001 | controller | Monday 13 October 2025  19:23:48 +0000 (0:00:00.047)       0:00:00.047 ********
2025-10-13 19:23:49.164035 | controller | ok: [localhost]
2025-10-13 19:23:49.225705 | controller |
2025-10-13 19:23:49.225776 | controller | TASK [Run build_push_container role name=build_push_container] *****************
2025-10-13 19:23:49.225793 | controller | Monday 13 October 2025  19:23:49 +0000 (0:00:01.061)       0:00:01.108 ********
2025-10-13 19:23:49.225817 | controller |
2025-10-13 19:23:49.778275 | controller | TASK [build_push_container : Ensure directories are present path={{ item }}, state=directory, mode=0755, recurse=True] ***
2025-10-13 19:23:49.778371 | controller | Monday 13 October 2025  19:23:49 +0000 (0:00:00.062)       0:00:01.170 ********
2025-10-13 19:23:49.778401 | controller | changed: [localhost] => (item=/home/zuul/ci-framework-data/artifacts/build_push_container/scripts)
2025-10-13 19:23:50.722596 | controller | changed: [localhost] => (item=/home/zuul/ci-framework-data/artifacts/build_push_container/logs)
2025-10-13 19:23:50.722718 | controller |
2025-10-13 19:23:50.722725 | controller | TASK [podman : Ensure podman is installed name={{ cifmw_podman_packages }}, state=present] ***
2025-10-13 19:23:50.722732 | controller | Monday 13 October 2025  19:23:49 +0000 (0:00:00.552)       0:00:01.722 ********
2025-10-13 19:23:50.722744 | controller | ok: [localhost]
2025-10-13 19:23:51.131552 | controller |
2025-10-13 19:23:51.131626 | controller | TASK [podman : Enable loginctl linger for ansible_user_id _raw_params=loginctl enable-linger {{ cifmw_podman_user_linger }}] ***
2025-10-13 19:23:51.131643 | controller | Monday 13 October 2025  19:23:50 +0000 (0:00:00.943)       0:00:02.666 ********
2025-10-13 19:23:51.131669 | controller | changed: [localhost]
2025-10-13 19:23:51.164701 | controller |
2025-10-13 19:23:51.164777 | controller | TASK [podman : Ensure subordinate UID entry exists for zuul path=/etc/subuid, line={{ target_user }}:{{ sub_id_start }}:{{ sub_id_count }}, state=present, create=True, mode=0644] ***
2025-10-13 19:23:51.164793 | controller | Monday 13 October 2025  19:23:51 +0000 (0:00:00.409)       0:00:03.076 ********
2025-10-13 19:23:51.164818 | controller | skipping: [localhost]
2025-10-13 19:23:51.192338 | controller |
2025-10-13 19:23:51.192363 | controller | TASK [podman : Ensure subordinate GID entry exists for zuul path=/etc/subgid, line={{ target_user }}:{{ sub_id_start }}:{{ sub_id_count }}, state=present, create=True, mode=0644] ***
2025-10-13 19:23:51.192370 | controller | Monday 13 October 2025  19:23:51 +0000 (0:00:00.033)       0:00:03.109 ********
2025-10-13 19:23:51.192380 | controller | skipping: [localhost]
2025-10-13 19:23:51.229334 | controller |
2025-10-13 19:23:51.229424 | controller | TASK [podman : Run podman system migrate if subuid/subgid files were changed _raw_params=podman system migrate] ***
2025-10-13 19:23:51.229453 | controller | Monday 13 October 2025  19:23:51 +0000 (0:00:00.027)       0:00:03.137 ********
2025-10-13 19:23:51.229482 | controller | skipping: [localhost]
2025-10-13 19:23:57.688839 | controller |
2025-10-13 19:23:57.688907 | controller | TASK [build_push_container : Install qemu-user-static for multi arch builds output_dir={{ (cifmw_build_push_container_artifacts, 'scripts') | ansible.builtin.path_join }}, script={{ _podman_qemu_user_static_cmd }}] ***
2025-10-13 19:23:57.688926 | controller | Monday 13 October 2025  19:23:51 +0000 (0:00:00.036)       0:00:03.174 ********
2025-10-13 19:23:57.688947 | controller | Follow script's output here: /home/zuul/ci-framework-data/artifacts/build_push_container/logs/ci_script_000_install_qemu_user_static_for.log
2025-10-13 19:23:57.997743 | controller | changed: [localhost]
2025-10-13 19:23:57.997822 | controller |
2025-10-13 19:23:57.997839 | controller | TASK [build_push_container : Ensure manifest doesn't exist _raw_params=podman manifest rm {{ _container_manifest_name }}] ***
2025-10-13 19:23:57.997853 | controller | Monday 13 October 2025  19:23:57 +0000 (0:00:06.458)       0:00:09.633 ********
2025-10-13 19:23:57.997879 | controller | ok: [localhost]
2025-10-13 19:23:58.330316 | controller |
2025-10-13 19:23:58.330406 | controller | TASK [build_push_container : Create manifest _raw_params=podman manifest create {{ _container_manifest_name }}] ***
2025-10-13 19:23:58.330452 | controller | Monday 13 October 2025  19:23:57 +0000 (0:00:00.309)       0:00:09.942 ********
2025-10-13 19:23:58.330486 | controller | changed: [localhost]
2025-10-13 19:38:45.922105 | controller |
2025-10-13 19:38:45.922212 | controller | TASK [build_push_container : Build container output_dir={{ (cifmw_build_push_container_artifacts, 'scripts') | ansible.builtin.path_join }}, script={{ _podman_build_cmd }}] ***
2025-10-13 19:38:45.922235 | controller | Monday 13 October 2025  19:23:58 +0000 (0:00:00.332)       0:00:10.274 ********
2025-10-13 19:38:45.922262 | controller | Follow script's output here: /home/zuul/ci-framework-data/artifacts/build_push_container/logs/ci_script_001_build.log
2025-10-13 19:39:16.931423 | controller | changed: [localhost]
2025-10-13 19:39:16.931470 | controller |
2025-10-13 19:39:16.931479 | controller | TASK [build_push_container : Push manifest output_dir={{ (cifmw_build_push_container_artifacts, 'scripts') | ansible.builtin.path_join }}, script={{ (_podman_push_manifest_cmd, item) | join(':') }}] ***
2025-10-13 19:39:16.931491 | controller | Monday 13 October 2025  19:38:45 +0000 (0:14:47.592)       0:14:57.867 ********
2025-10-13 19:39:16.931504 | controller | Follow script's output here: /home/zuul/ci-framework-data/artifacts/build_push_container/logs/ci_script_002_push.log
2025-10-13 19:39:17.043438 | controller | changed: [localhost] => (item=a301e7c15ccc9d964f35eb738c51dc015730d4ed)
2025-10-13 19:39:17.043489 | controller | Follow script's output here: /home/zuul/ci-framework-data/artifacts/build_push_container/logs/ci_script_003_push.log
2025-10-13 19:39:17.043500 | controller | changed: [localhost] => (item=3402-latest)
2025-10-13 19:39:17.043510 | controller |
2025-10-13 19:39:17.043520 | controller | TASK [build_push_container : Push container output_dir={{ (cifmw_build_push_container_artifacts, 'scripts') | ansible.builtin.path_join }}, script={{ (_podman_push_container_cmd, item) | join(':') }}] ***
2025-10-13 19:39:17.043530 | controller | Monday 13 October 2025  19:39:16 +0000 (0:00:31.009)       0:15:28.876 ********
2025-10-13 19:39:17.043546 | controller | skipping: [localhost] => (item=a301e7c15ccc9d964f35eb738c51dc015730d4ed)
2025-10-13 19:39:17.106075 | controller | skipping: [localhost] => (item=3402-latest)
2025-10-13 19:39:17.106321 | controller | skipping: [localhost]
2025-10-13 19:39:17.106346 | controller |
2025-10-13 19:39:17.106361 | controller | PLAY RECAP *********************************************************************
2025-10-13 19:39:17.106375 | controller | localhost                  : ok=9    changed=6    unreachable=0    failed=0    skipped=4    rescued=0    ignored=0
2025-10-13 19:39:17.106389 | controller |
2025-10-13 19:39:17.106403 | controller | Monday 13 October 2025  19:39:17 +0000 (0:00:00.112)       0:15:28.988 ********
2025-10-13 19:39:17.106420 | controller | ===============================================================================
2025-10-13 19:39:17.106434 | controller | build_push_container : Build container -------------------------------- 887.59s
2025-10-13 19:39:17.106448 | controller | build_push_container : Push manifest ----------------------------------- 31.01s
2025-10-13 19:39:17.106461 | controller | build_push_container : Install qemu-user-static for multi arch builds --- 6.46s
2025-10-13 19:39:17.106475 | controller | Gathering Facts --------------------------------------------------------- 1.06s
2025-10-13 19:39:17.106488 | controller | podman : Ensure podman is installed ------------------------------------- 0.94s
2025-10-13 19:39:17.106502 | controller | build_push_container : Ensure directories are present ------------------- 0.55s
2025-10-13 19:39:17.106515 | controller | podman : Enable loginctl linger for ansible_user_id --------------------- 0.41s
2025-10-13 19:39:17.106529 | controller | build_push_container : Create manifest ---------------------------------- 0.33s
2025-10-13 19:39:17.106542 | controller | build_push_container : Ensure manifest doesn't exist -------------------- 0.31s
2025-10-13 19:39:17.106555 | controller | build_push_container : Push container ----------------------------------- 0.11s
2025-10-13 19:39:17.106569 | controller | Run build_push_container role ------------------------------------------- 0.06s
2025-10-13 19:39:17.106582 | controller | podman : Run podman system migrate if subuid/subgid files were changed --- 0.04s
2025-10-13 19:39:17.106595 | controller | podman : Ensure subordinate UID entry exists for zuul ------------------- 0.03s
2025-10-13 19:39:17.106626 | controller | podman : Ensure subordinate GID entry exists for zuul ------------------- 0.03s
2025-10-13 19:39:17.361269 | controller | changed
2025-10-13 19:39:17.406619 | 
2025-10-13 19:39:17.406808 | PLAY RECAP
2025-10-13 19:39:17.406901 | controller | ok: 10 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-10-13 19:39:17.406947 | 
2025-10-13 19:39:17.558257 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/build_push_container_runner.yml@main]
2025-10-13 19:39:17.560794 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-10-13 19:39:18.211100 | 
2025-10-13 19:39:18.211215 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-10-13 19:39:18.235527 | 
2025-10-13 19:39:18.235661 | TASK [Filter out host if needed]
2025-10-13 19:39:18.244421 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-10-13 19:39:18.248865 | 
2025-10-13 19:39:18.248925 | TASK [Ensure file is present]
2025-10-13 19:39:19.041039 | controller | ok
2025-10-13 19:39:19.053346 | 
2025-10-13 19:39:19.053530 | TASK [Manage molecule report file]
2025-10-13 19:39:19.600150 | controller | skipping: Conditional result was False
2025-10-13 19:39:19.612766 | 
2025-10-13 19:39:19.612975 | TASK [Check if we get ci-framework-data basedir]
2025-10-13 19:39:19.875341 | controller | ok
2025-10-13 19:39:19.887374 | 
2025-10-13 19:39:19.887552 | TASK [Create ci-framework-data log directory for zuul]
2025-10-13 19:39:20.323365 | controller | changed
2025-10-13 19:39:20.334858 | 
2025-10-13 19:39:20.334987 | TASK [Copy ci-framework interesting files]
2025-10-13 19:39:20.753511 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2025-10-13 19:39:20.884059 | controller | changed
2025-10-13 19:39:20.896624 | 
2025-10-13 19:39:20.896803 | TASK [Get SELinux listing]
2025-10-13 19:39:21.452492 | controller | changed
2025-10-13 19:39:21.463270 | 
2025-10-13 19:39:21.463439 | TASK [Generate log index]
2025-10-13 19:39:22.478904 | controller | changed
2025-10-13 19:39:22.490066 | 
2025-10-13 19:39:22.490211 | TASK [Get some env related data]
2025-10-13 19:39:23.424690 | controller | /home/zuul/.local/bin/ansible
2025-10-13 19:39:24.542095 | controller | changed
2025-10-13 19:39:24.547851 | 
2025-10-13 19:39:24.547922 | TASK [Generate list of logs to collect in home directory]
2025-10-13 19:39:24.970997 | controller | ok: All paths examined
2025-10-13 19:39:24.978667 | 
2025-10-13 19:39:24.978776 | LOOP [Copy logs from home directory]
2025-10-13 19:39:25.477159 | controller | changed:
2025-10-13 19:39:25.477430 | controller | {
2025-10-13 19:39:25.477460 | controller |   "atime": 1760383401.6164153,
2025-10-13 19:39:25.477479 | controller |   "ctime": 1760384357.042627,
2025-10-13 19:39:25.477495 | controller |   "dev": 64513,
2025-10-13 19:39:25.477511 | controller |   "gid": 1000,
2025-10-13 19:39:25.477526 | controller |   "gr_name": "zuul",
2025-10-13 19:39:25.477542 | controller |   "inode": 4488056,
2025-10-13 19:39:25.477557 | controller |   "isblk": false,
2025-10-13 19:39:25.477572 | controller |   "ischr": false,
2025-10-13 19:39:25.477587 | controller |   "isdir": false,
2025-10-13 19:39:25.477601 | controller |   "isfifo": false,
2025-10-13 19:39:25.477616 | controller |   "isgid": false,
2025-10-13 19:39:25.477630 | controller |   "islnk": false,
2025-10-13 19:39:25.477644 | controller |   "isreg": true,
2025-10-13 19:39:25.477658 | controller |   "issock": false,
2025-10-13 19:39:25.477673 | controller |   "isuid": false,
2025-10-13 19:39:25.477687 | controller |   "mode": "0644",
2025-10-13 19:39:25.477701 | controller |   "mtime": 1760384357.042627,
2025-10-13 19:39:25.477715 | controller |   "nlink": 1,
2025-10-13 19:39:25.477757 | controller |   "path": "/home/zuul/ansible.log",
2025-10-13 19:39:25.477778 | controller |   "pw_name": "zuul",
2025-10-13 19:39:25.477794 | controller |   "rgrp": true,
2025-10-13 19:39:25.477809 | controller |   "roth": true,
2025-10-13 19:39:25.477823 | controller |   "rusr": true,
2025-10-13 19:39:25.477838 | controller |   "size": 16093,
2025-10-13 19:39:25.477852 | controller |   "uid": 1000,
2025-10-13 19:39:25.477867 | controller |   "wgrp": false,
2025-10-13 19:39:25.477881 | controller |   "woth": false,
2025-10-13 19:39:25.477898 | controller |   "wusr": true,
2025-10-13 19:39:25.477913 | controller |   "xgrp": false,
2025-10-13 19:39:25.477928 | controller |   "xoth": false,
2025-10-13 19:39:25.477943 | controller |   "xusr": false
2025-10-13 19:39:25.477966 | controller | }
2025-10-13 19:39:25.495474 | 
2025-10-13 19:39:25.495590 | TASK [Copy crio stats log file]
2025-10-13 19:39:25.520141 | controller | skipping: Conditional result was False
2025-10-13 19:39:25.526052 | 
2025-10-13 19:39:25.526116 | TASK [Get SELinux related data]
2025-10-13 19:39:25.803867 | controller | <no matches>
2025-10-13 19:39:26.057239 | controller | ERROR
2025-10-13 19:39:26.057447 | controller | {
2025-10-13 19:39:26.057478 | controller |   "delta": "0:00:00.023044",
2025-10-13 19:39:26.057497 | controller |   "end": "2025-10-13 19:39:25.806117",
2025-10-13 19:39:26.057514 | controller |   "msg": "non-zero return code",
2025-10-13 19:39:26.057530 | controller |   "rc": 1,
2025-10-13 19:39:26.057546 | controller |   "start": "2025-10-13 19:39:25.783073"
2025-10-13 19:39:26.057561 | controller | }
2025-10-13 19:39:26.057583 | controller | ERROR: Ignoring Errors
2025-10-13 19:39:26.062864 | 
2025-10-13 19:39:26.062936 | TASK [Create system configuration directory]
2025-10-13 19:39:26.374339 | controller | changed
2025-10-13 19:39:26.385844 | 
2025-10-13 19:39:26.386022 | TASK [Get some of the system configurations]
2025-10-13 19:39:26.935985 | controller | changed
2025-10-13 19:39:26.943586 | 
2025-10-13 19:39:26.943753 | TASK [Copy generated documentation if available]
2025-10-13 19:39:26.960348 | controller | skipping: Conditional result was False
2025-10-13 19:39:26.968339 | 
2025-10-13 19:39:26.968475 | TASK [Copy generated AsciiDoc documentation if available]
2025-10-13 19:39:26.984518 | controller | skipping: Conditional result was False
2025-10-13 19:39:26.992818 | 
2025-10-13 19:39:26.992958 | TASK [Compress logs bigger than 2MB]
2025-10-13 19:39:27.582461 | controller | changed
2025-10-13 19:39:27.590063 | 
2025-10-13 19:39:27.590198 | TASK [Copy files from workspace on node]
2025-10-13 19:39:27.612934 | controller | ok
2025-10-13 19:39:27.646550 | 
2025-10-13 19:39:27.646667 | TASK [fetch-output : Set log path for multiple nodes]
2025-10-13 19:39:27.670925 | controller | skipping: Conditional result was False
2025-10-13 19:39:27.679585 | 
2025-10-13 19:39:27.679749 | TASK [fetch-output : Set log path for single node]
2025-10-13 19:39:27.713542 | controller | ok
2025-10-13 19:39:27.721983 | 
2025-10-13 19:39:27.722135 | LOOP [fetch-output : Ensure local output dirs]
2025-10-13 19:39:28.005964 | controller -> localhost | ok: "/var/lib/zuul/builds/86cf2a09720f435088aab28176e3fe8d/work/logs"
2025-10-13 19:39:28.006329 | controller -> localhost | changed: All items complete
2025-10-13 19:39:28.006370 | 
2025-10-13 19:39:28.306940 | controller -> localhost | changed: "/var/lib/zuul/builds/86cf2a09720f435088aab28176e3fe8d/work/artifacts"
2025-10-13 19:39:28.540971 | controller -> localhost | changed: "/var/lib/zuul/builds/86cf2a09720f435088aab28176e3fe8d/work/docs"
2025-10-13 19:39:28.559967 | 
2025-10-13 19:39:28.560114 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-10-13 19:39:29.312315 | controller | changed:
2025-10-13 19:39:29.312859 | controller | .d..t...... ./
2025-10-13 19:39:29.312954 | controller | >f+++++++++ README.html
2025-10-13 19:39:29.313019 | controller | >f+++++++++ ansible.log
2025-10-13 19:39:29.313081 | controller | >f+++++++++ dmesg.log
2025-10-13 19:39:29.313139 | controller | >f+++++++++ installed-pkgs.log
2025-10-13 19:39:29.313197 | controller | >f+++++++++ python.log
2025-10-13 19:39:29.313261 | controller | >f+++++++++ registries.conf
2025-10-13 19:39:29.313319 | controller | >f+++++++++ selinux-denials.log
2025-10-13 19:39:29.313376 | controller | >f+++++++++ selinux-listing.log
2025-10-13 19:39:29.313443 | controller | cd+++++++++ ci-framework-data/
2025-10-13 19:39:29.313502 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-10-13 19:39:29.313591 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-10-13 19:39:29.313654 | controller | cd+++++++++ ci-framework-data/artifacts/build_push_container/
2025-10-13 19:39:29.313710 | controller | cd+++++++++ ci-framework-data/artifacts/build_push_container/logs/
2025-10-13 19:39:29.313827 | controller | >f+++++++++ ci-framework-data/artifacts/build_push_container/logs/ci_script_000_install_qemu_user_static_for.log
2025-10-13 19:39:29.313883 | controller | >f+++++++++ ci-framework-data/artifacts/build_push_container/logs/ci_script_001_build.log
2025-10-13 19:39:29.313944 | controller | >f+++++++++ ci-framework-data/artifacts/build_push_container/logs/ci_script_002_push.log
2025-10-13 19:39:29.314011 | controller | >f+++++++++ ci-framework-data/artifacts/build_push_container/logs/ci_script_003_push.log
2025-10-13 19:39:29.314074 | controller | cd+++++++++ ci-framework-data/artifacts/build_push_container/scripts/
2025-10-13 19:39:29.314135 | controller | >f+++++++++ ci-framework-data/artifacts/build_push_container/scripts/ci_script_000_install_qemu_user_static_for.sh
2025-10-13 19:39:29.314197 | controller | >f+++++++++ ci-framework-data/artifacts/build_push_container/scripts/ci_script_001_build.sh
2025-10-13 19:39:29.314259 | controller | >f+++++++++ ci-framework-data/artifacts/build_push_container/scripts/ci_script_002_push.sh
2025-10-13 19:39:29.314324 | controller | >f+++++++++ ci-framework-data/artifacts/build_push_container/scripts/ci_script_003_push.sh
2025-10-13 19:39:29.314390 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-10-13 19:39:29.314457 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-10-13 19:39:29.314527 | controller | cd+++++++++ ci-framework-data/logs/
2025-10-13 19:39:29.314604 | controller | cd+++++++++ registries.conf.d/
2025-10-13 19:39:29.314677 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-10-13 19:39:29.314797 | controller | cd+++++++++ system-config/
2025-10-13 19:39:29.314879 | controller | cd+++++++++ system-config/libvirt/
2025-10-13 19:39:29.314953 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-10-13 19:39:29.315017 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-10-13 19:39:29.853227 | controller | changed: .d..t...... ./
2025-10-13 19:39:30.347928 | controller | changed: .d..t...... ./
2025-10-13 19:39:30.362983 | 
2025-10-13 19:39:30.363085 | TASK [Return artifact to Zuul]
2025-10-13 19:39:30.386436 | controller | skipping: Conditional result was False
2025-10-13 19:39:30.406322 | 
2025-10-13 19:39:30.406384 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-10-13 19:39:30.406478 | 
2025-10-13 19:39:30.406505 | PLAY RECAP
2025-10-13 19:39:30.406541 | controller | ok: 16 changed: 12 unreachable: 0 failed: 0 skipped: 6 rescued: 0 ignored: 1
2025-10-13 19:39:30.406561 | 
2025-10-13 19:39:30.503163 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-10-13 19:39:30.504708 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-10-13 19:39:31.160705 | 
2025-10-13 19:39:31.160847 | PLAY [all]
2025-10-13 19:39:31.180508 | 
2025-10-13 19:39:31.180615 | TASK [include_role : fetch-output]
2025-10-13 19:39:31.220221 | controller | ok
2025-10-13 19:39:31.238287 | 
2025-10-13 19:39:31.238421 | TASK [fetch-output : Set log path for multiple nodes]
2025-10-13 19:39:31.304697 | controller | skipping: Conditional result was False
2025-10-13 19:39:31.310840 | 
2025-10-13 19:39:31.311032 | TASK [fetch-output : Set log path for single node]
2025-10-13 19:39:31.349922 | controller | ok
2025-10-13 19:39:31.355102 | 
2025-10-13 19:39:31.355171 | LOOP [fetch-output : Ensure local output dirs]
2025-10-13 19:39:31.798620 | controller -> localhost | ok: "/var/lib/zuul/builds/86cf2a09720f435088aab28176e3fe8d/work/logs"
2025-10-13 19:39:32.034769 | controller -> localhost | ok: "/var/lib/zuul/builds/86cf2a09720f435088aab28176e3fe8d/work/artifacts"
2025-10-13 19:39:32.237338 | controller -> localhost | ok: "/var/lib/zuul/builds/86cf2a09720f435088aab28176e3fe8d/work/docs"
2025-10-13 19:39:32.246139 | 
2025-10-13 19:39:32.246246 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-10-13 19:39:32.959646 | controller | ok
2025-10-13 19:39:32.959994 | controller | ok: All items complete
2025-10-13 19:39:32.960042 | 
2025-10-13 19:39:33.452257 | controller | ok
2025-10-13 19:39:33.962341 | controller | ok
2025-10-13 19:39:33.997500 | 
2025-10-13 19:39:33.997689 | TASK [include_role : fetch-output-openshift]
2025-10-13 19:39:34.015097 | controller | skipping: Conditional result was False
2025-10-13 19:39:34.029994 | 
2025-10-13 19:39:34.030714 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-10-13 19:39:34.454827 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.009613
2025-10-13 19:39:34.752129 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.014044
2025-10-13 19:39:34.808187 | 
2025-10-13 19:39:34.808302 | PLAY [all]
2025-10-13 19:39:34.828701 | 
2025-10-13 19:39:34.828841 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-10-13 19:39:35.345761 | controller | changed
2025-10-13 19:39:35.392371 | 
2025-10-13 19:39:35.392480 | PLAY RECAP
2025-10-13 19:39:35.392538 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-10-13 19:39:35.392568 | 
2025-10-13 19:39:35.499437 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-10-13 19:39:35.500780 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-10-13 19:39:36.124036 | 
2025-10-13 19:39:36.124151 | PLAY [localhost]
2025-10-13 19:39:36.142362 | 
2025-10-13 19:39:36.142468 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-10-13 19:39:36.623914 | localhost | changed
2025-10-13 19:39:36.629231 | 
2025-10-13 19:39:36.629336 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-10-13 19:39:36.669115 | localhost | ok
2025-10-13 19:39:36.677629 | 
2025-10-13 19:39:36.677709 | TASK [add-fileserver : Create SSH private key tempfile]
2025-10-13 19:39:37.133885 | localhost | changed
2025-10-13 19:39:37.138922 | 
2025-10-13 19:39:37.139003 | TASK [add-fileserver : Create SSH private key from secret]
2025-10-13 19:39:37.909266 | localhost | changed
2025-10-13 19:39:37.914374 | 
2025-10-13 19:39:37.914440 | TASK [add-fileserver : Add fileserver ssh key]
2025-10-13 19:39:38.338693 | localhost | Identity added: /var/lib/zuul/builds/86cf2a09720f435088aab28176e3fe8d/work/tmp/ansible.kmfw82zs (/var/lib/zuul/builds/86cf2a09720f435088aab28176e3fe8d/work/tmp/ansible.kmfw82zs)
2025-10-13 19:39:38.338965 | localhost | ok: Runtime: 0:00:00.009293
2025-10-13 19:39:38.343457 | 
2025-10-13 19:39:38.343524 | TASK [add-fileserver : Remove SSH private key from disk]
2025-10-13 19:39:38.633290 | localhost | ok: Runtime: 0:00:00.010267
2025-10-13 19:39:38.638323 | 
2025-10-13 19:39:38.638391 | TASK [add-fileserver : Add fileserver to inventory]
2025-10-13 19:39:38.724074 | localhost | changed
2025-10-13 19:39:38.732950 | 
2025-10-13 19:39:38.733143 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-10-13 19:39:39.145998 | localhost | changed
2025-10-13 19:39:39.167208 | 
2025-10-13 19:39:39.167314 | PLAY [localhost]
2025-10-13 19:39:39.180630 | 
2025-10-13 19:39:39.180771 | TASK [Generate bulk log download script]
2025-10-13 19:39:39.199023 | localhost | ok
2025-10-13 19:39:39.210845 | 
2025-10-13 19:39:39.210960 | TASK [local-log-download : Check API endpoint is defined]
2025-10-13 19:39:39.241462 | localhost | ok: All assertions passed
2025-10-13 19:39:39.248588 | 
2025-10-13 19:39:39.248700 | TASK [local-log-download : Create download script]
2025-10-13 19:39:39.701021 | localhost -> localhost | changed
2025-10-13 19:39:39.710837 | 
2025-10-13 19:39:39.710918 | TASK [Register quick-download link]
2025-10-13 19:39:39.775187 | localhost | ok
2025-10-13 19:39:39.790877 | 
2025-10-13 19:39:39.790939 | PLAY [logserver.rdoproject.org]
2025-10-13 19:39:39.800547 | 
2025-10-13 19:39:39.800607 | TASK [Set zuul-log-path fact]
2025-10-13 19:39:39.817114 | logserver.rdoproject.org | ok
2025-10-13 19:39:39.828640 | 
2025-10-13 19:39:39.828821 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-13 19:39:39.855066 | logserver.rdoproject.org | ok
2025-10-13 19:39:39.860225 | 
2025-10-13 19:39:39.860289 | TASK [upload-logs : Create log directories]
2025-10-13 19:39:40.494778 | logserver.rdoproject.org | changed
2025-10-13 19:39:40.501330 | 
2025-10-13 19:39:40.501492 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-10-13 19:39:40.783188 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.006824
2025-10-13 19:39:40.788228 | 
2025-10-13 19:39:40.788348 | TASK [upload-logs : Upload logs to log server]
2025-10-13 19:39:41.506638 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-10-13 19:39:41.513311 | 
2025-10-13 19:39:41.513472 | LOOP [upload-logs : Compress console log and json output]
2025-10-13 19:39:41.581428 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-13 19:39:41.591602 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-13 19:39:41.608454 | 
2025-10-13 19:39:41.608608 | LOOP [upload-logs : Upload compressed console log and json output]
2025-10-13 19:39:41.663070 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-13 19:39:41.663521 | 
2025-10-13 19:39:41.667854 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-13 19:39:41.684315 | 
2025-10-13 19:39:41.684460 | LOOP [upload-logs : Upload console log and json output]