2025-11-27 23:30:30.286524 | Job console starting...
2025-11-27 23:30:30.300289 | Updating repositories
2025-11-27 23:30:30.336424 | Preparing job workspace
2025-11-27 23:30:35.992066 | Running Ansible setup...
2025-11-27 23:30:39.908520 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-11-27 23:30:40.503236 | 
2025-11-27 23:30:40.503388 | PLAY [localhost]
2025-11-27 23:30:40.512506 | 
2025-11-27 23:30:40.512598 | TASK [Gathering Facts]
2025-11-27 23:30:41.594075 | localhost | ok
2025-11-27 23:30:41.606927 | 
2025-11-27 23:30:41.607040 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-11-27 23:30:42.062933 | localhost -> localhost | changed
2025-11-27 23:30:42.075468 | 
2025-11-27 23:30:42.075622 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-11-27 23:30:42.982608 | localhost -> localhost | changed
2025-11-27 23:30:43.001644 | 
2025-11-27 23:30:43.001854 | TASK [Setup log path fact]
2025-11-27 23:30:43.027533 | localhost | ok
2025-11-27 23:30:43.054089 | 
2025-11-27 23:30:43.054246 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-11-27 23:30:43.085140 | localhost | ok
2025-11-27 23:30:43.098556 | 
2025-11-27 23:30:43.098680 | TASK [emit-job-header : Print job information]
2025-11-27 23:30:43.144394 | # Job Information
2025-11-27 23:30:43.144693 | Ansible Version: 2.15.12
2025-11-27 23:30:43.144766 | Job: build-push-container-cifmw-client
2025-11-27 23:30:43.144819 | Pipeline: github-check
2025-11-27 23:30:43.144859 | Executor: ze01.softwarefactory-project.io
2025-11-27 23:30:43.144897 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3476
2025-11-27 23:30:43.144936 | Log URL (when completed): https://logserver.rdoproject.org/b92/rdoproject.org/b92f45bdbb314ff3ad923c049aecefa5/
2025-11-27 23:30:43.144976 | Event ID: e7f4b2b0-cbe8-11f0-9629-84cbe0696fde
2025-11-27 23:30:43.154075 | 
2025-11-27 23:30:43.154214 | LOOP [emit-job-header : Print node information]
2025-11-27 23:30:43.264758 | localhost | ok:
2025-11-27 23:30:43.265246 | localhost | # Node Information
2025-11-27 23:30:43.265288 | localhost | Inventory Hostname: controller
2025-11-27 23:30:43.265317 | localhost | Hostname: np0005538303
2025-11-27 23:30:43.265339 | localhost | Username: zuul
2025-11-27 23:30:43.265363 | localhost | Distro: CentOS 9
2025-11-27 23:30:43.265388 | localhost | Provider: vexxhost-nodepool-tripleo
2025-11-27 23:30:43.265411 | localhost | Region: RegionOne
2025-11-27 23:30:43.265438 | localhost | Label: cloud-centos-9-stream-tripleo
2025-11-27 23:30:43.265463 | localhost | Product Name: OpenStack Nova
2025-11-27 23:30:43.265489 | localhost | Interface IP: 38.102.83.45
2025-11-27 23:30:43.315795 | 
2025-11-27 23:30:43.315956 | PLAY [all]
2025-11-27 23:30:43.330745 | 
2025-11-27 23:30:43.330880 | TASK [Gather network facts]
2025-11-27 23:30:43.895834 | controller | ok
2025-11-27 23:30:43.935744 | 
2025-11-27 23:30:43.935941 | TASK [include_role : start-zuul-console]
2025-11-27 23:30:43.964607 | controller | ok
2025-11-27 23:30:43.987435 | 
2025-11-27 23:30:43.987534 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-11-27 23:30:44.389941 | controller | ok
2025-11-27 23:30:44.402631 | 
2025-11-27 23:30:44.402752 | TASK [include_role : add-build-sshkey]
2025-11-27 23:30:44.434519 | controller | ok
2025-11-27 23:30:44.453670 | 
2025-11-27 23:30:44.453751 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-11-27 23:30:44.692263 | controller -> localhost | ok
2025-11-27 23:30:44.698374 | 
2025-11-27 23:30:44.698447 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-11-27 23:30:44.717999 | controller | ok
2025-11-27 23:30:44.731483 | controller | included: /var/lib/zuul/builds/b92f45bdbb314ff3ad923c049aecefa5/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-11-27 23:30:44.737518 | 
2025-11-27 23:30:44.737581 | TASK [add-build-sshkey : Create Temp SSH key]
2025-11-27 23:30:45.627899 | controller -> localhost | Generating public/private rsa key pair.
2025-11-27 23:30:45.628213 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/b92f45bdbb314ff3ad923c049aecefa5/work/b92f45bdbb314ff3ad923c049aecefa5_id_rsa.
2025-11-27 23:30:45.628270 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/b92f45bdbb314ff3ad923c049aecefa5/work/b92f45bdbb314ff3ad923c049aecefa5_id_rsa.pub.
2025-11-27 23:30:45.628309 | controller -> localhost | The key fingerprint is:
2025-11-27 23:30:45.628338 | controller -> localhost | SHA256:lyPD4vJUwhd0ihJt5gAu3k75wx4OT9W0wh9h5RoHqM0 zuul-build-sshkey
2025-11-27 23:30:45.628364 | controller -> localhost | The key's randomart image is:
2025-11-27 23:30:45.628390 | controller -> localhost | +---[RSA 3072]----+
2025-11-27 23:30:45.628414 | controller -> localhost | |  ....  o...     |
2025-11-27 23:30:45.628439 | controller -> localhost | | .  ..++ o+      |
2025-11-27 23:30:45.628464 | controller -> localhost | |. . .== o= o     |
2025-11-27 23:30:45.628489 | controller -> localhost | |... .+oE+.*.     |
2025-11-27 23:30:45.628513 | controller -> localhost | | . +  ++S=+      |
2025-11-27 23:30:45.628537 | controller -> localhost | |  o o..=o+..     |
2025-11-27 23:30:45.628560 | controller -> localhost | |   o.*o  .       |
2025-11-27 23:30:45.628583 | controller -> localhost | |    *+o          |
2025-11-27 23:30:45.628606 | controller -> localhost | |     +.          |
2025-11-27 23:30:45.628628 | controller -> localhost | +----[SHA256]-----+
2025-11-27 23:30:45.628690 | controller -> localhost | ok: Runtime: 0:00:00.464144
2025-11-27 23:30:45.637390 | 
2025-11-27 23:30:45.637605 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-11-27 23:30:45.673806 | controller | ok
2025-11-27 23:30:45.690693 | controller | included: /var/lib/zuul/builds/b92f45bdbb314ff3ad923c049aecefa5/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-11-27 23:30:45.706470 | 
2025-11-27 23:30:45.706601 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-11-27 23:30:45.737384 | controller | skipping: Conditional result was False
2025-11-27 23:30:45.745205 | 
2025-11-27 23:30:45.745304 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-11-27 23:30:46.365188 | controller | changed
2025-11-27 23:30:46.376918 | 
2025-11-27 23:30:46.377125 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-11-27 23:30:46.685506 | controller | ok
2025-11-27 23:30:46.693780 | 
2025-11-27 23:30:46.694080 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-11-27 23:30:47.788315 | controller | changed
2025-11-27 23:30:47.806605 | 
2025-11-27 23:30:47.806787 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-11-27 23:30:48.792429 | controller | changed
2025-11-27 23:30:48.804558 | 
2025-11-27 23:30:48.804748 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-11-27 23:30:48.833176 | controller | skipping: Conditional result was False
2025-11-27 23:30:48.847192 | 
2025-11-27 23:30:48.847397 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-11-27 23:30:49.284612 | controller -> localhost | changed
2025-11-27 23:30:49.310843 | 
2025-11-27 23:30:49.311101 | TASK [add-build-sshkey : Add back temp key]
2025-11-27 23:30:49.615156 | controller -> localhost | Identity added: /var/lib/zuul/builds/b92f45bdbb314ff3ad923c049aecefa5/work/b92f45bdbb314ff3ad923c049aecefa5_id_rsa (zuul-build-sshkey)
2025-11-27 23:30:49.615366 | controller -> localhost | ok: Runtime: 0:00:00.015359
2025-11-27 23:30:49.621210 | 
2025-11-27 23:30:49.621271 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-11-27 23:30:50.072153 | controller | ok
2025-11-27 23:30:50.082690 | 
2025-11-27 23:30:50.082822 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-11-27 23:30:50.120913 | controller | skipping: Conditional result was False
2025-11-27 23:30:50.143112 | 
2025-11-27 23:30:50.143270 | TASK [include_role : validate-host]
2025-11-27 23:30:50.176249 | controller | ok
2025-11-27 23:30:50.204081 | 
2025-11-27 23:30:50.204239 | TASK [validate-host : Define zuul_info_dir fact]
2025-11-27 23:30:50.261884 | controller | ok
2025-11-27 23:30:50.308302 | 
2025-11-27 23:30:50.308462 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-11-27 23:30:50.624205 | controller -> localhost | ok
2025-11-27 23:30:50.636470 | 
2025-11-27 23:30:50.636617 | TASK [validate-host : Collect information about the host]
2025-11-27 23:30:51.487136 | controller | ok
2025-11-27 23:30:51.510342 | 
2025-11-27 23:30:51.510564 | TASK [validate-host : Sanitize hostname]
2025-11-27 23:30:51.609987 | controller | ok
2025-11-27 23:30:51.621823 | 
2025-11-27 23:30:51.621966 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-11-27 23:30:52.139873 | controller -> localhost | changed
2025-11-27 23:30:52.153293 | 
2025-11-27 23:30:52.153464 | TASK [validate-host : Collect information about zuul worker]
2025-11-27 23:30:52.622716 | controller | ok
2025-11-27 23:30:52.628026 | 
2025-11-27 23:30:52.628099 | TASK [validate-host : Write out all zuul information for each host]
2025-11-27 23:30:53.082289 | controller -> localhost | changed
2025-11-27 23:30:53.104849 | 
2025-11-27 23:30:53.104993 | TASK [include_role : prepare-workspace-openshift]
2025-11-27 23:30:53.132672 | controller | skipping: Conditional result was False
2025-11-27 23:30:53.143920 | 
2025-11-27 23:30:53.144120 | TASK [include_role : remove-zuul-sshkey]
2025-11-27 23:30:53.172448 | controller | skipping: Conditional result was False
2025-11-27 23:30:53.183939 | 
2025-11-27 23:30:53.184147 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-11-27 23:30:53.486509 | controller | ok: "logs"
2025-11-27 23:30:53.486881 | controller | ok: All items complete
2025-11-27 23:30:53.486923 | 
2025-11-27 23:30:53.680957 | controller | ok: "artifacts"
2025-11-27 23:30:53.925579 | controller | ok: "docs"
2025-11-27 23:30:53.938804 | 
2025-11-27 23:30:53.938982 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-11-27 23:30:54.213863 | controller | changed: "logs"
2025-11-27 23:30:54.470476 | controller | changed: "artifacts"
2025-11-27 23:30:54.709381 | controller | changed: "docs"
2025-11-27 23:30:54.766539 | 
2025-11-27 23:30:54.766671 | PLAY RECAP
2025-11-27 23:30:54.766742 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-11-27 23:30:54.766779 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-11-27 23:30:54.766804 | 
2025-11-27 23:30:54.916846 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-11-27 23:30:54.918217 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-11-27 23:30:55.550775 | 
2025-11-27 23:30:55.550905 | PLAY [all]
2025-11-27 23:30:55.572403 | 
2025-11-27 23:30:55.572591 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-11-27 23:30:55.635885 | controller | ok
2025-11-27 23:30:55.643674 | 
2025-11-27 23:30:55.643830 | TASK [mirror-info-fork : Create /etc/ci]
2025-11-27 23:30:56.130353 | controller | changed
2025-11-27 23:30:56.149425 | 
2025-11-27 23:30:56.149731 | TASK [mirror-info-fork : Install ci_mirror script]
2025-11-27 23:30:57.524752 | controller | changed
2025-11-27 23:30:57.548686 | 
2025-11-27 23:30:57.548826 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-11-27 23:30:58.034110 | controller | changed:
2025-11-27 23:30:58.034559 | controller | {
2025-11-27 23:30:58.034616 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-11-27 23:30:58.034658 | controller | }
2025-11-27 23:30:58.343764 | controller | changed:
2025-11-27 23:30:58.344194 | controller | {
2025-11-27 23:30:58.344312 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-11-27 23:30:58.344383 | controller | }
2025-11-27 23:30:58.663773 | controller | changed:
2025-11-27 23:30:58.663933 | controller | {
2025-11-27 23:30:58.663986 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com"
2025-11-27 23:30:58.664082 | controller | }
2025-11-27 23:30:58.967392 | controller | changed:
2025-11-27 23:30:58.967513 | controller | {
2025-11-27 23:30:58.967537 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-11-27 23:30:58.967555 | controller | }
2025-11-27 23:30:59.290609 | controller | changed:
2025-11-27 23:30:59.290778 | controller | {
2025-11-27 23:30:59.290828 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-11-27 23:30:59.290869 | controller | }
2025-11-27 23:30:59.593178 | controller | changed:
2025-11-27 23:30:59.593346 | controller | {
2025-11-27 23:30:59.593408 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-11-27 23:30:59.593453 | controller | }
2025-11-27 23:30:59.886800 | controller | changed:
2025-11-27 23:30:59.886961 | controller | {
2025-11-27 23:30:59.887041 | 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-11-27 23:30:59.887094 | controller | }
2025-11-27 23:31:00.151859 | controller | changed:
2025-11-27 23:31:00.152001 | controller | {
2025-11-27 23:31:00.152076 | 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-11-27 23:31:00.152105 | controller | }
2025-11-27 23:31:00.438102 | controller | changed:
2025-11-27 23:31:00.438300 | controller | {
2025-11-27 23:31:00.438359 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-11-27 23:31:00.438406 | controller | }
2025-11-27 23:31:00.749309 | controller | changed:
2025-11-27 23:31:00.749605 | controller | {
2025-11-27 23:31:00.749669 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-11-27 23:31:00.749711 | controller | }
2025-11-27 23:31:01.051036 | controller | changed:
2025-11-27 23:31:01.051150 | controller | {
2025-11-27 23:31:01.051173 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-11-27 23:31:01.051192 | controller | }
2025-11-27 23:31:01.363111 | controller | changed:
2025-11-27 23:31:01.367502 | controller | {
2025-11-27 23:31:01.367574 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-11-27 23:31:01.367608 | controller | }
2025-11-27 23:31:01.660437 | controller | changed:
2025-11-27 23:31:01.660606 | controller | {
2025-11-27 23:31:01.660658 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-11-27 23:31:01.660701 | controller | }
2025-11-27 23:31:01.973180 | controller | changed:
2025-11-27 23:31:01.973443 | controller | {
2025-11-27 23:31:01.973504 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-11-27 23:31:01.973545 | controller | }
2025-11-27 23:31:02.245198 | controller | changed:
2025-11-27 23:31:02.245359 | controller | {
2025-11-27 23:31:02.245409 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-11-27 23:31:02.245450 | controller | }
2025-11-27 23:31:02.549181 | controller | changed:
2025-11-27 23:31:02.549300 | controller | {
2025-11-27 23:31:02.549322 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-11-27 23:31:02.549340 | controller | }
2025-11-27 23:31:02.870735 | controller | changed:
2025-11-27 23:31:02.870899 | controller | {
2025-11-27 23:31:02.870949 | 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-11-27 23:31:02.870990 | controller | }
2025-11-27 23:31:03.159933 | controller | changed:
2025-11-27 23:31:03.160099 | controller | {
2025-11-27 23:31:03.160135 | 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-11-27 23:31:03.160180 | controller | }
2025-11-27 23:31:03.497479 | controller | changed:
2025-11-27 23:31:03.497648 | controller | {
2025-11-27 23:31:03.497699 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-11-27 23:31:03.497793 | controller | }
2025-11-27 23:31:03.761499 | controller | changed:
2025-11-27 23:31:03.761616 | controller | {
2025-11-27 23:31:03.761641 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-11-27 23:31:03.761661 | controller | }
2025-11-27 23:31:04.038482 | controller | changed:
2025-11-27 23:31:04.038602 | controller | {
2025-11-27 23:31:04.038626 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-11-27 23:31:04.038645 | controller | }
2025-11-27 23:31:04.357125 | controller | changed:
2025-11-27 23:31:04.357285 | controller | {
2025-11-27 23:31:04.357336 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-11-27 23:31:04.357376 | controller | }
2025-11-27 23:31:04.668274 | controller | changed:
2025-11-27 23:31:04.668531 | controller | {
2025-11-27 23:31:04.668612 | 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-11-27 23:31:04.668637 | controller | }
2025-11-27 23:31:04.977044 | controller | changed:
2025-11-27 23:31:04.977161 | controller | {
2025-11-27 23:31:04.977185 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-11-27 23:31:04.977204 | controller | }
2025-11-27 23:31:05.297015 | controller | changed:
2025-11-27 23:31:05.297141 | controller | {
2025-11-27 23:31:05.297166 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-11-27 23:31:05.297184 | controller | }
2025-11-27 23:31:05.589114 | controller | changed:
2025-11-27 23:31:05.589257 | controller | {
2025-11-27 23:31:05.589291 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-11-27 23:31:05.589318 | controller | }
2025-11-27 23:31:05.649584 | 
2025-11-27 23:31:05.649824 | TASK [Set timezone to UTC]
2025-11-27 23:31:06.340107 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-11-27 23:31:06.351483 | 
2025-11-27 23:31:06.351624 | TASK [Create nodepool directory]
2025-11-27 23:31:06.706598 | controller | changed
2025-11-27 23:31:06.721131 | 
2025-11-27 23:31:06.721358 | TASK [Create nodepool sub_nodes file]
2025-11-27 23:31:07.626728 | controller | changed
2025-11-27 23:31:07.631837 | 
2025-11-27 23:31:07.631914 | TASK [Create nodepool sub_nodes_private file]
2025-11-27 23:31:08.554123 | controller | changed
2025-11-27 23:31:08.565416 | 
2025-11-27 23:31:08.565529 | LOOP [Populate nodepool sub_nodes file]
2025-11-27 23:31:08.609494 | 
2025-11-27 23:31:08.609684 | LOOP [Populate nodepool sub_nodes_private file]
2025-11-27 23:31:08.652022 | 
2025-11-27 23:31:08.652261 | TASK [Create nodepool primary file]
2025-11-27 23:31:08.676493 | controller | skipping: Conditional result was False
2025-11-27 23:31:08.684898 | 
2025-11-27 23:31:08.685009 | TASK [Create nodepool node_private for this node]
2025-11-27 23:31:09.712653 | controller | changed
2025-11-27 23:31:09.719110 | 
2025-11-27 23:31:09.719193 | LOOP [Copy ssh keys to nodepool directory]
2025-11-27 23:31:10.147864 | controller | ok: Item: id_rsa Runtime: 0:00:00.008844
2025-11-27 23:31:10.148235 | 
2025-11-27 23:31:10.427670 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.010785
2025-11-27 23:31:10.436289 | 
2025-11-27 23:31:10.436406 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-11-27 23:31:11.338976 | controller | changed
2025-11-27 23:31:11.347406 | 
2025-11-27 23:31:11.347495 | TASK [Validate sudoers config after edits]
2025-11-27 23:31:11.678639 | controller | /etc/sudoers: parsed OK
2025-11-27 23:31:11.678885 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-11-27 23:31:11.678907 | controller | /etc/sudoers.d/zuul: parsed OK
2025-11-27 23:31:11.678923 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-11-27 23:31:11.904040 | controller | ok: Runtime: 0:00:00.010378
2025-11-27 23:31:11.910348 | 
2025-11-27 23:31:11.910449 | TASK [Show the environment passed in to job shell scripts]
2025-11-27 23:31:12.252023 | controller | SHELL=/bin/bash
2025-11-27 23:31:12.252143 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-11-27 23:31:12.252165 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-11-27 23:31:12.252180 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/76/3476/8db13f03e84e82fb0e1947bbe5672d8fe024d5e3
2025-11-27 23:31:12.252195 | controller | PWD=/home/zuul
2025-11-27 23:31:12.252209 | controller | ZUUL_PIPELINE=github-check
2025-11-27 23:31:12.252223 | controller | LOGNAME=zuul
2025-11-27 23:31:12.252237 | controller | XDG_SESSION_TYPE=tty
2025-11-27 23:31:12.252250 | controller | _=/usr/bin/env
2025-11-27 23:31:12.252345 | controller | MOTD_SHOWN=pam
2025-11-27 23:31:12.252364 | controller | HOME=/home/zuul
2025-11-27 23:31:12.252379 | controller | LANG=en_US.UTF-8
2025-11-27 23:31:12.252424 | controller | SSH_CONNECTION=38.102.83.114 45868 38.102.83.45 22
2025-11-27 23:31:12.252448 | 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-11-27 23:31:12.252477 | controller | ZUUL_CHANGE_IDS=3476,8db13f03e84e82fb0e1947bbe5672d8fe024d5e3
2025-11-27 23:31:12.252495 | controller | WORKSPACE=/home/zuul/workspace
2025-11-27 23:31:12.252514 | controller | XDG_SESSION_CLASS=user
2025-11-27 23:31:12.252532 | controller | SELINUX_ROLE_REQUESTED=
2025-11-27 23:31:12.252547 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-11-27 23:31:12.252561 | controller | USER=zuul
2025-11-27 23:31:12.252575 | controller | ZUUL_VOTING=True
2025-11-27 23:31:12.252588 | controller | BUILD_TIMEOUT=5400000
2025-11-27 23:31:12.252602 | controller | SELINUX_USE_CURRENT_RANGE=
2025-11-27 23:31:12.252615 | controller | SHLVL=1
2025-11-27 23:31:12.252629 | controller | ZUUL_PATCHSET=8db13f03e84e82fb0e1947bbe5672d8fe024d5e3
2025-11-27 23:31:12.252642 | controller | XDG_SESSION_ID=1
2025-11-27 23:31:12.252656 | controller | ZUUL_BRANCH=main
2025-11-27 23:31:12.252669 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-11-27 23:31:12.252683 | controller | SSH_CLIENT=38.102.83.114 45868 22
2025-11-27 23:31:12.252697 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-11-27 23:31:12.252710 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-11-27 23:31:12.252724 | controller | which_declare=declare -f
2025-11-27 23:31:12.252738 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2025-11-27 23:31:12.252752 | controller | SELINUX_LEVEL_REQUESTED=
2025-11-27 23:31:12.252766 | controller | ZUUL_CHANGE=3476
2025-11-27 23:31:12.252779 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-11-27 23:31:12.252792 | controller | ZUUL_UUID=b92f45bdbb314ff3ad923c049aecefa5
2025-11-27 23:31:12.252810 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-11-27 23:31:12.252825 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-11-27 23:31:12.252838 | controller | }
2025-11-27 23:31:12.445649 | controller | ok: Runtime: 0:00:00.012231
2025-11-27 23:31:12.451737 | 
2025-11-27 23:31:12.451802 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-11-27 23:31:12.476352 | controller | skipping: Conditional result was False
2025-11-27 23:31:12.485426 | 
2025-11-27 23:31:12.485517 | TASK [Symlink /home/zuul-worker/workspace]
2025-11-27 23:31:13.081476 | controller | skipping: Conditional result was False
2025-11-27 23:31:13.093858 | 
2025-11-27 23:31:13.094023 | TASK [Ensure legacy workspace directory]
2025-11-27 23:31:13.410766 | controller | changed
2025-11-27 23:31:13.433264 | 
2025-11-27 23:31:13.433315 | PLAY RECAP
2025-11-27 23:31:13.433355 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-11-27 23:31:13.433375 | 
2025-11-27 23:31:13.534219 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-11-27 23:31:13.535716 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/crc/podman.yaml@master]
2025-11-27 23:31:14.167740 | 
2025-11-27 23:31:14.167868 | PLAY [all,!compute]
2025-11-27 23:31:14.188193 | 
2025-11-27 23:31:14.188285 | TASK [Enable container-tools rhel-modules for c8/crc node]
2025-11-27 23:31:14.719089 | controller | skipping: Conditional result was False
2025-11-27 23:31:14.725905 | 
2025-11-27 23:31:14.726012 | TASK [Install podman]
2025-11-27 23:32:17.179379 | controller | changed
2025-11-27 23:32:17.186746 | 
2025-11-27 23:32:17.186896 | TASK [Set var name for quay login zuul secret]
2025-11-27 23:32:17.234570 | controller | skipping: Conditional result was False
2025-11-27 23:32:17.242558 | 
2025-11-27 23:32:17.242644 | TASK [Print the username]
2025-11-27 23:32:17.708519 | controller | openstack-k8s-operators+cirobot
2025-11-27 23:32:17.778198 | controller | ok: Runtime: 0:00:00.008858
2025-11-27 23:32:17.794650 | 
2025-11-27 23:32:17.794841 | TASK [Fail when user or password is not set]
2025-11-27 23:32:17.877112 | controller | skipping: Conditional result was False
2025-11-27 23:32:17.889281 | 
2025-11-27 23:32:17.889447 | TASK [Set vars for quay login]
2025-11-27 23:32:17.959475 | controller | Output suppressed because no_log was given
2025-11-27 23:32:17.970982 | 
2025-11-27 23:32:17.971157 | LOOP [Perform container registry login(s) with podman]
2025-11-27 23:32:18.913977 | controller | Output suppressed because no_log was given
2025-11-27 23:32:18.925604 | 
2025-11-27 23:32:18.925744 | LOOP [Perform container registry login(s) with buildah]
2025-11-27 23:32:19.517637 | controller | Output suppressed because no_log was given
2025-11-27 23:32:19.529306 | 
2025-11-27 23:32:19.529450 | TASK [Set Insecure registry for content provider]
2025-11-27 23:32:19.610236 | controller | skipping: Conditional result was False
2025-11-27 23:32:19.651861 | 
2025-11-27 23:32:19.651962 | PLAY RECAP
2025-11-27 23:32:19.652044 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0
2025-11-27 23:32:19.652080 | 
2025-11-27 23:32:19.757077 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/crc/podman.yaml@master]
2025-11-27 23:32:19.759216 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-11-27 23:32:20.399861 | 
2025-11-27 23:32:20.399997 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2025-11-27 23:32:20.423149 | 
2025-11-27 23:32:20.423345 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2025-11-27 23:32:20.457042 | controller | ok
2025-11-27 23:32:20.475491 | 
2025-11-27 23:32:20.475586 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-11-27 23:32:20.520252 | controller | skipping: Conditional result was False
2025-11-27 23:32:20.526705 | 
2025-11-27 23:32:20.526802 | TASK [mirror-info-fork : Create /etc/ci]
2025-11-27 23:32:20.991138 | controller | ok
2025-11-27 23:32:21.004097 | 
2025-11-27 23:32:21.004277 | TASK [mirror-info-fork : Install ci_mirror script]
2025-11-27 23:32:21.962367 | controller | ok
2025-11-27 23:32:21.984821 | 
2025-11-27 23:32:21.984966 | TASK [Prepare workspace]
2025-11-27 23:32:22.021945 | controller | ok
2025-11-27 23:32:22.048683 | 
2025-11-27 23:32:22.048814 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-11-27 23:32:22.466727 | controller | ok
2025-11-27 23:32:22.479467 | 
2025-11-27 23:32:22.479602 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-11-27 23:32:25.108223 | controller | Output suppressed because no_log was given
2025-11-27 23:32:25.130226 | 
2025-11-27 23:32:25.130357 | LOOP [Create zuul-output directory]
2025-11-27 23:32:25.418962 | controller | changed: "/home/zuul/ci-framework-data/logs"
2025-11-27 23:32:25.647880 | controller | ok: "/home/zuul/zuul-output/logs"
2025-11-27 23:32:25.663554 | 
2025-11-27 23:32:25.663755 | TASK [Install required packages]
2025-11-27 23:32:26.657750 | controller | ok: Nothing to do
2025-11-27 23:32:26.669456 | 
2025-11-27 23:32:26.669605 | TASK [Install venv]
2025-11-27 23:33:39.726938 | controller | changed
2025-11-27 23:33:39.777651 | 
2025-11-27 23:33:39.777828 | PLAY RECAP
2025-11-27 23:33:39.777923 | controller | ok: 7 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-11-27 23:33:39.777961 | 
2025-11-27 23:33:39.891142 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-11-27 23:33:39.893259 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-11-27 23:33:40.581063 | 
2025-11-27 23:33:40.581201 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-11-27 23:33:40.602197 | 
2025-11-27 23:33:40.602334 | TASK [Create zuul-output directory]
2025-11-27 23:33:41.027691 | controller | changed
2025-11-27 23:33:41.039297 | 
2025-11-27 23:33:41.039484 | TASK [Slurp Zuul inventory test]
2025-11-27 23:33:41.456810 | controller -> localhost | ok
2025-11-27 23:33:41.470904 | 
2025-11-27 23:33:41.471098 | TASK [Save zuul inventory]
2025-11-27 23:33:42.622126 | controller | changed
2025-11-27 23:33:42.634230 | 
2025-11-27 23:33:42.634375 | TASK [Save zuul vars without the change_message]
2025-11-27 23:33:43.662503 | controller | changed
2025-11-27 23:33:43.688172 | 
2025-11-27 23:33:43.688275 | PLAY RECAP
2025-11-27 23:33:43.688323 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-11-27 23:33:43.688349 | 
2025-11-27 23:33:43.786025 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-11-27 23:33:43.787394 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/build_push_container_runner.yml@main]
2025-11-27 23:33:44.382150 | 
2025-11-27 23:33:44.382270 | PLAY [Run build_push_container role]
2025-11-27 23:33:44.403584 | 
2025-11-27 23:33:44.403667 | TASK [Run build_push_container role]
2025-11-27 23:33:45.881612 | controller |
2025-11-27 23:33:47.139797 | controller | PLAY [Run build_push_container role] *******************************************
2025-11-27 23:33:47.139885 | controller |
2025-11-27 23:33:47.139938 | controller | TASK [Gathering Facts ] ********************************************************
2025-11-27 23:33:47.139953 | controller | Thursday 27 November 2025  23:33:45 +0000 (0:00:00.057)       0:00:00.057 *****
2025-11-27 23:33:47.139967 | controller | Thursday 27 November 2025  23:33:45 +0000 (0:00:00.056)       0:00:00.056 *****
2025-11-27 23:33:47.139991 | controller | ok: [localhost]
2025-11-27 23:33:47.208272 | controller |
2025-11-27 23:33:47.208357 | controller | TASK [Run build_push_container role name=build_push_container] *****************
2025-11-27 23:33:47.208494 | controller | Thursday 27 November 2025  23:33:47 +0000 (0:00:01.257)       0:00:01.315 *****
2025-11-27 23:33:47.208534 | controller | Thursday 27 November 2025  23:33:47 +0000 (0:00:01.257)       0:00:01.314 *****
2025-11-27 23:33:47.208565 | controller |
2025-11-27 23:33:47.792940 | controller | TASK [build_push_container : Ensure directories are present path={{ item }}, state=directory, mode=0755, recurse=True] ***
2025-11-27 23:33:47.793048 | controller | Thursday 27 November 2025  23:33:47 +0000 (0:00:00.069)       0:00:01.384 *****
2025-11-27 23:33:47.793066 | controller | Thursday 27 November 2025  23:33:47 +0000 (0:00:00.069)       0:00:01.383 *****
2025-11-27 23:33:47.793098 | controller | changed: [localhost] => (item=/home/zuul/ci-framework-data/artifacts/build_push_container/scripts)
2025-11-27 23:33:48.827217 | controller | changed: [localhost] => (item=/home/zuul/ci-framework-data/artifacts/build_push_container/logs)
2025-11-27 23:33:48.827300 | controller |
2025-11-27 23:33:48.827323 | controller | TASK [podman : Ensure podman is installed name={{ cifmw_podman_packages }}, state=present] ***
2025-11-27 23:33:48.827342 | controller | Thursday 27 November 2025  23:33:47 +0000 (0:00:00.584)       0:00:01.968 *****
2025-11-27 23:33:48.827360 | controller | Thursday 27 November 2025  23:33:47 +0000 (0:00:00.584)       0:00:01.967 *****
2025-11-27 23:33:48.827435 | controller | ok: [localhost]
2025-11-27 23:33:49.386511 | controller |
2025-11-27 23:33:49.386560 | controller | TASK [podman : Enable loginctl linger for ansible_user_id _raw_params=loginctl enable-linger {{ cifmw_podman_user_linger }}] ***
2025-11-27 23:33:49.386572 | controller | Thursday 27 November 2025  23:33:48 +0000 (0:00:01.034)       0:00:03.003 *****
2025-11-27 23:33:49.386582 | controller | Thursday 27 November 2025  23:33:48 +0000 (0:00:01.034)       0:00:03.002 *****
2025-11-27 23:33:49.386597 | controller | changed: [localhost]
2025-11-27 23:33:49.431602 | controller |
2025-11-27 23:33:49.431662 | 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-11-27 23:33:49.431683 | controller | Thursday 27 November 2025  23:33:49 +0000 (0:00:00.559)       0:00:03.562 *****
2025-11-27 23:33:49.431697 | controller | Thursday 27 November 2025  23:33:49 +0000 (0:00:00.559)       0:00:03.561 *****
2025-11-27 23:33:49.431719 | controller | skipping: [localhost]
2025-11-27 23:33:49.474165 | controller |
2025-11-27 23:33:49.474214 | 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-11-27 23:33:49.474226 | controller | Thursday 27 November 2025  23:33:49 +0000 (0:00:00.044)       0:00:03.607 *****
2025-11-27 23:33:49.474235 | controller | Thursday 27 November 2025  23:33:49 +0000 (0:00:00.045)       0:00:03.606 *****
2025-11-27 23:33:49.474250 | controller | skipping: [localhost]
2025-11-27 23:33:49.522908 | controller |
2025-11-27 23:33:49.522957 | controller | TASK [podman : Run podman system migrate if subuid/subgid files were changed _raw_params=podman system migrate] ***
2025-11-27 23:33:49.522971 | controller | Thursday 27 November 2025  23:33:49 +0000 (0:00:00.042)       0:00:03.650 *****
2025-11-27 23:33:49.522983 | controller | Thursday 27 November 2025  23:33:49 +0000 (0:00:00.042)       0:00:03.649 *****
2025-11-27 23:33:49.522999 | controller | skipping: [localhost]
2025-11-27 23:33:57.499663 | controller |
2025-11-27 23:33:57.499781 | 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-11-27 23:33:57.499831 | controller | Thursday 27 November 2025  23:33:49 +0000 (0:00:00.047)       0:00:03.698 *****
2025-11-27 23:33:57.499849 | controller | Thursday 27 November 2025  23:33:49 +0000 (0:00:00.047)       0:00:03.697 *****
2025-11-27 23:33:57.499876 | 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-11-27 23:33:57.870985 | controller | changed: [localhost]
2025-11-27 23:33:57.871070 | controller |
2025-11-27 23:33:57.871086 | controller | TASK [build_push_container : Ensure manifest doesn't exist _raw_params=podman manifest rm {{ _container_manifest_name }}] ***
2025-11-27 23:33:57.871101 | controller | Thursday 27 November 2025  23:33:57 +0000 (0:00:07.977)       0:00:11.675 *****
2025-11-27 23:33:57.871115 | controller | Thursday 27 November 2025  23:33:57 +0000 (0:00:07.977)       0:00:11.674 *****
2025-11-27 23:33:57.871142 | controller | ok: [localhost]
2025-11-27 23:33:58.411123 | controller |
2025-11-27 23:33:58.411215 | controller | TASK [build_push_container : Create manifest _raw_params=podman manifest create {{ _container_manifest_name }}] ***
2025-11-27 23:33:58.411233 | controller | Thursday 27 November 2025  23:33:57 +0000 (0:00:00.370)       0:00:12.046 *****
2025-11-27 23:33:58.411248 | controller | Thursday 27 November 2025  23:33:57 +0000 (0:00:00.370)       0:00:12.045 *****
2025-11-27 23:33:58.411275 | controller | changed: [localhost]
2025-11-27 23:49:05.478635 | controller |
2025-11-27 23:49:05.478700 | controller | TASK [build_push_container : Build container output_dir={{ (cifmw_build_push_container_artifacts, 'scripts') | ansible.builtin.path_join }}, script={{ _podman_build_cmd }}] ***
2025-11-27 23:49:05.478718 | controller | Thursday 27 November 2025  23:33:58 +0000 (0:00:00.540)       0:00:12.587 *****
2025-11-27 23:49:05.478727 | controller | Thursday 27 November 2025  23:33:58 +0000 (0:00:00.540)       0:00:12.585 *****
2025-11-27 23:49:05.478743 | controller | Follow script's output here: /home/zuul/ci-framework-data/artifacts/build_push_container/logs/ci_script_001_build.log
2025-11-27 23:49:35.554862 | controller | changed: [localhost]
2025-11-27 23:49:35.554935 | controller |
2025-11-27 23:49:35.554952 | 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-11-27 23:49:35.554970 | controller | Thursday 27 November 2025  23:49:05 +0000 (0:15:07.067)       0:15:19.654 *****
2025-11-27 23:49:35.554984 | controller | Thursday 27 November 2025  23:49:05 +0000 (0:15:07.067)       0:15:19.652 *****
2025-11-27 23:49:35.555006 | controller | Follow script's output here: /home/zuul/ci-framework-data/artifacts/build_push_container/logs/ci_script_002_push.log
2025-11-27 23:49:35.669564 | controller | changed: [localhost] => (item=8db13f03e84e82fb0e1947bbe5672d8fe024d5e3)
2025-11-27 23:49:35.669604 | controller | Follow script's output here: /home/zuul/ci-framework-data/artifacts/build_push_container/logs/ci_script_003_push.log
2025-11-27 23:49:35.669613 | controller | changed: [localhost] => (item=3476-latest)
2025-11-27 23:49:35.669622 | controller |
2025-11-27 23:49:35.669632 | 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-11-27 23:49:35.669641 | controller | Thursday 27 November 2025  23:49:35 +0000 (0:00:30.076)       0:15:49.730 *****
2025-11-27 23:49:35.669649 | controller | Thursday 27 November 2025  23:49:35 +0000 (0:00:30.076)       0:15:49.729 *****
2025-11-27 23:49:35.669666 | controller | skipping: [localhost] => (item=8db13f03e84e82fb0e1947bbe5672d8fe024d5e3)
2025-11-27 23:49:35.743778 | controller | skipping: [localhost] => (item=3476-latest)
2025-11-27 23:49:35.743843 | controller | skipping: [localhost]
2025-11-27 23:49:35.743851 | controller |
2025-11-27 23:49:35.743858 | controller | PLAY RECAP *********************************************************************
2025-11-27 23:49:35.743864 | controller | localhost                  : ok=9    changed=6    unreachable=0    failed=0    skipped=4    rescued=0    ignored=0
2025-11-27 23:49:35.743871 | controller |
2025-11-27 23:49:35.743877 | controller | Thursday 27 November 2025  23:49:35 +0000 (0:00:00.114)       0:15:49.845 *****
2025-11-27 23:49:35.743893 | controller | ===============================================================================
2025-11-27 23:49:35.743900 | controller | build_push_container : Build container -------------------------------- 907.07s
2025-11-27 23:49:35.743905 | controller | build_push_container : Push manifest ----------------------------------- 30.08s
2025-11-27 23:49:35.743911 | controller | build_push_container : Install qemu-user-static for multi arch builds --- 7.98s
2025-11-27 23:49:35.743917 | controller | Gathering Facts --------------------------------------------------------- 1.26s
2025-11-27 23:49:35.743923 | controller | podman : Ensure podman is installed ------------------------------------- 1.03s
2025-11-27 23:49:35.743929 | controller | build_push_container : Ensure directories are present ------------------- 0.58s
2025-11-27 23:49:35.743935 | controller | podman : Enable loginctl linger for ansible_user_id --------------------- 0.56s
2025-11-27 23:49:35.743953 | controller | build_push_container : Create manifest ---------------------------------- 0.54s
2025-11-27 23:49:35.743963 | controller | build_push_container : Ensure manifest doesn't exist -------------------- 0.37s
2025-11-27 23:49:35.743971 | controller | build_push_container : Push container ----------------------------------- 0.11s
2025-11-27 23:49:35.743979 | controller | Run build_push_container role ------------------------------------------- 0.07s
2025-11-27 23:49:35.743985 | controller | podman : Run podman system migrate if subuid/subgid files were changed --- 0.05s
2025-11-27 23:49:35.743991 | controller | podman : Ensure subordinate UID entry exists for zuul ------------------- 0.05s
2025-11-27 23:49:35.743996 | controller | podman : Ensure subordinate GID entry exists for zuul ------------------- 0.04s
2025-11-27 23:49:35.744003 | controller | Thursday 27 November 2025  23:49:35 +0000 (0:00:00.115)       0:15:49.845 *****
2025-11-27 23:49:35.744009 | controller | ===============================================================================
2025-11-27 23:49:35.744014 | controller | build_push_container -------------------------------------------------- 946.73s
2025-11-27 23:49:35.744020 | controller | podman ------------------------------------------------------------------ 1.73s
2025-11-27 23:49:35.744026 | controller | gather_facts ------------------------------------------------------------ 1.26s
2025-11-27 23:49:35.744032 | controller | ansible.builtin.include_role -------------------------------------------- 0.07s
2025-11-27 23:49:35.744038 | controller | ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
2025-11-27 23:49:35.744044 | controller | total ----------------------------------------------------------------- 949.79s
2025-11-27 23:49:36.095603 | controller | changed
2025-11-27 23:49:36.122950 | 
2025-11-27 23:49:36.123139 | PLAY RECAP
2025-11-27 23:49:36.123223 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-11-27 23:49:36.123285 | 
2025-11-27 23:49:36.220705 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/build_push_container_runner.yml@main]
2025-11-27 23:49:36.222460 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-11-27 23:49:36.789638 | 
2025-11-27 23:49:36.789766 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-11-27 23:49:36.810875 | 
2025-11-27 23:49:36.810978 | TASK [Filter out host if needed]
2025-11-27 23:49:36.819927 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-11-27 23:49:36.824709 | 
2025-11-27 23:49:36.824793 | TASK [Ensure file is present]
2025-11-27 23:49:37.206547 | controller | ok
2025-11-27 23:49:37.222107 | 
2025-11-27 23:49:37.222298 | TASK [Manage molecule report file]
2025-11-27 23:49:37.774522 | controller | skipping: Conditional result was False
2025-11-27 23:49:37.780581 | 
2025-11-27 23:49:37.780662 | TASK [Check if we get ci-framework-data basedir]
2025-11-27 23:49:38.070285 | controller | ok
2025-11-27 23:49:38.082504 | 
2025-11-27 23:49:38.082644 | TASK [Create ci-framework-data log directory for zuul]
2025-11-27 23:49:38.527906 | controller | changed
2025-11-27 23:49:38.539720 | 
2025-11-27 23:49:38.539868 | TASK [Copy ci-framework interesting files]
2025-11-27 23:49:39.009833 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2025-11-27 23:49:39.091459 | controller | changed
2025-11-27 23:49:39.103488 | 
2025-11-27 23:49:39.103626 | TASK [Get SELinux listing]
2025-11-27 23:49:39.655763 | controller | changed
2025-11-27 23:49:39.668925 | 
2025-11-27 23:49:39.669138 | TASK [Generate log index]
2025-11-27 23:49:40.625131 | controller | changed
2025-11-27 23:49:40.636609 | 
2025-11-27 23:49:40.636744 | TASK [Get some env related data]
2025-11-27 23:49:41.400486 | controller | /home/zuul/.local/bin/ansible
2025-11-27 23:49:42.185532 | controller | changed
2025-11-27 23:49:42.196999 | 
2025-11-27 23:49:42.197158 | TASK [Generate list of logs to collect in home directory]
2025-11-27 23:49:42.571264 | controller | ok: All paths examined
2025-11-27 23:49:42.583392 | 
2025-11-27 23:49:42.583519 | LOOP [Copy logs from home directory]
2025-11-27 23:49:44.242749 | controller | changed:
2025-11-27 23:49:44.243231 | controller | {
2025-11-27 23:49:44.243332 | controller |   "atime": 1764286398.436274,
2025-11-27 23:49:44.243395 | controller |   "ctime": 1764287375.668508,
2025-11-27 23:49:44.243451 | controller |   "dev": 64513,
2025-11-27 23:49:44.243506 | controller |   "gid": 1000,
2025-11-27 23:49:44.243559 | controller |   "gr_name": "zuul",
2025-11-27 23:49:44.243613 | controller |   "inode": 4345835,
2025-11-27 23:49:44.243665 | controller |   "isblk": false,
2025-11-27 23:49:44.243717 | controller |   "ischr": false,
2025-11-27 23:49:44.243768 | controller |   "isdir": false,
2025-11-27 23:49:44.243818 | controller |   "isfifo": false,
2025-11-27 23:49:44.243870 | controller |   "isgid": false,
2025-11-27 23:49:44.243920 | controller |   "islnk": false,
2025-11-27 23:49:44.243969 | controller |   "isreg": true,
2025-11-27 23:49:44.244091 | controller |   "issock": false,
2025-11-27 23:49:44.244151 | controller |   "isuid": false,
2025-11-27 23:49:44.244203 | controller |   "mode": "0644",
2025-11-27 23:49:44.244254 | controller |   "mtime": 1764287375.668508,
2025-11-27 23:49:44.244305 | controller |   "nlink": 1,
2025-11-27 23:49:44.244356 | controller |   "path": "/home/zuul/ansible.log",
2025-11-27 23:49:44.244410 | controller |   "pw_name": "zuul",
2025-11-27 23:49:44.244463 | controller |   "rgrp": true,
2025-11-27 23:49:44.244552 | controller |   "roth": true,
2025-11-27 23:49:44.244607 | controller |   "rusr": true,
2025-11-27 23:49:44.244660 | controller |   "size": 18992,
2025-11-27 23:49:44.244711 | controller |   "uid": 1000,
2025-11-27 23:49:44.244763 | controller |   "wgrp": false,
2025-11-27 23:49:44.244813 | controller |   "woth": false,
2025-11-27 23:49:44.244870 | controller |   "wusr": true,
2025-11-27 23:49:44.244922 | controller |   "xgrp": false,
2025-11-27 23:49:44.244975 | controller |   "xoth": false,
2025-11-27 23:49:44.245077 | controller |   "xusr": false
2025-11-27 23:49:44.245133 | controller | }
2025-11-27 23:49:44.266073 | 
2025-11-27 23:49:44.266282 | TASK [Copy crio stats log file]
2025-11-27 23:49:44.295292 | controller | skipping: Conditional result was False
2025-11-27 23:49:44.309494 | 
2025-11-27 23:49:44.309660 | TASK [Get SELinux related data]
2025-11-27 23:49:44.624775 | controller | <no matches>
2025-11-27 23:49:44.868340 | controller | ERROR
2025-11-27 23:49:44.868661 | controller | {
2025-11-27 23:49:44.868727 | controller |   "delta": "0:00:00.013507",
2025-11-27 23:49:44.868768 | controller |   "end": "2025-11-27 23:49:44.626412",
2025-11-27 23:49:44.868821 | controller |   "msg": "non-zero return code",
2025-11-27 23:49:44.868857 | controller |   "rc": 1,
2025-11-27 23:49:44.868891 | controller |   "start": "2025-11-27 23:49:44.612905"
2025-11-27 23:49:44.868925 | controller | }
2025-11-27 23:49:44.868970 | controller | ERROR: Ignoring Errors
2025-11-27 23:49:44.880148 | 
2025-11-27 23:49:44.880295 | TASK [Create system configuration directory]
2025-11-27 23:49:45.344757 | controller | changed
2025-11-27 23:49:45.356066 | 
2025-11-27 23:49:45.356194 | TASK [Get some of the system configurations]
2025-11-27 23:49:45.908244 | controller | changed
2025-11-27 23:49:45.926214 | 
2025-11-27 23:49:45.926385 | TASK [Copy generated documentation if available]
2025-11-27 23:49:45.955930 | controller | skipping: Conditional result was False
2025-11-27 23:49:45.968206 | 
2025-11-27 23:49:45.968335 | TASK [Copy generated AsciiDoc documentation if available]
2025-11-27 23:49:46.004825 | controller | skipping: Conditional result was False
2025-11-27 23:49:46.016228 | 
2025-11-27 23:49:46.016374 | TASK [Compress logs bigger than 2MB]
2025-11-27 23:49:46.564711 | controller | changed
2025-11-27 23:49:46.576180 | 
2025-11-27 23:49:46.576332 | TASK [Copy files from workspace on node]
2025-11-27 23:49:46.603987 | controller | ok
2025-11-27 23:49:46.647379 | 
2025-11-27 23:49:46.647507 | TASK [fetch-output : Set log path for multiple nodes]
2025-11-27 23:49:46.672428 | controller | skipping: Conditional result was False
2025-11-27 23:49:46.680438 | 
2025-11-27 23:49:46.680525 | TASK [fetch-output : Set log path for single node]
2025-11-27 23:49:46.733388 | controller | ok
2025-11-27 23:49:46.749254 | 
2025-11-27 23:49:46.749377 | LOOP [fetch-output : Ensure local output dirs]
2025-11-27 23:49:47.009615 | controller -> localhost | ok: "/var/lib/zuul/builds/b92f45bdbb314ff3ad923c049aecefa5/work/logs"
2025-11-27 23:49:47.010116 | controller -> localhost | changed: All items complete
2025-11-27 23:49:47.010184 | 
2025-11-27 23:49:47.254292 | controller -> localhost | changed: "/var/lib/zuul/builds/b92f45bdbb314ff3ad923c049aecefa5/work/artifacts"
2025-11-27 23:49:47.486184 | controller -> localhost | changed: "/var/lib/zuul/builds/b92f45bdbb314ff3ad923c049aecefa5/work/docs"
2025-11-27 23:49:47.499936 | 
2025-11-27 23:49:47.500100 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-11-27 23:49:48.179282 | controller | changed:
2025-11-27 23:49:48.179552 | controller | .d..t...... ./
2025-11-27 23:49:48.179594 | controller | >f+++++++++ README.html
2025-11-27 23:49:48.179625 | controller | >f+++++++++ ansible.log
2025-11-27 23:49:48.179654 | controller | >f+++++++++ dmesg.log
2025-11-27 23:49:48.179683 | controller | >f+++++++++ installed-pkgs.log
2025-11-27 23:49:48.179710 | controller | >f+++++++++ python.log
2025-11-27 23:49:48.179743 | controller | >f+++++++++ registries.conf
2025-11-27 23:49:48.179773 | controller | >f+++++++++ selinux-denials.log
2025-11-27 23:49:48.179800 | controller | >f+++++++++ selinux-listing.log
2025-11-27 23:49:48.179829 | controller | cd+++++++++ ci-framework-data/
2025-11-27 23:49:48.179855 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-11-27 23:49:48.179885 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-11-27 23:49:48.179914 | controller | cd+++++++++ ci-framework-data/artifacts/build_push_container/
2025-11-27 23:49:48.179941 | controller | cd+++++++++ ci-framework-data/artifacts/build_push_container/logs/
2025-11-27 23:49:48.179969 | controller | >f+++++++++ ci-framework-data/artifacts/build_push_container/logs/ci_script_000_install_qemu_user_static_for.log
2025-11-27 23:49:48.180000 | controller | >f+++++++++ ci-framework-data/artifacts/build_push_container/logs/ci_script_001_build.log
2025-11-27 23:49:48.180056 | controller | >f+++++++++ ci-framework-data/artifacts/build_push_container/logs/ci_script_002_push.log
2025-11-27 23:49:48.180093 | controller | >f+++++++++ ci-framework-data/artifacts/build_push_container/logs/ci_script_003_push.log
2025-11-27 23:49:48.180138 | controller | cd+++++++++ ci-framework-data/artifacts/build_push_container/scripts/
2025-11-27 23:49:48.180174 | controller | >f+++++++++ ci-framework-data/artifacts/build_push_container/scripts/ci_script_000_install_qemu_user_static_for.sh
2025-11-27 23:49:48.180207 | controller | >f+++++++++ ci-framework-data/artifacts/build_push_container/scripts/ci_script_001_build.sh
2025-11-27 23:49:48.180241 | controller | >f+++++++++ ci-framework-data/artifacts/build_push_container/scripts/ci_script_002_push.sh
2025-11-27 23:49:48.180275 | controller | >f+++++++++ ci-framework-data/artifacts/build_push_container/scripts/ci_script_003_push.sh
2025-11-27 23:49:48.180306 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-11-27 23:49:48.180338 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-11-27 23:49:48.180368 | controller | cd+++++++++ ci-framework-data/logs/
2025-11-27 23:49:48.180400 | controller | cd+++++++++ registries.conf.d/
2025-11-27 23:49:48.180434 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-11-27 23:49:48.180468 | controller | cd+++++++++ system-config/
2025-11-27 23:49:48.180505 | controller | cd+++++++++ system-config/libvirt/
2025-11-27 23:49:48.180539 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-11-27 23:49:48.180572 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-11-27 23:49:48.669689 | controller | changed: .d..t...... ./
2025-11-27 23:49:49.157516 | controller | changed: .d..t...... ./
2025-11-27 23:49:49.179908 | 
2025-11-27 23:49:49.180073 | TASK [Return artifact to Zuul]
2025-11-27 23:49:49.195718 | controller | skipping: Conditional result was False
2025-11-27 23:49:49.224927 | 
2025-11-27 23:49:49.225082 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-11-27 23:49:49.225224 | 
2025-11-27 23:49:49.225262 | PLAY RECAP
2025-11-27 23:49:49.225312 | controller | ok: 16 changed: 12 unreachable: 0 failed: 0 skipped: 6 rescued: 0 ignored: 1
2025-11-27 23:49:49.225366 | 
2025-11-27 23:49:49.339146 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-11-27 23:49:49.341176 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-11-27 23:49:49.933388 | 
2025-11-27 23:49:49.933501 | PLAY [all]
2025-11-27 23:49:49.952166 | 
2025-11-27 23:49:49.952240 | TASK [include_role : fetch-output]
2025-11-27 23:49:50.003623 | controller | ok
2025-11-27 23:49:50.025956 | 
2025-11-27 23:49:50.026087 | TASK [fetch-output : Set log path for multiple nodes]
2025-11-27 23:49:50.093495 | controller | skipping: Conditional result was False
2025-11-27 23:49:50.105832 | 
2025-11-27 23:49:50.105976 | TASK [fetch-output : Set log path for single node]
2025-11-27 23:49:50.152634 | controller | ok
2025-11-27 23:49:50.163352 | 
2025-11-27 23:49:50.163486 | LOOP [fetch-output : Ensure local output dirs]
2025-11-27 23:49:50.556168 | controller -> localhost | ok: "/var/lib/zuul/builds/b92f45bdbb314ff3ad923c049aecefa5/work/logs"
2025-11-27 23:49:50.811212 | controller -> localhost | ok: "/var/lib/zuul/builds/b92f45bdbb314ff3ad923c049aecefa5/work/artifacts"
2025-11-27 23:49:51.094830 | controller -> localhost | ok: "/var/lib/zuul/builds/b92f45bdbb314ff3ad923c049aecefa5/work/docs"
2025-11-27 23:49:51.114439 | 
2025-11-27 23:49:51.114639 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-11-27 23:49:51.855257 | controller | ok
2025-11-27 23:49:51.855607 | controller | ok: All items complete
2025-11-27 23:49:51.855671 | 
2025-11-27 23:49:52.397333 | controller | ok
2025-11-27 23:49:52.940240 | controller | ok
2025-11-27 23:49:52.965700 | 
2025-11-27 23:49:52.965912 | TASK [include_role : fetch-output-openshift]
2025-11-27 23:49:52.982211 | controller | skipping: Conditional result was False
2025-11-27 23:49:52.988746 | 
2025-11-27 23:49:52.988818 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-11-27 23:49:53.389912 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.006783
2025-11-27 23:49:53.615898 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.012528
2025-11-27 23:49:53.675925 | 
2025-11-27 23:49:53.676132 | PLAY [all]
2025-11-27 23:49:53.698265 | 
2025-11-27 23:49:53.698376 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-11-27 23:49:54.259422 | controller | changed
2025-11-27 23:49:54.310073 | 
2025-11-27 23:49:54.310172 | PLAY RECAP
2025-11-27 23:49:54.310226 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-11-27 23:49:54.310253 | 
2025-11-27 23:49:54.410432 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-11-27 23:49:54.412829 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-11-27 23:49:55.047697 | 
2025-11-27 23:49:55.047861 | PLAY [localhost]
2025-11-27 23:49:55.066237 | 
2025-11-27 23:49:55.066325 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-11-27 23:49:55.503120 | localhost | changed
2025-11-27 23:49:55.513065 | 
2025-11-27 23:49:55.513261 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-11-27 23:49:55.540337 | localhost | ok
2025-11-27 23:49:55.557808 | 
2025-11-27 23:49:55.557952 | TASK [add-fileserver : Create SSH private key tempfile]
2025-11-27 23:49:55.961500 | localhost | changed
2025-11-27 23:49:55.966725 | 
2025-11-27 23:49:55.966803 | TASK [add-fileserver : Create SSH private key from secret]
2025-11-27 23:49:56.693866 | localhost | changed
2025-11-27 23:49:56.698829 | 
2025-11-27 23:49:56.698894 | TASK [add-fileserver : Add fileserver ssh key]
2025-11-27 23:49:57.155275 | localhost | Identity added: /var/lib/zuul/builds/b92f45bdbb314ff3ad923c049aecefa5/work/tmp/ansible.g1yodiry (/var/lib/zuul/builds/b92f45bdbb314ff3ad923c049aecefa5/work/tmp/ansible.g1yodiry)
2025-11-27 23:49:57.155589 | localhost | ok: Runtime: 0:00:00.007155
2025-11-27 23:49:57.165732 | 
2025-11-27 23:49:57.165877 | TASK [add-fileserver : Remove SSH private key from disk]
2025-11-27 23:49:57.439390 | localhost | ok: Runtime: 0:00:00.008071
2025-11-27 23:49:57.449371 | 
2025-11-27 23:49:57.449510 | TASK [add-fileserver : Add fileserver to inventory]
2025-11-27 23:49:57.527224 | localhost | changed
2025-11-27 23:49:57.536642 | 
2025-11-27 23:49:57.536775 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-11-27 23:49:57.985461 | localhost | changed
2025-11-27 23:49:58.024447 | 
2025-11-27 23:49:58.024534 | PLAY [localhost]
2025-11-27 23:49:58.040841 | 
2025-11-27 23:49:58.040917 | TASK [Generate bulk log download script]
2025-11-27 23:49:58.061544 | localhost | ok
2025-11-27 23:49:58.077535 | 
2025-11-27 23:49:58.077629 | TASK [local-log-download : Check API endpoint is defined]
2025-11-27 23:49:58.118029 | localhost | ok: All assertions passed
2025-11-27 23:49:58.124004 | 
2025-11-27 23:49:58.124111 | TASK [local-log-download : Create download script]
2025-11-27 23:49:58.568792 | localhost -> localhost | changed
2025-11-27 23:49:58.589243 | 
2025-11-27 23:49:58.589386 | TASK [Register quick-download link]
2025-11-27 23:49:58.644609 | localhost | ok
2025-11-27 23:49:58.666506 | 
2025-11-27 23:49:58.666617 | PLAY [logserver.rdoproject.org]
2025-11-27 23:49:58.680175 | 
2025-11-27 23:49:58.680287 | TASK [Set zuul-log-path fact]
2025-11-27 23:49:58.698249 | logserver.rdoproject.org | ok
2025-11-27 23:49:58.710159 | 
2025-11-27 23:49:58.710273 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-11-27 23:49:58.738800 | logserver.rdoproject.org | ok
2025-11-27 23:49:58.746528 | 
2025-11-27 23:49:58.746622 | TASK [upload-logs : Create log directories]
2025-11-27 23:49:59.684771 | logserver.rdoproject.org | changed
2025-11-27 23:49:59.691552 | 
2025-11-27 23:49:59.691706 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-11-27 23:50:00.017545 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.011381
2025-11-27 23:50:00.029521 | 
2025-11-27 23:50:00.029720 | TASK [upload-logs : Upload logs to log server]
2025-11-27 23:50:00.833643 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-11-27 23:50:00.840935 | 
2025-11-27 23:50:00.841132 | LOOP [upload-logs : Compress console log and json output]
2025-11-27 23:50:00.914831 | logserver.rdoproject.org | skipping: Conditional result was False
2025-11-27 23:50:00.926262 | logserver.rdoproject.org | skipping: Conditional result was False
2025-11-27 23:50:00.936930 | 
2025-11-27 23:50:00.937172 | LOOP [upload-logs : Upload compressed console log and json output]
2025-11-27 23:50:00.982249 | logserver.rdoproject.org | skipping: Conditional result was False
2025-11-27 23:50:00.982668 | 
2025-11-27 23:50:00.985483 | logserver.rdoproject.org | skipping: Conditional result was False
2025-11-27 23:50:01.003182 | 
2025-11-27 23:50:01.003382 | LOOP [upload-logs : Upload console log and json output]