2025-10-03 04:11:52.003296 | Job console starting... 2025-10-03 04:11:52.241390 | Updating repositories 2025-10-03 04:11:52.973121 | Preparing job workspace 2025-10-03 04:12:00.898759 | Running Ansible setup... 2025-10-03 04:12:03.941184 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2025-10-03 04:12:04.504433 | 2025-10-03 04:12:04.504543 | PLAY [localhost] 2025-10-03 04:12:04.514217 | 2025-10-03 04:12:04.514291 | TASK [Gathering Facts] 2025-10-03 04:12:05.412113 | localhost | ok 2025-10-03 04:12:05.425779 | 2025-10-03 04:12:05.425882 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-10-03 04:12:05.788518 | localhost -> localhost | changed 2025-10-03 04:12:05.794280 | 2025-10-03 04:12:05.794383 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-10-03 04:12:06.510843 | localhost -> localhost | changed 2025-10-03 04:12:06.519187 | 2025-10-03 04:12:06.519257 | TASK [Setup log path fact] 2025-10-03 04:12:06.541776 | localhost | ok 2025-10-03 04:12:06.554213 | 2025-10-03 04:12:06.554357 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-03 04:12:06.581365 | localhost | ok 2025-10-03 04:12:06.588746 | 2025-10-03 04:12:06.588822 | TASK [emit-job-header : Print job information] 2025-10-03 04:12:06.615765 | # Job Information 2025-10-03 04:12:06.615899 | Ansible Version: 2.15.12 2025-10-03 04:12:06.615951 | Job: cifmw-molecule-reportportal 2025-10-03 04:12:06.615977 | Pipeline: github-check 2025-10-03 04:12:06.615999 | Executor: ibm-bm4-ze.softwarefactory-project.io 2025-10-03 04:12:06.616019 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3362 2025-10-03 04:12:06.616039 | Log URL (when completed): https://logserver.rdoproject.org/799/rdoproject.org/799ea728c94c48448cb78c7a4b27f02d/ 2025-10-03 04:12:06.616058 | Event ID: 3daecbd0-a00d-11f0-93f4-31ce34c57105 2025-10-03 04:12:06.619608 | 2025-10-03 04:12:06.619675 | LOOP [emit-job-header : Print node information] 2025-10-03 04:12:06.725559 | localhost | ok: 2025-10-03 04:12:06.725754 | localhost | # Node Information 2025-10-03 04:12:06.725788 | localhost | Inventory Hostname: controller 2025-10-03 04:12:06.725814 | localhost | Hostname: np0005467811 2025-10-03 04:12:06.725836 | localhost | Username: zuul 2025-10-03 04:12:06.725857 | localhost | Distro: CentOS 9 2025-10-03 04:12:06.725877 | localhost | Provider: ibm-bm4-nodepool 2025-10-03 04:12:06.725896 | localhost | Region: regionOne 2025-10-03 04:12:06.725947 | localhost | Label: cloud-centos-9-stream-tripleo-ibm 2025-10-03 04:12:06.725971 | localhost | Product Name: OpenStack Compute 2025-10-03 04:12:06.725990 | localhost | Interface IP: 192.168.26.27 2025-10-03 04:12:06.752381 | 2025-10-03 04:12:06.752477 | PLAY [all] 2025-10-03 04:12:06.760836 | 2025-10-03 04:12:06.760927 | TASK [Gather network facts] 2025-10-03 04:12:07.124693 | controller | ok 2025-10-03 04:12:07.139467 | 2025-10-03 04:12:07.139545 | TASK [include_role : start-zuul-console] 2025-10-03 04:12:07.158691 | controller | ok 2025-10-03 04:12:07.170347 | 2025-10-03 04:12:07.170418 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-10-03 04:12:07.493314 | controller | ok 2025-10-03 04:12:07.504280 | 2025-10-03 04:12:07.504365 | TASK [include_role : add-build-sshkey] 2025-10-03 04:12:07.534026 | controller | ok 2025-10-03 04:12:07.550776 | 2025-10-03 04:12:07.550852 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-03 04:12:07.788129 | controller -> localhost | ok 2025-10-03 04:12:07.794184 | 2025-10-03 04:12:07.794304 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-03 04:12:07.824876 | controller | ok 2025-10-03 04:12:07.847590 | controller | included: /var/lib/zuul/builds/799ea728c94c48448cb78c7a4b27f02d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-03 04:12:07.864682 | 2025-10-03 04:12:07.864771 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-03 04:12:08.699938 | controller -> localhost | Generating public/private rsa key pair. 2025-10-03 04:12:08.700110 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/799ea728c94c48448cb78c7a4b27f02d/work/799ea728c94c48448cb78c7a4b27f02d_id_rsa. 2025-10-03 04:12:08.700147 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/799ea728c94c48448cb78c7a4b27f02d/work/799ea728c94c48448cb78c7a4b27f02d_id_rsa.pub. 2025-10-03 04:12:08.700173 | controller -> localhost | The key fingerprint is: 2025-10-03 04:12:08.700195 | controller -> localhost | SHA256:k5SUZSI6BTFPAd9sTzAm7xNX/pDoROTx4kR1eF5zWXo zuul-build-sshkey 2025-10-03 04:12:08.700219 | controller -> localhost | The key's randomart image is: 2025-10-03 04:12:08.700241 | controller -> localhost | +---[RSA 3072]----+ 2025-10-03 04:12:08.700261 | controller -> localhost | | =+*.*+B.o.. +| 2025-10-03 04:12:08.700281 | controller -> localhost | | B O.X *.o.+o| 2025-10-03 04:12:08.700300 | controller -> localhost | | o o O O =o..E| 2025-10-03 04:12:08.700320 | controller -> localhost | | . + @ . o.. | 2025-10-03 04:12:08.700358 | controller -> localhost | | S + . | 2025-10-03 04:12:08.700380 | controller -> localhost | | o | 2025-10-03 04:12:08.700399 | controller -> localhost | | | 2025-10-03 04:12:08.700420 | controller -> localhost | | | 2025-10-03 04:12:08.700441 | controller -> localhost | | | 2025-10-03 04:12:08.700461 | controller -> localhost | +----[SHA256]-----+ 2025-10-03 04:12:08.700505 | controller -> localhost | ok: Runtime: 0:00:00.212888 2025-10-03 04:12:08.706116 | 2025-10-03 04:12:08.706181 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-03 04:12:08.723945 | controller | ok 2025-10-03 04:12:08.733608 | controller | included: /var/lib/zuul/builds/799ea728c94c48448cb78c7a4b27f02d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-03 04:12:08.741795 | 2025-10-03 04:12:08.741867 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-03 04:12:08.755123 | controller | skipping: Conditional result was False 2025-10-03 04:12:08.760742 | 2025-10-03 04:12:08.760814 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-03 04:12:09.138889 | controller | changed 2025-10-03 04:12:09.143844 | 2025-10-03 04:12:09.143946 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-03 04:12:09.348651 | controller | ok 2025-10-03 04:12:09.354201 | 2025-10-03 04:12:09.354281 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-03 04:12:09.951437 | controller | changed 2025-10-03 04:12:09.956290 | 2025-10-03 04:12:09.956360 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-03 04:12:10.533957 | controller | changed 2025-10-03 04:12:10.538768 | 2025-10-03 04:12:10.538838 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-03 04:12:10.551712 | controller | skipping: Conditional result was False 2025-10-03 04:12:10.557003 | 2025-10-03 04:12:10.557072 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-03 04:12:10.863613 | controller -> localhost | changed 2025-10-03 04:12:10.873403 | 2025-10-03 04:12:10.873489 | TASK [add-build-sshkey : Add back temp key] 2025-10-03 04:12:11.111139 | controller -> localhost | Identity added: /var/lib/zuul/builds/799ea728c94c48448cb78c7a4b27f02d/work/799ea728c94c48448cb78c7a4b27f02d_id_rsa (zuul-build-sshkey) 2025-10-03 04:12:11.111361 | controller -> localhost | ok: Runtime: 0:00:00.007276 2025-10-03 04:12:11.117127 | 2025-10-03 04:12:11.117198 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-03 04:12:11.439351 | controller | ok 2025-10-03 04:12:11.444068 | 2025-10-03 04:12:11.444160 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-03 04:12:11.470303 | controller | skipping: Conditional result was False 2025-10-03 04:12:11.479640 | 2025-10-03 04:12:11.479711 | TASK [include_role : validate-host] 2025-10-03 04:12:11.499109 | controller | ok 2025-10-03 04:12:11.519097 | 2025-10-03 04:12:11.519173 | TASK [validate-host : Define zuul_info_dir fact] 2025-10-03 04:12:11.547177 | controller | ok 2025-10-03 04:12:11.554117 | 2025-10-03 04:12:11.555069 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-10-03 04:12:11.801676 | controller -> localhost | ok 2025-10-03 04:12:11.808579 | 2025-10-03 04:12:11.808668 | TASK [validate-host : Collect information about the host] 2025-10-03 04:12:12.408756 | controller | ok 2025-10-03 04:12:12.417871 | 2025-10-03 04:12:12.418012 | TASK [validate-host : Sanitize hostname] 2025-10-03 04:12:12.498216 | controller | ok 2025-10-03 04:12:12.502581 | 2025-10-03 04:12:12.502647 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-10-03 04:12:12.892632 | controller -> localhost | changed 2025-10-03 04:12:12.898688 | 2025-10-03 04:12:12.898769 | TASK [validate-host : Collect information about zuul worker] 2025-10-03 04:12:13.261509 | controller | ok 2025-10-03 04:12:13.266562 | 2025-10-03 04:12:13.266658 | TASK [validate-host : Write out all zuul information for each host] 2025-10-03 04:12:13.688852 | controller -> localhost | changed 2025-10-03 04:12:13.699589 | 2025-10-03 04:12:13.699681 | TASK [include_role : prepare-workspace-openshift] 2025-10-03 04:12:13.713694 | controller | skipping: Conditional result was False 2025-10-03 04:12:13.718628 | 2025-10-03 04:12:13.718926 | TASK [include_role : remove-zuul-sshkey] 2025-10-03 04:12:13.733104 | controller | skipping: Conditional result was False 2025-10-03 04:12:13.738700 | 2025-10-03 04:12:13.738794 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2025-10-03 04:12:13.936299 | controller | ok: "logs" 2025-10-03 04:12:13.936693 | controller | ok: All items complete 2025-10-03 04:12:13.936746 | 2025-10-03 04:12:14.094256 | controller | ok: "artifacts" 2025-10-03 04:12:14.262556 | controller | ok: "docs" 2025-10-03 04:12:14.277658 | 2025-10-03 04:12:14.277780 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2025-10-03 04:12:14.471654 | controller | changed: "logs" 2025-10-03 04:12:14.639621 | controller | changed: "artifacts" 2025-10-03 04:12:14.816396 | controller | changed: "docs" 2025-10-03 04:12:14.847440 | 2025-10-03 04:12:14.847515 | PLAY RECAP 2025-10-03 04:12:14.847560 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0 2025-10-03 04:12:14.847589 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-03 04:12:14.847609 | 2025-10-03 04:12:14.968831 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2025-10-03 04:12:14.969624 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master] 2025-10-03 04:12:15.590203 | 2025-10-03 04:12:15.590304 | PLAY [all] 2025-10-03 04:12:15.612842 | 2025-10-03 04:12:15.612971 | TASK [mirror-info-fork : Set mirror_fqdn if not defined] 2025-10-03 04:12:15.702902 | controller | ok 2025-10-03 04:12:15.712085 | 2025-10-03 04:12:15.712189 | TASK [mirror-info-fork : Create /etc/ci] 2025-10-03 04:12:16.138311 | controller | changed 2025-10-03 04:12:16.144523 | 2025-10-03 04:12:16.144600 | TASK [mirror-info-fork : Install ci_mirror script] 2025-10-03 04:12:17.047665 | controller | changed 2025-10-03 04:12:17.058346 | 2025-10-03 04:12:17.058433 | LOOP [add-authorized-keys : Enable access via build key on all nodes] 2025-10-03 04:12:17.440839 | controller | changed: 2025-10-03 04:12:17.441184 | controller | { 2025-10-03 04:12:17.441224 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com" 2025-10-03 04:12:17.441264 | controller | } 2025-10-03 04:12:17.665581 | controller | changed: 2025-10-03 04:12:17.665672 | controller | { 2025-10-03 04:12:17.665702 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com" 2025-10-03 04:12:17.665737 | controller | } 2025-10-03 04:12:17.869710 | controller | changed: 2025-10-03 04:12:17.869819 | controller | { 2025-10-03 04:12:17.869849 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com" 2025-10-03 04:12:17.869871 | controller | } 2025-10-03 04:12:18.096457 | controller | changed: 2025-10-03 04:12:18.096548 | controller | { 2025-10-03 04:12:18.096578 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com" 2025-10-03 04:12:18.096602 | controller | } 2025-10-03 04:12:18.310058 | controller | changed: 2025-10-03 04:12:18.310137 | controller | { 2025-10-03 04:12:18.310164 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com" 2025-10-03 04:12:18.310185 | controller | } 2025-10-03 04:12:18.522201 | controller | changed: 2025-10-03 04:12:18.522284 | controller | { 2025-10-03 04:12:18.522313 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com" 2025-10-03 04:12:18.522335 | controller | } 2025-10-03 04:12:18.755153 | controller | changed: 2025-10-03 04:12:18.755243 | controller | { 2025-10-03 04:12:18.755270 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDLOQd4ZLtkZXQGY6UwAr/06ppWQK4fDO3HaqxPk98csyOCBXsliSKK39Bso828+5srIXiW7aI6aC9P5mwi4mUZlGPfJlQbfrcGvY+b/SocuvaGK+1RrHLoJCT52LBhwgrzlXio2jeksZeein8iaTrhsPrOAs7KggIL/rB9hEiB3NaOPWhhoCP4vlW6MEMExGcqB/1FVxXFBPnLkEyW0Lk7ycVflZl2ocRxbfjZi0+tI1Wlinp8PvSQSc/WVrAcDgKjc/mB4ODPOyYy3G8FHgfMsrXSDEyjBKgLKMsdCrAUcqJQWjkqXleXSYOV4q3pzL+9umK+q/e3P/bIoSFQzmJKTU1eDfuvPXmow9F5H54fii/Da7ezlMJ+wPGHJrRAkmzvMbALy7xwswLhZMkOGNtRcPqaKYRmIBKpw3o6bCTtcNUHOtOQnzwY8JzrM2eBWJBXAANYw+9/ho80JIiwhg29CFNpVBuHbql2YxJQNrnl90guN65rYNpDxdIluweyUf8= anbanerj@kaermorhen" 2025-10-03 04:12:18.755292 | controller | } 2025-10-03 04:12:18.973964 | controller | changed: 2025-10-03 04:12:18.974047 | controller | { 2025-10-03 04:12:18.974075 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQC3VwV8Im9kRm49lt3tM36hj4Zv27FxGo4C1Q/0jqhzFmHY7RHbmeRr8ObhwWoHjXSozKWg8FL5ER0z3hTwL0W6lez3sL7hUaCmSuZmG5Hnl3x4vTSxDI9JZ/Y65rtYiiWQo2fC5xJhU/4+0e5e/pseCm8cKRSu+SaxhO+sd6FDojA2x1BzOzKiQRDy/1zWGp/cZkxcEuB1wHI5LMzN03c67vmbu+fhZRAUO4dQkvcnj2LrhQtpa+ytvnSjr8icMDosf1OsbSffwZFyHB/hfWGAfe0eIeSA2XPraxiPknXxiPKx2MJsaUTYbsZcm3EjFdHBBMumw5rBI74zLrMRvCO9GwBEmGT4rFng1nP+yw5DB8sn2zqpOsPg1LYRwCPOUveC13P6pgsZZPh812e8v5EKnETct+5XI3dVpdw6CnNiLwAyVAF15DJvBGT/u1k0Myg/bQn+Gv9k2MSj6LvQmf6WbZu2Wgjm30z3FyCneBqTL7mLF19YXzeC0ufHz5pnO1E= dasm@fedora" 2025-10-03 04:12:18.974096 | controller | } 2025-10-03 04:12:19.182093 | controller | changed: 2025-10-03 04:12:19.182184 | controller | { 2025-10-03 04:12:19.182213 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner" 2025-10-03 04:12:19.182234 | controller | } 2025-10-03 04:12:19.396331 | controller | changed: 2025-10-03 04:12:19.396410 | controller | { 2025-10-03 04:12:19.396439 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com" 2025-10-03 04:12:19.396461 | controller | } 2025-10-03 04:12:19.687666 | controller | changed: 2025-10-03 04:12:19.687774 | controller | { 2025-10-03 04:12:19.687803 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com" 2025-10-03 04:12:19.687825 | controller | } 2025-10-03 04:12:19.856933 | controller | changed: 2025-10-03 04:12:19.857029 | controller | { 2025-10-03 04:12:19.857069 | controller | "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com" 2025-10-03 04:12:19.858750 | controller | } 2025-10-03 04:12:20.083190 | controller | changed: 2025-10-03 04:12:20.083277 | controller | { 2025-10-03 04:12:20.083308 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith" 2025-10-03 04:12:20.083330 | controller | } 2025-10-03 04:12:20.313347 | controller | changed: 2025-10-03 04:12:20.313424 | controller | { 2025-10-03 04:12:20.313452 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel" 2025-10-03 04:12:20.313475 | controller | } 2025-10-03 04:12:20.530511 | controller | changed: 2025-10-03 04:12:20.530597 | controller | { 2025-10-03 04:12:20.530626 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux" 2025-10-03 04:12:20.530649 | controller | } 2025-10-03 04:12:20.758049 | controller | changed: 2025-10-03 04:12:20.758157 | controller | { 2025-10-03 04:12:20.758186 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr" 2025-10-03 04:12:20.758209 | controller | } 2025-10-03 04:12:20.980354 | controller | changed: 2025-10-03 04:12:20.980441 | controller | { 2025-10-03 04:12:20.980470 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQCfNtF7NvKl915TGsGGoseUb06Hj8L/S4toWf0hExeY+F00woL6NvBlJD0nDct+P5a22I4EhvoQCRQ8reaPCm1lybR3uiRIJsj+8zkVvLwby9LXzfZorlNG9ofjd00FEmB09uW/YvTl6Q9XwwwX6tInzIOv3TMqTHHGOL74ibbj8J/FJR0cFEyj0z4WQRvtkh32xAHl83gbuINryMt0sqRI+clj2381NKL55DRLQrVw0gsfqqxiHAnXg21qWmc4J+b9e9kiuAFQjcjwTVkwJCcg3xbPwC/qokYRby/Y5S40UUd7/jEARGXT7RZgpzTuDd1oZiCVrnrqJNPaMNdVv5MLeFdf1B7iIe5aa/fGouX7AO4SdKhZUdnJmCFAGvjC6S3JMZ2wAcUl+OHnssfmdj7XL50cLo27vjuzMtLAgSqi6N99m92WCF2s8J9aVzszX7Xz9OKZCeGsiVJp3/NdABKzSEAyM9xBD/5Vho894Sav+otpySHe3p6RUTgbB5Zu8VyZRZ/UtB3ueXxyo764yrc6qWIDqrehm84Xm9g+/jpIBzGPl07NUNJpdt/6Sgf9RIKXw/7XypO5yZfUcuFNGTxLfqjTNrtgLZNcjfav6sSdVXVcMPL//XNuRdKmVFaO76eV/oGMQGr1fGcCD+N+CpI7+Q+fCNB6VFWG4nZFuI/Iuw== averdagu@redhat.com" 2025-10-03 04:12:20.980493 | controller | } 2025-10-03 04:12:21.222825 | controller | changed: 2025-10-03 04:12:21.222930 | controller | { 2025-10-03 04:12:21.222963 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDq8l27xI+QlQVdS4djp9ogSoyrNE2+Ox6vKPdhSNL1J3PE5w+WCSvMz9A5gnNuH810zwbekEApbxTze/gLQJwBHA52CChfURpXrFaxY7ePXRElwKAL3mJfzBWY/c5jnNL9TCVmFJTGZkFZP3Nh+BMgZvL6xBkt3WKm6Uq18qzd9XeKcZusrA+O+uLv1fVeQnadY9RIqOCyeFYCzLWrUfTyE8x/XG0hAWIM7qpnF2cALQS2h9n4hW5ybiUN790H08wf9hFwEf5nxY9Z9dVkPFQiTSGKNBzmnCXU9skxS/xhpFjJ5duGSZdtAHe9O+nGZm9c67hxgtf8e5PDuqAdXEv2cf6e3VBAt+Bz8EKI3yosTj0oZHfwr42Yzb1l/SKy14Rggsrc9KAQlrGXan6+u2jcQqqx7l+SWmnpFiWTV9u5cWj2IgOhApOitmRBPYqk9rE2usfO0hLn/Pj/R/Nau4803e1/EikdLE7Ps95s9mX5jRDjAoUa2JwFF5RsVFyL910= ashigupt@ashigupt.remote.csb" 2025-10-03 04:12:21.222995 | controller | } 2025-10-03 04:12:21.448379 | controller | changed: 2025-10-03 04:12:21.448458 | controller | { 2025-10-03 04:12:21.448488 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9" 2025-10-03 04:12:21.448510 | controller | } 2025-10-03 04:12:21.680574 | controller | changed: 2025-10-03 04:12:21.680668 | controller | { 2025-10-03 04:12:21.680701 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora" 2025-10-03 04:12:21.680738 | controller | } 2025-10-03 04:12:21.900456 | controller | changed: 2025-10-03 04:12:21.900597 | controller | { 2025-10-03 04:12:21.900629 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac" 2025-10-03 04:12:21.900651 | controller | } 2025-10-03 04:12:22.110575 | controller | changed: 2025-10-03 04:12:22.110951 | controller | { 2025-10-03 04:12:22.111002 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop" 2025-10-03 04:12:22.111028 | controller | } 2025-10-03 04:12:22.330803 | controller | changed: 2025-10-03 04:12:22.330893 | controller | { 2025-10-03 04:12:22.330956 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQDZdI7t1cxYx65heVI24HTV4F7oQLW1zyfxHreL2TIJKxjyrUUKIFEUmTutcBlJRLNT2Eoix6x1sOw9YrchloCLcn//SGfTElr9mSc5jbjb7QXEU+zJMhtxyEJ1Po3CUGnj7ckiIXw7wcawZtrEOAQ9pH3ExYCJcEMiyNjRQZCxT3tPK+S4B95EWh5Fsrz9CkwpjNRPPH7LigCeQTM3Wc7r97utAslBUUvYceDSLA7rMgkitJE38b7rZBeYzsGQ8YYUBjTCtehqQXxCRjizbHWaaZkBU+N3zkKB6n/iCNGIO690NK7A/qb6msTijiz1PeuM8ThOsi9qXnbX5v0PoTpcFSojV7NHAQ71f0XXuS43FhZctT+Dcx44dT8Fb5vJu2cJGrk+qF8ZgJYNpRS7gPg0EG2EqjK7JMf9ULdjSu0r+KlqIAyLvtzT4eOnQipoKlb/WG5D/0ohKv7OMQ352ggfkBFIQsRXyyTCT98Ft9juqPuahi3CAQmP4H9dyE+7+Kz437PEtsxLmfm6naNmWi7Ee1DqWPwS8rEajsm4sNM4wW9gdBboJQtc0uZw0DfLj1I9r3Mc8Ol0jYtz0yNQDSzVLrGCaJlC311trU70tZ+ZkAVV6Mn8lOhSbj1cK0lvSr6ZK4dgqGl3I1eTZJJhbLNdg7UOVaiRx9543+C/p/As7w== brjackma@redhat.com" 2025-10-03 04:12:22.330984 | controller | } 2025-10-03 04:12:22.557293 | controller | changed: 2025-10-03 04:12:22.557381 | controller | { 2025-10-03 04:12:22.557409 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com" 2025-10-03 04:12:22.557430 | controller | } 2025-10-03 04:12:22.779115 | controller | changed: 2025-10-03 04:12:22.779219 | controller | { 2025-10-03 04:12:22.779250 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com" 2025-10-03 04:12:22.779270 | controller | } 2025-10-03 04:12:22.998763 | controller | changed: 2025-10-03 04:12:22.998842 | controller | { 2025-10-03 04:12:22.998870 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono" 2025-10-03 04:12:22.998892 | controller | } 2025-10-03 04:12:23.023516 | 2025-10-03 04:12:23.023640 | TASK [Set timezone to UTC] 2025-10-03 04:12:23.438586 | controller | changed: executed `/bin/timedatectl set-timezone UTC` 2025-10-03 04:12:23.444817 | 2025-10-03 04:12:23.444889 | TASK [Create nodepool directory] 2025-10-03 04:12:23.648489 | controller | changed 2025-10-03 04:12:23.654476 | 2025-10-03 04:12:23.654560 | TASK [Create nodepool sub_nodes file] 2025-10-03 04:12:24.169802 | controller | changed 2025-10-03 04:12:24.175028 | 2025-10-03 04:12:24.175099 | TASK [Create nodepool sub_nodes_private file] 2025-10-03 04:12:24.762471 | controller | changed 2025-10-03 04:12:24.768531 | 2025-10-03 04:12:24.768621 | LOOP [Populate nodepool sub_nodes file] 2025-10-03 04:12:24.828836 | 2025-10-03 04:12:24.829001 | LOOP [Populate nodepool sub_nodes_private file] 2025-10-03 04:12:24.858105 | 2025-10-03 04:12:24.858247 | TASK [Create nodepool primary file] 2025-10-03 04:12:24.882092 | controller | skipping: Conditional result was False 2025-10-03 04:12:24.887509 | 2025-10-03 04:12:24.887597 | TASK [Create nodepool node_private for this node] 2025-10-03 04:12:25.423141 | controller | changed 2025-10-03 04:12:25.429852 | 2025-10-03 04:12:25.429954 | LOOP [Copy ssh keys to nodepool directory] 2025-10-03 04:12:25.794227 | controller | ok: Item: id_rsa Runtime: 0:00:00.006661 2025-10-03 04:12:25.794399 | 2025-10-03 04:12:25.965136 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.004574 2025-10-03 04:12:25.975772 | 2025-10-03 04:12:25.975850 | TASK [Add sudoers role for zuul-sudo-grep.sh] 2025-10-03 04:12:26.505816 | controller | changed 2025-10-03 04:12:26.511760 | 2025-10-03 04:12:26.511841 | TASK [Validate sudoers config after edits] 2025-10-03 04:12:26.759437 | controller | /etc/sudoers: parsed OK 2025-10-03 04:12:26.759533 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK 2025-10-03 04:12:26.759545 | controller | /etc/sudoers.d/zuul: parsed OK 2025-10-03 04:12:26.759551 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK 2025-10-03 04:12:27.056675 | controller | ok: Runtime: 0:00:00.005496 2025-10-03 04:12:27.062668 | 2025-10-03 04:12:27.062768 | TASK [Show the environment passed in to job shell scripts] 2025-10-03 04:12:27.281445 | controller | SHELL=/bin/bash 2025-10-03 04:12:27.281534 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework 2025-10-03 04:12:27.281546 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework 2025-10-03 04:12:27.281552 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/62/3362/41f2edaa0ac45cd85919f70775e84cf472326a6e 2025-10-03 04:12:27.281558 | controller | PWD=/home/zuul 2025-10-03 04:12:27.281564 | controller | ZUUL_PIPELINE=github-check 2025-10-03 04:12:27.281570 | controller | LOGNAME=zuul 2025-10-03 04:12:27.281576 | controller | XDG_SESSION_TYPE=tty 2025-10-03 04:12:27.281581 | controller | _=/usr/bin/env 2025-10-03 04:12:27.281586 | controller | MOTD_SHOWN=pam 2025-10-03 04:12:27.281592 | controller | HOME=/home/zuul 2025-10-03 04:12:27.281597 | controller | LANG=en_US.UTF-8 2025-10-03 04:12:27.281602 | controller | SSH_CONNECTION=192.168.26.12 33160 192.168.26.27 22 2025-10-03 04:12:27.281608 | controller | TOX_TESTENV_PASSENV=ZUUL_UUID ZUUL_PROJECT ZUUL_SHORT_PROJECT_NAME ZUUL_PIPELINE ZUUL_VOTING WORKSPACE BUILD_TIMEOUT ZUUL_BRANCH ZUUL_CHANGES ZUUL_CHANGE_IDS ZUUL_CHANGE ZUUL_PATCHSET 2025-10-03 04:12:27.281618 | controller | ZUUL_CHANGE_IDS=3362,41f2edaa0ac45cd85919f70775e84cf472326a6e 2025-10-03 04:12:27.281623 | controller | WORKSPACE=/home/zuul/workspace 2025-10-03 04:12:27.281629 | controller | XDG_SESSION_CLASS=user 2025-10-03 04:12:27.281635 | controller | SELINUX_ROLE_REQUESTED= 2025-10-03 04:12:27.281640 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s 2025-10-03 04:12:27.281645 | controller | USER=zuul 2025-10-03 04:12:27.281651 | controller | ZUUL_VOTING=True 2025-10-03 04:12:27.281656 | controller | BUILD_TIMEOUT=1800000 2025-10-03 04:12:27.281661 | controller | SELINUX_USE_CURRENT_RANGE= 2025-10-03 04:12:27.281666 | controller | SHLVL=1 2025-10-03 04:12:27.281672 | controller | ZUUL_PATCHSET=41f2edaa0ac45cd85919f70775e84cf472326a6e 2025-10-03 04:12:27.281677 | controller | XDG_SESSION_ID=1 2025-10-03 04:12:27.281683 | controller | ZUUL_BRANCH=main 2025-10-03 04:12:27.281688 | controller | XDG_RUNTIME_DIR=/run/user/1000 2025-10-03 04:12:27.281694 | controller | SSH_CLIENT=192.168.26.12 33160 22 2025-10-03 04:12:27.281699 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/ 2025-10-03 04:12:27.281704 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima: 2025-10-03 04:12:27.281710 | controller | which_declare=declare -f 2025-10-03 04:12:27.281716 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin 2025-10-03 04:12:27.281722 | controller | SELINUX_LEVEL_REQUESTED= 2025-10-03 04:12:27.281727 | controller | ZUUL_CHANGE=3362 2025-10-03 04:12:27.281733 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus 2025-10-03 04:12:27.281738 | controller | ZUUL_UUID=799ea728c94c48448cb78c7a4b27f02d 2025-10-03 04:12:27.281744 | controller | BASH_FUNC_which%%=() { ( alias; 2025-10-03 04:12:27.281751 | controller | eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@ 2025-10-03 04:12:27.281757 | controller | } 2025-10-03 04:12:27.587836 | controller | ok: Runtime: 0:00:00.007519 2025-10-03 04:12:27.593738 | 2025-10-03 04:12:27.593804 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars] 2025-10-03 04:12:27.617865 | controller | skipping: Conditional result was False 2025-10-03 04:12:27.623758 | 2025-10-03 04:12:27.623873 | TASK [Symlink /home/zuul-worker/workspace] 2025-10-03 04:12:28.147771 | controller | skipping: Conditional result was False 2025-10-03 04:12:28.154057 | 2025-10-03 04:12:28.154191 | TASK [Ensure legacy workspace directory] 2025-10-03 04:12:28.355574 | controller | changed 2025-10-03 04:12:28.373308 | 2025-10-03 04:12:28.373369 | PLAY RECAP 2025-10-03 04:12:28.373413 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0 2025-10-03 04:12:28.373436 | 2025-10-03 04:12:28.484292 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master] 2025-10-03 04:12:28.485080 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main] 2025-10-03 04:12:29.065273 | 2025-10-03 04:12:29.065381 | PLAY [Run ci/playbooks/dump_zuul_data.yml] 2025-10-03 04:12:29.093124 | 2025-10-03 04:12:29.093244 | TASK [Create zuul-output directory] 2025-10-03 04:12:29.417445 | controller | changed 2025-10-03 04:12:29.424685 | 2025-10-03 04:12:29.424836 | TASK [Slurp Zuul inventory test] 2025-10-03 04:12:29.775549 | controller -> localhost | ok 2025-10-03 04:12:29.781777 | 2025-10-03 04:12:29.781846 | TASK [Save zuul inventory] 2025-10-03 04:12:30.565612 | controller | changed 2025-10-03 04:12:30.571185 | 2025-10-03 04:12:30.571272 | TASK [Save zuul vars without the change_message] 2025-10-03 04:12:31.153937 | controller | changed 2025-10-03 04:12:31.178692 | 2025-10-03 04:12:31.178795 | PLAY RECAP 2025-10-03 04:12:31.178855 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-03 04:12:31.178880 | 2025-10-03 04:12:31.255569 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main] 2025-10-03 04:12:31.256358 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main] 2025-10-03 04:12:31.867047 | 2025-10-03 04:12:31.867319 | PLAY [Run ci/playbooks/molecule-prepare.yml] 2025-10-03 04:12:31.888675 | 2025-10-03 04:12:31.888760 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh] 2025-10-03 04:12:31.918234 | controller | ok 2025-10-03 04:12:31.935399 | 2025-10-03 04:12:31.935490 | TASK [mirror-info-fork : Set mirror_fqdn if not defined] 2025-10-03 04:12:31.959164 | controller | skipping: Conditional result was False 2025-10-03 04:12:31.964764 | 2025-10-03 04:12:31.964837 | TASK [mirror-info-fork : Create /etc/ci] 2025-10-03 04:12:32.338439 | controller | ok 2025-10-03 04:12:32.344618 | 2025-10-03 04:12:32.344710 | TASK [mirror-info-fork : Install ci_mirror script] 2025-10-03 04:12:32.999435 | controller | ok 2025-10-03 04:12:33.012269 | 2025-10-03 04:12:33.012352 | TASK [Prepare workspace] 2025-10-03 04:12:33.030622 | controller | ok 2025-10-03 04:12:33.047067 | 2025-10-03 04:12:33.047139 | TASK [prepare-workspace : Start zuul_console daemon.] 2025-10-03 04:12:33.343554 | controller | ok 2025-10-03 04:12:33.350314 | 2025-10-03 04:12:33.350398 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2025-10-03 04:12:35.440788 | controller | Output suppressed because no_log was given 2025-10-03 04:12:35.455141 | 2025-10-03 04:12:35.455216 | LOOP [Create zuul-output directory] 2025-10-03 04:12:35.669210 | controller | changed: "/home/zuul/ci-framework-data/logs" 2025-10-03 04:12:35.829008 | controller | ok: "/home/zuul/zuul-output/logs" 2025-10-03 04:12:35.837089 | 2025-10-03 04:12:35.837189 | TASK [Install required packages] 2025-10-03 04:13:28.224719 | controller | changed 2025-10-03 04:13:28.232499 | 2025-10-03 04:13:28.232620 | TASK [Install venv] 2025-10-03 04:14:32.179971 | controller | changed 2025-10-03 04:14:32.207048 | 2025-10-03 04:14:32.207126 | PLAY RECAP 2025-10-03 04:14:32.207171 | controller | ok: 7 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2025-10-03 04:14:32.207195 | 2025-10-03 04:14:32.293843 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main] 2025-10-03 04:14:32.294645 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main] 2025-10-03 04:14:32.857202 | 2025-10-03 04:14:32.857460 | PLAY [Run ci/playbooks/molecule-test.yml] 2025-10-03 04:14:32.881037 | 2025-10-03 04:14:32.881170 | TASK [Gather required facts] 2025-10-03 04:14:33.388272 | controller | ok 2025-10-03 04:14:33.394244 | 2025-10-03 04:14:33.394326 | TASK [Load environment var if instructed to] 2025-10-03 04:14:33.417900 | controller | skipping: Conditional result was False 2025-10-03 04:14:33.423711 | 2025-10-03 04:14:33.423792 | TASK [Run molecule] 2025-10-03 04:14:34.371877 | controller | INFO Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml 2025-10-03 04:14:34.441790 | controller | INFO Performing prerun with role_name_check=0... 2025-10-03 04:14:52.122542 | controller | WARNING Another version of '@NAMESPACE@.@NAME@' 3.1.4 was found installed in /home/zuul/.cache/ansible-compat/b2ede7/collections/ansible_collections, only the first one will be used, 3.1.4 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-03 04:14:52.122990 | controller | WARNING Another version of 'ansible.netcommon' 7.1.0 was found installed in /home/zuul/.cache/ansible-compat/b2ede7/collections/ansible_collections, only the first one will be used, 7.1.0 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-03 04:14:52.123496 | controller | WARNING Another version of 'ansible.posix' 1.6.2 was found installed in /home/zuul/.cache/ansible-compat/b2ede7/collections/ansible_collections, only the first one will be used, 1.6.2 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-03 04:14:52.123963 | controller | WARNING Another version of 'ansible.utils' 5.1.2 was found installed in /home/zuul/.cache/ansible-compat/b2ede7/collections/ansible_collections, only the first one will be used, 5.1.2 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-03 04:14:52.124451 | controller | WARNING Another version of 'cifmw.general' 1.0.0+41f2edaa was found installed in /home/zuul/.cache/ansible-compat/b2ede7/collections/ansible_collections, only the first one will be used, 1.0.0+41f2edaa (/home/zuul/.ansible/collections/ansible_collections). 2025-10-03 04:14:52.124922 | controller | WARNING Another version of 'cisco.ios' 9.0.3 was found installed in /home/zuul/.cache/ansible-compat/b2ede7/collections/ansible_collections, only the first one will be used, 9.0.3 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-03 04:14:52.125434 | controller | WARNING Another version of 'community.crypto' 2.22.3 was found installed in /home/zuul/.cache/ansible-compat/b2ede7/collections/ansible_collections, only the first one will be used, 2.22.3 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-03 04:14:52.125845 | controller | WARNING Another version of 'community.general' 10.0.1 was found installed in /home/zuul/.cache/ansible-compat/b2ede7/collections/ansible_collections, only the first one will be used, 10.0.1 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-03 04:14:52.126290 | controller | WARNING Another version of 'community.libvirt' 1.3.0 was found installed in /home/zuul/.cache/ansible-compat/b2ede7/collections/ansible_collections, only the first one will be used, 1.3.0 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-03 04:14:52.126754 | controller | WARNING Another version of 'community.okd' 4.0.0 was found installed in /home/zuul/.cache/ansible-compat/b2ede7/collections/ansible_collections, only the first one will be used, 4.0.0 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-03 04:14:52.127187 | controller | WARNING Another version of 'containers.podman' 1.16.2 was found installed in /home/zuul/.cache/ansible-compat/b2ede7/collections/ansible_collections, only the first one will be used, 1.16.2 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-03 04:14:52.127652 | controller | WARNING Another version of 'junipernetworks.junos' 9.1.0 was found installed in /home/zuul/.cache/ansible-compat/b2ede7/collections/ansible_collections, only the first one will be used, 9.1.0 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-03 04:14:52.128090 | controller | WARNING Another version of 'kubernetes.core' 5.0.0 was found installed in /home/zuul/.cache/ansible-compat/b2ede7/collections/ansible_collections, only the first one will be used, 5.0.0 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-03 04:14:52.128559 | controller | WARNING Another version of 'mellanox.onyx' 1.0.0 was found installed in /home/zuul/.cache/ansible-compat/b2ede7/collections/ansible_collections, only the first one will be used, 1.0.0 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-03 04:14:52.129009 | controller | WARNING Another version of 'openstack.config_template' 2.1.1 was found installed in /home/zuul/.cache/ansible-compat/b2ede7/collections/ansible_collections, only the first one will be used, 2.1.1 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-03 04:14:52.139898 | controller | INFO Running default > prepare 2025-10-03 04:14:52.140426 | controller | WARNING Skipping, prepare playbook not configured. 2025-10-03 04:14:52.148033 | controller | INFO Running default > converge 2025-10-03 04:14:52.884444 | controller | 2025-10-03 04:14:52.884536 | controller | PLAY [Converge] **************************************************************** 2025-10-03 04:14:52.884643 | controller | 2025-10-03 04:14:52.884753 | controller | TASK [Gathering Facts] ********************************************************* 2025-10-03 04:14:52.884859 | controller | Friday 03 October 2025 04:14:52 +0000 (0:00:00.011) 0:00:00.011 ******** 2025-10-03 04:14:53.766703 | controller | ok: [instance] 2025-10-03 04:14:53.766771 | controller | 2025-10-03 04:14:53.766864 | controller | TASK [reportportal : Check if mandatory variables are defined and not empty] *** 2025-10-03 04:14:53.766970 | controller | Friday 03 October 2025 04:14:53 +0000 (0:00:00.882) 0:00:00.893 ******** 2025-10-03 04:14:53.811654 | controller | ok: [instance] => changed=false 2025-10-03 04:14:53.811714 | controller | msg: All assertions passed 2025-10-03 04:14:53.811812 | controller | 2025-10-03 04:14:53.811925 | controller | TASK [reportportal : Make sure the droute directory exists] ******************** 2025-10-03 04:14:53.812031 | controller | Friday 03 October 2025 04:14:53 +0000 (0:00:00.045) 0:00:00.939 ******** 2025-10-03 04:14:54.135613 | controller | changed: [instance] 2025-10-03 04:14:54.135670 | controller | 2025-10-03 04:14:54.135780 | controller | TASK [reportportal : Get the Data router client] ******************************* 2025-10-03 04:14:54.135889 | controller | Friday 03 October 2025 04:14:54 +0000 (0:00:00.323) 0:00:01.262 ******** 2025-10-03 04:14:54.166301 | controller | skipping: [instance] 2025-10-03 04:14:54.166404 | controller | 2025-10-03 04:14:54.166526 | controller | TASK [reportportal : Create the metadata file] ********************************* 2025-10-03 04:14:54.166631 | controller | Friday 03 October 2025 04:14:54 +0000 (0:00:00.031) 0:00:01.293 ******** 2025-10-03 04:14:54.776940 | controller | changed: [instance] 2025-10-03 04:14:54.777003 | controller | 2025-10-03 04:14:54.777100 | controller | TASK [reportportal : Send the results via Data router client call] ************* 2025-10-03 04:14:54.777215 | controller | Friday 03 October 2025 04:14:54 +0000 (0:00:00.610) 0:00:01.904 ******** 2025-10-03 04:14:54.807769 | controller | skipping: [instance] 2025-10-03 04:14:54.807824 | controller | 2025-10-03 04:14:54.807942 | controller | TASK [reportportal : Log droute result] **************************************** 2025-10-03 04:14:54.808053 | controller | Friday 03 October 2025 04:14:54 +0000 (0:00:00.030) 0:00:01.935 ******** 2025-10-03 04:14:54.840443 | controller | skipping: [instance] 2025-10-03 04:14:54.840545 | controller | 2025-10-03 04:14:54.840645 | controller | TASK [Stat directory] ********************************************************** 2025-10-03 04:14:54.840748 | controller | Friday 03 October 2025 04:14:54 +0000 (0:00:00.032) 0:00:01.967 ******** 2025-10-03 04:14:55.051045 | controller | ok: [instance] 2025-10-03 04:14:55.051090 | controller | 2025-10-03 04:14:55.051215 | controller | TASK [Validate directory state] ************************************************ 2025-10-03 04:14:55.051323 | controller | Friday 03 October 2025 04:14:55 +0000 (0:00:00.210) 0:00:02.178 ******** 2025-10-03 04:14:55.078856 | controller | ok: [instance] => changed=false 2025-10-03 04:14:55.078949 | controller | msg: All assertions passed 2025-10-03 04:14:55.079065 | controller | 2025-10-03 04:14:55.079789 | controller | TASK [Stat metadata file] ****************************************************** 2025-10-03 04:14:55.079913 | controller | Friday 03 October 2025 04:14:55 +0000 (0:00:00.028) 0:00:02.206 ******** 2025-10-03 04:14:55.288254 | controller | ok: [instance] 2025-10-03 04:14:55.288310 | controller | 2025-10-03 04:14:55.288461 | controller | TASK [Validate file state] ***************************************************** 2025-10-03 04:14:55.288584 | controller | Friday 03 October 2025 04:14:55 +0000 (0:00:00.209) 0:00:02.415 ******** 2025-10-03 04:14:55.316365 | controller | ok: [instance] => changed=false 2025-10-03 04:14:55.316491 | controller | msg: All assertions passed 2025-10-03 04:14:55.316599 | controller | 2025-10-03 04:14:55.316708 | controller | TASK [Stat metadata golden file] *********************************************** 2025-10-03 04:14:55.316812 | controller | Friday 03 October 2025 04:14:55 +0000 (0:00:00.028) 0:00:02.444 ******** 2025-10-03 04:14:55.519174 | controller | ok: [instance] 2025-10-03 04:14:55.519232 | controller | 2025-10-03 04:14:55.519372 | controller | TASK [Validate metadata golden file state] ************************************* 2025-10-03 04:14:55.519522 | controller | Friday 03 October 2025 04:14:55 +0000 (0:00:00.202) 0:00:02.646 ******** 2025-10-03 04:14:55.546890 | controller | ok: [instance] => changed=false 2025-10-03 04:14:55.546946 | controller | msg: All assertions passed 2025-10-03 04:14:55.547062 | controller | 2025-10-03 04:14:55.547177 | controller | TASK [Validate metadata golden is the same as produced metadata] *************** 2025-10-03 04:14:55.547280 | controller | Friday 03 October 2025 04:14:55 +0000 (0:00:00.027) 0:00:02.674 ******** 2025-10-03 04:14:55.579246 | controller | ok: [instance] => changed=false 2025-10-03 04:14:55.579343 | controller | msg: All assertions passed 2025-10-03 04:14:55.579466 | controller | 2025-10-03 04:14:55.579622 | controller | PLAY RECAP ********************************************************************* 2025-10-03 04:14:55.579745 | controller | instance : ok=11 changed=2 unreachable=0 failed=0 skipped=3 rescued=0 ignored=0 2025-10-03 04:14:55.579842 | controller | 2025-10-03 04:14:55.579954 | controller | Friday 03 October 2025 04:14:55 +0000 (0:00:00.032) 0:00:02.707 ******** 2025-10-03 04:14:55.580052 | controller | =============================================================================== 2025-10-03 04:14:55.580154 | controller | Gathering Facts --------------------------------------------------------- 0.88s 2025-10-03 04:14:55.580256 | controller | reportportal : Create the metadata file --------------------------------- 0.61s 2025-10-03 04:14:55.580379 | controller | reportportal : Make sure the droute directory exists -------------------- 0.32s 2025-10-03 04:14:55.580518 | controller | Stat directory ---------------------------------------------------------- 0.21s 2025-10-03 04:14:55.580625 | controller | Stat metadata file ------------------------------------------------------ 0.21s 2025-10-03 04:14:55.580728 | controller | Stat metadata golden file ----------------------------------------------- 0.20s 2025-10-03 04:14:55.580830 | controller | reportportal : Check if mandatory variables are defined and not empty --- 0.05s 2025-10-03 04:14:55.580932 | controller | reportportal : Log droute result ---------------------------------------- 0.03s 2025-10-03 04:14:55.581033 | controller | Validate metadata golden is the same as produced metadata --------------- 0.03s 2025-10-03 04:14:55.581135 | controller | reportportal : Get the Data router client ------------------------------- 0.03s 2025-10-03 04:14:55.581237 | controller | reportportal : Send the results via Data router client call ------------- 0.03s 2025-10-03 04:14:55.581339 | controller | Validate file state ----------------------------------------------------- 0.03s 2025-10-03 04:14:55.581471 | controller | Validate directory state ------------------------------------------------ 0.03s 2025-10-03 04:14:55.581578 | controller | Validate metadata golden file state ------------------------------------- 0.03s 2025-10-03 04:14:55.653813 | controller | INFO Running default > cleanup 2025-10-03 04:14:55.654215 | controller | WARNING Skipping, cleanup playbook not configured. 2025-10-03 04:14:55.654866 | controller | INFO Writing /tmp/report.html report. 2025-10-03 04:14:55.980292 | controller | changed 2025-10-03 04:14:56.011271 | 2025-10-03 04:14:56.011391 | PLAY RECAP 2025-10-03 04:14:56.011434 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2025-10-03 04:14:56.011458 | 2025-10-03 04:14:56.108434 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main] 2025-10-03 04:14:56.109262 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main] 2025-10-03 04:14:56.698684 | 2025-10-03 04:14:56.698869 | PLAY [Run ci/playbooks/collect-logs.yml] 2025-10-03 04:14:56.725218 | 2025-10-03 04:14:56.725476 | TASK [Filter out host if needed] 2025-10-03 04:14:56.735813 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller 2025-10-03 04:14:56.740440 | 2025-10-03 04:14:56.740512 | TASK [Ensure file is present] 2025-10-03 04:14:57.109693 | controller | ok 2025-10-03 04:14:57.117568 | 2025-10-03 04:14:57.117644 | TASK [Manage molecule report file] 2025-10-03 04:14:57.646159 | controller | changed 2025-10-03 04:14:57.652948 | 2025-10-03 04:14:57.653112 | TASK [Check if we get ci-framework-data basedir] 2025-10-03 04:14:57.869765 | controller | ok 2025-10-03 04:14:57.875817 | 2025-10-03 04:14:57.875902 | TASK [Create ci-framework-data log directory for zuul] 2025-10-03 04:14:58.212836 | controller | changed 2025-10-03 04:14:58.218492 | 2025-10-03 04:14:58.218579 | TASK [Copy ci-framework interesting files] 2025-10-03 04:14:58.421491 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory 2025-10-03 04:14:58.742958 | controller | changed 2025-10-03 04:14:58.748065 | 2025-10-03 04:14:58.748132 | TASK [Get SELinux listing] 2025-10-03 04:14:59.271629 | controller | changed 2025-10-03 04:14:59.277518 | 2025-10-03 04:14:59.277589 | TASK [Generate log index] 2025-10-03 04:14:59.970618 | controller | changed 2025-10-03 04:14:59.975434 | 2025-10-03 04:14:59.975499 | TASK [Get some env related data] 2025-10-03 04:15:00.542342 | controller | /home/zuul/.local/bin/ansible 2025-10-03 04:15:01.504805 | controller | changed 2025-10-03 04:15:01.510446 | 2025-10-03 04:15:01.510540 | TASK [Generate list of logs to collect in home directory] 2025-10-03 04:15:01.827560 | controller | ok: All paths examined 2025-10-03 04:15:01.832717 | 2025-10-03 04:15:01.832820 | LOOP [Copy logs from home directory] 2025-10-03 04:15:02.170329 | controller | changed: 2025-10-03 04:15:02.170464 | controller | { 2025-10-03 04:15:02.170491 | controller | "atime": 1759464855.4456055, 2025-10-03 04:15:02.170510 | controller | "ctime": 1759464871.7496674, 2025-10-03 04:15:02.170528 | controller | "dev": 64513, 2025-10-03 04:15:02.170543 | controller | "gid": 1000, 2025-10-03 04:15:02.170559 | controller | "gr_name": "zuul", 2025-10-03 04:15:02.170574 | controller | "inode": 4450743, 2025-10-03 04:15:02.170589 | controller | "isblk": false, 2025-10-03 04:15:02.170604 | controller | "ischr": false, 2025-10-03 04:15:02.170618 | controller | "isdir": false, 2025-10-03 04:15:02.170632 | controller | "isfifo": false, 2025-10-03 04:15:02.170647 | controller | "isgid": false, 2025-10-03 04:15:02.170660 | controller | "islnk": false, 2025-10-03 04:15:02.170674 | controller | "isreg": true, 2025-10-03 04:15:02.170687 | controller | "issock": false, 2025-10-03 04:15:02.170700 | controller | "isuid": false, 2025-10-03 04:15:02.170713 | controller | "mode": "0644", 2025-10-03 04:15:02.170726 | controller | "mtime": 1759464871.7496674, 2025-10-03 04:15:02.170756 | controller | "nlink": 1, 2025-10-03 04:15:02.170771 | controller | "path": "/home/zuul/ansible.log", 2025-10-03 04:15:02.170786 | controller | "pw_name": "zuul", 2025-10-03 04:15:02.170800 | controller | "rgrp": true, 2025-10-03 04:15:02.170814 | controller | "roth": true, 2025-10-03 04:15:02.170827 | controller | "rusr": true, 2025-10-03 04:15:02.170842 | controller | "size": 6749, 2025-10-03 04:15:02.170857 | controller | "uid": 1000, 2025-10-03 04:15:02.170870 | controller | "wgrp": false, 2025-10-03 04:15:02.170884 | controller | "woth": false, 2025-10-03 04:15:02.170901 | controller | "wusr": true, 2025-10-03 04:15:02.170936 | controller | "xgrp": false, 2025-10-03 04:15:02.170953 | controller | "xoth": false, 2025-10-03 04:15:02.170967 | controller | "xusr": false 2025-10-03 04:15:02.170981 | controller | } 2025-10-03 04:15:02.182644 | 2025-10-03 04:15:02.182719 | TASK [Copy crio stats log file] 2025-10-03 04:15:02.207519 | controller | skipping: Conditional result was False 2025-10-03 04:15:02.213140 | 2025-10-03 04:15:02.213221 | TASK [Get SELinux related data] 2025-10-03 04:15:02.440384 | controller | 2025-10-03 04:15:02.741130 | controller | ERROR 2025-10-03 04:15:02.741313 | controller | { 2025-10-03 04:15:02.741351 | controller | "delta": "0:00:00.010919", 2025-10-03 04:15:02.741373 | controller | "end": "2025-10-03 04:15:02.441605", 2025-10-03 04:15:02.741393 | controller | "msg": "non-zero return code", 2025-10-03 04:15:02.741411 | controller | "rc": 1, 2025-10-03 04:15:02.741429 | controller | "start": "2025-10-03 04:15:02.430686" 2025-10-03 04:15:02.741446 | controller | } 2025-10-03 04:15:02.741471 | controller | ERROR: Ignoring Errors 2025-10-03 04:15:02.746840 | 2025-10-03 04:15:02.746929 | TASK [Create system configuration directory] 2025-10-03 04:15:02.937831 | controller | changed 2025-10-03 04:15:02.943039 | 2025-10-03 04:15:02.943142 | TASK [Get some of the system configurations] 2025-10-03 04:15:03.470477 | controller | changed 2025-10-03 04:15:03.475994 | 2025-10-03 04:15:03.476062 | TASK [Copy generated documentation if available] 2025-10-03 04:15:03.490102 | controller | skipping: Conditional result was False 2025-10-03 04:15:03.496102 | 2025-10-03 04:15:03.496176 | TASK [Copy generated AsciiDoc documentation if available] 2025-10-03 04:15:03.509989 | controller | skipping: Conditional result was False 2025-10-03 04:15:03.516378 | 2025-10-03 04:15:03.516445 | TASK [Compress logs bigger than 2MB] 2025-10-03 04:15:04.043339 | controller | changed 2025-10-03 04:15:04.051954 | 2025-10-03 04:15:04.052145 | TASK [Copy files from workspace on node] 2025-10-03 04:15:04.072376 | controller | ok 2025-10-03 04:15:04.097628 | 2025-10-03 04:15:04.097729 | TASK [fetch-output : Set log path for multiple nodes] 2025-10-03 04:15:04.112874 | controller | skipping: Conditional result was False 2025-10-03 04:15:04.120225 | 2025-10-03 04:15:04.120314 | TASK [fetch-output : Set log path for single node] 2025-10-03 04:15:04.153483 | controller | ok 2025-10-03 04:15:04.159743 | 2025-10-03 04:15:04.159848 | LOOP [fetch-output : Ensure local output dirs] 2025-10-03 04:15:04.370867 | controller -> localhost | ok: "/var/lib/zuul/builds/799ea728c94c48448cb78c7a4b27f02d/work/logs" 2025-10-03 04:15:04.371126 | controller -> localhost | changed: All items complete 2025-10-03 04:15:04.371160 | 2025-10-03 04:15:04.589422 | controller -> localhost | changed: "/var/lib/zuul/builds/799ea728c94c48448cb78c7a4b27f02d/work/artifacts" 2025-10-03 04:15:04.780884 | controller -> localhost | changed: "/var/lib/zuul/builds/799ea728c94c48448cb78c7a4b27f02d/work/docs" 2025-10-03 04:15:04.795595 | 2025-10-03 04:15:04.795718 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-10-03 04:15:05.387898 | controller | changed: 2025-10-03 04:15:05.388105 | controller | .d..t...... ./ 2025-10-03 04:15:05.388140 | controller | >f+++++++++ README.html 2025-10-03 04:15:05.388164 | controller | >f+++++++++ ansible-execution.log 2025-10-03 04:15:05.388189 | controller | >f+++++++++ ansible.log 2025-10-03 04:15:05.388210 | controller | >f+++++++++ dmesg.log 2025-10-03 04:15:05.388230 | controller | >f+++++++++ installed-pkgs.log 2025-10-03 04:15:05.388252 | controller | >f+++++++++ python.log 2025-10-03 04:15:05.388275 | controller | >f+++++++++ registries.conf 2025-10-03 04:15:05.388295 | controller | >f+++++++++ report.html 2025-10-03 04:15:05.388316 | controller | >f+++++++++ selinux-denials.log 2025-10-03 04:15:05.388336 | controller | >f+++++++++ selinux-listing.log 2025-10-03 04:15:05.388355 | controller | cd+++++++++ ci-framework-data/ 2025-10-03 04:15:05.388373 | controller | cd+++++++++ ci-framework-data/artifacts/ 2025-10-03 04:15:05.388392 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml 2025-10-03 04:15:05.388410 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/ 2025-10-03 04:15:05.388427 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml 2025-10-03 04:15:05.388444 | controller | cd+++++++++ ci-framework-data/logs/ 2025-10-03 04:15:05.388461 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log 2025-10-03 04:15:05.388479 | controller | cd+++++++++ registries.conf.d/ 2025-10-03 04:15:05.388496 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf 2025-10-03 04:15:05.388514 | controller | cd+++++++++ system-config/ 2025-10-03 04:15:05.388531 | controller | cd+++++++++ system-config/libvirt/ 2025-10-03 04:15:05.388548 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf 2025-10-03 04:15:05.388565 | controller | >f+++++++++ system-config/libvirt/libvirt.conf 2025-10-03 04:15:05.768276 | controller | changed: .d..t...... ./ 2025-10-03 04:15:06.206752 | controller | changed: .d..t...... ./ 2025-10-03 04:15:06.224502 | 2025-10-03 04:15:06.224657 | TASK [Return artifact to Zuul] 2025-10-03 04:15:06.255344 | controller | ok 2025-10-03 04:15:06.278187 | 2025-10-03 04:15:06.278265 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host] 2025-10-03 04:15:06.278461 | 2025-10-03 04:15:06.278499 | PLAY RECAP 2025-10-03 04:15:06.278543 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1 2025-10-03 04:15:06.278565 | 2025-10-03 04:15:06.433568 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main] 2025-10-03 04:15:06.434389 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2025-10-03 04:15:07.031511 | 2025-10-03 04:15:07.031667 | PLAY [all] 2025-10-03 04:15:07.052649 | 2025-10-03 04:15:07.052725 | TASK [include_role : fetch-output] 2025-10-03 04:15:07.097537 | controller | ok 2025-10-03 04:15:07.115219 | 2025-10-03 04:15:07.115325 | TASK [fetch-output : Set log path for multiple nodes] 2025-10-03 04:15:07.159945 | controller | skipping: Conditional result was False 2025-10-03 04:15:07.166373 | 2025-10-03 04:15:07.166452 | TASK [fetch-output : Set log path for single node] 2025-10-03 04:15:07.196307 | controller | ok 2025-10-03 04:15:07.201549 | 2025-10-03 04:15:07.201626 | LOOP [fetch-output : Ensure local output dirs] 2025-10-03 04:15:07.565096 | controller -> localhost | ok: "/var/lib/zuul/builds/799ea728c94c48448cb78c7a4b27f02d/work/logs" 2025-10-03 04:15:07.776460 | controller -> localhost | ok: "/var/lib/zuul/builds/799ea728c94c48448cb78c7a4b27f02d/work/artifacts" 2025-10-03 04:15:07.979394 | controller -> localhost | ok: "/var/lib/zuul/builds/799ea728c94c48448cb78c7a4b27f02d/work/docs" 2025-10-03 04:15:07.990635 | 2025-10-03 04:15:07.990795 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-10-03 04:15:08.537785 | controller | ok 2025-10-03 04:15:08.538031 | controller | ok: All items complete 2025-10-03 04:15:08.538065 | 2025-10-03 04:15:08.968881 | controller | ok 2025-10-03 04:15:09.383637 | controller | ok 2025-10-03 04:15:09.403106 | 2025-10-03 04:15:09.403198 | TASK [include_role : fetch-output-openshift] 2025-10-03 04:15:09.417619 | controller | skipping: Conditional result was False 2025-10-03 04:15:09.423112 | 2025-10-03 04:15:09.423184 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-10-03 04:15:09.800479 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.006522 2025-10-03 04:15:10.013656 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.008591 2025-10-03 04:15:10.046668 | 2025-10-03 04:15:10.046764 | PLAY [all] 2025-10-03 04:15:10.062216 | 2025-10-03 04:15:10.062283 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-10-03 04:15:10.502255 | controller | changed 2025-10-03 04:15:10.527258 | 2025-10-03 04:15:10.527394 | PLAY RECAP 2025-10-03 04:15:10.527449 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2025-10-03 04:15:10.527476 | 2025-10-03 04:15:10.605291 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2025-10-03 04:15:10.606088 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master] 2025-10-03 04:15:11.203981 | 2025-10-03 04:15:11.204149 | PLAY [localhost] 2025-10-03 04:15:11.222622 | 2025-10-03 04:15:11.222717 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-10-03 04:15:11.544638 | localhost | changed 2025-10-03 04:15:11.549387 | 2025-10-03 04:15:11.549469 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-10-03 04:15:11.566639 | localhost | ok 2025-10-03 04:15:11.574703 | 2025-10-03 04:15:11.574787 | TASK [add-fileserver : Create SSH private key tempfile] 2025-10-03 04:15:11.885844 | localhost | changed 2025-10-03 04:15:11.893179 | 2025-10-03 04:15:11.893272 | TASK [add-fileserver : Create SSH private key from secret] 2025-10-03 04:15:12.463092 | localhost | changed 2025-10-03 04:15:12.468376 | 2025-10-03 04:15:12.468450 | TASK [add-fileserver : Add fileserver ssh key] 2025-10-03 04:15:12.811028 | localhost | Identity added: /var/lib/zuul/builds/799ea728c94c48448cb78c7a4b27f02d/work/tmp/ansible.z1f5w02z (/var/lib/zuul/builds/799ea728c94c48448cb78c7a4b27f02d/work/tmp/ansible.z1f5w02z) 2025-10-03 04:15:12.811194 | localhost | ok: Runtime: 0:00:00.006984 2025-10-03 04:15:12.820977 | 2025-10-03 04:15:12.821160 | TASK [add-fileserver : Remove SSH private key from disk] 2025-10-03 04:15:13.053120 | localhost | ok: Runtime: 0:00:00.005096 2025-10-03 04:15:13.060506 | 2025-10-03 04:15:13.060597 | TASK [add-fileserver : Add fileserver to inventory] 2025-10-03 04:15:13.123320 | localhost | changed 2025-10-03 04:15:13.129266 | 2025-10-03 04:15:13.129352 | TASK [add-fileserver : Add fileserver server to known hosts] 2025-10-03 04:15:13.474222 | localhost | changed 2025-10-03 04:15:13.496147 | 2025-10-03 04:15:13.496303 | PLAY [localhost] 2025-10-03 04:15:13.509155 | 2025-10-03 04:15:13.509217 | TASK [Generate bulk log download script] 2025-10-03 04:15:13.527945 | localhost | ok 2025-10-03 04:15:13.540164 | 2025-10-03 04:15:13.540231 | TASK [local-log-download : Check API endpoint is defined] 2025-10-03 04:15:13.567900 | localhost | ok: All assertions passed 2025-10-03 04:15:13.572497 | 2025-10-03 04:15:13.572563 | TASK [local-log-download : Create download script] 2025-10-03 04:15:13.960469 | localhost -> localhost | changed 2025-10-03 04:15:13.970055 | 2025-10-03 04:15:13.970127 | TASK [Register quick-download link] 2025-10-03 04:15:13.988425 | localhost | ok 2025-10-03 04:15:14.026408 | 2025-10-03 04:15:14.026507 | PLAY [logserver.rdoproject.org] 2025-10-03 04:15:14.036773 | 2025-10-03 04:15:14.036839 | TASK [Set zuul-log-path fact] 2025-10-03 04:15:14.052819 | logserver.rdoproject.org | ok 2025-10-03 04:15:14.062160 | 2025-10-03 04:15:14.062229 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-03 04:15:14.078327 | logserver.rdoproject.org | ok 2025-10-03 04:15:14.084298 | 2025-10-03 04:15:14.084380 | TASK [upload-logs : Create log directories] 2025-10-03 04:15:15.715606 | logserver.rdoproject.org | changed 2025-10-03 04:15:15.718863 | 2025-10-03 04:15:15.718962 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-10-03 04:15:15.955641 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.004877 2025-10-03 04:15:15.961633 | 2025-10-03 04:15:15.961704 | TASK [upload-logs : Upload logs to log server] 2025-10-03 04:15:17.520005 | logserver.rdoproject.org | Output suppressed because no_log was given 2025-10-03 04:15:17.523378 | 2025-10-03 04:15:17.523483 | LOOP [upload-logs : Compress console log and json output] 2025-10-03 04:15:17.565165 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-03 04:15:17.573698 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-03 04:15:17.582346 | 2025-10-03 04:15:17.582494 | LOOP [upload-logs : Upload compressed console log and json output] 2025-10-03 04:15:17.623753 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-03 04:15:17.624109 | 2025-10-03 04:15:17.627500 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-03 04:15:17.640958 | 2025-10-03 04:15:17.641148 | LOOP [upload-logs : Upload console log and json output]