2025-10-13 21:30:09.558145 | Job console starting...
2025-10-13 21:30:09.797290 | Updating repositories
2025-10-13 21:30:10.528675 | Preparing job workspace
2025-10-13 21:30:17.827832 | Running Ansible setup...
2025-10-13 21:30:21.080536 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-10-13 21:30:21.633152 | 
2025-10-13 21:30:21.633501 | PLAY [localhost]
2025-10-13 21:30:21.643549 | 
2025-10-13 21:30:21.643641 | TASK [Gathering Facts]
2025-10-13 21:30:22.491538 | localhost | ok
2025-10-13 21:30:22.505248 | 
2025-10-13 21:30:22.505342 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-10-13 21:30:22.864968 | localhost -> localhost | changed
2025-10-13 21:30:22.871588 | 
2025-10-13 21:30:22.871672 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-10-13 21:30:23.652590 | localhost -> localhost | changed
2025-10-13 21:30:23.661952 | 
2025-10-13 21:30:23.662032 | TASK [Setup log path fact]
2025-10-13 21:30:23.682285 | localhost | ok
2025-10-13 21:30:23.693996 | 
2025-10-13 21:30:23.694068 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-13 21:30:23.723214 | localhost | ok
2025-10-13 21:30:23.730841 | 
2025-10-13 21:30:23.730942 | TASK [emit-job-header : Print job information]
2025-10-13 21:30:23.770225 | # Job Information
2025-10-13 21:30:23.770450 | Ansible Version: 2.15.12
2025-10-13 21:30:23.770482 | Job: build-push-container-cifmw-client
2025-10-13 21:30:23.770507 | Pipeline: github-check
2025-10-13 21:30:23.770528 | Executor: ibm-bm4-ze.softwarefactory-project.io
2025-10-13 21:30:23.770546 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3400
2025-10-13 21:30:23.770566 | Log URL (when completed): https://logserver.rdoproject.org/4b8/rdoproject.org/4b802516a1d94def9449e94b8ed007d6/
2025-10-13 21:30:23.770585 | Event ID: 51d5ee20-a875-11f0-9f88-6a7317f8abed
2025-10-13 21:30:23.774286 | 
2025-10-13 21:30:23.774367 | LOOP [emit-job-header : Print node information]
2025-10-13 21:30:23.868336 | localhost | ok:
2025-10-13 21:30:23.868624 | localhost | # Node Information
2025-10-13 21:30:23.868654 | localhost | Inventory Hostname: controller
2025-10-13 21:30:23.868679 | localhost | Hostname: np0005486343
2025-10-13 21:30:23.868699 | localhost | Username: zuul
2025-10-13 21:30:23.868719 | localhost | Distro: CentOS 9
2025-10-13 21:30:23.868736 | localhost | Provider: ibm-bm4-nodepool
2025-10-13 21:30:23.868752 | localhost | Region: regionOne
2025-10-13 21:30:23.868767 | localhost | Label: cloud-centos-9-stream-tripleo
2025-10-13 21:30:23.868782 | localhost | Product Name: OpenStack Compute
2025-10-13 21:30:23.868797 | localhost | Interface IP: 192.168.26.206
2025-10-13 21:30:23.892270 | 
2025-10-13 21:30:23.892448 | PLAY [all]
2025-10-13 21:30:23.899316 | 
2025-10-13 21:30:23.899385 | TASK [Gather network facts]
2025-10-13 21:30:24.286816 | controller | ok
2025-10-13 21:30:24.302235 | 
2025-10-13 21:30:24.302407 | TASK [include_role : start-zuul-console]
2025-10-13 21:30:24.322904 | controller | ok
2025-10-13 21:30:24.334723 | 
2025-10-13 21:30:24.334795 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-10-13 21:30:24.690235 | controller | ok
2025-10-13 21:30:24.698976 | 
2025-10-13 21:30:24.699052 | TASK [include_role : add-build-sshkey]
2025-10-13 21:30:24.729430 | controller | ok
2025-10-13 21:30:24.742462 | 
2025-10-13 21:30:24.742529 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-10-13 21:30:24.950227 | controller -> localhost | ok
2025-10-13 21:30:24.958232 | 
2025-10-13 21:30:24.958320 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-10-13 21:30:24.988892 | controller | ok
2025-10-13 21:30:25.002190 | controller | included: /var/lib/zuul/builds/4b802516a1d94def9449e94b8ed007d6/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-10-13 21:30:25.007843 | 
2025-10-13 21:30:25.007905 | TASK [add-build-sshkey : Create Temp SSH key]
2025-10-13 21:30:25.520532 | controller -> localhost | Generating public/private rsa key pair.
2025-10-13 21:30:25.520828 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/4b802516a1d94def9449e94b8ed007d6/work/4b802516a1d94def9449e94b8ed007d6_id_rsa.
2025-10-13 21:30:25.520873 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/4b802516a1d94def9449e94b8ed007d6/work/4b802516a1d94def9449e94b8ed007d6_id_rsa.pub.
2025-10-13 21:30:25.520900 | controller -> localhost | The key fingerprint is:
2025-10-13 21:30:25.520948 | controller -> localhost | SHA256:Qgjd3bAAHe9v6P4XqOWnrghrxKQ1OP9XxxrdqBeakts zuul-build-sshkey
2025-10-13 21:30:25.520975 | controller -> localhost | The key's randomart image is:
2025-10-13 21:30:25.520997 | controller -> localhost | +---[RSA 3072]----+
2025-10-13 21:30:25.521018 | controller -> localhost | |  ...+oo.o       |
2025-10-13 21:30:25.521038 | controller -> localhost | |   ...oo...      |
2025-10-13 21:30:25.521056 | controller -> localhost | |   .. . o        |
2025-10-13 21:30:25.521075 | controller -> localhost | |  o +. .         |
2025-10-13 21:30:25.521094 | controller -> localhost | |   B .. S  + o   |
2025-10-13 21:30:25.521113 | controller -> localhost | |  . +  . o= B .  |
2025-10-13 21:30:25.521132 | controller -> localhost | |   ...  .*oB o   |
2025-10-13 21:30:25.521152 | controller -> localhost | |    .o.o=o* +    |
2025-10-13 21:30:25.521171 | controller -> localhost | |   .. .o=*E=     |
2025-10-13 21:30:25.521189 | controller -> localhost | +----[SHA256]-----+
2025-10-13 21:30:25.521249 | controller -> localhost | ok: Runtime: 0:00:00.149588
2025-10-13 21:30:25.527691 | 
2025-10-13 21:30:25.527774 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-10-13 21:30:25.563174 | controller | ok
2025-10-13 21:30:25.573698 | controller | included: /var/lib/zuul/builds/4b802516a1d94def9449e94b8ed007d6/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-10-13 21:30:25.581732 | 
2025-10-13 21:30:25.581802 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-10-13 21:30:25.607447 | controller | skipping: Conditional result was False
2025-10-13 21:30:25.613701 | 
2025-10-13 21:30:25.613785 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-10-13 21:30:26.013367 | controller | changed
2025-10-13 21:30:26.018650 | 
2025-10-13 21:30:26.018745 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-10-13 21:30:26.223015 | controller | ok
2025-10-13 21:30:26.228171 | 
2025-10-13 21:30:26.228242 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-10-13 21:30:26.837673 | controller | changed
2025-10-13 21:30:26.843450 | 
2025-10-13 21:30:26.843590 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-10-13 21:30:27.442127 | controller | changed
2025-10-13 21:30:27.446995 | 
2025-10-13 21:30:27.447060 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-10-13 21:30:27.471799 | controller | skipping: Conditional result was False
2025-10-13 21:30:27.477480 | 
2025-10-13 21:30:27.477558 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-10-13 21:30:27.929934 | controller -> localhost | changed
2025-10-13 21:30:27.946018 | 
2025-10-13 21:30:27.946161 | TASK [add-build-sshkey : Add back temp key]
2025-10-13 21:30:28.192717 | controller -> localhost | Identity added: /var/lib/zuul/builds/4b802516a1d94def9449e94b8ed007d6/work/4b802516a1d94def9449e94b8ed007d6_id_rsa (zuul-build-sshkey)
2025-10-13 21:30:28.193039 | controller -> localhost | ok: Runtime: 0:00:00.007291
2025-10-13 21:30:28.199391 | 
2025-10-13 21:30:28.199466 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-10-13 21:30:28.535112 | controller | ok
2025-10-13 21:30:28.545183 | 
2025-10-13 21:30:28.545274 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-10-13 21:30:28.570384 | controller | skipping: Conditional result was False
2025-10-13 21:30:28.579784 | 
2025-10-13 21:30:28.579850 | TASK [include_role : validate-host]
2025-10-13 21:30:28.599036 | controller | ok
2025-10-13 21:30:28.618749 | 
2025-10-13 21:30:28.618817 | TASK [validate-host : Define zuul_info_dir fact]
2025-10-13 21:30:28.645979 | controller | ok
2025-10-13 21:30:28.650475 | 
2025-10-13 21:30:28.650536 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-10-13 21:30:28.887354 | controller -> localhost | ok
2025-10-13 21:30:28.895175 | 
2025-10-13 21:30:28.895264 | TASK [validate-host : Collect information about the host]
2025-10-13 21:30:29.526516 | controller | ok
2025-10-13 21:30:29.561380 | 
2025-10-13 21:30:29.561526 | TASK [validate-host : Sanitize hostname]
2025-10-13 21:30:29.625557 | controller | ok
2025-10-13 21:30:29.631113 | 
2025-10-13 21:30:29.631190 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-10-13 21:30:30.089365 | controller -> localhost | changed
2025-10-13 21:30:30.095408 | 
2025-10-13 21:30:30.095494 | TASK [validate-host : Collect information about zuul worker]
2025-10-13 21:30:30.440400 | controller | ok
2025-10-13 21:30:30.444857 | 
2025-10-13 21:30:30.444945 | TASK [validate-host : Write out all zuul information for each host]
2025-10-13 21:30:30.833663 | controller -> localhost | changed
2025-10-13 21:30:30.844482 | 
2025-10-13 21:30:30.844551 | TASK [include_role : prepare-workspace-openshift]
2025-10-13 21:30:30.868219 | controller | skipping: Conditional result was False
2025-10-13 21:30:30.873681 | 
2025-10-13 21:30:30.873744 | TASK [include_role : remove-zuul-sshkey]
2025-10-13 21:30:30.898141 | controller | skipping: Conditional result was False
2025-10-13 21:30:30.903411 | 
2025-10-13 21:30:30.903478 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-10-13 21:30:31.117662 | controller | ok: "logs"
2025-10-13 21:30:31.117993 | controller | ok: All items complete
2025-10-13 21:30:31.118025 | 
2025-10-13 21:30:31.281795 | controller | ok: "artifacts"
2025-10-13 21:30:31.451067 | controller | ok: "docs"
2025-10-13 21:30:31.462130 | 
2025-10-13 21:30:31.462212 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-10-13 21:30:31.679492 | controller | changed: "logs"
2025-10-13 21:30:31.854100 | controller | changed: "artifacts"
2025-10-13 21:30:32.031426 | controller | changed: "docs"
2025-10-13 21:30:32.054562 | 
2025-10-13 21:30:32.054629 | PLAY RECAP
2025-10-13 21:30:32.054671 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-10-13 21:30:32.054698 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-13 21:30:32.054717 | 
2025-10-13 21:30:32.145288 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-10-13 21:30:32.146459 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-10-13 21:30:32.714323 | 
2025-10-13 21:30:32.714466 | PLAY [all]
2025-10-13 21:30:32.734202 | 
2025-10-13 21:30:32.734279 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-10-13 21:30:32.784695 | controller | ok
2025-10-13 21:30:32.789614 | 
2025-10-13 21:30:32.789694 | TASK [mirror-info-fork : Create /etc/ci]
2025-10-13 21:30:33.164720 | controller | changed
2025-10-13 21:30:33.170816 | 
2025-10-13 21:30:33.170900 | TASK [mirror-info-fork : Install ci_mirror script]
2025-10-13 21:30:33.994603 | controller | changed
2025-10-13 21:30:34.006542 | 
2025-10-13 21:30:34.006670 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-10-13 21:30:34.383045 | controller | changed:
2025-10-13 21:30:34.383277 | controller | {
2025-10-13 21:30:34.383318 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-10-13 21:30:34.383353 | controller | }
2025-10-13 21:30:34.585563 | controller | changed:
2025-10-13 21:30:34.585628 | controller | {
2025-10-13 21:30:34.585657 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-10-13 21:30:34.585679 | controller | }
2025-10-13 21:30:34.789272 | controller | changed:
2025-10-13 21:30:34.789393 | controller | {
2025-10-13 21:30:34.789421 | 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 21:30:34.789442 | controller | }
2025-10-13 21:30:34.991503 | controller | changed:
2025-10-13 21:30:34.991648 | controller | {
2025-10-13 21:30:34.991675 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-10-13 21:30:34.991696 | controller | }
2025-10-13 21:30:35.194748 | controller | changed:
2025-10-13 21:30:35.194845 | controller | {
2025-10-13 21:30:35.194871 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-10-13 21:30:35.194891 | controller | }
2025-10-13 21:30:35.400844 | controller | changed:
2025-10-13 21:30:35.400997 | controller | {
2025-10-13 21:30:35.401028 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-10-13 21:30:35.401050 | controller | }
2025-10-13 21:30:35.602767 | controller | changed:
2025-10-13 21:30:35.602864 | controller | {
2025-10-13 21:30:35.602891 | 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 21:30:35.602933 | controller | }
2025-10-13 21:30:35.807073 | controller | changed:
2025-10-13 21:30:35.807188 | controller | {
2025-10-13 21:30:35.807244 | 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 21:30:35.807268 | controller | }
2025-10-13 21:30:36.011151 | controller | changed:
2025-10-13 21:30:36.011285 | controller | {
2025-10-13 21:30:36.011326 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-10-13 21:30:36.011347 | controller | }
2025-10-13 21:30:36.210528 | controller | changed:
2025-10-13 21:30:36.210635 | controller | {
2025-10-13 21:30:36.210661 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-10-13 21:30:36.210681 | controller | }
2025-10-13 21:30:36.411514 | controller | changed:
2025-10-13 21:30:36.411586 | controller | {
2025-10-13 21:30:36.411611 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-10-13 21:30:36.411631 | controller | }
2025-10-13 21:30:36.612483 | controller | changed:
2025-10-13 21:30:36.612672 | controller | {
2025-10-13 21:30:36.612703 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-10-13 21:30:36.612725 | controller | }
2025-10-13 21:30:36.813197 | controller | changed:
2025-10-13 21:30:36.813272 | controller | {
2025-10-13 21:30:36.813308 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-10-13 21:30:36.813331 | controller | }
2025-10-13 21:30:37.021426 | controller | changed:
2025-10-13 21:30:37.021516 | controller | {
2025-10-13 21:30:37.021542 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-10-13 21:30:37.021561 | controller | }
2025-10-13 21:30:37.223904 | controller | changed:
2025-10-13 21:30:37.224119 | controller | {
2025-10-13 21:30:37.224157 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-10-13 21:30:37.224180 | controller | }
2025-10-13 21:30:37.425570 | controller | changed:
2025-10-13 21:30:37.425649 | controller | {
2025-10-13 21:30:37.425675 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-10-13 21:30:37.425694 | controller | }
2025-10-13 21:30:37.625655 | controller | changed:
2025-10-13 21:30:37.625788 | controller | {
2025-10-13 21:30:37.625814 | 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 21:30:37.625834 | controller | }
2025-10-13 21:30:37.826703 | controller | changed:
2025-10-13 21:30:37.826761 | controller | {
2025-10-13 21:30:37.826786 | 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 21:30:37.826814 | controller | }
2025-10-13 21:30:38.029077 | controller | changed:
2025-10-13 21:30:38.029156 | controller | {
2025-10-13 21:30:38.029182 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-10-13 21:30:38.029200 | controller | }
2025-10-13 21:30:38.236128 | controller | changed:
2025-10-13 21:30:38.236211 | controller | {
2025-10-13 21:30:38.236237 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-10-13 21:30:38.236258 | controller | }
2025-10-13 21:30:38.438279 | controller | changed:
2025-10-13 21:30:38.438412 | controller | {
2025-10-13 21:30:38.438441 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-10-13 21:30:38.438463 | controller | }
2025-10-13 21:30:38.641169 | controller | changed:
2025-10-13 21:30:38.641221 | controller | {
2025-10-13 21:30:38.641247 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-10-13 21:30:38.641267 | controller | }
2025-10-13 21:30:38.843283 | controller | changed:
2025-10-13 21:30:38.843343 | controller | {
2025-10-13 21:30:38.843368 | 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 21:30:38.843388 | controller | }
2025-10-13 21:30:39.047100 | controller | changed:
2025-10-13 21:30:39.047976 | controller | {
2025-10-13 21:30:39.048012 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-10-13 21:30:39.048034 | controller | }
2025-10-13 21:30:39.246555 | controller | changed:
2025-10-13 21:30:39.246659 | controller | {
2025-10-13 21:30:39.246684 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-10-13 21:30:39.246702 | controller | }
2025-10-13 21:30:39.443728 | controller | changed:
2025-10-13 21:30:39.443844 | controller | {
2025-10-13 21:30:39.443869 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-10-13 21:30:39.443889 | controller | }
2025-10-13 21:30:39.462455 | 
2025-10-13 21:30:39.462591 | TASK [Set timezone to UTC]
2025-10-13 21:30:39.848708 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-10-13 21:30:39.856012 | 
2025-10-13 21:30:39.856108 | TASK [Create nodepool directory]
2025-10-13 21:30:40.062831 | controller | changed
2025-10-13 21:30:40.068816 | 
2025-10-13 21:30:40.068884 | TASK [Create nodepool sub_nodes file]
2025-10-13 21:30:40.568604 | controller | changed
2025-10-13 21:30:40.574389 | 
2025-10-13 21:30:40.574468 | TASK [Create nodepool sub_nodes_private file]
2025-10-13 21:30:41.117141 | controller | changed
2025-10-13 21:30:41.122312 | 
2025-10-13 21:30:41.122383 | LOOP [Populate nodepool sub_nodes file]
2025-10-13 21:30:41.151739 | 
2025-10-13 21:30:41.151858 | LOOP [Populate nodepool sub_nodes_private file]
2025-10-13 21:30:41.180236 | 
2025-10-13 21:30:41.180390 | TASK [Create nodepool primary file]
2025-10-13 21:30:41.203532 | controller | skipping: Conditional result was False
2025-10-13 21:30:41.209179 | 
2025-10-13 21:30:41.209255 | TASK [Create nodepool node_private for this node]
2025-10-13 21:30:41.833625 | controller | changed
2025-10-13 21:30:41.841786 | 
2025-10-13 21:30:41.841894 | LOOP [Copy ssh keys to nodepool directory]
2025-10-13 21:30:42.270900 | controller | ok: Item: id_rsa Runtime: 0:00:00.005598
2025-10-13 21:30:42.271114 | 
2025-10-13 21:30:42.449730 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.004935
2025-10-13 21:30:42.466874 | 
2025-10-13 21:30:42.467083 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-10-13 21:30:43.006495 | controller | changed
2025-10-13 21:30:43.012594 | 
2025-10-13 21:30:43.012663 | TASK [Validate sudoers config after edits]
2025-10-13 21:30:43.240207 | controller | /etc/sudoers: parsed OK
2025-10-13 21:30:43.240272 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-10-13 21:30:43.240282 | controller | /etc/sudoers.d/zuul: parsed OK
2025-10-13 21:30:43.240288 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-10-13 21:30:43.540217 | controller | ok: Runtime: 0:00:00.004979
2025-10-13 21:30:43.547459 | 
2025-10-13 21:30:43.547540 | TASK [Show the environment passed in to job shell scripts]
2025-10-13 21:30:43.769857 | controller | SHELL=/bin/bash
2025-10-13 21:30:43.769902 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-10-13 21:30:43.769912 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-10-13 21:30:43.769918 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/00/3400/1a213a18fb464a8a107c72feb9213fc204a6b545
2025-10-13 21:30:43.769924 | controller | PWD=/home/zuul
2025-10-13 21:30:43.769929 | controller | ZUUL_PIPELINE=github-check
2025-10-13 21:30:43.769935 | controller | LOGNAME=zuul
2025-10-13 21:30:43.769940 | controller | XDG_SESSION_TYPE=tty
2025-10-13 21:30:43.769945 | controller | _=/usr/bin/env
2025-10-13 21:30:43.769988 | controller | MOTD_SHOWN=pam
2025-10-13 21:30:43.769996 | controller | HOME=/home/zuul
2025-10-13 21:30:43.770001 | controller | LANG=en_US.UTF-8
2025-10-13 21:30:43.770007 | controller | SSH_CONNECTION=192.168.26.12 49102 192.168.26.206 22
2025-10-13 21:30:43.770012 | 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 21:30:43.770022 | controller | ZUUL_CHANGE_IDS=3400,1a213a18fb464a8a107c72feb9213fc204a6b545
2025-10-13 21:30:43.770027 | controller | WORKSPACE=/home/zuul/workspace
2025-10-13 21:30:43.770033 | controller | XDG_SESSION_CLASS=user
2025-10-13 21:30:43.770038 | controller | SELINUX_ROLE_REQUESTED=
2025-10-13 21:30:43.770044 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-10-13 21:30:43.770049 | controller | USER=zuul
2025-10-13 21:30:43.770054 | controller | ZUUL_VOTING=True
2025-10-13 21:30:43.770059 | controller | BUILD_TIMEOUT=5400000
2025-10-13 21:30:43.770065 | controller | SELINUX_USE_CURRENT_RANGE=
2025-10-13 21:30:43.770082 | controller | SHLVL=1
2025-10-13 21:30:43.770088 | controller | ZUUL_PATCHSET=1a213a18fb464a8a107c72feb9213fc204a6b545
2025-10-13 21:30:43.770093 | controller | XDG_SESSION_ID=1
2025-10-13 21:30:43.770098 | controller | ZUUL_BRANCH=main
2025-10-13 21:30:43.770103 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-10-13 21:30:43.770109 | controller | SSH_CLIENT=192.168.26.12 49102 22
2025-10-13 21:30:43.770114 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-10-13 21:30:43.770120 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-10-13 21:30:43.770125 | controller | which_declare=declare -f
2025-10-13 21:30:43.770131 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2025-10-13 21:30:43.770136 | controller | SELINUX_LEVEL_REQUESTED=
2025-10-13 21:30:43.770142 | controller | ZUUL_CHANGE=3400
2025-10-13 21:30:43.770147 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-10-13 21:30:43.770153 | controller | ZUUL_UUID=4b802516a1d94def9449e94b8ed007d6
2025-10-13 21:30:43.770158 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-10-13 21:30:43.770165 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-10-13 21:30:43.770170 | controller | }
2025-10-13 21:30:44.076699 | controller | ok: Runtime: 0:00:00.006838
2025-10-13 21:30:44.083257 | 
2025-10-13 21:30:44.083348 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-10-13 21:30:44.109098 | controller | skipping: Conditional result was False
2025-10-13 21:30:44.115222 | 
2025-10-13 21:30:44.115290 | TASK [Symlink /home/zuul-worker/workspace]
2025-10-13 21:30:44.642552 | controller | skipping: Conditional result was False
2025-10-13 21:30:44.649471 | 
2025-10-13 21:30:44.649546 | TASK [Ensure legacy workspace directory]
2025-10-13 21:30:44.842336 | controller | changed
2025-10-13 21:30:44.861548 | 
2025-10-13 21:30:44.861610 | PLAY RECAP
2025-10-13 21:30:44.861655 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-10-13 21:30:44.861678 | 
2025-10-13 21:30:44.963989 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-10-13 21:30:44.965116 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/crc/podman.yaml@master]
2025-10-13 21:30:45.510113 | 
2025-10-13 21:30:45.510255 | PLAY [all,!compute]
2025-10-13 21:30:45.529862 | 
2025-10-13 21:30:45.529960 | TASK [Enable container-tools rhel-modules for c8/crc node]
2025-10-13 21:30:46.057563 | controller | skipping: Conditional result was False
2025-10-13 21:30:46.063703 | 
2025-10-13 21:30:46.063787 | TASK [Install podman]
2025-10-13 21:31:35.233117 | controller | changed
2025-10-13 21:31:35.238288 | 
2025-10-13 21:31:35.238350 | TASK [Set var name for quay login zuul secret]
2025-10-13 21:31:35.283781 | controller | skipping: Conditional result was False
2025-10-13 21:31:35.288863 | 
2025-10-13 21:31:35.288951 | TASK [Print the username]
2025-10-13 21:31:35.617995 | controller | openstack-k8s-operators+cirobot
2025-10-13 21:31:35.814786 | controller | ok: Runtime: 0:00:00.003986
2025-10-13 21:31:35.820346 | 
2025-10-13 21:31:35.820420 | TASK [Fail when user or password is not set]
2025-10-13 21:31:35.865651 | controller | skipping: Conditional result was False
2025-10-13 21:31:35.870994 | 
2025-10-13 21:31:35.871059 | TASK [Set vars for quay login]
2025-10-13 21:31:35.920248 | controller | Output suppressed because no_log was given
2025-10-13 21:31:35.924600 | 
2025-10-13 21:31:35.924663 | LOOP [Perform container registry login(s) with podman]
2025-10-13 21:31:36.884169 | controller | Output suppressed because no_log was given
2025-10-13 21:31:36.889730 | 
2025-10-13 21:31:36.889801 | LOOP [Perform container registry login(s) with buildah]
2025-10-13 21:31:37.899784 | controller | Output suppressed because no_log was given
2025-10-13 21:31:37.904847 | 
2025-10-13 21:31:37.904939 | TASK [Set Insecure registry for content provider]
2025-10-13 21:31:37.951209 | controller | skipping: Conditional result was False
2025-10-13 21:31:37.970881 | 
2025-10-13 21:31:37.970959 | PLAY RECAP
2025-10-13 21:31:37.971005 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0
2025-10-13 21:31:37.971028 | 
2025-10-13 21:31:38.064817 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/crc/podman.yaml@master]
2025-10-13 21:31:38.066086 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-10-13 21:31:38.610685 | 
2025-10-13 21:31:38.610787 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2025-10-13 21:31:38.630437 | 
2025-10-13 21:31:38.630506 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2025-10-13 21:31:38.648788 | controller | ok
2025-10-13 21:31:38.665067 | 
2025-10-13 21:31:38.665147 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-10-13 21:31:38.689066 | controller | skipping: Conditional result was False
2025-10-13 21:31:38.694495 | 
2025-10-13 21:31:38.694565 | TASK [mirror-info-fork : Create /etc/ci]
2025-10-13 21:31:39.017960 | controller | ok
2025-10-13 21:31:39.023551 | 
2025-10-13 21:31:39.023627 | TASK [mirror-info-fork : Install ci_mirror script]
2025-10-13 21:31:39.642248 | controller | ok
2025-10-13 21:31:39.652616 | 
2025-10-13 21:31:39.652687 | TASK [Prepare workspace]
2025-10-13 21:31:39.675863 | controller | ok
2025-10-13 21:31:39.692307 | 
2025-10-13 21:31:39.692375 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-10-13 21:31:39.985998 | controller | ok
2025-10-13 21:31:39.993089 | 
2025-10-13 21:31:39.993160 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-10-13 21:31:41.819285 | controller | Output suppressed because no_log was given
2025-10-13 21:31:41.828762 | 
2025-10-13 21:31:41.828829 | LOOP [Create zuul-output directory]
2025-10-13 21:31:42.020546 | controller | changed: "/home/zuul/ci-framework-data/logs"
2025-10-13 21:31:42.178295 | controller | ok: "/home/zuul/zuul-output/logs"
2025-10-13 21:31:42.185009 | 
2025-10-13 21:31:42.185122 | TASK [Install required packages]
2025-10-13 21:31:42.976638 | controller | ok: Nothing to do
2025-10-13 21:31:42.981494 | 
2025-10-13 21:31:42.981558 | TASK [Install venv]
2025-10-13 21:32:47.062422 | controller | changed
2025-10-13 21:32:47.088206 | 
2025-10-13 21:32:47.088288 | PLAY RECAP
2025-10-13 21:32:47.088344 | controller | ok: 7 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-10-13 21:32:47.088374 | 
2025-10-13 21:32:47.170251 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-10-13 21:32:47.171333 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-10-13 21:32:47.690806 | 
2025-10-13 21:32:47.690944 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-10-13 21:32:47.711255 | 
2025-10-13 21:32:47.711340 | TASK [Create zuul-output directory]
2025-10-13 21:32:48.025989 | controller | changed
2025-10-13 21:32:48.031018 | 
2025-10-13 21:32:48.031098 | TASK [Slurp Zuul inventory test]
2025-10-13 21:32:48.299749 | controller -> localhost | ok
2025-10-13 21:32:48.306761 | 
2025-10-13 21:32:48.306833 | TASK [Save zuul inventory]
2025-10-13 21:32:49.037293 | controller | changed
2025-10-13 21:32:49.042186 | 
2025-10-13 21:32:49.042254 | TASK [Save zuul vars without the change_message]
2025-10-13 21:32:49.599105 | controller | changed
2025-10-13 21:32:49.617073 | 
2025-10-13 21:32:49.617142 | PLAY RECAP
2025-10-13 21:32:49.617193 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-13 21:32:49.617215 | 
2025-10-13 21:32:49.696517 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-10-13 21:32:49.697557 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/build_push_container_runner.yml@main]
2025-10-13 21:32:50.223623 | 
2025-10-13 21:32:50.223727 | PLAY [Run build_push_container role]
2025-10-13 21:32:50.244333 | 
2025-10-13 21:32:50.244409 | TASK [Run build_push_container role]
2025-10-13 21:32:50.974815 | controller |
2025-10-13 21:32:51.759273 | controller | PLAY [Run build_push_container role] *******************************************
2025-10-13 21:32:51.759339 | controller |
2025-10-13 21:32:51.759347 | controller | TASK [Gathering Facts ] ********************************************************
2025-10-13 21:32:51.759354 | controller | Monday 13 October 2025  21:32:50 +0000 (0:00:00.043)       0:00:00.043 ********
2025-10-13 21:32:51.759364 | controller | ok: [localhost]
2025-10-13 21:32:51.794738 | controller |
2025-10-13 21:32:51.794782 | controller | TASK [Run build_push_container role name=build_push_container] *****************
2025-10-13 21:32:51.794796 | controller | Monday 13 October 2025  21:32:51 +0000 (0:00:00.784)       0:00:00.828 ********
2025-10-13 21:32:51.794808 | controller |
2025-10-13 21:32:52.193320 | controller | TASK [build_push_container : Ensure directories are present path={{ item }}, state=directory, mode=0755, recurse=True] ***
2025-10-13 21:32:52.193355 | controller | Monday 13 October 2025  21:32:51 +0000 (0:00:00.035)       0:00:00.864 ********
2025-10-13 21:32:52.193370 | controller | changed: [localhost] => (item=/home/zuul/ci-framework-data/artifacts/build_push_container/scripts)
2025-10-13 21:32:52.948107 | controller | changed: [localhost] => (item=/home/zuul/ci-framework-data/artifacts/build_push_container/logs)
2025-10-13 21:32:52.948140 | controller |
2025-10-13 21:32:52.948148 | controller | TASK [podman : Ensure podman is installed name={{ cifmw_podman_packages }}, state=present] ***
2025-10-13 21:32:52.948154 | controller | Monday 13 October 2025  21:32:52 +0000 (0:00:00.398)       0:00:01.262 ********
2025-10-13 21:32:52.948166 | controller | ok: [localhost]
2025-10-13 21:32:53.230350 | controller |
2025-10-13 21:32:53.230382 | controller | TASK [podman : Enable loginctl linger for ansible_user_id _raw_params=loginctl enable-linger {{ cifmw_podman_user_linger }}] ***
2025-10-13 21:32:53.230391 | controller | Monday 13 October 2025  21:32:52 +0000 (0:00:00.754)       0:00:02.017 ********
2025-10-13 21:32:53.230402 | controller | changed: [localhost]
2025-10-13 21:32:53.252553 | controller |
2025-10-13 21:32:53.252578 | 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 21:32:53.252587 | controller | Monday 13 October 2025  21:32:53 +0000 (0:00:00.282)       0:00:02.299 ********
2025-10-13 21:32:53.252609 | controller | skipping: [localhost]
2025-10-13 21:32:53.271139 | controller |
2025-10-13 21:32:53.271160 | 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 21:32:53.271167 | controller | Monday 13 October 2025  21:32:53 +0000 (0:00:00.022)       0:00:02.321 ********
2025-10-13 21:32:53.271176 | controller | skipping: [localhost]
2025-10-13 21:32:53.295832 | controller |
2025-10-13 21:32:53.295861 | controller | TASK [podman : Run podman system migrate if subuid/subgid files were changed _raw_params=podman system migrate] ***
2025-10-13 21:32:53.295874 | controller | Monday 13 October 2025  21:32:53 +0000 (0:00:00.018)       0:00:02.340 ********
2025-10-13 21:32:53.295886 | controller | skipping: [localhost]
2025-10-13 21:33:00.200285 | controller |
2025-10-13 21:33:00.200327 | 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 21:33:00.200339 | controller | Monday 13 October 2025  21:32:53 +0000 (0:00:00.024)       0:00:02.365 ********
2025-10-13 21:33:00.200351 | 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 21:33:00.408203 | controller | changed: [localhost]
2025-10-13 21:33:00.408236 | controller |
2025-10-13 21:33:00.408245 | controller | TASK [build_push_container : Ensure manifest doesn't exist _raw_params=podman manifest rm {{ _container_manifest_name }}] ***
2025-10-13 21:33:00.408251 | controller | Monday 13 October 2025  21:33:00 +0000 (0:00:06.904)       0:00:09.269 ********
2025-10-13 21:33:00.408263 | controller | ok: [localhost]
2025-10-13 21:33:00.626140 | controller |
2025-10-13 21:33:00.626175 | controller | TASK [build_push_container : Create manifest _raw_params=podman manifest create {{ _container_manifest_name }}] ***
2025-10-13 21:33:00.626192 | controller | Monday 13 October 2025  21:33:00 +0000 (0:00:00.208)       0:00:09.477 ********
2025-10-13 21:33:00.626204 | controller | changed: [localhost]
2025-10-13 21:47:49.218203 | controller |
2025-10-13 21:47:49.218280 | 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 21:47:49.218293 | controller | Monday 13 October 2025  21:33:00 +0000 (0:00:00.217)       0:00:09.695 ********
2025-10-13 21:47:49.218305 | controller | Follow script's output here: /home/zuul/ci-framework-data/artifacts/build_push_container/logs/ci_script_001_build.log
2025-10-13 21:48:20.003718 | controller | changed: [localhost]
2025-10-13 21:48:20.003789 | controller |
2025-10-13 21:48:20.003802 | 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 21:48:20.003812 | controller | Monday 13 October 2025  21:47:49 +0000 (0:14:48.592)       0:14:58.287 ********
2025-10-13 21:48:20.003823 | controller | Follow script's output here: /home/zuul/ci-framework-data/artifacts/build_push_container/logs/ci_script_002_push.log
2025-10-13 21:48:20.057353 | controller | changed: [localhost] => (item=1a213a18fb464a8a107c72feb9213fc204a6b545)
2025-10-13 21:48:20.057376 | controller | Follow script's output here: /home/zuul/ci-framework-data/artifacts/build_push_container/logs/ci_script_003_push.log
2025-10-13 21:48:20.057385 | controller | changed: [localhost] => (item=3400-latest)
2025-10-13 21:48:20.057391 | controller |
2025-10-13 21:48:20.057397 | 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 21:48:20.057403 | controller | Monday 13 October 2025  21:48:20 +0000 (0:00:30.785)       0:15:29.072 ********
2025-10-13 21:48:20.057412 | controller | skipping: [localhost] => (item=1a213a18fb464a8a107c72feb9213fc204a6b545)
2025-10-13 21:48:20.120868 | controller | skipping: [localhost] => (item=3400-latest)
2025-10-13 21:48:20.120949 | controller | skipping: [localhost]
2025-10-13 21:48:20.120960 | controller |
2025-10-13 21:48:20.120968 | controller | PLAY RECAP *********************************************************************
2025-10-13 21:48:20.120975 | controller | localhost                  : ok=9    changed=6    unreachable=0    failed=0    skipped=4    rescued=0    ignored=0
2025-10-13 21:48:20.120982 | controller |
2025-10-13 21:48:20.120989 | controller | Monday 13 October 2025  21:48:20 +0000 (0:00:00.053)       0:15:29.126 ********
2025-10-13 21:48:20.120995 | controller | ===============================================================================
2025-10-13 21:48:20.121005 | controller | build_push_container : Build container -------------------------------- 888.59s
2025-10-13 21:48:20.121012 | controller | build_push_container : Push manifest ----------------------------------- 30.79s
2025-10-13 21:48:20.121018 | controller | build_push_container : Install qemu-user-static for multi arch builds --- 6.90s
2025-10-13 21:48:20.121025 | controller | Gathering Facts --------------------------------------------------------- 0.78s
2025-10-13 21:48:20.121031 | controller | podman : Ensure podman is installed ------------------------------------- 0.75s
2025-10-13 21:48:20.121181 | controller | build_push_container : Ensure directories are present ------------------- 0.40s
2025-10-13 21:48:20.121191 | controller | podman : Enable loginctl linger for ansible_user_id --------------------- 0.28s
2025-10-13 21:48:20.121198 | controller | build_push_container : Create manifest ---------------------------------- 0.22s
2025-10-13 21:48:20.121205 | controller | build_push_container : Ensure manifest doesn't exist -------------------- 0.21s
2025-10-13 21:48:20.121211 | controller | build_push_container : Push container ----------------------------------- 0.05s
2025-10-13 21:48:20.121218 | controller | Run build_push_container role ------------------------------------------- 0.04s
2025-10-13 21:48:20.121224 | controller | podman : Run podman system migrate if subuid/subgid files were changed --- 0.02s
2025-10-13 21:48:20.121231 | controller | podman : Ensure subordinate UID entry exists for zuul ------------------- 0.02s
2025-10-13 21:48:20.121249 | controller | podman : Ensure subordinate GID entry exists for zuul ------------------- 0.02s
2025-10-13 21:48:20.249303 | controller | changed
2025-10-13 21:48:20.267448 | 
2025-10-13 21:48:20.267503 | PLAY RECAP
2025-10-13 21:48:20.267546 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-13 21:48:20.267573 | 
2025-10-13 21:48:20.325885 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/build_push_container_runner.yml@main]
2025-10-13 21:48:20.326923 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-10-13 21:48:20.838903 | 
2025-10-13 21:48:20.839016 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-10-13 21:48:20.858897 | 
2025-10-13 21:48:20.858997 | TASK [Filter out host if needed]
2025-10-13 21:48:20.867267 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-10-13 21:48:20.871598 | 
2025-10-13 21:48:20.871664 | TASK [Ensure file is present]
2025-10-13 21:48:21.205580 | controller | ok
2025-10-13 21:48:21.210941 | 
2025-10-13 21:48:21.211022 | TASK [Manage molecule report file]
2025-10-13 21:48:21.736155 | controller | skipping: Conditional result was False
2025-10-13 21:48:21.741505 | 
2025-10-13 21:48:21.741573 | TASK [Check if we get ci-framework-data basedir]
2025-10-13 21:48:21.952873 | controller | ok
2025-10-13 21:48:21.957924 | 
2025-10-13 21:48:21.957990 | TASK [Create ci-framework-data log directory for zuul]
2025-10-13 21:48:22.260221 | controller | changed
2025-10-13 21:48:22.265207 | 
2025-10-13 21:48:22.265276 | TASK [Copy ci-framework interesting files]
2025-10-13 21:48:22.563172 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2025-10-13 21:48:22.790959 | controller | changed
2025-10-13 21:48:22.795802 | 
2025-10-13 21:48:22.795866 | TASK [Get SELinux listing]
2025-10-13 21:48:23.319614 | controller | changed
2025-10-13 21:48:23.324758 | 
2025-10-13 21:48:23.324820 | TASK [Generate log index]
2025-10-13 21:48:23.970218 | controller | changed
2025-10-13 21:48:23.974872 | 
2025-10-13 21:48:23.974948 | TASK [Get some env related data]
2025-10-13 21:48:24.552634 | controller | /home/zuul/.local/bin/ansible
2025-10-13 21:48:25.503761 | controller | changed
2025-10-13 21:48:25.508896 | 
2025-10-13 21:48:25.508978 | TASK [Generate list of logs to collect in home directory]
2025-10-13 21:48:25.800990 | controller | ok: All paths examined
2025-10-13 21:48:25.805894 | 
2025-10-13 21:48:25.805970 | LOOP [Copy logs from home directory]
2025-10-13 21:48:26.120612 | controller | changed:
2025-10-13 21:48:26.120753 | controller | {
2025-10-13 21:48:26.120781 | controller |   "atime": 1760391149.715012,
2025-10-13 21:48:26.120801 | controller |   "ctime": 1760392100.0559623,
2025-10-13 21:48:26.120817 | controller |   "dev": 64513,
2025-10-13 21:48:26.120832 | controller |   "gid": 1000,
2025-10-13 21:48:26.120847 | controller |   "gr_name": "zuul",
2025-10-13 21:48:26.120863 | controller |   "inode": 4488183,
2025-10-13 21:48:26.120877 | controller |   "isblk": false,
2025-10-13 21:48:26.120891 | controller |   "ischr": false,
2025-10-13 21:48:26.120905 | controller |   "isdir": false,
2025-10-13 21:48:26.120942 | controller |   "isfifo": false,
2025-10-13 21:48:26.120960 | controller |   "isgid": false,
2025-10-13 21:48:26.120975 | controller |   "islnk": false,
2025-10-13 21:48:26.120991 | controller |   "isreg": true,
2025-10-13 21:48:26.121005 | controller |   "issock": false,
2025-10-13 21:48:26.121019 | controller |   "isuid": false,
2025-10-13 21:48:26.121033 | controller |   "mode": "0644",
2025-10-13 21:48:26.121047 | controller |   "mtime": 1760392100.0559623,
2025-10-13 21:48:26.121067 | controller |   "nlink": 1,
2025-10-13 21:48:26.121082 | controller |   "path": "/home/zuul/ansible.log",
2025-10-13 21:48:26.121098 | controller |   "pw_name": "zuul",
2025-10-13 21:48:26.121112 | controller |   "rgrp": true,
2025-10-13 21:48:26.121125 | controller |   "roth": true,
2025-10-13 21:48:26.121139 | controller |   "rusr": true,
2025-10-13 21:48:26.121152 | controller |   "size": 16093,
2025-10-13 21:48:26.121166 | controller |   "uid": 1000,
2025-10-13 21:48:26.121179 | controller |   "wgrp": false,
2025-10-13 21:48:26.121204 | controller |   "woth": false,
2025-10-13 21:48:26.121222 | controller |   "wusr": true,
2025-10-13 21:48:26.121237 | controller |   "xgrp": false,
2025-10-13 21:48:26.121251 | controller |   "xoth": false,
2025-10-13 21:48:26.121265 | controller |   "xusr": false
2025-10-13 21:48:26.121278 | controller | }
2025-10-13 21:48:26.147029 | 
2025-10-13 21:48:26.147104 | TASK [Copy crio stats log file]
2025-10-13 21:48:26.160589 | controller | skipping: Conditional result was False
2025-10-13 21:48:26.167089 | 
2025-10-13 21:48:26.167153 | TASK [Get SELinux related data]
2025-10-13 21:48:26.386818 | controller | <no matches>
2025-10-13 21:48:26.693027 | controller | ERROR
2025-10-13 21:48:26.693166 | controller | {
2025-10-13 21:48:26.693208 | controller |   "delta": "0:00:00.008823",
2025-10-13 21:48:26.693229 | controller |   "end": "2025-10-13 21:48:26.387685",
2025-10-13 21:48:26.693248 | controller |   "msg": "non-zero return code",
2025-10-13 21:48:26.693264 | controller |   "rc": 1,
2025-10-13 21:48:26.693281 | controller |   "start": "2025-10-13 21:48:26.378862"
2025-10-13 21:48:26.693296 | controller | }
2025-10-13 21:48:26.693315 | controller | ERROR: Ignoring Errors
2025-10-13 21:48:26.697742 | 
2025-10-13 21:48:26.697806 | TASK [Create system configuration directory]
2025-10-13 21:48:26.898563 | controller | changed
2025-10-13 21:48:26.903256 | 
2025-10-13 21:48:26.903321 | TASK [Get some of the system configurations]
2025-10-13 21:48:27.427098 | controller | changed
2025-10-13 21:48:27.432190 | 
2025-10-13 21:48:27.432267 | TASK [Copy generated documentation if available]
2025-10-13 21:48:27.445773 | controller | skipping: Conditional result was False
2025-10-13 21:48:27.451187 | 
2025-10-13 21:48:27.451258 | TASK [Copy generated AsciiDoc documentation if available]
2025-10-13 21:48:27.464638 | controller | skipping: Conditional result was False
2025-10-13 21:48:27.469841 | 
2025-10-13 21:48:27.469902 | TASK [Compress logs bigger than 2MB]
2025-10-13 21:48:27.996111 | controller | changed
2025-10-13 21:48:28.001103 | 
2025-10-13 21:48:28.001168 | TASK [Copy files from workspace on node]
2025-10-13 21:48:28.019632 | controller | ok
2025-10-13 21:48:28.040208 | 
2025-10-13 21:48:28.040271 | TASK [fetch-output : Set log path for multiple nodes]
2025-10-13 21:48:28.053037 | controller | skipping: Conditional result was False
2025-10-13 21:48:28.058223 | 
2025-10-13 21:48:28.058289 | TASK [fetch-output : Set log path for single node]
2025-10-13 21:48:28.086595 | controller | ok
2025-10-13 21:48:28.091443 | 
2025-10-13 21:48:28.091508 | LOOP [fetch-output : Ensure local output dirs]
2025-10-13 21:48:28.282773 | controller -> localhost | ok: "/var/lib/zuul/builds/4b802516a1d94def9449e94b8ed007d6/work/logs"
2025-10-13 21:48:28.282978 | controller -> localhost | changed: All items complete
2025-10-13 21:48:28.283010 | 
2025-10-13 21:48:28.457173 | controller -> localhost | changed: "/var/lib/zuul/builds/4b802516a1d94def9449e94b8ed007d6/work/artifacts"
2025-10-13 21:48:28.634203 | controller -> localhost | changed: "/var/lib/zuul/builds/4b802516a1d94def9449e94b8ed007d6/work/docs"
2025-10-13 21:48:28.649386 | 
2025-10-13 21:48:28.649484 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-10-13 21:48:29.212670 | controller | changed:
2025-10-13 21:48:29.212850 | controller | .d..t...... ./
2025-10-13 21:48:29.212880 | controller | >f+++++++++ README.html
2025-10-13 21:48:29.212903 | controller | >f+++++++++ ansible.log
2025-10-13 21:48:29.212973 | controller | >f+++++++++ dmesg.log
2025-10-13 21:48:29.212999 | controller | >f+++++++++ installed-pkgs.log
2025-10-13 21:48:29.213019 | controller | >f+++++++++ python.log
2025-10-13 21:48:29.213039 | controller | >f+++++++++ registries.conf
2025-10-13 21:48:29.213059 | controller | >f+++++++++ selinux-denials.log
2025-10-13 21:48:29.213076 | controller | >f+++++++++ selinux-listing.log
2025-10-13 21:48:29.213094 | controller | cd+++++++++ ci-framework-data/
2025-10-13 21:48:29.213112 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-10-13 21:48:29.213129 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-10-13 21:48:29.213146 | controller | cd+++++++++ ci-framework-data/artifacts/build_push_container/
2025-10-13 21:48:29.213162 | controller | cd+++++++++ ci-framework-data/artifacts/build_push_container/logs/
2025-10-13 21:48:29.213179 | controller | >f+++++++++ ci-framework-data/artifacts/build_push_container/logs/ci_script_000_install_qemu_user_static_for.log
2025-10-13 21:48:29.213204 | controller | >f+++++++++ ci-framework-data/artifacts/build_push_container/logs/ci_script_001_build.log
2025-10-13 21:48:29.213222 | controller | >f+++++++++ ci-framework-data/artifacts/build_push_container/logs/ci_script_002_push.log
2025-10-13 21:48:29.213238 | controller | >f+++++++++ ci-framework-data/artifacts/build_push_container/logs/ci_script_003_push.log
2025-10-13 21:48:29.213257 | controller | cd+++++++++ ci-framework-data/artifacts/build_push_container/scripts/
2025-10-13 21:48:29.213271 | controller | >f+++++++++ ci-framework-data/artifacts/build_push_container/scripts/ci_script_000_install_qemu_user_static_for.sh
2025-10-13 21:48:29.213285 | controller | >f+++++++++ ci-framework-data/artifacts/build_push_container/scripts/ci_script_001_build.sh
2025-10-13 21:48:29.213299 | controller | >f+++++++++ ci-framework-data/artifacts/build_push_container/scripts/ci_script_002_push.sh
2025-10-13 21:48:29.213313 | controller | >f+++++++++ ci-framework-data/artifacts/build_push_container/scripts/ci_script_003_push.sh
2025-10-13 21:48:29.213329 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-10-13 21:48:29.213346 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-10-13 21:48:29.213361 | controller | cd+++++++++ ci-framework-data/logs/
2025-10-13 21:48:29.213377 | controller | cd+++++++++ registries.conf.d/
2025-10-13 21:48:29.213393 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-10-13 21:48:29.213408 | controller | cd+++++++++ system-config/
2025-10-13 21:48:29.213423 | controller | cd+++++++++ system-config/libvirt/
2025-10-13 21:48:29.213439 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-10-13 21:48:29.213454 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-10-13 21:48:29.579660 | controller | changed: .d..t...... ./
2025-10-13 21:48:29.935972 | controller | changed: .d..t...... ./
2025-10-13 21:48:29.949004 | 
2025-10-13 21:48:29.949101 | TASK [Return artifact to Zuul]
2025-10-13 21:48:29.962235 | controller | skipping: Conditional result was False
2025-10-13 21:48:29.981074 | 
2025-10-13 21:48:29.981139 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-10-13 21:48:29.981249 | 
2025-10-13 21:48:29.981276 | PLAY RECAP
2025-10-13 21:48:29.981319 | controller | ok: 16 changed: 12 unreachable: 0 failed: 0 skipped: 6 rescued: 0 ignored: 1
2025-10-13 21:48:29.981339 | 
2025-10-13 21:48:30.050731 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-10-13 21:48:30.051793 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-10-13 21:48:30.554005 | 
2025-10-13 21:48:30.554126 | PLAY [all]
2025-10-13 21:48:30.572286 | 
2025-10-13 21:48:30.572360 | TASK [include_role : fetch-output]
2025-10-13 21:48:30.601273 | controller | ok
2025-10-13 21:48:30.616669 | 
2025-10-13 21:48:30.616756 | TASK [fetch-output : Set log path for multiple nodes]
2025-10-13 21:48:30.650109 | controller | skipping: Conditional result was False
2025-10-13 21:48:30.655666 | 
2025-10-13 21:48:30.655742 | TASK [fetch-output : Set log path for single node]
2025-10-13 21:48:30.683464 | controller | ok
2025-10-13 21:48:30.688258 | 
2025-10-13 21:48:30.688327 | LOOP [fetch-output : Ensure local output dirs]
2025-10-13 21:48:30.996424 | controller -> localhost | ok: "/var/lib/zuul/builds/4b802516a1d94def9449e94b8ed007d6/work/logs"
2025-10-13 21:48:31.175317 | controller -> localhost | ok: "/var/lib/zuul/builds/4b802516a1d94def9449e94b8ed007d6/work/artifacts"
2025-10-13 21:48:31.355722 | controller -> localhost | ok: "/var/lib/zuul/builds/4b802516a1d94def9449e94b8ed007d6/work/docs"
2025-10-13 21:48:31.365701 | 
2025-10-13 21:48:31.365797 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-10-13 21:48:31.861790 | controller | ok
2025-10-13 21:48:31.861997 | controller | ok: All items complete
2025-10-13 21:48:31.862032 | 
2025-10-13 21:48:32.216831 | controller | ok
2025-10-13 21:48:32.565018 | controller | ok
2025-10-13 21:48:32.576000 | 
2025-10-13 21:48:32.576065 | TASK [include_role : fetch-output-openshift]
2025-10-13 21:48:32.589545 | controller | skipping: Conditional result was False
2025-10-13 21:48:32.594926 | 
2025-10-13 21:48:32.594997 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-10-13 21:48:32.918363 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.006125
2025-10-13 21:48:33.103375 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.005904
2025-10-13 21:48:33.127956 | 
2025-10-13 21:48:33.128019 | PLAY [all]
2025-10-13 21:48:33.141231 | 
2025-10-13 21:48:33.141297 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-10-13 21:48:33.543424 | controller | changed
2025-10-13 21:48:33.564942 | 
2025-10-13 21:48:33.564994 | PLAY RECAP
2025-10-13 21:48:33.565034 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-10-13 21:48:33.565055 | 
2025-10-13 21:48:33.631547 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-10-13 21:48:33.632540 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-10-13 21:48:34.166845 | 
2025-10-13 21:48:34.166965 | PLAY [localhost]
2025-10-13 21:48:34.183973 | 
2025-10-13 21:48:34.184048 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-10-13 21:48:34.474559 | localhost | changed
2025-10-13 21:48:34.478959 | 
2025-10-13 21:48:34.479037 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-10-13 21:48:34.496300 | localhost | ok
2025-10-13 21:48:34.503787 | 
2025-10-13 21:48:34.503853 | TASK [add-fileserver : Create SSH private key tempfile]
2025-10-13 21:48:34.786349 | localhost | changed
2025-10-13 21:48:34.790713 | 
2025-10-13 21:48:34.790780 | TASK [add-fileserver : Create SSH private key from secret]
2025-10-13 21:48:35.314471 | localhost | changed
2025-10-13 21:48:35.319368 | 
2025-10-13 21:48:35.319451 | TASK [add-fileserver : Add fileserver ssh key]
2025-10-13 21:48:35.641147 | localhost | Identity added: /var/lib/zuul/builds/4b802516a1d94def9449e94b8ed007d6/work/tmp/ansible.pdootsin (/var/lib/zuul/builds/4b802516a1d94def9449e94b8ed007d6/work/tmp/ansible.pdootsin)
2025-10-13 21:48:35.641338 | localhost | ok: Runtime: 0:00:00.006248
2025-10-13 21:48:35.645462 | 
2025-10-13 21:48:35.645526 | TASK [add-fileserver : Remove SSH private key from disk]
2025-10-13 21:48:35.854779 | localhost | ok: Runtime: 0:00:00.004132
2025-10-13 21:48:35.859418 | 
2025-10-13 21:48:35.859496 | TASK [add-fileserver : Add fileserver to inventory]
2025-10-13 21:48:35.907454 | localhost | changed
2025-10-13 21:48:35.911511 | 
2025-10-13 21:48:35.911579 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-10-13 21:48:36.211562 | localhost | changed
2025-10-13 21:48:36.230673 | 
2025-10-13 21:48:36.230743 | PLAY [localhost]
2025-10-13 21:48:36.241671 | 
2025-10-13 21:48:36.241732 | TASK [Generate bulk log download script]
2025-10-13 21:48:36.258892 | localhost | ok
2025-10-13 21:48:36.269797 | 
2025-10-13 21:48:36.269863 | TASK [local-log-download : Check API endpoint is defined]
2025-10-13 21:48:36.296754 | localhost | ok: All assertions passed
2025-10-13 21:48:36.301002 | 
2025-10-13 21:48:36.301070 | TASK [local-log-download : Create download script]
2025-10-13 21:48:36.637707 | localhost -> localhost | changed
2025-10-13 21:48:36.646465 | 
2025-10-13 21:48:36.646544 | TASK [Register quick-download link]
2025-10-13 21:48:36.662721 | localhost | ok
2025-10-13 21:48:36.696178 | 
2025-10-13 21:48:36.696254 | PLAY [logserver.rdoproject.org]
2025-10-13 21:48:36.705266 | 
2025-10-13 21:48:36.705323 | TASK [Set zuul-log-path fact]
2025-10-13 21:48:36.720270 | logserver.rdoproject.org | ok
2025-10-13 21:48:36.728708 | 
2025-10-13 21:48:36.728775 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-13 21:48:36.744050 | logserver.rdoproject.org | ok
2025-10-13 21:48:36.749167 | 
2025-10-13 21:48:36.749242 | TASK [upload-logs : Create log directories]
2025-10-13 21:48:39.075109 | logserver.rdoproject.org | changed
2025-10-13 21:48:39.078133 | 
2025-10-13 21:48:39.078223 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-10-13 21:48:39.284011 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.003620
2025-10-13 21:48:39.288108 | 
2025-10-13 21:48:39.288172 | TASK [upload-logs : Upload logs to log server]
2025-10-13 21:48:40.793328 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-10-13 21:48:40.796069 | 
2025-10-13 21:48:40.796132 | LOOP [upload-logs : Compress console log and json output]
2025-10-13 21:48:40.831271 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-13 21:48:40.839709 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-13 21:48:40.852570 | 
2025-10-13 21:48:40.852636 | LOOP [upload-logs : Upload compressed console log and json output]
2025-10-13 21:48:40.884494 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-13 21:48:40.884688 | 
2025-10-13 21:48:40.887957 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-13 21:48:40.898829 | 
2025-10-13 21:48:40.898896 | LOOP [upload-logs : Upload console log and json output]