2025-10-08 11:52:00.566527 | Job console starting... 2025-10-08 11:52:00.804363 | Updating repositories 2025-10-08 11:52:01.583941 | Preparing job workspace 2025-10-08 11:52:08.979244 | Running Ansible setup... 2025-10-08 11:52:12.168229 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2025-10-08 11:52:12.915364 | 2025-10-08 11:52:12.915465 | PLAY [localhost] 2025-10-08 11:52:12.932902 | 2025-10-08 11:52:12.933031 | TASK [Gathering Facts] 2025-10-08 11:52:13.947477 | localhost | ok 2025-10-08 11:52:13.961114 | 2025-10-08 11:52:13.961207 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-10-08 11:52:14.362005 | localhost -> localhost | changed 2025-10-08 11:52:14.367702 | 2025-10-08 11:52:14.367790 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-10-08 11:52:15.217015 | localhost -> localhost | changed 2025-10-08 11:52:15.226320 | 2025-10-08 11:52:15.226403 | TASK [Setup log path fact] 2025-10-08 11:52:15.245674 | localhost | ok 2025-10-08 11:52:15.257066 | 2025-10-08 11:52:15.257149 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-08 11:52:15.274029 | localhost | ok 2025-10-08 11:52:15.281369 | 2025-10-08 11:52:15.281445 | TASK [emit-job-header : Print job information] 2025-10-08 11:52:15.308206 | # Job Information 2025-10-08 11:52:15.308350 | Ansible Version: 2.15.12 2025-10-08 11:52:15.308380 | Job: cifmw-molecule-ci_setup 2025-10-08 11:52:15.308402 | Pipeline: github-check 2025-10-08 11:52:15.308422 | Executor: ibm-bm4-ze.softwarefactory-project.io 2025-10-08 11:52:15.308442 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3373 2025-10-08 11:52:15.308461 | Log URL (when completed): https://logserver.rdoproject.org/806/rdoproject.org/80636582ccd5454282e52fc1edac7800/ 2025-10-08 11:52:15.308480 | Event ID: 3a070520-a421-11f0-9b5a-2d6f635919d0 2025-10-08 11:52:15.312108 | 2025-10-08 11:52:15.312177 | LOOP [emit-job-header : Print node information] 2025-10-08 11:52:15.412397 | localhost | ok: 2025-10-08 11:52:15.412550 | localhost | # Node Information 2025-10-08 11:52:15.412578 | localhost | Inventory Hostname: controller 2025-10-08 11:52:15.412603 | localhost | Hostname: np0005475927 2025-10-08 11:52:15.412622 | localhost | Username: zuul 2025-10-08 11:52:15.412641 | localhost | Distro: CentOS 9 2025-10-08 11:52:15.412657 | localhost | Provider: ibm-bm4-nodepool 2025-10-08 11:52:15.412673 | localhost | Region: regionOne 2025-10-08 11:52:15.412700 | localhost | Label: cloud-centos-9-stream-tripleo-ibm 2025-10-08 11:52:15.412719 | localhost | Product Name: OpenStack Compute 2025-10-08 11:52:15.412735 | localhost | Interface IP: 192.168.26.43 2025-10-08 11:52:15.453764 | 2025-10-08 11:52:15.453847 | PLAY [all] 2025-10-08 11:52:15.462998 | 2025-10-08 11:52:15.463070 | TASK [Gather network facts] 2025-10-08 11:52:15.806461 | controller | ok 2025-10-08 11:52:15.821815 | 2025-10-08 11:52:15.821896 | TASK [include_role : start-zuul-console] 2025-10-08 11:52:15.838981 | controller | ok 2025-10-08 11:52:15.852269 | 2025-10-08 11:52:15.852341 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-10-08 11:52:16.206719 | controller | ok 2025-10-08 11:52:16.216758 | 2025-10-08 11:52:16.216841 | TASK [include_role : add-build-sshkey] 2025-10-08 11:52:16.235995 | controller | ok 2025-10-08 11:52:16.257320 | 2025-10-08 11:52:16.257418 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-08 11:52:16.489192 | controller -> localhost | ok 2025-10-08 11:52:16.496397 | 2025-10-08 11:52:16.496481 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-08 11:52:16.514256 | controller | ok 2025-10-08 11:52:16.527275 | controller | included: /var/lib/zuul/builds/80636582ccd5454282e52fc1edac7800/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-08 11:52:16.532953 | 2025-10-08 11:52:16.533022 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-08 11:52:17.076795 | controller -> localhost | Generating public/private rsa key pair. 2025-10-08 11:52:17.077001 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/80636582ccd5454282e52fc1edac7800/work/80636582ccd5454282e52fc1edac7800_id_rsa. 2025-10-08 11:52:17.077037 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/80636582ccd5454282e52fc1edac7800/work/80636582ccd5454282e52fc1edac7800_id_rsa.pub. 2025-10-08 11:52:17.077063 | controller -> localhost | The key fingerprint is: 2025-10-08 11:52:17.077084 | controller -> localhost | SHA256:kNyM7dBYl8DliBnoBHRFTuh0UE7kRP05OPgpGZYzkYo zuul-build-sshkey 2025-10-08 11:52:17.077106 | controller -> localhost | The key's randomart image is: 2025-10-08 11:52:17.077126 | controller -> localhost | +---[RSA 3072]----+ 2025-10-08 11:52:17.077146 | controller -> localhost | | .o.oX@+ooo. | 2025-10-08 11:52:17.077165 | controller -> localhost | | .=O+&o+. | 2025-10-08 11:52:17.077184 | controller -> localhost | | * o&+*o.. | 2025-10-08 11:52:17.077203 | controller -> localhost | | E + B+o + | 2025-10-08 11:52:17.077222 | controller -> localhost | | . *So . | 2025-10-08 11:52:17.077241 | controller -> localhost | | o o | 2025-10-08 11:52:17.077258 | controller -> localhost | | . | 2025-10-08 11:52:17.077276 | controller -> localhost | | | 2025-10-08 11:52:17.077293 | controller -> localhost | | | 2025-10-08 11:52:17.077310 | controller -> localhost | +----[SHA256]-----+ 2025-10-08 11:52:17.077351 | controller -> localhost | ok: Runtime: 0:00:00.183717 2025-10-08 11:52:17.083571 | 2025-10-08 11:52:17.083643 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-08 11:52:17.107702 | controller | ok 2025-10-08 11:52:17.117436 | controller | included: /var/lib/zuul/builds/80636582ccd5454282e52fc1edac7800/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-08 11:52:17.125508 | 2025-10-08 11:52:17.125578 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-08 11:52:17.159764 | controller | skipping: Conditional result was False 2025-10-08 11:52:17.165681 | 2025-10-08 11:52:17.165780 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-08 11:52:17.661326 | controller | changed 2025-10-08 11:52:17.666108 | 2025-10-08 11:52:17.666173 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-08 11:52:17.861816 | controller | ok 2025-10-08 11:52:17.866666 | 2025-10-08 11:52:17.866746 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-08 11:52:18.548283 | controller | changed 2025-10-08 11:52:18.553759 | 2025-10-08 11:52:18.553855 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-08 11:52:19.130095 | controller | changed 2025-10-08 11:52:19.135193 | 2025-10-08 11:52:19.135264 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-08 11:52:19.161340 | controller | skipping: Conditional result was False 2025-10-08 11:52:19.167321 | 2025-10-08 11:52:19.167403 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-08 11:52:19.614432 | controller -> localhost | changed 2025-10-08 11:52:19.624864 | 2025-10-08 11:52:19.624973 | TASK [add-build-sshkey : Add back temp key] 2025-10-08 11:52:20.026949 | controller -> localhost | Identity added: /var/lib/zuul/builds/80636582ccd5454282e52fc1edac7800/work/80636582ccd5454282e52fc1edac7800_id_rsa (zuul-build-sshkey) 2025-10-08 11:52:20.027128 | controller -> localhost | ok: Runtime: 0:00:00.013719 2025-10-08 11:52:20.033229 | 2025-10-08 11:52:20.033299 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-08 11:52:20.357164 | controller | ok 2025-10-08 11:52:20.362403 | 2025-10-08 11:52:20.362474 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-08 11:52:20.412941 | controller | skipping: Conditional result was False 2025-10-08 11:52:20.423791 | 2025-10-08 11:52:20.423869 | TASK [include_role : validate-host] 2025-10-08 11:52:20.451039 | controller | ok 2025-10-08 11:52:20.485299 | 2025-10-08 11:52:20.485405 | TASK [validate-host : Define zuul_info_dir fact] 2025-10-08 11:52:20.514627 | controller | ok 2025-10-08 11:52:20.520524 | 2025-10-08 11:52:20.520607 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-10-08 11:52:20.811391 | controller -> localhost | ok 2025-10-08 11:52:20.822501 | 2025-10-08 11:52:20.822569 | TASK [validate-host : Collect information about the host] 2025-10-08 11:52:21.443982 | controller | ok 2025-10-08 11:52:21.535501 | 2025-10-08 11:52:21.535593 | TASK [validate-host : Sanitize hostname] 2025-10-08 11:52:21.634509 | controller | ok 2025-10-08 11:52:21.639343 | 2025-10-08 11:52:21.639411 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-10-08 11:52:22.475013 | controller -> localhost | changed 2025-10-08 11:52:22.481703 | 2025-10-08 11:52:22.481778 | TASK [validate-host : Collect information about zuul worker] 2025-10-08 11:52:22.947320 | controller | ok 2025-10-08 11:52:22.955543 | 2025-10-08 11:52:22.955617 | TASK [validate-host : Write out all zuul information for each host] 2025-10-08 11:52:23.962141 | controller -> localhost | changed 2025-10-08 11:52:23.981363 | 2025-10-08 11:52:23.981434 | TASK [include_role : prepare-workspace-openshift] 2025-10-08 11:52:24.015561 | controller | skipping: Conditional result was False 2025-10-08 11:52:24.020555 | 2025-10-08 11:52:24.020619 | TASK [include_role : remove-zuul-sshkey] 2025-10-08 11:52:24.044277 | controller | skipping: Conditional result was False 2025-10-08 11:52:24.050358 | 2025-10-08 11:52:24.050438 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2025-10-08 11:52:24.265591 | controller | ok: "logs" 2025-10-08 11:52:24.265827 | controller | ok: All items complete 2025-10-08 11:52:24.265859 | 2025-10-08 11:52:24.426643 | controller | ok: "artifacts" 2025-10-08 11:52:24.590048 | controller | ok: "docs" 2025-10-08 11:52:24.598590 | 2025-10-08 11:52:24.598739 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2025-10-08 11:52:24.838207 | controller | changed: "logs" 2025-10-08 11:52:25.008140 | controller | changed: "artifacts" 2025-10-08 11:52:25.185625 | controller | changed: "docs" 2025-10-08 11:52:25.227030 | 2025-10-08 11:52:25.227109 | PLAY RECAP 2025-10-08 11:52:25.227153 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0 2025-10-08 11:52:25.227182 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-08 11:52:25.227203 | 2025-10-08 11:52:25.439957 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2025-10-08 11:52:25.440721 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master] 2025-10-08 11:52:26.299602 | 2025-10-08 11:52:26.299729 | PLAY [all] 2025-10-08 11:52:26.320492 | 2025-10-08 11:52:26.320581 | TASK [mirror-info-fork : Set mirror_fqdn if not defined] 2025-10-08 11:52:26.390338 | controller | ok 2025-10-08 11:52:26.399287 | 2025-10-08 11:52:26.399393 | TASK [mirror-info-fork : Create /etc/ci] 2025-10-08 11:52:26.776353 | controller | changed 2025-10-08 11:52:26.787114 | 2025-10-08 11:52:26.787191 | TASK [mirror-info-fork : Install ci_mirror script] 2025-10-08 11:52:27.702694 | controller | changed 2025-10-08 11:52:27.713372 | 2025-10-08 11:52:27.713460 | LOOP [add-authorized-keys : Enable access via build key on all nodes] 2025-10-08 11:52:28.296495 | controller | changed: 2025-10-08 11:52:28.296653 | controller | { 2025-10-08 11:52:28.296708 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com" 2025-10-08 11:52:28.296742 | controller | } 2025-10-08 11:52:28.505375 | controller | changed: 2025-10-08 11:52:28.505463 | controller | { 2025-10-08 11:52:28.505493 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com" 2025-10-08 11:52:28.505517 | controller | } 2025-10-08 11:52:28.710879 | controller | changed: 2025-10-08 11:52:28.710982 | controller | { 2025-10-08 11:52:28.711014 | 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-08 11:52:28.711037 | controller | } 2025-10-08 11:52:28.928246 | controller | changed: 2025-10-08 11:52:28.928330 | controller | { 2025-10-08 11:52:28.928359 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com" 2025-10-08 11:52:28.928381 | controller | } 2025-10-08 11:52:29.142457 | controller | changed: 2025-10-08 11:52:29.142547 | controller | { 2025-10-08 11:52:29.142576 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com" 2025-10-08 11:52:29.142599 | controller | } 2025-10-08 11:52:29.343249 | controller | changed: 2025-10-08 11:52:29.343325 | controller | { 2025-10-08 11:52:29.343354 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com" 2025-10-08 11:52:29.343377 | controller | } 2025-10-08 11:52:29.549943 | controller | changed: 2025-10-08 11:52:29.550027 | controller | { 2025-10-08 11:52:29.550054 | 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-08 11:52:29.550078 | controller | } 2025-10-08 11:52:29.756556 | controller | changed: 2025-10-08 11:52:29.756644 | controller | { 2025-10-08 11:52:29.756675 | 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-08 11:52:29.756715 | controller | } 2025-10-08 11:52:29.965149 | controller | changed: 2025-10-08 11:52:29.965256 | controller | { 2025-10-08 11:52:29.965287 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner" 2025-10-08 11:52:29.965315 | controller | } 2025-10-08 11:52:30.168613 | controller | changed: 2025-10-08 11:52:30.168763 | controller | { 2025-10-08 11:52:30.168796 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com" 2025-10-08 11:52:30.168818 | controller | } 2025-10-08 11:52:30.369086 | controller | changed: 2025-10-08 11:52:30.369176 | controller | { 2025-10-08 11:52:30.369205 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com" 2025-10-08 11:52:30.369226 | controller | } 2025-10-08 11:52:30.571799 | controller | changed: 2025-10-08 11:52:30.571885 | controller | { 2025-10-08 11:52:30.571934 | controller | "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com" 2025-10-08 11:52:30.571960 | controller | } 2025-10-08 11:52:30.789885 | controller | changed: 2025-10-08 11:52:30.789996 | controller | { 2025-10-08 11:52:30.790027 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith" 2025-10-08 11:52:30.790051 | controller | } 2025-10-08 11:52:30.990267 | controller | changed: 2025-10-08 11:52:30.990380 | controller | { 2025-10-08 11:52:30.990409 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel" 2025-10-08 11:52:30.990431 | controller | } 2025-10-08 11:52:31.190601 | controller | changed: 2025-10-08 11:52:31.190711 | controller | { 2025-10-08 11:52:31.190741 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux" 2025-10-08 11:52:31.190763 | controller | } 2025-10-08 11:52:31.396739 | controller | changed: 2025-10-08 11:52:31.396832 | controller | { 2025-10-08 11:52:31.396861 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr" 2025-10-08 11:52:31.396882 | controller | } 2025-10-08 11:52:31.607393 | controller | changed: 2025-10-08 11:52:31.607486 | controller | { 2025-10-08 11:52:31.607516 | 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-08 11:52:31.607539 | controller | } 2025-10-08 11:52:31.838209 | controller | changed: 2025-10-08 11:52:31.838293 | controller | { 2025-10-08 11:52:31.838323 | 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-08 11:52:31.838353 | controller | } 2025-10-08 11:52:32.042372 | controller | changed: 2025-10-08 11:52:32.042462 | controller | { 2025-10-08 11:52:32.042490 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9" 2025-10-08 11:52:32.042511 | controller | } 2025-10-08 11:52:32.260997 | controller | changed: 2025-10-08 11:52:32.261161 | controller | { 2025-10-08 11:52:32.261197 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora" 2025-10-08 11:52:32.261219 | controller | } 2025-10-08 11:52:32.476588 | controller | changed: 2025-10-08 11:52:32.476673 | controller | { 2025-10-08 11:52:32.476713 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac" 2025-10-08 11:52:32.476735 | controller | } 2025-10-08 11:52:32.699667 | controller | changed: 2025-10-08 11:52:32.699750 | controller | { 2025-10-08 11:52:32.699778 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop" 2025-10-08 11:52:32.699807 | controller | } 2025-10-08 11:52:32.915723 | controller | changed: 2025-10-08 11:52:32.915822 | controller | { 2025-10-08 11:52:32.915851 | 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-08 11:52:32.915873 | controller | } 2025-10-08 11:52:33.124330 | controller | changed: 2025-10-08 11:52:33.124419 | controller | { 2025-10-08 11:52:33.124447 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com" 2025-10-08 11:52:33.124469 | controller | } 2025-10-08 11:52:33.351042 | controller | changed: 2025-10-08 11:52:33.351117 | controller | { 2025-10-08 11:52:33.351145 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com" 2025-10-08 11:52:33.351167 | controller | } 2025-10-08 11:52:33.563462 | controller | changed: 2025-10-08 11:52:33.563564 | controller | { 2025-10-08 11:52:33.563626 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono" 2025-10-08 11:52:33.563672 | controller | } 2025-10-08 11:52:33.603817 | 2025-10-08 11:52:33.603923 | TASK [Set timezone to UTC] 2025-10-08 11:52:34.285056 | controller | changed: executed `/bin/timedatectl set-timezone UTC` 2025-10-08 11:52:34.290215 | 2025-10-08 11:52:34.290311 | TASK [Create nodepool directory] 2025-10-08 11:52:34.542145 | controller | changed 2025-10-08 11:52:34.547825 | 2025-10-08 11:52:34.547897 | TASK [Create nodepool sub_nodes file] 2025-10-08 11:52:35.068740 | controller | changed 2025-10-08 11:52:35.074007 | 2025-10-08 11:52:35.074088 | TASK [Create nodepool sub_nodes_private file] 2025-10-08 11:52:35.591076 | controller | changed 2025-10-08 11:52:35.596051 | 2025-10-08 11:52:35.596119 | LOOP [Populate nodepool sub_nodes file] 2025-10-08 11:52:35.625708 | 2025-10-08 11:52:35.625856 | LOOP [Populate nodepool sub_nodes_private file] 2025-10-08 11:52:35.654363 | 2025-10-08 11:52:35.654492 | TASK [Create nodepool primary file] 2025-10-08 11:52:35.678110 | controller | skipping: Conditional result was False 2025-10-08 11:52:35.683428 | 2025-10-08 11:52:35.683505 | TASK [Create nodepool node_private for this node] 2025-10-08 11:52:36.229880 | controller | changed 2025-10-08 11:52:36.235477 | 2025-10-08 11:52:36.235550 | LOOP [Copy ssh keys to nodepool directory] 2025-10-08 11:52:36.612605 | controller | ok: Item: id_rsa Runtime: 0:00:00.004971 2025-10-08 11:52:36.612796 | 2025-10-08 11:52:36.782125 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.004654 2025-10-08 11:52:36.791545 | 2025-10-08 11:52:36.791640 | TASK [Add sudoers role for zuul-sudo-grep.sh] 2025-10-08 11:52:37.388057 | controller | changed 2025-10-08 11:52:37.394263 | 2025-10-08 11:52:37.394369 | TASK [Validate sudoers config after edits] 2025-10-08 11:52:37.638683 | controller | /etc/sudoers: parsed OK 2025-10-08 11:52:37.638750 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK 2025-10-08 11:52:37.638761 | controller | /etc/sudoers.d/zuul: parsed OK 2025-10-08 11:52:37.638767 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK 2025-10-08 11:52:37.933509 | controller | ok: Runtime: 0:00:00.004911 2025-10-08 11:52:37.939628 | 2025-10-08 11:52:37.939700 | TASK [Show the environment passed in to job shell scripts] 2025-10-08 11:52:38.198846 | controller | SHELL=/bin/bash 2025-10-08 11:52:38.198905 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework 2025-10-08 11:52:38.198917 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework 2025-10-08 11:52:38.198924 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/73/3373/1a7b2ae378ead444d1ed756aace4ec009d749f1a 2025-10-08 11:52:38.198930 | controller | PWD=/home/zuul 2025-10-08 11:52:38.198935 | controller | ZUUL_PIPELINE=github-check 2025-10-08 11:52:38.198941 | controller | LOGNAME=zuul 2025-10-08 11:52:38.198946 | controller | XDG_SESSION_TYPE=tty 2025-10-08 11:52:38.198951 | controller | _=/usr/bin/env 2025-10-08 11:52:38.198957 | controller | MOTD_SHOWN=pam 2025-10-08 11:52:38.199006 | controller | HOME=/home/zuul 2025-10-08 11:52:38.199015 | controller | LANG=en_US.UTF-8 2025-10-08 11:52:38.199021 | controller | SSH_CONNECTION=192.168.26.12 50076 192.168.26.43 22 2025-10-08 11:52:38.199026 | 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-08 11:52:38.199034 | controller | ZUUL_CHANGE_IDS=3373,1a7b2ae378ead444d1ed756aace4ec009d749f1a 2025-10-08 11:52:38.199040 | controller | WORKSPACE=/home/zuul/workspace 2025-10-08 11:52:38.199045 | controller | XDG_SESSION_CLASS=user 2025-10-08 11:52:38.199051 | controller | SELINUX_ROLE_REQUESTED= 2025-10-08 11:52:38.199056 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s 2025-10-08 11:52:38.199061 | controller | USER=zuul 2025-10-08 11:52:38.199066 | controller | ZUUL_VOTING=True 2025-10-08 11:52:38.199072 | controller | BUILD_TIMEOUT=1800000 2025-10-08 11:52:38.199077 | controller | SELINUX_USE_CURRENT_RANGE= 2025-10-08 11:52:38.199082 | controller | SHLVL=1 2025-10-08 11:52:38.199087 | controller | ZUUL_PATCHSET=1a7b2ae378ead444d1ed756aace4ec009d749f1a 2025-10-08 11:52:38.199093 | controller | XDG_SESSION_ID=1 2025-10-08 11:52:38.199098 | controller | ZUUL_BRANCH=main 2025-10-08 11:52:38.199103 | controller | XDG_RUNTIME_DIR=/run/user/1000 2025-10-08 11:52:38.199108 | controller | SSH_CLIENT=192.168.26.12 50076 22 2025-10-08 11:52:38.199114 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/ 2025-10-08 11:52:38.199119 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima: 2025-10-08 11:52:38.199124 | controller | which_declare=declare -f 2025-10-08 11:52:38.199129 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin 2025-10-08 11:52:38.199135 | controller | SELINUX_LEVEL_REQUESTED= 2025-10-08 11:52:38.199140 | controller | ZUUL_CHANGE=3373 2025-10-08 11:52:38.199145 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus 2025-10-08 11:52:38.199150 | controller | ZUUL_UUID=80636582ccd5454282e52fc1edac7800 2025-10-08 11:52:38.199157 | controller | BASH_FUNC_which%%=() { ( alias; 2025-10-08 11:52:38.199163 | controller | eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@ 2025-10-08 11:52:38.199168 | controller | } 2025-10-08 11:52:38.502270 | controller | ok: Runtime: 0:00:00.005646 2025-10-08 11:52:38.507924 | 2025-10-08 11:52:38.507996 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars] 2025-10-08 11:52:38.531485 | controller | skipping: Conditional result was False 2025-10-08 11:52:38.537120 | 2025-10-08 11:52:38.537188 | TASK [Symlink /home/zuul-worker/workspace] 2025-10-08 11:52:39.097952 | controller | skipping: Conditional result was False 2025-10-08 11:52:39.103647 | 2025-10-08 11:52:39.103727 | TASK [Ensure legacy workspace directory] 2025-10-08 11:52:39.284351 | controller | changed 2025-10-08 11:52:39.303817 | 2025-10-08 11:52:39.303876 | PLAY RECAP 2025-10-08 11:52:39.303943 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0 2025-10-08 11:52:39.303973 | 2025-10-08 11:52:39.390811 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master] 2025-10-08 11:52:39.391579 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main] 2025-10-08 11:52:39.919956 | 2025-10-08 11:52:39.920077 | PLAY [Run ci/playbooks/dump_zuul_data.yml] 2025-10-08 11:52:39.947650 | 2025-10-08 11:52:39.947750 | TASK [Create zuul-output directory] 2025-10-08 11:52:40.304860 | controller | changed 2025-10-08 11:52:40.328065 | 2025-10-08 11:52:40.328200 | TASK [Slurp Zuul inventory test] 2025-10-08 11:52:40.888765 | controller -> localhost | ok 2025-10-08 11:52:40.894830 | 2025-10-08 11:52:40.894926 | TASK [Save zuul inventory] 2025-10-08 11:52:41.856188 | controller | changed 2025-10-08 11:52:41.861806 | 2025-10-08 11:52:41.861881 | TASK [Save zuul vars without the change_message] 2025-10-08 11:52:42.555877 | controller | changed 2025-10-08 11:52:42.584311 | 2025-10-08 11:52:42.584376 | PLAY RECAP 2025-10-08 11:52:42.584422 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-08 11:52:42.584446 | 2025-10-08 11:52:42.683516 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main] 2025-10-08 11:52:42.684285 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main] 2025-10-08 11:52:43.420586 | 2025-10-08 11:52:43.420705 | PLAY [Run ci/playbooks/molecule-prepare.yml] 2025-10-08 11:52:43.441491 | 2025-10-08 11:52:43.441579 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh] 2025-10-08 11:52:43.459974 | controller | ok 2025-10-08 11:52:43.478033 | 2025-10-08 11:52:43.478120 | TASK [mirror-info-fork : Set mirror_fqdn if not defined] 2025-10-08 11:52:43.521968 | controller | skipping: Conditional result was False 2025-10-08 11:52:43.528051 | 2025-10-08 11:52:43.528123 | TASK [mirror-info-fork : Create /etc/ci] 2025-10-08 11:52:43.886328 | controller | ok 2025-10-08 11:52:43.892658 | 2025-10-08 11:52:43.892746 | TASK [mirror-info-fork : Install ci_mirror script] 2025-10-08 11:52:44.524819 | controller | ok 2025-10-08 11:52:44.536503 | 2025-10-08 11:52:44.536590 | TASK [Prepare workspace] 2025-10-08 11:52:44.556880 | controller | ok 2025-10-08 11:52:44.574121 | 2025-10-08 11:52:44.574203 | TASK [prepare-workspace : Start zuul_console daemon.] 2025-10-08 11:52:44.912175 | controller | ok 2025-10-08 11:52:44.919039 | 2025-10-08 11:52:44.919115 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2025-10-08 11:52:47.105310 | controller | Output suppressed because no_log was given 2025-10-08 11:52:47.120578 | 2025-10-08 11:52:47.120649 | LOOP [Create zuul-output directory] 2025-10-08 11:52:47.328887 | controller | changed: "/home/zuul/ci-framework-data/logs" 2025-10-08 11:52:47.498849 | controller | ok: "/home/zuul/zuul-output/logs" 2025-10-08 11:52:47.513370 | 2025-10-08 11:52:47.513433 | TASK [Install required packages] 2025-10-08 11:53:49.374949 | controller | changed 2025-10-08 11:53:49.380742 | 2025-10-08 11:53:49.380814 | TASK [Install venv] 2025-10-08 11:54:50.196510 | controller | changed 2025-10-08 11:54:50.225322 | 2025-10-08 11:54:50.225422 | PLAY RECAP 2025-10-08 11:54:50.225468 | controller | ok: 7 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2025-10-08 11:54:50.225493 | 2025-10-08 11:54:50.318668 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main] 2025-10-08 11:54:50.319581 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main] 2025-10-08 11:54:50.862969 | 2025-10-08 11:54:50.863080 | PLAY [Run ci/playbooks/molecule-test.yml] 2025-10-08 11:54:50.884572 | 2025-10-08 11:54:50.884689 | TASK [Gather required facts] 2025-10-08 11:54:51.385025 | controller | ok 2025-10-08 11:54:51.390533 | 2025-10-08 11:54:51.390632 | TASK [Load environment var if instructed to] 2025-10-08 11:54:51.414387 | controller | skipping: Conditional result was False 2025-10-08 11:54:51.420363 | 2025-10-08 11:54:51.420436 | TASK [Run molecule] 2025-10-08 11:54:52.391001 | controller | INFO Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml 2025-10-08 11:54:52.462936 | controller | INFO Performing prerun with role_name_check=0... 2025-10-08 11:55:14.501689 | controller | WARNING Another version of '@NAMESPACE@.@NAME@' 3.1.4 was found installed in /home/zuul/.cache/ansible-compat/092978/collections/ansible_collections, only the first one will be used, 3.1.4 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-08 11:55:14.502139 | controller | WARNING Another version of 'ansible.netcommon' 7.1.0 was found installed in /home/zuul/.cache/ansible-compat/092978/collections/ansible_collections, only the first one will be used, 7.1.0 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-08 11:55:14.502637 | controller | WARNING Another version of 'ansible.posix' 1.6.2 was found installed in /home/zuul/.cache/ansible-compat/092978/collections/ansible_collections, only the first one will be used, 1.6.2 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-08 11:55:14.503096 | controller | WARNING Another version of 'ansible.utils' 5.1.2 was found installed in /home/zuul/.cache/ansible-compat/092978/collections/ansible_collections, only the first one will be used, 5.1.2 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-08 11:55:14.503576 | controller | WARNING Another version of 'cifmw.general' 1.0.0+1a7b2ae3 was found installed in /home/zuul/.cache/ansible-compat/092978/collections/ansible_collections, only the first one will be used, 1.0.0+1a7b2ae3 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-08 11:55:14.504027 | controller | WARNING Another version of 'cisco.ios' 9.0.3 was found installed in /home/zuul/.cache/ansible-compat/092978/collections/ansible_collections, only the first one will be used, 9.0.3 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-08 11:55:14.504485 | controller | WARNING Another version of 'community.crypto' 2.22.3 was found installed in /home/zuul/.cache/ansible-compat/092978/collections/ansible_collections, only the first one will be used, 2.22.3 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-08 11:55:14.504937 | controller | WARNING Another version of 'community.general' 10.0.1 was found installed in /home/zuul/.cache/ansible-compat/092978/collections/ansible_collections, only the first one will be used, 10.0.1 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-08 11:55:14.505373 | controller | WARNING Another version of 'community.libvirt' 1.3.0 was found installed in /home/zuul/.cache/ansible-compat/092978/collections/ansible_collections, only the first one will be used, 1.3.0 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-08 11:55:14.505812 | controller | WARNING Another version of 'community.okd' 4.0.0 was found installed in /home/zuul/.cache/ansible-compat/092978/collections/ansible_collections, only the first one will be used, 4.0.0 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-08 11:55:14.506277 | controller | WARNING Another version of 'containers.podman' 1.16.2 was found installed in /home/zuul/.cache/ansible-compat/092978/collections/ansible_collections, only the first one will be used, 1.16.2 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-08 11:55:14.506739 | controller | WARNING Another version of 'junipernetworks.junos' 9.1.0 was found installed in /home/zuul/.cache/ansible-compat/092978/collections/ansible_collections, only the first one will be used, 9.1.0 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-08 11:55:14.507187 | controller | WARNING Another version of 'kubernetes.core' 5.0.0 was found installed in /home/zuul/.cache/ansible-compat/092978/collections/ansible_collections, only the first one will be used, 5.0.0 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-08 11:55:14.507643 | controller | WARNING Another version of 'mellanox.onyx' 1.0.0 was found installed in /home/zuul/.cache/ansible-compat/092978/collections/ansible_collections, only the first one will be used, 1.0.0 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-08 11:55:14.508088 | controller | WARNING Another version of 'openstack.config_template' 2.1.1 was found installed in /home/zuul/.cache/ansible-compat/092978/collections/ansible_collections, only the first one will be used, 2.1.1 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-08 11:55:14.517671 | controller | INFO Running default > prepare 2025-10-08 11:55:14.518140 | controller | WARNING Skipping, prepare playbook not configured. 2025-10-08 11:55:14.526219 | controller | INFO Running default > converge 2025-10-08 11:55:15.246886 | controller | 2025-10-08 11:55:15.246958 | controller | PLAY [Converge] **************************************************************** 2025-10-08 11:55:15.247057 | controller | 2025-10-08 11:55:15.247165 | controller | TASK [Gathering Facts] ********************************************************* 2025-10-08 11:55:15.247279 | controller | Wednesday 08 October 2025 11:55:15 +0000 (0:00:00.016) 0:00:00.016 ***** 2025-10-08 11:55:16.089578 | controller | ok: [instance] 2025-10-08 11:55:16.089628 | controller | 2025-10-08 11:55:16.089710 | controller | TASK [ci_setup : Gather variables for each operating system] ******************* 2025-10-08 11:55:16.089817 | controller | Wednesday 08 October 2025 11:55:16 +0000 (0:00:00.843) 0:00:00.860 ***** 2025-10-08 11:55:16.133898 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_setup/vars/redhat.yml) 2025-10-08 11:55:16.133962 | controller | 2025-10-08 11:55:16.134106 | controller | TASK [ci_setup : List packages to install] ************************************* 2025-10-08 11:55:16.134231 | controller | Wednesday 08 October 2025 11:55:16 +0000 (0:00:00.044) 0:00:00.904 ***** 2025-10-08 11:55:16.154162 | controller | ok: [instance] => 2025-10-08 11:55:16.154273 | controller | cifmw_ci_setup_packages: 2025-10-08 11:55:16.154408 | controller | - bash-completion 2025-10-08 11:55:16.154539 | controller | - ca-certificates 2025-10-08 11:55:16.154648 | controller | - git-core 2025-10-08 11:55:16.154750 | controller | - make 2025-10-08 11:55:16.154845 | controller | - tar 2025-10-08 11:55:16.154965 | controller | - tmux 2025-10-08 11:55:16.155058 | controller | - python3-pip 2025-10-08 11:55:16.155149 | controller | 2025-10-08 11:55:16.155246 | controller | TASK [ci_setup : Install needed packages] ************************************** 2025-10-08 11:55:16.155345 | controller | Wednesday 08 October 2025 11:55:16 +0000 (0:00:00.020) 0:00:00.924 ***** 2025-10-08 11:55:19.741343 | controller | changed: [instance] 2025-10-08 11:55:19.741413 | controller | 2025-10-08 11:55:19.741521 | controller | TASK [ci_setup : Gather version of openshift client] *************************** 2025-10-08 11:55:19.741622 | controller | Wednesday 08 October 2025 11:55:19 +0000 (0:00:03.587) 0:00:04.511 ***** 2025-10-08 11:55:20.038581 | controller | ok: [instance] 2025-10-08 11:55:20.038648 | controller | 2025-10-08 11:55:20.038753 | controller | TASK [ci_setup : Ensure openshift client install path is present] ************** 2025-10-08 11:55:20.038854 | controller | Wednesday 08 October 2025 11:55:20 +0000 (0:00:00.297) 0:00:04.809 ***** 2025-10-08 11:55:20.355752 | controller | changed: [instance] 2025-10-08 11:55:20.355836 | controller | 2025-10-08 11:55:20.355984 | controller | TASK [ci_setup : Install openshift client] ************************************* 2025-10-08 11:55:20.356099 | controller | Wednesday 08 October 2025 11:55:20 +0000 (0:00:00.317) 0:00:05.126 ***** 2025-10-08 11:55:26.739442 | controller | changed: [instance] 2025-10-08 11:55:26.739531 | controller | 2025-10-08 11:55:26.739659 | controller | TASK [ci_setup : Add the OC path to cifmw_path if needed] ********************** 2025-10-08 11:55:26.739761 | controller | Wednesday 08 October 2025 11:55:26 +0000 (0:00:06.383) 0:00:11.509 ***** 2025-10-08 11:55:26.773322 | controller | skipping: [instance] 2025-10-08 11:55:26.773820 | controller | 2025-10-08 11:55:26.773961 | controller | TASK [ci_setup : Create completion file] *************************************** 2025-10-08 11:55:26.774072 | controller | Wednesday 08 October 2025 11:55:26 +0000 (0:00:00.034) 0:00:11.543 ***** 2025-10-08 11:55:27.041544 | controller | changed: [instance] 2025-10-08 11:55:27.041632 | controller | 2025-10-08 11:55:27.041734 | controller | TASK [ci_setup : Source completion from within .bashrc] ************************ 2025-10-08 11:55:27.041839 | controller | Wednesday 08 October 2025 11:55:27 +0000 (0:00:00.268) 0:00:11.812 ***** 2025-10-08 11:55:27.325979 | controller | changed: [instance] 2025-10-08 11:55:27.326049 | controller | 2025-10-08 11:55:27.326159 | controller | TASK [ci_setup : Check rhsm status] ******************************************** 2025-10-08 11:55:27.326269 | controller | Wednesday 08 October 2025 11:55:27 +0000 (0:00:00.284) 0:00:12.096 ***** 2025-10-08 11:55:27.344728 | controller | skipping: [instance] 2025-10-08 11:55:27.344817 | controller | 2025-10-08 11:55:27.344942 | controller | TASK [ci_setup : Gather the repos to be enabled] ******************************* 2025-10-08 11:55:27.345044 | controller | Wednesday 08 October 2025 11:55:27 +0000 (0:00:00.018) 0:00:12.115 ***** 2025-10-08 11:55:27.363228 | controller | skipping: [instance] 2025-10-08 11:55:27.363306 | controller | 2025-10-08 11:55:27.363432 | controller | TASK [ci_setup : Enabling the required repositories.] ************************** 2025-10-08 11:55:27.363533 | controller | Wednesday 08 October 2025 11:55:27 +0000 (0:00:00.018) 0:00:12.133 ***** 2025-10-08 11:55:27.381612 | controller | skipping: [instance] 2025-10-08 11:55:27.381689 | controller | 2025-10-08 11:55:27.381791 | controller | TASK [ci_setup : Get current /etc/redhat-release] ****************************** 2025-10-08 11:55:27.381909 | controller | Wednesday 08 October 2025 11:55:27 +0000 (0:00:00.018) 0:00:12.152 ***** 2025-10-08 11:55:27.399110 | controller | skipping: [instance] 2025-10-08 11:55:27.399214 | controller | 2025-10-08 11:55:27.399356 | controller | TASK [ci_setup : Print current /etc/redhat-release] **************************** 2025-10-08 11:55:27.399484 | controller | Wednesday 08 October 2025 11:55:27 +0000 (0:00:00.017) 0:00:12.169 ***** 2025-10-08 11:55:27.416437 | controller | skipping: [instance] 2025-10-08 11:55:27.416522 | controller | 2025-10-08 11:55:27.416625 | controller | TASK [ci_setup : Ensure the repos are enabled in the system using yum] ********* 2025-10-08 11:55:27.416724 | controller | Wednesday 08 October 2025 11:55:27 +0000 (0:00:00.017) 0:00:12.187 ***** 2025-10-08 11:55:27.739760 | controller | changed: [instance] => (item={'name': 'crb', 'baseurl': 'https://mirror.stream.centos.org/9-stream/CRB/x86_64/os/', 'description': 'Code ready builder', 'gpgcheck': True}) 2025-10-08 11:55:27.739828 | controller | 2025-10-08 11:55:27.739958 | controller | TASK [ci_setup : Manage directories] ******************************************* 2025-10-08 11:55:27.740113 | controller | Wednesday 08 October 2025 11:55:27 +0000 (0:00:00.323) 0:00:12.510 ***** 2025-10-08 11:55:28.722641 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/artifacts/manifests/openstack/cr) 2025-10-08 11:55:28.910687 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/logs) 2025-10-08 11:55:28.910718 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/tmp) 2025-10-08 11:55:28.910726 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/volumes) 2025-10-08 11:55:28.910733 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/parameters) 2025-10-08 11:55:28.910738 | controller | 2025-10-08 11:55:28.910744 | controller | TASK [Stat directory] ********************************************************** 2025-10-08 11:55:28.910749 | controller | Wednesday 08 October 2025 11:55:28 +0000 (0:00:00.981) 0:00:13.492 ***** 2025-10-08 11:55:28.910758 | controller | ok: [instance] 2025-10-08 11:55:28.932851 | controller | 2025-10-08 11:55:28.932890 | controller | TASK [Debug] ******************************************************************* 2025-10-08 11:55:28.932899 | controller | Wednesday 08 October 2025 11:55:28 +0000 (0:00:00.188) 0:00:13.680 ***** 2025-10-08 11:55:28.932909 | controller | ok: [instance] => 2025-10-08 11:55:28.957988 | controller | msg: /home/zuul/ci-framework-data/volumes 2025-10-08 11:55:28.958022 | controller | 2025-10-08 11:55:28.958030 | controller | TASK [Validate directory state] ************************************************ 2025-10-08 11:55:28.958036 | controller | Wednesday 08 October 2025 11:55:28 +0000 (0:00:00.021) 0:00:13.702 ***** 2025-10-08 11:55:28.958046 | controller | ok: [instance] => changed=false 2025-10-08 11:55:29.144516 | controller | msg: All assertions passed 2025-10-08 11:55:29.144548 | controller | 2025-10-08 11:55:29.144556 | controller | TASK [Gather repo file stats] ************************************************** 2025-10-08 11:55:29.144562 | controller | Wednesday 08 October 2025 11:55:28 +0000 (0:00:00.025) 0:00:13.728 ***** 2025-10-08 11:55:29.144572 | controller | ok: [instance] 2025-10-08 11:55:29.165944 | controller | 2025-10-08 11:55:29.165974 | controller | TASK [Print the stat information] ********************************************** 2025-10-08 11:55:29.165988 | controller | Wednesday 08 October 2025 11:55:29 +0000 (0:00:00.186) 0:00:13.914 ***** 2025-10-08 11:55:29.166010 | controller | ok: [instance] => 2025-10-08 11:55:29.167324 | controller | crb_stat: 2025-10-08 11:55:29.167356 | controller | changed: false 2025-10-08 11:55:29.167363 | controller | failed: false 2025-10-08 11:55:29.167369 | controller | stat: 2025-10-08 11:55:29.167380 | controller | atime: 1759924527.701991 2025-10-08 11:55:29.167403 | controller | attr_flags: '' 2025-10-08 11:55:29.167410 | controller | attributes: [] 2025-10-08 11:55:29.167415 | controller | block_size: 4096 2025-10-08 11:55:29.167420 | controller | blocks: 8 2025-10-08 11:55:29.167426 | controller | charset: unknown 2025-10-08 11:55:29.167431 | controller | ctime: 1759924527.701991 2025-10-08 11:55:29.167436 | controller | dev: 64513 2025-10-08 11:55:29.167441 | controller | device_type: 0 2025-10-08 11:55:29.167449 | controller | executable: false 2025-10-08 11:55:29.167455 | controller | exists: true 2025-10-08 11:55:29.167460 | controller | gid: 0 2025-10-08 11:55:29.167465 | controller | gr_name: root 2025-10-08 11:55:29.167473 | controller | inode: 4453285 2025-10-08 11:55:29.167543 | controller | isblk: false 2025-10-08 11:55:29.167554 | controller | ischr: false 2025-10-08 11:55:29.167654 | controller | isdir: false 2025-10-08 11:55:29.167763 | controller | isfifo: false 2025-10-08 11:55:29.167885 | controller | isgid: false 2025-10-08 11:55:29.168005 | controller | islnk: false 2025-10-08 11:55:29.168123 | controller | isreg: true 2025-10-08 11:55:29.168222 | controller | issock: false 2025-10-08 11:55:29.168358 | controller | isuid: false 2025-10-08 11:55:29.168509 | controller | mimetype: unknown 2025-10-08 11:55:29.168642 | controller | mode: '0600' 2025-10-08 11:55:29.168772 | controller | mtime: 1759924527.701991 2025-10-08 11:55:29.168923 | controller | nlink: 1 2025-10-08 11:55:29.169066 | controller | path: /etc/yum.repos.d/crb.repo 2025-10-08 11:55:29.169202 | controller | pw_name: root 2025-10-08 11:55:29.169349 | controller | readable: false 2025-10-08 11:55:29.169477 | controller | rgrp: false 2025-10-08 11:55:29.169604 | controller | roth: false 2025-10-08 11:55:29.169728 | controller | rusr: true 2025-10-08 11:55:29.169853 | controller | size: 125 2025-10-08 11:55:29.170014 | controller | uid: 0 2025-10-08 11:55:29.170146 | controller | version: null 2025-10-08 11:55:29.170271 | controller | wgrp: false 2025-10-08 11:55:29.170423 | controller | woth: false 2025-10-08 11:55:29.170548 | controller | writeable: false 2025-10-08 11:55:29.170671 | controller | wusr: true 2025-10-08 11:55:29.170797 | controller | xgrp: false 2025-10-08 11:55:29.170944 | controller | xoth: false 2025-10-08 11:55:29.171075 | controller | xusr: false 2025-10-08 11:55:29.171196 | controller | 2025-10-08 11:55:29.171344 | controller | TASK [Verify the file exists] ************************************************** 2025-10-08 11:55:29.171480 | controller | Wednesday 08 October 2025 11:55:29 +0000 (0:00:00.021) 0:00:13.936 ***** 2025-10-08 11:55:29.185152 | controller | ok: [instance] => changed=false 2025-10-08 11:55:29.185334 | controller | msg: All assertions passed 2025-10-08 11:55:29.185466 | controller | 2025-10-08 11:55:29.185601 | controller | TASK [Get oc stat informaiton] ************************************************* 2025-10-08 11:55:29.185734 | controller | Wednesday 08 October 2025 11:55:29 +0000 (0:00:00.019) 0:00:13.956 ***** 2025-10-08 11:55:29.508281 | controller | ok: [instance] 2025-10-08 11:55:29.543458 | controller | 2025-10-08 11:55:29.543486 | controller | TASK [Verify the oc stat information] ****************************************** 2025-10-08 11:55:29.543494 | controller | Wednesday 08 October 2025 11:55:29 +0000 (0:00:00.322) 0:00:14.278 ***** 2025-10-08 11:55:29.543503 | controller | ok: [instance] => changed=false 2025-10-08 11:55:29.807934 | controller | msg: All assertions passed 2025-10-08 11:55:29.807963 | controller | 2025-10-08 11:55:29.807971 | controller | TASK [Verify openshift client version] ***************************************** 2025-10-08 11:55:29.807977 | controller | Wednesday 08 October 2025 11:55:29 +0000 (0:00:00.035) 0:00:14.313 ***** 2025-10-08 11:55:29.807987 | controller | changed: [instance] 2025-10-08 11:55:29.808009 | controller | 2025-10-08 11:55:29.808018 | controller | TASK [Debug - oc version output] *********************************************** 2025-10-08 11:55:29.808150 | controller | Wednesday 08 October 2025 11:55:29 +0000 (0:00:00.264) 0:00:14.578 ***** 2025-10-08 11:55:29.828404 | controller | ok: [instance] => 2025-10-08 11:55:29.828496 | controller | msg: |- 2025-10-08 11:55:29.828511 | controller | clientVersion: 2025-10-08 11:55:29.828525 | controller | buildDate: "2025-09-23T14:02:39Z" 2025-10-08 11:55:29.828531 | controller | compiler: gc 2025-10-08 11:55:29.828537 | controller | gitCommit: 298429ba9831d1d72b89edd9beb82a6ee665c3b7 2025-10-08 11:55:29.828542 | controller | gitTreeState: clean 2025-10-08 11:55:29.828547 | controller | gitVersion: 4.19.0-202509230411.p2.g298429b.assembly.stream.el9-298429b 2025-10-08 11:55:29.828553 | controller | goVersion: go1.23.9 (Red Hat 1.23.9-1.el9_6) X:strictfipsruntime 2025-10-08 11:55:29.828559 | controller | major: "" 2025-10-08 11:55:29.828564 | controller | minor: "" 2025-10-08 11:55:29.828569 | controller | platform: linux/amd64 2025-10-08 11:55:29.828574 | controller | kustomizeVersion: v5.5.0 2025-10-08 11:55:29.828580 | controller | releaseClientVersion: 4.19.14 2025-10-08 11:55:29.828585 | controller | 2025-10-08 11:55:29.828590 | controller | TASK [Compare the expected version] ******************************************** 2025-10-08 11:55:29.828598 | controller | Wednesday 08 October 2025 11:55:29 +0000 (0:00:00.019) 0:00:14.597 ***** 2025-10-08 11:55:29.861601 | controller | ok: [instance] => changed=false 2025-10-08 11:55:29.861773 | controller | msg: All assertions passed 2025-10-08 11:55:29.861932 | controller | 2025-10-08 11:55:29.862079 | controller | TASK [ci_setup : Manage directories] ******************************************* 2025-10-08 11:55:29.862216 | controller | Wednesday 08 October 2025 11:55:29 +0000 (0:00:00.034) 0:00:14.632 ***** 2025-10-08 11:55:30.822887 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/artifacts/manifests/openstack/cr) 2025-10-08 11:55:30.838633 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/logs) 2025-10-08 11:55:30.838655 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/tmp) 2025-10-08 11:55:30.838662 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/volumes) 2025-10-08 11:55:30.838669 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/artifacts/parameters) 2025-10-08 11:55:30.838675 | controller | 2025-10-08 11:55:30.838680 | controller | TASK [ci_setup : Check rhsm status] ******************************************** 2025-10-08 11:55:30.838686 | controller | Wednesday 08 October 2025 11:55:30 +0000 (0:00:00.960) 0:00:15.592 ***** 2025-10-08 11:55:30.838695 | controller | skipping: [instance] 2025-10-08 11:55:30.854490 | controller | 2025-10-08 11:55:30.854510 | controller | TASK [ci_setup : Gather the repos to be enabled] ******************************* 2025-10-08 11:55:30.854517 | controller | Wednesday 08 October 2025 11:55:30 +0000 (0:00:00.016) 0:00:15.609 ***** 2025-10-08 11:55:30.854526 | controller | skipping: [instance] 2025-10-08 11:55:30.870294 | controller | 2025-10-08 11:55:30.870327 | controller | TASK [ci_setup : Enabling the required repositories.] ************************** 2025-10-08 11:55:30.870334 | controller | Wednesday 08 October 2025 11:55:30 +0000 (0:00:00.015) 0:00:15.624 ***** 2025-10-08 11:55:30.870342 | controller | skipping: [instance] 2025-10-08 11:55:30.884928 | controller | 2025-10-08 11:55:30.884951 | controller | TASK [ci_setup : Get current /etc/redhat-release] ****************************** 2025-10-08 11:55:30.884960 | controller | Wednesday 08 October 2025 11:55:30 +0000 (0:00:00.015) 0:00:15.640 ***** 2025-10-08 11:55:30.884970 | controller | skipping: [instance] 2025-10-08 11:55:30.885143 | controller | 2025-10-08 11:55:30.885331 | controller | TASK [ci_setup : Print current /etc/redhat-release] **************************** 2025-10-08 11:55:30.885493 | controller | Wednesday 08 October 2025 11:55:30 +0000 (0:00:00.015) 0:00:15.655 ***** 2025-10-08 11:55:30.899883 | controller | skipping: [instance] 2025-10-08 11:55:30.900045 | controller | 2025-10-08 11:55:30.900190 | controller | TASK [ci_setup : Ensure the repos are enabled in the system using yum] ********* 2025-10-08 11:55:30.900351 | controller | Wednesday 08 October 2025 11:55:30 +0000 (0:00:00.015) 0:00:15.670 ***** 2025-10-08 11:55:31.124510 | controller | changed: [instance] => (item={'name': 'crb', 'baseurl': 'https://mirror.stream.centos.org/9-stream/CRB/x86_64/os/', 'description': 'Code ready builder', 'gpgcheck': True}) 2025-10-08 11:55:31.292064 | controller | 2025-10-08 11:55:31.292094 | controller | TASK [Stat directory] ********************************************************** 2025-10-08 11:55:31.292102 | controller | Wednesday 08 October 2025 11:55:31 +0000 (0:00:00.223) 0:00:15.894 ***** 2025-10-08 11:55:31.292112 | controller | ok: [instance] 2025-10-08 11:55:31.316584 | controller | 2025-10-08 11:55:31.316615 | controller | TASK [Validate directory state] ************************************************ 2025-10-08 11:55:31.316624 | controller | Wednesday 08 October 2025 11:55:31 +0000 (0:00:00.167) 0:00:16.062 ***** 2025-10-08 11:55:31.316640 | controller | ok: [instance] => changed=false 2025-10-08 11:55:31.358567 | controller | msg: All assertions passed 2025-10-08 11:55:31.358594 | controller | 2025-10-08 11:55:31.358601 | controller | TASK [ci_setup : Gather variables for each operating system] ******************* 2025-10-08 11:55:31.358607 | controller | Wednesday 08 October 2025 11:55:31 +0000 (0:00:00.024) 0:00:16.086 ***** 2025-10-08 11:55:31.358617 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_setup/vars/redhat.yml) 2025-10-08 11:55:31.378532 | controller | 2025-10-08 11:55:31.378562 | controller | TASK [ci_setup : List packages to install] ************************************* 2025-10-08 11:55:31.378570 | controller | Wednesday 08 October 2025 11:55:31 +0000 (0:00:00.042) 0:00:16.128 ***** 2025-10-08 11:55:31.378580 | controller | ok: [instance] => 2025-10-08 11:55:32.082895 | controller | cifmw_ci_setup_packages: 2025-10-08 11:55:32.082926 | controller | - bash-completion 2025-10-08 11:55:32.082934 | controller | - ca-certificates 2025-10-08 11:55:32.082940 | controller | - git-core 2025-10-08 11:55:32.082945 | controller | - make 2025-10-08 11:55:32.082951 | controller | - tar 2025-10-08 11:55:32.082956 | controller | - tmux 2025-10-08 11:55:32.082961 | controller | - python3-pip 2025-10-08 11:55:32.082967 | controller | 2025-10-08 11:55:32.082973 | controller | TASK [ci_setup : Install needed packages] ************************************** 2025-10-08 11:55:32.082978 | controller | Wednesday 08 October 2025 11:55:31 +0000 (0:00:00.019) 0:00:16.147 ***** 2025-10-08 11:55:32.082988 | controller | ok: [instance] 2025-10-08 11:55:32.351573 | controller | 2025-10-08 11:55:32.351604 | controller | TASK [ci_setup : Gather version of openshift client] *************************** 2025-10-08 11:55:32.351612 | controller | Wednesday 08 October 2025 11:55:32 +0000 (0:00:00.705) 0:00:16.853 ***** 2025-10-08 11:55:32.351623 | controller | ok: [instance] 2025-10-08 11:55:32.552578 | controller | 2025-10-08 11:55:32.552612 | controller | TASK [ci_setup : Ensure openshift client install path is present] ************** 2025-10-08 11:55:32.552620 | controller | Wednesday 08 October 2025 11:55:32 +0000 (0:00:00.268) 0:00:17.121 ***** 2025-10-08 11:55:32.552631 | controller | ok: [instance] 2025-10-08 11:55:32.612590 | controller | 2025-10-08 11:55:32.612621 | controller | TASK [ci_setup : Install openshift client] ************************************* 2025-10-08 11:55:32.612629 | controller | Wednesday 08 October 2025 11:55:32 +0000 (0:00:00.200) 0:00:17.322 ***** 2025-10-08 11:55:32.612639 | controller | skipping: [instance] 2025-10-08 11:55:32.644008 | controller | 2025-10-08 11:55:32.644034 | controller | TASK [ci_setup : Add the OC path to cifmw_path if needed] ********************** 2025-10-08 11:55:32.644041 | controller | Wednesday 08 October 2025 11:55:32 +0000 (0:00:00.060) 0:00:17.382 ***** 2025-10-08 11:55:32.644051 | controller | skipping: [instance] 2025-10-08 11:55:32.822636 | controller | 2025-10-08 11:55:32.822669 | controller | TASK [ci_setup : Create completion file] *************************************** 2025-10-08 11:55:32.822677 | controller | Wednesday 08 October 2025 11:55:32 +0000 (0:00:00.031) 0:00:17.414 ***** 2025-10-08 11:55:32.822687 | controller | ok: [instance] 2025-10-08 11:55:33.006504 | controller | 2025-10-08 11:55:33.006535 | controller | TASK [ci_setup : Source completion from within .bashrc] ************************ 2025-10-08 11:55:33.006544 | controller | Wednesday 08 October 2025 11:55:32 +0000 (0:00:00.178) 0:00:17.592 ***** 2025-10-08 11:55:33.006554 | controller | ok: [instance] 2025-10-08 11:55:33.023171 | controller | 2025-10-08 11:55:33.023197 | controller | TASK [ci_setup : Check rhsm status] ******************************************** 2025-10-08 11:55:33.023207 | controller | Wednesday 08 October 2025 11:55:33 +0000 (0:00:00.183) 0:00:17.776 ***** 2025-10-08 11:55:33.023218 | controller | skipping: [instance] 2025-10-08 11:55:33.039339 | controller | 2025-10-08 11:55:33.039367 | controller | TASK [ci_setup : Gather the repos to be enabled] ******************************* 2025-10-08 11:55:33.039376 | controller | Wednesday 08 October 2025 11:55:33 +0000 (0:00:00.016) 0:00:17.793 ***** 2025-10-08 11:55:33.039388 | controller | skipping: [instance] 2025-10-08 11:55:33.055978 | controller | 2025-10-08 11:55:33.056020 | controller | TASK [ci_setup : Enabling the required repositories.] ************************** 2025-10-08 11:55:33.056030 | controller | Wednesday 08 October 2025 11:55:33 +0000 (0:00:00.016) 0:00:17.809 ***** 2025-10-08 11:55:33.056042 | controller | skipping: [instance] 2025-10-08 11:55:33.056071 | controller | 2025-10-08 11:55:33.056081 | controller | TASK [ci_setup : Get current /etc/redhat-release] ****************************** 2025-10-08 11:55:33.056181 | controller | Wednesday 08 October 2025 11:55:33 +0000 (0:00:00.016) 0:00:17.826 ***** 2025-10-08 11:55:33.073904 | controller | skipping: [instance] 2025-10-08 11:55:33.090657 | controller | 2025-10-08 11:55:33.090678 | controller | TASK [ci_setup : Print current /etc/redhat-release] **************************** 2025-10-08 11:55:33.090686 | controller | Wednesday 08 October 2025 11:55:33 +0000 (0:00:00.017) 0:00:17.844 ***** 2025-10-08 11:55:33.090695 | controller | skipping: [instance] 2025-10-08 11:55:33.317238 | controller | 2025-10-08 11:55:33.317271 | controller | TASK [ci_setup : Ensure the repos are enabled in the system using yum] ********* 2025-10-08 11:55:33.317280 | controller | Wednesday 08 October 2025 11:55:33 +0000 (0:00:00.016) 0:00:17.861 ***** 2025-10-08 11:55:33.317306 | controller | changed: [instance] => (item={'name': 'crb', 'baseurl': 'https://mirror.stream.centos.org/9-stream/CRB/x86_64/os/', 'description': 'Code ready builder', 'gpgcheck': True}) 2025-10-08 11:55:34.344253 | controller | 2025-10-08 11:55:34.344294 | controller | TASK [ci_setup : Manage directories] ******************************************* 2025-10-08 11:55:34.344303 | controller | Wednesday 08 October 2025 11:55:33 +0000 (0:00:00.226) 0:00:18.087 ***** 2025-10-08 11:55:34.344313 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/artifacts/manifests/openstack/cr) 2025-10-08 11:55:34.401159 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/logs) 2025-10-08 11:55:34.401191 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/tmp) 2025-10-08 11:55:34.401199 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/volumes) 2025-10-08 11:55:34.401205 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/artifacts/parameters) 2025-10-08 11:55:34.401211 | controller | 2025-10-08 11:55:34.401216 | controller | PLAY RECAP ********************************************************************* 2025-10-08 11:55:34.401222 | controller | instance : ok=35 changed=12 unreachable=0 failed=0 skipped=18 rescued=0 ignored=0 2025-10-08 11:55:34.401228 | controller | 2025-10-08 11:55:34.401233 | controller | Wednesday 08 October 2025 11:55:34 +0000 (0:00:01.024) 0:00:19.111 ***** 2025-10-08 11:55:34.401238 | controller | =============================================================================== 2025-10-08 11:55:34.401243 | controller | ci_setup : Install openshift client ------------------------------------- 6.38s 2025-10-08 11:55:34.401249 | controller | ci_setup : Install needed packages -------------------------------------- 3.59s 2025-10-08 11:55:34.401254 | controller | ci_setup : Manage directories ------------------------------------------- 1.02s 2025-10-08 11:55:34.401259 | controller | ci_setup : Manage directories ------------------------------------------- 0.98s 2025-10-08 11:55:34.401264 | controller | ci_setup : Manage directories ------------------------------------------- 0.96s 2025-10-08 11:55:34.401270 | controller | Gathering Facts --------------------------------------------------------- 0.84s 2025-10-08 11:55:34.401286 | controller | ci_setup : Install needed packages -------------------------------------- 0.71s 2025-10-08 11:55:34.401292 | controller | ci_setup : Ensure the repos are enabled in the system using yum --------- 0.32s 2025-10-08 11:55:34.401297 | controller | Get oc stat informaiton ------------------------------------------------- 0.32s 2025-10-08 11:55:34.401303 | controller | ci_setup : Ensure openshift client install path is present -------------- 0.32s 2025-10-08 11:55:34.401308 | controller | ci_setup : Gather version of openshift client --------------------------- 0.30s 2025-10-08 11:55:34.401313 | controller | ci_setup : Source completion from within .bashrc ------------------------ 0.28s 2025-10-08 11:55:34.401318 | controller | ci_setup : Gather version of openshift client --------------------------- 0.27s 2025-10-08 11:55:34.401323 | controller | ci_setup : Create completion file --------------------------------------- 0.27s 2025-10-08 11:55:34.401334 | controller | Verify openshift client version ----------------------------------------- 0.26s 2025-10-08 11:55:34.401340 | controller | ci_setup : Ensure the repos are enabled in the system using yum --------- 0.23s 2025-10-08 11:55:34.401345 | controller | ci_setup : Ensure the repos are enabled in the system using yum --------- 0.22s 2025-10-08 11:55:34.401350 | controller | ci_setup : Ensure openshift client install path is present -------------- 0.20s 2025-10-08 11:55:34.401355 | controller | Stat directory ---------------------------------------------------------- 0.19s 2025-10-08 11:55:34.401361 | controller | Gather repo file stats -------------------------------------------------- 0.19s 2025-10-08 11:55:34.401374 | controller | INFO Running default > cleanup 2025-10-08 11:55:34.401805 | controller | WARNING Skipping, cleanup playbook not configured. 2025-10-08 11:55:34.402381 | controller | INFO Writing /tmp/report.html report. 2025-10-08 11:55:34.497119 | controller | changed 2025-10-08 11:55:34.519210 | 2025-10-08 11:55:34.519279 | PLAY RECAP 2025-10-08 11:55:34.519320 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2025-10-08 11:55:34.519343 | 2025-10-08 11:55:34.609394 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main] 2025-10-08 11:55:34.610244 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main] 2025-10-08 11:55:35.147398 | 2025-10-08 11:55:35.147504 | PLAY [Run ci/playbooks/collect-logs.yml] 2025-10-08 11:55:35.167676 | 2025-10-08 11:55:35.167766 | TASK [Filter out host if needed] 2025-10-08 11:55:35.176259 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller 2025-10-08 11:55:35.180702 | 2025-10-08 11:55:35.180771 | TASK [Ensure file is present] 2025-10-08 11:55:35.503400 | controller | ok 2025-10-08 11:55:35.510321 | 2025-10-08 11:55:35.510408 | TASK [Manage molecule report file] 2025-10-08 11:55:36.032941 | controller | changed 2025-10-08 11:55:36.038850 | 2025-10-08 11:55:36.038957 | TASK [Check if we get ci-framework-data basedir] 2025-10-08 11:55:36.230326 | controller | ok 2025-10-08 11:55:36.235500 | 2025-10-08 11:55:36.235589 | TASK [Create ci-framework-data log directory for zuul] 2025-10-08 11:55:36.548301 | controller | changed 2025-10-08 11:55:36.554028 | 2025-10-08 11:55:36.554102 | TASK [Copy ci-framework interesting files] 2025-10-08 11:55:36.769946 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory 2025-10-08 11:55:37.081679 | controller | changed 2025-10-08 11:55:37.086973 | 2025-10-08 11:55:37.087069 | TASK [Get SELinux listing] 2025-10-08 11:55:37.613787 | controller | changed 2025-10-08 11:55:37.620246 | 2025-10-08 11:55:37.620349 | TASK [Generate log index] 2025-10-08 11:55:38.252840 | controller | changed 2025-10-08 11:55:38.257864 | 2025-10-08 11:55:38.257951 | TASK [Get some env related data] 2025-10-08 11:55:38.795399 | controller | /home/zuul/.local/bin/ansible 2025-10-08 11:55:39.782435 | controller | changed 2025-10-08 11:55:39.788729 | 2025-10-08 11:55:39.788851 | TASK [Generate list of logs to collect in home directory] 2025-10-08 11:55:40.087218 | controller | ok: All paths examined 2025-10-08 11:55:40.096479 | 2025-10-08 11:55:40.096683 | LOOP [Copy logs from home directory] 2025-10-08 11:55:40.438325 | controller | changed: 2025-10-08 11:55:40.438490 | controller | { 2025-10-08 11:55:40.438519 | controller | "atime": 1759924473.8587039, 2025-10-08 11:55:40.438539 | controller | "ctime": 1759924489.765816, 2025-10-08 11:55:40.438556 | controller | "dev": 64513, 2025-10-08 11:55:40.438587 | controller | "gid": 1000, 2025-10-08 11:55:40.438604 | controller | "gr_name": "zuul", 2025-10-08 11:55:40.438621 | controller | "inode": 4447317, 2025-10-08 11:55:40.438636 | controller | "isblk": false, 2025-10-08 11:55:40.438650 | controller | "ischr": false, 2025-10-08 11:55:40.438665 | controller | "isdir": false, 2025-10-08 11:55:40.438680 | controller | "isfifo": false, 2025-10-08 11:55:40.438695 | controller | "isgid": false, 2025-10-08 11:55:40.438709 | controller | "islnk": false, 2025-10-08 11:55:40.438722 | controller | "isreg": true, 2025-10-08 11:55:40.438736 | controller | "issock": false, 2025-10-08 11:55:40.438750 | controller | "isuid": false, 2025-10-08 11:55:40.438763 | controller | "mode": "0644", 2025-10-08 11:55:40.438778 | controller | "mtime": 1759924489.765816, 2025-10-08 11:55:40.438794 | controller | "nlink": 1, 2025-10-08 11:55:40.438809 | controller | "path": "/home/zuul/ansible.log", 2025-10-08 11:55:40.438824 | controller | "pw_name": "zuul", 2025-10-08 11:55:40.438840 | controller | "rgrp": true, 2025-10-08 11:55:40.438855 | controller | "roth": true, 2025-10-08 11:55:40.438869 | controller | "rusr": true, 2025-10-08 11:55:40.438883 | controller | "size": 6749, 2025-10-08 11:55:40.438897 | controller | "uid": 1000, 2025-10-08 11:55:40.438931 | controller | "wgrp": false, 2025-10-08 11:55:40.438953 | controller | "woth": false, 2025-10-08 11:55:40.438972 | controller | "wusr": true, 2025-10-08 11:55:40.438989 | controller | "xgrp": false, 2025-10-08 11:55:40.439005 | controller | "xoth": false, 2025-10-08 11:55:40.439020 | controller | "xusr": false 2025-10-08 11:55:40.439034 | controller | } 2025-10-08 11:55:40.454130 | 2025-10-08 11:55:40.454300 | TASK [Copy crio stats log file] 2025-10-08 11:55:40.470467 | controller | skipping: Conditional result was False 2025-10-08 11:55:40.478295 | 2025-10-08 11:55:40.478425 | TASK [Get SELinux related data] 2025-10-08 11:55:40.699114 | controller | 2025-10-08 11:55:41.009823 | controller | ERROR 2025-10-08 11:55:41.010105 | controller | { 2025-10-08 11:55:41.010146 | controller | "delta": "0:00:00.011391", 2025-10-08 11:55:41.010168 | controller | "end": "2025-10-08 11:55:40.700124", 2025-10-08 11:55:41.010188 | controller | "msg": "non-zero return code", 2025-10-08 11:55:41.010204 | controller | "rc": 1, 2025-10-08 11:55:41.010221 | controller | "start": "2025-10-08 11:55:40.688733" 2025-10-08 11:55:41.010237 | controller | } 2025-10-08 11:55:41.010263 | controller | ERROR: Ignoring Errors 2025-10-08 11:55:41.016725 | 2025-10-08 11:55:41.016827 | TASK [Create system configuration directory] 2025-10-08 11:55:41.220921 | controller | changed 2025-10-08 11:55:41.226493 | 2025-10-08 11:55:41.226611 | TASK [Get some of the system configurations] 2025-10-08 11:55:41.752516 | controller | changed 2025-10-08 11:55:41.758205 | 2025-10-08 11:55:41.758285 | TASK [Copy generated documentation if available] 2025-10-08 11:55:41.782088 | controller | skipping: Conditional result was False 2025-10-08 11:55:41.787633 | 2025-10-08 11:55:41.787703 | TASK [Copy generated AsciiDoc documentation if available] 2025-10-08 11:55:41.801428 | controller | skipping: Conditional result was False 2025-10-08 11:55:41.808229 | 2025-10-08 11:55:41.808300 | TASK [Compress logs bigger than 2MB] 2025-10-08 11:55:42.334798 | controller | changed 2025-10-08 11:55:42.340510 | 2025-10-08 11:55:42.340615 | TASK [Copy files from workspace on node] 2025-10-08 11:55:42.361897 | controller | ok 2025-10-08 11:55:42.386781 | 2025-10-08 11:55:42.386931 | TASK [fetch-output : Set log path for multiple nodes] 2025-10-08 11:55:42.414166 | controller | skipping: Conditional result was False 2025-10-08 11:55:42.420488 | 2025-10-08 11:55:42.420605 | TASK [fetch-output : Set log path for single node] 2025-10-08 11:55:42.450458 | controller | ok 2025-10-08 11:55:42.455457 | 2025-10-08 11:55:42.455525 | LOOP [fetch-output : Ensure local output dirs] 2025-10-08 11:55:42.673809 | controller -> localhost | ok: "/var/lib/zuul/builds/80636582ccd5454282e52fc1edac7800/work/logs" 2025-10-08 11:55:42.674066 | controller -> localhost | changed: All items complete 2025-10-08 11:55:42.674103 | 2025-10-08 11:55:42.894311 | controller -> localhost | changed: "/var/lib/zuul/builds/80636582ccd5454282e52fc1edac7800/work/artifacts" 2025-10-08 11:55:43.108729 | controller -> localhost | changed: "/var/lib/zuul/builds/80636582ccd5454282e52fc1edac7800/work/docs" 2025-10-08 11:55:43.127903 | 2025-10-08 11:55:43.128083 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-10-08 11:55:43.711314 | controller | changed: 2025-10-08 11:55:43.711503 | controller | .d..t...... ./ 2025-10-08 11:55:43.711536 | controller | >f+++++++++ README.html 2025-10-08 11:55:43.711572 | controller | >f+++++++++ ansible-execution.log 2025-10-08 11:55:43.711741 | controller | >f+++++++++ ansible.log 2025-10-08 11:55:43.711777 | controller | >f+++++++++ dmesg.log 2025-10-08 11:55:43.711800 | controller | >f+++++++++ installed-pkgs.log 2025-10-08 11:55:43.711822 | controller | >f+++++++++ python.log 2025-10-08 11:55:43.711843 | controller | >f+++++++++ registries.conf 2025-10-08 11:55:43.711859 | controller | >f+++++++++ report.html 2025-10-08 11:55:43.711878 | controller | >f+++++++++ selinux-denials.log 2025-10-08 11:55:43.711895 | controller | >f+++++++++ selinux-listing.log 2025-10-08 11:55:43.711948 | controller | cd+++++++++ ci-framework-data/ 2025-10-08 11:55:43.711972 | controller | cd+++++++++ ci-framework-data/artifacts/ 2025-10-08 11:55:43.711990 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml 2025-10-08 11:55:43.712008 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/ 2025-10-08 11:55:43.712025 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/openstack/ 2025-10-08 11:55:43.712041 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/openstack/cr/ 2025-10-08 11:55:43.712057 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/ 2025-10-08 11:55:43.712071 | controller | cd+++++++++ ci-framework-data/logs/ 2025-10-08 11:55:43.712086 | controller | cd+++++++++ registries.conf.d/ 2025-10-08 11:55:43.712101 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf 2025-10-08 11:55:43.712117 | controller | cd+++++++++ system-config/ 2025-10-08 11:55:43.712133 | controller | cd+++++++++ system-config/libvirt/ 2025-10-08 11:55:43.712149 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf 2025-10-08 11:55:43.712165 | controller | >f+++++++++ system-config/libvirt/libvirt.conf 2025-10-08 11:55:44.113658 | controller | changed: .d..t...... ./ 2025-10-08 11:55:44.489684 | controller | changed: .d..t...... ./ 2025-10-08 11:55:44.507486 | 2025-10-08 11:55:44.507592 | TASK [Return artifact to Zuul] 2025-10-08 11:55:44.544715 | controller | ok 2025-10-08 11:55:44.564860 | 2025-10-08 11:55:44.564974 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host] 2025-10-08 11:55:44.565089 | 2025-10-08 11:55:44.565116 | PLAY RECAP 2025-10-08 11:55:44.565153 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1 2025-10-08 11:55:44.565174 | 2025-10-08 11:55:44.651781 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main] 2025-10-08 11:55:44.652539 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2025-10-08 11:55:45.214529 | 2025-10-08 11:55:45.214649 | PLAY [all] 2025-10-08 11:55:45.234134 | 2025-10-08 11:55:45.234234 | TASK [include_role : fetch-output] 2025-10-08 11:55:45.274117 | controller | ok 2025-10-08 11:55:45.292228 | 2025-10-08 11:55:45.292366 | TASK [fetch-output : Set log path for multiple nodes] 2025-10-08 11:55:45.337210 | controller | skipping: Conditional result was False 2025-10-08 11:55:45.343343 | 2025-10-08 11:55:45.343448 | TASK [fetch-output : Set log path for single node] 2025-10-08 11:55:45.372028 | controller | ok 2025-10-08 11:55:45.377493 | 2025-10-08 11:55:45.377586 | LOOP [fetch-output : Ensure local output dirs] 2025-10-08 11:55:45.699200 | controller -> localhost | ok: "/var/lib/zuul/builds/80636582ccd5454282e52fc1edac7800/work/logs" 2025-10-08 11:55:45.886387 | controller -> localhost | ok: "/var/lib/zuul/builds/80636582ccd5454282e52fc1edac7800/work/artifacts" 2025-10-08 11:55:46.076521 | controller -> localhost | ok: "/var/lib/zuul/builds/80636582ccd5454282e52fc1edac7800/work/docs" 2025-10-08 11:55:46.087375 | 2025-10-08 11:55:46.087459 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-10-08 11:55:46.629791 | controller | ok 2025-10-08 11:55:46.629992 | controller | ok: All items complete 2025-10-08 11:55:46.630024 | 2025-10-08 11:55:47.012311 | controller | ok 2025-10-08 11:55:47.399284 | controller | ok 2025-10-08 11:55:47.413986 | 2025-10-08 11:55:47.414094 | TASK [include_role : fetch-output-openshift] 2025-10-08 11:55:47.427595 | controller | skipping: Conditional result was False 2025-10-08 11:55:47.433370 | 2025-10-08 11:55:47.433442 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-10-08 11:55:47.803135 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.006067 2025-10-08 11:55:48.015433 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.006370 2025-10-08 11:55:48.042868 | 2025-10-08 11:55:48.042986 | PLAY [all] 2025-10-08 11:55:48.057412 | 2025-10-08 11:55:48.057483 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-10-08 11:55:48.481098 | controller | changed 2025-10-08 11:55:48.503520 | 2025-10-08 11:55:48.503595 | PLAY RECAP 2025-10-08 11:55:48.503639 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2025-10-08 11:55:48.503661 | 2025-10-08 11:55:48.574293 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2025-10-08 11:55:48.575063 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master] 2025-10-08 11:55:49.168667 | 2025-10-08 11:55:49.168815 | PLAY [localhost] 2025-10-08 11:55:49.187090 | 2025-10-08 11:55:49.187189 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-10-08 11:55:49.509139 | localhost | changed 2025-10-08 11:55:49.513822 | 2025-10-08 11:55:49.513898 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-10-08 11:55:49.531370 | localhost | ok 2025-10-08 11:55:49.539085 | 2025-10-08 11:55:49.539155 | TASK [add-fileserver : Create SSH private key tempfile] 2025-10-08 11:55:49.850807 | localhost | changed 2025-10-08 11:55:49.856624 | 2025-10-08 11:55:49.856771 | TASK [add-fileserver : Create SSH private key from secret] 2025-10-08 11:55:50.445778 | localhost | changed 2025-10-08 11:55:50.453949 | 2025-10-08 11:55:50.454037 | TASK [add-fileserver : Add fileserver ssh key] 2025-10-08 11:55:50.827594 | localhost | Identity added: /var/lib/zuul/builds/80636582ccd5454282e52fc1edac7800/work/tmp/ansible.0t_6crc8 (/var/lib/zuul/builds/80636582ccd5454282e52fc1edac7800/work/tmp/ansible.0t_6crc8) 2025-10-08 11:55:50.827759 | localhost | ok: Runtime: 0:00:00.006433 2025-10-08 11:55:50.832728 | 2025-10-08 11:55:50.832801 | TASK [add-fileserver : Remove SSH private key from disk] 2025-10-08 11:55:51.064227 | localhost | ok: Runtime: 0:00:00.004009 2025-10-08 11:55:51.070303 | 2025-10-08 11:55:51.070389 | TASK [add-fileserver : Add fileserver to inventory] 2025-10-08 11:55:51.120058 | localhost | changed 2025-10-08 11:55:51.124421 | 2025-10-08 11:55:51.124513 | TASK [add-fileserver : Add fileserver server to known hosts] 2025-10-08 11:55:51.456936 | localhost | changed 2025-10-08 11:55:51.477608 | 2025-10-08 11:55:51.477687 | PLAY [localhost] 2025-10-08 11:55:51.489616 | 2025-10-08 11:55:51.489683 | TASK [Generate bulk log download script] 2025-10-08 11:55:51.508241 | localhost | ok 2025-10-08 11:55:51.519880 | 2025-10-08 11:55:51.519982 | TASK [local-log-download : Check API endpoint is defined] 2025-10-08 11:55:51.547424 | localhost | ok: All assertions passed 2025-10-08 11:55:51.551825 | 2025-10-08 11:55:51.551894 | TASK [local-log-download : Create download script] 2025-10-08 11:55:51.910124 | localhost -> localhost | changed 2025-10-08 11:55:51.919435 | 2025-10-08 11:55:51.919519 | TASK [Register quick-download link] 2025-10-08 11:55:51.936687 | localhost | ok 2025-10-08 11:55:51.973136 | 2025-10-08 11:55:51.973212 | PLAY [logserver.rdoproject.org] 2025-10-08 11:55:51.983143 | 2025-10-08 11:55:51.983217 | TASK [Set zuul-log-path fact] 2025-10-08 11:55:51.999684 | logserver.rdoproject.org | ok 2025-10-08 11:55:52.009996 | 2025-10-08 11:55:52.010060 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-08 11:55:52.036136 | logserver.rdoproject.org | ok 2025-10-08 11:55:52.042007 | 2025-10-08 11:55:52.042077 | TASK [upload-logs : Create log directories] 2025-10-08 11:55:53.791062 | logserver.rdoproject.org | changed 2025-10-08 11:55:53.794850 | 2025-10-08 11:55:53.794944 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-10-08 11:55:54.046506 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.006150 2025-10-08 11:55:54.052064 | 2025-10-08 11:55:54.052131 | TASK [upload-logs : Upload logs to log server] 2025-10-08 11:55:55.669166 | logserver.rdoproject.org | Output suppressed because no_log was given 2025-10-08 11:55:55.672605 | 2025-10-08 11:55:55.672714 | LOOP [upload-logs : Compress console log and json output] 2025-10-08 11:55:55.720598 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-08 11:55:55.730513 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-08 11:55:55.741442 | 2025-10-08 11:55:55.741574 | LOOP [upload-logs : Upload compressed console log and json output] 2025-10-08 11:55:55.789201 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-08 11:55:55.789613 | 2025-10-08 11:55:55.793154 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-08 11:55:55.800435 | 2025-10-08 11:55:55.800565 | LOOP [upload-logs : Upload console log and json output]