2025-10-03 07:45:09.493439 | Job console starting... 2025-10-03 07:45:09.732014 | Updating repositories 2025-10-03 07:45:10.511309 | Preparing job workspace 2025-10-03 07:45:19.383648 | Running Ansible setup... 2025-10-03 07:45:23.177873 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2025-10-03 07:45:23.781116 | 2025-10-03 07:45:23.781221 | PLAY [localhost] 2025-10-03 07:45:23.789949 | 2025-10-03 07:45:23.790020 | TASK [Gathering Facts] 2025-10-03 07:45:24.680296 | localhost | ok 2025-10-03 07:45:24.694650 | 2025-10-03 07:45:24.694733 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-10-03 07:45:25.056509 | localhost -> localhost | changed 2025-10-03 07:45:25.062489 | 2025-10-03 07:45:25.062565 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-10-03 07:45:25.806207 | localhost -> localhost | changed 2025-10-03 07:45:25.815739 | 2025-10-03 07:45:25.815848 | TASK [Setup log path fact] 2025-10-03 07:45:25.833757 | localhost | ok 2025-10-03 07:45:25.849310 | 2025-10-03 07:45:25.849418 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-03 07:45:25.869682 | localhost | ok 2025-10-03 07:45:25.879210 | 2025-10-03 07:45:25.879302 | TASK [emit-job-header : Print job information] 2025-10-03 07:45:25.911439 | # Job Information 2025-10-03 07:45:25.911589 | Ansible Version: 2.15.12 2025-10-03 07:45:25.911626 | Job: cifmw-molecule-reportportal 2025-10-03 07:45:25.911650 | Pipeline: github-check 2025-10-03 07:45:25.911671 | Executor: ibm-bm4-ze.softwarefactory-project.io 2025-10-03 07:45:25.911690 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3362 2025-10-03 07:45:25.911710 | Log URL (when completed): https://logserver.rdoproject.org/968/rdoproject.org/9689a3dea0d2415f9842ed19a2199c70/ 2025-10-03 07:45:25.911730 | Event ID: f958eab0-a02a-11f0-9ba1-0df9636946d8 2025-10-03 07:45:25.915824 | 2025-10-03 07:45:25.915897 | LOOP [emit-job-header : Print node information] 2025-10-03 07:45:26.003712 | localhost | ok: 2025-10-03 07:45:26.003874 | localhost | # Node Information 2025-10-03 07:45:26.003921 | localhost | Inventory Hostname: controller 2025-10-03 07:45:26.003954 | localhost | Hostname: np0005468285 2025-10-03 07:45:26.003976 | localhost | Username: zuul 2025-10-03 07:45:26.003998 | localhost | Distro: CentOS 9 2025-10-03 07:45:26.004016 | localhost | Provider: ibm-bm4-nodepool 2025-10-03 07:45:26.004034 | localhost | Region: regionOne 2025-10-03 07:45:26.004051 | localhost | Label: cloud-centos-9-stream-tripleo-ibm 2025-10-03 07:45:26.004068 | localhost | Product Name: OpenStack Compute 2025-10-03 07:45:26.004085 | localhost | Interface IP: 192.168.26.89 2025-10-03 07:45:26.032659 | 2025-10-03 07:45:26.032792 | PLAY [all] 2025-10-03 07:45:26.039099 | 2025-10-03 07:45:26.039160 | TASK [Gather network facts] 2025-10-03 07:45:26.389851 | controller | ok 2025-10-03 07:45:26.409317 | 2025-10-03 07:45:26.409404 | TASK [include_role : start-zuul-console] 2025-10-03 07:45:26.427084 | controller | ok 2025-10-03 07:45:26.439040 | 2025-10-03 07:45:26.439115 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-10-03 07:45:26.742105 | controller | ok 2025-10-03 07:45:26.750798 | 2025-10-03 07:45:26.750875 | TASK [include_role : add-build-sshkey] 2025-10-03 07:45:26.768448 | controller | ok 2025-10-03 07:45:26.783051 | 2025-10-03 07:45:26.783125 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-03 07:45:27.062223 | controller -> localhost | ok 2025-10-03 07:45:27.069473 | 2025-10-03 07:45:27.069555 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-03 07:45:27.108059 | controller | ok 2025-10-03 07:45:27.149287 | controller | included: /var/lib/zuul/builds/9689a3dea0d2415f9842ed19a2199c70/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-03 07:45:27.166458 | 2025-10-03 07:45:27.166549 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-03 07:45:27.970737 | controller -> localhost | Generating public/private rsa key pair. 2025-10-03 07:45:27.970956 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/9689a3dea0d2415f9842ed19a2199c70/work/9689a3dea0d2415f9842ed19a2199c70_id_rsa. 2025-10-03 07:45:27.970996 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/9689a3dea0d2415f9842ed19a2199c70/work/9689a3dea0d2415f9842ed19a2199c70_id_rsa.pub. 2025-10-03 07:45:27.971022 | controller -> localhost | The key fingerprint is: 2025-10-03 07:45:27.971044 | controller -> localhost | SHA256:htQ7shBBvic0avqv/Zkr9ix9Cd8Wuth/+R0YqvD6kfE zuul-build-sshkey 2025-10-03 07:45:27.971065 | controller -> localhost | The key's randomart image is: 2025-10-03 07:45:27.971085 | controller -> localhost | +---[RSA 3072]----+ 2025-10-03 07:45:27.971105 | controller -> localhost | | .o | 2025-10-03 07:45:27.971130 | controller -> localhost | | . . . | 2025-10-03 07:45:27.971149 | controller -> localhost | | = . . | 2025-10-03 07:45:27.971169 | controller -> localhost | | o = . . | 2025-10-03 07:45:27.971187 | controller -> localhost | | o + + S. . | 2025-10-03 07:45:27.971206 | controller -> localhost | | o +.+ .= . o | 2025-10-03 07:45:27.971236 | controller -> localhost | |. ..+ * E o . | 2025-10-03 07:45:27.971258 | controller -> localhost | | . .+..=B = o ..| 2025-10-03 07:45:27.971277 | controller -> localhost | | o+o=O+=B.. .. .| 2025-10-03 07:45:27.971296 | controller -> localhost | +----[SHA256]-----+ 2025-10-03 07:45:27.971339 | controller -> localhost | ok: Runtime: 0:00:00.338049 2025-10-03 07:45:27.976883 | 2025-10-03 07:45:27.976963 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-03 07:45:27.995399 | controller | ok 2025-10-03 07:45:28.007096 | controller | included: /var/lib/zuul/builds/9689a3dea0d2415f9842ed19a2199c70/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-03 07:45:28.015360 | 2025-10-03 07:45:28.015424 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-03 07:45:28.029636 | controller | skipping: Conditional result was False 2025-10-03 07:45:28.035045 | 2025-10-03 07:45:28.035112 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-03 07:45:28.409854 | controller | changed 2025-10-03 07:45:28.414693 | 2025-10-03 07:45:28.414767 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-03 07:45:28.595195 | controller | ok 2025-10-03 07:45:28.600084 | 2025-10-03 07:45:28.600161 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-03 07:45:29.150738 | controller | changed 2025-10-03 07:45:29.156480 | 2025-10-03 07:45:29.156546 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-03 07:45:29.690287 | controller | changed 2025-10-03 07:45:29.695289 | 2025-10-03 07:45:29.695354 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-03 07:45:29.708676 | controller | skipping: Conditional result was False 2025-10-03 07:45:29.715115 | 2025-10-03 07:45:29.715188 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-03 07:45:30.034459 | controller -> localhost | changed 2025-10-03 07:45:30.048495 | 2025-10-03 07:45:30.048635 | TASK [add-build-sshkey : Add back temp key] 2025-10-03 07:45:30.319500 | controller -> localhost | Identity added: /var/lib/zuul/builds/9689a3dea0d2415f9842ed19a2199c70/work/9689a3dea0d2415f9842ed19a2199c70_id_rsa (zuul-build-sshkey) 2025-10-03 07:45:30.319667 | controller -> localhost | ok: Runtime: 0:00:00.007451 2025-10-03 07:45:30.325676 | 2025-10-03 07:45:30.325744 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-03 07:45:30.619623 | controller | ok 2025-10-03 07:45:30.624302 | 2025-10-03 07:45:30.624371 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-03 07:45:30.647872 | controller | skipping: Conditional result was False 2025-10-03 07:45:30.656726 | 2025-10-03 07:45:30.656805 | TASK [include_role : validate-host] 2025-10-03 07:45:30.673963 | controller | ok 2025-10-03 07:45:30.693373 | 2025-10-03 07:45:30.693446 | TASK [validate-host : Define zuul_info_dir fact] 2025-10-03 07:45:30.720672 | controller | ok 2025-10-03 07:45:30.725456 | 2025-10-03 07:45:30.725523 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-10-03 07:45:30.950980 | controller -> localhost | ok 2025-10-03 07:45:30.956784 | 2025-10-03 07:45:30.956869 | TASK [validate-host : Collect information about the host] 2025-10-03 07:45:31.545199 | controller | ok 2025-10-03 07:45:31.557394 | 2025-10-03 07:45:31.557501 | TASK [validate-host : Sanitize hostname] 2025-10-03 07:45:31.637273 | controller | ok 2025-10-03 07:45:31.662092 | 2025-10-03 07:45:31.662185 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-10-03 07:45:32.060496 | controller -> localhost | changed 2025-10-03 07:45:32.066367 | 2025-10-03 07:45:32.066439 | TASK [validate-host : Collect information about zuul worker] 2025-10-03 07:45:32.379266 | controller | ok 2025-10-03 07:45:32.384800 | 2025-10-03 07:45:32.384894 | TASK [validate-host : Write out all zuul information for each host] 2025-10-03 07:45:32.800887 | controller -> localhost | changed 2025-10-03 07:45:32.811385 | 2025-10-03 07:45:32.811451 | TASK [include_role : prepare-workspace-openshift] 2025-10-03 07:45:32.847388 | controller | skipping: Conditional result was False 2025-10-03 07:45:32.852682 | 2025-10-03 07:45:32.852753 | TASK [include_role : remove-zuul-sshkey] 2025-10-03 07:45:32.867543 | controller | skipping: Conditional result was False 2025-10-03 07:45:32.872966 | 2025-10-03 07:45:32.873036 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2025-10-03 07:45:33.073640 | controller | ok: "logs" 2025-10-03 07:45:33.073879 | controller | ok: All items complete 2025-10-03 07:45:33.073931 | 2025-10-03 07:45:33.231954 | controller | ok: "artifacts" 2025-10-03 07:45:33.395187 | controller | ok: "docs" 2025-10-03 07:45:33.401219 | 2025-10-03 07:45:33.401324 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2025-10-03 07:45:33.591855 | controller | changed: "logs" 2025-10-03 07:45:33.754977 | controller | changed: "artifacts" 2025-10-03 07:45:33.916823 | controller | changed: "docs" 2025-10-03 07:45:33.943950 | 2025-10-03 07:45:33.944027 | PLAY RECAP 2025-10-03 07:45:33.944073 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0 2025-10-03 07:45:33.944102 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-03 07:45:33.944123 | 2025-10-03 07:45:34.028288 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2025-10-03 07:45:34.029023 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master] 2025-10-03 07:45:34.625285 | 2025-10-03 07:45:34.625399 | PLAY [all] 2025-10-03 07:45:34.653697 | 2025-10-03 07:45:34.653829 | TASK [mirror-info-fork : Set mirror_fqdn if not defined] 2025-10-03 07:45:34.713442 | controller | ok 2025-10-03 07:45:34.718417 | 2025-10-03 07:45:34.718508 | TASK [mirror-info-fork : Create /etc/ci] 2025-10-03 07:45:35.046080 | controller | changed 2025-10-03 07:45:35.051578 | 2025-10-03 07:45:35.051656 | TASK [mirror-info-fork : Install ci_mirror script] 2025-10-03 07:45:35.829458 | controller | changed 2025-10-03 07:45:35.838904 | 2025-10-03 07:45:35.839021 | LOOP [add-authorized-keys : Enable access via build key on all nodes] 2025-10-03 07:45:36.197741 | controller | changed: 2025-10-03 07:45:36.197939 | controller | { 2025-10-03 07:45:36.197973 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com" 2025-10-03 07:45:36.198006 | controller | } 2025-10-03 07:45:36.405371 | controller | changed: 2025-10-03 07:45:36.405462 | controller | { 2025-10-03 07:45:36.405492 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com" 2025-10-03 07:45:36.405515 | controller | } 2025-10-03 07:45:36.605181 | controller | changed: 2025-10-03 07:45:36.605273 | controller | { 2025-10-03 07:45:36.605299 | 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 07:45:36.605320 | controller | } 2025-10-03 07:45:36.807177 | controller | changed: 2025-10-03 07:45:36.807280 | controller | { 2025-10-03 07:45:36.807314 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com" 2025-10-03 07:45:36.807341 | controller | } 2025-10-03 07:45:37.013017 | controller | changed: 2025-10-03 07:45:37.013114 | controller | { 2025-10-03 07:45:37.013142 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com" 2025-10-03 07:45:37.013163 | controller | } 2025-10-03 07:45:37.219146 | controller | changed: 2025-10-03 07:45:37.219230 | controller | { 2025-10-03 07:45:37.219259 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com" 2025-10-03 07:45:37.219280 | controller | } 2025-10-03 07:45:37.423395 | controller | changed: 2025-10-03 07:45:37.423500 | controller | { 2025-10-03 07:45:37.423605 | 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 07:45:37.423639 | controller | } 2025-10-03 07:45:37.620021 | controller | changed: 2025-10-03 07:45:37.620112 | controller | { 2025-10-03 07:45:37.620141 | 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 07:45:37.620164 | controller | } 2025-10-03 07:45:37.827610 | controller | changed: 2025-10-03 07:45:37.827708 | controller | { 2025-10-03 07:45:37.827737 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner" 2025-10-03 07:45:37.827760 | controller | } 2025-10-03 07:45:38.034584 | controller | changed: 2025-10-03 07:45:38.034676 | controller | { 2025-10-03 07:45:38.034704 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com" 2025-10-03 07:45:38.034727 | controller | } 2025-10-03 07:45:38.241662 | controller | changed: 2025-10-03 07:45:38.241754 | controller | { 2025-10-03 07:45:38.241800 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com" 2025-10-03 07:45:38.241823 | controller | } 2025-10-03 07:45:38.484783 | controller | changed: 2025-10-03 07:45:38.484874 | controller | { 2025-10-03 07:45:38.484902 | controller | "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com" 2025-10-03 07:45:38.487005 | controller | } 2025-10-03 07:45:38.664203 | controller | changed: 2025-10-03 07:45:38.664295 | controller | { 2025-10-03 07:45:38.664325 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith" 2025-10-03 07:45:38.664347 | controller | } 2025-10-03 07:45:38.867817 | controller | changed: 2025-10-03 07:45:38.867898 | controller | { 2025-10-03 07:45:38.867952 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel" 2025-10-03 07:45:38.867976 | controller | } 2025-10-03 07:45:39.071188 | controller | changed: 2025-10-03 07:45:39.071279 | controller | { 2025-10-03 07:45:39.071306 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux" 2025-10-03 07:45:39.071328 | controller | } 2025-10-03 07:45:39.273175 | controller | changed: 2025-10-03 07:45:39.273259 | controller | { 2025-10-03 07:45:39.273288 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr" 2025-10-03 07:45:39.273310 | controller | } 2025-10-03 07:45:39.473674 | controller | changed: 2025-10-03 07:45:39.473763 | controller | { 2025-10-03 07:45:39.473811 | 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 07:45:39.473835 | controller | } 2025-10-03 07:45:39.672037 | controller | changed: 2025-10-03 07:45:39.672114 | controller | { 2025-10-03 07:45:39.672140 | 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 07:45:39.672168 | controller | } 2025-10-03 07:45:39.874334 | controller | changed: 2025-10-03 07:45:39.874570 | controller | { 2025-10-03 07:45:39.874606 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9" 2025-10-03 07:45:39.874629 | controller | } 2025-10-03 07:45:40.074302 | controller | changed: 2025-10-03 07:45:40.074374 | controller | { 2025-10-03 07:45:40.074401 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora" 2025-10-03 07:45:40.074422 | controller | } 2025-10-03 07:45:40.279439 | controller | changed: 2025-10-03 07:45:40.279523 | controller | { 2025-10-03 07:45:40.279549 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac" 2025-10-03 07:45:40.279570 | controller | } 2025-10-03 07:45:40.482155 | controller | changed: 2025-10-03 07:45:40.482239 | controller | { 2025-10-03 07:45:40.482266 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop" 2025-10-03 07:45:40.482286 | controller | } 2025-10-03 07:45:40.686635 | controller | changed: 2025-10-03 07:45:40.686703 | controller | { 2025-10-03 07:45:40.686730 | 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 07:45:40.686752 | controller | } 2025-10-03 07:45:40.890614 | controller | changed: 2025-10-03 07:45:40.890704 | controller | { 2025-10-03 07:45:40.890732 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com" 2025-10-03 07:45:40.890752 | controller | } 2025-10-03 07:45:41.096108 | controller | changed: 2025-10-03 07:45:41.096192 | controller | { 2025-10-03 07:45:41.096220 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com" 2025-10-03 07:45:41.096240 | controller | } 2025-10-03 07:45:41.300223 | controller | changed: 2025-10-03 07:45:41.300310 | controller | { 2025-10-03 07:45:41.300340 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono" 2025-10-03 07:45:41.300361 | controller | } 2025-10-03 07:45:41.318696 | 2025-10-03 07:45:41.318769 | TASK [Set timezone to UTC] 2025-10-03 07:45:41.701275 | controller | changed: executed `/bin/timedatectl set-timezone UTC` 2025-10-03 07:45:41.706038 | 2025-10-03 07:45:41.706122 | TASK [Create nodepool directory] 2025-10-03 07:45:41.907066 | controller | changed 2025-10-03 07:45:41.912411 | 2025-10-03 07:45:41.912491 | TASK [Create nodepool sub_nodes file] 2025-10-03 07:45:42.405961 | controller | changed 2025-10-03 07:45:42.410865 | 2025-10-03 07:45:42.410957 | TASK [Create nodepool sub_nodes_private file] 2025-10-03 07:45:42.885150 | controller | changed 2025-10-03 07:45:42.890523 | 2025-10-03 07:45:42.890588 | LOOP [Populate nodepool sub_nodes file] 2025-10-03 07:45:42.919253 | 2025-10-03 07:45:42.919390 | LOOP [Populate nodepool sub_nodes_private file] 2025-10-03 07:45:42.947971 | 2025-10-03 07:45:42.948104 | TASK [Create nodepool primary file] 2025-10-03 07:45:42.971422 | controller | skipping: Conditional result was False 2025-10-03 07:45:42.976665 | 2025-10-03 07:45:42.976744 | TASK [Create nodepool node_private for this node] 2025-10-03 07:45:43.490581 | controller | changed 2025-10-03 07:45:43.495863 | 2025-10-03 07:45:43.495941 | LOOP [Copy ssh keys to nodepool directory] 2025-10-03 07:45:43.825871 | controller | ok: Item: id_rsa Runtime: 0:00:00.005399 2025-10-03 07:45:43.826110 | 2025-10-03 07:45:43.991286 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.004544 2025-10-03 07:45:44.005456 | 2025-10-03 07:45:44.005525 | TASK [Add sudoers role for zuul-sudo-grep.sh] 2025-10-03 07:45:44.519653 | controller | changed 2025-10-03 07:45:44.524537 | 2025-10-03 07:45:44.524600 | TASK [Validate sudoers config after edits] 2025-10-03 07:45:44.733681 | controller | /etc/sudoers: parsed OK 2025-10-03 07:45:44.733806 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK 2025-10-03 07:45:44.733822 | controller | /etc/sudoers.d/zuul: parsed OK 2025-10-03 07:45:44.733832 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK 2025-10-03 07:45:45.050133 | controller | ok: Runtime: 0:00:00.005583 2025-10-03 07:45:45.056013 | 2025-10-03 07:45:45.056098 | TASK [Show the environment passed in to job shell scripts] 2025-10-03 07:45:45.251329 | controller | SHELL=/bin/bash 2025-10-03 07:45:45.251364 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework 2025-10-03 07:45:45.251374 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework 2025-10-03 07:45:45.251380 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/62/3362/41f2edaa0ac45cd85919f70775e84cf472326a6e 2025-10-03 07:45:45.251386 | controller | PWD=/home/zuul 2025-10-03 07:45:45.251391 | controller | ZUUL_PIPELINE=github-check 2025-10-03 07:45:45.251396 | controller | LOGNAME=zuul 2025-10-03 07:45:45.251402 | controller | XDG_SESSION_TYPE=tty 2025-10-03 07:45:45.251434 | controller | _=/usr/bin/env 2025-10-03 07:45:45.251442 | controller | MOTD_SHOWN=pam 2025-10-03 07:45:45.251447 | controller | HOME=/home/zuul 2025-10-03 07:45:45.251453 | controller | LANG=en_US.UTF-8 2025-10-03 07:45:45.251458 | controller | SSH_CONNECTION=192.168.26.12 44884 192.168.26.89 22 2025-10-03 07:45:45.251463 | 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 07:45:45.251471 | controller | ZUUL_CHANGE_IDS=3362,41f2edaa0ac45cd85919f70775e84cf472326a6e 2025-10-03 07:45:45.251477 | controller | WORKSPACE=/home/zuul/workspace 2025-10-03 07:45:45.251482 | controller | XDG_SESSION_CLASS=user 2025-10-03 07:45:45.251487 | controller | SELINUX_ROLE_REQUESTED= 2025-10-03 07:45:45.251492 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s 2025-10-03 07:45:45.251497 | controller | USER=zuul 2025-10-03 07:45:45.251502 | controller | ZUUL_VOTING=True 2025-10-03 07:45:45.251508 | controller | BUILD_TIMEOUT=1800000 2025-10-03 07:45:45.251513 | controller | SELINUX_USE_CURRENT_RANGE= 2025-10-03 07:45:45.251518 | controller | SHLVL=1 2025-10-03 07:45:45.251523 | controller | ZUUL_PATCHSET=41f2edaa0ac45cd85919f70775e84cf472326a6e 2025-10-03 07:45:45.251528 | controller | XDG_SESSION_ID=1 2025-10-03 07:45:45.251534 | controller | ZUUL_BRANCH=main 2025-10-03 07:45:45.251539 | controller | XDG_RUNTIME_DIR=/run/user/1000 2025-10-03 07:45:45.251544 | controller | SSH_CLIENT=192.168.26.12 44884 22 2025-10-03 07:45:45.251549 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/ 2025-10-03 07:45:45.251554 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima: 2025-10-03 07:45:45.251561 | controller | which_declare=declare -f 2025-10-03 07:45:45.251566 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin 2025-10-03 07:45:45.251571 | controller | SELINUX_LEVEL_REQUESTED= 2025-10-03 07:45:45.251577 | controller | ZUUL_CHANGE=3362 2025-10-03 07:45:45.251582 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus 2025-10-03 07:45:45.251587 | controller | ZUUL_UUID=9689a3dea0d2415f9842ed19a2199c70 2025-10-03 07:45:45.251592 | controller | BASH_FUNC_which%%=() { ( alias; 2025-10-03 07:45:45.251598 | controller | eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@ 2025-10-03 07:45:45.251603 | controller | } 2025-10-03 07:45:45.582247 | controller | ok: Runtime: 0:00:00.005415 2025-10-03 07:45:45.588085 | 2025-10-03 07:45:45.588171 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars] 2025-10-03 07:45:45.601674 | controller | skipping: Conditional result was False 2025-10-03 07:45:45.607887 | 2025-10-03 07:45:45.607977 | TASK [Symlink /home/zuul-worker/workspace] 2025-10-03 07:45:46.133458 | controller | skipping: Conditional result was False 2025-10-03 07:45:46.139533 | 2025-10-03 07:45:46.139615 | TASK [Ensure legacy workspace directory] 2025-10-03 07:45:46.326863 | controller | changed 2025-10-03 07:45:46.344997 | 2025-10-03 07:45:46.345051 | PLAY RECAP 2025-10-03 07:45:46.345094 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0 2025-10-03 07:45:46.345118 | 2025-10-03 07:45:46.417466 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master] 2025-10-03 07:45:46.418223 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main] 2025-10-03 07:45:46.960875 | 2025-10-03 07:45:46.960995 | PLAY [Run ci/playbooks/dump_zuul_data.yml] 2025-10-03 07:45:46.981938 | 2025-10-03 07:45:46.982016 | TASK [Create zuul-output directory] 2025-10-03 07:45:47.305562 | controller | changed 2025-10-03 07:45:47.311093 | 2025-10-03 07:45:47.311176 | TASK [Slurp Zuul inventory test] 2025-10-03 07:45:47.598122 | controller -> localhost | ok 2025-10-03 07:45:47.604567 | 2025-10-03 07:45:47.604638 | TASK [Save zuul inventory] 2025-10-03 07:45:48.332189 | controller | changed 2025-10-03 07:45:48.337072 | 2025-10-03 07:45:48.337144 | TASK [Save zuul vars without the change_message] 2025-10-03 07:45:48.904361 | controller | changed 2025-10-03 07:45:48.925623 | 2025-10-03 07:45:48.925673 | PLAY RECAP 2025-10-03 07:45:48.925716 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-03 07:45:48.925739 | 2025-10-03 07:45:49.007686 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main] 2025-10-03 07:45:49.008510 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main] 2025-10-03 07:45:49.578397 | 2025-10-03 07:45:49.578500 | PLAY [Run ci/playbooks/molecule-prepare.yml] 2025-10-03 07:45:49.599121 | 2025-10-03 07:45:49.599199 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh] 2025-10-03 07:45:49.637976 | controller | ok 2025-10-03 07:45:49.655129 | 2025-10-03 07:45:49.655224 | TASK [mirror-info-fork : Set mirror_fqdn if not defined] 2025-10-03 07:45:49.698471 | controller | skipping: Conditional result was False 2025-10-03 07:45:49.704244 | 2025-10-03 07:45:49.704316 | TASK [mirror-info-fork : Create /etc/ci] 2025-10-03 07:45:50.026435 | controller | ok 2025-10-03 07:45:50.043209 | 2025-10-03 07:45:50.043294 | TASK [mirror-info-fork : Install ci_mirror script] 2025-10-03 07:45:50.645856 | controller | ok 2025-10-03 07:45:50.658432 | 2025-10-03 07:45:50.658553 | TASK [Prepare workspace] 2025-10-03 07:45:50.676446 | controller | ok 2025-10-03 07:45:50.695847 | 2025-10-03 07:45:50.695961 | TASK [prepare-workspace : Start zuul_console daemon.] 2025-10-03 07:45:50.991957 | controller | ok 2025-10-03 07:45:50.999898 | 2025-10-03 07:45:51 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2025-10-03 07:45:52.924763 | controller | Output suppressed because no_log was given 2025-10-03 07:45:52.933595 | 2025-10-03 07:45:52.933662 | LOOP [Create zuul-output directory] 2025-10-03 07:45:53.122033 | controller | changed: "/home/zuul/ci-framework-data/logs" 2025-10-03 07:45:53.280535 | controller | ok: "/home/zuul/zuul-output/logs" 2025-10-03 07:45:53.288557 | 2025-10-03 07:45:53.288658 | TASK [Install required packages] 2025-10-03 07:46:47.204454 | controller | changed 2025-10-03 07:46:47.210018 | 2025-10-03 07:46:47.210104 | TASK [Install venv] 2025-10-03 07:47:48.723680 | controller | changed 2025-10-03 07:47:48.749497 | 2025-10-03 07:47:48.749567 | PLAY RECAP 2025-10-03 07:47:48.749612 | controller | ok: 7 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2025-10-03 07:47:48.749637 | 2025-10-03 07:47:48.829740 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main] 2025-10-03 07:47:48.830550 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main] 2025-10-03 07:47:49.376647 | 2025-10-03 07:47:49.376751 | PLAY [Run ci/playbooks/molecule-test.yml] 2025-10-03 07:47:49.397647 | 2025-10-03 07:47:49.397748 | TASK [Gather required facts] 2025-10-03 07:47:49.920486 | controller | ok 2025-10-03 07:47:49.926430 | 2025-10-03 07:47:49.926513 | TASK [Load environment var if instructed to] 2025-10-03 07:47:49.949859 | controller | skipping: Conditional result was False 2025-10-03 07:47:49.955160 | 2025-10-03 07:47:49.955232 | TASK [Run molecule] 2025-10-03 07:47:50.943435 | controller | INFO Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml 2025-10-03 07:47:51.012756 | controller | INFO Performing prerun with role_name_check=0... 2025-10-03 07:48:08.591877 | 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 07:48:08.592335 | 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 07:48:08.592807 | 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 07:48:08.593266 | 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 07:48:08.593727 | 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 07:48:08.594214 | 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 07:48:08.594656 | 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 07:48:08.595134 | 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 07:48:08.595574 | 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 07:48:08.596055 | 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 07:48:08.596512 | 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 07:48:08.597006 | 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 07:48:08.597441 | 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 07:48:08.597913 | 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 07:48:08.598373 | 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 07:48:08.607903 | controller | INFO Running default > prepare 2025-10-03 07:48:08.608373 | controller | WARNING Skipping, prepare playbook not configured. 2025-10-03 07:48:08.615848 | controller | INFO Running default > converge 2025-10-03 07:48:09.273778 | controller | 2025-10-03 07:48:09.273916 | controller | PLAY [Converge] **************************************************************** 2025-10-03 07:48:09.274015 | controller | 2025-10-03 07:48:09.274121 | controller | TASK [Gathering Facts] ********************************************************* 2025-10-03 07:48:09.274231 | controller | Friday 03 October 2025 07:48:09 +0000 (0:00:00.010) 0:00:00.010 ******** 2025-10-03 07:48:10.123085 | controller | ok: [instance] 2025-10-03 07:48:10.123140 | controller | 2025-10-03 07:48:10.123286 | controller | TASK [reportportal : Check if mandatory variables are defined and not empty] *** 2025-10-03 07:48:10.123453 | controller | Friday 03 October 2025 07:48:10 +0000 (0:00:00.849) 0:00:00.859 ******** 2025-10-03 07:48:10.161486 | controller | ok: [instance] => changed=false 2025-10-03 07:48:10.161589 | controller | msg: All assertions passed 2025-10-03 07:48:10.161706 | controller | 2025-10-03 07:48:10.161885 | controller | TASK [reportportal : Make sure the droute directory exists] ******************** 2025-10-03 07:48:10.161991 | controller | Friday 03 October 2025 07:48:10 +0000 (0:00:00.038) 0:00:00.898 ******** 2025-10-03 07:48:10.447935 | controller | changed: [instance] 2025-10-03 07:48:10.448011 | controller | 2025-10-03 07:48:10.448124 | controller | TASK [reportportal : Get the Data router client] ******************************* 2025-10-03 07:48:10.448231 | controller | Friday 03 October 2025 07:48:10 +0000 (0:00:00.286) 0:00:01.185 ******** 2025-10-03 07:48:10.474641 | controller | skipping: [instance] 2025-10-03 07:48:10.474742 | controller | 2025-10-03 07:48:10.474917 | controller | TASK [reportportal : Create the metadata file] ********************************* 2025-10-03 07:48:10.475043 | controller | Friday 03 October 2025 07:48:10 +0000 (0:00:00.026) 0:00:01.211 ******** 2025-10-03 07:48:11.027635 | controller | changed: [instance] 2025-10-03 07:48:11.027711 | controller | 2025-10-03 07:48:11.027856 | controller | TASK [reportportal : Send the results via Data router client call] ************* 2025-10-03 07:48:11.027972 | controller | Friday 03 October 2025 07:48:11 +0000 (0:00:00.552) 0:00:01.764 ******** 2025-10-03 07:48:11.052528 | controller | skipping: [instance] 2025-10-03 07:48:11.052623 | controller | 2025-10-03 07:48:11.052738 | controller | TASK [reportportal : Log droute result] **************************************** 2025-10-03 07:48:11.052887 | controller | Friday 03 October 2025 07:48:11 +0000 (0:00:00.025) 0:00:01.789 ******** 2025-10-03 07:48:11.080603 | controller | skipping: [instance] 2025-10-03 07:48:11.080691 | controller | 2025-10-03 07:48:11.080835 | controller | TASK [Stat directory] ********************************************************** 2025-10-03 07:48:11.080953 | controller | Friday 03 October 2025 07:48:11 +0000 (0:00:00.028) 0:00:01.817 ******** 2025-10-03 07:48:11.261625 | controller | ok: [instance] 2025-10-03 07:48:11.261703 | controller | 2025-10-03 07:48:11.261847 | controller | TASK [Validate directory state] ************************************************ 2025-10-03 07:48:11.261960 | controller | Friday 03 October 2025 07:48:11 +0000 (0:00:00.180) 0:00:01.998 ******** 2025-10-03 07:48:11.284527 | controller | ok: [instance] => changed=false 2025-10-03 07:48:11.284605 | controller | msg: All assertions passed 2025-10-03 07:48:11.284706 | controller | 2025-10-03 07:48:11.285196 | controller | TASK [Stat metadata file] ****************************************************** 2025-10-03 07:48:11.285321 | controller | Friday 03 October 2025 07:48:11 +0000 (0:00:00.022) 0:00:02.021 ******** 2025-10-03 07:48:11.465889 | controller | ok: [instance] 2025-10-03 07:48:11.465978 | controller | 2025-10-03 07:48:11.466081 | controller | TASK [Validate file state] ***************************************************** 2025-10-03 07:48:11.466189 | controller | Friday 03 October 2025 07:48:11 +0000 (0:00:00.181) 0:00:02.203 ******** 2025-10-03 07:48:11.488226 | controller | ok: [instance] => changed=false 2025-10-03 07:48:11.488340 | controller | msg: All assertions passed 2025-10-03 07:48:11.488453 | controller | 2025-10-03 07:48:11.488554 | controller | TASK [Stat metadata golden file] *********************************************** 2025-10-03 07:48:11.488658 | controller | Friday 03 October 2025 07:48:11 +0000 (0:00:00.022) 0:00:02.225 ******** 2025-10-03 07:48:11.667905 | controller | ok: [instance] 2025-10-03 07:48:11.667959 | controller | 2025-10-03 07:48:11.668078 | controller | TASK [Validate metadata golden file state] ************************************* 2025-10-03 07:48:11.668182 | controller | Friday 03 October 2025 07:48:11 +0000 (0:00:00.179) 0:00:02.405 ******** 2025-10-03 07:48:11.690872 | controller | ok: [instance] => changed=false 2025-10-03 07:48:11.690975 | controller | msg: All assertions passed 2025-10-03 07:48:11.691071 | controller | 2025-10-03 07:48:11.691182 | controller | TASK [Validate metadata golden is the same as produced metadata] *************** 2025-10-03 07:48:11.691295 | controller | Friday 03 October 2025 07:48:11 +0000 (0:00:00.023) 0:00:02.428 ******** 2025-10-03 07:48:11.718740 | controller | ok: [instance] => changed=false 2025-10-03 07:48:11.718910 | controller | msg: All assertions passed 2025-10-03 07:48:11.719019 | controller | 2025-10-03 07:48:11.719128 | controller | PLAY RECAP ********************************************************************* 2025-10-03 07:48:11.719242 | controller | instance : ok=11 changed=2 unreachable=0 failed=0 skipped=3 rescued=0 ignored=0 2025-10-03 07:48:11.719339 | controller | 2025-10-03 07:48:11.719446 | controller | Friday 03 October 2025 07:48:11 +0000 (0:00:00.027) 0:00:02.456 ******** 2025-10-03 07:48:11.719543 | controller | =============================================================================== 2025-10-03 07:48:11.719647 | controller | Gathering Facts --------------------------------------------------------- 0.85s 2025-10-03 07:48:11.719745 | controller | reportportal : Create the metadata file --------------------------------- 0.55s 2025-10-03 07:48:11.719908 | controller | reportportal : Make sure the droute directory exists -------------------- 0.29s 2025-10-03 07:48:11.720009 | controller | Stat metadata file ------------------------------------------------------ 0.18s 2025-10-03 07:48:11.720116 | controller | Stat directory ---------------------------------------------------------- 0.18s 2025-10-03 07:48:11.720216 | controller | Stat metadata golden file ----------------------------------------------- 0.18s 2025-10-03 07:48:11.720314 | controller | reportportal : Check if mandatory variables are defined and not empty --- 0.04s 2025-10-03 07:48:11.720413 | controller | reportportal : Log droute result ---------------------------------------- 0.03s 2025-10-03 07:48:11.720513 | controller | Validate metadata golden is the same as produced metadata --------------- 0.03s 2025-10-03 07:48:11.720608 | controller | reportportal : Get the Data router client ------------------------------- 0.03s 2025-10-03 07:48:11.720707 | controller | reportportal : Send the results via Data router client call ------------- 0.03s 2025-10-03 07:48:11.720921 | controller | Validate metadata golden file state ------------------------------------- 0.02s 2025-10-03 07:48:11.720949 | controller | Validate directory state ------------------------------------------------ 0.02s 2025-10-03 07:48:11.721043 | controller | Validate file state ----------------------------------------------------- 0.02s 2025-10-03 07:48:11.770762 | controller | INFO Running default > cleanup 2025-10-03 07:48:11.771198 | controller | WARNING Skipping, cleanup playbook not configured. 2025-10-03 07:48:11.771982 | controller | INFO Writing /tmp/report.html report. 2025-10-03 07:48:12.012025 | controller | changed 2025-10-03 07:48:12.031500 | 2025-10-03 07:48:12.031581 | PLAY RECAP 2025-10-03 07:48:12.031630 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2025-10-03 07:48:12.031659 | 2025-10-03 07:48:12.101063 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main] 2025-10-03 07:48:12.101852 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main] 2025-10-03 07:48:12.660438 | 2025-10-03 07:48:12.660528 | PLAY [Run ci/playbooks/collect-logs.yml] 2025-10-03 07:48:12.681364 | 2025-10-03 07:48:12.681457 | TASK [Filter out host if needed] 2025-10-03 07:48:12.690347 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller 2025-10-03 07:48:12.695025 | 2025-10-03 07:48:12.695105 | TASK [Ensure file is present] 2025-10-03 07:48:13.018410 | controller | ok 2025-10-03 07:48:13.025316 | 2025-10-03 07:48:13.025398 | TASK [Manage molecule report file] 2025-10-03 07:48:13.548300 | controller | changed 2025-10-03 07:48:13.554255 | 2025-10-03 07:48:13.554335 | TASK [Check if we get ci-framework-data basedir] 2025-10-03 07:48:13.735513 | controller | ok 2025-10-03 07:48:13.740717 | 2025-10-03 07:48:13.740782 | TASK [Create ci-framework-data log directory for zuul] 2025-10-03 07:48:14.053811 | controller | changed 2025-10-03 07:48:14.059177 | 2025-10-03 07:48:14.059253 | TASK [Copy ci-framework interesting files] 2025-10-03 07:48:14.246203 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory 2025-10-03 07:48:14.587117 | controller | changed 2025-10-03 07:48:14.592391 | 2025-10-03 07:48:14.592457 | TASK [Get SELinux listing] 2025-10-03 07:48:15.115997 | controller | changed 2025-10-03 07:48:15.122016 | 2025-10-03 07:48:15.122126 | TASK [Generate log index] 2025-10-03 07:48:15.741253 | controller | changed 2025-10-03 07:48:15.746495 | 2025-10-03 07:48:15.746566 | TASK [Get some env related data] 2025-10-03 07:48:16.270044 | controller | /home/zuul/.local/bin/ansible 2025-10-03 07:48:17.278636 | controller | changed 2025-10-03 07:48:17.283940 | 2025-10-03 07:48:17.284011 | TASK [Generate list of logs to collect in home directory] 2025-10-03 07:48:17.598229 | controller | ok: All paths examined 2025-10-03 07:48:17.603757 | 2025-10-03 07:48:17.603830 | LOOP [Copy logs from home directory] 2025-10-03 07:48:17.922897 | controller | changed: 2025-10-03 07:48:17.923027 | controller | { 2025-10-03 07:48:17.923056 | controller | "atime": 1759477652.280242, 2025-10-03 07:48:17.923077 | controller | "ctime": 1759477668.2386694, 2025-10-03 07:48:17.923095 | controller | "dev": 64513, 2025-10-03 07:48:17.923130 | controller | "gid": 1000, 2025-10-03 07:48:17.923148 | controller | "gr_name": "zuul", 2025-10-03 07:48:17.923165 | controller | "inode": 4460154, 2025-10-03 07:48:17.923181 | controller | "isblk": false, 2025-10-03 07:48:17.923196 | controller | "ischr": false, 2025-10-03 07:48:17.923210 | controller | "isdir": false, 2025-10-03 07:48:17.923224 | controller | "isfifo": false, 2025-10-03 07:48:17.923238 | controller | "isgid": false, 2025-10-03 07:48:17.923252 | controller | "islnk": false, 2025-10-03 07:48:17.923265 | controller | "isreg": true, 2025-10-03 07:48:17.923279 | controller | "issock": false, 2025-10-03 07:48:17.923293 | controller | "isuid": false, 2025-10-03 07:48:17.923308 | controller | "mode": "0644", 2025-10-03 07:48:17.923321 | controller | "mtime": 1759477668.2386694, 2025-10-03 07:48:17.923424 | controller | "nlink": 1, 2025-10-03 07:48:17.923457 | controller | "path": "/home/zuul/ansible.log", 2025-10-03 07:48:17.923478 | controller | "pw_name": "zuul", 2025-10-03 07:48:17.923496 | controller | "rgrp": true, 2025-10-03 07:48:17.923513 | controller | "roth": true, 2025-10-03 07:48:17.923529 | controller | "rusr": true, 2025-10-03 07:48:17.923544 | controller | "size": 6749, 2025-10-03 07:48:17.923559 | controller | "uid": 1000, 2025-10-03 07:48:17.923574 | controller | "wgrp": false, 2025-10-03 07:48:17.923588 | controller | "woth": false, 2025-10-03 07:48:17.923605 | controller | "wusr": true, 2025-10-03 07:48:17.923621 | controller | "xgrp": false, 2025-10-03 07:48:17.923636 | controller | "xoth": false, 2025-10-03 07:48:17.923650 | controller | "xusr": false 2025-10-03 07:48:17.923664 | controller | } 2025-10-03 07:48:17.935830 | 2025-10-03 07:48:17.935896 | TASK [Copy crio stats log file] 2025-10-03 07:48:17.949744 | controller | skipping: Conditional result was False 2025-10-03 07:48:17.955735 | 2025-10-03 07:48:17.955803 | TASK [Get SELinux related data] 2025-10-03 07:48:18.156150 | controller | 2025-10-03 07:48:18.482491 | controller | ERROR 2025-10-03 07:48:18.482667 | controller | { 2025-10-03 07:48:18.482703 | controller | "delta": "0:00:00.008680", 2025-10-03 07:48:18.482727 | controller | "end": "2025-10-03 07:48:18.157144", 2025-10-03 07:48:18.482748 | controller | "msg": "non-zero return code", 2025-10-03 07:48:18.482766 | controller | "rc": 1, 2025-10-03 07:48:18.482783 | controller | "start": "2025-10-03 07:48:18.148464" 2025-10-03 07:48:18.482800 | controller | } 2025-10-03 07:48:18.482823 | controller | ERROR: Ignoring Errors 2025-10-03 07:48:18.488293 | 2025-10-03 07:48:18.488357 | TASK [Create system configuration directory] 2025-10-03 07:48:18.678658 | controller | changed 2025-10-03 07:48:18.684156 | 2025-10-03 07:48:18.684226 | TASK [Get some of the system configurations] 2025-10-03 07:48:19.211542 | controller | changed 2025-10-03 07:48:19.216771 | 2025-10-03 07:48:19.216839 | TASK [Copy generated documentation if available] 2025-10-03 07:48:19.230529 | controller | skipping: Conditional result was False 2025-10-03 07:48:19.236288 | 2025-10-03 07:48:19.236356 | TASK [Copy generated AsciiDoc documentation if available] 2025-10-03 07:48:19.250253 | controller | skipping: Conditional result was False 2025-10-03 07:48:19.256143 | 2025-10-03 07:48:19.256209 | TASK [Compress logs bigger than 2MB] 2025-10-03 07:48:19.782744 | controller | changed 2025-10-03 07:48:19.788384 | 2025-10-03 07:48:19.788448 | TASK [Copy files from workspace on node] 2025-10-03 07:48:19.806560 | controller | ok 2025-10-03 07:48:19.829379 | 2025-10-03 07:48:19.829544 | TASK [fetch-output : Set log path for multiple nodes] 2025-10-03 07:48:19.853262 | controller | skipping: Conditional result was False 2025-10-03 07:48:19.858667 | 2025-10-03 07:48:19.858735 | TASK [fetch-output : Set log path for single node] 2025-10-03 07:48:19.887084 | controller | ok 2025-10-03 07:48:19.893275 | 2025-10-03 07:48:19.893340 | LOOP [fetch-output : Ensure local output dirs] 2025-10-03 07:48:20.105325 | controller -> localhost | ok: "/var/lib/zuul/builds/9689a3dea0d2415f9842ed19a2199c70/work/logs" 2025-10-03 07:48:20.105563 | controller -> localhost | changed: All items complete 2025-10-03 07:48:20.105594 | 2025-10-03 07:48:20.289165 | controller -> localhost | changed: "/var/lib/zuul/builds/9689a3dea0d2415f9842ed19a2199c70/work/artifacts" 2025-10-03 07:48:20.477300 | controller -> localhost | changed: "/var/lib/zuul/builds/9689a3dea0d2415f9842ed19a2199c70/work/docs" 2025-10-03 07:48:20.492183 | 2025-10-03 07:48:20.492356 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-10-03 07:48:21.026660 | controller | changed: 2025-10-03 07:48:21.026855 | controller | .d..t...... ./ 2025-10-03 07:48:21.026885 | controller | >f+++++++++ README.html 2025-10-03 07:48:21.026923 | controller | >f+++++++++ ansible-execution.log 2025-10-03 07:48:21.026953 | controller | >f+++++++++ ansible.log 2025-10-03 07:48:21.026975 | controller | >f+++++++++ dmesg.log 2025-10-03 07:48:21.026994 | controller | >f+++++++++ installed-pkgs.log 2025-10-03 07:48:21.027014 | controller | >f+++++++++ python.log 2025-10-03 07:48:21.027034 | controller | >f+++++++++ registries.conf 2025-10-03 07:48:21.027052 | controller | >f+++++++++ report.html 2025-10-03 07:48:21.027071 | controller | >f+++++++++ selinux-denials.log 2025-10-03 07:48:21.027216 | controller | >f+++++++++ selinux-listing.log 2025-10-03 07:48:21.027245 | controller | cd+++++++++ ci-framework-data/ 2025-10-03 07:48:21.027265 | controller | cd+++++++++ ci-framework-data/artifacts/ 2025-10-03 07:48:21.027283 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml 2025-10-03 07:48:21.027301 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/ 2025-10-03 07:48:21.027319 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml 2025-10-03 07:48:21.027336 | controller | cd+++++++++ ci-framework-data/logs/ 2025-10-03 07:48:21.027352 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log 2025-10-03 07:48:21.027367 | controller | cd+++++++++ registries.conf.d/ 2025-10-03 07:48:21.027382 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf 2025-10-03 07:48:21.027397 | controller | cd+++++++++ system-config/ 2025-10-03 07:48:21.027411 | controller | cd+++++++++ system-config/libvirt/ 2025-10-03 07:48:21.027427 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf 2025-10-03 07:48:21.027444 | controller | >f+++++++++ system-config/libvirt/libvirt.conf 2025-10-03 07:48:21.421753 | controller | changed: .d..t...... ./ 2025-10-03 07:48:21.832400 | controller | changed: .d..t...... ./ 2025-10-03 07:48:21.852151 | 2025-10-03 07:48:21.852268 | TASK [Return artifact to Zuul] 2025-10-03 07:48:21.894051 | controller | ok 2025-10-03 07:48:21.916679 | 2025-10-03 07:48:21.916749 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host] 2025-10-03 07:48:21.916847 | 2025-10-03 07:48:21.916874 | PLAY RECAP 2025-10-03 07:48:21.916929 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1 2025-10-03 07:48:21.916959 | 2025-10-03 07:48:22.015679 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main] 2025-10-03 07:48:22.017000 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2025-10-03 07:48:22.548111 | 2025-10-03 07:48:22.548231 | PLAY [all] 2025-10-03 07:48:22.567086 | 2025-10-03 07:48:22.567197 | TASK [include_role : fetch-output] 2025-10-03 07:48:22.595552 | controller | ok 2025-10-03 07:48:22.612392 | 2025-10-03 07:48:22.612478 | TASK [fetch-output : Set log path for multiple nodes] 2025-10-03 07:48:22.657269 | controller | skipping: Conditional result was False 2025-10-03 07:48:22.663007 | 2025-10-03 07:48:22.663086 | TASK [fetch-output : Set log path for single node] 2025-10-03 07:48:22.691315 | controller | ok 2025-10-03 07:48:22.696236 | 2025-10-03 07:48:22.696305 | LOOP [fetch-output : Ensure local output dirs] 2025-10-03 07:48:23.023614 | controller -> localhost | ok: "/var/lib/zuul/builds/9689a3dea0d2415f9842ed19a2199c70/work/logs" 2025-10-03 07:48:23.221848 | controller -> localhost | ok: "/var/lib/zuul/builds/9689a3dea0d2415f9842ed19a2199c70/work/artifacts" 2025-10-03 07:48:23.412262 | controller -> localhost | ok: "/var/lib/zuul/builds/9689a3dea0d2415f9842ed19a2199c70/work/docs" 2025-10-03 07:48:23.422571 | 2025-10-03 07:48:23.422665 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-10-03 07:48:23.963216 | controller | ok 2025-10-03 07:48:23.963401 | controller | ok: All items complete 2025-10-03 07:48:23.963433 | 2025-10-03 07:48:24.350144 | controller | ok 2025-10-03 07:48:24.725440 | controller | ok 2025-10-03 07:48:24.736835 | 2025-10-03 07:48:24.736954 | TASK [include_role : fetch-output-openshift] 2025-10-03 07:48:24.750719 | controller | skipping: Conditional result was False 2025-10-03 07:48:24.756870 | 2025-10-03 07:48:24.756967 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-10-03 07:48:25.110426 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.006238 2025-10-03 07:48:25.303429 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.007405 2025-10-03 07:48:25.338025 | 2025-10-03 07:48:25.338156 | PLAY [all] 2025-10-03 07:48:25.354387 | 2025-10-03 07:48:25.354500 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-10-03 07:48:25.750234 | controller | changed 2025-10-03 07:48:25.774338 | 2025-10-03 07:48:25.774410 | PLAY RECAP 2025-10-03 07:48:25.774455 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2025-10-03 07:48:25.774478 | 2025-10-03 07:48:25.862669 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2025-10-03 07:48:25.863424 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master] 2025-10-03 07:48:26.448596 | 2025-10-03 07:48:26.448724 | PLAY [localhost] 2025-10-03 07:48:26.467771 | 2025-10-03 07:48:26.467899 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-10-03 07:48:26.793077 | localhost | changed 2025-10-03 07:48:26.798033 | 2025-10-03 07:48:26.798115 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-10-03 07:48:26.825811 | localhost | ok 2025-10-03 07:48:26.834417 | 2025-10-03 07:48:26.834498 | TASK [add-fileserver : Create SSH private key tempfile] 2025-10-03 07:48:27.147213 | localhost | changed 2025-10-03 07:48:27.153363 | 2025-10-03 07:48:27.153450 | TASK [add-fileserver : Create SSH private key from secret] 2025-10-03 07:48:27.708694 | localhost | changed 2025-10-03 07:48:27.714376 | 2025-10-03 07:48:27.714531 | TASK [add-fileserver : Add fileserver ssh key] 2025-10-03 07:48:28.057001 | localhost | Identity added: /var/lib/zuul/builds/9689a3dea0d2415f9842ed19a2199c70/work/tmp/ansible._op_2xc0 (/var/lib/zuul/builds/9689a3dea0d2415f9842ed19a2199c70/work/tmp/ansible._op_2xc0) 2025-10-03 07:48:28.057188 | localhost | ok: Runtime: 0:00:00.006745 2025-10-03 07:48:28.061718 | 2025-10-03 07:48:28.061786 | TASK [add-fileserver : Remove SSH private key from disk] 2025-10-03 07:48:28.283360 | localhost | ok: Runtime: 0:00:00.004512 2025-10-03 07:48:28.288197 | 2025-10-03 07:48:28.288289 | TASK [add-fileserver : Add fileserver to inventory] 2025-10-03 07:48:28.337438 | localhost | changed 2025-10-03 07:48:28.341689 | 2025-10-03 07:48:28.341841 | TASK [add-fileserver : Add fileserver server to known hosts] 2025-10-03 07:48:28.674815 | localhost | changed 2025-10-03 07:48:28.696157 | 2025-10-03 07:48:28.696243 | PLAY [localhost] 2025-10-03 07:48:28.709290 | 2025-10-03 07:48:28.709362 | TASK [Generate bulk log download script] 2025-10-03 07:48:28.734547 | localhost | ok 2025-10-03 07:48:28.746642 | 2025-10-03 07:48:28.746731 | TASK [local-log-download : Check API endpoint is defined] 2025-10-03 07:48:28.774149 | localhost | ok: All assertions passed 2025-10-03 07:48:28.778768 | 2025-10-03 07:48:28.778848 | TASK [local-log-download : Create download script] 2025-10-03 07:48:29.136300 | localhost -> localhost | changed 2025-10-03 07:48:29.146303 | 2025-10-03 07:48:29.146380 | TASK [Register quick-download link] 2025-10-03 07:48:29.163876 | localhost | ok 2025-10-03 07:48:29.217804 | 2025-10-03 07:48:29.218212 | PLAY [logserver.rdoproject.org] 2025-10-03 07:48:29.228479 | 2025-10-03 07:48:29.228558 | TASK [Set zuul-log-path fact] 2025-10-03 07:48:29.244513 | logserver.rdoproject.org | ok 2025-10-03 07:48:29.254427 | 2025-10-03 07:48:29.254524 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-03 07:48:29.281746 | logserver.rdoproject.org | ok 2025-10-03 07:48:29.287440 | 2025-10-03 07:48:29.287504 | TASK [upload-logs : Create log directories] 2025-10-03 07:48:30.947509 | logserver.rdoproject.org | changed 2025-10-03 07:48:30.950835 | 2025-10-03 07:48:30.950903 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-10-03 07:48:31.187450 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.004128 2025-10-03 07:48:31.192126 | 2025-10-03 07:48:31.192211 | TASK [upload-logs : Upload logs to log server] 2025-10-03 07:48:32.732517 | logserver.rdoproject.org | Output suppressed because no_log was given 2025-10-03 07:48:32.736074 | 2025-10-03 07:48:32.736166 | LOOP [upload-logs : Compress console log and json output] 2025-10-03 07:48:32.792218 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-03 07:48:32.805212 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-03 07:48:32.816954 | 2025-10-03 07:48:32.817050 | LOOP [upload-logs : Upload compressed console log and json output] 2025-10-03 07:48:32.897119 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-03 07:48:32.897349 | 2025-10-03 07:48:32.900930 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-03 07:48:32.905830 | 2025-10-03 07:48:32.905906 | LOOP [upload-logs : Upload console log and json output]