2025-10-07 07:28:15.474066 | Job console starting... 2025-10-07 07:28:15.856363 | Updating repositories 2025-10-07 07:28:16.642737 | Preparing job workspace 2025-10-07 07:28:30.400520 | Running Ansible setup... 2025-10-07 07:28:36.841503 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2025-10-07 07:28:38.819408 | 2025-10-07 07:28:38.819515 | PLAY [localhost] 2025-10-07 07:28:38.864470 | 2025-10-07 07:28:38.864547 | TASK [Gathering Facts] 2025-10-07 07:28:41.278571 | localhost | ok 2025-10-07 07:28:41.328346 | 2025-10-07 07:28:41.328454 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-10-07 07:28:42.999391 | localhost -> localhost | changed 2025-10-07 07:28:43.005208 | 2025-10-07 07:28:43.005285 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-10-07 07:28:46.264459 | localhost -> localhost | changed 2025-10-07 07:28:46.273033 | 2025-10-07 07:28:46.273113 | TASK [Setup log path fact] 2025-10-07 07:28:46.321398 | localhost | ok 2025-10-07 07:28:46.353184 | 2025-10-07 07:28:46.353263 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-07 07:28:46.431756 | localhost | ok 2025-10-07 07:28:46.439556 | 2025-10-07 07:28:46.439624 | TASK [emit-job-header : Print job information] 2025-10-07 07:28:46.561547 | # Job Information 2025-10-07 07:28:46.561684 | Ansible Version: 2.15.12 2025-10-07 07:28:46.561714 | Job: cifmw-molecule-cifmw_snr_nhc 2025-10-07 07:28:46.561738 | Pipeline: github-check 2025-10-07 07:28:46.561759 | Executor: ibm-bm4-ze.softwarefactory-project.io 2025-10-07 07:28:46.561779 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3366 2025-10-07 07:28:46.561798 | Log URL (when completed): https://logserver.rdoproject.org/007/rdoproject.org/007f961ce0ae472c9ca349317c552dce/ 2025-10-07 07:28:46.561817 | Event ID: 0b3e6df0-a34f-11f0-8d70-8afb6d89ca3c 2025-10-07 07:28:46.565546 | 2025-10-07 07:28:46.565612 | LOOP [emit-job-header : Print node information] 2025-10-07 07:28:47.001883 | localhost | ok: 2025-10-07 07:28:47.002064 | localhost | # Node Information 2025-10-07 07:28:47.002118 | localhost | Inventory Hostname: controller 2025-10-07 07:28:47.002152 | localhost | Hostname: np0005473027 2025-10-07 07:28:47.002179 | localhost | Username: zuul 2025-10-07 07:28:47.002204 | localhost | Distro: CentOS 9 2025-10-07 07:28:47.002227 | localhost | Provider: ibm-bm4-nodepool 2025-10-07 07:28:47.002249 | localhost | Region: regionOne 2025-10-07 07:28:47.002272 | localhost | Label: cloud-centos-9-stream-tripleo-ibm 2025-10-07 07:28:47.002294 | localhost | Product Name: OpenStack Compute 2025-10-07 07:28:47.002316 | localhost | Interface IP: 192.168.26.106 2025-10-07 07:28:47.073871 | 2025-10-07 07:28:47.073977 | PLAY [all] 2025-10-07 07:28:47.088490 | 2025-10-07 07:28:47.088557 | TASK [Gather network facts] 2025-10-07 07:28:47.574282 | controller | ok 2025-10-07 07:28:47.656555 | 2025-10-07 07:28:47.656659 | TASK [include_role : start-zuul-console] 2025-10-07 07:28:47.725054 | controller | ok 2025-10-07 07:28:47.757433 | 2025-10-07 07:28:47.757507 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-10-07 07:28:48.659134 | controller | ok 2025-10-07 07:28:48.673474 | 2025-10-07 07:28:48.673567 | TASK [include_role : add-build-sshkey] 2025-10-07 07:28:48.731106 | controller | ok 2025-10-07 07:28:48.754489 | 2025-10-07 07:28:48.754665 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-07 07:28:49.594101 | controller -> localhost | ok 2025-10-07 07:28:49.600098 | 2025-10-07 07:28:49.600185 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-07 07:28:49.675833 | controller | ok 2025-10-07 07:28:49.745074 | controller | included: /var/lib/zuul/builds/007f961ce0ae472c9ca349317c552dce/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-07 07:28:49.771881 | 2025-10-07 07:28:49.771985 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-07 07:28:51.726963 | controller -> localhost | Generating public/private rsa key pair. 2025-10-07 07:28:51.727151 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/007f961ce0ae472c9ca349317c552dce/work/007f961ce0ae472c9ca349317c552dce_id_rsa. 2025-10-07 07:28:51.727186 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/007f961ce0ae472c9ca349317c552dce/work/007f961ce0ae472c9ca349317c552dce_id_rsa.pub. 2025-10-07 07:28:51.727215 | controller -> localhost | The key fingerprint is: 2025-10-07 07:28:51.727236 | controller -> localhost | SHA256:gVHPGCwGUMoBUxhxTsz2y4TZfITpEXd3QztaT5ESorM zuul-build-sshkey 2025-10-07 07:28:51.727258 | controller -> localhost | The key's randomart image is: 2025-10-07 07:28:51.727277 | controller -> localhost | +---[RSA 3072]----+ 2025-10-07 07:28:51.727295 | controller -> localhost | |=O*o+=oo+ o.=... | 2025-10-07 07:28:51.727314 | controller -> localhost | |.*+o+.++.B o.o.. | 2025-10-07 07:28:51.727332 | controller -> localhost | | .+B +..= o +.. | 2025-10-07 07:28:51.727351 | controller -> localhost | | o * . + o + | 2025-10-07 07:28:51.727369 | controller -> localhost | | o o E . . | 2025-10-07 07:28:51.727387 | controller -> localhost | | o | 2025-10-07 07:28:51.727406 | controller -> localhost | | | 2025-10-07 07:28:51.727427 | controller -> localhost | | | 2025-10-07 07:28:51.727447 | controller -> localhost | | | 2025-10-07 07:28:51.727465 | controller -> localhost | +----[SHA256]-----+ 2025-10-07 07:28:51.727506 | controller -> localhost | ok: Runtime: 0:00:00.289463 2025-10-07 07:28:51.733563 | 2025-10-07 07:28:51.733629 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-07 07:28:51.822049 | controller | ok 2025-10-07 07:28:51.841388 | controller | included: /var/lib/zuul/builds/007f961ce0ae472c9ca349317c552dce/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-07 07:28:51.894864 | 2025-10-07 07:28:51.895012 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-07 07:28:51.990332 | controller | skipping: Conditional result was False 2025-10-07 07:28:51.996486 | 2025-10-07 07:28:51.996556 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-07 07:28:52.934619 | controller | changed 2025-10-07 07:28:52.941114 | 2025-10-07 07:28:52.941210 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-07 07:28:53.268891 | controller | ok 2025-10-07 07:28:53.281205 | 2025-10-07 07:28:53.281341 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-07 07:28:54.222065 | controller | changed 2025-10-07 07:28:54.244465 | 2025-10-07 07:28:54.244542 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-07 07:28:55.200170 | controller | changed 2025-10-07 07:28:55.208159 | 2025-10-07 07:28:55.208225 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-07 07:28:55.324856 | controller | skipping: Conditional result was False 2025-10-07 07:28:55.330641 | 2025-10-07 07:28:55.330723 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-07 07:28:56.278843 | controller -> localhost | changed 2025-10-07 07:28:56.297667 | 2025-10-07 07:28:56.297739 | TASK [add-build-sshkey : Add back temp key] 2025-10-07 07:28:57.374661 | controller -> localhost | Identity added: /var/lib/zuul/builds/007f961ce0ae472c9ca349317c552dce/work/007f961ce0ae472c9ca349317c552dce_id_rsa (zuul-build-sshkey) 2025-10-07 07:28:57.374903 | controller -> localhost | ok: Runtime: 0:00:00.023937 2025-10-07 07:28:57.380930 | 2025-10-07 07:28:57.381011 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-07 07:28:58.091993 | controller | ok 2025-10-07 07:28:58.110516 | 2025-10-07 07:28:58.110600 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-07 07:28:58.195386 | controller | skipping: Conditional result was False 2025-10-07 07:28:58.214532 | 2025-10-07 07:28:58.214613 | TASK [include_role : validate-host] 2025-10-07 07:28:58.286541 | controller | ok 2025-10-07 07:28:58.373269 | 2025-10-07 07:28:58.373379 | TASK [validate-host : Define zuul_info_dir fact] 2025-10-07 07:28:58.483946 | controller | ok 2025-10-07 07:28:58.488357 | 2025-10-07 07:28:58.488426 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-10-07 07:28:59.492715 | controller -> localhost | ok 2025-10-07 07:28:59.498470 | 2025-10-07 07:28:59.498548 | TASK [validate-host : Collect information about the host] 2025-10-07 07:29:00.239190 | controller | ok 2025-10-07 07:29:00.259712 | 2025-10-07 07:29:00.259796 | TASK [validate-host : Sanitize hostname] 2025-10-07 07:29:00.400076 | controller | ok 2025-10-07 07:29:00.407784 | 2025-10-07 07:29:00.407865 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-10-07 07:29:02.082010 | controller -> localhost | changed 2025-10-07 07:29:02.087699 | 2025-10-07 07:29:02.087764 | TASK [validate-host : Collect information about zuul worker] 2025-10-07 07:29:03.085586 | controller | ok 2025-10-07 07:29:03.091439 | 2025-10-07 07:29:03.091508 | TASK [validate-host : Write out all zuul information for each host] 2025-10-07 07:29:04.494094 | controller -> localhost | changed 2025-10-07 07:29:04.504022 | 2025-10-07 07:29:04.504092 | TASK [include_role : prepare-workspace-openshift] 2025-10-07 07:29:04.556753 | controller | skipping: Conditional result was False 2025-10-07 07:29:04.563386 | 2025-10-07 07:29:04.563453 | TASK [include_role : remove-zuul-sshkey] 2025-10-07 07:29:04.619245 | controller | skipping: Conditional result was False 2025-10-07 07:29:04.625529 | 2025-10-07 07:29:04.625600 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2025-10-07 07:29:04.928287 | controller | ok: "logs" 2025-10-07 07:29:04.928487 | controller | ok: All items complete 2025-10-07 07:29:04.928517 | 2025-10-07 07:29:05.120952 | controller | ok: "artifacts" 2025-10-07 07:29:05.365029 | controller | ok: "docs" 2025-10-07 07:29:05.377475 | 2025-10-07 07:29:05.377574 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2025-10-07 07:29:05.691005 | controller | changed: "logs" 2025-10-07 07:29:05.882687 | controller | changed: "artifacts" 2025-10-07 07:29:06.077764 | controller | changed: "docs" 2025-10-07 07:29:06.162030 | 2025-10-07 07:29:06.162124 | PLAY RECAP 2025-10-07 07:29:06.162191 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0 2025-10-07 07:29:06.162224 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-07 07:29:06.162245 | 2025-10-07 07:29:06.623347 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2025-10-07 07:29:06.624079 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master] 2025-10-07 07:29:09.373655 | 2025-10-07 07:29:09.373774 | PLAY [all] 2025-10-07 07:29:09.455203 | 2025-10-07 07:29:09.455333 | TASK [mirror-info-fork : Set mirror_fqdn if not defined] 2025-10-07 07:29:09.551855 | controller | ok 2025-10-07 07:29:09.562687 | 2025-10-07 07:29:09.562794 | TASK [mirror-info-fork : Create /etc/ci] 2025-10-07 07:29:10.374517 | controller | changed 2025-10-07 07:29:10.386405 | 2025-10-07 07:29:10.386499 | TASK [mirror-info-fork : Install ci_mirror script] 2025-10-07 07:29:12.382834 | controller | changed 2025-10-07 07:29:12.417464 | 2025-10-07 07:29:12.417579 | LOOP [add-authorized-keys : Enable access via build key on all nodes] 2025-10-07 07:29:13.412667 | controller | changed: 2025-10-07 07:29:13.412834 | controller | { 2025-10-07 07:29:13.412868 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com" 2025-10-07 07:29:13.412898 | controller | } 2025-10-07 07:29:13.669636 | controller | changed: 2025-10-07 07:29:13.669726 | controller | { 2025-10-07 07:29:13.669755 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com" 2025-10-07 07:29:13.669777 | controller | } 2025-10-07 07:29:13.904277 | controller | changed: 2025-10-07 07:29:13.904360 | controller | { 2025-10-07 07:29:13.904389 | 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-07 07:29:13.904413 | controller | } 2025-10-07 07:29:14.145003 | controller | changed: 2025-10-07 07:29:14.145094 | controller | { 2025-10-07 07:29:14.145122 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com" 2025-10-07 07:29:14.145144 | controller | } 2025-10-07 07:29:14.368657 | controller | changed: 2025-10-07 07:29:14.368744 | controller | { 2025-10-07 07:29:14.368771 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com" 2025-10-07 07:29:14.368792 | controller | } 2025-10-07 07:29:14.593199 | controller | changed: 2025-10-07 07:29:14.593285 | controller | { 2025-10-07 07:29:14.593313 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com" 2025-10-07 07:29:14.593335 | controller | } 2025-10-07 07:29:14.868349 | controller | changed: 2025-10-07 07:29:14.868426 | controller | { 2025-10-07 07:29:14.868453 | 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-07 07:29:14.868476 | controller | } 2025-10-07 07:29:15.243424 | controller | changed: 2025-10-07 07:29:15.243507 | controller | { 2025-10-07 07:29:15.243535 | 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-07 07:29:15.243557 | controller | } 2025-10-07 07:29:15.338803 | controller | changed: 2025-10-07 07:29:15.338883 | controller | { 2025-10-07 07:29:15.338931 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner" 2025-10-07 07:29:15.338956 | controller | } 2025-10-07 07:29:15.581349 | controller | changed: 2025-10-07 07:29:15.581423 | controller | { 2025-10-07 07:29:15.581450 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com" 2025-10-07 07:29:15.581471 | controller | } 2025-10-07 07:29:15.796344 | controller | changed: 2025-10-07 07:29:15.796479 | controller | { 2025-10-07 07:29:15.796510 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com" 2025-10-07 07:29:15.796534 | controller | } 2025-10-07 07:29:16.023733 | controller | changed: 2025-10-07 07:29:16.023808 | controller | { 2025-10-07 07:29:16.023835 | controller | "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com" 2025-10-07 07:29:16.023857 | controller | } 2025-10-07 07:29:16.274489 | controller | changed: 2025-10-07 07:29:16.274579 | controller | { 2025-10-07 07:29:16.274606 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith" 2025-10-07 07:29:16.274628 | controller | } 2025-10-07 07:29:16.486033 | controller | changed: 2025-10-07 07:29:16.486114 | controller | { 2025-10-07 07:29:16.486141 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel" 2025-10-07 07:29:16.486163 | controller | } 2025-10-07 07:29:16.726967 | controller | changed: 2025-10-07 07:29:16.727045 | controller | { 2025-10-07 07:29:16.727073 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux" 2025-10-07 07:29:16.727095 | controller | } 2025-10-07 07:29:16.973100 | controller | changed: 2025-10-07 07:29:16.973210 | controller | { 2025-10-07 07:29:16.973241 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr" 2025-10-07 07:29:16.973263 | controller | } 2025-10-07 07:29:17.196561 | controller | changed: 2025-10-07 07:29:17.196651 | controller | { 2025-10-07 07:29:17.196679 | 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-07 07:29:17.196702 | controller | } 2025-10-07 07:29:17.422104 | controller | changed: 2025-10-07 07:29:17.422187 | controller | { 2025-10-07 07:29:17.422215 | 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-07 07:29:17.422245 | controller | } 2025-10-07 07:29:17.670430 | controller | changed: 2025-10-07 07:29:17.670592 | controller | { 2025-10-07 07:29:17.670622 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9" 2025-10-07 07:29:17.670644 | controller | } 2025-10-07 07:29:17.883049 | controller | changed: 2025-10-07 07:29:17.883132 | controller | { 2025-10-07 07:29:17.883160 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora" 2025-10-07 07:29:17.883199 | controller | } 2025-10-07 07:29:18.115707 | controller | changed: 2025-10-07 07:29:18.115781 | controller | { 2025-10-07 07:29:18.116022 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac" 2025-10-07 07:29:18.116052 | controller | } 2025-10-07 07:29:18.346054 | controller | changed: 2025-10-07 07:29:18.346133 | controller | { 2025-10-07 07:29:18.346160 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop" 2025-10-07 07:29:18.346199 | controller | } 2025-10-07 07:29:18.592786 | controller | changed: 2025-10-07 07:29:18.592863 | controller | { 2025-10-07 07:29:18.592890 | 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-07 07:29:18.592933 | controller | } 2025-10-07 07:29:18.824456 | controller | changed: 2025-10-07 07:29:18.824539 | controller | { 2025-10-07 07:29:18.824568 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com" 2025-10-07 07:29:18.824589 | controller | } 2025-10-07 07:29:19.042725 | controller | changed: 2025-10-07 07:29:19.042819 | controller | { 2025-10-07 07:29:19.042846 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com" 2025-10-07 07:29:19.042867 | controller | } 2025-10-07 07:29:19.263885 | controller | changed: 2025-10-07 07:29:19.263995 | controller | { 2025-10-07 07:29:19.264024 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono" 2025-10-07 07:29:19.264058 | controller | } 2025-10-07 07:29:19.311783 | 2025-10-07 07:29:19.311883 | TASK [Set timezone to UTC] 2025-10-07 07:29:20.036246 | controller | changed: executed `/bin/timedatectl set-timezone UTC` 2025-10-07 07:29:20.041115 | 2025-10-07 07:29:20.041207 | TASK [Create nodepool directory] 2025-10-07 07:29:20.264113 | controller | changed 2025-10-07 07:29:20.270830 | 2025-10-07 07:29:20.270945 | TASK [Create nodepool sub_nodes file] 2025-10-07 07:29:21.009841 | controller | changed 2025-10-07 07:29:21.016552 | 2025-10-07 07:29:21.016634 | TASK [Create nodepool sub_nodes_private file] 2025-10-07 07:29:21.759260 | controller | changed 2025-10-07 07:29:21.765241 | 2025-10-07 07:29:21.765319 | LOOP [Populate nodepool sub_nodes file] 2025-10-07 07:29:21.894021 | 2025-10-07 07:29:21.894166 | LOOP [Populate nodepool sub_nodes_private file] 2025-10-07 07:29:21.968482 | 2025-10-07 07:29:21.968661 | TASK [Create nodepool primary file] 2025-10-07 07:29:22.032743 | controller | skipping: Conditional result was False 2025-10-07 07:29:22.038176 | 2025-10-07 07:29:22.038267 | TASK [Create nodepool node_private for this node] 2025-10-07 07:29:22.780975 | controller | changed 2025-10-07 07:29:22.793020 | 2025-10-07 07:29:22.793926 | LOOP [Copy ssh keys to nodepool directory] 2025-10-07 07:29:23.761292 | controller | ok: Item: id_rsa Runtime: 0:00:00.006761 2025-10-07 07:29:23.761471 | 2025-10-07 07:29:23.954033 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.004375 2025-10-07 07:29:23.971404 | 2025-10-07 07:29:23.971485 | TASK [Add sudoers role for zuul-sudo-grep.sh] 2025-10-07 07:29:24.855331 | controller | changed 2025-10-07 07:29:24.860465 | 2025-10-07 07:29:24.860528 | TASK [Validate sudoers config after edits] 2025-10-07 07:29:25.273955 | controller | /etc/sudoers: parsed OK 2025-10-07 07:29:25.274033 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK 2025-10-07 07:29:25.274054 | controller | /etc/sudoers.d/zuul: parsed OK 2025-10-07 07:29:25.274060 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK 2025-10-07 07:29:25.509923 | controller | ok: Runtime: 0:00:00.005175 2025-10-07 07:29:25.516160 | 2025-10-07 07:29:25.516251 | TASK [Show the environment passed in to job shell scripts] 2025-10-07 07:29:25.863815 | controller | SHELL=/bin/bash 2025-10-07 07:29:25.863866 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework 2025-10-07 07:29:25.863876 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework 2025-10-07 07:29:25.863883 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/66/3366/b916ef9cc83cd6f61cb4c8a27bbc55fc27d7eaae 2025-10-07 07:29:25.863889 | controller | PWD=/home/zuul 2025-10-07 07:29:25.863894 | controller | ZUUL_PIPELINE=github-check 2025-10-07 07:29:25.863900 | controller | LOGNAME=zuul 2025-10-07 07:29:25.863928 | controller | XDG_SESSION_TYPE=tty 2025-10-07 07:29:25.863934 | controller | _=/usr/bin/env 2025-10-07 07:29:25.863948 | controller | MOTD_SHOWN=pam 2025-10-07 07:29:25.863953 | controller | HOME=/home/zuul 2025-10-07 07:29:25.863964 | controller | LANG=en_US.UTF-8 2025-10-07 07:29:25.864012 | controller | SSH_CONNECTION=192.168.26.12 41318 192.168.26.106 22 2025-10-07 07:29:25.864021 | 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-07 07:29:25.864030 | controller | ZUUL_CHANGE_IDS=3366,b916ef9cc83cd6f61cb4c8a27bbc55fc27d7eaae 2025-10-07 07:29:25.864036 | controller | WORKSPACE=/home/zuul/workspace 2025-10-07 07:29:25.864042 | controller | XDG_SESSION_CLASS=user 2025-10-07 07:29:25.864047 | controller | SELINUX_ROLE_REQUESTED= 2025-10-07 07:29:25.864053 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s 2025-10-07 07:29:25.864058 | controller | USER=zuul 2025-10-07 07:29:25.864075 | controller | ZUUL_VOTING=True 2025-10-07 07:29:25.864081 | controller | BUILD_TIMEOUT=1800000 2025-10-07 07:29:25.864086 | controller | SELINUX_USE_CURRENT_RANGE= 2025-10-07 07:29:25.864091 | controller | SHLVL=1 2025-10-07 07:29:25.864097 | controller | ZUUL_PATCHSET=b916ef9cc83cd6f61cb4c8a27bbc55fc27d7eaae 2025-10-07 07:29:25.864102 | controller | XDG_SESSION_ID=1 2025-10-07 07:29:25.864108 | controller | ZUUL_BRANCH=main 2025-10-07 07:29:25.864113 | controller | XDG_RUNTIME_DIR=/run/user/1000 2025-10-07 07:29:25.864118 | controller | SSH_CLIENT=192.168.26.12 41318 22 2025-10-07 07:29:25.864124 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/ 2025-10-07 07:29:25.864129 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima: 2025-10-07 07:29:25.864135 | controller | which_declare=declare -f 2025-10-07 07:29:25.864141 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin 2025-10-07 07:29:25.864146 | controller | SELINUX_LEVEL_REQUESTED= 2025-10-07 07:29:25.864152 | controller | ZUUL_CHANGE=3366 2025-10-07 07:29:25.864157 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus 2025-10-07 07:29:25.864162 | controller | ZUUL_UUID=007f961ce0ae472c9ca349317c552dce 2025-10-07 07:29:25.864168 | controller | BASH_FUNC_which%%=() { ( alias; 2025-10-07 07:29:25.864173 | controller | eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@ 2025-10-07 07:29:25.864179 | controller | } 2025-10-07 07:29:26.093046 | controller | ok: Runtime: 0:00:00.006429 2025-10-07 07:29:26.100364 | 2025-10-07 07:29:26.100428 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars] 2025-10-07 07:29:26.164658 | controller | skipping: Conditional result was False 2025-10-07 07:29:26.170229 | 2025-10-07 07:29:26.170297 | TASK [Symlink /home/zuul-worker/workspace] 2025-10-07 07:29:26.866057 | controller | skipping: Conditional result was False 2025-10-07 07:29:26.875214 | 2025-10-07 07:29:26.875288 | TASK [Ensure legacy workspace directory] 2025-10-07 07:29:27.188980 | controller | changed 2025-10-07 07:29:27.263051 | 2025-10-07 07:29:27.263128 | PLAY RECAP 2025-10-07 07:29:27.263173 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0 2025-10-07 07:29:27.263208 | 2025-10-07 07:29:27.615611 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master] 2025-10-07 07:29:27.616495 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main] 2025-10-07 07:29:29.116876 | 2025-10-07 07:29:29.123022 | PLAY [Run ci/playbooks/dump_zuul_data.yml] 2025-10-07 07:29:29.192997 | 2025-10-07 07:29:29.193102 | TASK [Create zuul-output directory] 2025-10-07 07:29:29.722149 | controller | changed 2025-10-07 07:29:29.738545 | 2025-10-07 07:29:29.738663 | TASK [Slurp Zuul inventory test] 2025-10-07 07:29:30.596380 | controller -> localhost | ok 2025-10-07 07:29:30.605076 | 2025-10-07 07:29:30.605252 | TASK [Save zuul inventory] 2025-10-07 07:29:32.412354 | controller | changed 2025-10-07 07:29:32.417446 | 2025-10-07 07:29:32.417518 | TASK [Save zuul vars without the change_message] 2025-10-07 07:29:33.518991 | controller | changed 2025-10-07 07:29:33.594447 | 2025-10-07 07:29:33.594631 | PLAY RECAP 2025-10-07 07:29:33.594872 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-07 07:29:33.594920 | 2025-10-07 07:29:34.078805 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main] 2025-10-07 07:29:34.079579 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main] 2025-10-07 07:29:36.449233 | 2025-10-07 07:29:36.449351 | PLAY [Run ci/playbooks/molecule-prepare.yml] 2025-10-07 07:29:36.515392 | 2025-10-07 07:29:36.515516 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh] 2025-10-07 07:29:36.565754 | controller | ok 2025-10-07 07:29:36.626708 | 2025-10-07 07:29:36.626841 | TASK [mirror-info-fork : Set mirror_fqdn if not defined] 2025-10-07 07:29:36.742193 | controller | skipping: Conditional result was False 2025-10-07 07:29:36.751663 | 2025-10-07 07:29:36.751756 | TASK [mirror-info-fork : Create /etc/ci] 2025-10-07 07:29:37.439303 | controller | ok 2025-10-07 07:29:37.445095 | 2025-10-07 07:29:37.445167 | TASK [mirror-info-fork : Install ci_mirror script] 2025-10-07 07:29:38.562025 | controller | ok 2025-10-07 07:29:38.600427 | 2025-10-07 07:29:38.600514 | TASK [Prepare workspace] 2025-10-07 07:29:38.659505 | controller | ok 2025-10-07 07:29:38.708384 | 2025-10-07 07:29:38.708477 | TASK [prepare-workspace : Start zuul_console daemon.] 2025-10-07 07:29:39.280355 | controller | ok 2025-10-07 07:29:39.286966 | 2025-10-07 07:29:39.287038 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2025-10-07 07:29:43.884057 | controller | Output suppressed because no_log was given 2025-10-07 07:29:43.898427 | 2025-10-07 07:29:43.898511 | LOOP [Create zuul-output directory] 2025-10-07 07:29:44.148713 | controller | changed: "/home/zuul/ci-framework-data/logs" 2025-10-07 07:29:44.330154 | controller | ok: "/home/zuul/zuul-output/logs" 2025-10-07 07:29:44.344860 | 2025-10-07 07:29:44.344982 | TASK [Install required packages] 2025-10-07 07:30:36.129259 | controller | changed 2025-10-07 07:30:36.135942 | 2025-10-07 07:30:36.136034 | TASK [Install venv] 2025-10-07 07:31:40.717491 | controller | changed 2025-10-07 07:31:40.743337 | 2025-10-07 07:31:40.743401 | PLAY RECAP 2025-10-07 07:31:40.743445 | controller | ok: 7 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2025-10-07 07:31:40.743487 | 2025-10-07 07:31:40.816807 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main] 2025-10-07 07:31:40.817671 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main] 2025-10-07 07:31:41.340423 | 2025-10-07 07:31:41.340537 | PLAY [Run ci/playbooks/molecule-test.yml] 2025-10-07 07:31:41.361286 | 2025-10-07 07:31:41.361359 | TASK [Gather required facts] 2025-10-07 07:31:41.848266 | controller | ok 2025-10-07 07:31:41.853652 | 2025-10-07 07:31:41.853744 | TASK [Load environment var if instructed to] 2025-10-07 07:31:41.877727 | controller | skipping: Conditional result was False 2025-10-07 07:31:41.882981 | 2025-10-07 07:31:41.883050 | TASK [Check if group_vars dir exists] 2025-10-07 07:31:42.195167 | controller | ok 2025-10-07 07:31:42.201384 | 2025-10-07 07:31:42.201488 | TASK [Create group_vars dir if does not exist] 2025-10-07 07:31:42.214975 | controller | skipping: Conditional result was False 2025-10-07 07:31:42.220614 | 2025-10-07 07:31:42.220691 | TASK [Run molecule] 2025-10-07 07:31:43.178853 | controller | INFO Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml 2025-10-07 07:31:43.250173 | controller | WARNING Driver podman does not provide a schema. 2025-10-07 07:31:43.252933 | controller | INFO Performing prerun with role_name_check=0... 2025-10-07 07:32:04.362667 | controller | WARNING Another version of '@NAMESPACE@.@NAME@' 3.1.4 was found installed in /home/zuul/.cache/ansible-compat/8256b2/collections/ansible_collections, only the first one will be used, 3.1.4 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-07 07:32:04.363100 | controller | WARNING Another version of 'ansible.netcommon' 7.1.0 was found installed in /home/zuul/.cache/ansible-compat/8256b2/collections/ansible_collections, only the first one will be used, 7.1.0 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-07 07:32:04.363564 | controller | WARNING Another version of 'ansible.posix' 1.6.2 was found installed in /home/zuul/.cache/ansible-compat/8256b2/collections/ansible_collections, only the first one will be used, 1.6.2 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-07 07:32:04.364026 | controller | WARNING Another version of 'ansible.utils' 5.1.2 was found installed in /home/zuul/.cache/ansible-compat/8256b2/collections/ansible_collections, only the first one will be used, 5.1.2 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-07 07:32:04.364514 | controller | WARNING Another version of 'cifmw.general' 1.0.0+f5954785 was found installed in /home/zuul/.cache/ansible-compat/8256b2/collections/ansible_collections, only the first one will be used, 1.0.0+f5954785 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-07 07:32:04.364967 | controller | WARNING Another version of 'cisco.ios' 9.0.3 was found installed in /home/zuul/.cache/ansible-compat/8256b2/collections/ansible_collections, only the first one will be used, 9.0.3 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-07 07:32:04.365407 | controller | WARNING Another version of 'community.crypto' 2.22.3 was found installed in /home/zuul/.cache/ansible-compat/8256b2/collections/ansible_collections, only the first one will be used, 2.22.3 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-07 07:32:04.365859 | controller | WARNING Another version of 'community.general' 10.0.1 was found installed in /home/zuul/.cache/ansible-compat/8256b2/collections/ansible_collections, only the first one will be used, 10.0.1 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-07 07:32:04.366304 | controller | WARNING Another version of 'community.libvirt' 1.3.0 was found installed in /home/zuul/.cache/ansible-compat/8256b2/collections/ansible_collections, only the first one will be used, 1.3.0 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-07 07:32:04.366767 | controller | WARNING Another version of 'community.okd' 4.0.0 was found installed in /home/zuul/.cache/ansible-compat/8256b2/collections/ansible_collections, only the first one will be used, 4.0.0 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-07 07:32:04.367257 | controller | WARNING Another version of 'containers.podman' 1.16.2 was found installed in /home/zuul/.cache/ansible-compat/8256b2/collections/ansible_collections, only the first one will be used, 1.16.2 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-07 07:32:04.367735 | controller | WARNING Another version of 'junipernetworks.junos' 9.1.0 was found installed in /home/zuul/.cache/ansible-compat/8256b2/collections/ansible_collections, only the first one will be used, 9.1.0 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-07 07:32:04.368173 | controller | WARNING Another version of 'kubernetes.core' 5.0.0 was found installed in /home/zuul/.cache/ansible-compat/8256b2/collections/ansible_collections, only the first one will be used, 5.0.0 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-07 07:32:04.368620 | controller | WARNING Another version of 'mellanox.onyx' 1.0.0 was found installed in /home/zuul/.cache/ansible-compat/8256b2/collections/ansible_collections, only the first one will be used, 1.0.0 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-07 07:32:04.369075 | controller | WARNING Another version of 'openstack.config_template' 2.1.1 was found installed in /home/zuul/.cache/ansible-compat/8256b2/collections/ansible_collections, only the first one will be used, 2.1.1 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-07 07:32:04.380613 | controller | INFO Running default > dependency 2025-10-07 07:32:04.381590 | controller | WARNING Skipping, missing the requirements file. 2025-10-07 07:32:04.382338 | controller | WARNING Skipping, missing the requirements file. 2025-10-07 07:32:04.391469 | controller | INFO Running default > create 2025-10-07 07:32:04.631302 | controller | INFO Sanity checks: 'podman' 2025-10-07 07:32:05.529539 | controller | 2025-10-07 07:32:05.529638 | controller | PLAY [Create] ****************************************************************** 2025-10-07 07:32:05.529735 | controller | 2025-10-07 07:32:05.529838 | controller | TASK [Get podman executable path] ********************************************** 2025-10-07 07:32:05.529967 | controller | Tuesday 07 October 2025 07:32:05 +0000 (0:00:00.014) 0:00:00.014 ******* 2025-10-07 07:32:05.830376 | controller | ok: [localhost] 2025-10-07 07:32:05.830445 | controller | 2025-10-07 07:32:05.830578 | controller | TASK [Register podman executable path] ***************************************** 2025-10-07 07:32:05.830687 | controller | Tuesday 07 October 2025 07:32:05 +0000 (0:00:00.301) 0:00:00.315 ******* 2025-10-07 07:32:05.871485 | controller | ok: [localhost] 2025-10-07 07:32:05.871538 | controller | 2025-10-07 07:32:05.871665 | controller | TASK [Get passwd entries for USER env] ***************************************** 2025-10-07 07:32:05.871771 | controller | Tuesday 07 October 2025 07:32:05 +0000 (0:00:00.041) 0:00:00.357 ******* 2025-10-07 07:32:06.171413 | controller | ok: [localhost] 2025-10-07 07:32:06.171474 | controller | 2025-10-07 07:32:06.171585 | controller | TASK [Get shell async_dir] ***************************************************** 2025-10-07 07:32:06.171689 | controller | Tuesday 07 October 2025 07:32:06 +0000 (0:00:00.296) 0:00:00.653 ******* 2025-10-07 07:32:06.215661 | controller | ok: [localhost] 2025-10-07 07:32:06.215733 | controller | 2025-10-07 07:32:06.215837 | controller | TASK [Set async_dir for HOME env] ********************************************** 2025-10-07 07:32:06.215963 | controller | Tuesday 07 October 2025 07:32:06 +0000 (0:00:00.047) 0:00:00.701 ******* 2025-10-07 07:32:06.250201 | controller | skipping: [localhost] 2025-10-07 07:32:06.250309 | controller | 2025-10-07 07:32:06.250435 | controller | TASK [Log into a container registry] ******************************************* 2025-10-07 07:32:06.250582 | controller | Tuesday 07 October 2025 07:32:06 +0000 (0:00:00.034) 0:00:00.735 ******* 2025-10-07 07:32:06.295592 | controller | skipping: [localhost] => (item="instance registry username: None specified") 2025-10-07 07:32:06.295691 | controller | skipping: [localhost] 2025-10-07 07:32:06.295791 | controller | 2025-10-07 07:32:06.295897 | controller | TASK [Check presence of custom Dockerfiles] ************************************ 2025-10-07 07:32:06.296028 | controller | Tuesday 07 October 2025 07:32:06 +0000 (0:00:00.045) 0:00:00.781 ******* 2025-10-07 07:32:06.595777 | controller | ok: [localhost] => (item=Dockerfile: None specified) 2025-10-07 07:32:06.595854 | controller | 2025-10-07 07:32:06.596025 | controller | TASK [Create Dockerfiles from image names] ************************************* 2025-10-07 07:32:06.596135 | controller | Tuesday 07 October 2025 07:32:06 +0000 (0:00:00.300) 0:00:01.081 ******* 2025-10-07 07:32:06.645612 | controller | skipping: [localhost] => (item="Dockerfile: None specified; Image: registry.access.redhat.com/ubi9/ubi:latest") 2025-10-07 07:32:06.645743 | controller | skipping: [localhost] 2025-10-07 07:32:06.645847 | controller | 2025-10-07 07:32:06.645981 | controller | TASK [Discover local Podman images] ******************************************** 2025-10-07 07:32:06.646087 | controller | Tuesday 07 October 2025 07:32:06 +0000 (0:00:00.049) 0:00:01.130 ******* 2025-10-07 07:32:07.546637 | controller | ok: [localhost] => (item=instance) 2025-10-07 07:32:07.546773 | controller | 2025-10-07 07:32:07.546958 | controller | TASK [Build an Ansible compatible image] *************************************** 2025-10-07 07:32:07.547068 | controller | Tuesday 07 October 2025 07:32:07 +0000 (0:00:00.901) 0:00:02.032 ******* 2025-10-07 07:32:07.587780 | controller | skipping: [localhost] => (item=registry.access.redhat.com/ubi9/ubi:latest) 2025-10-07 07:32:07.587887 | controller | skipping: [localhost] 2025-10-07 07:32:07.588030 | controller | 2025-10-07 07:32:07.588141 | controller | TASK [Determine the CMD directives] ******************************************** 2025-10-07 07:32:07.588251 | controller | Tuesday 07 October 2025 07:32:07 +0000 (0:00:00.041) 0:00:02.073 ******* 2025-10-07 07:32:07.637788 | controller | ok: [localhost] => (item="instance command: sleep infinity") 2025-10-07 07:32:07.637882 | controller | 2025-10-07 07:32:07.638012 | controller | TASK [Remove possible pre-existing containers] ********************************* 2025-10-07 07:32:07.638118 | controller | Tuesday 07 October 2025 07:32:07 +0000 (0:00:00.049) 0:00:02.123 ******* 2025-10-07 07:32:07.875707 | controller | changed: [localhost] 2025-10-07 07:32:07.875804 | controller | 2025-10-07 07:32:07.875956 | controller | TASK [Discover local podman networks] ****************************************** 2025-10-07 07:32:07.876108 | controller | Tuesday 07 October 2025 07:32:07 +0000 (0:00:00.237) 0:00:02.361 ******* 2025-10-07 07:32:07.919856 | controller | skipping: [localhost] => (item=instance: None specified) 2025-10-07 07:32:07.919956 | controller | skipping: [localhost] 2025-10-07 07:32:07.920069 | controller | 2025-10-07 07:32:07.920177 | controller | TASK [Create podman network dedicated to this scenario] ************************ 2025-10-07 07:32:07.920283 | controller | Tuesday 07 October 2025 07:32:07 +0000 (0:00:00.044) 0:00:02.405 ******* 2025-10-07 07:32:07.958267 | controller | skipping: [localhost] 2025-10-07 07:32:07.958320 | controller | 2025-10-07 07:32:07.958643 | controller | TASK [Create molecule instance(s)] ********************************************* 2025-10-07 07:32:07.958755 | controller | Tuesday 07 October 2025 07:32:07 +0000 (0:00:00.038) 0:00:02.443 ******* 2025-10-07 07:32:08.291043 | controller | changed: [localhost] => (item=instance) 2025-10-07 07:32:08.291102 | controller | 2025-10-07 07:32:08.291215 | controller | TASK [Wait for instance(s) creation to complete] ******************************* 2025-10-07 07:32:08.291318 | controller | Tuesday 07 October 2025 07:32:08 +0000 (0:00:00.332) 0:00:02.776 ******* 2025-10-07 07:32:13.777034 | controller | FAILED - RETRYING: [localhost]: Wait for instance(s) creation to complete (300 retries left). 2025-10-07 07:32:13.777096 | controller | changed: [localhost] => (item=instance) 2025-10-07 07:32:13.779393 | controller | 2025-10-07 07:32:13.830682 | controller | PLAY RECAP ********************************************************************* 2025-10-07 07:32:13.830728 | controller | localhost : ok=10 changed=3 unreachable=0 failed=0 skipped=6 rescued=0 ignored=0 2025-10-07 07:32:13.830737 | controller | 2025-10-07 07:32:13.830744 | controller | Tuesday 07 October 2025 07:32:13 +0000 (0:00:05.486) 0:00:08.262 ******* 2025-10-07 07:32:13.830749 | controller | =============================================================================== 2025-10-07 07:32:13.830755 | controller | Wait for instance(s) creation to complete ------------------------------- 5.49s 2025-10-07 07:32:13.830760 | controller | Discover local Podman images -------------------------------------------- 0.90s 2025-10-07 07:32:13.830766 | controller | Create molecule instance(s) --------------------------------------------- 0.33s 2025-10-07 07:32:13.830771 | controller | Get podman executable path ---------------------------------------------- 0.30s 2025-10-07 07:32:13.830777 | controller | Check presence of custom Dockerfiles ------------------------------------ 0.30s 2025-10-07 07:32:13.830782 | controller | Get passwd entries for USER env ----------------------------------------- 0.30s 2025-10-07 07:32:13.830794 | controller | Remove possible pre-existing containers --------------------------------- 0.24s 2025-10-07 07:32:13.830799 | controller | Determine the CMD directives -------------------------------------------- 0.05s 2025-10-07 07:32:13.830806 | controller | Create Dockerfiles from image names ------------------------------------- 0.05s 2025-10-07 07:32:13.830811 | controller | Get shell async_dir ----------------------------------------------------- 0.05s 2025-10-07 07:32:13.830816 | controller | Log into a container registry ------------------------------------------- 0.05s 2025-10-07 07:32:13.830822 | controller | Discover local podman networks ------------------------------------------ 0.04s 2025-10-07 07:32:13.830827 | controller | Build an Ansible compatible image --------------------------------------- 0.04s 2025-10-07 07:32:13.830840 | controller | Register podman executable path ----------------------------------------- 0.04s 2025-10-07 07:32:13.830845 | controller | Create podman network dedicated to this scenario ------------------------ 0.04s 2025-10-07 07:32:13.830851 | controller | Set async_dir for HOME env ---------------------------------------------- 0.03s 2025-10-07 07:32:13.830865 | controller | INFO Running default > prepare 2025-10-07 07:32:14.244815 | controller | 2025-10-07 07:32:14.244878 | controller | PLAY [Prepare] ***************************************************************** 2025-10-07 07:32:14.245068 | controller | 2025-10-07 07:32:14.245173 | controller | TASK [Gathering Facts] ********************************************************* 2025-10-07 07:32:14.245292 | controller | Tuesday 07 October 2025 07:32:14 +0000 (0:00:00.010) 0:00:00.010 ******* 2025-10-07 07:32:15.367689 | controller | ok: [instance] 2025-10-07 07:32:22.083327 | controller | 2025-10-07 07:32:22.083360 | controller | TASK [Install Python pip and dependencies using dnf] *************************** 2025-10-07 07:32:22.083369 | controller | Tuesday 07 October 2025 07:32:15 +0000 (0:00:01.122) 0:00:01.132 ******* 2025-10-07 07:32:22.083379 | controller | changed: [instance] 2025-10-07 07:32:27.366559 | controller | 2025-10-07 07:32:27.366594 | controller | TASK [Install Python dependencies] ********************************************* 2025-10-07 07:32:27.366603 | controller | Tuesday 07 October 2025 07:32:22 +0000 (0:00:06.716) 0:00:07.849 ******* 2025-10-07 07:32:27.366613 | controller | changed: [instance] 2025-10-07 07:32:28.902645 | controller | 2025-10-07 07:32:28.902675 | controller | TASK [Create mock kubeconfig file] ********************************************* 2025-10-07 07:32:28.902683 | controller | Tuesday 07 October 2025 07:32:27 +0000 (0:00:05.283) 0:00:13.132 ******* 2025-10-07 07:32:28.902693 | controller | changed: [instance] 2025-10-07 07:32:30.338972 | controller | 2025-10-07 07:32:30.339003 | controller | TASK [Create mock kubeadmin password file] ************************************* 2025-10-07 07:32:30.339012 | controller | Tuesday 07 October 2025 07:32:28 +0000 (0:00:01.536) 0:00:14.668 ******* 2025-10-07 07:32:30.339022 | controller | changed: [instance] 2025-10-07 07:32:30.339048 | controller | 2025-10-07 07:32:30.339054 | controller | PLAY RECAP ********************************************************************* 2025-10-07 07:32:30.339060 | controller | instance : ok=5 changed=4 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2025-10-07 07:32:30.339066 | controller | 2025-10-07 07:32:30.339071 | controller | Tuesday 07 October 2025 07:32:30 +0000 (0:00:01.435) 0:00:16.104 ******* 2025-10-07 07:32:30.339077 | controller | =============================================================================== 2025-10-07 07:32:30.339082 | controller | Install Python pip and dependencies using dnf --------------------------- 6.72s 2025-10-07 07:32:30.339087 | controller | Install Python dependencies --------------------------------------------- 5.28s 2025-10-07 07:32:30.339093 | controller | Create mock kubeconfig file --------------------------------------------- 1.54s 2025-10-07 07:32:30.339098 | controller | Create mock kubeadmin password file ------------------------------------- 1.44s 2025-10-07 07:32:30.339106 | controller | Gathering Facts --------------------------------------------------------- 1.12s 2025-10-07 07:32:30.396459 | controller | INFO Running default > converge 2025-10-07 07:32:30.851583 | controller | 2025-10-07 07:32:30.877825 | controller | PLAY [Converge] **************************************************************** 2025-10-07 07:32:30.877845 | controller | 2025-10-07 07:32:30.877858 | controller | TASK [Test that required variables are defined] ******************************** 2025-10-07 07:32:30.877865 | controller | Tuesday 07 October 2025 07:32:30 +0000 (0:00:00.022) 0:00:00.022 ******* 2025-10-07 07:32:30.877874 | controller | ok: [instance] => changed=false 2025-10-07 07:32:30.899000 | controller | msg: Required variables are defined 2025-10-07 07:32:30.899022 | controller | 2025-10-07 07:32:30.899032 | controller | TASK [Display test information] ************************************************ 2025-10-07 07:32:30.899039 | controller | Tuesday 07 October 2025 07:32:30 +0000 (0:00:00.026) 0:00:00.049 ******* 2025-10-07 07:32:30.899050 | controller | ok: [instance] => 2025-10-07 07:32:31.993878 | controller | msg: 'Testing role cifmw_snr_nhc with kubeconfig: /tmp/kubeconfig and namespace: workload-availability' 2025-10-07 07:32:31.993937 | controller | 2025-10-07 07:32:31.993948 | controller | TASK [Test that Python kubernetes library is available] ************************ 2025-10-07 07:32:31.993960 | controller | Tuesday 07 October 2025 07:32:30 +0000 (0:00:00.021) 0:00:00.071 ******* 2025-10-07 07:32:31.993971 | controller | ok: [instance] 2025-10-07 07:32:31.993991 | controller | 2025-10-07 07:32:31.994231 | controller | TASK [Display kubernetes library test result] ********************************** 2025-10-07 07:32:32.018853 | controller | Tuesday 07 October 2025 07:32:31 +0000 (0:00:01.094) 0:00:01.165 ******* 2025-10-07 07:32:32.018882 | controller | ok: [instance] => 2025-10-07 07:32:32.831027 | controller | msg: Library available 2025-10-07 07:32:32.831063 | controller | 2025-10-07 07:32:32.831071 | controller | TASK [Test that mock kubeconfig exists] **************************************** 2025-10-07 07:32:32.831077 | controller | Tuesday 07 October 2025 07:32:32 +0000 (0:00:00.024) 0:00:01.190 ******* 2025-10-07 07:32:32.831088 | controller | ok: [instance] 2025-10-07 07:32:32.831350 | controller | 2025-10-07 07:32:32.856866 | controller | TASK [Assert kubeconfig exists] ************************************************ 2025-10-07 07:32:32.856893 | controller | Tuesday 07 October 2025 07:32:32 +0000 (0:00:00.812) 0:00:02.003 ******* 2025-10-07 07:32:32.856924 | controller | ok: [instance] => changed=false 2025-10-07 07:32:34.302138 | controller | msg: Kubeconfig file exists 2025-10-07 07:32:34.302168 | controller | 2025-10-07 07:32:34.302176 | controller | TASK [Test kubernetes.core.k8s module availability] **************************** 2025-10-07 07:32:34.302182 | controller | Tuesday 07 October 2025 07:32:32 +0000 (0:00:00.025) 0:00:02.028 ******* 2025-10-07 07:32:34.302192 | controller | ok: [instance] 2025-10-07 07:32:34.323198 | controller | 2025-10-07 07:32:34.323224 | controller | TASK [Display k8s connection test result] ************************************** 2025-10-07 07:32:34.323232 | controller | Tuesday 07 October 2025 07:32:34 +0000 (0:00:01.445) 0:00:03.474 ******* 2025-10-07 07:32:34.323242 | controller | ok: [instance] => 2025-10-07 07:32:34.375828 | controller | msg: 'K8s connection test result: False' 2025-10-07 07:32:34.375854 | controller | 2025-10-07 07:32:34.375866 | controller | TASK [Include the cifmw_snr_nhc role] ****************************************** 2025-10-07 07:32:34.375872 | controller | Tuesday 07 October 2025 07:32:34 +0000 (0:00:00.021) 0:00:03.495 ******* 2025-10-07 07:32:34.375881 | controller | 2025-10-07 07:32:34.401996 | controller | TASK [cifmw_snr_nhc : Check if NodeHealthCheck exists and is active] *********** 2025-10-07 07:32:34.402027 | controller | Tuesday 07 October 2025 07:32:34 +0000 (0:00:00.052) 0:00:03.548 ******* 2025-10-07 07:32:34.402040 | controller | skipping: [instance] 2025-10-07 07:32:34.402098 | controller | 2025-10-07 07:32:34.402118 | controller | TASK [cifmw_snr_nhc : Check for active SelfNodeRemediations] ******************* 2025-10-07 07:32:34.402130 | controller | Tuesday 07 October 2025 07:32:34 +0000 (0:00:00.026) 0:00:03.574 ******* 2025-10-07 07:32:34.427776 | controller | skipping: [instance] 2025-10-07 07:32:34.453719 | controller | 2025-10-07 07:32:34.453741 | controller | TASK [cifmw_snr_nhc : Display active remediations info] ************************ 2025-10-07 07:32:34.453749 | controller | Tuesday 07 October 2025 07:32:34 +0000 (0:00:00.025) 0:00:03.600 ******* 2025-10-07 07:32:34.453759 | controller | skipping: [instance] 2025-10-07 07:32:34.480804 | controller | 2025-10-07 07:32:34.480827 | controller | TASK [cifmw_snr_nhc : Disable NodeHealthCheck to stop active remediations] ***** 2025-10-07 07:32:34.480835 | controller | Tuesday 07 October 2025 07:32:34 +0000 (0:00:00.025) 0:00:03.625 ******* 2025-10-07 07:32:34.480844 | controller | skipping: [instance] 2025-10-07 07:32:34.508888 | controller | 2025-10-07 07:32:34.508929 | controller | TASK [cifmw_snr_nhc : Wait for active remediations to stop] ******************** 2025-10-07 07:32:34.508940 | controller | Tuesday 07 October 2025 07:32:34 +0000 (0:00:00.027) 0:00:03.653 ******* 2025-10-07 07:32:34.508949 | controller | skipping: [instance] 2025-10-07 07:32:34.547989 | controller | 2025-10-07 07:32:34.548022 | controller | TASK [cifmw_snr_nhc : Delete existing NodeHealthCheck resources] *************** 2025-10-07 07:32:34.548031 | controller | Tuesday 07 October 2025 07:32:34 +0000 (0:00:00.027) 0:00:03.680 ******* 2025-10-07 07:32:34.548041 | controller | skipping: [instance] 2025-10-07 07:32:34.574209 | controller | 2025-10-07 07:32:34.574230 | controller | TASK [cifmw_snr_nhc : Check for blocking remediations when deletion fails] ***** 2025-10-07 07:32:34.574254 | controller | Tuesday 07 October 2025 07:32:34 +0000 (0:00:00.039) 0:00:03.720 ******* 2025-10-07 07:32:34.574266 | controller | skipping: [instance] 2025-10-07 07:32:34.599861 | controller | 2025-10-07 07:32:34.599880 | controller | TASK [cifmw_snr_nhc : Display blocking remediation details] ******************** 2025-10-07 07:32:34.599887 | controller | Tuesday 07 October 2025 07:32:34 +0000 (0:00:00.026) 0:00:03.746 ******* 2025-10-07 07:32:34.599896 | controller | skipping: [instance] 2025-10-07 07:32:34.626039 | controller | 2025-10-07 07:32:34.626056 | controller | TASK [cifmw_snr_nhc : Display warning if NodeHealthCheck deletion failed due to active remediation] *** 2025-10-07 07:32:34.626063 | controller | Tuesday 07 October 2025 07:32:34 +0000 (0:00:00.025) 0:00:03.772 ******* 2025-10-07 07:32:34.626072 | controller | skipping: [instance] 2025-10-07 07:32:34.651959 | controller | 2025-10-07 07:32:34.651975 | controller | TASK [cifmw_snr_nhc : Skip NodeHealthCheck deletion retry if webhook blocks it] *** 2025-10-07 07:32:34.651982 | controller | Tuesday 07 October 2025 07:32:34 +0000 (0:00:00.026) 0:00:03.798 ******* 2025-10-07 07:32:34.651991 | controller | skipping: [instance] 2025-10-07 07:32:34.677614 | controller | 2025-10-07 07:32:34.677634 | controller | TASK [cifmw_snr_nhc : Check if SelfNodeRemediationConfig exists] *************** 2025-10-07 07:32:34.677642 | controller | Tuesday 07 October 2025 07:32:34 +0000 (0:00:00.025) 0:00:03.824 ******* 2025-10-07 07:32:34.677651 | controller | skipping: [instance] 2025-10-07 07:32:34.705668 | controller | 2025-10-07 07:32:34.705693 | controller | TASK [cifmw_snr_nhc : Delete existing SelfNodeRemediationConfig resources] ***** 2025-10-07 07:32:34.705702 | controller | Tuesday 07 October 2025 07:32:34 +0000 (0:00:00.025) 0:00:03.849 ******* 2025-10-07 07:32:34.705714 | controller | skipping: [instance] 2025-10-07 07:32:34.732013 | controller | 2025-10-07 07:32:34.732049 | controller | TASK [cifmw_snr_nhc : Check if SelfNodeRemediationTemplate exists] ************* 2025-10-07 07:32:34.732059 | controller | Tuesday 07 October 2025 07:32:34 +0000 (0:00:00.027) 0:00:03.877 ******* 2025-10-07 07:32:34.732071 | controller | skipping: [instance] 2025-10-07 07:32:34.732101 | controller | 2025-10-07 07:32:34.732109 | controller | TASK [cifmw_snr_nhc : Delete existing SelfNodeRemediationTemplate resources] *** 2025-10-07 07:32:34.732119 | controller | Tuesday 07 October 2025 07:32:34 +0000 (0:00:00.026) 0:00:03.904 ******* 2025-10-07 07:32:34.757896 | controller | skipping: [instance] 2025-10-07 07:32:34.783457 | controller | 2025-10-07 07:32:34.783478 | controller | TASK [cifmw_snr_nhc : Check if Subscriptions exist] **************************** 2025-10-07 07:32:34.783486 | controller | Tuesday 07 October 2025 07:32:34 +0000 (0:00:00.025) 0:00:03.930 ******* 2025-10-07 07:32:34.783495 | controller | skipping: [instance] 2025-10-07 07:32:34.813392 | controller | 2025-10-07 07:32:34.813414 | controller | TASK [cifmw_snr_nhc : Delete existing Subscriptions] *************************** 2025-10-07 07:32:34.813422 | controller | Tuesday 07 October 2025 07:32:34 +0000 (0:00:00.025) 0:00:03.955 ******* 2025-10-07 07:32:34.813431 | controller | skipping: [instance] => (item=self-node-remediation-operator) 2025-10-07 07:32:34.838491 | controller | skipping: [instance] => (item=node-health-check-operator) 2025-10-07 07:32:34.838511 | controller | skipping: [instance] 2025-10-07 07:32:34.838519 | controller | 2025-10-07 07:32:34.838525 | controller | TASK [cifmw_snr_nhc : Check if OperatorGroup exists] *************************** 2025-10-07 07:32:34.838531 | controller | Tuesday 07 October 2025 07:32:34 +0000 (0:00:00.029) 0:00:03.985 ******* 2025-10-07 07:32:34.838539 | controller | skipping: [instance] 2025-10-07 07:32:34.864232 | controller | 2025-10-07 07:32:34.864255 | controller | TASK [cifmw_snr_nhc : Delete existing OperatorGroup] *************************** 2025-10-07 07:32:34.864263 | controller | Tuesday 07 October 2025 07:32:34 +0000 (0:00:00.025) 0:00:04.010 ******* 2025-10-07 07:32:34.864272 | controller | skipping: [instance] 2025-10-07 07:32:34.892996 | controller | 2025-10-07 07:32:34.893027 | controller | TASK [cifmw_snr_nhc : Delete the entire workload-availability namespace] ******* 2025-10-07 07:32:34.893037 | controller | Tuesday 07 October 2025 07:32:34 +0000 (0:00:00.025) 0:00:04.036 ******* 2025-10-07 07:32:34.893049 | controller | skipping: [instance] 2025-10-07 07:32:34.893077 | controller | 2025-10-07 07:32:34.893087 | controller | TASK [cifmw_snr_nhc : Wait for namespace deletion to complete] ***************** 2025-10-07 07:32:34.893173 | controller | Tuesday 07 October 2025 07:32:34 +0000 (0:00:00.028) 0:00:04.065 ******* 2025-10-07 07:32:34.918994 | controller | skipping: [instance] 2025-10-07 07:32:34.919032 | controller | 2025-10-07 07:32:34.919226 | controller | TASK [cifmw_snr_nhc : Create the workload-availability namespace] ************** 2025-10-07 07:32:36.172672 | controller | Tuesday 07 October 2025 07:32:34 +0000 (0:00:00.026) 0:00:04.091 ******* 2025-10-07 07:32:36.172722 | controller | An exception occurred during task execution. To see the full traceback, use -vvv. The error was: urllib3.exceptions.MaxRetryError: HTTPSConnectionPool(host='api.test.example.com', port=6443): Max retries exceeded with url: /version (Caused by NewConnectionError(': Failed to establish a new connection: [Errno -2] Name or service not known')) 2025-10-07 07:32:36.172941 | controller | fatal: [instance]: FAILED! => changed=false 2025-10-07 07:32:36.172962 | controller | module_stderr: |- 2025-10-07 07:32:36.173337 | controller | Traceback (most recent call last): 2025-10-07 07:32:36.173353 | controller | File "/usr/lib/python3.9/site-packages/urllib3/connection.py", line 169, in _new_conn 2025-10-07 07:32:36.173863 | controller | conn = connection.create_connection( 2025-10-07 07:32:36.173880 | controller | File "/usr/lib/python3.9/site-packages/urllib3/util/connection.py", line 73, in create_connection 2025-10-07 07:32:36.173890 | controller | for res in socket.getaddrinfo(host, port, family, socket.SOCK_STREAM): 2025-10-07 07:32:36.174687 | controller | File "/usr/lib64/python3.9/socket.py", line 966, in getaddrinfo 2025-10-07 07:32:36.174707 | controller | for res in _socket.getaddrinfo(host, port, family, type, proto, flags): 2025-10-07 07:32:36.174718 | controller | socket.gaierror: [Errno -2] Name or service not known 2025-10-07 07:32:36.175339 | controller | 2025-10-07 07:32:36.175357 | controller | During handling of the above exception, another exception occurred: 2025-10-07 07:32:36.175365 | controller | 2025-10-07 07:32:36.175371 | controller | Traceback (most recent call last): 2025-10-07 07:32:36.175376 | controller | File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 700, in urlopen 2025-10-07 07:32:36.175382 | controller | httplib_response = self._make_request( 2025-10-07 07:32:36.175387 | controller | File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 383, in _make_request 2025-10-07 07:32:36.175393 | controller | self._validate_conn(conn) 2025-10-07 07:32:36.175408 | controller | File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 1015, in _validate_conn 2025-10-07 07:32:36.175498 | controller | conn.connect() 2025-10-07 07:32:36.175526 | controller | File "/usr/lib/python3.9/site-packages/urllib3/connection.py", line 353, in connect 2025-10-07 07:32:36.175534 | controller | conn = self._new_conn() 2025-10-07 07:32:36.175541 | controller | File "/usr/lib/python3.9/site-packages/urllib3/connection.py", line 181, in _new_conn 2025-10-07 07:32:36.175547 | controller | raise NewConnectionError( 2025-10-07 07:32:36.175557 | controller | urllib3.exceptions.NewConnectionError: : Failed to establish a new connection: [Errno -2] Name or service not known 2025-10-07 07:32:36.175585 | controller | 2025-10-07 07:32:36.175738 | controller | During handling of the above exception, another exception occurred: 2025-10-07 07:32:36.175873 | controller | 2025-10-07 07:32:36.176057 | controller | Traceback (most recent call last): 2025-10-07 07:32:36.176211 | controller | File "/tmp/ansible-tmp-1759822354.9321191-28186-238063770760949/AnsiballZ_k8s.py", line 107, in 2025-10-07 07:32:36.176351 | controller | _ansiballz_main() 2025-10-07 07:32:36.176497 | controller | File "/tmp/ansible-tmp-1759822354.9321191-28186-238063770760949/AnsiballZ_k8s.py", line 99, in _ansiballz_main 2025-10-07 07:32:36.176654 | controller | invoke_module(zipped_mod, temp_path, ANSIBALLZ_PARAMS) 2025-10-07 07:32:36.176803 | controller | File "/tmp/ansible-tmp-1759822354.9321191-28186-238063770760949/AnsiballZ_k8s.py", line 47, in invoke_module 2025-10-07 07:32:36.176997 | controller | runpy.run_module(mod_name='ansible_collections.kubernetes.core.plugins.modules.k8s', init_globals=dict(_module_fqn='ansible_collections.kubernetes.core.plugins.modules.k8s', _modlib_path=modlib_path), 2025-10-07 07:32:36.177157 | controller | File "/usr/lib64/python3.9/runpy.py", line 225, in run_module 2025-10-07 07:32:36.177318 | controller | return _run_module_code(code, init_globals, run_name, mod_spec) 2025-10-07 07:32:36.177458 | controller | File "/usr/lib64/python3.9/runpy.py", line 97, in _run_module_code 2025-10-07 07:32:36.177614 | controller | _run_code(code, mod_globals, init_globals, 2025-10-07 07:32:36.177753 | controller | File "/usr/lib64/python3.9/runpy.py", line 87, in _run_code 2025-10-07 07:32:36.177888 | controller | exec(code, run_globals) 2025-10-07 07:32:36.178057 | controller | File "/tmp/ansible_kubernetes.core.k8s_payload_fpq454yj/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/modules/k8s.py", line 509, in 2025-10-07 07:32:36.178205 | controller | File "/tmp/ansible_kubernetes.core.k8s_payload_fpq454yj/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/modules/k8s.py", line 503, in main 2025-10-07 07:32:36.178354 | controller | File "/tmp/ansible_kubernetes.core.k8s_payload_fpq454yj/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/k8s/runner.py", line 88, in run_module 2025-10-07 07:32:36.178499 | controller | File "/tmp/ansible_kubernetes.core.k8s_payload_fpq454yj/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/k8s/client.py", line 352, in get_api_client 2025-10-07 07:32:36.178663 | controller | File "/tmp/ansible_kubernetes.core.k8s_payload_fpq454yj/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/k8s/client.py", line 246, in wrapper 2025-10-07 07:32:36.178807 | controller | File "/tmp/ansible_kubernetes.core.k8s_payload_fpq454yj/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/k8s/client.py", line 259, in create_api_client 2025-10-07 07:32:36.178980 | controller | File "/usr/local/lib/python3.9/site-packages/kubernetes/dynamic/client.py", line 84, in __init__ 2025-10-07 07:32:36.179130 | controller | self.__discoverer = discoverer(self, cache_file) 2025-10-07 07:32:36.179273 | controller | File "/tmp/ansible_kubernetes.core.k8s_payload_fpq454yj/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/client/discovery.py", line 190, in __init__ 2025-10-07 07:32:36.179415 | controller | File "/tmp/ansible_kubernetes.core.k8s_payload_fpq454yj/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/client/discovery.py", line 45, in __init__ 2025-10-07 07:32:36.179557 | controller | File "/tmp/ansible_kubernetes.core.k8s_payload_fpq454yj/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/client/discovery.py", line 92, in __init_cache 2025-10-07 07:32:36.179719 | controller | File "/usr/local/lib/python3.9/site-packages/kubernetes/dynamic/discovery.py", line 146, in _load_server_info 2025-10-07 07:32:36.179869 | controller | 'kubernetes': self.client.request('get', '/version', serializer=just_json) 2025-10-07 07:32:36.180035 | controller | File "/usr/local/lib/python3.9/site-packages/kubernetes/dynamic/client.py", line 55, in inner 2025-10-07 07:32:36.180177 | controller | resp = func(self, *args, **kwargs) 2025-10-07 07:32:36.180317 | controller | File "/usr/local/lib/python3.9/site-packages/kubernetes/dynamic/client.py", line 277, in request 2025-10-07 07:32:36.180452 | controller | api_response = self.client.call_api( 2025-10-07 07:32:36.180610 | controller | File "/usr/local/lib/python3.9/site-packages/kubernetes/client/api_client.py", line 348, in call_api 2025-10-07 07:32:36.180748 | controller | return self.__call_api(resource_path, method, 2025-10-07 07:32:36.180896 | controller | File "/usr/local/lib/python3.9/site-packages/kubernetes/client/api_client.py", line 180, in __call_api 2025-10-07 07:32:36.181064 | controller | response_data = self.request( 2025-10-07 07:32:36.181209 | controller | File "/usr/local/lib/python3.9/site-packages/kubernetes/client/api_client.py", line 373, in request 2025-10-07 07:32:36.181353 | controller | return self.rest_client.GET(url, 2025-10-07 07:32:36.181506 | controller | File "/usr/local/lib/python3.9/site-packages/kubernetes/client/rest.py", line 244, in GET 2025-10-07 07:32:36.181660 | controller | return self.request("GET", url, 2025-10-07 07:32:36.181801 | controller | File "/usr/local/lib/python3.9/site-packages/kubernetes/client/rest.py", line 217, in request 2025-10-07 07:32:36.181969 | controller | r = self.pool_manager.request(method, url, 2025-10-07 07:32:36.182117 | controller | File "/usr/lib/python3.9/site-packages/urllib3/request.py", line 74, in request 2025-10-07 07:32:36.182251 | controller | return self.request_encode_url( 2025-10-07 07:32:36.182397 | controller | File "/usr/lib/python3.9/site-packages/urllib3/request.py", line 96, in request_encode_url 2025-10-07 07:32:36.182582 | controller | return self.urlopen(method, url, **extra_kw) 2025-10-07 07:32:36.182727 | controller | File "/usr/lib/python3.9/site-packages/urllib3/poolmanager.py", line 376, in urlopen 2025-10-07 07:32:36.182862 | controller | response = conn.urlopen(method, u.request_uri, **kw) 2025-10-07 07:32:36.183035 | controller | File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 784, in urlopen 2025-10-07 07:32:36.183187 | controller | return self.urlopen( 2025-10-07 07:32:36.183336 | controller | File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 784, in urlopen 2025-10-07 07:32:36.183471 | controller | return self.urlopen( 2025-10-07 07:32:36.183626 | controller | File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 784, in urlopen 2025-10-07 07:32:36.183761 | controller | return self.urlopen( 2025-10-07 07:32:36.183899 | controller | File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 756, in urlopen 2025-10-07 07:32:36.184069 | controller | retries = retries.increment( 2025-10-07 07:32:36.184212 | controller | File "/usr/lib/python3.9/site-packages/urllib3/util/retry.py", line 576, in increment 2025-10-07 07:32:36.184349 | controller | raise MaxRetryError(_pool, url, error or ResponseError(cause)) 2025-10-07 07:32:36.184514 | controller | urllib3.exceptions.MaxRetryError: HTTPSConnectionPool(host='api.test.example.com', port=6443): Max retries exceeded with url: /version (Caused by NewConnectionError(': Failed to establish a new connection: [Errno -2] Name or service not known')) 2025-10-07 07:32:36.184692 | controller | module_stdout: '' 2025-10-07 07:32:36.184827 | controller | msg: |- 2025-10-07 07:32:36.184994 | controller | MODULE FAILURE 2025-10-07 07:32:36.185136 | controller | See stdout/stderr for the exact error 2025-10-07 07:32:36.185269 | controller | rc: 1 2025-10-07 07:32:36.185479 | controller | 2025-10-07 07:32:36.185646 | controller | TASK [Capture role execution error] ******************************************** 2025-10-07 07:32:36.186055 | controller | Tuesday 07 October 2025 07:32:36 +0000 (0:00:01.259) 0:00:05.350 ******* 2025-10-07 07:32:36.200800 | controller | ok: [instance] 2025-10-07 07:32:36.200977 | controller | 2025-10-07 07:32:36.201132 | controller | TASK [Display role execution error details] ************************************ 2025-10-07 07:32:36.201288 | controller | Tuesday 07 October 2025 07:32:36 +0000 (0:00:00.022) 0:00:05.373 ******* 2025-10-07 07:32:36.222135 | controller | ok: [instance] => 2025-10-07 07:32:36.222293 | controller | msg: |- 2025-10-07 07:32:36.222438 | controller | Role execution failed with error: 2025-10-07 07:32:36.222638 | controller | {'delegate_to': None, 'delegate_facts': None, 'notify': None, 'collections': [], 'tags': [], 'when': [], 'name': 'Create the workload-availability namespace', 'connection': 'ssh', 'port': None, 'remote_user': None, 'vars': {}, 'module_defaults': [], 'environment': [{}], 'no_log': False, 'run_once': None, 'ignore_errors': None, 'ignore_unreachable': None, 'check_mode': False, 'diff': False, 'any_errors_fatal': False, 'throttle': 0, 'timeout': 0, 'debugger': None, 'become': False, 'become_method': 'sudo', 'become_user': None, 'become_flags': None, 'become_exe': None, 'args': {'kubeconfig': '/tmp/kubeconfig', 'state': 'present', 'resource_definition': {'apiVersion': 'v1', 'kind': 'Namespace', 'metadata': {'name': 'workload-availability'}}}, 'action': 'kubernetes.core.k8s', 'async_val': 0, 'async': 0, 'changed_when': [], 'delay': 5, 'failed_when': [], 'loop': None, 'loop_control': {'loop_var': 'item', 'index_var': None, 'label': None, 'pause': 0.0, 'extended': None, 'extended_allitems': True, 'uuid': 'fa163edd-f1e8-a67d-79ff-000000000166', 'finalized': True, 'squashed': False}, 'poll': 15, 'register': 'namespace_result', 'retries': 3, 'until': [], 'loop_with': None, 'uuid': 'fa163edd-f1e8-a67d-79ff-00000000008f', 'finalized': True, 'squashed': True} 2025-10-07 07:32:36.222792 | controller | 2025-10-07 07:32:36.222957 | controller | TASK [Analyze specific error patterns] ***************************************** 2025-10-07 07:32:36.223107 | controller | Tuesday 07 October 2025 07:32:36 +0000 (0:00:00.021) 0:00:05.394 ******* 2025-10-07 07:32:36.258001 | controller | ok: [instance] => 2025-10-07 07:32:36.258156 | controller | msg: |- 2025-10-07 07:32:36.258299 | controller | Error analysis: 2025-10-07 07:32:36.258439 | controller | - Connection error: False 2025-10-07 07:32:36.258592 | controller | - Authentication error: False 2025-10-07 07:32:36.258734 | controller | - API error: False 2025-10-07 07:32:36.258870 | controller | - Timeout error: False 2025-10-07 07:32:36.259025 | controller | 2025-10-07 07:32:36.259172 | controller | TASK [Continue with test evaluation] ******************************************* 2025-10-07 07:32:36.259311 | controller | Tuesday 07 October 2025 07:32:36 +0000 (0:00:00.035) 0:00:05.430 ******* 2025-10-07 07:32:36.277683 | controller | ok: [instance] => 2025-10-07 07:32:36.277863 | controller | msg: Role failed as expected in test environment - this is normal 2025-10-07 07:32:36.278040 | controller | 2025-10-07 07:32:36.278184 | controller | TASK [Verify namespace exists] ************************************************* 2025-10-07 07:32:36.278321 | controller | Tuesday 07 October 2025 07:32:36 +0000 (0:00:00.019) 0:00:05.450 ******* 2025-10-07 07:32:37.515983 | controller | ok: [instance] 2025-10-07 07:32:37.516012 | controller | 2025-10-07 07:32:37.516021 | controller | TASK [Display namespace verification result] *********************************** 2025-10-07 07:32:37.516030 | controller | Tuesday 07 October 2025 07:32:37 +0000 (0:00:01.237) 0:00:06.687 ******* 2025-10-07 07:32:37.532348 | controller | ok: [instance] => 2025-10-07 07:32:37.532379 | controller | msg: 'Namespace verification result: {''failed'': False, ''module_stdout'': '''', ''module_stderr'': ''Traceback (most recent call last):\n File "/usr/lib/python3.9/site-packages/urllib3/connection.py", line 169, in _new_conn\n conn = connection.create_connection(\n File "/usr/lib/python3.9/site-packages/urllib3/util/connection.py", line 73, in create_connection\n for res in socket.getaddrinfo(host, port, family, socket.SOCK_STREAM):\n File "/usr/lib64/python3.9/socket.py", line 966, in getaddrinfo\n for res in _socket.getaddrinfo(host, port, family, type, proto, flags):\nsocket.gaierror: [Errno -2] Name or service not known\n\nDuring handling of the above exception, another exception occurred:\n\nTraceback (most recent call last):\n File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 700, in urlopen\n httplib_response = self._make_request(\n File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 383, in _make_request\n self._validate_conn(conn)\n File 2025-10-07 07:32:37.532388 | controller | "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 1015, in _validate_conn\n conn.connect()\n File "/usr/lib/python3.9/site-packages/urllib3/connection.py", line 353, in connect\n conn = self._new_conn()\n File "/usr/lib/python3.9/site-packages/urllib3/connection.py", line 181, in _new_conn\n raise NewConnectionError(\nurllib3.exceptions.NewConnectionError: : Failed to establish a new connection: [Errno -2] Name or service not known\n\nDuring handling of the above exception, another exception occurred:\n\nTraceback (most recent call last):\n File "/tmp/ansible-tmp-1759822356.2905173-28361-186755192995884/AnsiballZ_k8s.py", line 107, in \n _ansiballz_main()\n File "/tmp/ansible-tmp-1759822356.2905173-28361-186755192995884/AnsiballZ_k8s.py", line 99, in _ansiballz_main\n invoke_module(zipped_mod, temp_path, ANSIBALLZ_PARAMS)\n File "/tmp/ansible-tmp-1759822356.2905173-28361-186755192995884/AnsiballZ_k8s.py", 2025-10-07 07:32:37.532403 | controller | line 47, in invoke_module\n runpy.run_module(mod_name=\''ansible_collections.kubernetes.core.plugins.modules.k8s\'', init_globals=dict(_module_fqn=\''ansible_collections.kubernetes.core.plugins.modules.k8s\'', _modlib_path=modlib_path),\n File "/usr/lib64/python3.9/runpy.py", line 225, in run_module\n return _run_module_code(code, init_globals, run_name, mod_spec)\n File "/usr/lib64/python3.9/runpy.py", line 97, in _run_module_code\n _run_code(code, mod_globals, init_globals,\n File "/usr/lib64/python3.9/runpy.py", line 87, in _run_code\n exec(code, run_globals)\n File "/tmp/ansible_kubernetes.core.k8s_payload_hexenicw/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/modules/k8s.py", line 509, in \n File "/tmp/ansible_kubernetes.core.k8s_payload_hexenicw/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/modules/k8s.py", line 503, in main\n File "/tmp/ansible_kubernetes.core.k8s_payload_hexenicw/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/k8s/runner.py", 2025-10-07 07:32:37.532416 | controller | line 88, in run_module\n File "/tmp/ansible_kubernetes.core.k8s_payload_hexenicw/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/k8s/client.py", line 352, in get_api_client\n File "/tmp/ansible_kubernetes.core.k8s_payload_hexenicw/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/k8s/client.py", line 246, in wrapper\n File "/tmp/ansible_kubernetes.core.k8s_payload_hexenicw/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/k8s/client.py", line 259, in create_api_client\n File "/usr/local/lib/python3.9/site-packages/kubernetes/dynamic/client.py", line 84, in __init__\n self.__discoverer = discoverer(self, cache_file)\n File "/tmp/ansible_kubernetes.core.k8s_payload_hexenicw/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/client/discovery.py", line 190, in __init__\n File "/tmp/ansible_kubernetes.core.k8s_payload_hexenicw/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/client/discovery.py", 2025-10-07 07:32:37.532434 | controller | line 45, in __init__\n File "/tmp/ansible_kubernetes.core.k8s_payload_hexenicw/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/client/discovery.py", line 92, in __init_cache\n File "/usr/local/lib/python3.9/site-packages/kubernetes/dynamic/discovery.py", line 146, in _load_server_info\n \''kubernetes\'': self.client.request(\''get\'', \''/version\'', serializer=just_json)\n File "/usr/local/lib/python3.9/site-packages/kubernetes/dynamic/client.py", line 55, in inner\n resp = func(self, *args, **kwargs)\n File "/usr/local/lib/python3.9/site-packages/kubernetes/dynamic/client.py", line 277, in request\n api_response = self.client.call_api(\n File "/usr/local/lib/python3.9/site-packages/kubernetes/client/api_client.py", line 348, in call_api\n return self.__call_api(resource_path, method,\n File "/usr/local/lib/python3.9/site-packages/kubernetes/client/api_client.py", line 180, in __call_api\n response_data = self.request(\n File 2025-10-07 07:32:37.532440 | controller | "/usr/local/lib/python3.9/site-packages/kubernetes/client/api_client.py", line 373, in request\n return self.rest_client.GET(url,\n File "/usr/local/lib/python3.9/site-packages/kubernetes/client/rest.py", line 244, in GET\n return self.request("GET", url,\n File "/usr/local/lib/python3.9/site-packages/kubernetes/client/rest.py", line 217, in request\n r = self.pool_manager.request(method, url,\n File "/usr/lib/python3.9/site-packages/urllib3/request.py", line 74, in request\n return self.request_encode_url(\n File "/usr/lib/python3.9/site-packages/urllib3/request.py", line 96, in request_encode_url\n return self.urlopen(method, url, **extra_kw)\n File "/usr/lib/python3.9/site-packages/urllib3/poolmanager.py", line 376, in urlopen\n response = conn.urlopen(method, u.request_uri, **kw)\n File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 784, in urlopen\n return self.urlopen(\n File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", 2025-10-07 07:32:37.532451 | controller | line 784, in urlopen\n return self.urlopen(\n File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 784, in urlopen\n return self.urlopen(\n File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 756, in urlopen\n retries = retries.increment(\n File "/usr/lib/python3.9/site-packages/urllib3/util/retry.py", line 576, in increment\n raise MaxRetryError(_pool, url, error or ResponseError(cause))\nurllib3.exceptions.MaxRetryError: HTTPSConnectionPool(host=\''api.test.example.com\'', port=6443): Max retries exceeded with url: /version (Caused by NewConnectionError(\'': Failed to establish a new connection: [Errno -2] Name or service not known\''))\n'', ''exception'': ''Traceback (most recent call last):\n File "/usr/lib/python3.9/site-packages/urllib3/connection.py", line 169, in _new_conn\n conn = connection.create_connection(\n File "/usr/lib/python3.9/site-packages/urllib3/util/connection.py", 2025-10-07 07:32:37.532459 | controller | line 73, in create_connection\n for res in socket.getaddrinfo(host, port, family, socket.SOCK_STREAM):\n File "/usr/lib64/python3.9/socket.py", line 966, in getaddrinfo\n for res in _socket.getaddrinfo(host, port, family, type, proto, flags):\nsocket.gaierror: [Errno -2] Name or service not known\n\nDuring handling of the above exception, another exception occurred:\n\nTraceback (most recent call last):\n File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 700, in urlopen\n httplib_response = self._make_request(\n File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 383, in _make_request\n self._validate_conn(conn)\n File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 1015, in _validate_conn\n conn.connect()\n File "/usr/lib/python3.9/site-packages/urllib3/connection.py", line 353, in connect\n conn = self._new_conn()\n File "/usr/lib/python3.9/site-packages/urllib3/connection.py", line 181, in _new_conn\n raise 2025-10-07 07:32:37.532481 | controller | NewConnectionError(\nurllib3.exceptions.NewConnectionError: : Failed to establish a new connection: [Errno -2] Name or service not known\n\nDuring handling of the above exception, another exception occurred:\n\nTraceback (most recent call last):\n File "/tmp/ansible-tmp-1759822356.2905173-28361-186755192995884/AnsiballZ_k8s.py", line 107, in \n _ansiballz_main()\n File "/tmp/ansible-tmp-1759822356.2905173-28361-186755192995884/AnsiballZ_k8s.py", line 99, in _ansiballz_main\n invoke_module(zipped_mod, temp_path, ANSIBALLZ_PARAMS)\n File "/tmp/ansible-tmp-1759822356.2905173-28361-186755192995884/AnsiballZ_k8s.py", line 47, in invoke_module\n runpy.run_module(mod_name=\''ansible_collections.kubernetes.core.plugins.modules.k8s\'', init_globals=dict(_module_fqn=\''ansible_collections.kubernetes.core.plugins.modules.k8s\'', _modlib_path=modlib_path),\n File "/usr/lib64/python3.9/runpy.py", line 225, in run_module\n return 2025-10-07 07:32:37.533150 | controller | _run_module_code(code, init_globals, run_name, mod_spec)\n File "/usr/lib64/python3.9/runpy.py", line 97, in _run_module_code\n _run_code(code, mod_globals, init_globals,\n File "/usr/lib64/python3.9/runpy.py", line 87, in _run_code\n exec(code, run_globals)\n File "/tmp/ansible_kubernetes.core.k8s_payload_hexenicw/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/modules/k8s.py", line 509, in \n File "/tmp/ansible_kubernetes.core.k8s_payload_hexenicw/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/modules/k8s.py", line 503, in main\n File "/tmp/ansible_kubernetes.core.k8s_payload_hexenicw/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/k8s/runner.py", line 88, in run_module\n File "/tmp/ansible_kubernetes.core.k8s_payload_hexenicw/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/k8s/client.py", line 352, 2025-10-07 07:32:37.533172 | controller | in get_api_client\n File "/tmp/ansible_kubernetes.core.k8s_payload_hexenicw/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/k8s/client.py", line 246, in wrapper\n File "/tmp/ansible_kubernetes.core.k8s_payload_hexenicw/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/k8s/client.py", line 259, in create_api_client\n File "/usr/local/lib/python3.9/site-packages/kubernetes/dynamic/client.py", line 84, in __init__\n self.__discoverer = discoverer(self, cache_file)\n File "/tmp/ansible_kubernetes.core.k8s_payload_hexenicw/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/client/discovery.py", line 190, in __init__\n File "/tmp/ansible_kubernetes.core.k8s_payload_hexenicw/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/client/discovery.py", line 45, in __init__\n File "/tmp/ansible_kubernetes.core.k8s_payload_hexenicw/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/client/discovery.py", 2025-10-07 07:32:37.533181 | controller | line 92, in __init_cache\n File "/usr/local/lib/python3.9/site-packages/kubernetes/dynamic/discovery.py", line 146, in _load_server_info\n \''kubernetes\'': self.client.request(\''get\'', \''/version\'', serializer=just_json)\n File "/usr/local/lib/python3.9/site-packages/kubernetes/dynamic/client.py", line 55, in inner\n resp = func(self, *args, **kwargs)\n File "/usr/local/lib/python3.9/site-packages/kubernetes/dynamic/client.py", line 277, in request\n api_response = self.client.call_api(\n File "/usr/local/lib/python3.9/site-packages/kubernetes/client/api_client.py", line 348, in call_api\n return self.__call_api(resource_path, method,\n File "/usr/local/lib/python3.9/site-packages/kubernetes/client/api_client.py", line 180, in __call_api\n response_data = self.request(\n File "/usr/local/lib/python3.9/site-packages/kubernetes/client/api_client.py", line 373, in request\n return self.rest_client.GET(url,\n File "/usr/local/lib/python3.9/site-packages/kubernetes/client/rest.py", 2025-10-07 07:32:37.533194 | controller | line 244, in GET\n return self.request("GET", url,\n File "/usr/local/lib/python3.9/site-packages/kubernetes/client/rest.py", line 217, in request\n r = self.pool_manager.request(method, url,\n File "/usr/lib/python3.9/site-packages/urllib3/request.py", line 74, in request\n return self.request_encode_url(\n File "/usr/lib/python3.9/site-packages/urllib3/request.py", line 96, in request_encode_url\n return self.urlopen(method, url, **extra_kw)\n File "/usr/lib/python3.9/site-packages/urllib3/poolmanager.py", line 376, in urlopen\n response = conn.urlopen(method, u.request_uri, **kw)\n File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 784, in urlopen\n return self.urlopen(\n File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 784, in urlopen\n return self.urlopen(\n File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 784, in urlopen\n return self.urlopen(\n File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", 2025-10-07 07:32:37.537729 | controller | line 756, in urlopen\n retries = retries.increment(\n File "/usr/lib/python3.9/site-packages/urllib3/util/retry.py", line 576, in increment\n raise MaxRetryError(_pool, url, error or ResponseError(cause))\nurllib3.exceptions.MaxRetryError: HTTPSConnectionPool(host=\''api.test.example.com\'', port=6443): Max retries exceeded with url: /version (Caused by NewConnectionError(\'': Failed to establish a new connection: [Errno -2] Name or service not known\''))\n'', ''msg'': ''MODULE FAILURE\nSee stdout/stderr for the exact error'', ''rc'': 1, ''changed'': False, ''failed_when_result'': False}' 2025-10-07 07:32:37.537753 | controller | 2025-10-07 07:32:38.870369 | controller | TASK [Test namespace creation idempotency] ************************************* 2025-10-07 07:32:38.870399 | controller | Tuesday 07 October 2025 07:32:37 +0000 (0:00:00.022) 0:00:06.710 ******* 2025-10-07 07:32:38.870411 | controller | ok: [instance] 2025-10-07 07:32:38.891493 | controller | 2025-10-07 07:32:38.891522 | controller | TASK [Assert expected behavior for namespace creation] ************************* 2025-10-07 07:32:38.891530 | controller | Tuesday 07 October 2025 07:32:38 +0000 (0:00:01.332) 0:00:08.042 ******* 2025-10-07 07:32:38.891540 | controller | ok: [instance] => changed=false 2025-10-07 07:32:40.126964 | controller | msg: Namespace creation task behaves consistently 2025-10-07 07:32:40.126994 | controller | 2025-10-07 07:32:40.127007 | controller | TASK [Verify OperatorGroup exists] ********************************************* 2025-10-07 07:32:40.127015 | controller | Tuesday 07 October 2025 07:32:38 +0000 (0:00:00.020) 0:00:08.063 ******* 2025-10-07 07:32:40.127025 | controller | ok: [instance] 2025-10-07 07:32:40.144997 | controller | 2025-10-07 07:32:40.145020 | controller | TASK [Display OperatorGroup verification result] ******************************* 2025-10-07 07:32:40.145028 | controller | Tuesday 07 October 2025 07:32:40 +0000 (0:00:01.235) 0:00:09.299 ******* 2025-10-07 07:32:40.145039 | controller | ok: [instance] => 2025-10-07 07:32:40.145046 | controller | msg: 'OperatorGroup verification result: {''failed'': False, ''module_stdout'': '''', ''module_stderr'': ''Traceback (most recent call last):\n File "/usr/lib/python3.9/site-packages/urllib3/connection.py", line 169, in _new_conn\n conn = connection.create_connection(\n File "/usr/lib/python3.9/site-packages/urllib3/util/connection.py", line 73, in create_connection\n for res in socket.getaddrinfo(host, port, family, socket.SOCK_STREAM):\n File "/usr/lib64/python3.9/socket.py", line 966, in getaddrinfo\n for res in _socket.getaddrinfo(host, port, family, type, proto, flags):\nsocket.gaierror: [Errno -2] Name or service not known\n\nDuring handling of the above exception, another exception occurred:\n\nTraceback (most recent call last):\n File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 700, in urlopen\n httplib_response = self._make_request(\n File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 383, in _make_request\n self._validate_conn(conn)\n File 2025-10-07 07:32:40.145053 | controller | "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 1015, in _validate_conn\n conn.connect()\n File "/usr/lib/python3.9/site-packages/urllib3/connection.py", line 353, in connect\n conn = self._new_conn()\n File "/usr/lib/python3.9/site-packages/urllib3/connection.py", line 181, in _new_conn\n raise NewConnectionError(\nurllib3.exceptions.NewConnectionError: : Failed to establish a new connection: [Errno -2] Name or service not known\n\nDuring handling of the above exception, another exception occurred:\n\nTraceback (most recent call last):\n File "/tmp/ansible-tmp-1759822358.9023402-28701-18497295987616/AnsiballZ_k8s.py", line 107, in \n _ansiballz_main()\n File "/tmp/ansible-tmp-1759822358.9023402-28701-18497295987616/AnsiballZ_k8s.py", line 99, in _ansiballz_main\n invoke_module(zipped_mod, temp_path, ANSIBALLZ_PARAMS)\n File "/tmp/ansible-tmp-1759822358.9023402-28701-18497295987616/AnsiballZ_k8s.py", 2025-10-07 07:32:40.145064 | controller | line 47, in invoke_module\n runpy.run_module(mod_name=\''ansible_collections.kubernetes.core.plugins.modules.k8s\'', init_globals=dict(_module_fqn=\''ansible_collections.kubernetes.core.plugins.modules.k8s\'', _modlib_path=modlib_path),\n File "/usr/lib64/python3.9/runpy.py", line 225, in run_module\n return _run_module_code(code, init_globals, run_name, mod_spec)\n File "/usr/lib64/python3.9/runpy.py", line 97, in _run_module_code\n _run_code(code, mod_globals, init_globals,\n File "/usr/lib64/python3.9/runpy.py", line 87, in _run_code\n exec(code, run_globals)\n File "/tmp/ansible_kubernetes.core.k8s_payload_hcj4xwyu/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/modules/k8s.py", line 509, in \n File "/tmp/ansible_kubernetes.core.k8s_payload_hcj4xwyu/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/modules/k8s.py", line 503, in main\n File "/tmp/ansible_kubernetes.core.k8s_payload_hcj4xwyu/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/k8s/runner.py", 2025-10-07 07:32:40.145077 | controller | line 88, in run_module\n File "/tmp/ansible_kubernetes.core.k8s_payload_hcj4xwyu/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/k8s/client.py", line 352, in get_api_client\n File "/tmp/ansible_kubernetes.core.k8s_payload_hcj4xwyu/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/k8s/client.py", line 246, in wrapper\n File "/tmp/ansible_kubernetes.core.k8s_payload_hcj4xwyu/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/k8s/client.py", line 259, in create_api_client\n File "/usr/local/lib/python3.9/site-packages/kubernetes/dynamic/client.py", line 84, in __init__\n self.__discoverer = discoverer(self, cache_file)\n File "/tmp/ansible_kubernetes.core.k8s_payload_hcj4xwyu/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/client/discovery.py", line 190, in __init__\n File "/tmp/ansible_kubernetes.core.k8s_payload_hcj4xwyu/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/client/discovery.py", 2025-10-07 07:32:40.146245 | controller | line 45, in __init__\n File "/tmp/ansible_kubernetes.core.k8s_payload_hcj4xwyu/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/client/discovery.py", line 92, in __init_cache\n File "/usr/local/lib/python3.9/site-packages/kubernetes/dynamic/discovery.py", line 146, in _load_server_info\n \''kubernetes\'': self.client.request(\''get\'', \''/version\'', serializer=just_json)\n File "/usr/local/lib/python3.9/site-packages/kubernetes/dynamic/client.py", line 55, in inner\n resp = func(self, *args, **kwargs)\n File "/usr/local/lib/python3.9/site-packages/kubernetes/dynamic/client.py", line 277, in request\n api_response = self.client.call_api(\n File "/usr/local/lib/python3.9/site-packages/kubernetes/client/api_client.py", line 348, in call_api\n return self.__call_api(resource_path, method,\n File "/usr/local/lib/python3.9/site-packages/kubernetes/client/api_client.py", line 180, in __call_api\n response_data = self.request(\n File 2025-10-07 07:32:40.146271 | controller | "/usr/local/lib/python3.9/site-packages/kubernetes/client/api_client.py", line 373, in request\n return self.rest_client.GET(url,\n File "/usr/local/lib/python3.9/site-packages/kubernetes/client/rest.py", line 244, in GET\n return self.request("GET", url,\n File "/usr/local/lib/python3.9/site-packages/kubernetes/client/rest.py", line 217, in request\n r = self.pool_manager.request(method, url,\n File "/usr/lib/python3.9/site-packages/urllib3/request.py", line 74, in request\n return self.request_encode_url(\n File "/usr/lib/python3.9/site-packages/urllib3/request.py", line 96, in request_encode_url\n return self.urlopen(method, url, **extra_kw)\n File "/usr/lib/python3.9/site-packages/urllib3/poolmanager.py", line 376, in urlopen\n response = conn.urlopen(method, u.request_uri, **kw)\n File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 784, in urlopen\n return self.urlopen(\n File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", 2025-10-07 07:32:40.146282 | controller | line 784, in urlopen\n return self.urlopen(\n File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 784, in urlopen\n return self.urlopen(\n File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 756, in urlopen\n retries = retries.increment(\n File "/usr/lib/python3.9/site-packages/urllib3/util/retry.py", line 576, in increment\n raise MaxRetryError(_pool, url, error or ResponseError(cause))\nurllib3.exceptions.MaxRetryError: HTTPSConnectionPool(host=\''api.test.example.com\'', port=6443): Max retries exceeded with url: /version (Caused by NewConnectionError(\'': Failed to establish a new connection: [Errno -2] Name or service not known\''))\n'', ''exception'': ''Traceback (most recent call last):\n File "/usr/lib/python3.9/site-packages/urllib3/connection.py", line 169, in _new_conn\n conn = connection.create_connection(\n File "/usr/lib/python3.9/site-packages/urllib3/util/connection.py", 2025-10-07 07:32:40.146288 | controller | line 73, in create_connection\n for res in socket.getaddrinfo(host, port, family, socket.SOCK_STREAM):\n File "/usr/lib64/python3.9/socket.py", line 966, in getaddrinfo\n for res in _socket.getaddrinfo(host, port, family, type, proto, flags):\nsocket.gaierror: [Errno -2] Name or service not known\n\nDuring handling of the above exception, another exception occurred:\n\nTraceback (most recent call last):\n File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 700, in urlopen\n httplib_response = self._make_request(\n File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 383, in _make_request\n self._validate_conn(conn)\n File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 1015, in _validate_conn\n conn.connect()\n File "/usr/lib/python3.9/site-packages/urllib3/connection.py", line 353, in connect\n conn = self._new_conn()\n File "/usr/lib/python3.9/site-packages/urllib3/connection.py", line 181, in _new_conn\n raise 2025-10-07 07:32:40.146294 | controller | NewConnectionError(\nurllib3.exceptions.NewConnectionError: : Failed to establish a new connection: [Errno -2] Name or service not known\n\nDuring handling of the above exception, another exception occurred:\n\nTraceback (most recent call last):\n File "/tmp/ansible-tmp-1759822358.9023402-28701-18497295987616/AnsiballZ_k8s.py", line 107, in \n _ansiballz_main()\n File "/tmp/ansible-tmp-1759822358.9023402-28701-18497295987616/AnsiballZ_k8s.py", line 99, in _ansiballz_main\n invoke_module(zipped_mod, temp_path, ANSIBALLZ_PARAMS)\n File "/tmp/ansible-tmp-1759822358.9023402-28701-18497295987616/AnsiballZ_k8s.py", line 47, in invoke_module\n runpy.run_module(mod_name=\''ansible_collections.kubernetes.core.plugins.modules.k8s\'', init_globals=dict(_module_fqn=\''ansible_collections.kubernetes.core.plugins.modules.k8s\'', _modlib_path=modlib_path),\n File "/usr/lib64/python3.9/runpy.py", line 225, in run_module\n return 2025-10-07 07:32:40.146305 | controller | _run_module_code(code, init_globals, run_name, mod_spec)\n File "/usr/lib64/python3.9/runpy.py", line 97, in _run_module_code\n _run_code(code, mod_globals, init_globals,\n File "/usr/lib64/python3.9/runpy.py", line 87, in _run_code\n exec(code, run_globals)\n File "/tmp/ansible_kubernetes.core.k8s_payload_hcj4xwyu/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/modules/k8s.py", line 509, in \n File "/tmp/ansible_kubernetes.core.k8s_payload_hcj4xwyu/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/modules/k8s.py", line 503, in main\n File "/tmp/ansible_kubernetes.core.k8s_payload_hcj4xwyu/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/k8s/runner.py", line 88, in run_module\n File "/tmp/ansible_kubernetes.core.k8s_payload_hcj4xwyu/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/k8s/client.py", line 352, 2025-10-07 07:32:40.146316 | controller | in get_api_client\n File "/tmp/ansible_kubernetes.core.k8s_payload_hcj4xwyu/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/k8s/client.py", line 246, in wrapper\n File "/tmp/ansible_kubernetes.core.k8s_payload_hcj4xwyu/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/k8s/client.py", line 259, in create_api_client\n File "/usr/local/lib/python3.9/site-packages/kubernetes/dynamic/client.py", line 84, in __init__\n self.__discoverer = discoverer(self, cache_file)\n File "/tmp/ansible_kubernetes.core.k8s_payload_hcj4xwyu/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/client/discovery.py", line 190, in __init__\n File "/tmp/ansible_kubernetes.core.k8s_payload_hcj4xwyu/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/client/discovery.py", line 45, in __init__\n File "/tmp/ansible_kubernetes.core.k8s_payload_hcj4xwyu/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/client/discovery.py", 2025-10-07 07:32:40.146322 | controller | line 92, in __init_cache\n File "/usr/local/lib/python3.9/site-packages/kubernetes/dynamic/discovery.py", line 146, in _load_server_info\n \''kubernetes\'': self.client.request(\''get\'', \''/version\'', serializer=just_json)\n File "/usr/local/lib/python3.9/site-packages/kubernetes/dynamic/client.py", line 55, in inner\n resp = func(self, *args, **kwargs)\n File "/usr/local/lib/python3.9/site-packages/kubernetes/dynamic/client.py", line 277, in request\n api_response = self.client.call_api(\n File "/usr/local/lib/python3.9/site-packages/kubernetes/client/api_client.py", line 348, in call_api\n return self.__call_api(resource_path, method,\n File "/usr/local/lib/python3.9/site-packages/kubernetes/client/api_client.py", line 180, in __call_api\n response_data = self.request(\n File "/usr/local/lib/python3.9/site-packages/kubernetes/client/api_client.py", line 373, in request\n return self.rest_client.GET(url,\n File "/usr/local/lib/python3.9/site-packages/kubernetes/client/rest.py", 2025-10-07 07:32:40.146333 | controller | line 244, in GET\n return self.request("GET", url,\n File "/usr/local/lib/python3.9/site-packages/kubernetes/client/rest.py", line 217, in request\n r = self.pool_manager.request(method, url,\n File "/usr/lib/python3.9/site-packages/urllib3/request.py", line 74, in request\n return self.request_encode_url(\n File "/usr/lib/python3.9/site-packages/urllib3/request.py", line 96, in request_encode_url\n return self.urlopen(method, url, **extra_kw)\n File "/usr/lib/python3.9/site-packages/urllib3/poolmanager.py", line 376, in urlopen\n response = conn.urlopen(method, u.request_uri, **kw)\n File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 784, in urlopen\n return self.urlopen(\n File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 784, in urlopen\n return self.urlopen(\n File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 784, in urlopen\n return self.urlopen(\n File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", 2025-10-07 07:32:40.150402 | controller | line 756, in urlopen\n retries = retries.increment(\n File "/usr/lib/python3.9/site-packages/urllib3/util/retry.py", line 576, in increment\n raise MaxRetryError(_pool, url, error or ResponseError(cause))\nurllib3.exceptions.MaxRetryError: HTTPSConnectionPool(host=\''api.test.example.com\'', port=6443): Max retries exceeded with url: /version (Caused by NewConnectionError(\'': Failed to establish a new connection: [Errno -2] Name or service not known\''))\n'', ''msg'': ''MODULE FAILURE\nSee stdout/stderr for the exact error'', ''rc'': 1, ''changed'': False, ''failed_when_result'': False}' 2025-10-07 07:32:40.150425 | controller | 2025-10-07 07:32:41.292432 | controller | TASK [Test OperatorGroup creation idempotency] ********************************* 2025-10-07 07:32:41.292466 | controller | Tuesday 07 October 2025 07:32:40 +0000 (0:00:00.023) 0:00:09.322 ******* 2025-10-07 07:32:41.292478 | controller | ok: [instance] 2025-10-07 07:32:41.315725 | controller | 2025-10-07 07:32:41.315749 | controller | TASK [Assert expected behavior for OperatorGroup creation] ********************* 2025-10-07 07:32:41.315757 | controller | Tuesday 07 October 2025 07:32:41 +0000 (0:00:01.141) 0:00:10.464 ******* 2025-10-07 07:32:41.315768 | controller | ok: [instance] => changed=false 2025-10-07 07:32:42.481455 | controller | msg: OperatorGroup creation task behaves consistently 2025-10-07 07:32:42.481487 | controller | 2025-10-07 07:32:42.481495 | controller | TASK [Verify SNR Subscription exists] ****************************************** 2025-10-07 07:32:42.481501 | controller | Tuesday 07 October 2025 07:32:41 +0000 (0:00:00.023) 0:00:10.487 ******* 2025-10-07 07:32:42.481511 | controller | ok: [instance] 2025-10-07 07:32:42.497199 | controller | 2025-10-07 07:32:42.497231 | controller | TASK [Display SNR Subscription verification result] **************************** 2025-10-07 07:32:42.497239 | controller | Tuesday 07 October 2025 07:32:42 +0000 (0:00:01.165) 0:00:11.653 ******* 2025-10-07 07:32:42.497251 | controller | ok: [instance] => 2025-10-07 07:32:42.497258 | controller | msg: 'SNR Subscription verification result: {''failed'': False, ''module_stdout'': '''', ''module_stderr'': ''Traceback (most recent call last):\n File "/usr/lib/python3.9/site-packages/urllib3/connection.py", line 169, in _new_conn\n conn = connection.create_connection(\n File "/usr/lib/python3.9/site-packages/urllib3/util/connection.py", line 73, in create_connection\n for res in socket.getaddrinfo(host, port, family, socket.SOCK_STREAM):\n File "/usr/lib64/python3.9/socket.py", line 966, in getaddrinfo\n for res in _socket.getaddrinfo(host, port, family, type, proto, flags):\nsocket.gaierror: [Errno -2] Name or service not known\n\nDuring handling of the above exception, another exception occurred:\n\nTraceback (most recent call last):\n File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 700, in urlopen\n httplib_response = self._make_request(\n File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 383, in _make_request\n self._validate_conn(conn)\n File 2025-10-07 07:32:42.497266 | controller | "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 1015, in _validate_conn\n conn.connect()\n File "/usr/lib/python3.9/site-packages/urllib3/connection.py", line 353, in connect\n conn = self._new_conn()\n File "/usr/lib/python3.9/site-packages/urllib3/connection.py", line 181, in _new_conn\n raise NewConnectionError(\nurllib3.exceptions.NewConnectionError: : Failed to establish a new connection: [Errno -2] Name or service not known\n\nDuring handling of the above exception, another exception occurred:\n\nTraceback (most recent call last):\n File "/tmp/ansible-tmp-1759822361.3284583-29039-122205187506335/AnsiballZ_k8s.py", line 107, in \n _ansiballz_main()\n File "/tmp/ansible-tmp-1759822361.3284583-29039-122205187506335/AnsiballZ_k8s.py", line 99, in _ansiballz_main\n invoke_module(zipped_mod, temp_path, ANSIBALLZ_PARAMS)\n File "/tmp/ansible-tmp-1759822361.3284583-29039-122205187506335/AnsiballZ_k8s.py", 2025-10-07 07:32:42.497272 | controller | line 47, in invoke_module\n runpy.run_module(mod_name=\''ansible_collections.kubernetes.core.plugins.modules.k8s\'', init_globals=dict(_module_fqn=\''ansible_collections.kubernetes.core.plugins.modules.k8s\'', _modlib_path=modlib_path),\n File "/usr/lib64/python3.9/runpy.py", line 225, in run_module\n return _run_module_code(code, init_globals, run_name, mod_spec)\n File "/usr/lib64/python3.9/runpy.py", line 97, in _run_module_code\n _run_code(code, mod_globals, init_globals,\n File "/usr/lib64/python3.9/runpy.py", line 87, in _run_code\n exec(code, run_globals)\n File "/tmp/ansible_kubernetes.core.k8s_payload_1qv_p98e/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/modules/k8s.py", line 509, in \n File "/tmp/ansible_kubernetes.core.k8s_payload_1qv_p98e/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/modules/k8s.py", line 503, in main\n File "/tmp/ansible_kubernetes.core.k8s_payload_1qv_p98e/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/k8s/runner.py", 2025-10-07 07:32:42.497285 | controller | line 88, in run_module\n File "/tmp/ansible_kubernetes.core.k8s_payload_1qv_p98e/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/k8s/client.py", line 352, in get_api_client\n File "/tmp/ansible_kubernetes.core.k8s_payload_1qv_p98e/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/k8s/client.py", line 246, in wrapper\n File "/tmp/ansible_kubernetes.core.k8s_payload_1qv_p98e/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/k8s/client.py", line 259, in create_api_client\n File "/usr/local/lib/python3.9/site-packages/kubernetes/dynamic/client.py", line 84, in __init__\n self.__discoverer = discoverer(self, cache_file)\n File "/tmp/ansible_kubernetes.core.k8s_payload_1qv_p98e/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/client/discovery.py", line 190, in __init__\n File "/tmp/ansible_kubernetes.core.k8s_payload_1qv_p98e/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/client/discovery.py", 2025-10-07 07:32:42.497314 | controller | line 45, in __init__\n File "/tmp/ansible_kubernetes.core.k8s_payload_1qv_p98e/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/client/discovery.py", line 92, in __init_cache\n File "/usr/local/lib/python3.9/site-packages/kubernetes/dynamic/discovery.py", line 146, in _load_server_info\n \''kubernetes\'': self.client.request(\''get\'', \''/version\'', serializer=just_json)\n File "/usr/local/lib/python3.9/site-packages/kubernetes/dynamic/client.py", line 55, in inner\n resp = func(self, *args, **kwargs)\n File "/usr/local/lib/python3.9/site-packages/kubernetes/dynamic/client.py", line 277, in request\n api_response = self.client.call_api(\n File "/usr/local/lib/python3.9/site-packages/kubernetes/client/api_client.py", line 348, in call_api\n return self.__call_api(resource_path, method,\n File "/usr/local/lib/python3.9/site-packages/kubernetes/client/api_client.py", line 180, in __call_api\n response_data = self.request(\n File 2025-10-07 07:32:42.497324 | controller | "/usr/local/lib/python3.9/site-packages/kubernetes/client/api_client.py", line 373, in request\n return self.rest_client.GET(url,\n File "/usr/local/lib/python3.9/site-packages/kubernetes/client/rest.py", line 244, in GET\n return self.request("GET", url,\n File "/usr/local/lib/python3.9/site-packages/kubernetes/client/rest.py", line 217, in request\n r = self.pool_manager.request(method, url,\n File "/usr/lib/python3.9/site-packages/urllib3/request.py", line 74, in request\n return self.request_encode_url(\n File "/usr/lib/python3.9/site-packages/urllib3/request.py", line 96, in request_encode_url\n return self.urlopen(method, url, **extra_kw)\n File "/usr/lib/python3.9/site-packages/urllib3/poolmanager.py", line 376, in urlopen\n response = conn.urlopen(method, u.request_uri, **kw)\n File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 784, in urlopen\n return self.urlopen(\n File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", 2025-10-07 07:32:42.497954 | controller | line 784, in urlopen\n return self.urlopen(\n File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 784, in urlopen\n return self.urlopen(\n File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 756, in urlopen\n retries = retries.increment(\n File "/usr/lib/python3.9/site-packages/urllib3/util/retry.py", line 576, in increment\n raise MaxRetryError(_pool, url, error or ResponseError(cause))\nurllib3.exceptions.MaxRetryError: HTTPSConnectionPool(host=\''api.test.example.com\'', port=6443): Max retries exceeded with url: /version (Caused by NewConnectionError(\'': Failed to establish a new connection: [Errno -2] Name or service not known\''))\n'', ''exception'': ''Traceback (most recent call last):\n File "/usr/lib/python3.9/site-packages/urllib3/connection.py", line 169, in _new_conn\n conn = connection.create_connection(\n File "/usr/lib/python3.9/site-packages/urllib3/util/connection.py", 2025-10-07 07:32:42.497970 | controller | line 73, in create_connection\n for res in socket.getaddrinfo(host, port, family, socket.SOCK_STREAM):\n File "/usr/lib64/python3.9/socket.py", line 966, in getaddrinfo\n for res in _socket.getaddrinfo(host, port, family, type, proto, flags):\nsocket.gaierror: [Errno -2] Name or service not known\n\nDuring handling of the above exception, another exception occurred:\n\nTraceback (most recent call last):\n File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 700, in urlopen\n httplib_response = self._make_request(\n File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 383, in _make_request\n self._validate_conn(conn)\n File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 1015, in _validate_conn\n conn.connect()\n File "/usr/lib/python3.9/site-packages/urllib3/connection.py", line 353, in connect\n conn = self._new_conn()\n File "/usr/lib/python3.9/site-packages/urllib3/connection.py", line 181, in _new_conn\n raise 2025-10-07 07:32:42.497980 | controller | NewConnectionError(\nurllib3.exceptions.NewConnectionError: : Failed to establish a new connection: [Errno -2] Name or service not known\n\nDuring handling of the above exception, another exception occurred:\n\nTraceback (most recent call last):\n File "/tmp/ansible-tmp-1759822361.3284583-29039-122205187506335/AnsiballZ_k8s.py", line 107, in \n _ansiballz_main()\n File "/tmp/ansible-tmp-1759822361.3284583-29039-122205187506335/AnsiballZ_k8s.py", line 99, in _ansiballz_main\n invoke_module(zipped_mod, temp_path, ANSIBALLZ_PARAMS)\n File "/tmp/ansible-tmp-1759822361.3284583-29039-122205187506335/AnsiballZ_k8s.py", line 47, in invoke_module\n runpy.run_module(mod_name=\''ansible_collections.kubernetes.core.plugins.modules.k8s\'', init_globals=dict(_module_fqn=\''ansible_collections.kubernetes.core.plugins.modules.k8s\'', _modlib_path=modlib_path),\n File "/usr/lib64/python3.9/runpy.py", line 225, in run_module\n return 2025-10-07 07:32:42.497986 | controller | _run_module_code(code, init_globals, run_name, mod_spec)\n File "/usr/lib64/python3.9/runpy.py", line 97, in _run_module_code\n _run_code(code, mod_globals, init_globals,\n File "/usr/lib64/python3.9/runpy.py", line 87, in _run_code\n exec(code, run_globals)\n File "/tmp/ansible_kubernetes.core.k8s_payload_1qv_p98e/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/modules/k8s.py", line 509, in \n File "/tmp/ansible_kubernetes.core.k8s_payload_1qv_p98e/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/modules/k8s.py", line 503, in main\n File "/tmp/ansible_kubernetes.core.k8s_payload_1qv_p98e/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/k8s/runner.py", line 88, in run_module\n File "/tmp/ansible_kubernetes.core.k8s_payload_1qv_p98e/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/k8s/client.py", line 352, 2025-10-07 07:32:42.497996 | controller | in get_api_client\n File "/tmp/ansible_kubernetes.core.k8s_payload_1qv_p98e/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/k8s/client.py", line 246, in wrapper\n File "/tmp/ansible_kubernetes.core.k8s_payload_1qv_p98e/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/k8s/client.py", line 259, in create_api_client\n File "/usr/local/lib/python3.9/site-packages/kubernetes/dynamic/client.py", line 84, in __init__\n self.__discoverer = discoverer(self, cache_file)\n File "/tmp/ansible_kubernetes.core.k8s_payload_1qv_p98e/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/client/discovery.py", line 190, in __init__\n File "/tmp/ansible_kubernetes.core.k8s_payload_1qv_p98e/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/client/discovery.py", line 45, in __init__\n File "/tmp/ansible_kubernetes.core.k8s_payload_1qv_p98e/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/client/discovery.py", 2025-10-07 07:32:42.498320 | controller | line 92, in __init_cache\n File "/usr/local/lib/python3.9/site-packages/kubernetes/dynamic/discovery.py", line 146, in _load_server_info\n \''kubernetes\'': self.client.request(\''get\'', \''/version\'', serializer=just_json)\n File "/usr/local/lib/python3.9/site-packages/kubernetes/dynamic/client.py", line 55, in inner\n resp = func(self, *args, **kwargs)\n File "/usr/local/lib/python3.9/site-packages/kubernetes/dynamic/client.py", line 277, in request\n api_response = self.client.call_api(\n File "/usr/local/lib/python3.9/site-packages/kubernetes/client/api_client.py", line 348, in call_api\n return self.__call_api(resource_path, method,\n File "/usr/local/lib/python3.9/site-packages/kubernetes/client/api_client.py", line 180, in __call_api\n response_data = self.request(\n File "/usr/local/lib/python3.9/site-packages/kubernetes/client/api_client.py", line 373, in request\n return self.rest_client.GET(url,\n File "/usr/local/lib/python3.9/site-packages/kubernetes/client/rest.py", 2025-10-07 07:32:42.502415 | controller | line 244, in GET\n return self.request("GET", url,\n File "/usr/local/lib/python3.9/site-packages/kubernetes/client/rest.py", line 217, in request\n r = self.pool_manager.request(method, url,\n File "/usr/lib/python3.9/site-packages/urllib3/request.py", line 74, in request\n return self.request_encode_url(\n File "/usr/lib/python3.9/site-packages/urllib3/request.py", line 96, in request_encode_url\n return self.urlopen(method, url, **extra_kw)\n File "/usr/lib/python3.9/site-packages/urllib3/poolmanager.py", line 376, in urlopen\n response = conn.urlopen(method, u.request_uri, **kw)\n File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 784, in urlopen\n return self.urlopen(\n File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 784, in urlopen\n return self.urlopen(\n File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 784, in urlopen\n return self.urlopen(\n File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", 2025-10-07 07:32:42.502433 | controller | line 756, in urlopen\n retries = retries.increment(\n File "/usr/lib/python3.9/site-packages/urllib3/util/retry.py", line 576, in increment\n raise MaxRetryError(_pool, url, error or ResponseError(cause))\nurllib3.exceptions.MaxRetryError: HTTPSConnectionPool(host=\''api.test.example.com\'', port=6443): Max retries exceeded with url: /version (Caused by NewConnectionError(\'': Failed to establish a new connection: [Errno -2] Name or service not known\''))\n'', ''msg'': ''MODULE FAILURE\nSee stdout/stderr for the exact error'', ''rc'': 1, ''changed'': False, ''failed_when_result'': False}' 2025-10-07 07:32:42.502444 | controller | 2025-10-07 07:32:43.600554 | controller | TASK [Test SNR Subscription creation idempotency] ****************************** 2025-10-07 07:32:43.600598 | controller | Tuesday 07 October 2025 07:32:42 +0000 (0:00:00.021) 0:00:11.674 ******* 2025-10-07 07:32:43.600611 | controller | ok: [instance] 2025-10-07 07:32:43.621530 | controller | 2025-10-07 07:32:43.621548 | controller | TASK [Assert expected behavior for SNR Subscription creation] ****************** 2025-10-07 07:32:43.621556 | controller | Tuesday 07 October 2025 07:32:43 +0000 (0:00:01.097) 0:00:12.772 ******* 2025-10-07 07:32:43.621565 | controller | ok: [instance] => changed=false 2025-10-07 07:32:44.944457 | controller | msg: SNR Subscription creation task behaves consistently 2025-10-07 07:32:44.944487 | controller | 2025-10-07 07:32:44.944495 | controller | TASK [Verify SNR deployment status] ******************************************** 2025-10-07 07:32:44.944502 | controller | Tuesday 07 October 2025 07:32:43 +0000 (0:00:00.021) 0:00:12.793 ******* 2025-10-07 07:32:44.944511 | controller | ok: [instance] 2025-10-07 07:32:44.962328 | controller | 2025-10-07 07:32:44.962358 | controller | TASK [Display SNR deployment verification result] ****************************** 2025-10-07 07:32:44.962366 | controller | Tuesday 07 October 2025 07:32:44 +0000 (0:00:01.322) 0:00:14.116 ******* 2025-10-07 07:32:44.962377 | controller | ok: [instance] => 2025-10-07 07:32:44.962433 | controller | msg: 'SNR deployment verification result: {''failed'': False, ''module_stdout'': '''', ''module_stderr'': ''Traceback (most recent call last):\n File "/usr/lib/python3.9/site-packages/urllib3/connection.py", line 169, in _new_conn\n conn = connection.create_connection(\n File "/usr/lib/python3.9/site-packages/urllib3/util/connection.py", line 73, in create_connection\n for res in socket.getaddrinfo(host, port, family, socket.SOCK_STREAM):\n File "/usr/lib64/python3.9/socket.py", line 966, in getaddrinfo\n for res in _socket.getaddrinfo(host, port, family, type, proto, flags):\nsocket.gaierror: [Errno -2] Name or service not known\n\nDuring handling of the above exception, another exception occurred:\n\nTraceback (most recent call last):\n File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 700, in urlopen\n httplib_response = self._make_request(\n File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 383, in _make_request\n self._validate_conn(conn)\n File 2025-10-07 07:32:44.962442 | controller | "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 1015, in _validate_conn\n conn.connect()\n File "/usr/lib/python3.9/site-packages/urllib3/connection.py", line 353, in connect\n conn = self._new_conn()\n File "/usr/lib/python3.9/site-packages/urllib3/connection.py", line 181, in _new_conn\n raise NewConnectionError(\nurllib3.exceptions.NewConnectionError: : Failed to establish a new connection: [Errno -2] Name or service not known\n\nDuring handling of the above exception, another exception occurred:\n\nTraceback (most recent call last):\n File "/tmp/ansible-tmp-1759822363.633274-29376-123202735620434/AnsiballZ_k8s_info.py", line 107, in \n _ansiballz_main()\n File "/tmp/ansible-tmp-1759822363.633274-29376-123202735620434/AnsiballZ_k8s_info.py", line 99, in _ansiballz_main\n invoke_module(zipped_mod, temp_path, ANSIBALLZ_PARAMS)\n File "/tmp/ansible-tmp-1759822363.633274-29376-123202735620434/AnsiballZ_k8s_info.py", 2025-10-07 07:32:44.962454 | controller | line 47, in invoke_module\n runpy.run_module(mod_name=\''ansible_collections.kubernetes.core.plugins.modules.k8s_info\'', init_globals=dict(_module_fqn=\''ansible_collections.kubernetes.core.plugins.modules.k8s_info\'', _modlib_path=modlib_path),\n File "/usr/lib64/python3.9/runpy.py", line 225, in run_module\n return _run_module_code(code, init_globals, run_name, mod_spec)\n File "/usr/lib64/python3.9/runpy.py", line 97, in _run_module_code\n _run_code(code, mod_globals, init_globals,\n File "/usr/lib64/python3.9/runpy.py", line 87, in _run_code\n exec(code, run_globals)\n File "/tmp/ansible_kubernetes.core.k8s_info_payload_hpi_519p/ansible_kubernetes.core.k8s_info_payload.zip/ansible_collections/kubernetes/core/plugins/modules/k8s_info.py", line 229, in \n File "/tmp/ansible_kubernetes.core.k8s_info_payload_hpi_519p/ansible_kubernetes.core.k8s_info_payload.zip/ansible_collections/kubernetes/core/plugins/modules/k8s_info.py", line 221, in main\n File "/tmp/ansible_kubernetes.core.k8s_info_payload_hpi_519p/ansible_kubernetes.core.k8s_info_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/k8s/client.py", 2025-10-07 07:32:44.962466 | controller | line 352, in get_api_client\n File "/tmp/ansible_kubernetes.core.k8s_info_payload_hpi_519p/ansible_kubernetes.core.k8s_info_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/k8s/client.py", line 246, in wrapper\n File "/tmp/ansible_kubernetes.core.k8s_info_payload_hpi_519p/ansible_kubernetes.core.k8s_info_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/k8s/client.py", line 259, in create_api_client\n File "/usr/local/lib/python3.9/site-packages/kubernetes/dynamic/client.py", line 84, in __init__\n self.__discoverer = discoverer(self, cache_file)\n File "/tmp/ansible_kubernetes.core.k8s_info_payload_hpi_519p/ansible_kubernetes.core.k8s_info_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/client/discovery.py", line 190, in __init__\n File "/tmp/ansible_kubernetes.core.k8s_info_payload_hpi_519p/ansible_kubernetes.core.k8s_info_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/client/discovery.py", line 2025-10-07 07:32:44.962572 | controller | 45, in __init__\n File "/tmp/ansible_kubernetes.core.k8s_info_payload_hpi_519p/ansible_kubernetes.core.k8s_info_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/client/discovery.py", line 92, in __init_cache\n File "/usr/local/lib/python3.9/site-packages/kubernetes/dynamic/discovery.py", line 146, in _load_server_info\n \''kubernetes\'': self.client.request(\''get\'', \''/version\'', serializer=just_json)\n File "/usr/local/lib/python3.9/site-packages/kubernetes/dynamic/client.py", line 55, in inner\n resp = func(self, *args, **kwargs)\n File "/usr/local/lib/python3.9/site-packages/kubernetes/dynamic/client.py", line 277, in request\n api_response = self.client.call_api(\n File "/usr/local/lib/python3.9/site-packages/kubernetes/client/api_client.py", line 348, in call_api\n return self.__call_api(resource_path, method,\n File "/usr/local/lib/python3.9/site-packages/kubernetes/client/api_client.py", line 180, in __call_api\n response_data = self.request(\n File 2025-10-07 07:32:44.962711 | controller | "/usr/local/lib/python3.9/site-packages/kubernetes/client/api_client.py", line 373, in request\n return self.rest_client.GET(url,\n File "/usr/local/lib/python3.9/site-packages/kubernetes/client/rest.py", line 244, in GET\n return self.request("GET", url,\n File "/usr/local/lib/python3.9/site-packages/kubernetes/client/rest.py", line 217, in request\n r = self.pool_manager.request(method, url,\n File "/usr/lib/python3.9/site-packages/urllib3/request.py", line 74, in request\n return self.request_encode_url(\n File "/usr/lib/python3.9/site-packages/urllib3/request.py", line 96, in request_encode_url\n return self.urlopen(method, url, **extra_kw)\n File "/usr/lib/python3.9/site-packages/urllib3/poolmanager.py", line 376, in urlopen\n response = conn.urlopen(method, u.request_uri, **kw)\n File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 784, in urlopen\n return self.urlopen(\n File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", 2025-10-07 07:32:44.962851 | controller | line 784, in urlopen\n return self.urlopen(\n File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 784, in urlopen\n return self.urlopen(\n File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 756, in urlopen\n retries = retries.increment(\n File "/usr/lib/python3.9/site-packages/urllib3/util/retry.py", line 576, in increment\n raise MaxRetryError(_pool, url, error or ResponseError(cause))\nurllib3.exceptions.MaxRetryError: HTTPSConnectionPool(host=\''api.test.example.com\'', port=6443): Max retries exceeded with url: /version (Caused by NewConnectionError(\'': Failed to establish a new connection: [Errno -2] Name or service not known\''))\n'', ''exception'': ''Traceback (most recent call last):\n File "/usr/lib/python3.9/site-packages/urllib3/connection.py", line 169, in _new_conn\n conn = connection.create_connection(\n File "/usr/lib/python3.9/site-packages/urllib3/util/connection.py", 2025-10-07 07:32:44.963005 | controller | line 73, in create_connection\n for res in socket.getaddrinfo(host, port, family, socket.SOCK_STREAM):\n File "/usr/lib64/python3.9/socket.py", line 966, in getaddrinfo\n for res in _socket.getaddrinfo(host, port, family, type, proto, flags):\nsocket.gaierror: [Errno -2] Name or service not known\n\nDuring handling of the above exception, another exception occurred:\n\nTraceback (most recent call last):\n File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 700, in urlopen\n httplib_response = self._make_request(\n File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 383, in _make_request\n self._validate_conn(conn)\n File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 1015, in _validate_conn\n conn.connect()\n File "/usr/lib/python3.9/site-packages/urllib3/connection.py", line 353, in connect\n conn = self._new_conn()\n File "/usr/lib/python3.9/site-packages/urllib3/connection.py", line 181, in _new_conn\n raise 2025-10-07 07:32:44.963131 | controller | NewConnectionError(\nurllib3.exceptions.NewConnectionError: : Failed to establish a new connection: [Errno -2] Name or service not known\n\nDuring handling of the above exception, another exception occurred:\n\nTraceback (most recent call last):\n File "/tmp/ansible-tmp-1759822363.633274-29376-123202735620434/AnsiballZ_k8s_info.py", line 107, in \n _ansiballz_main()\n File "/tmp/ansible-tmp-1759822363.633274-29376-123202735620434/AnsiballZ_k8s_info.py", line 99, in _ansiballz_main\n invoke_module(zipped_mod, temp_path, ANSIBALLZ_PARAMS)\n File "/tmp/ansible-tmp-1759822363.633274-29376-123202735620434/AnsiballZ_k8s_info.py", line 47, in invoke_module\n runpy.run_module(mod_name=\''ansible_collections.kubernetes.core.plugins.modules.k8s_info\'', init_globals=dict(_module_fqn=\''ansible_collections.kubernetes.core.plugins.modules.k8s_info\'', _modlib_path=modlib_path),\n File "/usr/lib64/python3.9/runpy.py", 2025-10-07 07:32:44.963260 | controller | line 225, in run_module\n return _run_module_code(code, init_globals, run_name, mod_spec)\n File "/usr/lib64/python3.9/runpy.py", line 97, in _run_module_code\n _run_code(code, mod_globals, init_globals,\n File "/usr/lib64/python3.9/runpy.py", line 87, in _run_code\n exec(code, run_globals)\n File "/tmp/ansible_kubernetes.core.k8s_info_payload_hpi_519p/ansible_kubernetes.core.k8s_info_payload.zip/ansible_collections/kubernetes/core/plugins/modules/k8s_info.py", line 229, in \n File "/tmp/ansible_kubernetes.core.k8s_info_payload_hpi_519p/ansible_kubernetes.core.k8s_info_payload.zip/ansible_collections/kubernetes/core/plugins/modules/k8s_info.py", line 221, in main\n File "/tmp/ansible_kubernetes.core.k8s_info_payload_hpi_519p/ansible_kubernetes.core.k8s_info_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/k8s/client.py", line 352, in get_api_client\n File "/tmp/ansible_kubernetes.core.k8s_info_payload_hpi_519p/ansible_kubernetes.core.k8s_info_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/k8s/client.py", 2025-10-07 07:32:44.963388 | controller | line 246, in wrapper\n File "/tmp/ansible_kubernetes.core.k8s_info_payload_hpi_519p/ansible_kubernetes.core.k8s_info_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/k8s/client.py", line 259, in create_api_client\n File "/usr/local/lib/python3.9/site-packages/kubernetes/dynamic/client.py", line 84, in __init__\n self.__discoverer = discoverer(self, cache_file)\n File "/tmp/ansible_kubernetes.core.k8s_info_payload_hpi_519p/ansible_kubernetes.core.k8s_info_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/client/discovery.py", line 190, in __init__\n File "/tmp/ansible_kubernetes.core.k8s_info_payload_hpi_519p/ansible_kubernetes.core.k8s_info_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/client/discovery.py", line 45, in __init__\n File "/tmp/ansible_kubernetes.core.k8s_info_payload_hpi_519p/ansible_kubernetes.core.k8s_info_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/client/discovery.py", line 2025-10-07 07:32:44.963522 | controller | 92, in __init_cache\n File "/usr/local/lib/python3.9/site-packages/kubernetes/dynamic/discovery.py", line 146, in _load_server_info\n \''kubernetes\'': self.client.request(\''get\'', \''/version\'', serializer=just_json)\n File "/usr/local/lib/python3.9/site-packages/kubernetes/dynamic/client.py", line 55, in inner\n resp = func(self, *args, **kwargs)\n File "/usr/local/lib/python3.9/site-packages/kubernetes/dynamic/client.py", line 277, in request\n api_response = self.client.call_api(\n File "/usr/local/lib/python3.9/site-packages/kubernetes/client/api_client.py", line 348, in call_api\n return self.__call_api(resource_path, method,\n File "/usr/local/lib/python3.9/site-packages/kubernetes/client/api_client.py", line 180, in __call_api\n response_data = self.request(\n File "/usr/local/lib/python3.9/site-packages/kubernetes/client/api_client.py", line 373, in request\n return self.rest_client.GET(url,\n File "/usr/local/lib/python3.9/site-packages/kubernetes/client/rest.py", 2025-10-07 07:32:44.963665 | controller | line 244, in GET\n return self.request("GET", url,\n File "/usr/local/lib/python3.9/site-packages/kubernetes/client/rest.py", line 217, in request\n r = self.pool_manager.request(method, url,\n File "/usr/lib/python3.9/site-packages/urllib3/request.py", line 74, in request\n return self.request_encode_url(\n File "/usr/lib/python3.9/site-packages/urllib3/request.py", line 96, in request_encode_url\n return self.urlopen(method, url, **extra_kw)\n File "/usr/lib/python3.9/site-packages/urllib3/poolmanager.py", line 376, in urlopen\n response = conn.urlopen(method, u.request_uri, **kw)\n File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 784, in urlopen\n return self.urlopen(\n File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 784, in urlopen\n return self.urlopen(\n File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 784, in urlopen\n return self.urlopen(\n File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", 2025-10-07 07:32:44.963784 | controller | line 756, in urlopen\n retries = retries.increment(\n File "/usr/lib/python3.9/site-packages/urllib3/util/retry.py", line 576, in increment\n raise MaxRetryError(_pool, url, error or ResponseError(cause))\nurllib3.exceptions.MaxRetryError: HTTPSConnectionPool(host=\''api.test.example.com\'', port=6443): Max retries exceeded with url: /version (Caused by NewConnectionError(\'': Failed to establish a new connection: [Errno -2] Name or service not known\''))\n'', ''msg'': ''MODULE FAILURE\nSee stdout/stderr for the exact error'', ''rc'': 1, ''changed'': False, ''failed_when_result'': False}' 2025-10-07 07:32:44.968448 | controller | 2025-10-07 07:32:44.987863 | controller | TASK [Test deployment verification behavior] *********************************** 2025-10-07 07:32:44.987878 | controller | Tuesday 07 October 2025 07:32:44 +0000 (0:00:00.024) 0:00:14.140 ******* 2025-10-07 07:32:44.987889 | controller | ok: [instance] => 2025-10-07 07:32:44.988066 | controller | msg: Testing deployment verification logic - expected to fail in mock environment 2025-10-07 07:32:44.988202 | controller | 2025-10-07 07:32:44.988339 | controller | TASK [Assert SNR deployment verification behaves as expected] ****************** 2025-10-07 07:32:44.988472 | controller | Tuesday 07 October 2025 07:32:44 +0000 (0:00:00.019) 0:00:14.160 ******* 2025-10-07 07:32:45.010005 | controller | ok: [instance] => changed=false 2025-10-07 07:32:45.010286 | controller | msg: SNR deployment verification logic behaves as expected 2025-10-07 07:32:45.010314 | controller | 2025-10-07 07:32:46.207582 | controller | TASK [Verify NHC Subscription exists] ****************************************** 2025-10-07 07:32:46.207635 | controller | Tuesday 07 October 2025 07:32:45 +0000 (0:00:00.021) 0:00:14.182 ******* 2025-10-07 07:32:46.207649 | controller | ok: [instance] 2025-10-07 07:32:46.223994 | controller | 2025-10-07 07:32:46.224029 | controller | TASK [Display NHC Subscription verification result] **************************** 2025-10-07 07:32:46.224038 | controller | Tuesday 07 October 2025 07:32:46 +0000 (0:00:01.197) 0:00:15.379 ******* 2025-10-07 07:32:46.224049 | controller | ok: [instance] => 2025-10-07 07:32:46.224522 | controller | msg: 'NHC Subscription verification result: {''failed'': False, ''module_stdout'': '''', ''module_stderr'': ''Traceback (most recent call last):\n File "/usr/lib/python3.9/site-packages/urllib3/connection.py", line 169, in _new_conn\n conn = connection.create_connection(\n File "/usr/lib/python3.9/site-packages/urllib3/util/connection.py", line 73, in create_connection\n for res in socket.getaddrinfo(host, port, family, socket.SOCK_STREAM):\n File "/usr/lib64/python3.9/socket.py", line 966, in getaddrinfo\n for res in _socket.getaddrinfo(host, port, family, type, proto, flags):\nsocket.gaierror: [Errno -2] Name or service not known\n\nDuring handling of the above exception, another exception occurred:\n\nTraceback (most recent call last):\n File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 700, in urlopen\n httplib_response = self._make_request(\n File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 383, in _make_request\n self._validate_conn(conn)\n File 2025-10-07 07:32:46.224554 | controller | "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 1015, in _validate_conn\n conn.connect()\n File "/usr/lib/python3.9/site-packages/urllib3/connection.py", line 353, in connect\n conn = self._new_conn()\n File "/usr/lib/python3.9/site-packages/urllib3/connection.py", line 181, in _new_conn\n raise NewConnectionError(\nurllib3.exceptions.NewConnectionError: : Failed to establish a new connection: [Errno -2] Name or service not known\n\nDuring handling of the above exception, another exception occurred:\n\nTraceback (most recent call last):\n File "/tmp/ansible-tmp-1759822365.0223029-29548-219770441301352/AnsiballZ_k8s.py", line 107, in \n _ansiballz_main()\n File "/tmp/ansible-tmp-1759822365.0223029-29548-219770441301352/AnsiballZ_k8s.py", line 99, in _ansiballz_main\n invoke_module(zipped_mod, temp_path, ANSIBALLZ_PARAMS)\n File "/tmp/ansible-tmp-1759822365.0223029-29548-219770441301352/AnsiballZ_k8s.py", 2025-10-07 07:32:46.224562 | controller | line 47, in invoke_module\n runpy.run_module(mod_name=\''ansible_collections.kubernetes.core.plugins.modules.k8s\'', init_globals=dict(_module_fqn=\''ansible_collections.kubernetes.core.plugins.modules.k8s\'', _modlib_path=modlib_path),\n File "/usr/lib64/python3.9/runpy.py", line 225, in run_module\n return _run_module_code(code, init_globals, run_name, mod_spec)\n File "/usr/lib64/python3.9/runpy.py", line 97, in _run_module_code\n _run_code(code, mod_globals, init_globals,\n File "/usr/lib64/python3.9/runpy.py", line 87, in _run_code\n exec(code, run_globals)\n File "/tmp/ansible_kubernetes.core.k8s_payload_tlj3bzkr/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/modules/k8s.py", line 509, in \n File "/tmp/ansible_kubernetes.core.k8s_payload_tlj3bzkr/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/modules/k8s.py", line 503, in main\n File "/tmp/ansible_kubernetes.core.k8s_payload_tlj3bzkr/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/k8s/runner.py", 2025-10-07 07:32:46.224575 | controller | line 88, in run_module\n File "/tmp/ansible_kubernetes.core.k8s_payload_tlj3bzkr/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/k8s/client.py", line 352, in get_api_client\n File "/tmp/ansible_kubernetes.core.k8s_payload_tlj3bzkr/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/k8s/client.py", line 246, in wrapper\n File "/tmp/ansible_kubernetes.core.k8s_payload_tlj3bzkr/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/k8s/client.py", line 259, in create_api_client\n File "/usr/local/lib/python3.9/site-packages/kubernetes/dynamic/client.py", line 84, in __init__\n self.__discoverer = discoverer(self, cache_file)\n File "/tmp/ansible_kubernetes.core.k8s_payload_tlj3bzkr/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/client/discovery.py", line 190, in __init__\n File "/tmp/ansible_kubernetes.core.k8s_payload_tlj3bzkr/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/client/discovery.py", 2025-10-07 07:32:46.224585 | controller | line 45, in __init__\n File "/tmp/ansible_kubernetes.core.k8s_payload_tlj3bzkr/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/client/discovery.py", line 92, in __init_cache\n File "/usr/local/lib/python3.9/site-packages/kubernetes/dynamic/discovery.py", line 146, in _load_server_info\n \''kubernetes\'': self.client.request(\''get\'', \''/version\'', serializer=just_json)\n File "/usr/local/lib/python3.9/site-packages/kubernetes/dynamic/client.py", line 55, in inner\n resp = func(self, *args, **kwargs)\n File "/usr/local/lib/python3.9/site-packages/kubernetes/dynamic/client.py", line 277, in request\n api_response = self.client.call_api(\n File "/usr/local/lib/python3.9/site-packages/kubernetes/client/api_client.py", line 348, in call_api\n return self.__call_api(resource_path, method,\n File "/usr/local/lib/python3.9/site-packages/kubernetes/client/api_client.py", line 180, in __call_api\n response_data = self.request(\n File 2025-10-07 07:32:46.224614 | controller | "/usr/local/lib/python3.9/site-packages/kubernetes/client/api_client.py", line 373, in request\n return self.rest_client.GET(url,\n File "/usr/local/lib/python3.9/site-packages/kubernetes/client/rest.py", line 244, in GET\n return self.request("GET", url,\n File "/usr/local/lib/python3.9/site-packages/kubernetes/client/rest.py", line 217, in request\n r = self.pool_manager.request(method, url,\n File "/usr/lib/python3.9/site-packages/urllib3/request.py", line 74, in request\n return self.request_encode_url(\n File "/usr/lib/python3.9/site-packages/urllib3/request.py", line 96, in request_encode_url\n return self.urlopen(method, url, **extra_kw)\n File "/usr/lib/python3.9/site-packages/urllib3/poolmanager.py", line 376, in urlopen\n response = conn.urlopen(method, u.request_uri, **kw)\n File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 784, in urlopen\n return self.urlopen(\n File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", 2025-10-07 07:32:46.224624 | controller | line 784, in urlopen\n return self.urlopen(\n File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 784, in urlopen\n return self.urlopen(\n File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 756, in urlopen\n retries = retries.increment(\n File "/usr/lib/python3.9/site-packages/urllib3/util/retry.py", line 576, in increment\n raise MaxRetryError(_pool, url, error or ResponseError(cause))\nurllib3.exceptions.MaxRetryError: HTTPSConnectionPool(host=\''api.test.example.com\'', port=6443): Max retries exceeded with url: /version (Caused by NewConnectionError(\'': Failed to establish a new connection: [Errno -2] Name or service not known\''))\n'', ''exception'': ''Traceback (most recent call last):\n File "/usr/lib/python3.9/site-packages/urllib3/connection.py", line 169, in _new_conn\n conn = connection.create_connection(\n File "/usr/lib/python3.9/site-packages/urllib3/util/connection.py", 2025-10-07 07:32:46.224649 | controller | line 73, in create_connection\n for res in socket.getaddrinfo(host, port, family, socket.SOCK_STREAM):\n File "/usr/lib64/python3.9/socket.py", line 966, in getaddrinfo\n for res in _socket.getaddrinfo(host, port, family, type, proto, flags):\nsocket.gaierror: [Errno -2] Name or service not known\n\nDuring handling of the above exception, another exception occurred:\n\nTraceback (most recent call last):\n File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 700, in urlopen\n httplib_response = self._make_request(\n File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 383, in _make_request\n self._validate_conn(conn)\n File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 1015, in _validate_conn\n conn.connect()\n File "/usr/lib/python3.9/site-packages/urllib3/connection.py", line 353, in connect\n conn = self._new_conn()\n File "/usr/lib/python3.9/site-packages/urllib3/connection.py", line 181, in _new_conn\n raise 2025-10-07 07:32:46.225402 | controller | NewConnectionError(\nurllib3.exceptions.NewConnectionError: : Failed to establish a new connection: [Errno -2] Name or service not known\n\nDuring handling of the above exception, another exception occurred:\n\nTraceback (most recent call last):\n File "/tmp/ansible-tmp-1759822365.0223029-29548-219770441301352/AnsiballZ_k8s.py", line 107, in \n _ansiballz_main()\n File "/tmp/ansible-tmp-1759822365.0223029-29548-219770441301352/AnsiballZ_k8s.py", line 99, in _ansiballz_main\n invoke_module(zipped_mod, temp_path, ANSIBALLZ_PARAMS)\n File "/tmp/ansible-tmp-1759822365.0223029-29548-219770441301352/AnsiballZ_k8s.py", line 47, in invoke_module\n runpy.run_module(mod_name=\''ansible_collections.kubernetes.core.plugins.modules.k8s\'', init_globals=dict(_module_fqn=\''ansible_collections.kubernetes.core.plugins.modules.k8s\'', _modlib_path=modlib_path),\n File "/usr/lib64/python3.9/runpy.py", line 225, in run_module\n return 2025-10-07 07:32:46.225428 | controller | _run_module_code(code, init_globals, run_name, mod_spec)\n File "/usr/lib64/python3.9/runpy.py", line 97, in _run_module_code\n _run_code(code, mod_globals, init_globals,\n File "/usr/lib64/python3.9/runpy.py", line 87, in _run_code\n exec(code, run_globals)\n File "/tmp/ansible_kubernetes.core.k8s_payload_tlj3bzkr/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/modules/k8s.py", line 509, in \n File "/tmp/ansible_kubernetes.core.k8s_payload_tlj3bzkr/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/modules/k8s.py", line 503, in main\n File "/tmp/ansible_kubernetes.core.k8s_payload_tlj3bzkr/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/k8s/runner.py", line 88, in run_module\n File "/tmp/ansible_kubernetes.core.k8s_payload_tlj3bzkr/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/k8s/client.py", line 352, 2025-10-07 07:32:46.225436 | controller | in get_api_client\n File "/tmp/ansible_kubernetes.core.k8s_payload_tlj3bzkr/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/k8s/client.py", line 246, in wrapper\n File "/tmp/ansible_kubernetes.core.k8s_payload_tlj3bzkr/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/k8s/client.py", line 259, in create_api_client\n File "/usr/local/lib/python3.9/site-packages/kubernetes/dynamic/client.py", line 84, in __init__\n self.__discoverer = discoverer(self, cache_file)\n File "/tmp/ansible_kubernetes.core.k8s_payload_tlj3bzkr/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/client/discovery.py", line 190, in __init__\n File "/tmp/ansible_kubernetes.core.k8s_payload_tlj3bzkr/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/client/discovery.py", line 45, in __init__\n File "/tmp/ansible_kubernetes.core.k8s_payload_tlj3bzkr/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/client/discovery.py", 2025-10-07 07:32:46.225449 | controller | line 92, in __init_cache\n File "/usr/local/lib/python3.9/site-packages/kubernetes/dynamic/discovery.py", line 146, in _load_server_info\n \''kubernetes\'': self.client.request(\''get\'', \''/version\'', serializer=just_json)\n File "/usr/local/lib/python3.9/site-packages/kubernetes/dynamic/client.py", line 55, in inner\n resp = func(self, *args, **kwargs)\n File "/usr/local/lib/python3.9/site-packages/kubernetes/dynamic/client.py", line 277, in request\n api_response = self.client.call_api(\n File "/usr/local/lib/python3.9/site-packages/kubernetes/client/api_client.py", line 348, in call_api\n return self.__call_api(resource_path, method,\n File "/usr/local/lib/python3.9/site-packages/kubernetes/client/api_client.py", line 180, in __call_api\n response_data = self.request(\n File "/usr/local/lib/python3.9/site-packages/kubernetes/client/api_client.py", line 373, in request\n return self.rest_client.GET(url,\n File "/usr/local/lib/python3.9/site-packages/kubernetes/client/rest.py", 2025-10-07 07:32:46.229521 | controller | line 244, in GET\n return self.request("GET", url,\n File "/usr/local/lib/python3.9/site-packages/kubernetes/client/rest.py", line 217, in request\n r = self.pool_manager.request(method, url,\n File "/usr/lib/python3.9/site-packages/urllib3/request.py", line 74, in request\n return self.request_encode_url(\n File "/usr/lib/python3.9/site-packages/urllib3/request.py", line 96, in request_encode_url\n return self.urlopen(method, url, **extra_kw)\n File "/usr/lib/python3.9/site-packages/urllib3/poolmanager.py", line 376, in urlopen\n response = conn.urlopen(method, u.request_uri, **kw)\n File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 784, in urlopen\n return self.urlopen(\n File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 784, in urlopen\n return self.urlopen(\n File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 784, in urlopen\n return self.urlopen(\n File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", 2025-10-07 07:32:46.229538 | controller | line 756, in urlopen\n retries = retries.increment(\n File "/usr/lib/python3.9/site-packages/urllib3/util/retry.py", line 576, in increment\n raise MaxRetryError(_pool, url, error or ResponseError(cause))\nurllib3.exceptions.MaxRetryError: HTTPSConnectionPool(host=\''api.test.example.com\'', port=6443): Max retries exceeded with url: /version (Caused by NewConnectionError(\'': Failed to establish a new connection: [Errno -2] Name or service not known\''))\n'', ''msg'': ''MODULE FAILURE\nSee stdout/stderr for the exact error'', ''rc'': 1, ''changed'': False, ''failed_when_result'': False}' 2025-10-07 07:32:46.229548 | controller | 2025-10-07 07:32:47.434682 | controller | TASK [Test NHC Subscription creation idempotency] ****************************** 2025-10-07 07:32:47.434712 | controller | Tuesday 07 October 2025 07:32:46 +0000 (0:00:00.022) 0:00:15.402 ******* 2025-10-07 07:32:47.434724 | controller | ok: [instance] 2025-10-07 07:32:47.458731 | controller | 2025-10-07 07:32:47.458761 | controller | TASK [Assert expected behavior for NHC Subscription creation] ****************** 2025-10-07 07:32:47.458769 | controller | Tuesday 07 October 2025 07:32:47 +0000 (0:00:01.204) 0:00:16.606 ******* 2025-10-07 07:32:47.458779 | controller | ok: [instance] => changed=false 2025-10-07 07:32:48.619100 | controller | msg: NHC Subscription creation task behaves consistently 2025-10-07 07:32:48.619133 | controller | 2025-10-07 07:32:48.619141 | controller | TASK [Verify CSV status] ******************************************************* 2025-10-07 07:32:48.619147 | controller | Tuesday 07 October 2025 07:32:47 +0000 (0:00:00.024) 0:00:16.630 ******* 2025-10-07 07:32:48.619163 | controller | ok: [instance] 2025-10-07 07:32:48.619182 | controller | 2025-10-07 07:32:48.619284 | controller | TASK [Display CSV verification result] ***************************************** 2025-10-07 07:32:48.619404 | controller | Tuesday 07 October 2025 07:32:48 +0000 (0:00:01.160) 0:00:17.791 ******* 2025-10-07 07:32:48.635992 | controller | ok: [instance] => 2025-10-07 07:32:48.636127 | controller | msg: 'CSV verification result: {''failed'': False, ''module_stdout'': '''', ''module_stderr'': ''Traceback (most recent call last):\n File "/usr/lib/python3.9/site-packages/urllib3/connection.py", line 169, in _new_conn\n conn = connection.create_connection(\n File "/usr/lib/python3.9/site-packages/urllib3/util/connection.py", line 73, in create_connection\n for res in socket.getaddrinfo(host, port, family, socket.SOCK_STREAM):\n File "/usr/lib64/python3.9/socket.py", line 966, in getaddrinfo\n for res in _socket.getaddrinfo(host, port, family, type, proto, flags):\nsocket.gaierror: [Errno -2] Name or service not known\n\nDuring handling of the above exception, another exception occurred:\n\nTraceback (most recent call last):\n File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 700, in urlopen\n httplib_response = self._make_request(\n File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 383, in _make_request\n self._validate_conn(conn)\n File 2025-10-07 07:32:48.636273 | controller | "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 1015, in _validate_conn\n conn.connect()\n File "/usr/lib/python3.9/site-packages/urllib3/connection.py", line 353, in connect\n conn = self._new_conn()\n File "/usr/lib/python3.9/site-packages/urllib3/connection.py", line 181, in _new_conn\n raise NewConnectionError(\nurllib3.exceptions.NewConnectionError: : Failed to establish a new connection: [Errno -2] Name or service not known\n\nDuring handling of the above exception, another exception occurred:\n\nTraceback (most recent call last):\n File "/tmp/ansible-tmp-1759822367.470852-29889-222165761240183/AnsiballZ_k8s_info.py", line 107, in \n _ansiballz_main()\n File "/tmp/ansible-tmp-1759822367.470852-29889-222165761240183/AnsiballZ_k8s_info.py", line 99, in _ansiballz_main\n invoke_module(zipped_mod, temp_path, ANSIBALLZ_PARAMS)\n File "/tmp/ansible-tmp-1759822367.470852-29889-222165761240183/AnsiballZ_k8s_info.py", 2025-10-07 07:32:48.637281 | controller | line 47, in invoke_module\n runpy.run_module(mod_name=\''ansible_collections.kubernetes.core.plugins.modules.k8s_info\'', init_globals=dict(_module_fqn=\''ansible_collections.kubernetes.core.plugins.modules.k8s_info\'', _modlib_path=modlib_path),\n File "/usr/lib64/python3.9/runpy.py", line 225, in run_module\n return _run_module_code(code, init_globals, run_name, mod_spec)\n File "/usr/lib64/python3.9/runpy.py", line 97, in _run_module_code\n _run_code(code, mod_globals, init_globals,\n File "/usr/lib64/python3.9/runpy.py", line 87, in _run_code\n exec(code, run_globals)\n File "/tmp/ansible_kubernetes.core.k8s_info_payload_nor_npb3/ansible_kubernetes.core.k8s_info_payload.zip/ansible_collections/kubernetes/core/plugins/modules/k8s_info.py", line 229, in \n File "/tmp/ansible_kubernetes.core.k8s_info_payload_nor_npb3/ansible_kubernetes.core.k8s_info_payload.zip/ansible_collections/kubernetes/core/plugins/modules/k8s_info.py", line 221, in main\n File "/tmp/ansible_kubernetes.core.k8s_info_payload_nor_npb3/ansible_kubernetes.core.k8s_info_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/k8s/client.py", 2025-10-07 07:32:48.637305 | controller | line 352, in get_api_client\n File "/tmp/ansible_kubernetes.core.k8s_info_payload_nor_npb3/ansible_kubernetes.core.k8s_info_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/k8s/client.py", line 246, in wrapper\n File "/tmp/ansible_kubernetes.core.k8s_info_payload_nor_npb3/ansible_kubernetes.core.k8s_info_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/k8s/client.py", line 259, in create_api_client\n File "/usr/local/lib/python3.9/site-packages/kubernetes/dynamic/client.py", line 84, in __init__\n self.__discoverer = discoverer(self, cache_file)\n File "/tmp/ansible_kubernetes.core.k8s_info_payload_nor_npb3/ansible_kubernetes.core.k8s_info_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/client/discovery.py", line 190, in __init__\n File "/tmp/ansible_kubernetes.core.k8s_info_payload_nor_npb3/ansible_kubernetes.core.k8s_info_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/client/discovery.py", line 2025-10-07 07:32:48.637316 | controller | 45, in __init__\n File "/tmp/ansible_kubernetes.core.k8s_info_payload_nor_npb3/ansible_kubernetes.core.k8s_info_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/client/discovery.py", line 92, in __init_cache\n File "/usr/local/lib/python3.9/site-packages/kubernetes/dynamic/discovery.py", line 146, in _load_server_info\n \''kubernetes\'': self.client.request(\''get\'', \''/version\'', serializer=just_json)\n File "/usr/local/lib/python3.9/site-packages/kubernetes/dynamic/client.py", line 55, in inner\n resp = func(self, *args, **kwargs)\n File "/usr/local/lib/python3.9/site-packages/kubernetes/dynamic/client.py", line 277, in request\n api_response = self.client.call_api(\n File "/usr/local/lib/python3.9/site-packages/kubernetes/client/api_client.py", line 348, in call_api\n return self.__call_api(resource_path, method,\n File "/usr/local/lib/python3.9/site-packages/kubernetes/client/api_client.py", line 180, in __call_api\n response_data = self.request(\n File 2025-10-07 07:32:48.637326 | controller | "/usr/local/lib/python3.9/site-packages/kubernetes/client/api_client.py", line 373, in request\n return self.rest_client.GET(url,\n File "/usr/local/lib/python3.9/site-packages/kubernetes/client/rest.py", line 244, in GET\n return self.request("GET", url,\n File "/usr/local/lib/python3.9/site-packages/kubernetes/client/rest.py", line 217, in request\n r = self.pool_manager.request(method, url,\n File "/usr/lib/python3.9/site-packages/urllib3/request.py", line 74, in request\n return self.request_encode_url(\n File "/usr/lib/python3.9/site-packages/urllib3/request.py", line 96, in request_encode_url\n return self.urlopen(method, url, **extra_kw)\n File "/usr/lib/python3.9/site-packages/urllib3/poolmanager.py", line 376, in urlopen\n response = conn.urlopen(method, u.request_uri, **kw)\n File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 784, in urlopen\n return self.urlopen(\n File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", 2025-10-07 07:32:48.637352 | controller | line 784, in urlopen\n return self.urlopen(\n File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 784, in urlopen\n return self.urlopen(\n File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 756, in urlopen\n retries = retries.increment(\n File "/usr/lib/python3.9/site-packages/urllib3/util/retry.py", line 576, in increment\n raise MaxRetryError(_pool, url, error or ResponseError(cause))\nurllib3.exceptions.MaxRetryError: HTTPSConnectionPool(host=\''api.test.example.com\'', port=6443): Max retries exceeded with url: /version (Caused by NewConnectionError(\'': Failed to establish a new connection: [Errno -2] Name or service not known\''))\n'', ''exception'': ''Traceback (most recent call last):\n File "/usr/lib/python3.9/site-packages/urllib3/connection.py", line 169, in _new_conn\n conn = connection.create_connection(\n File "/usr/lib/python3.9/site-packages/urllib3/util/connection.py", 2025-10-07 07:32:48.637359 | controller | line 73, in create_connection\n for res in socket.getaddrinfo(host, port, family, socket.SOCK_STREAM):\n File "/usr/lib64/python3.9/socket.py", line 966, in getaddrinfo\n for res in _socket.getaddrinfo(host, port, family, type, proto, flags):\nsocket.gaierror: [Errno -2] Name or service not known\n\nDuring handling of the above exception, another exception occurred:\n\nTraceback (most recent call last):\n File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 700, in urlopen\n httplib_response = self._make_request(\n File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 383, in _make_request\n self._validate_conn(conn)\n File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 1015, in _validate_conn\n conn.connect()\n File "/usr/lib/python3.9/site-packages/urllib3/connection.py", line 353, in connect\n conn = self._new_conn()\n File "/usr/lib/python3.9/site-packages/urllib3/connection.py", line 181, in _new_conn\n raise 2025-10-07 07:32:48.637367 | controller | NewConnectionError(\nurllib3.exceptions.NewConnectionError: : Failed to establish a new connection: [Errno -2] Name or service not known\n\nDuring handling of the above exception, another exception occurred:\n\nTraceback (most recent call last):\n File "/tmp/ansible-tmp-1759822367.470852-29889-222165761240183/AnsiballZ_k8s_info.py", line 107, in \n _ansiballz_main()\n File "/tmp/ansible-tmp-1759822367.470852-29889-222165761240183/AnsiballZ_k8s_info.py", line 99, in _ansiballz_main\n invoke_module(zipped_mod, temp_path, ANSIBALLZ_PARAMS)\n File "/tmp/ansible-tmp-1759822367.470852-29889-222165761240183/AnsiballZ_k8s_info.py", line 47, in invoke_module\n runpy.run_module(mod_name=\''ansible_collections.kubernetes.core.plugins.modules.k8s_info\'', init_globals=dict(_module_fqn=\''ansible_collections.kubernetes.core.plugins.modules.k8s_info\'', _modlib_path=modlib_path),\n File "/usr/lib64/python3.9/runpy.py", 2025-10-07 07:32:48.637374 | controller | line 225, in run_module\n return _run_module_code(code, init_globals, run_name, mod_spec)\n File "/usr/lib64/python3.9/runpy.py", line 97, in _run_module_code\n _run_code(code, mod_globals, init_globals,\n File "/usr/lib64/python3.9/runpy.py", line 87, in _run_code\n exec(code, run_globals)\n File "/tmp/ansible_kubernetes.core.k8s_info_payload_nor_npb3/ansible_kubernetes.core.k8s_info_payload.zip/ansible_collections/kubernetes/core/plugins/modules/k8s_info.py", line 229, in \n File "/tmp/ansible_kubernetes.core.k8s_info_payload_nor_npb3/ansible_kubernetes.core.k8s_info_payload.zip/ansible_collections/kubernetes/core/plugins/modules/k8s_info.py", line 221, in main\n File "/tmp/ansible_kubernetes.core.k8s_info_payload_nor_npb3/ansible_kubernetes.core.k8s_info_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/k8s/client.py", line 352, in get_api_client\n File "/tmp/ansible_kubernetes.core.k8s_info_payload_nor_npb3/ansible_kubernetes.core.k8s_info_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/k8s/client.py", 2025-10-07 07:32:48.637854 | controller | line 246, in wrapper\n File "/tmp/ansible_kubernetes.core.k8s_info_payload_nor_npb3/ansible_kubernetes.core.k8s_info_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/k8s/client.py", line 259, in create_api_client\n File "/usr/local/lib/python3.9/site-packages/kubernetes/dynamic/client.py", line 84, in __init__\n self.__discoverer = discoverer(self, cache_file)\n File "/tmp/ansible_kubernetes.core.k8s_info_payload_nor_npb3/ansible_kubernetes.core.k8s_info_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/client/discovery.py", line 190, in __init__\n File "/tmp/ansible_kubernetes.core.k8s_info_payload_nor_npb3/ansible_kubernetes.core.k8s_info_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/client/discovery.py", line 45, in __init__\n File "/tmp/ansible_kubernetes.core.k8s_info_payload_nor_npb3/ansible_kubernetes.core.k8s_info_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/client/discovery.py", line 2025-10-07 07:32:48.643316 | controller | 92, in __init_cache\n File "/usr/local/lib/python3.9/site-packages/kubernetes/dynamic/discovery.py", line 146, in _load_server_info\n \''kubernetes\'': self.client.request(\''get\'', \''/version\'', serializer=just_json)\n File "/usr/local/lib/python3.9/site-packages/kubernetes/dynamic/client.py", line 55, in inner\n resp = func(self, *args, **kwargs)\n File "/usr/local/lib/python3.9/site-packages/kubernetes/dynamic/client.py", line 277, in request\n api_response = self.client.call_api(\n File "/usr/local/lib/python3.9/site-packages/kubernetes/client/api_client.py", line 348, in call_api\n return self.__call_api(resource_path, method,\n File "/usr/local/lib/python3.9/site-packages/kubernetes/client/api_client.py", line 180, in __call_api\n response_data = self.request(\n File "/usr/local/lib/python3.9/site-packages/kubernetes/client/api_client.py", line 373, in request\n return self.rest_client.GET(url,\n File "/usr/local/lib/python3.9/site-packages/kubernetes/client/rest.py", 2025-10-07 07:32:48.643347 | controller | line 244, in GET\n return self.request("GET", url,\n File "/usr/local/lib/python3.9/site-packages/kubernetes/client/rest.py", line 217, in request\n r = self.pool_manager.request(method, url,\n File "/usr/lib/python3.9/site-packages/urllib3/request.py", line 74, in request\n return self.request_encode_url(\n File "/usr/lib/python3.9/site-packages/urllib3/request.py", line 96, in request_encode_url\n return self.urlopen(method, url, **extra_kw)\n File "/usr/lib/python3.9/site-packages/urllib3/poolmanager.py", line 376, in urlopen\n response = conn.urlopen(method, u.request_uri, **kw)\n File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 784, in urlopen\n return self.urlopen(\n File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 784, in urlopen\n return self.urlopen(\n File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 784, in urlopen\n return self.urlopen(\n File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", 2025-10-07 07:32:48.643357 | controller | line 756, in urlopen\n retries = retries.increment(\n File "/usr/lib/python3.9/site-packages/urllib3/util/retry.py", line 576, in increment\n raise MaxRetryError(_pool, url, error or ResponseError(cause))\nurllib3.exceptions.MaxRetryError: HTTPSConnectionPool(host=\''api.test.example.com\'', port=6443): Max retries exceeded with url: /version (Caused by NewConnectionError(\'': Failed to establish a new connection: [Errno -2] Name or service not known\''))\n'', ''msg'': ''MODULE FAILURE\nSee stdout/stderr for the exact error'', ''rc'': 1, ''changed'': False, ''failed_when_result'': False}' 2025-10-07 07:32:48.643369 | controller | 2025-10-07 07:32:48.661365 | controller | TASK [Test CSV verification behavior] ****************************************** 2025-10-07 07:32:48.661384 | controller | Tuesday 07 October 2025 07:32:48 +0000 (0:00:00.024) 0:00:17.815 ******* 2025-10-07 07:32:48.661394 | controller | ok: [instance] => 2025-10-07 07:32:48.681718 | controller | msg: Testing CSV verification logic - expected to fail in mock environment 2025-10-07 07:32:48.681737 | controller | 2025-10-07 07:32:48.681745 | controller | TASK [Assert CSV verification behaves as expected] ***************************** 2025-10-07 07:32:48.681752 | controller | Tuesday 07 October 2025 07:32:48 +0000 (0:00:00.017) 0:00:17.833 ******* 2025-10-07 07:32:48.681762 | controller | ok: [instance] => changed=false 2025-10-07 07:32:48.681968 | controller | msg: CSV verification logic behaves as expected 2025-10-07 07:32:48.682139 | controller | 2025-10-07 07:32:48.682309 | controller | TASK [Verify NHC CR exists] **************************************************** 2025-10-07 07:32:48.682478 | controller | Tuesday 07 October 2025 07:32:48 +0000 (0:00:00.020) 0:00:17.854 ******* 2025-10-07 07:32:49.806741 | controller | ok: [instance] 2025-10-07 07:32:49.825742 | controller | 2025-10-07 07:32:49.825771 | controller | TASK [Display NHC CR verification result] ************************************** 2025-10-07 07:32:49.825779 | controller | Tuesday 07 October 2025 07:32:49 +0000 (0:00:01.124) 0:00:18.978 ******* 2025-10-07 07:32:49.825790 | controller | ok: [instance] => 2025-10-07 07:32:49.825805 | controller | msg: 'NHC CR verification result: {''failed'': False, ''module_stdout'': '''', ''module_stderr'': ''Traceback (most recent call last):\n File "/usr/lib/python3.9/site-packages/urllib3/connection.py", line 169, in _new_conn\n conn = connection.create_connection(\n File "/usr/lib/python3.9/site-packages/urllib3/util/connection.py", line 73, in create_connection\n for res in socket.getaddrinfo(host, port, family, socket.SOCK_STREAM):\n File "/usr/lib64/python3.9/socket.py", line 966, in getaddrinfo\n for res in _socket.getaddrinfo(host, port, family, type, proto, flags):\nsocket.gaierror: [Errno -2] Name or service not known\n\nDuring handling of the above exception, another exception occurred:\n\nTraceback (most recent call last):\n File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 700, in urlopen\n httplib_response = self._make_request(\n File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 383, in _make_request\n self._validate_conn(conn)\n File 2025-10-07 07:32:49.825825 | controller | "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 1015, in _validate_conn\n conn.connect()\n File "/usr/lib/python3.9/site-packages/urllib3/connection.py", line 353, in connect\n conn = self._new_conn()\n File "/usr/lib/python3.9/site-packages/urllib3/connection.py", line 181, in _new_conn\n raise NewConnectionError(\nurllib3.exceptions.NewConnectionError: : Failed to establish a new connection: [Errno -2] Name or service not known\n\nDuring handling of the above exception, another exception occurred:\n\nTraceback (most recent call last):\n File "/tmp/ansible-tmp-1759822368.6944952-30062-216115356674253/AnsiballZ_k8s.py", line 107, in \n _ansiballz_main()\n File "/tmp/ansible-tmp-1759822368.6944952-30062-216115356674253/AnsiballZ_k8s.py", line 99, in _ansiballz_main\n invoke_module(zipped_mod, temp_path, ANSIBALLZ_PARAMS)\n File "/tmp/ansible-tmp-1759822368.6944952-30062-216115356674253/AnsiballZ_k8s.py", 2025-10-07 07:32:49.825832 | controller | line 47, in invoke_module\n runpy.run_module(mod_name=\''ansible_collections.kubernetes.core.plugins.modules.k8s\'', init_globals=dict(_module_fqn=\''ansible_collections.kubernetes.core.plugins.modules.k8s\'', _modlib_path=modlib_path),\n File "/usr/lib64/python3.9/runpy.py", line 225, in run_module\n return _run_module_code(code, init_globals, run_name, mod_spec)\n File "/usr/lib64/python3.9/runpy.py", line 97, in _run_module_code\n _run_code(code, mod_globals, init_globals,\n File "/usr/lib64/python3.9/runpy.py", line 87, in _run_code\n exec(code, run_globals)\n File "/tmp/ansible_kubernetes.core.k8s_payload_u33qvgkf/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/modules/k8s.py", line 509, in \n File "/tmp/ansible_kubernetes.core.k8s_payload_u33qvgkf/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/modules/k8s.py", line 503, in main\n File "/tmp/ansible_kubernetes.core.k8s_payload_u33qvgkf/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/k8s/runner.py", 2025-10-07 07:32:49.825845 | controller | line 88, in run_module\n File "/tmp/ansible_kubernetes.core.k8s_payload_u33qvgkf/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/k8s/client.py", line 352, in get_api_client\n File "/tmp/ansible_kubernetes.core.k8s_payload_u33qvgkf/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/k8s/client.py", line 246, in wrapper\n File "/tmp/ansible_kubernetes.core.k8s_payload_u33qvgkf/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/k8s/client.py", line 259, in create_api_client\n File "/usr/local/lib/python3.9/site-packages/kubernetes/dynamic/client.py", line 84, in __init__\n self.__discoverer = discoverer(self, cache_file)\n File "/tmp/ansible_kubernetes.core.k8s_payload_u33qvgkf/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/client/discovery.py", line 190, in __init__\n File "/tmp/ansible_kubernetes.core.k8s_payload_u33qvgkf/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/client/discovery.py", 2025-10-07 07:32:49.825854 | controller | line 45, in __init__\n File "/tmp/ansible_kubernetes.core.k8s_payload_u33qvgkf/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/client/discovery.py", line 92, in __init_cache\n File "/usr/local/lib/python3.9/site-packages/kubernetes/dynamic/discovery.py", line 146, in _load_server_info\n \''kubernetes\'': self.client.request(\''get\'', \''/version\'', serializer=just_json)\n File "/usr/local/lib/python3.9/site-packages/kubernetes/dynamic/client.py", line 55, in inner\n resp = func(self, *args, **kwargs)\n File "/usr/local/lib/python3.9/site-packages/kubernetes/dynamic/client.py", line 277, in request\n api_response = self.client.call_api(\n File "/usr/local/lib/python3.9/site-packages/kubernetes/client/api_client.py", line 348, in call_api\n return self.__call_api(resource_path, method,\n File "/usr/local/lib/python3.9/site-packages/kubernetes/client/api_client.py", line 180, in __call_api\n response_data = self.request(\n File 2025-10-07 07:32:49.825860 | controller | "/usr/local/lib/python3.9/site-packages/kubernetes/client/api_client.py", line 373, in request\n return self.rest_client.GET(url,\n File "/usr/local/lib/python3.9/site-packages/kubernetes/client/rest.py", line 244, in GET\n return self.request("GET", url,\n File "/usr/local/lib/python3.9/site-packages/kubernetes/client/rest.py", line 217, in request\n r = self.pool_manager.request(method, url,\n File "/usr/lib/python3.9/site-packages/urllib3/request.py", line 74, in request\n return self.request_encode_url(\n File "/usr/lib/python3.9/site-packages/urllib3/request.py", line 96, in request_encode_url\n return self.urlopen(method, url, **extra_kw)\n File "/usr/lib/python3.9/site-packages/urllib3/poolmanager.py", line 376, in urlopen\n response = conn.urlopen(method, u.request_uri, **kw)\n File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 784, in urlopen\n return self.urlopen(\n File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", 2025-10-07 07:32:49.825866 | controller | line 784, in urlopen\n return self.urlopen(\n File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 784, in urlopen\n return self.urlopen(\n File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 756, in urlopen\n retries = retries.increment(\n File "/usr/lib/python3.9/site-packages/urllib3/util/retry.py", line 576, in increment\n raise MaxRetryError(_pool, url, error or ResponseError(cause))\nurllib3.exceptions.MaxRetryError: HTTPSConnectionPool(host=\''api.test.example.com\'', port=6443): Max retries exceeded with url: /version (Caused by NewConnectionError(\'': Failed to establish a new connection: [Errno -2] Name or service not known\''))\n'', ''exception'': ''Traceback (most recent call last):\n File "/usr/lib/python3.9/site-packages/urllib3/connection.py", line 169, in _new_conn\n conn = connection.create_connection(\n File "/usr/lib/python3.9/site-packages/urllib3/util/connection.py", 2025-10-07 07:32:49.825877 | controller | line 73, in create_connection\n for res in socket.getaddrinfo(host, port, family, socket.SOCK_STREAM):\n File "/usr/lib64/python3.9/socket.py", line 966, in getaddrinfo\n for res in _socket.getaddrinfo(host, port, family, type, proto, flags):\nsocket.gaierror: [Errno -2] Name or service not known\n\nDuring handling of the above exception, another exception occurred:\n\nTraceback (most recent call last):\n File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 700, in urlopen\n httplib_response = self._make_request(\n File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 383, in _make_request\n self._validate_conn(conn)\n File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 1015, in _validate_conn\n conn.connect()\n File "/usr/lib/python3.9/site-packages/urllib3/connection.py", line 353, in connect\n conn = self._new_conn()\n File "/usr/lib/python3.9/site-packages/urllib3/connection.py", line 181, in _new_conn\n raise 2025-10-07 07:32:49.825885 | controller | NewConnectionError(\nurllib3.exceptions.NewConnectionError: : Failed to establish a new connection: [Errno -2] Name or service not known\n\nDuring handling of the above exception, another exception occurred:\n\nTraceback (most recent call last):\n File "/tmp/ansible-tmp-1759822368.6944952-30062-216115356674253/AnsiballZ_k8s.py", line 107, in \n _ansiballz_main()\n File "/tmp/ansible-tmp-1759822368.6944952-30062-216115356674253/AnsiballZ_k8s.py", line 99, in _ansiballz_main\n invoke_module(zipped_mod, temp_path, ANSIBALLZ_PARAMS)\n File "/tmp/ansible-tmp-1759822368.6944952-30062-216115356674253/AnsiballZ_k8s.py", line 47, in invoke_module\n runpy.run_module(mod_name=\''ansible_collections.kubernetes.core.plugins.modules.k8s\'', init_globals=dict(_module_fqn=\''ansible_collections.kubernetes.core.plugins.modules.k8s\'', _modlib_path=modlib_path),\n File "/usr/lib64/python3.9/runpy.py", line 225, in run_module\n return 2025-10-07 07:32:49.825891 | controller | _run_module_code(code, init_globals, run_name, mod_spec)\n File "/usr/lib64/python3.9/runpy.py", line 97, in _run_module_code\n _run_code(code, mod_globals, init_globals,\n File "/usr/lib64/python3.9/runpy.py", line 87, in _run_code\n exec(code, run_globals)\n File "/tmp/ansible_kubernetes.core.k8s_payload_u33qvgkf/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/modules/k8s.py", line 509, in \n File "/tmp/ansible_kubernetes.core.k8s_payload_u33qvgkf/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/modules/k8s.py", line 503, in main\n File "/tmp/ansible_kubernetes.core.k8s_payload_u33qvgkf/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/k8s/runner.py", line 88, in run_module\n File "/tmp/ansible_kubernetes.core.k8s_payload_u33qvgkf/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/k8s/client.py", line 352, 2025-10-07 07:32:49.825918 | controller | in get_api_client\n File "/tmp/ansible_kubernetes.core.k8s_payload_u33qvgkf/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/k8s/client.py", line 246, in wrapper\n File "/tmp/ansible_kubernetes.core.k8s_payload_u33qvgkf/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/k8s/client.py", line 259, in create_api_client\n File "/usr/local/lib/python3.9/site-packages/kubernetes/dynamic/client.py", line 84, in __init__\n self.__discoverer = discoverer(self, cache_file)\n File "/tmp/ansible_kubernetes.core.k8s_payload_u33qvgkf/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/client/discovery.py", line 190, in __init__\n File "/tmp/ansible_kubernetes.core.k8s_payload_u33qvgkf/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/client/discovery.py", line 45, in __init__\n File "/tmp/ansible_kubernetes.core.k8s_payload_u33qvgkf/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/client/discovery.py", 2025-10-07 07:32:49.825938 | controller | line 92, in __init_cache\n File "/usr/local/lib/python3.9/site-packages/kubernetes/dynamic/discovery.py", line 146, in _load_server_info\n \''kubernetes\'': self.client.request(\''get\'', \''/version\'', serializer=just_json)\n File "/usr/local/lib/python3.9/site-packages/kubernetes/dynamic/client.py", line 55, in inner\n resp = func(self, *args, **kwargs)\n File "/usr/local/lib/python3.9/site-packages/kubernetes/dynamic/client.py", line 277, in request\n api_response = self.client.call_api(\n File "/usr/local/lib/python3.9/site-packages/kubernetes/client/api_client.py", line 348, in call_api\n return self.__call_api(resource_path, method,\n File "/usr/local/lib/python3.9/site-packages/kubernetes/client/api_client.py", line 180, in __call_api\n response_data = self.request(\n File "/usr/local/lib/python3.9/site-packages/kubernetes/client/api_client.py", line 373, in request\n return self.rest_client.GET(url,\n File "/usr/local/lib/python3.9/site-packages/kubernetes/client/rest.py", 2025-10-07 07:32:49.832031 | controller | line 244, in GET\n return self.request("GET", url,\n File "/usr/local/lib/python3.9/site-packages/kubernetes/client/rest.py", line 217, in request\n r = self.pool_manager.request(method, url,\n File "/usr/lib/python3.9/site-packages/urllib3/request.py", line 74, in request\n return self.request_encode_url(\n File "/usr/lib/python3.9/site-packages/urllib3/request.py", line 96, in request_encode_url\n return self.urlopen(method, url, **extra_kw)\n File "/usr/lib/python3.9/site-packages/urllib3/poolmanager.py", line 376, in urlopen\n response = conn.urlopen(method, u.request_uri, **kw)\n File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 784, in urlopen\n return self.urlopen(\n File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 784, in urlopen\n return self.urlopen(\n File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 784, in urlopen\n return self.urlopen(\n File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", 2025-10-07 07:32:49.832075 | controller | line 756, in urlopen\n retries = retries.increment(\n File "/usr/lib/python3.9/site-packages/urllib3/util/retry.py", line 576, in increment\n raise MaxRetryError(_pool, url, error or ResponseError(cause))\nurllib3.exceptions.MaxRetryError: HTTPSConnectionPool(host=\''api.test.example.com\'', port=6443): Max retries exceeded with url: /version (Caused by NewConnectionError(\'': Failed to establish a new connection: [Errno -2] Name or service not known\''))\n'', ''msg'': ''MODULE FAILURE\nSee stdout/stderr for the exact error'', ''rc'': 1, ''changed'': False, ''failed_when_result'': False}' 2025-10-07 07:32:49.832094 | controller | 2025-10-07 07:32:50.995215 | controller | TASK [Test NHC CR creation idempotency] **************************************** 2025-10-07 07:32:50.995245 | controller | Tuesday 07 October 2025 07:32:49 +0000 (0:00:00.025) 0:00:19.004 ******* 2025-10-07 07:32:50.995257 | controller | ok: [instance] 2025-10-07 07:32:51.018470 | controller | 2025-10-07 07:32:51.018493 | controller | TASK [Assert expected behavior for NHC CR creation] **************************** 2025-10-07 07:32:51.018500 | controller | Tuesday 07 October 2025 07:32:50 +0000 (0:00:01.162) 0:00:20.167 ******* 2025-10-07 07:32:51.018510 | controller | ok: [instance] => changed=false 2025-10-07 07:32:51.050629 | controller | msg: NHC CR creation task behaves consistently 2025-10-07 07:32:51.050671 | controller | 2025-10-07 07:32:51.050680 | controller | TASK [Verify role structure and logic] ***************************************** 2025-10-07 07:32:51.050686 | controller | Tuesday 07 October 2025 07:32:51 +0000 (0:00:00.023) 0:00:20.190 ******* 2025-10-07 07:32:51.050697 | controller | ok: [instance] => 2025-10-07 07:32:51.050826 | controller | msg: Role execution and verification completed - errors are expected in test environment without real K8s cluster 2025-10-07 07:32:51.052950 | controller | 2025-10-07 07:32:51.052968 | controller | PLAY RECAP ********************************************************************* 2025-10-07 07:32:51.053055 | controller | instance : ok=41 changed=0 unreachable=0 failed=0 skipped=20 rescued=1 ignored=0 2025-10-07 07:32:51.053063 | controller | 2025-10-07 07:32:51.053070 | controller | Tuesday 07 October 2025 07:32:51 +0000 (0:00:00.032) 0:00:20.222 ******* 2025-10-07 07:32:51.053075 | controller | =============================================================================== 2025-10-07 07:32:51.053081 | controller | Test kubernetes.core.k8s module availability ---------------------------- 1.45s 2025-10-07 07:32:51.053086 | controller | Test namespace creation idempotency ------------------------------------- 1.33s 2025-10-07 07:32:51.053092 | controller | Verify SNR deployment status -------------------------------------------- 1.32s 2025-10-07 07:32:51.053097 | controller | cifmw_snr_nhc : Create the workload-availability namespace -------------- 1.26s 2025-10-07 07:32:51.053108 | controller | Verify namespace exists ------------------------------------------------- 1.24s 2025-10-07 07:32:51.053113 | controller | Verify OperatorGroup exists --------------------------------------------- 1.24s 2025-10-07 07:32:51.053119 | controller | Test NHC Subscription creation idempotency ------------------------------ 1.20s 2025-10-07 07:32:51.053124 | controller | Verify NHC Subscription exists ------------------------------------------ 1.20s 2025-10-07 07:32:51.053130 | controller | Verify SNR Subscription exists ------------------------------------------ 1.17s 2025-10-07 07:32:51.053135 | controller | Test NHC CR creation idempotency ---------------------------------------- 1.16s 2025-10-07 07:32:51.053140 | controller | Verify CSV status ------------------------------------------------------- 1.16s 2025-10-07 07:32:51.053145 | controller | Test OperatorGroup creation idempotency --------------------------------- 1.14s 2025-10-07 07:32:51.053151 | controller | Verify NHC CR exists ---------------------------------------------------- 1.12s 2025-10-07 07:32:51.053157 | controller | Test SNR Subscription creation idempotency ------------------------------ 1.10s 2025-10-07 07:32:51.053162 | controller | Test that Python kubernetes library is available ------------------------ 1.09s 2025-10-07 07:32:51.053167 | controller | Test that mock kubeconfig exists ---------------------------------------- 0.81s 2025-10-07 07:32:51.053175 | controller | Include the cifmw_snr_nhc role ------------------------------------------ 0.05s 2025-10-07 07:32:51.053305 | controller | cifmw_snr_nhc : Delete existing NodeHealthCheck resources --------------- 0.04s 2025-10-07 07:32:51.053315 | controller | Analyze specific error patterns ----------------------------------------- 0.04s 2025-10-07 07:32:51.053428 | controller | Verify role structure and logic ----------------------------------------- 0.03s 2025-10-07 07:32:51.111174 | controller | INFO Running default > verify 2025-10-07 07:32:51.111479 | controller | INFO Running Ansible Verifier 2025-10-07 07:32:51.539618 | controller | 2025-10-07 07:32:52.643388 | controller | PLAY [Verify] ****************************************************************** 2025-10-07 07:32:52.643421 | controller | 2025-10-07 07:32:52.643487 | controller | TASK [Check if Python kubernetes library is installed] ************************* 2025-10-07 07:32:52.643494 | controller | Tuesday 07 October 2025 07:32:51 +0000 (0:00:00.021) 0:00:00.021 ******* 2025-10-07 07:32:52.643505 | controller | ok: [instance] 2025-10-07 07:32:52.664877 | controller | 2025-10-07 07:32:52.664934 | controller | TASK [Display kubernetes library version] ************************************** 2025-10-07 07:32:52.664947 | controller | Tuesday 07 October 2025 07:32:52 +0000 (0:00:01.104) 0:00:01.125 ******* 2025-10-07 07:32:52.664958 | controller | ok: [instance] => 2025-10-07 07:32:53.417001 | controller | msg: 'kubernetes library version: 34.1.0' 2025-10-07 07:32:53.417039 | controller | 2025-10-07 07:32:53.417047 | controller | TASK [Verify kubeconfig mock file exists] ************************************** 2025-10-07 07:32:53.417054 | controller | Tuesday 07 October 2025 07:32:52 +0000 (0:00:00.021) 0:00:01.147 ******* 2025-10-07 07:32:53.417066 | controller | ok: [instance] 2025-10-07 07:32:53.417185 | controller | 2025-10-07 07:32:53.417202 | controller | TASK [Assert kubeconfig mock file exists] ************************************** 2025-10-07 07:32:53.440009 | controller | Tuesday 07 October 2025 07:32:53 +0000 (0:00:00.752) 0:00:01.899 ******* 2025-10-07 07:32:53.440046 | controller | ok: [instance] => changed=false 2025-10-07 07:32:53.440073 | controller | msg: Mock kubeconfig file exists 2025-10-07 07:32:53.440080 | controller | 2025-10-07 07:32:53.440086 | controller | TASK [Verify kubeadmin password mock file exists] ****************************** 2025-10-07 07:32:53.440094 | controller | Tuesday 07 October 2025 07:32:53 +0000 (0:00:00.023) 0:00:01.922 ******* 2025-10-07 07:32:54.095730 | controller | ok: [instance] 2025-10-07 07:32:54.117361 | controller | 2025-10-07 07:32:54.117401 | controller | TASK [Assert kubeadmin password mock file exists] ****************************** 2025-10-07 07:32:54.117412 | controller | Tuesday 07 October 2025 07:32:54 +0000 (0:00:00.655) 0:00:02.577 ******* 2025-10-07 07:32:54.117425 | controller | ok: [instance] => changed=false 2025-10-07 07:32:54.744027 | controller | msg: Mock kubeadmin password file exists 2025-10-07 07:32:54.744091 | controller | 2025-10-07 07:32:54.744101 | controller | TASK [Test Python yaml library] ************************************************ 2025-10-07 07:32:54.744108 | controller | Tuesday 07 October 2025 07:32:54 +0000 (0:00:00.021) 0:00:02.599 ******* 2025-10-07 07:32:54.744118 | controller | ok: [instance] 2025-10-07 07:32:54.744291 | controller | 2025-10-07 07:32:54.744640 | controller | TASK [Display yaml test result] ************************************************ 2025-10-07 07:32:54.745101 | controller | Tuesday 07 October 2025 07:32:54 +0000 (0:00:00.626) 0:00:03.226 ******* 2025-10-07 07:32:54.779427 | controller | ok: [instance] => 2025-10-07 07:32:54.821632 | controller | msg: yaml library works 2025-10-07 07:32:54.821666 | controller | 2025-10-07 07:32:54.821675 | controller | PLAY RECAP ********************************************************************* 2025-10-07 07:32:54.821682 | controller | instance : ok=8 changed=0 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2025-10-07 07:32:54.821687 | controller | 2025-10-07 07:32:54.821693 | controller | Tuesday 07 October 2025 07:32:54 +0000 (0:00:00.034) 0:00:03.260 ******* 2025-10-07 07:32:54.821698 | controller | =============================================================================== 2025-10-07 07:32:54.821704 | controller | Check if Python kubernetes library is installed ------------------------- 1.10s 2025-10-07 07:32:54.821709 | controller | Verify kubeconfig mock file exists -------------------------------------- 0.75s 2025-10-07 07:32:54.821715 | controller | Verify kubeadmin password mock file exists ------------------------------ 0.66s 2025-10-07 07:32:54.821720 | controller | Test Python yaml library ------------------------------------------------ 0.63s 2025-10-07 07:32:54.821738 | controller | Display yaml test result ------------------------------------------------ 0.03s 2025-10-07 07:32:54.821744 | controller | Assert kubeconfig mock file exists -------------------------------------- 0.02s 2025-10-07 07:32:54.821750 | controller | Assert kubeadmin password mock file exists ------------------------------ 0.02s 2025-10-07 07:32:54.821756 | controller | Display kubernetes library version -------------------------------------- 0.02s 2025-10-07 07:32:54.821766 | controller | INFO Verifier completed successfully. 2025-10-07 07:32:54.831949 | controller | INFO Running default > destroy 2025-10-07 07:32:55.241652 | controller | 2025-10-07 07:32:55.241883 | controller | PLAY [Destroy] ***************************************************************** 2025-10-07 07:32:55.242056 | controller | 2025-10-07 07:32:55.242208 | controller | TASK [Get passwd entries for USER env] ***************************************** 2025-10-07 07:32:55.242349 | controller | Tuesday 07 October 2025 07:32:55 +0000 (0:00:00.014) 0:00:00.014 ******* 2025-10-07 07:32:55.521975 | controller | ok: [localhost] 2025-10-07 07:32:55.522063 | controller | 2025-10-07 07:32:55.522080 | controller | TASK [Get shell async_dir] ***************************************************** 2025-10-07 07:32:55.522091 | controller | Tuesday 07 October 2025 07:32:55 +0000 (0:00:00.280) 0:00:00.294 ******* 2025-10-07 07:32:55.561967 | controller | ok: [localhost] 2025-10-07 07:32:55.562042 | controller | 2025-10-07 07:32:55.562062 | controller | TASK [Set async_dir for HOME env] ********************************************** 2025-10-07 07:32:55.562075 | controller | Tuesday 07 October 2025 07:32:55 +0000 (0:00:00.039) 0:00:00.334 ******* 2025-10-07 07:32:55.594297 | controller | skipping: [localhost] 2025-10-07 07:32:55.960977 | controller | 2025-10-07 07:32:55.961039 | controller | TASK [Destroy molecule instance(s)] ******************************************** 2025-10-07 07:32:55.961048 | controller | Tuesday 07 October 2025 07:32:55 +0000 (0:00:00.032) 0:00:00.366 ******* 2025-10-07 07:32:55.961060 | controller | changed: [localhost] => (item={'capabilities': ['SYS_ADMIN'], 'command': 'sleep infinity', 'groups': ['molecule', 'rhol_crc_molecule'], 'image': 'registry.access.redhat.com/ubi9/ubi:latest', 'name': 'instance', 'pre_build_image': True, 'privileged': True, 'tmpfs': ['/run', '/tmp'], 'volumes': ['/sys/fs/cgroup:/sys/fs/cgroup:ro']}) 2025-10-07 07:33:06.577894 | controller | 2025-10-07 07:33:06.577941 | controller | TASK [Wait for instance(s) deletion to complete] ******************************* 2025-10-07 07:33:06.577949 | controller | Tuesday 07 October 2025 07:32:55 +0000 (0:00:00.366) 0:00:00.733 ******* 2025-10-07 07:33:06.577960 | controller | FAILED - RETRYING: [localhost]: Wait for instance(s) deletion to complete (300 retries left). 2025-10-07 07:33:06.628739 | controller | FAILED - RETRYING: [localhost]: Wait for instance(s) deletion to complete (299 retries left). 2025-10-07 07:33:06.628771 | controller | changed: [localhost] => (item={'failed': 0, 'started': 1, 'finished': 0, 'ansible_job_id': 'j682041941849.31122', 'results_file': '/home/zuul/.ansible_async/j682041941849.31122', 'changed': True, 'item': {'capabilities': ['SYS_ADMIN'], 'command': 'sleep infinity', 'groups': ['molecule', 'rhol_crc_molecule'], 'image': 'registry.access.redhat.com/ubi9/ubi:latest', 'name': 'instance', 'pre_build_image': True, 'privileged': True, 'tmpfs': ['/run', '/tmp'], 'volumes': ['/sys/fs/cgroup:/sys/fs/cgroup:ro']}, 'ansible_loop_var': 'item'}) 2025-10-07 07:33:06.628781 | controller | 2025-10-07 07:33:06.628788 | controller | TASK [Delete podman network dedicated to this scenario] ************************ 2025-10-07 07:33:06.628794 | controller | Tuesday 07 October 2025 07:33:06 +0000 (0:00:10.616) 0:00:11.349 ******* 2025-10-07 07:33:06.628804 | controller | skipping: [localhost] => (item=instance: None specified) 2025-10-07 07:33:06.672517 | controller | skipping: [localhost] 2025-10-07 07:33:06.672548 | controller | 2025-10-07 07:33:06.672557 | controller | PLAY RECAP ********************************************************************* 2025-10-07 07:33:06.672563 | controller | localhost : ok=4 changed=2 unreachable=0 failed=0 skipped=2 rescued=0 ignored=0 2025-10-07 07:33:06.672569 | controller | 2025-10-07 07:33:06.672575 | controller | Tuesday 07 October 2025 07:33:06 +0000 (0:00:00.050) 0:00:11.400 ******* 2025-10-07 07:33:06.672581 | controller | =============================================================================== 2025-10-07 07:33:06.672586 | controller | Wait for instance(s) deletion to complete ------------------------------ 10.62s 2025-10-07 07:33:06.672592 | controller | Destroy molecule instance(s) -------------------------------------------- 0.37s 2025-10-07 07:33:06.672597 | controller | Get passwd entries for USER env ----------------------------------------- 0.28s 2025-10-07 07:33:06.672602 | controller | Delete podman network dedicated to this scenario ------------------------ 0.05s 2025-10-07 07:33:06.672608 | controller | Get shell async_dir ----------------------------------------------------- 0.04s 2025-10-07 07:33:06.672613 | controller | Set async_dir for HOME env ---------------------------------------------- 0.03s 2025-10-07 07:33:06.672624 | controller | INFO Pruning extra files from scenario ephemeral directory 2025-10-07 07:33:06.674483 | controller | INFO Writing /tmp/report.html report. 2025-10-07 07:33:06.839303 | controller | changed 2025-10-07 07:33:06.865087 | 2025-10-07 07:33:06.865172 | PLAY RECAP 2025-10-07 07:33:06.865214 | controller | ok: 3 changed: 1 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2025-10-07 07:33:06.865238 | 2025-10-07 07:33:06.945010 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main] 2025-10-07 07:33:06.945826 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main] 2025-10-07 07:33:07.519479 | 2025-10-07 07:33:07.519579 | PLAY [Run ci/playbooks/collect-logs.yml] 2025-10-07 07:33:07.539952 | 2025-10-07 07:33:07.540035 | TASK [Filter out host if needed] 2025-10-07 07:33:07.549079 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller 2025-10-07 07:33:07.553615 | 2025-10-07 07:33:07.553698 | TASK [Ensure file is present] 2025-10-07 07:33:07.896559 | controller | ok 2025-10-07 07:33:07.905420 | 2025-10-07 07:33:07.905491 | TASK [Manage molecule report file] 2025-10-07 07:33:08.433998 | controller | changed 2025-10-07 07:33:08.439305 | 2025-10-07 07:33:08.439390 | TASK [Check if we get ci-framework-data basedir] 2025-10-07 07:33:08.630252 | controller | ok 2025-10-07 07:33:08.640766 | 2025-10-07 07:33:08.640853 | TASK [Create ci-framework-data log directory for zuul] 2025-10-07 07:33:08.964458 | controller | changed 2025-10-07 07:33:08.973141 | 2025-10-07 07:33:08.973249 | TASK [Copy ci-framework interesting files] 2025-10-07 07:33:09.180027 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory 2025-10-07 07:33:09.506104 | controller | changed 2025-10-07 07:33:09.512163 | 2025-10-07 07:33:09.512240 | TASK [Get SELinux listing] 2025-10-07 07:33:10.052671 | controller | changed 2025-10-07 07:33:10.058471 | 2025-10-07 07:33:10.058537 | TASK [Generate log index] 2025-10-07 07:33:10.676327 | controller | changed 2025-10-07 07:33:10.681354 | 2025-10-07 07:33:10.681434 | TASK [Get some env related data] 2025-10-07 07:33:11.219264 | controller | /home/zuul/.local/bin/ansible 2025-10-07 07:33:12.208852 | controller | changed 2025-10-07 07:33:12.216026 | 2025-10-07 07:33:12.216124 | TASK [Generate list of logs to collect in home directory] 2025-10-07 07:33:12.644393 | controller | ok: All paths examined 2025-10-07 07:33:12.650761 | 2025-10-07 07:33:12.650842 | LOOP [Copy logs from home directory] 2025-10-07 07:33:13.047833 | controller | changed: 2025-10-07 07:33:13.047964 | controller | { 2025-10-07 07:33:13.047995 | controller | "atime": 1759822281.679649, 2025-10-07 07:33:13.048023 | controller | "ctime": 1759822300.2938845, 2025-10-07 07:33:13.048042 | controller | "dev": 64513, 2025-10-07 07:33:13.048060 | controller | "gid": 1000, 2025-10-07 07:33:13.048077 | controller | "gr_name": "zuul", 2025-10-07 07:33:13.048094 | controller | "inode": 4448579, 2025-10-07 07:33:13.048110 | controller | "isblk": false, 2025-10-07 07:33:13.048126 | controller | "ischr": false, 2025-10-07 07:33:13.048142 | controller | "isdir": false, 2025-10-07 07:33:13.048158 | controller | "isfifo": false, 2025-10-07 07:33:13.048176 | controller | "isgid": false, 2025-10-07 07:33:13.048192 | controller | "islnk": false, 2025-10-07 07:33:13.048209 | controller | "isreg": true, 2025-10-07 07:33:13.048224 | controller | "issock": false, 2025-10-07 07:33:13.048240 | controller | "isuid": false, 2025-10-07 07:33:13.048256 | controller | "mode": "0644", 2025-10-07 07:33:13.048271 | controller | "mtime": 1759822300.2938845, 2025-10-07 07:33:13.048476 | controller | "nlink": 1, 2025-10-07 07:33:13.048510 | controller | "path": "/home/zuul/ansible.log", 2025-10-07 07:33:13.048532 | controller | "pw_name": "zuul", 2025-10-07 07:33:13.048550 | controller | "rgrp": true, 2025-10-07 07:33:13.048567 | controller | "roth": true, 2025-10-07 07:33:13.048583 | controller | "rusr": true, 2025-10-07 07:33:13.048599 | controller | "size": 6749, 2025-10-07 07:33:13.048615 | controller | "uid": 1000, 2025-10-07 07:33:13.048631 | controller | "wgrp": false, 2025-10-07 07:33:13.048647 | controller | "woth": false, 2025-10-07 07:33:13.048678 | controller | "wusr": true, 2025-10-07 07:33:13.048696 | controller | "xgrp": false, 2025-10-07 07:33:13.048712 | controller | "xoth": false, 2025-10-07 07:33:13.048727 | controller | "xusr": false 2025-10-07 07:33:13.048741 | controller | } 2025-10-07 07:33:13.061560 | 2025-10-07 07:33:13.061624 | TASK [Copy crio stats log file] 2025-10-07 07:33:13.085618 | controller | skipping: Conditional result was False 2025-10-07 07:33:13.091774 | 2025-10-07 07:33:13.091845 | TASK [Get SELinux related data] 2025-10-07 07:33:13.326084 | controller | 2025-10-07 07:33:13.636562 | controller | ERROR 2025-10-07 07:33:13.636694 | controller | { 2025-10-07 07:33:13.636728 | controller | "delta": "0:00:00.008927", 2025-10-07 07:33:13.636751 | controller | "end": "2025-10-07 07:33:13.327434", 2025-10-07 07:33:13.636771 | controller | "msg": "non-zero return code", 2025-10-07 07:33:13.636789 | controller | "rc": 1, 2025-10-07 07:33:13.636806 | controller | "start": "2025-10-07 07:33:13.318507" 2025-10-07 07:33:13.636822 | controller | } 2025-10-07 07:33:13.636846 | controller | ERROR: Ignoring Errors 2025-10-07 07:33:13.642135 | 2025-10-07 07:33:13.642200 | TASK [Create system configuration directory] 2025-10-07 07:33:13.826890 | controller | changed 2025-10-07 07:33:13.831699 | 2025-10-07 07:33:13.831762 | TASK [Get some of the system configurations] 2025-10-07 07:33:14.357397 | controller | changed 2025-10-07 07:33:14.363083 | 2025-10-07 07:33:14.363156 | TASK [Copy generated documentation if available] 2025-10-07 07:33:14.377065 | controller | skipping: Conditional result was False 2025-10-07 07:33:14.382965 | 2025-10-07 07:33:14.383040 | TASK [Copy generated AsciiDoc documentation if available] 2025-10-07 07:33:14.396642 | controller | skipping: Conditional result was False 2025-10-07 07:33:14.403090 | 2025-10-07 07:33:14.403157 | TASK [Compress logs bigger than 2MB] 2025-10-07 07:33:14.930796 | controller | changed 2025-10-07 07:33:14.936735 | 2025-10-07 07:33:14.936816 | TASK [Copy files from workspace on node] 2025-10-07 07:33:14.955398 | controller | ok 2025-10-07 07:33:14.980508 | 2025-10-07 07:33:14.980618 | TASK [fetch-output : Set log path for multiple nodes] 2025-10-07 07:33:14.999127 | controller | skipping: Conditional result was False 2025-10-07 07:33:15.005013 | 2025-10-07 07:33:15.005102 | TASK [fetch-output : Set log path for single node] 2025-10-07 07:33:15.033506 | controller | ok 2025-10-07 07:33:15.039985 | 2025-10-07 07:33:15.040054 | LOOP [fetch-output : Ensure local output dirs] 2025-10-07 07:33:15.272728 | controller -> localhost | ok: "/var/lib/zuul/builds/007f961ce0ae472c9ca349317c552dce/work/logs" 2025-10-07 07:33:15.273002 | controller -> localhost | changed: All items complete 2025-10-07 07:33:15.273042 | 2025-10-07 07:33:15.507766 | controller -> localhost | changed: "/var/lib/zuul/builds/007f961ce0ae472c9ca349317c552dce/work/artifacts" 2025-10-07 07:33:15.742158 | controller -> localhost | changed: "/var/lib/zuul/builds/007f961ce0ae472c9ca349317c552dce/work/docs" 2025-10-07 07:33:15.754546 | 2025-10-07 07:33:15.754647 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-10-07 07:33:16.495235 | controller | changed: 2025-10-07 07:33:16.495415 | controller | .d..t...... ./ 2025-10-07 07:33:16.495446 | controller | >f+++++++++ README.html 2025-10-07 07:33:16.495470 | controller | >f+++++++++ ansible-execution.log 2025-10-07 07:33:16.495493 | controller | >f+++++++++ ansible.log 2025-10-07 07:33:16.495513 | controller | >f+++++++++ dmesg.log 2025-10-07 07:33:16.495532 | controller | >f+++++++++ installed-pkgs.log 2025-10-07 07:33:16.495553 | controller | >f+++++++++ python.log 2025-10-07 07:33:16.495573 | controller | >f+++++++++ registries.conf 2025-10-07 07:33:16.495591 | controller | >f+++++++++ report.html 2025-10-07 07:33:16.495612 | controller | >f+++++++++ selinux-denials.log 2025-10-07 07:33:16.495630 | controller | >f+++++++++ selinux-listing.log 2025-10-07 07:33:16.495648 | controller | cd+++++++++ ci-framework-data/ 2025-10-07 07:33:16.495680 | controller | cd+++++++++ ci-framework-data/artifacts/ 2025-10-07 07:33:16.495701 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml 2025-10-07 07:33:16.495720 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/ 2025-10-07 07:33:16.495738 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml 2025-10-07 07:33:16.495756 | controller | cd+++++++++ ci-framework-data/logs/ 2025-10-07 07:33:16.495774 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log 2025-10-07 07:33:16.495792 | controller | cd+++++++++ registries.conf.d/ 2025-10-07 07:33:16.495820 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf 2025-10-07 07:33:16.495847 | controller | cd+++++++++ system-config/ 2025-10-07 07:33:16.495872 | controller | cd+++++++++ system-config/libvirt/ 2025-10-07 07:33:16.495897 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf 2025-10-07 07:33:16.500779 | controller | >f+++++++++ system-config/libvirt/libvirt.conf 2025-10-07 07:33:16.946147 | controller | changed: .d..t...... ./ 2025-10-07 07:33:17.407716 | controller | changed: .d..t...... ./ 2025-10-07 07:33:17.441543 | 2025-10-07 07:33:17.441654 | TASK [Return artifact to Zuul] 2025-10-07 07:33:17.500610 | controller | ok 2025-10-07 07:33:17.542143 | 2025-10-07 07:33:17.542216 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host] 2025-10-07 07:33:17.542322 | 2025-10-07 07:33:17.542350 | PLAY RECAP 2025-10-07 07:33:17.542404 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1 2025-10-07 07:33:17.542430 | 2025-10-07 07:33:17.637936 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main] 2025-10-07 07:33:17.638724 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2025-10-07 07:33:18.242469 | 2025-10-07 07:33:18.242563 | PLAY [all] 2025-10-07 07:33:18.261031 | 2025-10-07 07:33:18.261105 | TASK [include_role : fetch-output] 2025-10-07 07:33:18.289248 | controller | ok 2025-10-07 07:33:18.305934 | 2025-10-07 07:33:18.306017 | TASK [fetch-output : Set log path for multiple nodes] 2025-10-07 07:33:18.359923 | controller | skipping: Conditional result was False 2025-10-07 07:33:18.365720 | 2025-10-07 07:33:18.365802 | TASK [fetch-output : Set log path for single node] 2025-10-07 07:33:18.393363 | controller | ok 2025-10-07 07:33:18.398439 | 2025-10-07 07:33:18.398511 | LOOP [fetch-output : Ensure local output dirs] 2025-10-07 07:33:18.803627 | controller -> localhost | ok: "/var/lib/zuul/builds/007f961ce0ae472c9ca349317c552dce/work/logs" 2025-10-07 07:33:19.012478 | controller -> localhost | ok: "/var/lib/zuul/builds/007f961ce0ae472c9ca349317c552dce/work/artifacts" 2025-10-07 07:33:19.209077 | controller -> localhost | ok: "/var/lib/zuul/builds/007f961ce0ae472c9ca349317c552dce/work/docs" 2025-10-07 07:33:19.224097 | 2025-10-07 07:33:19.224213 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-10-07 07:33:19.834502 | controller | ok 2025-10-07 07:33:19.838296 | controller | ok: All items complete 2025-10-07 07:33:19.838346 | 2025-10-07 07:33:20.253576 | controller | ok 2025-10-07 07:33:20.634325 | controller | ok 2025-10-07 07:33:20.647408 | 2025-10-07 07:33:20.647496 | TASK [include_role : fetch-output-openshift] 2025-10-07 07:33:20.661187 | controller | skipping: Conditional result was False 2025-10-07 07:33:20.667273 | 2025-10-07 07:33:20.667346 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-10-07 07:33:21.183179 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.020163 2025-10-07 07:33:21.460625 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.007815 2025-10-07 07:33:21.534230 | 2025-10-07 07:33:21.534327 | PLAY [all] 2025-10-07 07:33:21.556436 | 2025-10-07 07:33:21.556528 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-10-07 07:33:22.152084 | controller | changed 2025-10-07 07:33:22.179189 | 2025-10-07 07:33:22.179298 | PLAY RECAP 2025-10-07 07:33:22.179358 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2025-10-07 07:33:22.179384 | 2025-10-07 07:33:22.283673 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2025-10-07 07:33:22.284461 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master] 2025-10-07 07:33:22.925333 | 2025-10-07 07:33:22.925475 | PLAY [localhost] 2025-10-07 07:33:22.943263 | 2025-10-07 07:33:22.943340 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-10-07 07:33:23.533323 | localhost | changed 2025-10-07 07:33:23.538305 | 2025-10-07 07:33:23.538387 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-10-07 07:33:23.588383 | localhost | ok 2025-10-07 07:33:23.603251 | 2025-10-07 07:33:23.603340 | TASK [add-fileserver : Create SSH private key tempfile] 2025-10-07 07:33:24.055781 | localhost | changed 2025-10-07 07:33:24.064219 | 2025-10-07 07:33:24.064307 | TASK [add-fileserver : Create SSH private key from secret] 2025-10-07 07:33:25.373803 | localhost | changed 2025-10-07 07:33:25.383974 | 2025-10-07 07:33:25.384832 | TASK [add-fileserver : Add fileserver ssh key] 2025-10-07 07:33:25.993393 | localhost | Identity added: /var/lib/zuul/builds/007f961ce0ae472c9ca349317c552dce/work/tmp/ansible.8v7xoz9x (/var/lib/zuul/builds/007f961ce0ae472c9ca349317c552dce/work/tmp/ansible.8v7xoz9x) 2025-10-07 07:33:25.993565 | localhost | ok: Runtime: 0:00:00.010068 2025-10-07 07:33:25.999129 | 2025-10-07 07:33:25.999202 | TASK [add-fileserver : Remove SSH private key from disk] 2025-10-07 07:33:26.610062 | localhost | ok: Runtime: 0:00:00.021653 2025-10-07 07:33:26.614786 | 2025-10-07 07:33:26.614870 | TASK [add-fileserver : Add fileserver to inventory] 2025-10-07 07:33:26.821129 | localhost | changed 2025-10-07 07:33:26.834298 | 2025-10-07 07:33:26.834388 | TASK [add-fileserver : Add fileserver server to known hosts] 2025-10-07 07:33:27.416298 | localhost | changed 2025-10-07 07:33:27.474877 | 2025-10-07 07:33:27.475010 | PLAY [localhost] 2025-10-07 07:33:27.494562 | 2025-10-07 07:33:27.494643 | TASK [Generate bulk log download script] 2025-10-07 07:33:27.530773 | localhost | ok 2025-10-07 07:33:27.553994 | 2025-10-07 07:33:27.554110 | TASK [local-log-download : Check API endpoint is defined] 2025-10-07 07:33:27.603045 | localhost | ok: All assertions passed 2025-10-07 07:33:27.609041 | 2025-10-07 07:33:27.609126 | TASK [local-log-download : Create download script] 2025-10-07 07:33:28.125595 | localhost -> localhost | changed 2025-10-07 07:33:28.134624 | 2025-10-07 07:33:28.134720 | TASK [Register quick-download link] 2025-10-07 07:33:28.152996 | localhost | ok 2025-10-07 07:33:28.169837 | 2025-10-07 07:33:28.169961 | PLAY [logserver.rdoproject.org] 2025-10-07 07:33:28.181542 | 2025-10-07 07:33:28.181622 | TASK [Set zuul-log-path fact] 2025-10-07 07:33:28.208109 | logserver.rdoproject.org | ok 2025-10-07 07:33:28.217571 | 2025-10-07 07:33:28.217645 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-07 07:33:28.268434 | logserver.rdoproject.org | ok 2025-10-07 07:33:28.274253 | 2025-10-07 07:33:28.274326 | TASK [upload-logs : Create log directories] 2025-10-07 07:33:30.019929 | logserver.rdoproject.org | changed 2025-10-07 07:33:30.024882 | 2025-10-07 07:33:30.024975 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-10-07 07:33:30.619484 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.023622 2025-10-07 07:33:30.624229 | 2025-10-07 07:33:30.624311 | TASK [upload-logs : Upload logs to log server] 2025-10-07 07:33:32.715685 | logserver.rdoproject.org | Output suppressed because no_log was given 2025-10-07 07:33:32.720329 | 2025-10-07 07:33:32.720411 | LOOP [upload-logs : Compress console log and json output] 2025-10-07 07:33:32.790225 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-07 07:33:32.791009 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-07 07:33:32.808843 | 2025-10-07 07:33:32.808973 | LOOP [upload-logs : Upload compressed console log and json output] 2025-10-07 07:33:32.859761 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-07 07:33:32.860009 | 2025-10-07 07:33:32.860614 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-07 07:33:32.866336 | 2025-10-07 07:33:32.866443 | LOOP [upload-logs : Upload console log and json output]