2025-12-02 18:46:27.951789 | Job console starting... 2025-12-02 18:46:27.962550 | Updating repositories 2025-12-02 18:46:27.990701 | Preparing job workspace 2025-12-02 18:46:34.382215 | Running Ansible setup... 2025-12-02 18:46:39.762807 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2025-12-02 18:46:40.424318 | 2025-12-02 18:46:40.424454 | PLAY [localhost] 2025-12-02 18:46:40.454563 | 2025-12-02 18:46:40.454964 | TASK [Gathering Facts] 2025-12-02 18:46:41.536346 | localhost | ok 2025-12-02 18:46:41.553454 | 2025-12-02 18:46:41.553698 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-12-02 18:46:42.071823 | localhost -> localhost | changed 2025-12-02 18:46:42.080525 | 2025-12-02 18:46:42.080714 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-12-02 18:46:43.063030 | localhost -> localhost | changed 2025-12-02 18:46:43.079505 | 2025-12-02 18:46:43.079634 | TASK [Setup log path fact] 2025-12-02 18:46:43.108595 | localhost | ok 2025-12-02 18:46:43.124576 | 2025-12-02 18:46:43.124785 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-12-02 18:46:43.154082 | localhost | ok 2025-12-02 18:46:43.163464 | 2025-12-02 18:46:43.163583 | TASK [emit-job-header : Print job information] 2025-12-02 18:46:43.202901 | # Job Information 2025-12-02 18:46:43.203098 | Ansible Version: 2.15.12 2025-12-02 18:46:43.203126 | Job: build-push-container-cifmw-client 2025-12-02 18:46:43.203146 | Pipeline: github-check 2025-12-02 18:46:43.203165 | Executor: ze04.softwarefactory-project.io 2025-12-02 18:46:43.203183 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3511 2025-12-02 18:46:43.203203 | Log URL (when completed): https://logserver.rdoproject.org/b93/rdoproject.org/b938e9b6d9194a2583565d20f80b17c5/ 2025-12-02 18:46:43.203221 | Event ID: 0f936630-cfaf-11f0-9db0-fe20f8451fab 2025-12-02 18:46:43.207716 | 2025-12-02 18:46:43.207790 | LOOP [emit-job-header : Print node information] 2025-12-02 18:46:43.313097 | localhost | ok: 2025-12-02 18:46:43.313365 | localhost | # Node Information 2025-12-02 18:46:43.313405 | localhost | Inventory Hostname: controller 2025-12-02 18:46:43.313441 | localhost | Hostname: np0005542730 2025-12-02 18:46:43.313472 | localhost | Username: zuul 2025-12-02 18:46:43.313505 | localhost | Distro: CentOS 9 2025-12-02 18:46:43.313780 | localhost | Provider: vexxhost-nodepool-tripleo 2025-12-02 18:46:43.313816 | localhost | Region: RegionOne 2025-12-02 18:46:43.313844 | localhost | Label: cloud-centos-9-stream-tripleo 2025-12-02 18:46:43.313872 | localhost | Product Name: OpenStack Nova 2025-12-02 18:46:43.313899 | localhost | Interface IP: 38.102.83.30 2025-12-02 18:46:43.345654 | 2025-12-02 18:46:43.345851 | PLAY [all] 2025-12-02 18:46:43.355027 | 2025-12-02 18:46:43.355113 | TASK [Gather network facts] 2025-12-02 18:46:43.972343 | controller | ok 2025-12-02 18:46:44.002436 | 2025-12-02 18:46:44.002600 | TASK [include_role : start-zuul-console] 2025-12-02 18:46:44.042073 | controller | ok 2025-12-02 18:46:44.074004 | 2025-12-02 18:46:44.074199 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-12-02 18:46:44.574106 | controller | ok 2025-12-02 18:46:44.595140 | 2025-12-02 18:46:44.595297 | TASK [include_role : add-build-sshkey] 2025-12-02 18:46:44.643724 | controller | ok 2025-12-02 18:46:44.672383 | 2025-12-02 18:46:44.672574 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-12-02 18:46:44.998164 | controller -> localhost | ok 2025-12-02 18:46:45.005093 | 2025-12-02 18:46:45.005163 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-12-02 18:46:45.056720 | controller | ok 2025-12-02 18:46:45.074712 | controller | included: /var/lib/zuul/builds/b938e9b6d9194a2583565d20f80b17c5/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-12-02 18:46:45.083397 | 2025-12-02 18:46:45.083502 | TASK [add-build-sshkey : Create Temp SSH key] 2025-12-02 18:46:46.038226 | controller -> localhost | Generating public/private rsa key pair. 2025-12-02 18:46:46.038474 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/b938e9b6d9194a2583565d20f80b17c5/work/b938e9b6d9194a2583565d20f80b17c5_id_rsa. 2025-12-02 18:46:46.038504 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/b938e9b6d9194a2583565d20f80b17c5/work/b938e9b6d9194a2583565d20f80b17c5_id_rsa.pub. 2025-12-02 18:46:46.038526 | controller -> localhost | The key fingerprint is: 2025-12-02 18:46:46.038545 | controller -> localhost | SHA256:dJRntOfaxoej7MXFnxioILY9mF/Y8xr7mpQJD856Q8I zuul-build-sshkey 2025-12-02 18:46:46.038564 | controller -> localhost | The key's randomart image is: 2025-12-02 18:46:46.038582 | controller -> localhost | +---[RSA 3072]----+ 2025-12-02 18:46:46.038600 | controller -> localhost | | .o. | 2025-12-02 18:46:46.038619 | controller -> localhost | | .. o. | 2025-12-02 18:46:46.038637 | controller -> localhost | | . .o. . | 2025-12-02 18:46:46.038655 | controller -> localhost | | . . .o . | 2025-12-02 18:46:46.038672 | controller -> localhost | | .o +S . .. o| 2025-12-02 18:46:46.038730 | controller -> localhost | | .EO.B + =ooo| 2025-12-02 18:46:46.038750 | controller -> localhost | | +o* @ ..O.o| 2025-12-02 18:46:46.038767 | controller -> localhost | | oo+ *. + o | 2025-12-02 18:46:46.038784 | controller -> localhost | | ....=+++ | 2025-12-02 18:46:46.038801 | controller -> localhost | +----[SHA256]-----+ 2025-12-02 18:46:46.038850 | controller -> localhost | ok: Runtime: 0:00:00.383770 2025-12-02 18:46:46.049673 | 2025-12-02 18:46:46.049928 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-12-02 18:46:46.090584 | controller | ok 2025-12-02 18:46:46.110038 | controller | included: /var/lib/zuul/builds/b938e9b6d9194a2583565d20f80b17c5/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-12-02 18:46:46.120883 | 2025-12-02 18:46:46.121013 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-12-02 18:46:46.156606 | controller | skipping: Conditional result was False 2025-12-02 18:46:46.162898 | 2025-12-02 18:46:46.162984 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-12-02 18:46:46.712856 | controller | changed 2025-12-02 18:46:46.726102 | 2025-12-02 18:46:46.726282 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-12-02 18:46:47.039209 | controller | ok 2025-12-02 18:46:47.052440 | 2025-12-02 18:46:47.052705 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-12-02 18:46:48.038569 | controller | changed 2025-12-02 18:46:48.044888 | 2025-12-02 18:46:48.044989 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-12-02 18:46:49.052011 | controller | changed 2025-12-02 18:46:49.057510 | 2025-12-02 18:46:49.057598 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-12-02 18:46:49.094287 | controller | skipping: Conditional result was False 2025-12-02 18:46:49.101430 | 2025-12-02 18:46:49.101537 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-12-02 18:46:49.550320 | controller -> localhost | changed 2025-12-02 18:46:49.574922 | 2025-12-02 18:46:49.575167 | TASK [add-build-sshkey : Add back temp key] 2025-12-02 18:46:49.879633 | controller -> localhost | Identity added: /var/lib/zuul/builds/b938e9b6d9194a2583565d20f80b17c5/work/b938e9b6d9194a2583565d20f80b17c5_id_rsa (zuul-build-sshkey) 2025-12-02 18:46:49.880097 | controller -> localhost | ok: Runtime: 0:00:00.018784 2025-12-02 18:46:49.895510 | 2025-12-02 18:46:49.895779 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-12-02 18:46:50.340460 | controller | ok 2025-12-02 18:46:50.349020 | 2025-12-02 18:46:50.349133 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-12-02 18:46:50.394716 | controller | skipping: Conditional result was False 2025-12-02 18:46:50.409754 | 2025-12-02 18:46:50.409866 | TASK [include_role : validate-host] 2025-12-02 18:46:50.450274 | controller | ok 2025-12-02 18:46:50.474531 | 2025-12-02 18:46:50.474713 | TASK [validate-host : Define zuul_info_dir fact] 2025-12-02 18:46:50.608669 | controller | ok 2025-12-02 18:46:50.614199 | 2025-12-02 18:46:50.614274 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-12-02 18:46:50.960174 | controller -> localhost | ok 2025-12-02 18:46:50.967803 | 2025-12-02 18:46:50.967924 | TASK [validate-host : Collect information about the host] 2025-12-02 18:46:51.770527 | controller | ok 2025-12-02 18:46:51.782561 | 2025-12-02 18:46:51.782744 | TASK [validate-host : Sanitize hostname] 2025-12-02 18:46:51.846661 | controller | ok 2025-12-02 18:46:51.852655 | 2025-12-02 18:46:51.852793 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-12-02 18:46:52.403405 | controller -> localhost | changed 2025-12-02 18:46:52.410001 | 2025-12-02 18:46:52.410107 | TASK [validate-host : Collect information about zuul worker] 2025-12-02 18:46:52.915530 | controller | ok 2025-12-02 18:46:52.946224 | 2025-12-02 18:46:52.946435 | TASK [validate-host : Write out all zuul information for each host] 2025-12-02 18:46:53.506329 | controller -> localhost | changed 2025-12-02 18:46:53.517017 | 2025-12-02 18:46:53.517112 | TASK [include_role : prepare-workspace-openshift] 2025-12-02 18:46:53.542156 | controller | skipping: Conditional result was False 2025-12-02 18:46:53.554183 | 2025-12-02 18:46:53.554480 | TASK [include_role : remove-zuul-sshkey] 2025-12-02 18:46:53.583243 | controller | skipping: Conditional result was False 2025-12-02 18:46:53.592505 | 2025-12-02 18:46:53.593926 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2025-12-02 18:46:53.919019 | controller | ok: "logs" 2025-12-02 18:46:53.919574 | controller | ok: All items complete 2025-12-02 18:46:53.919660 | 2025-12-02 18:46:54.205173 | controller | ok: "artifacts" 2025-12-02 18:46:54.451887 | controller | ok: "docs" 2025-12-02 18:46:54.466610 | 2025-12-02 18:46:54.466866 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2025-12-02 18:46:54.742651 | controller | changed: "logs" 2025-12-02 18:46:55.034635 | controller | changed: "artifacts" 2025-12-02 18:46:55.284184 | controller | changed: "docs" 2025-12-02 18:46:55.333523 | 2025-12-02 18:46:55.333702 | PLAY RECAP 2025-12-02 18:46:55.333772 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0 2025-12-02 18:46:55.333811 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-12-02 18:46:55.333838 | 2025-12-02 18:46:55.530039 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2025-12-02 18:46:55.532443 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master] 2025-12-02 18:46:56.282129 | 2025-12-02 18:46:56.282306 | PLAY [all] 2025-12-02 18:46:56.306154 | 2025-12-02 18:46:56.306276 | TASK [mirror-info-fork : Set mirror_fqdn if not defined] 2025-12-02 18:46:56.377962 | controller | ok 2025-12-02 18:46:56.383760 | 2025-12-02 18:46:56.383842 | TASK [mirror-info-fork : Create /etc/ci] 2025-12-02 18:46:56.941295 | controller | changed 2025-12-02 18:46:56.955610 | 2025-12-02 18:46:56.956232 | TASK [mirror-info-fork : Install ci_mirror script] 2025-12-02 18:46:58.315297 | controller | changed 2025-12-02 18:46:58.330314 | 2025-12-02 18:46:58.330447 | LOOP [add-authorized-keys : Enable access via build key on all nodes] 2025-12-02 18:46:58.877356 | controller | changed: 2025-12-02 18:46:58.877942 | controller | { 2025-12-02 18:46:58.878024 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com" 2025-12-02 18:46:58.878104 | controller | } 2025-12-02 18:46:59.157815 | controller | changed: 2025-12-02 18:46:59.157961 | controller | { 2025-12-02 18:46:59.158000 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com" 2025-12-02 18:46:59.158029 | controller | } 2025-12-02 18:46:59.415557 | controller | changed: 2025-12-02 18:46:59.415762 | controller | { 2025-12-02 18:46:59.415836 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com" 2025-12-02 18:46:59.415884 | controller | } 2025-12-02 18:46:59.690738 | controller | changed: 2025-12-02 18:46:59.690936 | controller | { 2025-12-02 18:46:59.690992 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com" 2025-12-02 18:46:59.691036 | controller | } 2025-12-02 18:46:59.993497 | controller | changed: 2025-12-02 18:46:59.993722 | controller | { 2025-12-02 18:46:59.993783 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com" 2025-12-02 18:46:59.993825 | controller | } 2025-12-02 18:47:00.276303 | controller | changed: 2025-12-02 18:47:00.276494 | controller | { 2025-12-02 18:47:00.276550 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com" 2025-12-02 18:47:00.276593 | controller | } 2025-12-02 18:47:00.550802 | controller | changed: 2025-12-02 18:47:00.550989 | controller | { 2025-12-02 18:47:00.551043 | 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-12-02 18:47:00.551085 | controller | } 2025-12-02 18:47:00.822498 | controller | changed: 2025-12-02 18:47:00.822730 | controller | { 2025-12-02 18:47:00.822822 | 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-12-02 18:47:00.822885 | controller | } 2025-12-02 18:47:01.105556 | controller | changed: 2025-12-02 18:47:01.105721 | controller | { 2025-12-02 18:47:01.105751 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner" 2025-12-02 18:47:01.105771 | controller | } 2025-12-02 18:47:01.358873 | controller | changed: 2025-12-02 18:47:01.359028 | controller | { 2025-12-02 18:47:01.359054 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com" 2025-12-02 18:47:01.359076 | controller | } 2025-12-02 18:47:01.656533 | controller | changed: 2025-12-02 18:47:01.656717 | controller | { 2025-12-02 18:47:01.656761 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com" 2025-12-02 18:47:01.656791 | controller | } 2025-12-02 18:47:01.928485 | controller | changed: 2025-12-02 18:47:01.928811 | controller | { 2025-12-02 18:47:01.928869 | controller | "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com" 2025-12-02 18:47:01.928911 | controller | } 2025-12-02 18:47:02.223787 | controller | changed: 2025-12-02 18:47:02.223904 | controller | { 2025-12-02 18:47:02.223928 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith" 2025-12-02 18:47:02.223947 | controller | } 2025-12-02 18:47:02.546780 | controller | changed: 2025-12-02 18:47:02.547795 | controller | { 2025-12-02 18:47:02.548006 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel" 2025-12-02 18:47:02.548036 | controller | } 2025-12-02 18:47:02.804268 | controller | changed: 2025-12-02 18:47:02.804433 | controller | { 2025-12-02 18:47:02.804484 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux" 2025-12-02 18:47:02.804526 | controller | } 2025-12-02 18:47:03.092354 | controller | changed: 2025-12-02 18:47:03.092492 | controller | { 2025-12-02 18:47:03.092536 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr" 2025-12-02 18:47:03.092570 | controller | } 2025-12-02 18:47:03.402327 | controller | changed: 2025-12-02 18:47:03.402494 | controller | { 2025-12-02 18:47:03.402518 | 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-12-02 18:47:03.402538 | controller | } 2025-12-02 18:47:03.731092 | controller | changed: 2025-12-02 18:47:03.731308 | controller | { 2025-12-02 18:47:03.731363 | 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-12-02 18:47:03.731428 | controller | } 2025-12-02 18:47:04.029607 | controller | changed: 2025-12-02 18:47:04.030134 | controller | { 2025-12-02 18:47:04.030196 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9" 2025-12-02 18:47:04.030229 | controller | } 2025-12-02 18:47:04.317861 | controller | changed: 2025-12-02 18:47:04.318021 | controller | { 2025-12-02 18:47:04.318051 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora" 2025-12-02 18:47:04.318079 | controller | } 2025-12-02 18:47:04.627442 | controller | changed: 2025-12-02 18:47:04.627586 | controller | { 2025-12-02 18:47:04.627609 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac" 2025-12-02 18:47:04.627628 | controller | } 2025-12-02 18:47:04.896996 | controller | changed: 2025-12-02 18:47:04.897145 | controller | { 2025-12-02 18:47:04.897352 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop" 2025-12-02 18:47:04.897378 | controller | } 2025-12-02 18:47:05.157782 | controller | changed: 2025-12-02 18:47:05.158326 | controller | { 2025-12-02 18:47:05.158373 | 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-12-02 18:47:05.158405 | controller | } 2025-12-02 18:47:05.479266 | controller | changed: 2025-12-02 18:47:05.479489 | controller | { 2025-12-02 18:47:05.479515 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com" 2025-12-02 18:47:05.479534 | controller | } 2025-12-02 18:47:05.778413 | controller | changed: 2025-12-02 18:47:05.778645 | controller | { 2025-12-02 18:47:05.778733 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com" 2025-12-02 18:47:05.778787 | controller | } 2025-12-02 18:47:06.029952 | controller | changed: 2025-12-02 18:47:06.030158 | controller | { 2025-12-02 18:47:06.030222 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono" 2025-12-02 18:47:06.030273 | controller | } 2025-12-02 18:47:06.066768 | 2025-12-02 18:47:06.066937 | TASK [Set timezone to UTC] 2025-12-02 18:47:06.786372 | controller | changed: executed `/bin/timedatectl set-timezone UTC` 2025-12-02 18:47:06.792494 | 2025-12-02 18:47:06.792658 | TASK [Create nodepool directory] 2025-12-02 18:47:07.084983 | controller | changed 2025-12-02 18:47:07.092052 | 2025-12-02 18:47:07.092183 | TASK [Create nodepool sub_nodes file] 2025-12-02 18:47:07.958773 | controller | changed 2025-12-02 18:47:07.970269 | 2025-12-02 18:47:07.970380 | TASK [Create nodepool sub_nodes_private file] 2025-12-02 18:47:08.799367 | controller | changed 2025-12-02 18:47:08.805192 | 2025-12-02 18:47:08.805260 | LOOP [Populate nodepool sub_nodes file] 2025-12-02 18:47:08.847931 | 2025-12-02 18:47:08.848128 | LOOP [Populate nodepool sub_nodes_private file] 2025-12-02 18:47:08.890785 | 2025-12-02 18:47:08.891063 | TASK [Create nodepool primary file] 2025-12-02 18:47:08.926805 | controller | skipping: Conditional result was False 2025-12-02 18:47:08.934048 | 2025-12-02 18:47:08.934150 | TASK [Create nodepool node_private for this node] 2025-12-02 18:47:09.859136 | controller | changed 2025-12-02 18:47:09.869843 | 2025-12-02 18:47:09.870002 | LOOP [Copy ssh keys to nodepool directory] 2025-12-02 18:47:10.352139 | controller | ok: Item: id_rsa Runtime: 0:00:00.009007 2025-12-02 18:47:10.352401 | 2025-12-02 18:47:10.583879 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.008291 2025-12-02 18:47:10.593105 | 2025-12-02 18:47:10.593253 | TASK [Add sudoers role for zuul-sudo-grep.sh] 2025-12-02 18:47:11.534900 | controller | changed 2025-12-02 18:47:11.541100 | 2025-12-02 18:47:11.541230 | TASK [Validate sudoers config after edits] 2025-12-02 18:47:11.871098 | controller | /etc/sudoers: parsed OK 2025-12-02 18:47:11.871373 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK 2025-12-02 18:47:11.871400 | controller | /etc/sudoers.d/zuul: parsed OK 2025-12-02 18:47:11.871462 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK 2025-12-02 18:47:12.075527 | controller | ok: Runtime: 0:00:00.008031 2025-12-02 18:47:12.090658 | 2025-12-02 18:47:12.090878 | TASK [Show the environment passed in to job shell scripts] 2025-12-02 18:47:12.419841 | controller | SHELL=/bin/bash 2025-12-02 18:47:12.419952 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework 2025-12-02 18:47:12.419973 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework 2025-12-02 18:47:12.419990 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/11/3511/cc6f8b60f607e436d8eb4ff1e9a65ce10231ff40 2025-12-02 18:47:12.420005 | controller | PWD=/home/zuul 2025-12-02 18:47:12.420019 | controller | ZUUL_PIPELINE=github-check 2025-12-02 18:47:12.420032 | controller | LOGNAME=zuul 2025-12-02 18:47:12.420046 | controller | XDG_SESSION_TYPE=tty 2025-12-02 18:47:12.420059 | controller | _=/usr/bin/env 2025-12-02 18:47:12.420072 | controller | MOTD_SHOWN=pam 2025-12-02 18:47:12.420086 | controller | HOME=/home/zuul 2025-12-02 18:47:12.420099 | controller | LANG=en_US.UTF-8 2025-12-02 18:47:12.420131 | controller | SSH_CONNECTION=38.102.83.114 58206 38.102.83.30 22 2025-12-02 18:47:12.420223 | 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-12-02 18:47:12.420275 | controller | ZUUL_CHANGE_IDS=3511,cc6f8b60f607e436d8eb4ff1e9a65ce10231ff40 2025-12-02 18:47:12.420296 | controller | WORKSPACE=/home/zuul/workspace 2025-12-02 18:47:12.420310 | controller | XDG_SESSION_CLASS=user 2025-12-02 18:47:12.420324 | controller | SELINUX_ROLE_REQUESTED= 2025-12-02 18:47:12.420338 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s 2025-12-02 18:47:12.420351 | controller | USER=zuul 2025-12-02 18:47:12.420365 | controller | ZUUL_VOTING=True 2025-12-02 18:47:12.420378 | controller | BUILD_TIMEOUT=5400000 2025-12-02 18:47:12.420392 | controller | SELINUX_USE_CURRENT_RANGE= 2025-12-02 18:47:12.420405 | controller | SHLVL=1 2025-12-02 18:47:12.420418 | controller | ZUUL_PATCHSET=cc6f8b60f607e436d8eb4ff1e9a65ce10231ff40 2025-12-02 18:47:12.420432 | controller | XDG_SESSION_ID=1 2025-12-02 18:47:12.420445 | controller | ZUUL_BRANCH=main 2025-12-02 18:47:12.420458 | controller | XDG_RUNTIME_DIR=/run/user/1000 2025-12-02 18:47:12.420471 | controller | SSH_CLIENT=38.102.83.114 58206 22 2025-12-02 18:47:12.420485 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/ 2025-12-02 18:47:12.420499 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima: 2025-12-02 18:47:12.420512 | controller | which_declare=declare -f 2025-12-02 18:47:12.420526 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin 2025-12-02 18:47:12.420540 | controller | SELINUX_LEVEL_REQUESTED= 2025-12-02 18:47:12.420553 | controller | ZUUL_CHANGE=3511 2025-12-02 18:47:12.420566 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus 2025-12-02 18:47:12.420579 | controller | ZUUL_UUID=b938e9b6d9194a2583565d20f80b17c5 2025-12-02 18:47:12.420593 | controller | BASH_FUNC_which%%=() { ( alias; 2025-12-02 18:47:12.420606 | controller | eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@ 2025-12-02 18:47:12.420620 | controller | } 2025-12-02 18:47:12.632412 | controller | ok: Runtime: 0:00:00.012314 2025-12-02 18:47:12.646014 | 2025-12-02 18:47:12.646269 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars] 2025-12-02 18:47:12.684113 | controller | skipping: Conditional result was False 2025-12-02 18:47:12.691238 | 2025-12-02 18:47:12.691398 | TASK [Symlink /home/zuul-worker/workspace] 2025-12-02 18:47:13.282431 | controller | skipping: Conditional result was False 2025-12-02 18:47:13.302108 | 2025-12-02 18:47:13.302361 | TASK [Ensure legacy workspace directory] 2025-12-02 18:47:13.608121 | controller | changed 2025-12-02 18:47:13.631564 | 2025-12-02 18:47:13.631799 | PLAY RECAP 2025-12-02 18:47:13.631848 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0 2025-12-02 18:47:13.631870 | 2025-12-02 18:47:13.821623 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master] 2025-12-02 18:47:13.823127 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/crc/podman.yaml@master] 2025-12-02 18:47:14.493718 | 2025-12-02 18:47:14.493969 | PLAY [all,!compute] 2025-12-02 18:47:14.516821 | 2025-12-02 18:47:14.516984 | TASK [Enable container-tools rhel-modules for c8/crc node] 2025-12-02 18:47:15.062888 | controller | skipping: Conditional result was False 2025-12-02 18:47:15.079202 | 2025-12-02 18:47:15.079412 | TASK [Install podman] 2025-12-02 18:48:21.415543 | controller | changed 2025-12-02 18:48:21.425534 | 2025-12-02 18:48:21.425672 | TASK [Set var name for quay login zuul secret] 2025-12-02 18:48:21.510559 | controller | skipping: Conditional result was False 2025-12-02 18:48:21.519989 | 2025-12-02 18:48:21.520104 | TASK [Print the username] 2025-12-02 18:48:22.027237 | controller | openstack-k8s-operators+cirobot 2025-12-02 18:48:22.067064 | controller | ok: Runtime: 0:00:00.005934 2025-12-02 18:48:22.073122 | 2025-12-02 18:48:22.073196 | TASK [Fail when user or password is not set] 2025-12-02 18:48:22.153353 | controller | skipping: Conditional result was False 2025-12-02 18:48:22.162235 | 2025-12-02 18:48:22.162332 | TASK [Set vars for quay login] 2025-12-02 18:48:22.256500 | controller | Output suppressed because no_log was given 2025-12-02 18:48:22.274743 | 2025-12-02 18:48:22.274923 | LOOP [Perform container registry login(s) with podman] 2025-12-02 18:48:23.097016 | controller | Output suppressed because no_log was given 2025-12-02 18:48:23.104652 | 2025-12-02 18:48:23.104772 | LOOP [Perform container registry login(s) with buildah] 2025-12-02 18:48:23.742420 | controller | Output suppressed because no_log was given 2025-12-02 18:48:23.750136 | 2025-12-02 18:48:23.750275 | TASK [Set Insecure registry for content provider] 2025-12-02 18:48:23.837767 | controller | skipping: Conditional result was False 2025-12-02 18:48:23.857080 | 2025-12-02 18:48:23.857166 | PLAY RECAP 2025-12-02 18:48:23.857211 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2025-12-02 18:48:23.857233 | 2025-12-02 18:48:23.996556 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/crc/podman.yaml@master] 2025-12-02 18:48:24.001128 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main] 2025-12-02 18:48:24.681413 | 2025-12-02 18:48:24.681546 | PLAY [Run ci/playbooks/molecule-prepare.yml] 2025-12-02 18:48:24.707054 | 2025-12-02 18:48:24.707212 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh] 2025-12-02 18:48:24.726061 | controller | ok 2025-12-02 18:48:24.743550 | 2025-12-02 18:48:24.743635 | TASK [mirror-info-fork : Set mirror_fqdn if not defined] 2025-12-02 18:48:24.788153 | controller | skipping: Conditional result was False 2025-12-02 18:48:24.796246 | 2025-12-02 18:48:24.796343 | TASK [mirror-info-fork : Create /etc/ci] 2025-12-02 18:48:25.231026 | controller | ok 2025-12-02 18:48:25.243526 | 2025-12-02 18:48:25.243672 | TASK [mirror-info-fork : Install ci_mirror script] 2025-12-02 18:48:26.221877 | controller | ok 2025-12-02 18:48:26.236183 | 2025-12-02 18:48:26.236334 | TASK [Prepare workspace] 2025-12-02 18:48:26.262534 | controller | ok 2025-12-02 18:48:26.288487 | 2025-12-02 18:48:26.288702 | TASK [prepare-workspace : Start zuul_console daemon.] 2025-12-02 18:48:26.776074 | controller | ok 2025-12-02 18:48:26.785743 | 2025-12-02 18:48:26.785890 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2025-12-02 18:48:29.447503 | controller | Output suppressed because no_log was given 2025-12-02 18:48:29.461819 | 2025-12-02 18:48:29.461963 | LOOP [Create zuul-output directory] 2025-12-02 18:48:29.743201 | controller | changed: "/home/zuul/ci-framework-data/logs" 2025-12-02 18:48:29.970106 | controller | ok: "/home/zuul/zuul-output/logs" 2025-12-02 18:48:29.977049 | 2025-12-02 18:48:29.977127 | TASK [Install required packages] 2025-12-02 18:48:31.269706 | controller | ok: Nothing to do 2025-12-02 18:48:31.281577 | 2025-12-02 18:48:31.281801 | TASK [Install venv] 2025-12-02 18:49:41.462945 | controller | changed 2025-12-02 18:49:41.520875 | 2025-12-02 18:49:41.521022 | PLAY RECAP 2025-12-02 18:49:41.521103 | controller | ok: 7 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2025-12-02 18:49:41.521144 | 2025-12-02 18:49:41.697611 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main] 2025-12-02 18:49:41.700442 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main] 2025-12-02 18:49:42.362852 | 2025-12-02 18:49:42.362983 | PLAY [Run ci/playbooks/dump_zuul_data.yml] 2025-12-02 18:49:42.385309 | 2025-12-02 18:49:42.385419 | TASK [Create zuul-output directory] 2025-12-02 18:49:42.868996 | controller | changed 2025-12-02 18:49:42.877645 | 2025-12-02 18:49:42.877840 | TASK [Slurp Zuul inventory test] 2025-12-02 18:49:43.178500 | controller -> localhost | ok 2025-12-02 18:49:43.185753 | 2025-12-02 18:49:43.185850 | TASK [Save zuul inventory] 2025-12-02 18:49:44.337348 | controller | changed 2025-12-02 18:49:44.359756 | 2025-12-02 18:49:44.359954 | TASK [Save zuul vars without the change_message] 2025-12-02 18:49:45.349949 | controller | changed 2025-12-02 18:49:45.371738 | 2025-12-02 18:49:45.371861 | PLAY RECAP 2025-12-02 18:49:45.371906 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-12-02 18:49:45.371927 | 2025-12-02 18:49:45.523245 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main] 2025-12-02 18:49:45.524504 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/build_push_container_runner.yml@main] 2025-12-02 18:49:46.158443 | 2025-12-02 18:49:46.158568 | PLAY [Run build_push_container role] 2025-12-02 18:49:46.180530 | 2025-12-02 18:49:46.180656 | TASK [Run build_push_container role] 2025-12-02 18:49:47.199551 | controller | 2025-12-02 18:49:48.290100 | controller | PLAY [Run build_push_container role] ******************************************* 2025-12-02 18:49:48.290321 | controller | 2025-12-02 18:49:48.290348 | controller | TASK [Gathering Facts ] ******************************************************** 2025-12-02 18:49:48.290362 | controller | Tuesday 02 December 2025 18:49:47 +0000 (0:00:00.049) 0:00:00.049 ****** 2025-12-02 18:49:48.290376 | controller | Tuesday 02 December 2025 18:49:47 +0000 (0:00:00.048) 0:00:00.048 ****** 2025-12-02 18:49:48.290403 | controller | ok: [localhost] 2025-12-02 18:49:48.351382 | controller | 2025-12-02 18:49:48.351434 | controller | TASK [Run build_push_container role name=build_push_container] ***************** 2025-12-02 18:49:48.351451 | controller | Tuesday 02 December 2025 18:49:48 +0000 (0:00:01.089) 0:00:01.139 ****** 2025-12-02 18:49:48.351464 | controller | Tuesday 02 December 2025 18:49:48 +0000 (0:00:01.090) 0:00:01.138 ****** 2025-12-02 18:49:48.351486 | controller | 2025-12-02 18:49:48.969140 | controller | TASK [build_push_container : Ensure directories are present path={{ item }}, state=directory, mode=0755, recurse=True] *** 2025-12-02 18:49:48.969209 | controller | Tuesday 02 December 2025 18:49:48 +0000 (0:00:00.061) 0:00:01.201 ****** 2025-12-02 18:49:48.969226 | controller | Tuesday 02 December 2025 18:49:48 +0000 (0:00:00.061) 0:00:01.200 ****** 2025-12-02 18:49:48.969290 | controller | changed: [localhost] => (item=/home/zuul/ci-framework-data/artifacts/build_push_container/scripts) 2025-12-02 18:49:49.977391 | controller | changed: [localhost] => (item=/home/zuul/ci-framework-data/artifacts/build_push_container/logs) 2025-12-02 18:49:49.977481 | controller | 2025-12-02 18:49:49.977499 | controller | TASK [podman : Ensure podman is installed name={{ cifmw_podman_packages }}, state=present] *** 2025-12-02 18:49:49.977513 | controller | Tuesday 02 December 2025 18:49:48 +0000 (0:00:00.616) 0:00:01.818 ****** 2025-12-02 18:49:49.977528 | controller | Tuesday 02 December 2025 18:49:48 +0000 (0:00:00.616) 0:00:01.817 ****** 2025-12-02 18:49:49.977563 | controller | ok: [localhost] 2025-12-02 18:49:50.468893 | controller | 2025-12-02 18:49:50.468972 | controller | TASK [podman : Enable loginctl linger for ansible_user_id _raw_params=loginctl enable-linger {{ cifmw_podman_user_linger }}] *** 2025-12-02 18:49:50.468989 | controller | Tuesday 02 December 2025 18:49:49 +0000 (0:00:01.008) 0:00:02.826 ****** 2025-12-02 18:49:50.469002 | controller | Tuesday 02 December 2025 18:49:49 +0000 (0:00:01.008) 0:00:02.825 ****** 2025-12-02 18:49:50.469027 | controller | changed: [localhost] 2025-12-02 18:49:50.508878 | controller | 2025-12-02 18:49:50.508941 | 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-12-02 18:49:50.508964 | controller | Tuesday 02 December 2025 18:49:50 +0000 (0:00:00.492) 0:00:03.319 ****** 2025-12-02 18:49:50.508977 | controller | Tuesday 02 December 2025 18:49:50 +0000 (0:00:00.492) 0:00:03.318 ****** 2025-12-02 18:49:50.509002 | controller | skipping: [localhost] 2025-12-02 18:49:50.546725 | controller | 2025-12-02 18:49:50.546779 | 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-12-02 18:49:50.546791 | controller | Tuesday 02 December 2025 18:49:50 +0000 (0:00:00.040) 0:00:03.359 ****** 2025-12-02 18:49:50.546801 | controller | Tuesday 02 December 2025 18:49:50 +0000 (0:00:00.040) 0:00:03.358 ****** 2025-12-02 18:49:50.546819 | controller | skipping: [localhost] 2025-12-02 18:49:50.574852 | controller | 2025-12-02 18:49:50.574898 | controller | TASK [podman : Run podman system migrate if subuid/subgid files were changed _raw_params=podman system migrate] *** 2025-12-02 18:49:50.574906 | controller | Tuesday 02 December 2025 18:49:50 +0000 (0:00:00.037) 0:00:03.397 ****** 2025-12-02 18:49:50.574913 | controller | Tuesday 02 December 2025 18:49:50 +0000 (0:00:00.037) 0:00:03.395 ****** 2025-12-02 18:49:50.574933 | controller | skipping: [localhost] 2025-12-02 18:49:59.250610 | controller | 2025-12-02 18:49:59.250686 | 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-12-02 18:49:59.250728 | controller | Tuesday 02 December 2025 18:49:50 +0000 (0:00:00.028) 0:00:03.425 ****** 2025-12-02 18:49:59.250743 | controller | Tuesday 02 December 2025 18:49:50 +0000 (0:00:00.028) 0:00:03.424 ****** 2025-12-02 18:49:59.250767 | 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-12-02 18:49:59.591164 | controller | changed: [localhost] 2025-12-02 18:49:59.591284 | controller | 2025-12-02 18:49:59.591307 | controller | TASK [build_push_container : Ensure manifest doesn't exist _raw_params=podman manifest rm {{ _container_manifest_name }}] *** 2025-12-02 18:49:59.591322 | controller | Tuesday 02 December 2025 18:49:59 +0000 (0:00:08.675) 0:00:12.101 ****** 2025-12-02 18:49:59.591337 | controller | Tuesday 02 December 2025 18:49:59 +0000 (0:00:08.675) 0:00:12.099 ****** 2025-12-02 18:49:59.591374 | controller | ok: [localhost] 2025-12-02 18:49:59.956896 | controller | 2025-12-02 18:49:59.956978 | controller | TASK [build_push_container : Create manifest _raw_params=podman manifest create {{ _container_manifest_name }}] *** 2025-12-02 18:49:59.956989 | controller | Tuesday 02 December 2025 18:49:59 +0000 (0:00:00.340) 0:00:12.441 ****** 2025-12-02 18:49:59.956999 | controller | Tuesday 02 December 2025 18:49:59 +0000 (0:00:00.340) 0:00:12.439 ****** 2025-12-02 18:49:59.957020 | controller | changed: [localhost] 2025-12-02 19:05:16.042125 | controller | 2025-12-02 19:05:16.042303 | controller | TASK [build_push_container : Build container output_dir={{ (cifmw_build_push_container_artifacts, 'scripts') | ansible.builtin.path_join }}, script={{ _podman_build_cmd }}] *** 2025-12-02 19:05:16.042350 | controller | Tuesday 02 December 2025 18:49:59 +0000 (0:00:00.365) 0:00:12.806 ****** 2025-12-02 19:05:16.042369 | controller | Tuesday 02 December 2025 18:49:59 +0000 (0:00:00.365) 0:00:12.805 ****** 2025-12-02 19:05:16.042407 | controller | Follow script's output here: /home/zuul/ci-framework-data/artifacts/build_push_container/logs/ci_script_001_build.log 2025-12-02 19:05:45.232065 | controller | changed: [localhost] 2025-12-02 19:05:45.232166 | controller | 2025-12-02 19:05:45.232190 | 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-12-02 19:05:45.232215 | controller | Tuesday 02 December 2025 19:05:16 +0000 (0:15:16.084) 0:15:28.891 ****** 2025-12-02 19:05:45.232233 | controller | Tuesday 02 December 2025 19:05:16 +0000 (0:15:16.084) 0:15:28.890 ****** 2025-12-02 19:05:45.232308 | controller | Follow script's output here: /home/zuul/ci-framework-data/artifacts/build_push_container/logs/ci_script_002_push.log 2025-12-02 19:05:45.343337 | controller | changed: [localhost] => (item=cc6f8b60f607e436d8eb4ff1e9a65ce10231ff40) 2025-12-02 19:05:45.343388 | controller | Follow script's output here: /home/zuul/ci-framework-data/artifacts/build_push_container/logs/ci_script_003_push.log 2025-12-02 19:05:45.343402 | controller | changed: [localhost] => (item=3511-latest) 2025-12-02 19:05:45.343416 | controller | 2025-12-02 19:05:45.343426 | 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-12-02 19:05:45.343435 | controller | Tuesday 02 December 2025 19:05:45 +0000 (0:00:29.190) 0:15:58.082 ****** 2025-12-02 19:05:45.343445 | controller | Tuesday 02 December 2025 19:05:45 +0000 (0:00:29.190) 0:15:58.080 ****** 2025-12-02 19:05:45.343466 | controller | skipping: [localhost] => (item=cc6f8b60f607e436d8eb4ff1e9a65ce10231ff40) 2025-12-02 19:05:45.425098 | controller | skipping: [localhost] => (item=3511-latest) 2025-12-02 19:05:45.425157 | controller | skipping: [localhost] 2025-12-02 19:05:45.425165 | controller | 2025-12-02 19:05:45.425172 | controller | PLAY RECAP ********************************************************************* 2025-12-02 19:05:45.425179 | controller | localhost : ok=9 changed=6 unreachable=0 failed=0 skipped=4 rescued=0 ignored=0 2025-12-02 19:05:45.425185 | controller | 2025-12-02 19:05:45.425191 | controller | Tuesday 02 December 2025 19:05:45 +0000 (0:00:00.111) 0:15:58.193 ****** 2025-12-02 19:05:45.425210 | controller | =============================================================================== 2025-12-02 19:05:45.425216 | controller | build_push_container : Build container -------------------------------- 916.08s 2025-12-02 19:05:45.425222 | controller | build_push_container : Push manifest ----------------------------------- 29.19s 2025-12-02 19:05:45.425228 | controller | build_push_container : Install qemu-user-static for multi arch builds --- 8.68s 2025-12-02 19:05:45.425234 | controller | Gathering Facts --------------------------------------------------------- 1.09s 2025-12-02 19:05:45.425240 | controller | podman : Ensure podman is installed ------------------------------------- 1.01s 2025-12-02 19:05:45.425268 | controller | build_push_container : Ensure directories are present ------------------- 0.62s 2025-12-02 19:05:45.425277 | controller | podman : Enable loginctl linger for ansible_user_id --------------------- 0.49s 2025-12-02 19:05:45.425284 | controller | build_push_container : Create manifest ---------------------------------- 0.37s 2025-12-02 19:05:45.425293 | controller | build_push_container : Ensure manifest doesn't exist -------------------- 0.34s 2025-12-02 19:05:45.425299 | controller | build_push_container : Push container ----------------------------------- 0.11s 2025-12-02 19:05:45.425305 | controller | Run build_push_container role ------------------------------------------- 0.06s 2025-12-02 19:05:45.425312 | controller | podman : Ensure subordinate UID entry exists for zuul ------------------- 0.04s 2025-12-02 19:05:45.425318 | controller | podman : Ensure subordinate GID entry exists for zuul ------------------- 0.04s 2025-12-02 19:05:45.425323 | controller | podman : Run podman system migrate if subuid/subgid files were changed --- 0.03s 2025-12-02 19:05:45.425330 | controller | Tuesday 02 December 2025 19:05:45 +0000 (0:00:00.112) 0:15:58.193 ****** 2025-12-02 19:05:45.425336 | controller | =============================================================================== 2025-12-02 19:05:45.425342 | controller | build_push_container -------------------------------------------------- 955.39s 2025-12-02 19:05:45.425348 | controller | podman ------------------------------------------------------------------ 1.61s 2025-12-02 19:05:45.425354 | controller | gather_facts ------------------------------------------------------------ 1.09s 2025-12-02 19:05:45.425360 | controller | ansible.builtin.include_role -------------------------------------------- 0.06s 2025-12-02 19:05:45.425366 | controller | ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~ 2025-12-02 19:05:45.425372 | controller | total ----------------------------------------------------------------- 958.14s 2025-12-02 19:05:45.624558 | controller | changed 2025-12-02 19:05:45.667151 | 2025-12-02 19:05:45.667305 | PLAY RECAP 2025-12-02 19:05:45.667380 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-12-02 19:05:45.667422 | 2025-12-02 19:05:45.783197 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/build_push_container_runner.yml@main] 2025-12-02 19:05:45.785433 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main] 2025-12-02 19:05:46.390911 | 2025-12-02 19:05:46.391031 | PLAY [Run ci/playbooks/collect-logs.yml] 2025-12-02 19:05:46.411705 | 2025-12-02 19:05:46.411782 | TASK [Filter out host if needed] 2025-12-02 19:05:46.420304 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller 2025-12-02 19:05:46.424848 | 2025-12-02 19:05:46.424911 | TASK [Ensure file is present] 2025-12-02 19:05:46.881788 | controller | ok 2025-12-02 19:05:46.893587 | 2025-12-02 19:05:46.893760 | TASK [Manage molecule report file] 2025-12-02 19:05:47.442747 | controller | skipping: Conditional result was False 2025-12-02 19:05:47.455395 | 2025-12-02 19:05:47.455534 | TASK [Check if we get ci-framework-data basedir] 2025-12-02 19:05:47.778351 | controller | ok 2025-12-02 19:05:47.789807 | 2025-12-02 19:05:47.789942 | TASK [Create ci-framework-data log directory for zuul] 2025-12-02 19:05:48.248388 | controller | changed 2025-12-02 19:05:48.260192 | 2025-12-02 19:05:48.260328 | TASK [Copy ci-framework interesting files] 2025-12-02 19:05:48.735570 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory 2025-12-02 19:05:48.808224 | controller | changed 2025-12-02 19:05:48.818861 | 2025-12-02 19:05:48.818993 | TASK [Get SELinux listing] 2025-12-02 19:05:49.370731 | controller | changed 2025-12-02 19:05:49.382983 | 2025-12-02 19:05:49.383120 | TASK [Generate log index] 2025-12-02 19:05:50.453737 | controller | changed 2025-12-02 19:05:50.464669 | 2025-12-02 19:05:50.464850 | TASK [Get some env related data] 2025-12-02 19:05:51.226917 | controller | /home/zuul/.local/bin/ansible 2025-12-02 19:05:52.019602 | controller | changed 2025-12-02 19:05:52.031732 | 2025-12-02 19:05:52.031866 | TASK [Generate list of logs to collect in home directory] 2025-12-02 19:05:52.417833 | controller | ok: All paths examined 2025-12-02 19:05:52.430245 | 2025-12-02 19:05:52.430378 | LOOP [Copy logs from home directory] 2025-12-02 19:05:52.919213 | controller | changed: 2025-12-02 19:05:52.919431 | controller | { 2025-12-02 19:05:52.919484 | controller | "atime": 1764701363.3663776, 2025-12-02 19:05:52.919524 | controller | "ctime": 1764702345.3421962, 2025-12-02 19:05:52.919562 | controller | "dev": 64513, 2025-12-02 19:05:52.919597 | controller | "gid": 1000, 2025-12-02 19:05:52.919634 | controller | "gr_name": "zuul", 2025-12-02 19:05:52.919671 | controller | "inode": 4330727, 2025-12-02 19:05:52.919774 | controller | "isblk": false, 2025-12-02 19:05:52.919812 | controller | "ischr": false, 2025-12-02 19:05:52.919846 | controller | "isdir": false, 2025-12-02 19:05:52.919880 | controller | "isfifo": false, 2025-12-02 19:05:52.919913 | controller | "isgid": false, 2025-12-02 19:05:52.919946 | controller | "islnk": false, 2025-12-02 19:05:52.919979 | controller | "isreg": true, 2025-12-02 19:05:52.920012 | controller | "issock": false, 2025-12-02 19:05:52.920045 | controller | "isuid": false, 2025-12-02 19:05:52.920078 | controller | "mode": "0644", 2025-12-02 19:05:52.920111 | controller | "mtime": 1764702345.3421962, 2025-12-02 19:05:52.920145 | controller | "nlink": 1, 2025-12-02 19:05:52.920180 | controller | "path": "/home/zuul/ansible.log", 2025-12-02 19:05:52.920217 | controller | "pw_name": "zuul", 2025-12-02 19:05:52.920252 | controller | "rgrp": true, 2025-12-02 19:05:52.920458 | controller | "roth": true, 2025-12-02 19:05:52.920509 | controller | "rusr": true, 2025-12-02 19:05:52.920545 | controller | "size": 18992, 2025-12-02 19:05:52.920579 | controller | "uid": 1000, 2025-12-02 19:05:52.920613 | controller | "wgrp": false, 2025-12-02 19:05:52.920647 | controller | "woth": false, 2025-12-02 19:05:52.920754 | controller | "wusr": true, 2025-12-02 19:05:52.920800 | controller | "xgrp": false, 2025-12-02 19:05:52.920837 | controller | "xoth": false, 2025-12-02 19:05:52.920872 | controller | "xusr": false 2025-12-02 19:05:52.920905 | controller | } 2025-12-02 19:05:52.944005 | 2025-12-02 19:05:52.944196 | TASK [Copy crio stats log file] 2025-12-02 19:05:52.973302 | controller | skipping: Conditional result was False 2025-12-02 19:05:52.986326 | 2025-12-02 19:05:52.986466 | TASK [Get SELinux related data] 2025-12-02 19:05:53.304038 | controller | 2025-12-02 19:05:53.536420 | controller | ERROR 2025-12-02 19:05:53.536652 | controller | { 2025-12-02 19:05:53.536781 | controller | "delta": "0:00:00.010197", 2025-12-02 19:05:53.536833 | controller | "end": "2025-12-02 19:05:53.305141", 2025-12-02 19:05:53.536886 | controller | "msg": "non-zero return code", 2025-12-02 19:05:53.536923 | controller | "rc": 1, 2025-12-02 19:05:53.536958 | controller | "start": "2025-12-02 19:05:53.294944" 2025-12-02 19:05:53.536992 | controller | } 2025-12-02 19:05:53.537038 | controller | ERROR: Ignoring Errors 2025-12-02 19:05:53.548621 | 2025-12-02 19:05:53.548818 | TASK [Create system configuration directory] 2025-12-02 19:05:53.832197 | controller | changed 2025-12-02 19:05:53.843198 | 2025-12-02 19:05:53.843323 | TASK [Get some of the system configurations] 2025-12-02 19:05:54.394096 | controller | changed 2025-12-02 19:05:54.405493 | 2025-12-02 19:05:54.405622 | TASK [Copy generated documentation if available] 2025-12-02 19:05:54.433253 | controller | skipping: Conditional result was False 2025-12-02 19:05:54.446208 | 2025-12-02 19:05:54.446336 | TASK [Copy generated AsciiDoc documentation if available] 2025-12-02 19:05:54.474336 | controller | skipping: Conditional result was False 2025-12-02 19:05:54.487192 | 2025-12-02 19:05:54.487322 | TASK [Compress logs bigger than 2MB] 2025-12-02 19:05:55.035020 | controller | changed 2025-12-02 19:05:55.047942 | 2025-12-02 19:05:55.048092 | TASK [Copy files from workspace on node] 2025-12-02 19:05:55.081768 | controller | ok 2025-12-02 19:05:55.121381 | 2025-12-02 19:05:55.121485 | TASK [fetch-output : Set log path for multiple nodes] 2025-12-02 19:05:55.147619 | controller | skipping: Conditional result was False 2025-12-02 19:05:55.158409 | 2025-12-02 19:05:55.158507 | TASK [fetch-output : Set log path for single node] 2025-12-02 19:05:55.205529 | controller | ok 2025-12-02 19:05:55.216871 | 2025-12-02 19:05:55.217006 | LOOP [fetch-output : Ensure local output dirs] 2025-12-02 19:05:55.505694 | controller -> localhost | ok: "/var/lib/zuul/builds/b938e9b6d9194a2583565d20f80b17c5/work/logs" 2025-12-02 19:05:55.506026 | controller -> localhost | changed: All items complete 2025-12-02 19:05:55.506054 | 2025-12-02 19:05:55.742813 | controller -> localhost | changed: "/var/lib/zuul/builds/b938e9b6d9194a2583565d20f80b17c5/work/artifacts" 2025-12-02 19:05:55.962124 | controller -> localhost | changed: "/var/lib/zuul/builds/b938e9b6d9194a2583565d20f80b17c5/work/docs" 2025-12-02 19:05:55.972097 | 2025-12-02 19:05:55.972213 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-12-02 19:05:56.655643 | controller | changed: 2025-12-02 19:05:56.658726 | controller | .d..t...... ./ 2025-12-02 19:05:56.658811 | controller | >f+++++++++ README.html 2025-12-02 19:05:56.658836 | controller | >f+++++++++ ansible.log 2025-12-02 19:05:56.658856 | controller | >f+++++++++ dmesg.log 2025-12-02 19:05:56.658874 | controller | >f+++++++++ installed-pkgs.log 2025-12-02 19:05:56.658892 | controller | >f+++++++++ python.log 2025-12-02 19:05:56.658912 | controller | >f+++++++++ registries.conf 2025-12-02 19:05:56.658931 | controller | >f+++++++++ selinux-denials.log 2025-12-02 19:05:56.658949 | controller | >f+++++++++ selinux-listing.log 2025-12-02 19:05:56.658968 | controller | cd+++++++++ ci-framework-data/ 2025-12-02 19:05:56.658986 | controller | cd+++++++++ ci-framework-data/artifacts/ 2025-12-02 19:05:56.659004 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml 2025-12-02 19:05:56.659021 | controller | cd+++++++++ ci-framework-data/artifacts/build_push_container/ 2025-12-02 19:05:56.659038 | controller | cd+++++++++ ci-framework-data/artifacts/build_push_container/logs/ 2025-12-02 19:05:56.659056 | controller | >f+++++++++ ci-framework-data/artifacts/build_push_container/logs/ci_script_000_install_qemu_user_static_for.log 2025-12-02 19:05:56.659073 | controller | >f+++++++++ ci-framework-data/artifacts/build_push_container/logs/ci_script_001_build.log 2025-12-02 19:05:56.659090 | controller | >f+++++++++ ci-framework-data/artifacts/build_push_container/logs/ci_script_002_push.log 2025-12-02 19:05:56.659107 | controller | >f+++++++++ ci-framework-data/artifacts/build_push_container/logs/ci_script_003_push.log 2025-12-02 19:05:56.659131 | controller | cd+++++++++ ci-framework-data/artifacts/build_push_container/scripts/ 2025-12-02 19:05:56.659148 | controller | >f+++++++++ ci-framework-data/artifacts/build_push_container/scripts/ci_script_000_install_qemu_user_static_for.sh 2025-12-02 19:05:56.659164 | controller | >f+++++++++ ci-framework-data/artifacts/build_push_container/scripts/ci_script_001_build.sh 2025-12-02 19:05:56.659180 | controller | >f+++++++++ ci-framework-data/artifacts/build_push_container/scripts/ci_script_002_push.sh 2025-12-02 19:05:56.659196 | controller | >f+++++++++ ci-framework-data/artifacts/build_push_container/scripts/ci_script_003_push.sh 2025-12-02 19:05:56.659213 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/ 2025-12-02 19:05:56.659229 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml 2025-12-02 19:05:56.659247 | controller | cd+++++++++ ci-framework-data/logs/ 2025-12-02 19:05:56.659263 | controller | cd+++++++++ registries.conf.d/ 2025-12-02 19:05:56.659280 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf 2025-12-02 19:05:56.659297 | controller | cd+++++++++ system-config/ 2025-12-02 19:05:56.659313 | controller | cd+++++++++ system-config/libvirt/ 2025-12-02 19:05:56.659329 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf 2025-12-02 19:05:56.659346 | controller | >f+++++++++ system-config/libvirt/libvirt.conf 2025-12-02 19:05:57.176273 | controller | changed: .d..t...... ./ 2025-12-02 19:05:57.708407 | controller | changed: .d..t...... ./ 2025-12-02 19:05:57.736822 | 2025-12-02 19:05:57.737134 | TASK [Return artifact to Zuul] 2025-12-02 19:05:57.775195 | controller | skipping: Conditional result was False 2025-12-02 19:05:57.804892 | 2025-12-02 19:05:57.805022 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host] 2025-12-02 19:05:57.805147 | 2025-12-02 19:05:57.805188 | PLAY RECAP 2025-12-02 19:05:57.805240 | controller | ok: 16 changed: 12 unreachable: 0 failed: 0 skipped: 6 rescued: 0 ignored: 1 2025-12-02 19:05:57.805267 | 2025-12-02 19:05:57.949846 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main] 2025-12-02 19:05:57.952006 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2025-12-02 19:05:58.536256 | 2025-12-02 19:05:58.536383 | PLAY [all] 2025-12-02 19:05:58.555265 | 2025-12-02 19:05:58.555391 | TASK [include_role : fetch-output] 2025-12-02 19:05:58.597213 | controller | ok 2025-12-02 19:05:58.613250 | 2025-12-02 19:05:58.613325 | TASK [fetch-output : Set log path for multiple nodes] 2025-12-02 19:05:58.689106 | controller | skipping: Conditional result was False 2025-12-02 19:05:58.701030 | 2025-12-02 19:05:58.701173 | TASK [fetch-output : Set log path for single node] 2025-12-02 19:05:58.748869 | controller | ok 2025-12-02 19:05:58.759675 | 2025-12-02 19:05:58.759847 | LOOP [fetch-output : Ensure local output dirs] 2025-12-02 19:05:59.193567 | controller -> localhost | ok: "/var/lib/zuul/builds/b938e9b6d9194a2583565d20f80b17c5/work/logs" 2025-12-02 19:05:59.479876 | controller -> localhost | ok: "/var/lib/zuul/builds/b938e9b6d9194a2583565d20f80b17c5/work/artifacts" 2025-12-02 19:05:59.720384 | controller -> localhost | ok: "/var/lib/zuul/builds/b938e9b6d9194a2583565d20f80b17c5/work/docs" 2025-12-02 19:05:59.741217 | 2025-12-02 19:05:59.741443 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-12-02 19:06:00.421445 | controller | ok 2025-12-02 19:06:00.421883 | controller | ok: All items complete 2025-12-02 19:06:00.421948 | 2025-12-02 19:06:00.955164 | controller | ok 2025-12-02 19:06:01.520355 | controller | ok 2025-12-02 19:06:01.553505 | 2025-12-02 19:06:01.553649 | TASK [include_role : fetch-output-openshift] 2025-12-02 19:06:01.581729 | controller | skipping: Conditional result was False 2025-12-02 19:06:01.596519 | 2025-12-02 19:06:01.596669 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-12-02 19:06:02.052992 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.011519 2025-12-02 19:06:02.296459 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.010130 2025-12-02 19:06:02.353847 | 2025-12-02 19:06:02.353948 | PLAY [all] 2025-12-02 19:06:02.373369 | 2025-12-02 19:06:02.373460 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-12-02 19:06:02.942612 | controller | changed 2025-12-02 19:06:02.989338 | 2025-12-02 19:06:02.989406 | PLAY RECAP 2025-12-02 19:06:02.989458 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2025-12-02 19:06:02.989486 | 2025-12-02 19:06:03.126774 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2025-12-02 19:06:03.128230 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master] 2025-12-02 19:06:03.756973 | 2025-12-02 19:06:03.757094 | PLAY [localhost] 2025-12-02 19:06:03.774516 | 2025-12-02 19:06:03.774594 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-12-02 19:06:04.215920 | localhost | changed 2025-12-02 19:06:04.226009 | 2025-12-02 19:06:04.226179 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-12-02 19:06:04.261990 | localhost | ok 2025-12-02 19:06:04.279612 | 2025-12-02 19:06:04.279786 | TASK [add-fileserver : Create SSH private key tempfile] 2025-12-02 19:06:04.663613 | localhost | changed 2025-12-02 19:06:04.673960 | 2025-12-02 19:06:04.674105 | TASK [add-fileserver : Create SSH private key from secret] 2025-12-02 19:06:05.411196 | localhost | changed 2025-12-02 19:06:05.422171 | 2025-12-02 19:06:05.422313 | TASK [add-fileserver : Add fileserver ssh key] 2025-12-02 19:06:05.855452 | localhost | Identity added: /var/lib/zuul/builds/b938e9b6d9194a2583565d20f80b17c5/work/tmp/ansible.6xjx87lv (/var/lib/zuul/builds/b938e9b6d9194a2583565d20f80b17c5/work/tmp/ansible.6xjx87lv) 2025-12-02 19:06:05.855830 | localhost | ok: Runtime: 0:00:00.007369 2025-12-02 19:06:05.865356 | 2025-12-02 19:06:05.865495 | TASK [add-fileserver : Remove SSH private key from disk] 2025-12-02 19:06:06.137514 | localhost | ok: Runtime: 0:00:00.009101 2025-12-02 19:06:06.148421 | 2025-12-02 19:06:06.148578 | TASK [add-fileserver : Add fileserver to inventory] 2025-12-02 19:06:06.205074 | localhost | changed 2025-12-02 19:06:06.214466 | 2025-12-02 19:06:06.214600 | TASK [add-fileserver : Add fileserver server to known hosts] 2025-12-02 19:06:06.612583 | localhost | changed 2025-12-02 19:06:06.633380 | 2025-12-02 19:06:06.633523 | PLAY [localhost] 2025-12-02 19:06:06.646288 | 2025-12-02 19:06:06.646353 | TASK [Generate bulk log download script] 2025-12-02 19:06:06.665005 | localhost | ok 2025-12-02 19:06:06.676851 | 2025-12-02 19:06:06.676918 | TASK [local-log-download : Check API endpoint is defined] 2025-12-02 19:06:06.716316 | localhost | ok: All assertions passed 2025-12-02 19:06:06.722568 | 2025-12-02 19:06:06.722654 | TASK [local-log-download : Create download script] 2025-12-02 19:06:07.194578 | localhost -> localhost | changed 2025-12-02 19:06:07.203596 | 2025-12-02 19:06:07.203658 | TASK [Register quick-download link] 2025-12-02 19:06:07.256666 | localhost | ok 2025-12-02 19:06:07.272083 | 2025-12-02 19:06:07.272141 | PLAY [logserver.rdoproject.org] 2025-12-02 19:06:07.281499 | 2025-12-02 19:06:07.281564 | TASK [Set zuul-log-path fact] 2025-12-02 19:06:07.298957 | logserver.rdoproject.org | ok 2025-12-02 19:06:07.307912 | 2025-12-02 19:06:07.307974 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-12-02 19:06:07.345001 | logserver.rdoproject.org | ok 2025-12-02 19:06:07.350022 | 2025-12-02 19:06:07.350083 | TASK [upload-logs : Create log directories] 2025-12-02 19:06:08.249650 | logserver.rdoproject.org | changed 2025-12-02 19:06:08.252714 | 2025-12-02 19:06:08.252783 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-12-02 19:06:08.550878 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.008862 2025-12-02 19:06:08.555452 | 2025-12-02 19:06:08.555529 | TASK [upload-logs : Upload logs to log server] 2025-12-02 19:06:09.305909 | logserver.rdoproject.org | Output suppressed because no_log was given 2025-12-02 19:06:09.312343 | 2025-12-02 19:06:09.312481 | LOOP [upload-logs : Compress console log and json output] 2025-12-02 19:06:09.370420 | logserver.rdoproject.org | skipping: Conditional result was False 2025-12-02 19:06:09.382276 | logserver.rdoproject.org | skipping: Conditional result was False 2025-12-02 19:06:09.400736 | 2025-12-02 19:06:09.400910 | LOOP [upload-logs : Upload compressed console log and json output] 2025-12-02 19:06:09.453131 | logserver.rdoproject.org | skipping: Conditional result was False 2025-12-02 19:06:09.453620 | 2025-12-02 19:06:09.456129 | logserver.rdoproject.org | skipping: Conditional result was False 2025-12-02 19:06:09.467121 | 2025-12-02 19:06:09.467319 | LOOP [upload-logs : Upload console log and json output]