2025-10-13 10:56:35.955277 | Job console starting... 2025-10-13 10:56:36.328392 | Updating repositories 2025-10-13 10:56:37.323103 | Preparing job workspace 2025-10-13 10:56:58.893953 | Running Ansible setup... 2025-10-13 10:57:08.551328 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2025-10-13 10:57:11.157287 | 2025-10-13 10:57:11.157392 | PLAY [localhost] 2025-10-13 10:57:11.218228 | 2025-10-13 10:57:11.218334 | TASK [Gathering Facts] 2025-10-13 10:57:14.122257 | localhost | ok 2025-10-13 10:57:14.150823 | 2025-10-13 10:57:14.153620 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-10-13 10:57:15.695333 | localhost -> localhost | changed 2025-10-13 10:57:15.703421 | 2025-10-13 10:57:15.703495 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-10-13 10:57:19.050479 | localhost -> localhost | changed 2025-10-13 10:57:19.099555 | 2025-10-13 10:57:19.099664 | TASK [Setup log path fact] 2025-10-13 10:57:19.183884 | localhost | ok 2025-10-13 10:57:19.260256 | 2025-10-13 10:57:19.260369 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-13 10:57:19.329093 | localhost | ok 2025-10-13 10:57:19.341666 | 2025-10-13 10:57:19.341745 | TASK [emit-job-header : Print job information] 2025-10-13 10:57:19.458881 | # Job Information 2025-10-13 10:57:19.459040 | Ansible Version: 2.15.12 2025-10-13 10:57:19.459073 | Job: cifmw-molecule-cifmw_test_role 2025-10-13 10:57:19.459097 | Pipeline: github-check 2025-10-13 10:57:19.459119 | Executor: ibm-bm4-ze.softwarefactory-project.io 2025-10-13 10:57:19.459140 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3402 2025-10-13 10:57:19.459160 | Log URL (when completed): https://logserver.rdoproject.org/3e5/rdoproject.org/3e5286f1c93c4509a1a4dc9b95cb8bac/ 2025-10-13 10:57:19.459180 | Event ID: f4c76cb0-a822-11f0-863f-c4a61c57c215 2025-10-13 10:57:19.462956 | 2025-10-13 10:57:19.463024 | LOOP [emit-job-header : Print node information] 2025-10-13 10:57:19.817777 | localhost | ok: 2025-10-13 10:57:19.817973 | localhost | # Node Information 2025-10-13 10:57:19.818007 | localhost | Inventory Hostname: controller 2025-10-13 10:57:19.818034 | localhost | Hostname: np0005483881 2025-10-13 10:57:19.818057 | localhost | Username: zuul 2025-10-13 10:57:19.818080 | localhost | Distro: CentOS 9 2025-10-13 10:57:19.818100 | localhost | Provider: ibm-bm4-nodepool 2025-10-13 10:57:19.818119 | localhost | Region: regionOne 2025-10-13 10:57:19.818137 | localhost | Label: cloud-centos-9-stream-tripleo-ibm 2025-10-13 10:57:19.818162 | localhost | Product Name: OpenStack Compute 2025-10-13 10:57:19.818181 | localhost | Interface IP: 192.168.26.66 2025-10-13 10:57:19.851695 | 2025-10-13 10:57:19.851801 | PLAY [all] 2025-10-13 10:57:19.862344 | 2025-10-13 10:57:19.862410 | TASK [Gather network facts] 2025-10-13 10:57:20.287827 | controller | ok 2025-10-13 10:57:20.321838 | 2025-10-13 10:57:20.321971 | TASK [include_role : start-zuul-console] 2025-10-13 10:57:20.394427 | controller | ok 2025-10-13 10:57:20.449166 | 2025-10-13 10:57:20.449260 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-10-13 10:57:21.155155 | controller | ok 2025-10-13 10:57:21.189078 | 2025-10-13 10:57:21.189162 | TASK [include_role : add-build-sshkey] 2025-10-13 10:57:21.275646 | controller | ok 2025-10-13 10:57:21.336517 | 2025-10-13 10:57:21.336622 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-13 10:57:22.595260 | controller -> localhost | ok 2025-10-13 10:57:22.600820 | 2025-10-13 10:57:22.600885 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-13 10:57:22.701226 | controller | ok 2025-10-13 10:57:22.769714 | controller | included: /var/lib/zuul/builds/3e5286f1c93c4509a1a4dc9b95cb8bac/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-13 10:57:22.799236 | 2025-10-13 10:57:22.799325 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-13 10:57:25.264637 | controller -> localhost | Generating public/private rsa key pair. 2025-10-13 10:57:25.264828 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/3e5286f1c93c4509a1a4dc9b95cb8bac/work/3e5286f1c93c4509a1a4dc9b95cb8bac_id_rsa. 2025-10-13 10:57:25.264865 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/3e5286f1c93c4509a1a4dc9b95cb8bac/work/3e5286f1c93c4509a1a4dc9b95cb8bac_id_rsa.pub. 2025-10-13 10:57:25.264920 | controller -> localhost | The key fingerprint is: 2025-10-13 10:57:25.264953 | controller -> localhost | SHA256:xN3Eit8IplsISDuMOJILpS09ecNzd7M0YdIUxtZQ4Ig zuul-build-sshkey 2025-10-13 10:57:25.264977 | controller -> localhost | The key's randomart image is: 2025-10-13 10:57:25.264999 | controller -> localhost | +---[RSA 3072]----+ 2025-10-13 10:57:25.265020 | controller -> localhost | | +*Bo | 2025-10-13 10:57:25.265040 | controller -> localhost | | o o.=*oo | 2025-10-13 10:57:25.265060 | controller -> localhost | |.X = E +=oo. | 2025-10-13 10:57:25.265080 | controller -> localhost | |X X * ..= * | 2025-10-13 10:57:25.265100 | controller -> localhost | |o+ + = =S= * | 2025-10-13 10:57:25.265118 | controller -> localhost | |. o . + . | 2025-10-13 10:57:25.265136 | controller -> localhost | | o | 2025-10-13 10:57:25.265155 | controller -> localhost | | . | 2025-10-13 10:57:25.265175 | controller -> localhost | | | 2025-10-13 10:57:25.265194 | controller -> localhost | +----[SHA256]-----+ 2025-10-13 10:57:25.265241 | controller -> localhost | ok: Runtime: 0:00:00.736794 2025-10-13 10:57:25.276506 | 2025-10-13 10:57:25.276570 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-13 10:57:25.330791 | controller | ok 2025-10-13 10:57:25.395933 | controller | included: /var/lib/zuul/builds/3e5286f1c93c4509a1a4dc9b95cb8bac/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-13 10:57:25.453030 | 2025-10-13 10:57:25.453154 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-13 10:57:25.529920 | controller | skipping: Conditional result was False 2025-10-13 10:57:25.535475 | 2025-10-13 10:57:25.535542 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-13 10:57:26.524109 | controller | changed 2025-10-13 10:57:26.529294 | 2025-10-13 10:57:26.529356 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-13 10:57:26.819982 | controller | ok 2025-10-13 10:57:27.033181 | 2025-10-13 10:57:27.033280 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-13 10:57:27.880291 | controller | changed 2025-10-13 10:57:27.885259 | 2025-10-13 10:57:27.885327 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-13 10:57:28.797095 | controller | changed 2025-10-13 10:57:28.804883 | 2025-10-13 10:57:28.804965 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-13 10:57:28.903822 | controller | skipping: Conditional result was False 2025-10-13 10:57:28.910307 | 2025-10-13 10:57:28.910388 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-13 10:57:30.068023 | controller -> localhost | changed 2025-10-13 10:57:30.089061 | 2025-10-13 10:57:30.089150 | TASK [add-build-sshkey : Add back temp key] 2025-10-13 10:57:30.996097 | controller -> localhost | Identity added: /var/lib/zuul/builds/3e5286f1c93c4509a1a4dc9b95cb8bac/work/3e5286f1c93c4509a1a4dc9b95cb8bac_id_rsa (zuul-build-sshkey) 2025-10-13 10:57:30.996350 | controller -> localhost | ok: Runtime: 0:00:00.038327 2025-10-13 10:57:31.002565 | 2025-10-13 10:57:31.002640 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-13 10:57:31.691551 | controller | ok 2025-10-13 10:57:31.697182 | 2025-10-13 10:57:31.697268 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-13 10:57:31.865301 | controller | skipping: Conditional result was False 2025-10-13 10:57:31.881401 | 2025-10-13 10:57:31.881497 | TASK [include_role : validate-host] 2025-10-13 10:57:32.046370 | controller | ok 2025-10-13 10:57:32.129797 | 2025-10-13 10:57:32.129925 | TASK [validate-host : Define zuul_info_dir fact] 2025-10-13 10:57:32.246077 | controller | ok 2025-10-13 10:57:32.284425 | 2025-10-13 10:57:32.284524 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-10-13 10:57:33.124861 | controller -> localhost | ok 2025-10-13 10:57:33.131684 | 2025-10-13 10:57:33.131760 | TASK [validate-host : Collect information about the host] 2025-10-13 10:57:33.840382 | controller | ok 2025-10-13 10:57:33.902889 | 2025-10-13 10:57:33.905308 | TASK [validate-host : Sanitize hostname] 2025-10-13 10:57:34.167554 | controller | ok 2025-10-13 10:57:34.183438 | 2025-10-13 10:57:34.183523 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-10-13 10:57:36.032831 | controller -> localhost | changed 2025-10-13 10:57:36.038539 | 2025-10-13 10:57:36.038604 | TASK [validate-host : Collect information about zuul worker] 2025-10-13 10:57:37.189823 | controller | ok 2025-10-13 10:57:37.197340 | 2025-10-13 10:57:37.197422 | TASK [validate-host : Write out all zuul information for each host] 2025-10-13 10:57:38.580210 | controller -> localhost | changed 2025-10-13 10:57:38.596501 | 2025-10-13 10:57:38.596852 | TASK [include_role : prepare-workspace-openshift] 2025-10-13 10:57:38.672662 | controller | skipping: Conditional result was False 2025-10-13 10:57:38.679421 | 2025-10-13 10:57:38.679599 | TASK [include_role : remove-zuul-sshkey] 2025-10-13 10:57:38.752392 | controller | skipping: Conditional result was False 2025-10-13 10:57:38.760786 | 2025-10-13 10:57:38.760867 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2025-10-13 10:57:39.062062 | controller | ok: "logs" 2025-10-13 10:57:39.062297 | controller | ok: All items complete 2025-10-13 10:57:39.062326 | 2025-10-13 10:57:39.258888 | controller | ok: "artifacts" 2025-10-13 10:57:39.445857 | controller | ok: "docs" 2025-10-13 10:57:39.474793 | 2025-10-13 10:57:39.474951 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2025-10-13 10:57:39.752856 | controller | changed: "logs" 2025-10-13 10:57:39.958667 | controller | changed: "artifacts" 2025-10-13 10:57:40.142200 | controller | changed: "docs" 2025-10-13 10:57:40.220099 | 2025-10-13 10:57:40.220186 | PLAY RECAP 2025-10-13 10:57:40.220235 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0 2025-10-13 10:57:40.220265 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-13 10:57:40.220285 | 2025-10-13 10:57:40.522790 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2025-10-13 10:57:40.524098 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master] 2025-10-13 10:57:42.702005 | 2025-10-13 10:57:42.702158 | PLAY [all] 2025-10-13 10:57:42.756921 | 2025-10-13 10:57:42.757022 | TASK [mirror-info-fork : Set mirror_fqdn if not defined] 2025-10-13 10:57:42.900447 | controller | ok 2025-10-13 10:57:42.907536 | 2025-10-13 10:57:42.907627 | TASK [mirror-info-fork : Create /etc/ci] 2025-10-13 10:57:43.945803 | controller | changed 2025-10-13 10:57:43.961419 | 2025-10-13 10:57:43.961511 | TASK [mirror-info-fork : Install ci_mirror script] 2025-10-13 10:57:45.368456 | controller | changed 2025-10-13 10:57:45.385434 | 2025-10-13 10:57:45.385509 | LOOP [add-authorized-keys : Enable access via build key on all nodes] 2025-10-13 10:57:46.038736 | controller | changed: 2025-10-13 10:57:46.038986 | controller | { 2025-10-13 10:57:46.039070 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com" 2025-10-13 10:57:46.039112 | controller | } 2025-10-13 10:57:46.246264 | controller | changed: 2025-10-13 10:57:46.246357 | controller | { 2025-10-13 10:57:46.246386 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com" 2025-10-13 10:57:46.246409 | controller | } 2025-10-13 10:57:46.475169 | controller | changed: 2025-10-13 10:57:46.475265 | controller | { 2025-10-13 10:57:46.475292 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com" 2025-10-13 10:57:46.475314 | controller | } 2025-10-13 10:57:46.686162 | controller | changed: 2025-10-13 10:57:46.686244 | controller | { 2025-10-13 10:57:46.686273 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com" 2025-10-13 10:57:46.686294 | controller | } 2025-10-13 10:57:46.900460 | controller | changed: 2025-10-13 10:57:46.900554 | controller | { 2025-10-13 10:57:46.900582 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com" 2025-10-13 10:57:46.900603 | controller | } 2025-10-13 10:57:47.109599 | controller | changed: 2025-10-13 10:57:47.109686 | controller | { 2025-10-13 10:57:47.109714 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com" 2025-10-13 10:57:47.109751 | controller | } 2025-10-13 10:57:47.320874 | controller | changed: 2025-10-13 10:57:47.320963 | controller | { 2025-10-13 10:57:47.320993 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDLOQd4ZLtkZXQGY6UwAr/06ppWQK4fDO3HaqxPk98csyOCBXsliSKK39Bso828+5srIXiW7aI6aC9P5mwi4mUZlGPfJlQbfrcGvY+b/SocuvaGK+1RrHLoJCT52LBhwgrzlXio2jeksZeein8iaTrhsPrOAs7KggIL/rB9hEiB3NaOPWhhoCP4vlW6MEMExGcqB/1FVxXFBPnLkEyW0Lk7ycVflZl2ocRxbfjZi0+tI1Wlinp8PvSQSc/WVrAcDgKjc/mB4ODPOyYy3G8FHgfMsrXSDEyjBKgLKMsdCrAUcqJQWjkqXleXSYOV4q3pzL+9umK+q/e3P/bIoSFQzmJKTU1eDfuvPXmow9F5H54fii/Da7ezlMJ+wPGHJrRAkmzvMbALy7xwswLhZMkOGNtRcPqaKYRmIBKpw3o6bCTtcNUHOtOQnzwY8JzrM2eBWJBXAANYw+9/ho80JIiwhg29CFNpVBuHbql2YxJQNrnl90guN65rYNpDxdIluweyUf8= anbanerj@kaermorhen" 2025-10-13 10:57:47.321017 | controller | } 2025-10-13 10:57:47.527644 | controller | changed: 2025-10-13 10:57:47.527734 | controller | { 2025-10-13 10:57:47.527764 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQC3VwV8Im9kRm49lt3tM36hj4Zv27FxGo4C1Q/0jqhzFmHY7RHbmeRr8ObhwWoHjXSozKWg8FL5ER0z3hTwL0W6lez3sL7hUaCmSuZmG5Hnl3x4vTSxDI9JZ/Y65rtYiiWQo2fC5xJhU/4+0e5e/pseCm8cKRSu+SaxhO+sd6FDojA2x1BzOzKiQRDy/1zWGp/cZkxcEuB1wHI5LMzN03c67vmbu+fhZRAUO4dQkvcnj2LrhQtpa+ytvnSjr8icMDosf1OsbSffwZFyHB/hfWGAfe0eIeSA2XPraxiPknXxiPKx2MJsaUTYbsZcm3EjFdHBBMumw5rBI74zLrMRvCO9GwBEmGT4rFng1nP+yw5DB8sn2zqpOsPg1LYRwCPOUveC13P6pgsZZPh812e8v5EKnETct+5XI3dVpdw6CnNiLwAyVAF15DJvBGT/u1k0Myg/bQn+Gv9k2MSj6LvQmf6WbZu2Wgjm30z3FyCneBqTL7mLF19YXzeC0ufHz5pnO1E= dasm@fedora" 2025-10-13 10:57:47.527787 | controller | } 2025-10-13 10:57:47.726400 | controller | changed: 2025-10-13 10:57:47.726479 | controller | { 2025-10-13 10:57:47.726508 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner" 2025-10-13 10:57:47.726531 | controller | } 2025-10-13 10:57:47.938732 | controller | changed: 2025-10-13 10:57:47.938824 | controller | { 2025-10-13 10:57:47.938852 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com" 2025-10-13 10:57:47.938874 | controller | } 2025-10-13 10:57:48.139223 | controller | changed: 2025-10-13 10:57:48.139305 | controller | { 2025-10-13 10:57:48.139333 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com" 2025-10-13 10:57:48.139355 | controller | } 2025-10-13 10:57:48.352358 | controller | changed: 2025-10-13 10:57:48.352861 | controller | { 2025-10-13 10:57:48.352956 | controller | "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com" 2025-10-13 10:57:48.353056 | controller | } 2025-10-13 10:57:48.562582 | controller | changed: 2025-10-13 10:57:48.562671 | controller | { 2025-10-13 10:57:48.562699 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith" 2025-10-13 10:57:48.562733 | controller | } 2025-10-13 10:57:48.763469 | controller | changed: 2025-10-13 10:57:48.763599 | controller | { 2025-10-13 10:57:48.763630 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel" 2025-10-13 10:57:48.763653 | controller | } 2025-10-13 10:57:48.965370 | controller | changed: 2025-10-13 10:57:48.965454 | controller | { 2025-10-13 10:57:48.965482 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux" 2025-10-13 10:57:48.965505 | controller | } 2025-10-13 10:57:49.164265 | controller | changed: 2025-10-13 10:57:49.164343 | controller | { 2025-10-13 10:57:49.164382 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr" 2025-10-13 10:57:49.164409 | controller | } 2025-10-13 10:57:49.367203 | controller | changed: 2025-10-13 10:57:49.367339 | controller | { 2025-10-13 10:57:49.367434 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQCfNtF7NvKl915TGsGGoseUb06Hj8L/S4toWf0hExeY+F00woL6NvBlJD0nDct+P5a22I4EhvoQCRQ8reaPCm1lybR3uiRIJsj+8zkVvLwby9LXzfZorlNG9ofjd00FEmB09uW/YvTl6Q9XwwwX6tInzIOv3TMqTHHGOL74ibbj8J/FJR0cFEyj0z4WQRvtkh32xAHl83gbuINryMt0sqRI+clj2381NKL55DRLQrVw0gsfqqxiHAnXg21qWmc4J+b9e9kiuAFQjcjwTVkwJCcg3xbPwC/qokYRby/Y5S40UUd7/jEARGXT7RZgpzTuDd1oZiCVrnrqJNPaMNdVv5MLeFdf1B7iIe5aa/fGouX7AO4SdKhZUdnJmCFAGvjC6S3JMZ2wAcUl+OHnssfmdj7XL50cLo27vjuzMtLAgSqi6N99m92WCF2s8J9aVzszX7Xz9OKZCeGsiVJp3/NdABKzSEAyM9xBD/5Vho894Sav+otpySHe3p6RUTgbB5Zu8VyZRZ/UtB3ueXxyo764yrc6qWIDqrehm84Xm9g+/jpIBzGPl07NUNJpdt/6Sgf9RIKXw/7XypO5yZfUcuFNGTxLfqjTNrtgLZNcjfav6sSdVXVcMPL//XNuRdKmVFaO76eV/oGMQGr1fGcCD+N+CpI7+Q+fCNB6VFWG4nZFuI/Iuw== averdagu@redhat.com" 2025-10-13 10:57:49.367468 | controller | } 2025-10-13 10:57:49.572900 | controller | changed: 2025-10-13 10:57:49.576518 | controller | { 2025-10-13 10:57:49.576573 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDq8l27xI+QlQVdS4djp9ogSoyrNE2+Ox6vKPdhSNL1J3PE5w+WCSvMz9A5gnNuH810zwbekEApbxTze/gLQJwBHA52CChfURpXrFaxY7ePXRElwKAL3mJfzBWY/c5jnNL9TCVmFJTGZkFZP3Nh+BMgZvL6xBkt3WKm6Uq18qzd9XeKcZusrA+O+uLv1fVeQnadY9RIqOCyeFYCzLWrUfTyE8x/XG0hAWIM7qpnF2cALQS2h9n4hW5ybiUN790H08wf9hFwEf5nxY9Z9dVkPFQiTSGKNBzmnCXU9skxS/xhpFjJ5duGSZdtAHe9O+nGZm9c67hxgtf8e5PDuqAdXEv2cf6e3VBAt+Bz8EKI3yosTj0oZHfwr42Yzb1l/SKy14Rggsrc9KAQlrGXan6+u2jcQqqx7l+SWmnpFiWTV9u5cWj2IgOhApOitmRBPYqk9rE2usfO0hLn/Pj/R/Nau4803e1/EikdLE7Ps95s9mX5jRDjAoUa2JwFF5RsVFyL910= ashigupt@ashigupt.remote.csb" 2025-10-13 10:57:49.576615 | controller | } 2025-10-13 10:57:49.791646 | controller | changed: 2025-10-13 10:57:49.791786 | controller | { 2025-10-13 10:57:49.791814 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9" 2025-10-13 10:57:49.791836 | controller | } 2025-10-13 10:57:50.010633 | controller | changed: 2025-10-13 10:57:50.010776 | controller | { 2025-10-13 10:57:50.010810 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora" 2025-10-13 10:57:50.010833 | controller | } 2025-10-13 10:57:50.214767 | controller | changed: 2025-10-13 10:57:50.214864 | controller | { 2025-10-13 10:57:50.214893 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac" 2025-10-13 10:57:50.214934 | controller | } 2025-10-13 10:57:50.432194 | controller | changed: 2025-10-13 10:57:50.432286 | controller | { 2025-10-13 10:57:50.432402 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop" 2025-10-13 10:57:50.432518 | controller | } 2025-10-13 10:57:50.636655 | controller | changed: 2025-10-13 10:57:50.636747 | controller | { 2025-10-13 10:57:50.636777 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQDZdI7t1cxYx65heVI24HTV4F7oQLW1zyfxHreL2TIJKxjyrUUKIFEUmTutcBlJRLNT2Eoix6x1sOw9YrchloCLcn//SGfTElr9mSc5jbjb7QXEU+zJMhtxyEJ1Po3CUGnj7ckiIXw7wcawZtrEOAQ9pH3ExYCJcEMiyNjRQZCxT3tPK+S4B95EWh5Fsrz9CkwpjNRPPH7LigCeQTM3Wc7r97utAslBUUvYceDSLA7rMgkitJE38b7rZBeYzsGQ8YYUBjTCtehqQXxCRjizbHWaaZkBU+N3zkKB6n/iCNGIO690NK7A/qb6msTijiz1PeuM8ThOsi9qXnbX5v0PoTpcFSojV7NHAQ71f0XXuS43FhZctT+Dcx44dT8Fb5vJu2cJGrk+qF8ZgJYNpRS7gPg0EG2EqjK7JMf9ULdjSu0r+KlqIAyLvtzT4eOnQipoKlb/WG5D/0ohKv7OMQ352ggfkBFIQsRXyyTCT98Ft9juqPuahi3CAQmP4H9dyE+7+Kz437PEtsxLmfm6naNmWi7Ee1DqWPwS8rEajsm4sNM4wW9gdBboJQtc0uZw0DfLj1I9r3Mc8Ol0jYtz0yNQDSzVLrGCaJlC311trU70tZ+ZkAVV6Mn8lOhSbj1cK0lvSr6ZK4dgqGl3I1eTZJJhbLNdg7UOVaiRx9543+C/p/As7w== brjackma@redhat.com" 2025-10-13 10:57:50.636799 | controller | } 2025-10-13 10:57:50.864451 | controller | changed: 2025-10-13 10:57:50.864545 | controller | { 2025-10-13 10:57:50.864573 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com" 2025-10-13 10:57:50.864594 | controller | } 2025-10-13 10:57:51.070162 | controller | changed: 2025-10-13 10:57:51.070604 | controller | { 2025-10-13 10:57:51.070655 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com" 2025-10-13 10:57:51.070680 | controller | } 2025-10-13 10:57:51.294398 | controller | changed: 2025-10-13 10:57:51.294490 | controller | { 2025-10-13 10:57:51.294518 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono" 2025-10-13 10:57:51.294539 | controller | } 2025-10-13 10:57:51.320336 | 2025-10-13 10:57:51.320444 | TASK [Set timezone to UTC] 2025-10-13 10:57:51.790310 | controller | changed: executed `/bin/timedatectl set-timezone UTC` 2025-10-13 10:57:51.795898 | 2025-10-13 10:57:51.796003 | TASK [Create nodepool directory] 2025-10-13 10:57:52.070005 | controller | changed 2025-10-13 10:57:52.076318 | 2025-10-13 10:57:52.076401 | TASK [Create nodepool sub_nodes file] 2025-10-13 10:57:52.661621 | controller | changed 2025-10-13 10:57:52.673074 | 2025-10-13 10:57:52.673164 | TASK [Create nodepool sub_nodes_private file] 2025-10-13 10:57:53.259595 | controller | changed 2025-10-13 10:57:53.265597 | 2025-10-13 10:57:53.265675 | LOOP [Populate nodepool sub_nodes file] 2025-10-13 10:57:53.335640 | 2025-10-13 10:57:53.335811 | LOOP [Populate nodepool sub_nodes_private file] 2025-10-13 10:57:53.410974 | 2025-10-13 10:57:53.411520 | TASK [Create nodepool primary file] 2025-10-13 10:57:53.472059 | controller | skipping: Conditional result was False 2025-10-13 10:57:53.478996 | 2025-10-13 10:57:53.479093 | TASK [Create nodepool node_private for this node] 2025-10-13 10:57:54.096967 | controller | changed 2025-10-13 10:57:54.102341 | 2025-10-13 10:57:54.102403 | LOOP [Copy ssh keys to nodepool directory] 2025-10-13 10:57:54.553508 | controller | ok: Item: id_rsa Runtime: 0:00:00.005275 2025-10-13 10:57:54.553678 | 2025-10-13 10:57:54.721386 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.004512 2025-10-13 10:57:54.736568 | 2025-10-13 10:57:54.736671 | TASK [Add sudoers role for zuul-sudo-grep.sh] 2025-10-13 10:57:55.322896 | controller | changed 2025-10-13 10:57:55.328658 | 2025-10-13 10:57:55.328754 | TASK [Validate sudoers config after edits] 2025-10-13 10:57:55.548977 | controller | /etc/sudoers: parsed OK 2025-10-13 10:57:55.549069 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK 2025-10-13 10:57:55.549084 | controller | /etc/sudoers.d/zuul: parsed OK 2025-10-13 10:57:55.549091 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK 2025-10-13 10:57:55.856695 | controller | ok: Runtime: 0:00:00.005247 2025-10-13 10:57:55.862880 | 2025-10-13 10:57:55.862972 | TASK [Show the environment passed in to job shell scripts] 2025-10-13 10:57:56.068853 | controller | SHELL=/bin/bash 2025-10-13 10:57:56.068896 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework 2025-10-13 10:57:56.068905 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework 2025-10-13 10:57:56.068911 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/02/3402/6139d33ce8b95cb841792108bc733b3ec3296a29 2025-10-13 10:57:56.068929 | controller | PWD=/home/zuul 2025-10-13 10:57:56.068969 | controller | ZUUL_PIPELINE=github-check 2025-10-13 10:57:56.068978 | controller | LOGNAME=zuul 2025-10-13 10:57:56.068983 | controller | XDG_SESSION_TYPE=tty 2025-10-13 10:57:56.068989 | controller | _=/usr/bin/env 2025-10-13 10:57:56.068994 | controller | MOTD_SHOWN=pam 2025-10-13 10:57:56.068999 | controller | HOME=/home/zuul 2025-10-13 10:57:56.069005 | controller | LANG=en_US.UTF-8 2025-10-13 10:57:56.069010 | controller | SSH_CONNECTION=192.168.26.12 59846 192.168.26.66 22 2025-10-13 10:57:56.069015 | controller | TOX_TESTENV_PASSENV=ZUUL_UUID ZUUL_PROJECT ZUUL_SHORT_PROJECT_NAME ZUUL_PIPELINE ZUUL_VOTING WORKSPACE BUILD_TIMEOUT ZUUL_BRANCH ZUUL_CHANGES ZUUL_CHANGE_IDS ZUUL_CHANGE ZUUL_PATCHSET 2025-10-13 10:57:56.069023 | controller | ZUUL_CHANGE_IDS=3402,6139d33ce8b95cb841792108bc733b3ec3296a29 2025-10-13 10:57:56.069029 | controller | WORKSPACE=/home/zuul/workspace 2025-10-13 10:57:56.069034 | controller | XDG_SESSION_CLASS=user 2025-10-13 10:57:56.069039 | controller | SELINUX_ROLE_REQUESTED= 2025-10-13 10:57:56.069045 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s 2025-10-13 10:57:56.069075 | controller | USER=zuul 2025-10-13 10:57:56.069082 | controller | ZUUL_VOTING=True 2025-10-13 10:57:56.069087 | controller | BUILD_TIMEOUT=1800000 2025-10-13 10:57:56.069092 | controller | SELINUX_USE_CURRENT_RANGE= 2025-10-13 10:57:56.069098 | controller | SHLVL=1 2025-10-13 10:57:56.069103 | controller | ZUUL_PATCHSET=6139d33ce8b95cb841792108bc733b3ec3296a29 2025-10-13 10:57:56.069108 | controller | XDG_SESSION_ID=1 2025-10-13 10:57:56.069114 | controller | ZUUL_BRANCH=main 2025-10-13 10:57:56.069119 | controller | XDG_RUNTIME_DIR=/run/user/1000 2025-10-13 10:57:56.069124 | controller | SSH_CLIENT=192.168.26.12 59846 22 2025-10-13 10:57:56.069129 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/ 2025-10-13 10:57:56.069135 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima: 2025-10-13 10:57:56.069140 | controller | which_declare=declare -f 2025-10-13 10:57:56.069145 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin 2025-10-13 10:57:56.069150 | controller | SELINUX_LEVEL_REQUESTED= 2025-10-13 10:57:56.069156 | controller | ZUUL_CHANGE=3402 2025-10-13 10:57:56.069161 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus 2025-10-13 10:57:56.069166 | controller | ZUUL_UUID=3e5286f1c93c4509a1a4dc9b95cb8bac 2025-10-13 10:57:56.069173 | controller | BASH_FUNC_which%%=() { ( alias; 2025-10-13 10:57:56.069179 | controller | eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@ 2025-10-13 10:57:56.069184 | controller | } 2025-10-13 10:57:56.392468 | controller | ok: Runtime: 0:00:00.005185 2025-10-13 10:57:56.397995 | 2025-10-13 10:57:56.398077 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars] 2025-10-13 10:57:56.411888 | controller | skipping: Conditional result was False 2025-10-13 10:57:56.418543 | 2025-10-13 10:57:56.418623 | TASK [Symlink /home/zuul-worker/workspace] 2025-10-13 10:57:56.970410 | controller | skipping: Conditional result was False 2025-10-13 10:57:56.977230 | 2025-10-13 10:57:56.977298 | TASK [Ensure legacy workspace directory] 2025-10-13 10:57:57.261999 | controller | changed 2025-10-13 10:57:57.346370 | 2025-10-13 10:57:57.346447 | PLAY RECAP 2025-10-13 10:57:57.346487 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0 2025-10-13 10:57:57.346510 | 2025-10-13 10:57:57.493098 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master] 2025-10-13 10:57:57.493854 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main] 2025-10-13 10:57:58.638479 | 2025-10-13 10:57:58.638618 | PLAY [Run ci/playbooks/dump_zuul_data.yml] 2025-10-13 10:57:58.686298 | 2025-10-13 10:57:58.686380 | TASK [Create zuul-output directory] 2025-10-13 10:57:59.102286 | controller | changed 2025-10-13 10:57:59.111960 | 2025-10-13 10:57:59.126022 | TASK [Slurp Zuul inventory test] 2025-10-13 10:58:00.119156 | controller -> localhost | ok 2025-10-13 10:58:00.127719 | 2025-10-13 10:58:00.127826 | TASK [Save zuul inventory] 2025-10-13 10:58:01.406216 | controller | changed 2025-10-13 10:58:01.420578 | 2025-10-13 10:58:01.420686 | TASK [Save zuul vars without the change_message] 2025-10-13 10:58:02.123103 | controller | changed 2025-10-13 10:58:02.145975 | 2025-10-13 10:58:02.146071 | PLAY RECAP 2025-10-13 10:58:02.146118 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-13 10:58:02.146141 | 2025-10-13 10:58:02.243804 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main] 2025-10-13 10:58:02.244564 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main] 2025-10-13 10:58:02.831441 | 2025-10-13 10:58:02.831544 | PLAY [Run ci/playbooks/molecule-prepare.yml] 2025-10-13 10:58:02.851611 | 2025-10-13 10:58:02.851687 | TASK [Read group_vars] 2025-10-13 10:58:02.869755 | controller | ok 2025-10-13 10:58:02.887973 | 2025-10-13 10:58:02.888089 | TASK [cifmw_helpers : Check directory is available /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/group_vars] 2025-10-13 10:58:03.191416 | controller | ok 2025-10-13 10:58:03.197405 | 2025-10-13 10:58:03.197476 | TASK [cifmw_helpers : List available files] 2025-10-13 10:58:03.503890 | controller | pwd: ignoring non-option arguments 2025-10-13 10:58:03.503922 | controller | /home/zuul 2025-10-13 10:58:03.726021 | controller | changed 2025-10-13 10:58:03.731972 | 2025-10-13 10:58:03.732041 | TASK [cifmw_helpers : List available files] 2025-10-13 10:58:04.259665 | controller | skipping: Conditional result was False 2025-10-13 10:58:04.265980 | 2025-10-13 10:58:04.266076 | LOOP [cifmw_helpers : Read vars] 2025-10-13 10:58:04.287498 | 2025-10-13 10:58:04.287657 | LOOP [cifmw_helpers : Call task to parse all files as fact] 2025-10-13 10:58:04.313517 | 2025-10-13 10:58:04.313662 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh] 2025-10-13 10:58:04.333294 | controller | ok 2025-10-13 10:58:04.384555 | 2025-10-13 10:58:04.384676 | TASK [mirror-info-fork : Set mirror_fqdn if not defined] 2025-10-13 10:58:04.409333 | controller | skipping: Conditional result was False 2025-10-13 10:58:04.416231 | 2025-10-13 10:58:04.416310 | TASK [mirror-info-fork : Create /etc/ci] 2025-10-13 10:58:04.708178 | controller | ok 2025-10-13 10:58:04.713661 | 2025-10-13 10:58:04.713740 | TASK [mirror-info-fork : Install ci_mirror script] 2025-10-13 10:58:05.208009 | controller | ok 2025-10-13 10:58:05.220545 | 2025-10-13 10:58:05.220656 | TASK [Prepare workspace] 2025-10-13 10:58:05.240799 | controller | ok 2025-10-13 10:58:05.266526 | 2025-10-13 10:58:05.266639 | TASK [prepare-workspace : Start zuul_console daemon.] 2025-10-13 10:58:05.594652 | controller | ok 2025-10-13 10:58:05.602683 | 2025-10-13 10:58:05.602838 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2025-10-13 10:58:08.857436 | controller | Output suppressed because no_log was given 2025-10-13 10:58:08.869441 | 2025-10-13 10:58:08.869507 | LOOP [Create zuul-output directory] 2025-10-13 10:58:09.117479 | controller | changed: "/home/zuul/ci-framework-data/logs" 2025-10-13 10:58:09.117689 | 2025-10-13 10:58:09.282160 | controller | ok: "/home/zuul/zuul-output/logs" 2025-10-13 10:58:09.294361 | 2025-10-13 10:58:09.294475 | TASK [Install required packages] 2025-10-13 10:59:00.063452 | controller | changed 2025-10-13 10:59:00.068404 | 2025-10-13 10:59:00.068466 | TASK [Install venv] 2025-10-13 11:00:01.741410 | controller | changed 2025-10-13 11:00:01.765768 | 2025-10-13 11:00:01.765828 | PLAY RECAP 2025-10-13 11:00:01.765871 | controller | ok: 9 changed: 5 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2025-10-13 11:00:01.765894 | 2025-10-13 11:00:01.843147 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main] 2025-10-13 11:00:01.844077 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main] 2025-10-13 11:00:02.405873 | 2025-10-13 11:00:02.405994 | PLAY [Run ci/playbooks/molecule-test.yml] 2025-10-13 11:00:02.428163 | 2025-10-13 11:00:02.428306 | TASK [Gather required facts] 2025-10-13 11:00:02.914849 | controller | ok 2025-10-13 11:00:02.920335 | 2025-10-13 11:00:02.920417 | TASK [Load environment var if instructed to] 2025-10-13 11:00:02.947224 | controller | skipping: Conditional result was False 2025-10-13 11:00:02.952686 | 2025-10-13 11:00:02.952769 | TASK [Ensure group_vars dir exists] 2025-10-13 11:00:03.265045 | controller | ok 2025-10-13 11:00:03.271570 | 2025-10-13 11:00:03.271647 | TASK [Run molecule] 2025-10-13 11:00:04.214036 | controller | INFO Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml 2025-10-13 11:00:04.283954 | controller | INFO Performing prerun with role_name_check=0... 2025-10-13 11:00:24.267340 | controller | WARNING Another version of '@NAMESPACE@.@NAME@' 3.1.4 was found installed in /home/zuul/.cache/ansible-compat/9cc8b6/collections/ansible_collections, only the first one will be used, 3.1.4 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-13 11:00:24.267826 | controller | WARNING Another version of 'ansible.netcommon' 7.1.0 was found installed in /home/zuul/.cache/ansible-compat/9cc8b6/collections/ansible_collections, only the first one will be used, 7.1.0 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-13 11:00:24.268316 | controller | WARNING Another version of 'ansible.posix' 1.6.2 was found installed in /home/zuul/.cache/ansible-compat/9cc8b6/collections/ansible_collections, only the first one will be used, 1.6.2 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-13 11:00:24.268794 | controller | WARNING Another version of 'ansible.utils' 5.1.2 was found installed in /home/zuul/.cache/ansible-compat/9cc8b6/collections/ansible_collections, only the first one will be used, 5.1.2 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-13 11:00:24.269282 | controller | WARNING Another version of 'cifmw.general' 1.0.0+55a27ffc was found installed in /home/zuul/.cache/ansible-compat/9cc8b6/collections/ansible_collections, only the first one will be used, 1.0.0+55a27ffc (/home/zuul/.ansible/collections/ansible_collections). 2025-10-13 11:00:24.269770 | controller | WARNING Another version of 'cisco.ios' 9.0.3 was found installed in /home/zuul/.cache/ansible-compat/9cc8b6/collections/ansible_collections, only the first one will be used, 9.0.3 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-13 11:00:24.270195 | controller | WARNING Another version of 'community.crypto' 2.22.3 was found installed in /home/zuul/.cache/ansible-compat/9cc8b6/collections/ansible_collections, only the first one will be used, 2.22.3 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-13 11:00:24.270667 | controller | WARNING Another version of 'community.general' 10.0.1 was found installed in /home/zuul/.cache/ansible-compat/9cc8b6/collections/ansible_collections, only the first one will be used, 10.0.1 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-13 11:00:24.271120 | controller | WARNING Another version of 'community.libvirt' 1.3.0 was found installed in /home/zuul/.cache/ansible-compat/9cc8b6/collections/ansible_collections, only the first one will be used, 1.3.0 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-13 11:00:24.271581 | controller | WARNING Another version of 'community.okd' 4.0.0 was found installed in /home/zuul/.cache/ansible-compat/9cc8b6/collections/ansible_collections, only the first one will be used, 4.0.0 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-13 11:00:24.272006 | controller | WARNING Another version of 'containers.podman' 1.16.2 was found installed in /home/zuul/.cache/ansible-compat/9cc8b6/collections/ansible_collections, only the first one will be used, 1.16.2 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-13 11:00:24.272492 | controller | WARNING Another version of 'junipernetworks.junos' 9.1.0 was found installed in /home/zuul/.cache/ansible-compat/9cc8b6/collections/ansible_collections, only the first one will be used, 9.1.0 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-13 11:00:24.272930 | controller | WARNING Another version of 'kubernetes.core' 5.0.0 was found installed in /home/zuul/.cache/ansible-compat/9cc8b6/collections/ansible_collections, only the first one will be used, 5.0.0 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-13 11:00:24.273403 | controller | WARNING Another version of 'mellanox.onyx' 1.0.0 was found installed in /home/zuul/.cache/ansible-compat/9cc8b6/collections/ansible_collections, only the first one will be used, 1.0.0 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-13 11:00:24.273853 | controller | WARNING Another version of 'openstack.config_template' 2.1.1 was found installed in /home/zuul/.cache/ansible-compat/9cc8b6/collections/ansible_collections, only the first one will be used, 2.1.1 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-13 11:00:24.284309 | controller | INFO Running default > prepare 2025-10-13 11:00:24.938321 | controller | 2025-10-13 11:00:24.938389 | controller | PLAY [Prepare] ***************************************************************** 2025-10-13 11:00:24.938447 | controller | 2025-10-13 11:00:24.938549 | controller | TASK [Gathering Facts] ********************************************************* 2025-10-13 11:00:24.938647 | controller | Monday 13 October 2025 11:00:24 +0000 (0:00:00.012) 0:00:00.012 ******** 2025-10-13 11:00:25.773142 | controller | ok: [instance] 2025-10-13 11:00:25.773249 | controller | 2025-10-13 11:00:25.773353 | controller | TASK [test_deps : Gather facts if they don't exist] **************************** 2025-10-13 11:00:25.773479 | controller | Monday 13 October 2025 11:00:25 +0000 (0:00:00.835) 0:00:00.848 ******** 2025-10-13 11:00:25.790771 | controller | skipping: [instance] 2025-10-13 11:00:25.790852 | controller | 2025-10-13 11:00:25.790956 | controller | TASK [test_deps : Gather variables for each operating system] ****************** 2025-10-13 11:00:25.791065 | controller | Monday 13 October 2025 11:00:25 +0000 (0:00:00.017) 0:00:00.866 ******** 2025-10-13 11:00:25.832106 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/test_deps/vars/centos-9.yml) 2025-10-13 11:00:25.832202 | controller | 2025-10-13 11:00:25.832308 | controller | TASK [test_deps : Detect mirrors file] ***************************************** 2025-10-13 11:00:25.832429 | controller | Monday 13 October 2025 11:00:25 +0000 (0:00:00.041) 0:00:00.907 ******** 2025-10-13 11:00:26.110549 | controller | ok: [instance] 2025-10-13 11:00:26.110628 | controller | 2025-10-13 11:00:26.110742 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] *********** 2025-10-13 11:00:26.110847 | controller | Monday 13 October 2025 11:00:26 +0000 (0:00:00.278) 0:00:01.185 ******** 2025-10-13 11:00:26.420138 | controller | ok: [instance] 2025-10-13 11:00:26.420229 | controller | 2025-10-13 11:00:26.420354 | controller | TASK [test_deps : Disable ubi 9 repos] ***************************************** 2025-10-13 11:00:26.420464 | controller | Monday 13 October 2025 11:00:26 +0000 (0:00:00.309) 0:00:01.495 ******** 2025-10-13 11:00:27.099559 | controller | ok: [instance] => (item=ubi-9-appstream) 2025-10-13 11:00:27.099640 | controller | ok: [instance] => (item=ubi-9-baseos) 2025-10-13 11:00:27.099756 | controller | ok: [instance] => (item=ubi-9-codeready-builder) 2025-10-13 11:00:27.099847 | controller | 2025-10-13 11:00:27.099952 | controller | TASK [Deploy repo-setup] ******************************************************* 2025-10-13 11:00:27.100068 | controller | Monday 13 October 2025 11:00:27 +0000 (0:00:00.679) 0:00:02.174 ******** 2025-10-13 11:00:27.158159 | controller | 2025-10-13 11:00:27.158284 | controller | TASK [repo_setup : Ensure directories are present] ***************************** 2025-10-13 11:00:27.158433 | controller | Monday 13 October 2025 11:00:27 +0000 (0:00:00.058) 0:00:02.233 ******** 2025-10-13 11:00:27.685914 | controller | changed: [instance] => (item=tmp) 2025-10-13 11:00:27.686003 | controller | changed: [instance] => (item=artifacts/repositories) 2025-10-13 11:00:27.686144 | controller | changed: [instance] => (item=venv/repo_setup) 2025-10-13 11:00:27.686250 | controller | 2025-10-13 11:00:27.686371 | controller | TASK [repo_setup : Make sure git-core package is installed] ******************** 2025-10-13 11:00:27.686472 | controller | Monday 13 October 2025 11:00:27 +0000 (0:00:00.527) 0:00:02.761 ******** 2025-10-13 11:00:28.448757 | controller | ok: [instance] 2025-10-13 11:00:28.449072 | controller | 2025-10-13 11:00:29.640600 | controller | TASK [repo_setup : Get repo-setup repository] ********************************** 2025-10-13 11:00:29.640629 | controller | Monday 13 October 2025 11:00:28 +0000 (0:00:00.762) 0:00:03.524 ******** 2025-10-13 11:00:29.640640 | controller | changed: [instance] 2025-10-13 11:00:37.592577 | controller | 2025-10-13 11:00:37.592613 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************ 2025-10-13 11:00:37.592624 | controller | Monday 13 October 2025 11:00:29 +0000 (0:00:01.191) 0:00:04.715 ******** 2025-10-13 11:00:37.592635 | controller | changed: [instance] 2025-10-13 11:00:38.292134 | controller | 2025-10-13 11:00:38.292174 | controller | TASK [repo_setup : Install repo-setup package] ********************************* 2025-10-13 11:00:38.292183 | controller | Monday 13 October 2025 11:00:37 +0000 (0:00:07.951) 0:00:12.667 ******** 2025-10-13 11:00:38.292194 | controller | changed: [instance] 2025-10-13 11:00:38.312149 | controller | 2025-10-13 11:00:38.312180 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] *** 2025-10-13 11:00:38.312188 | controller | Monday 13 October 2025 11:00:38 +0000 (0:00:00.699) 0:00:13.367 ******** 2025-10-13 11:00:38.312199 | controller | skipping: [instance] 2025-10-13 11:00:38.312223 | controller | 2025-10-13 11:00:38.312424 | controller | TASK [repo_setup : Run repo-setup] ********************************************* 2025-10-13 11:00:39.131590 | controller | Monday 13 October 2025 11:00:38 +0000 (0:00:00.020) 0:00:13.387 ******** 2025-10-13 11:00:39.131625 | controller | changed: [instance] 2025-10-13 11:00:39.131792 | controller | 2025-10-13 11:00:39.131811 | controller | TASK [repo_setup : Get component repo] ***************************************** 2025-10-13 11:00:39.165260 | controller | Monday 13 October 2025 11:00:39 +0000 (0:00:00.817) 0:00:14.205 ******** 2025-10-13 11:00:39.165287 | controller | skipping: [instance] 2025-10-13 11:00:39.199221 | controller | 2025-10-13 11:00:39.199245 | controller | TASK [repo_setup : Rename component repo] ************************************** 2025-10-13 11:00:39.199253 | controller | Monday 13 October 2025 11:00:39 +0000 (0:00:00.034) 0:00:14.240 ******** 2025-10-13 11:00:39.199264 | controller | skipping: [instance] 2025-10-13 11:00:39.199285 | controller | 2025-10-13 11:00:39.199294 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] ******* 2025-10-13 11:00:39.199744 | controller | Monday 13 October 2025 11:00:39 +0000 (0:00:00.034) 0:00:14.274 ******** 2025-10-13 11:00:39.233997 | controller | skipping: [instance] 2025-10-13 11:00:39.777001 | controller | 2025-10-13 11:00:39.777030 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************ 2025-10-13 11:00:39.777038 | controller | Monday 13 October 2025 11:00:39 +0000 (0:00:00.034) 0:00:14.308 ******** 2025-10-13 11:00:39.777065 | controller | changed: [instance] 2025-10-13 11:00:40.230947 | controller | 2025-10-13 11:00:40.230976 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] ******************* 2025-10-13 11:00:40.230984 | controller | Monday 13 October 2025 11:00:39 +0000 (0:00:00.543) 0:00:14.851 ******** 2025-10-13 11:00:40.230994 | controller | changed: [instance] 2025-10-13 11:00:40.259174 | controller | 2025-10-13 11:00:40.259204 | controller | TASK [repo_setup : Dump current-podified hash] ********************************* 2025-10-13 11:00:40.259212 | controller | Monday 13 October 2025 11:00:40 +0000 (0:00:00.454) 0:00:15.306 ******** 2025-10-13 11:00:40.259222 | controller | skipping: [instance] 2025-10-13 11:00:40.287984 | controller | 2025-10-13 11:00:40.288007 | controller | TASK [repo_setup : Slurp current podified hash] ******************************** 2025-10-13 11:00:40.288015 | controller | Monday 13 October 2025 11:00:40 +0000 (0:00:00.028) 0:00:15.334 ******** 2025-10-13 11:00:40.288028 | controller | skipping: [instance] 2025-10-13 11:00:40.315547 | controller | 2025-10-13 11:00:40.315569 | controller | TASK [repo_setup : Update the value of full_hash] ****************************** 2025-10-13 11:00:40.315576 | controller | Monday 13 October 2025 11:00:40 +0000 (0:00:00.028) 0:00:15.363 ******** 2025-10-13 11:00:40.315585 | controller | skipping: [instance] 2025-10-13 11:00:40.352659 | controller | 2025-10-13 11:00:40.352682 | controller | TASK [repo_setup : Export hashes facts for further use] ************************ 2025-10-13 11:00:40.352690 | controller | Monday 13 October 2025 11:00:40 +0000 (0:00:00.027) 0:00:15.390 ******** 2025-10-13 11:00:40.352700 | controller | ok: [instance] 2025-10-13 11:00:40.378848 | controller | 2025-10-13 11:00:40.378873 | controller | TASK [repo_setup : Create download directory] ********************************** 2025-10-13 11:00:40.378881 | controller | Monday 13 October 2025 11:00:40 +0000 (0:00:00.037) 0:00:15.427 ******** 2025-10-13 11:00:40.378891 | controller | skipping: [instance] 2025-10-13 11:00:40.405344 | controller | 2025-10-13 11:00:40.405394 | controller | TASK [repo_setup : Print the URL to request] *********************************** 2025-10-13 11:00:40.405404 | controller | Monday 13 October 2025 11:00:40 +0000 (0:00:00.026) 0:00:15.453 ******** 2025-10-13 11:00:40.405428 | controller | skipping: [instance] 2025-10-13 11:00:40.405450 | controller | 2025-10-13 11:00:40.405458 | controller | TASK [Download the RPM] ******************************************************** 2025-10-13 11:00:40.405467 | controller | Monday 13 October 2025 11:00:40 +0000 (0:00:00.026) 0:00:15.480 ******** 2025-10-13 11:00:40.430786 | controller | skipping: [instance] 2025-10-13 11:00:40.430839 | controller | 2025-10-13 11:00:40.430945 | controller | TASK [repo_setup : Install RHOS Release tool] ********************************** 2025-10-13 11:00:40.431085 | controller | Monday 13 October 2025 11:00:40 +0000 (0:00:00.025) 0:00:15.506 ******** 2025-10-13 11:00:40.460091 | controller | skipping: [instance] 2025-10-13 11:00:40.486812 | controller | 2025-10-13 11:00:40.486847 | controller | TASK [repo_setup : Get rhos-release tool version] ****************************** 2025-10-13 11:00:40.486856 | controller | Monday 13 October 2025 11:00:40 +0000 (0:00:00.028) 0:00:15.535 ******** 2025-10-13 11:00:40.486867 | controller | skipping: [instance] 2025-10-13 11:00:40.518143 | controller | 2025-10-13 11:00:40.518178 | controller | TASK [repo_setup : Print rhos-release tool version] **************************** 2025-10-13 11:00:40.518187 | controller | Monday 13 October 2025 11:00:40 +0000 (0:00:00.026) 0:00:15.561 ******** 2025-10-13 11:00:40.518199 | controller | skipping: [instance] 2025-10-13 11:00:40.544104 | controller | 2025-10-13 11:00:40.544135 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] *** 2025-10-13 11:00:40.544143 | controller | Monday 13 October 2025 11:00:40 +0000 (0:00:00.030) 0:00:15.592 ******** 2025-10-13 11:00:40.544154 | controller | skipping: [instance] 2025-10-13 11:00:40.544226 | controller | 2025-10-13 11:00:40.544233 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] *************************** 2025-10-13 11:00:40.544241 | controller | Monday 13 October 2025 11:00:40 +0000 (0:00:00.026) 0:00:15.619 ******** 2025-10-13 11:00:40.725252 | controller | ok: [instance] 2025-10-13 11:00:40.929518 | controller | 2025-10-13 11:00:40.929559 | controller | TASK [repo_setup : Use RDO proxy mirrors] ************************************** 2025-10-13 11:00:40.929567 | controller | Monday 13 October 2025 11:00:40 +0000 (0:00:00.180) 0:00:15.800 ******** 2025-10-13 11:00:40.929579 | controller | changed: [instance] 2025-10-13 11:00:41.157345 | controller | 2025-10-13 11:00:41.157391 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] *** 2025-10-13 11:00:41.157401 | controller | Monday 13 October 2025 11:00:40 +0000 (0:00:00.204) 0:00:16.004 ******** 2025-10-13 11:00:41.157414 | controller | changed: [instance] 2025-10-13 11:00:41.177764 | controller | 2025-10-13 11:00:41.177794 | controller | TASK [repo_setup : Check for gating.repo file on content provider] ************* 2025-10-13 11:00:41.177803 | controller | Monday 13 October 2025 11:00:41 +0000 (0:00:00.227) 0:00:16.232 ******** 2025-10-13 11:00:41.177816 | controller | skipping: [instance] 2025-10-13 11:00:41.198161 | controller | 2025-10-13 11:00:41.198197 | controller | TASK [repo_setup : Populate gating repo from content provider ip] ************** 2025-10-13 11:00:41.198206 | controller | Monday 13 October 2025 11:00:41 +0000 (0:00:00.020) 0:00:16.252 ******** 2025-10-13 11:00:41.198218 | controller | skipping: [instance] 2025-10-13 11:00:41.219817 | controller | 2025-10-13 11:00:41.219848 | controller | TASK [repo_setup : Check for DLRN repo at the destination] ********************* 2025-10-13 11:00:41.219858 | controller | Monday 13 October 2025 11:00:41 +0000 (0:00:00.020) 0:00:16.273 ******** 2025-10-13 11:00:41.219870 | controller | skipping: [instance] 2025-10-13 11:00:41.240992 | controller | 2025-10-13 11:00:41.241025 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] *** 2025-10-13 11:00:41.241034 | controller | Monday 13 October 2025 11:00:41 +0000 (0:00:00.021) 0:00:16.295 ******** 2025-10-13 11:00:41.241060 | controller | skipping: [instance] 2025-10-13 11:00:41.262190 | controller | 2025-10-13 11:00:41.262230 | controller | TASK [repo_setup : Check for DLRN component repo] ****************************** 2025-10-13 11:00:41.262247 | controller | Monday 13 October 2025 11:00:41 +0000 (0:00:00.021) 0:00:16.316 ******** 2025-10-13 11:00:41.262260 | controller | skipping: [instance] 2025-10-13 11:00:41.283703 | controller | 2025-10-13 11:00:41.283729 | controller | TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] *** 2025-10-13 11:00:41.283744 | controller | Monday 13 October 2025 11:00:41 +0000 (0:00:00.021) 0:00:16.337 ******** 2025-10-13 11:00:41.283754 | controller | skipping: [instance] 2025-10-13 11:00:41.300417 | controller | 2025-10-13 11:00:41.300444 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ******** 2025-10-13 11:00:41.300454 | controller | Monday 13 October 2025 11:00:41 +0000 (0:00:00.021) 0:00:16.358 ******** 2025-10-13 11:00:41.300466 | controller | skipping: [instance] 2025-10-13 11:00:41.329158 | controller | 2025-10-13 11:00:41.329181 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ****** 2025-10-13 11:00:41.329190 | controller | Monday 13 October 2025 11:00:41 +0000 (0:00:00.016) 0:00:16.375 ******** 2025-10-13 11:00:41.329200 | controller | skipping: [instance] 2025-10-13 11:00:41.346337 | controller | 2025-10-13 11:00:41.346363 | controller | TASK [repo_setup : Cleanup existing metadata] ********************************** 2025-10-13 11:00:41.346371 | controller | Monday 13 October 2025 11:00:41 +0000 (0:00:00.028) 0:00:16.404 ******** 2025-10-13 11:00:41.346382 | controller | skipping: [instance] 2025-10-13 11:00:41.366853 | controller | 2025-10-13 11:00:41.366881 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] ********* 2025-10-13 11:00:41.366890 | controller | Monday 13 October 2025 11:00:41 +0000 (0:00:00.016) 0:00:16.421 ******** 2025-10-13 11:00:41.366903 | controller | skipping: [instance] 2025-10-13 11:00:41.397676 | controller | 2025-10-13 11:00:41.397700 | controller | TASK [test_deps : Look for redhat-release rpm] ********************************* 2025-10-13 11:00:41.397707 | controller | Monday 13 October 2025 11:00:41 +0000 (0:00:00.020) 0:00:16.441 ******** 2025-10-13 11:00:41.397717 | controller | skipping: [instance] 2025-10-13 11:00:41.428903 | controller | 2025-10-13 11:00:41.428942 | controller | TASK [test_deps : Remove redhat-release] *************************************** 2025-10-13 11:00:41.428949 | controller | Monday 13 October 2025 11:00:41 +0000 (0:00:00.030) 0:00:16.472 ******** 2025-10-13 11:00:41.428964 | controller | skipping: [instance] 2025-10-13 11:00:41.459933 | controller | 2025-10-13 11:00:41.460076 | controller | TASK [test_deps : Install centos-stream-release] ******************************* 2025-10-13 11:00:41.460091 | controller | Monday 13 October 2025 11:00:41 +0000 (0:00:00.030) 0:00:16.503 ******** 2025-10-13 11:00:41.460110 | controller | skipping: [instance] 2025-10-13 11:00:41.491163 | controller | 2025-10-13 11:00:41.491187 | controller | TASK [test_deps : Install additional packages] ********************************* 2025-10-13 11:00:41.491196 | controller | Monday 13 October 2025 11:00:41 +0000 (0:00:00.030) 0:00:16.534 ******** 2025-10-13 11:00:41.491207 | controller | skipping: [instance] 2025-10-13 11:01:23.866173 | controller | 2025-10-13 11:01:23.866207 | controller | TASK [test_deps : Install selinux python libs] ********************************* 2025-10-13 11:01:23.866218 | controller | Monday 13 October 2025 11:00:41 +0000 (0:00:00.031) 0:00:16.566 ******** 2025-10-13 11:01:23.866230 | controller | ok: [instance] 2025-10-13 11:01:25.010986 | controller | 2025-10-13 11:01:25.011019 | controller | TASK [test_deps : Install python yaml libs] ************************************ 2025-10-13 11:01:25.011027 | controller | Monday 13 October 2025 11:01:23 +0000 (0:00:42.375) 0:00:58.941 ******** 2025-10-13 11:01:25.011038 | controller | ok: [instance] 2025-10-13 11:01:25.053137 | controller | 2025-10-13 11:01:25.053177 | controller | TASK [test_deps : Install extra packages] ************************************** 2025-10-13 11:01:25.053187 | controller | Monday 13 October 2025 11:01:25 +0000 (0:00:01.144) 0:01:00.086 ******** 2025-10-13 11:01:25.053200 | controller | skipping: [instance] 2025-10-13 11:01:25.055068 | controller | 2025-10-13 11:01:25.055094 | controller | PLAY RECAP ********************************************************************* 2025-10-13 11:01:25.055102 | controller | instance : ok=19 changed=9 unreachable=0 failed=0 skipped=30 rescued=0 ignored=0 2025-10-13 11:01:25.055108 | controller | 2025-10-13 11:01:25.055114 | controller | Monday 13 October 2025 11:01:25 +0000 (0:00:00.041) 0:01:00.127 ******** 2025-10-13 11:01:25.055119 | controller | =============================================================================== 2025-10-13 11:01:25.055124 | controller | test_deps : Install selinux python libs -------------------------------- 42.38s 2025-10-13 11:01:25.055141 | controller | repo_setup : Initialize python venv and install requirements ------------ 7.95s 2025-10-13 11:01:25.109132 | controller | repo_setup : Get repo-setup repository ---------------------------------- 1.19s 2025-10-13 11:01:25.109163 | controller | test_deps : Install python yaml libs ------------------------------------ 1.14s 2025-10-13 11:01:25.109176 | controller | Gathering Facts --------------------------------------------------------- 0.84s 2025-10-13 11:01:25.109182 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.82s 2025-10-13 11:01:25.109188 | controller | repo_setup : Make sure git-core package is installed -------------------- 0.76s 2025-10-13 11:01:25.109193 | controller | repo_setup : Install repo-setup package --------------------------------- 0.70s 2025-10-13 11:01:25.109198 | controller | test_deps : Disable ubi 9 repos ----------------------------------------- 0.68s 2025-10-13 11:01:25.109204 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.54s 2025-10-13 11:01:25.109209 | controller | repo_setup : Ensure directories are present ----------------------------- 0.53s 2025-10-13 11:01:25.109214 | controller | repo_setup : Dump full hash in delorean.repo.md5 file ------------------- 0.45s 2025-10-13 11:01:25.109219 | controller | test_deps : Disable ubi host subscription-manager integration ----------- 0.31s 2025-10-13 11:01:25.109225 | controller | test_deps : Detect mirrors file ----------------------------------------- 0.28s 2025-10-13 11:01:25.109230 | controller | repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist) --- 0.23s 2025-10-13 11:01:25.109236 | controller | repo_setup : Use RDO proxy mirrors -------------------------------------- 0.20s 2025-10-13 11:01:25.109241 | controller | repo_setup : Check for /etc/ci/mirror_info.sh --------------------------- 0.18s 2025-10-13 11:01:25.109246 | controller | Deploy repo-setup ------------------------------------------------------- 0.06s 2025-10-13 11:01:25.109252 | controller | test_deps : Install extra packages -------------------------------------- 0.04s 2025-10-13 11:01:25.109257 | controller | test_deps : Gather variables for each operating system ------------------ 0.04s 2025-10-13 11:01:25.109267 | controller | INFO Running default > converge 2025-10-13 11:01:25.521727 | controller | 2025-10-13 11:01:26.279692 | controller | PLAY [Converge] **************************************************************** 2025-10-13 11:01:26.279723 | controller | 2025-10-13 11:01:26.279731 | controller | TASK [Gathering Facts] ********************************************************* 2025-10-13 11:01:26.279737 | controller | Monday 13 October 2025 11:01:25 +0000 (0:00:00.013) 0:00:00.013 ******** 2025-10-13 11:01:26.279747 | controller | ok: [instance] 2025-10-13 11:01:26.314161 | controller | 2025-10-13 11:01:26.314195 | controller | TASK [cifmw_test_role : Hello World] ******************************************* 2025-10-13 11:01:26.314205 | controller | Monday 13 October 2025 11:01:26 +0000 (0:00:00.758) 0:00:00.772 ******** 2025-10-13 11:01:26.314217 | controller | ok: [instance] => 2025-10-13 11:01:26.314243 | controller | msg: Hello world from cifmw_test_role 2025-10-13 11:01:26.314252 | controller | 2025-10-13 11:01:26.314259 | controller | PLAY RECAP ********************************************************************* 2025-10-13 11:01:26.314266 | controller | instance : ok=2 changed=0 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2025-10-13 11:01:26.314273 | controller | 2025-10-13 11:01:26.314279 | controller | Monday 13 October 2025 11:01:26 +0000 (0:00:00.034) 0:00:00.806 ******** 2025-10-13 11:01:26.314288 | controller | =============================================================================== 2025-10-13 11:01:26.314469 | controller | Gathering Facts --------------------------------------------------------- 0.76s 2025-10-13 11:01:26.366934 | controller | cifmw_test_role : Hello World ------------------------------------------- 0.03s 2025-10-13 11:01:26.366971 | controller | INFO Running default > cleanup 2025-10-13 11:01:26.367446 | controller | WARNING Skipping, cleanup playbook not configured. 2025-10-13 11:01:26.368015 | controller | INFO Writing /tmp/report.html report. 2025-10-13 11:01:26.895082 | controller | changed 2025-10-13 11:01:26.919290 | 2025-10-13 11:01:26.919359 | PLAY RECAP 2025-10-13 11:01:26.919404 | controller | ok: 3 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2025-10-13 11:01:26.919429 | 2025-10-13 11:01:27.167213 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main] 2025-10-13 11:01:27.168082 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main] 2025-10-13 11:01:28.216953 | 2025-10-13 11:01:28.217057 | PLAY [Run ci/playbooks/collect-logs.yml] 2025-10-13 11:01:28.276565 | 2025-10-13 11:01:28.276713 | TASK [Filter out host if needed] 2025-10-13 11:01:28.297460 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller 2025-10-13 11:01:28.304090 | 2025-10-13 11:01:28.304172 | TASK [Ensure file is present] 2025-10-13 11:01:28.727671 | controller | ok 2025-10-13 11:01:28.739582 | 2025-10-13 11:01:28.739653 | TASK [Manage molecule report file] 2025-10-13 11:01:29.267306 | controller | changed 2025-10-13 11:01:29.272580 | 2025-10-13 11:01:29.272643 | TASK [Check if we get ci-framework-data basedir] 2025-10-13 11:01:29.484180 | controller | ok 2025-10-13 11:01:29.499361 | 2025-10-13 11:01:29.499433 | TASK [Create ci-framework-data log directory for zuul] 2025-10-13 11:01:30.025016 | controller | changed 2025-10-13 11:01:30.030487 | 2025-10-13 11:01:30.030561 | TASK [Copy ci-framework interesting files] 2025-10-13 11:01:30.284944 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory 2025-10-13 11:01:30.593154 | controller | changed 2025-10-13 11:01:30.598294 | 2025-10-13 11:01:30.598360 | TASK [Get SELinux listing] 2025-10-13 11:01:31.151894 | controller | changed 2025-10-13 11:01:31.160184 | 2025-10-13 11:01:31.160256 | TASK [Generate log index] 2025-10-13 11:01:31.863857 | controller | changed 2025-10-13 11:01:31.870225 | 2025-10-13 11:01:31.870354 | TASK [Get some env related data] 2025-10-13 11:01:32.414615 | controller | /home/zuul/.local/bin/ansible 2025-10-13 11:01:33.399218 | controller | changed 2025-10-13 11:01:33.405132 | 2025-10-13 11:01:33.405195 | TASK [Generate list of logs to collect in home directory] 2025-10-13 11:01:33.815111 | controller | ok: All paths examined 2025-10-13 11:01:33.821450 | 2025-10-13 11:01:33.821518 | LOOP [Copy logs from home directory] 2025-10-13 11:01:34.292980 | controller | changed: 2025-10-13 11:01:34.293113 | controller | { 2025-10-13 11:01:34.293142 | controller | "atime": 1760353184.1683154, 2025-10-13 11:01:34.293164 | controller | "ctime": 1760353201.3205106, 2025-10-13 11:01:34.293183 | controller | "dev": 64513, 2025-10-13 11:01:34.293201 | controller | "gid": 1000, 2025-10-13 11:01:34.293218 | controller | "gr_name": "zuul", 2025-10-13 11:01:34.293236 | controller | "inode": 4480875, 2025-10-13 11:01:34.293252 | controller | "isblk": false, 2025-10-13 11:01:34.293268 | controller | "ischr": false, 2025-10-13 11:01:34.293285 | controller | "isdir": false, 2025-10-13 11:01:34.293300 | controller | "isfifo": false, 2025-10-13 11:01:34.293316 | controller | "isgid": false, 2025-10-13 11:01:34.293466 | controller | "islnk": false, 2025-10-13 11:01:34.293480 | controller | "isreg": true, 2025-10-13 11:01:34.293496 | controller | "issock": false, 2025-10-13 11:01:34.293510 | controller | "isuid": false, 2025-10-13 11:01:34.293525 | controller | "mode": "0644", 2025-10-13 11:01:34.293540 | controller | "mtime": 1760353201.3205106, 2025-10-13 11:01:34.293555 | controller | "nlink": 1, 2025-10-13 11:01:34.293570 | controller | "path": "/home/zuul/ansible.log", 2025-10-13 11:01:34.293585 | controller | "pw_name": "zuul", 2025-10-13 11:01:34.293601 | controller | "rgrp": true, 2025-10-13 11:01:34.293616 | controller | "roth": true, 2025-10-13 11:01:34.293631 | controller | "rusr": true, 2025-10-13 11:01:34.293647 | controller | "size": 6749, 2025-10-13 11:01:34.293663 | controller | "uid": 1000, 2025-10-13 11:01:34.293679 | controller | "wgrp": false, 2025-10-13 11:01:34.293694 | controller | "woth": false, 2025-10-13 11:01:34.293712 | controller | "wusr": true, 2025-10-13 11:01:34.293729 | controller | "xgrp": false, 2025-10-13 11:01:34.293832 | controller | "xoth": false, 2025-10-13 11:01:34.293861 | controller | "xusr": false 2025-10-13 11:01:34.294184 | controller | } 2025-10-13 11:01:34.304356 | 2025-10-13 11:01:34.304460 | TASK [Copy crio stats log file] 2025-10-13 11:01:34.348861 | controller | skipping: Conditional result was False 2025-10-13 11:01:34.355095 | 2025-10-13 11:01:34.355178 | TASK [Get SELinux related data] 2025-10-13 11:01:34.648713 | controller | 2025-10-13 11:01:34.928390 | controller | ERROR 2025-10-13 11:01:34.928597 | controller | { 2025-10-13 11:01:34.928631 | controller | "delta": "0:00:00.011215", 2025-10-13 11:01:34.928654 | controller | "end": "2025-10-13 11:01:34.649693", 2025-10-13 11:01:34.928675 | controller | "msg": "non-zero return code", 2025-10-13 11:01:34.928695 | controller | "rc": 1, 2025-10-13 11:01:34.928714 | controller | "start": "2025-10-13 11:01:34.638478" 2025-10-13 11:01:34.928732 | controller | } 2025-10-13 11:01:34.928756 | controller | ERROR: Ignoring Errors 2025-10-13 11:01:34.936207 | 2025-10-13 11:01:34.936272 | TASK [Create system configuration directory] 2025-10-13 11:01:35.186110 | controller | changed 2025-10-13 11:01:35.200547 | 2025-10-13 11:01:35.200618 | TASK [Get some of the system configurations] 2025-10-13 11:01:35.761273 | controller | changed 2025-10-13 11:01:35.767094 | 2025-10-13 11:01:35.767182 | TASK [Copy generated documentation if available] 2025-10-13 11:01:35.811018 | controller | skipping: Conditional result was False 2025-10-13 11:01:35.818817 | 2025-10-13 11:01:35.818895 | TASK [Copy generated AsciiDoc documentation if available] 2025-10-13 11:01:35.864340 | controller | skipping: Conditional result was False 2025-10-13 11:01:35.870220 | 2025-10-13 11:01:35.870284 | TASK [Compress logs bigger than 2MB] 2025-10-13 11:01:36.429034 | controller | changed 2025-10-13 11:01:36.435044 | 2025-10-13 11:01:36.435117 | TASK [Copy files from workspace on node] 2025-10-13 11:01:36.490484 | controller | ok 2025-10-13 11:01:36.537191 | 2025-10-13 11:01:36.537296 | TASK [fetch-output : Set log path for multiple nodes] 2025-10-13 11:01:36.571732 | controller | skipping: Conditional result was False 2025-10-13 11:01:36.578705 | 2025-10-13 11:01:36.578775 | TASK [fetch-output : Set log path for single node] 2025-10-13 11:01:36.617678 | controller | ok 2025-10-13 11:01:36.623338 | 2025-10-13 11:01:36.623410 | LOOP [fetch-output : Ensure local output dirs] 2025-10-13 11:01:36.950276 | controller -> localhost | ok: "/var/lib/zuul/builds/3e5286f1c93c4509a1a4dc9b95cb8bac/work/logs" 2025-10-13 11:01:36.950529 | controller -> localhost | changed: All items complete 2025-10-13 11:01:36.950561 | 2025-10-13 11:01:37.186748 | controller -> localhost | changed: "/var/lib/zuul/builds/3e5286f1c93c4509a1a4dc9b95cb8bac/work/artifacts" 2025-10-13 11:01:37.578737 | controller -> localhost | changed: "/var/lib/zuul/builds/3e5286f1c93c4509a1a4dc9b95cb8bac/work/docs" 2025-10-13 11:01:37.594839 | 2025-10-13 11:01:37.594967 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-10-13 11:01:38.284798 | controller | changed: 2025-10-13 11:01:38.285037 | controller | .d..t...... ./ 2025-10-13 11:01:38.285073 | controller | >f+++++++++ README.html 2025-10-13 11:01:38.285099 | controller | >f+++++++++ ansible-execution.log 2025-10-13 11:01:38.285123 | controller | >f+++++++++ ansible.log 2025-10-13 11:01:38.285144 | controller | >f+++++++++ dmesg.log 2025-10-13 11:01:38.285164 | controller | >f+++++++++ installed-pkgs.log 2025-10-13 11:01:38.285186 | controller | >f+++++++++ python.log 2025-10-13 11:01:38.285209 | controller | >f+++++++++ registries.conf 2025-10-13 11:01:38.285228 | controller | >f+++++++++ report.html 2025-10-13 11:01:38.285248 | controller | >f+++++++++ selinux-denials.log 2025-10-13 11:01:38.285267 | controller | >f+++++++++ selinux-listing.log 2025-10-13 11:01:38.285285 | controller | cd+++++++++ ci-framework-data/ 2025-10-13 11:01:38.285303 | controller | cd+++++++++ ci-framework-data/artifacts/ 2025-10-13 11:01:38.285321 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml 2025-10-13 11:01:38.285338 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/ 2025-10-13 11:01:38.285355 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml 2025-10-13 11:01:38.285372 | controller | cd+++++++++ ci-framework-data/artifacts/repositories/ 2025-10-13 11:01:38.285388 | controller | >f+++++++++ ci-framework-data/artifacts/repositories/delorean.repo.md5 2025-10-13 11:01:38.285405 | controller | cd+++++++++ ci-framework-data/logs/ 2025-10-13 11:01:38.285422 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log 2025-10-13 11:01:38.285439 | controller | cd+++++++++ registries.conf.d/ 2025-10-13 11:01:38.285456 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf 2025-10-13 11:01:38.285473 | controller | cd+++++++++ system-config/ 2025-10-13 11:01:38.285490 | controller | cd+++++++++ system-config/libvirt/ 2025-10-13 11:01:38.285506 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf 2025-10-13 11:01:38.285522 | controller | >f+++++++++ system-config/libvirt/libvirt.conf 2025-10-13 11:01:38.711417 | controller | changed: .d..t...... ./ 2025-10-13 11:01:39.143706 | controller | changed: .d..t...... ./ 2025-10-13 11:01:39.162127 | 2025-10-13 11:01:39.162239 | TASK [Return artifact to Zuul] 2025-10-13 11:01:39.193407 | controller | ok 2025-10-13 11:01:39.216895 | 2025-10-13 11:01:39.216995 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host] 2025-10-13 11:01:39.217102 | 2025-10-13 11:01:39.217129 | PLAY RECAP 2025-10-13 11:01:39.217165 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1 2025-10-13 11:01:39.217186 | 2025-10-13 11:01:39.319375 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main] 2025-10-13 11:01:39.320129 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2025-10-13 11:01:40.029081 | 2025-10-13 11:01:40.029190 | PLAY [all] 2025-10-13 11:01:40.057111 | 2025-10-13 11:01:40.057222 | TASK [include_role : fetch-output] 2025-10-13 11:01:40.097537 | controller | ok 2025-10-13 11:01:40.121195 | 2025-10-13 11:01:40.121310 | TASK [fetch-output : Set log path for multiple nodes] 2025-10-13 11:01:40.199461 | controller | skipping: Conditional result was False 2025-10-13 11:01:40.206197 | 2025-10-13 11:01:40.206293 | TASK [fetch-output : Set log path for single node] 2025-10-13 11:01:40.275440 | controller | ok 2025-10-13 11:01:40.284533 | 2025-10-13 11:01:40.284603 | LOOP [fetch-output : Ensure local output dirs] 2025-10-13 11:01:40.841738 | controller -> localhost | ok: "/var/lib/zuul/builds/3e5286f1c93c4509a1a4dc9b95cb8bac/work/logs" 2025-10-13 11:01:41.140276 | controller -> localhost | ok: "/var/lib/zuul/builds/3e5286f1c93c4509a1a4dc9b95cb8bac/work/artifacts" 2025-10-13 11:01:41.439153 | controller -> localhost | ok: "/var/lib/zuul/builds/3e5286f1c93c4509a1a4dc9b95cb8bac/work/docs" 2025-10-13 11:01:41.452350 | 2025-10-13 11:01:41.452464 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-10-13 11:01:42.249459 | controller | ok 2025-10-13 11:01:42.249629 | controller | ok: All items complete 2025-10-13 11:01:42.249661 | 2025-10-13 11:01:42.651450 | controller | ok 2025-10-13 11:01:43.121399 | controller | ok 2025-10-13 11:01:43.146376 | 2025-10-13 11:01:43.146487 | TASK [include_role : fetch-output-openshift] 2025-10-13 11:01:43.195047 | controller | skipping: Conditional result was False 2025-10-13 11:01:43.201413 | 2025-10-13 11:01:43.201499 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-10-13 11:01:43.722873 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.007193 2025-10-13 11:01:43.923890 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.016584 2025-10-13 11:01:43.956902 | 2025-10-13 11:01:43.957001 | PLAY [all] 2025-10-13 11:01:43.971705 | 2025-10-13 11:01:43.971770 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-10-13 11:01:44.368859 | controller | changed 2025-10-13 11:01:44.414398 | 2025-10-13 11:01:44.414478 | PLAY RECAP 2025-10-13 11:01:44.414522 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2025-10-13 11:01:44.414545 | 2025-10-13 11:01:44.517496 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2025-10-13 11:01:44.518277 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master] 2025-10-13 11:01:45.163732 | 2025-10-13 11:01:45.163865 | PLAY [localhost] 2025-10-13 11:01:45.184445 | 2025-10-13 11:01:45.184521 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-10-13 11:01:45.935230 | localhost | changed 2025-10-13 11:01:45.940603 | 2025-10-13 11:01:45.940698 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-10-13 11:01:45.997463 | localhost | ok 2025-10-13 11:01:46.015950 | 2025-10-13 11:01:46.016057 | TASK [add-fileserver : Create SSH private key tempfile] 2025-10-13 11:01:46.548869 | localhost | changed 2025-10-13 11:01:46.553514 | 2025-10-13 11:01:46.553591 | TASK [add-fileserver : Create SSH private key from secret] 2025-10-13 11:01:47.636295 | localhost | changed 2025-10-13 11:01:47.642049 | 2025-10-13 11:01:47.642134 | TASK [add-fileserver : Add fileserver ssh key] 2025-10-13 11:01:48.467465 | localhost | Identity added: /var/lib/zuul/builds/3e5286f1c93c4509a1a4dc9b95cb8bac/work/tmp/ansible.orgsjho0 (/var/lib/zuul/builds/3e5286f1c93c4509a1a4dc9b95cb8bac/work/tmp/ansible.orgsjho0) 2025-10-13 11:01:48.467645 | localhost | ok: Runtime: 0:00:00.012696 2025-10-13 11:01:48.472022 | 2025-10-13 11:01:48.472089 | TASK [add-fileserver : Remove SSH private key from disk] 2025-10-13 11:01:49.112227 | localhost | ok: Runtime: 0:00:00.025456 2025-10-13 11:01:49.118557 | 2025-10-13 11:01:49.118631 | TASK [add-fileserver : Add fileserver to inventory] 2025-10-13 11:01:49.241031 | localhost | changed 2025-10-13 11:01:49.245797 | 2025-10-13 11:01:49.245883 | TASK [add-fileserver : Add fileserver server to known hosts] 2025-10-13 11:01:49.949499 | localhost | changed 2025-10-13 11:01:50.019968 | 2025-10-13 11:01:50.020058 | PLAY [localhost] 2025-10-13 11:01:50.047823 | 2025-10-13 11:01:50.052975 | TASK [Generate bulk log download script] 2025-10-13 11:01:50.093934 | localhost | ok 2025-10-13 11:01:50.107675 | 2025-10-13 11:01:50.107774 | TASK [local-log-download : Check API endpoint is defined] 2025-10-13 11:01:50.168460 | localhost | ok: All assertions passed 2025-10-13 11:01:50.219137 | 2025-10-13 11:01:50.219233 | TASK [local-log-download : Create download script] 2025-10-13 11:01:51.325374 | localhost -> localhost | changed 2025-10-13 11:01:51.334463 | 2025-10-13 11:01:51.334538 | TASK [Register quick-download link] 2025-10-13 11:01:51.352889 | localhost | ok 2025-10-13 11:01:51.397977 | 2025-10-13 11:01:51.398102 | PLAY [logserver.rdoproject.org] 2025-10-13 11:01:51.411983 | 2025-10-13 11:01:51.412056 | TASK [Set zuul-log-path fact] 2025-10-13 11:01:51.448556 | logserver.rdoproject.org | ok 2025-10-13 11:01:51.458125 | 2025-10-13 11:01:51.458215 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-13 11:01:51.524571 | logserver.rdoproject.org | ok 2025-10-13 11:01:51.530453 | 2025-10-13 11:01:51.530525 | TASK [upload-logs : Create log directories] 2025-10-13 11:01:53.269928 | logserver.rdoproject.org | changed 2025-10-13 11:01:53.273285 | 2025-10-13 11:01:53.273350 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-10-13 11:01:53.621230 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.004890 2025-10-13 11:01:53.637420 | 2025-10-13 11:01:53.637491 | TASK [upload-logs : Upload logs to log server] 2025-10-13 11:01:55.732506 | logserver.rdoproject.org | Output suppressed because no_log was given 2025-10-13 11:01:55.736289 | 2025-10-13 11:01:55.736357 | LOOP [upload-logs : Compress console log and json output] 2025-10-13 11:01:55.806140 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-13 11:01:55.814276 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-13 11:01:55.824504 | 2025-10-13 11:01:55.824629 | LOOP [upload-logs : Upload compressed console log and json output] 2025-10-13 11:01:55.914805 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-13 11:01:55.915079 | 2025-10-13 11:01:55.915592 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-13 11:01:55.925638 | 2025-10-13 11:01:55.925736 | LOOP [upload-logs : Upload console log and json output]