2025-10-13 06:07:22.747379 | Job console starting... 2025-10-13 06:07:23.129845 | Updating repositories 2025-10-13 06:07:23.998486 | Preparing job workspace 2025-10-13 06:07:40.347728 | Running Ansible setup... 2025-10-13 06:07:48.526791 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2025-10-13 06:07:50.477699 | 2025-10-13 06:07:50.477826 | PLAY [localhost] 2025-10-13 06:07:50.507559 | 2025-10-13 06:07:50.507678 | TASK [Gathering Facts] 2025-10-13 06:07:53.229146 | localhost | ok 2025-10-13 06:07:53.261105 | 2025-10-13 06:07:53.261215 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-10-13 06:07:54.184775 | localhost -> localhost | changed 2025-10-13 06:07:54.191770 | 2025-10-13 06:07:54.191843 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-10-13 06:07:56.841839 | localhost -> localhost | changed 2025-10-13 06:07:56.850277 | 2025-10-13 06:07:56.850349 | TASK [Setup log path fact] 2025-10-13 06:07:56.884960 | localhost | ok 2025-10-13 06:07:56.954955 | 2025-10-13 06:07:56.955066 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-13 06:07:56.999029 | localhost | ok 2025-10-13 06:07:57.034627 | 2025-10-13 06:07:57.034724 | TASK [emit-job-header : Print job information] 2025-10-13 06:07:57.132421 | # Job Information 2025-10-13 06:07:57.132554 | Ansible Version: 2.15.12 2025-10-13 06:07:57.132585 | Job: cifmw-molecule-cifmw_snr_nhc 2025-10-13 06:07:57.132608 | Pipeline: github-check 2025-10-13 06:07:57.132629 | Executor: ibm-bm4-ze.softwarefactory-project.io 2025-10-13 06:07:57.132649 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3237 2025-10-13 06:07:57.132670 | Log URL (when completed): https://logserver.rdoproject.org/c03/rdoproject.org/c0345959015b47d798e63a5b7997f2e1/ 2025-10-13 06:07:57.132689 | Event ID: a3968a60-a7fa-11f0-8c52-d08cf4908dcc 2025-10-13 06:07:57.137075 | 2025-10-13 06:07:57.137148 | LOOP [emit-job-header : Print node information] 2025-10-13 06:07:57.281612 | localhost | ok: 2025-10-13 06:07:57.281808 | localhost | # Node Information 2025-10-13 06:07:57.281843 | localhost | Inventory Hostname: controller 2025-10-13 06:07:57.281870 | localhost | Hostname: np0005482090 2025-10-13 06:07:57.281893 | localhost | Username: zuul 2025-10-13 06:07:57.281943 | localhost | Distro: CentOS 9 2025-10-13 06:07:57.281982 | localhost | Provider: ibm-bm4-nodepool 2025-10-13 06:07:57.282004 | localhost | Region: regionOne 2025-10-13 06:07:57.282023 | localhost | Label: cloud-centos-9-stream-tripleo-ibm 2025-10-13 06:07:57.282042 | localhost | Product Name: OpenStack Compute 2025-10-13 06:07:57.282060 | localhost | Interface IP: 192.168.26.148 2025-10-13 06:07:57.342778 | 2025-10-13 06:07:57.342900 | PLAY [all] 2025-10-13 06:07:57.357178 | 2025-10-13 06:07:57.357243 | TASK [Gather network facts] 2025-10-13 06:07:57.789864 | controller | ok 2025-10-13 06:07:57.865209 | 2025-10-13 06:07:57.865304 | TASK [include_role : start-zuul-console] 2025-10-13 06:07:57.925603 | controller | ok 2025-10-13 06:07:57.953221 | 2025-10-13 06:07:57.953304 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-10-13 06:07:58.601893 | controller | ok 2025-10-13 06:07:58.621891 | 2025-10-13 06:07:58.622005 | TASK [include_role : add-build-sshkey] 2025-10-13 06:07:58.681370 | controller | ok 2025-10-13 06:07:58.723071 | 2025-10-13 06:07:58.723153 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-13 06:07:59.562668 | controller -> localhost | ok 2025-10-13 06:07:59.567805 | 2025-10-13 06:07:59.567865 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-13 06:07:59.627675 | controller | ok 2025-10-13 06:07:59.661736 | controller | included: /var/lib/zuul/builds/c0345959015b47d798e63a5b7997f2e1/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-13 06:07:59.690998 | 2025-10-13 06:07:59.702976 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-13 06:08:01.714205 | controller -> localhost | Generating public/private rsa key pair. 2025-10-13 06:08:01.714369 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/c0345959015b47d798e63a5b7997f2e1/work/c0345959015b47d798e63a5b7997f2e1_id_rsa. 2025-10-13 06:08:01.714404 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/c0345959015b47d798e63a5b7997f2e1/work/c0345959015b47d798e63a5b7997f2e1_id_rsa.pub. 2025-10-13 06:08:01.714438 | controller -> localhost | The key fingerprint is: 2025-10-13 06:08:01.714469 | controller -> localhost | SHA256:Mp/LGflkqIv33pO+biJZYAVjyCkvbfmIGAiPmWO4Crw zuul-build-sshkey 2025-10-13 06:08:01.714492 | controller -> localhost | The key's randomart image is: 2025-10-13 06:08:01.714513 | controller -> localhost | +---[RSA 3072]----+ 2025-10-13 06:08:01.714533 | controller -> localhost | | . o+. | 2025-10-13 06:08:01.714552 | controller -> localhost | |. . +. .. | 2025-10-13 06:08:01.714572 | controller -> localhost | |+= + . . | 2025-10-13 06:08:01.714592 | controller -> localhost | |O.o = o | 2025-10-13 06:08:01.714611 | controller -> localhost | |o= + +o.S | 2025-10-13 06:08:01.714629 | controller -> localhost | |+.. . .+.+ | 2025-10-13 06:08:01.714647 | controller -> localhost | |o . o* o. | 2025-10-13 06:08:01.714666 | controller -> localhost | |.E .+o.O+ | 2025-10-13 06:08:01.714685 | controller -> localhost | | ..o=*=*o | 2025-10-13 06:08:01.714703 | controller -> localhost | +----[SHA256]-----+ 2025-10-13 06:08:01.714746 | controller -> localhost | ok: Runtime: 0:00:00.417983 2025-10-13 06:08:01.720950 | 2025-10-13 06:08:01.721024 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-13 06:08:01.800282 | controller | ok 2025-10-13 06:08:01.828514 | controller | included: /var/lib/zuul/builds/c0345959015b47d798e63a5b7997f2e1/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-13 06:08:01.894411 | 2025-10-13 06:08:01.894509 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-13 06:08:01.976539 | controller | skipping: Conditional result was False 2025-10-13 06:08:01.982257 | 2025-10-13 06:08:01.982322 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-13 06:08:02.831213 | controller | changed 2025-10-13 06:08:02.841466 | 2025-10-13 06:08:02.841539 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-13 06:08:03.162219 | controller | ok 2025-10-13 06:08:03.168608 | 2025-10-13 06:08:03.168684 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-13 06:08:04.155559 | controller | changed 2025-10-13 06:08:04.161851 | 2025-10-13 06:08:04.161935 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-13 06:08:05.015884 | controller | changed 2025-10-13 06:08:05.148483 | 2025-10-13 06:08:05.148581 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-13 06:08:05.238136 | controller | skipping: Conditional result was False 2025-10-13 06:08:05.243815 | 2025-10-13 06:08:05.243890 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-13 06:08:06.472737 | controller -> localhost | changed 2025-10-13 06:08:06.482465 | 2025-10-13 06:08:06.482531 | TASK [add-build-sshkey : Add back temp key] 2025-10-13 06:08:07.687009 | controller -> localhost | Identity added: /var/lib/zuul/builds/c0345959015b47d798e63a5b7997f2e1/work/c0345959015b47d798e63a5b7997f2e1_id_rsa (zuul-build-sshkey) 2025-10-13 06:08:07.688345 | controller -> localhost | ok: Runtime: 0:00:00.036528 2025-10-13 06:08:07.697376 | 2025-10-13 06:08:07.697476 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-13 06:08:08.432787 | controller | ok 2025-10-13 06:08:08.441326 | 2025-10-13 06:08:08.441403 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-13 06:08:08.484273 | controller | skipping: Conditional result was False 2025-10-13 06:08:08.510583 | 2025-10-13 06:08:08.510688 | TASK [include_role : validate-host] 2025-10-13 06:08:08.615643 | controller | ok 2025-10-13 06:08:08.753245 | 2025-10-13 06:08:08.753346 | TASK [validate-host : Define zuul_info_dir fact] 2025-10-13 06:08:08.856393 | controller | ok 2025-10-13 06:08:08.866652 | 2025-10-13 06:08:08.866718 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-10-13 06:08:09.766102 | controller -> localhost | ok 2025-10-13 06:08:09.772110 | 2025-10-13 06:08:09.772183 | TASK [validate-host : Collect information about the host] 2025-10-13 06:08:10.505744 | controller | ok 2025-10-13 06:08:10.532483 | 2025-10-13 06:08:10.532563 | TASK [validate-host : Sanitize hostname] 2025-10-13 06:08:10.724232 | controller | ok 2025-10-13 06:08:10.729587 | 2025-10-13 06:08:10.729660 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-10-13 06:08:12.331397 | controller -> localhost | changed 2025-10-13 06:08:12.340328 | 2025-10-13 06:08:12.340399 | TASK [validate-host : Collect information about zuul worker] 2025-10-13 06:08:13.255618 | controller | ok 2025-10-13 06:08:13.260676 | 2025-10-13 06:08:13.260745 | TASK [validate-host : Write out all zuul information for each host] 2025-10-13 06:08:15.299287 | controller -> localhost | changed 2025-10-13 06:08:15.319848 | 2025-10-13 06:08:15.319980 | TASK [include_role : prepare-workspace-openshift] 2025-10-13 06:08:15.427755 | controller | skipping: Conditional result was False 2025-10-13 06:08:15.442425 | 2025-10-13 06:08:15.442509 | TASK [include_role : remove-zuul-sshkey] 2025-10-13 06:08:15.571773 | controller | skipping: Conditional result was False 2025-10-13 06:08:15.579625 | 2025-10-13 06:08:15.579739 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2025-10-13 06:08:16.027658 | controller | ok: "logs" 2025-10-13 06:08:16.027858 | controller | ok: All items complete 2025-10-13 06:08:16.040178 | 2025-10-13 06:08:16.242086 | controller | ok: "artifacts" 2025-10-13 06:08:16.417959 | controller | ok: "docs" 2025-10-13 06:08:16.430871 | 2025-10-13 06:08:16.431012 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2025-10-13 06:08:16.707755 | controller | changed: "logs" 2025-10-13 06:08:16.911456 | controller | changed: "artifacts" 2025-10-13 06:08:17.132013 | controller | changed: "docs" 2025-10-13 06:08:17.233084 | 2025-10-13 06:08:17.233164 | PLAY RECAP 2025-10-13 06:08:17.233211 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0 2025-10-13 06:08:17.233313 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-13 06:08:17.233343 | 2025-10-13 06:08:17.741400 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2025-10-13 06:08:17.742137 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master] 2025-10-13 06:08:20.380238 | 2025-10-13 06:08:20.380378 | PLAY [all] 2025-10-13 06:08:20.460307 | 2025-10-13 06:08:20.460413 | TASK [mirror-info-fork : Set mirror_fqdn if not defined] 2025-10-13 06:08:20.797885 | controller | ok 2025-10-13 06:08:20.808007 | 2025-10-13 06:08:20.808095 | TASK [mirror-info-fork : Create /etc/ci] 2025-10-13 06:08:21.353547 | controller | changed 2025-10-13 06:08:21.359079 | 2025-10-13 06:08:21.359153 | TASK [mirror-info-fork : Install ci_mirror script] 2025-10-13 06:08:22.871293 | controller | changed 2025-10-13 06:08:22.887434 | 2025-10-13 06:08:22.887546 | LOOP [add-authorized-keys : Enable access via build key on all nodes] 2025-10-13 06:08:23.610405 | controller | changed: 2025-10-13 06:08:23.610566 | controller | { 2025-10-13 06:08:23.610597 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com" 2025-10-13 06:08:23.610630 | controller | } 2025-10-13 06:08:23.851493 | controller | changed: 2025-10-13 06:08:23.851578 | controller | { 2025-10-13 06:08:23.851607 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com" 2025-10-13 06:08:23.851629 | controller | } 2025-10-13 06:08:24.112618 | controller | changed: 2025-10-13 06:08:24.112707 | controller | { 2025-10-13 06:08:24.112735 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com" 2025-10-13 06:08:24.112757 | controller | } 2025-10-13 06:08:24.348390 | controller | changed: 2025-10-13 06:08:24.348474 | controller | { 2025-10-13 06:08:24.348502 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com" 2025-10-13 06:08:24.348524 | controller | } 2025-10-13 06:08:24.590682 | controller | changed: 2025-10-13 06:08:24.590811 | controller | { 2025-10-13 06:08:24.590841 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com" 2025-10-13 06:08:24.590863 | controller | } 2025-10-13 06:08:24.823497 | controller | changed: 2025-10-13 06:08:24.823582 | controller | { 2025-10-13 06:08:24.823611 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com" 2025-10-13 06:08:24.823633 | controller | } 2025-10-13 06:08:25.052971 | controller | changed: 2025-10-13 06:08:25.053046 | controller | { 2025-10-13 06:08:25.053073 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDLOQd4ZLtkZXQGY6UwAr/06ppWQK4fDO3HaqxPk98csyOCBXsliSKK39Bso828+5srIXiW7aI6aC9P5mwi4mUZlGPfJlQbfrcGvY+b/SocuvaGK+1RrHLoJCT52LBhwgrzlXio2jeksZeein8iaTrhsPrOAs7KggIL/rB9hEiB3NaOPWhhoCP4vlW6MEMExGcqB/1FVxXFBPnLkEyW0Lk7ycVflZl2ocRxbfjZi0+tI1Wlinp8PvSQSc/WVrAcDgKjc/mB4ODPOyYy3G8FHgfMsrXSDEyjBKgLKMsdCrAUcqJQWjkqXleXSYOV4q3pzL+9umK+q/e3P/bIoSFQzmJKTU1eDfuvPXmow9F5H54fii/Da7ezlMJ+wPGHJrRAkmzvMbALy7xwswLhZMkOGNtRcPqaKYRmIBKpw3o6bCTtcNUHOtOQnzwY8JzrM2eBWJBXAANYw+9/ho80JIiwhg29CFNpVBuHbql2YxJQNrnl90guN65rYNpDxdIluweyUf8= anbanerj@kaermorhen" 2025-10-13 06:08:25.053096 | controller | } 2025-10-13 06:08:25.297444 | controller | changed: 2025-10-13 06:08:25.297516 | controller | { 2025-10-13 06:08:25.297543 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQC3VwV8Im9kRm49lt3tM36hj4Zv27FxGo4C1Q/0jqhzFmHY7RHbmeRr8ObhwWoHjXSozKWg8FL5ER0z3hTwL0W6lez3sL7hUaCmSuZmG5Hnl3x4vTSxDI9JZ/Y65rtYiiWQo2fC5xJhU/4+0e5e/pseCm8cKRSu+SaxhO+sd6FDojA2x1BzOzKiQRDy/1zWGp/cZkxcEuB1wHI5LMzN03c67vmbu+fhZRAUO4dQkvcnj2LrhQtpa+ytvnSjr8icMDosf1OsbSffwZFyHB/hfWGAfe0eIeSA2XPraxiPknXxiPKx2MJsaUTYbsZcm3EjFdHBBMumw5rBI74zLrMRvCO9GwBEmGT4rFng1nP+yw5DB8sn2zqpOsPg1LYRwCPOUveC13P6pgsZZPh812e8v5EKnETct+5XI3dVpdw6CnNiLwAyVAF15DJvBGT/u1k0Myg/bQn+Gv9k2MSj6LvQmf6WbZu2Wgjm30z3FyCneBqTL7mLF19YXzeC0ufHz5pnO1E= dasm@fedora" 2025-10-13 06:08:25.297566 | controller | } 2025-10-13 06:08:25.542752 | controller | changed: 2025-10-13 06:08:25.542846 | controller | { 2025-10-13 06:08:25.542876 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner" 2025-10-13 06:08:25.542899 | controller | } 2025-10-13 06:08:25.821632 | controller | changed: 2025-10-13 06:08:25.821721 | controller | { 2025-10-13 06:08:25.821749 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com" 2025-10-13 06:08:25.821772 | controller | } 2025-10-13 06:08:26.029425 | controller | changed: 2025-10-13 06:08:26.029513 | controller | { 2025-10-13 06:08:26.029538 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com" 2025-10-13 06:08:26.029558 | controller | } 2025-10-13 06:08:26.258246 | controller | changed: 2025-10-13 06:08:26.258323 | controller | { 2025-10-13 06:08:26.258351 | controller | "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com" 2025-10-13 06:08:26.258373 | controller | } 2025-10-13 06:08:26.493942 | controller | changed: 2025-10-13 06:08:26.494026 | controller | { 2025-10-13 06:08:26.494053 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith" 2025-10-13 06:08:26.494074 | controller | } 2025-10-13 06:08:26.730299 | controller | changed: 2025-10-13 06:08:26.730384 | controller | { 2025-10-13 06:08:26.730412 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel" 2025-10-13 06:08:26.730434 | controller | } 2025-10-13 06:08:26.948270 | controller | changed: 2025-10-13 06:08:26.948351 | controller | { 2025-10-13 06:08:26.948387 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux" 2025-10-13 06:08:26.948416 | controller | } 2025-10-13 06:08:27.189506 | controller | changed: 2025-10-13 06:08:27.189594 | controller | { 2025-10-13 06:08:27.189621 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr" 2025-10-13 06:08:27.189643 | controller | } 2025-10-13 06:08:27.444190 | controller | changed: 2025-10-13 06:08:27.444270 | controller | { 2025-10-13 06:08:27.444297 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQCfNtF7NvKl915TGsGGoseUb06Hj8L/S4toWf0hExeY+F00woL6NvBlJD0nDct+P5a22I4EhvoQCRQ8reaPCm1lybR3uiRIJsj+8zkVvLwby9LXzfZorlNG9ofjd00FEmB09uW/YvTl6Q9XwwwX6tInzIOv3TMqTHHGOL74ibbj8J/FJR0cFEyj0z4WQRvtkh32xAHl83gbuINryMt0sqRI+clj2381NKL55DRLQrVw0gsfqqxiHAnXg21qWmc4J+b9e9kiuAFQjcjwTVkwJCcg3xbPwC/qokYRby/Y5S40UUd7/jEARGXT7RZgpzTuDd1oZiCVrnrqJNPaMNdVv5MLeFdf1B7iIe5aa/fGouX7AO4SdKhZUdnJmCFAGvjC6S3JMZ2wAcUl+OHnssfmdj7XL50cLo27vjuzMtLAgSqi6N99m92WCF2s8J9aVzszX7Xz9OKZCeGsiVJp3/NdABKzSEAyM9xBD/5Vho894Sav+otpySHe3p6RUTgbB5Zu8VyZRZ/UtB3ueXxyo764yrc6qWIDqrehm84Xm9g+/jpIBzGPl07NUNJpdt/6Sgf9RIKXw/7XypO5yZfUcuFNGTxLfqjTNrtgLZNcjfav6sSdVXVcMPL//XNuRdKmVFaO76eV/oGMQGr1fGcCD+N+CpI7+Q+fCNB6VFWG4nZFuI/Iuw== averdagu@redhat.com" 2025-10-13 06:08:27.444320 | controller | } 2025-10-13 06:08:27.682597 | controller | changed: 2025-10-13 06:08:27.687648 | controller | { 2025-10-13 06:08:27.687711 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDq8l27xI+QlQVdS4djp9ogSoyrNE2+Ox6vKPdhSNL1J3PE5w+WCSvMz9A5gnNuH810zwbekEApbxTze/gLQJwBHA52CChfURpXrFaxY7ePXRElwKAL3mJfzBWY/c5jnNL9TCVmFJTGZkFZP3Nh+BMgZvL6xBkt3WKm6Uq18qzd9XeKcZusrA+O+uLv1fVeQnadY9RIqOCyeFYCzLWrUfTyE8x/XG0hAWIM7qpnF2cALQS2h9n4hW5ybiUN790H08wf9hFwEf5nxY9Z9dVkPFQiTSGKNBzmnCXU9skxS/xhpFjJ5duGSZdtAHe9O+nGZm9c67hxgtf8e5PDuqAdXEv2cf6e3VBAt+Bz8EKI3yosTj0oZHfwr42Yzb1l/SKy14Rggsrc9KAQlrGXan6+u2jcQqqx7l+SWmnpFiWTV9u5cWj2IgOhApOitmRBPYqk9rE2usfO0hLn/Pj/R/Nau4803e1/EikdLE7Ps95s9mX5jRDjAoUa2JwFF5RsVFyL910= ashigupt@ashigupt.remote.csb" 2025-10-13 06:08:27.687746 | controller | } 2025-10-13 06:08:27.901030 | controller | changed: 2025-10-13 06:08:27.901108 | controller | { 2025-10-13 06:08:27.901136 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9" 2025-10-13 06:08:27.901158 | controller | } 2025-10-13 06:08:28.142193 | controller | changed: 2025-10-13 06:08:28.142277 | controller | { 2025-10-13 06:08:28.142305 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora" 2025-10-13 06:08:28.142328 | controller | } 2025-10-13 06:08:28.370602 | controller | changed: 2025-10-13 06:08:28.370686 | controller | { 2025-10-13 06:08:28.370714 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac" 2025-10-13 06:08:28.370736 | controller | } 2025-10-13 06:08:28.643457 | controller | changed: 2025-10-13 06:08:28.643553 | controller | { 2025-10-13 06:08:28.643580 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop" 2025-10-13 06:08:28.643601 | controller | } 2025-10-13 06:08:28.855860 | controller | changed: 2025-10-13 06:08:28.855968 | controller | { 2025-10-13 06:08:28.855997 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQDZdI7t1cxYx65heVI24HTV4F7oQLW1zyfxHreL2TIJKxjyrUUKIFEUmTutcBlJRLNT2Eoix6x1sOw9YrchloCLcn//SGfTElr9mSc5jbjb7QXEU+zJMhtxyEJ1Po3CUGnj7ckiIXw7wcawZtrEOAQ9pH3ExYCJcEMiyNjRQZCxT3tPK+S4B95EWh5Fsrz9CkwpjNRPPH7LigCeQTM3Wc7r97utAslBUUvYceDSLA7rMgkitJE38b7rZBeYzsGQ8YYUBjTCtehqQXxCRjizbHWaaZkBU+N3zkKB6n/iCNGIO690NK7A/qb6msTijiz1PeuM8ThOsi9qXnbX5v0PoTpcFSojV7NHAQ71f0XXuS43FhZctT+Dcx44dT8Fb5vJu2cJGrk+qF8ZgJYNpRS7gPg0EG2EqjK7JMf9ULdjSu0r+KlqIAyLvtzT4eOnQipoKlb/WG5D/0ohKv7OMQ352ggfkBFIQsRXyyTCT98Ft9juqPuahi3CAQmP4H9dyE+7+Kz437PEtsxLmfm6naNmWi7Ee1DqWPwS8rEajsm4sNM4wW9gdBboJQtc0uZw0DfLj1I9r3Mc8Ol0jYtz0yNQDSzVLrGCaJlC311trU70tZ+ZkAVV6Mn8lOhSbj1cK0lvSr6ZK4dgqGl3I1eTZJJhbLNdg7UOVaiRx9543+C/p/As7w== brjackma@redhat.com" 2025-10-13 06:08:28.856019 | controller | } 2025-10-13 06:08:29.101757 | controller | changed: 2025-10-13 06:08:29.101828 | controller | { 2025-10-13 06:08:29.101854 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com" 2025-10-13 06:08:29.101875 | controller | } 2025-10-13 06:08:29.361273 | controller | changed: 2025-10-13 06:08:29.361354 | controller | { 2025-10-13 06:08:29.361382 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com" 2025-10-13 06:08:29.361403 | controller | } 2025-10-13 06:08:29.614116 | controller | changed: 2025-10-13 06:08:29.616521 | controller | { 2025-10-13 06:08:29.616563 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono" 2025-10-13 06:08:29.616588 | controller | } 2025-10-13 06:08:29.693674 | 2025-10-13 06:08:29.693791 | TASK [Set timezone to UTC] 2025-10-13 06:08:30.621719 | controller | changed: executed `/bin/timedatectl set-timezone UTC` 2025-10-13 06:08:30.626492 | 2025-10-13 06:08:30.626555 | TASK [Create nodepool directory] 2025-10-13 06:08:30.943482 | controller | changed 2025-10-13 06:08:30.953861 | 2025-10-13 06:08:30.953970 | TASK [Create nodepool sub_nodes file] 2025-10-13 06:08:31.747150 | controller | changed 2025-10-13 06:08:31.772729 | 2025-10-13 06:08:31.772857 | TASK [Create nodepool sub_nodes_private file] 2025-10-13 06:08:32.597625 | controller | changed 2025-10-13 06:08:32.608458 | 2025-10-13 06:08:32.608521 | LOOP [Populate nodepool sub_nodes file] 2025-10-13 06:08:32.717712 | 2025-10-13 06:08:32.717852 | LOOP [Populate nodepool sub_nodes_private file] 2025-10-13 06:08:32.777523 | 2025-10-13 06:08:32.777675 | TASK [Create nodepool primary file] 2025-10-13 06:08:32.831835 | controller | skipping: Conditional result was False 2025-10-13 06:08:32.847756 | 2025-10-13 06:08:32.847833 | TASK [Create nodepool node_private for this node] 2025-10-13 06:08:33.559727 | controller | changed 2025-10-13 06:08:33.573784 | 2025-10-13 06:08:33.573861 | LOOP [Copy ssh keys to nodepool directory] 2025-10-13 06:08:34.652261 | controller | ok: Item: id_rsa Runtime: 0:00:00.007786 2025-10-13 06:08:34.654044 | 2025-10-13 06:08:34.833385 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.005708 2025-10-13 06:08:34.844625 | 2025-10-13 06:08:34.844734 | TASK [Add sudoers role for zuul-sudo-grep.sh] 2025-10-13 06:08:35.730891 | controller | changed 2025-10-13 06:08:35.742513 | 2025-10-13 06:08:35.742577 | TASK [Validate sudoers config after edits] 2025-10-13 06:08:36.139243 | controller | /etc/sudoers: parsed OK 2025-10-13 06:08:36.139313 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK 2025-10-13 06:08:36.139323 | controller | /etc/sudoers.d/zuul: parsed OK 2025-10-13 06:08:36.139329 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK 2025-10-13 06:08:36.357859 | controller | ok: Runtime: 0:00:00.008391 2025-10-13 06:08:36.363573 | 2025-10-13 06:08:36.363634 | TASK [Show the environment passed in to job shell scripts] 2025-10-13 06:08:36.805265 | controller | SHELL=/bin/bash 2025-10-13 06:08:36.805336 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework 2025-10-13 06:08:36.805346 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework 2025-10-13 06:08:36.805353 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/37/3237/938aa47fd5a26934a4f417d4265e6e3a902fba7a 2025-10-13 06:08:36.805358 | controller | PWD=/home/zuul 2025-10-13 06:08:36.805364 | controller | ZUUL_PIPELINE=github-check 2025-10-13 06:08:36.805369 | controller | LOGNAME=zuul 2025-10-13 06:08:36.805375 | controller | XDG_SESSION_TYPE=tty 2025-10-13 06:08:36.805381 | controller | _=/usr/bin/env 2025-10-13 06:08:36.805387 | controller | MOTD_SHOWN=pam 2025-10-13 06:08:36.805457 | controller | HOME=/home/zuul 2025-10-13 06:08:36.805467 | controller | LANG=en_US.UTF-8 2025-10-13 06:08:36.805472 | controller | SSH_CONNECTION=192.168.26.12 46082 192.168.26.148 22 2025-10-13 06:08:36.805478 | controller | TOX_TESTENV_PASSENV=ZUUL_UUID ZUUL_PROJECT ZUUL_SHORT_PROJECT_NAME ZUUL_PIPELINE ZUUL_VOTING WORKSPACE BUILD_TIMEOUT ZUUL_BRANCH ZUUL_CHANGES ZUUL_CHANGE_IDS ZUUL_CHANGE ZUUL_PATCHSET 2025-10-13 06:08:36.805488 | controller | ZUUL_CHANGE_IDS=3237,938aa47fd5a26934a4f417d4265e6e3a902fba7a 2025-10-13 06:08:36.805493 | controller | WORKSPACE=/home/zuul/workspace 2025-10-13 06:08:36.805499 | controller | XDG_SESSION_CLASS=user 2025-10-13 06:08:36.805504 | controller | SELINUX_ROLE_REQUESTED= 2025-10-13 06:08:36.805510 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s 2025-10-13 06:08:36.805515 | controller | USER=zuul 2025-10-13 06:08:36.805521 | controller | ZUUL_VOTING=True 2025-10-13 06:08:36.805526 | controller | BUILD_TIMEOUT=1800000 2025-10-13 06:08:36.805532 | controller | SELINUX_USE_CURRENT_RANGE= 2025-10-13 06:08:36.805537 | controller | SHLVL=1 2025-10-13 06:08:36.805543 | controller | ZUUL_PATCHSET=938aa47fd5a26934a4f417d4265e6e3a902fba7a 2025-10-13 06:08:36.805548 | controller | XDG_SESSION_ID=1 2025-10-13 06:08:36.805553 | controller | ZUUL_BRANCH=main 2025-10-13 06:08:36.805559 | controller | XDG_RUNTIME_DIR=/run/user/1000 2025-10-13 06:08:36.805564 | controller | SSH_CLIENT=192.168.26.12 46082 22 2025-10-13 06:08:36.805569 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/ 2025-10-13 06:08:36.805575 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima: 2025-10-13 06:08:36.805580 | controller | which_declare=declare -f 2025-10-13 06:08:36.805586 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin 2025-10-13 06:08:36.805592 | controller | SELINUX_LEVEL_REQUESTED= 2025-10-13 06:08:36.805598 | controller | ZUUL_CHANGE=3237 2025-10-13 06:08:36.805603 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus 2025-10-13 06:08:36.805608 | controller | ZUUL_UUID=c0345959015b47d798e63a5b7997f2e1 2025-10-13 06:08:36.805614 | controller | BASH_FUNC_which%%=() { ( alias; 2025-10-13 06:08:36.805619 | controller | eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@ 2025-10-13 06:08:36.805624 | controller | } 2025-10-13 06:08:36.976426 | controller | ok: Runtime: 0:00:00.008836 2025-10-13 06:08:36.982561 | 2025-10-13 06:08:36.982622 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars] 2025-10-13 06:08:37.057351 | controller | skipping: Conditional result was False 2025-10-13 06:08:37.064467 | 2025-10-13 06:08:37.064544 | TASK [Symlink /home/zuul-worker/workspace] 2025-10-13 06:08:37.868129 | controller | skipping: Conditional result was False 2025-10-13 06:08:37.873872 | 2025-10-13 06:08:37.874017 | TASK [Ensure legacy workspace directory] 2025-10-13 06:08:38.190309 | controller | changed 2025-10-13 06:08:38.277114 | 2025-10-13 06:08:38.277249 | PLAY RECAP 2025-10-13 06:08:38.277303 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0 2025-10-13 06:08:38.277327 | 2025-10-13 06:08:38.613395 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master] 2025-10-13 06:08:38.614178 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main] 2025-10-13 06:08:40.811656 | 2025-10-13 06:08:40.811761 | PLAY [Run ci/playbooks/dump_zuul_data.yml] 2025-10-13 06:08:40.889600 | 2025-10-13 06:08:40.889720 | TASK [Create zuul-output directory] 2025-10-13 06:08:41.430812 | controller | changed 2025-10-13 06:08:41.436091 | 2025-10-13 06:08:41.436169 | TASK [Slurp Zuul inventory test] 2025-10-13 06:08:42.550966 | controller -> localhost | ok 2025-10-13 06:08:42.558173 | 2025-10-13 06:08:42.558246 | TASK [Save zuul inventory] 2025-10-13 06:08:43.973721 | controller | changed 2025-10-13 06:08:43.988847 | 2025-10-13 06:08:43.988978 | TASK [Save zuul vars without the change_message] 2025-10-13 06:08:45.206485 | controller | changed 2025-10-13 06:08:45.306371 | 2025-10-13 06:08:45.306446 | PLAY RECAP 2025-10-13 06:08:45.306496 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-13 06:08:45.306522 | 2025-10-13 06:08:45.818589 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main] 2025-10-13 06:08:45.819370 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main] 2025-10-13 06:08:47.840085 | 2025-10-13 06:08:47.842325 | PLAY [Run ci/playbooks/molecule-prepare.yml] 2025-10-13 06:08:47.948457 | 2025-10-13 06:08:47.948570 | TASK [Read group_vars] 2025-10-13 06:08:48.022358 | controller | ok 2025-10-13 06:08:48.124122 | 2025-10-13 06:08:48.124263 | TASK [cifmw_helpers : Check directory is available] 2025-10-13 06:08:49.012600 | controller | ok 2025-10-13 06:08:49.026107 | 2025-10-13 06:08:49.026181 | TASK [cifmw_helpers : List dir in ansible user home] 2025-10-13 06:08:50.150346 | controller | var one 2025-10-13 06:08:50.151774 | controller | ls: cannot access '/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/group_vars'$'\n': No such file or directory 2025-10-13 06:08:50.153169 | controller | ls: cannot access '/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/group_vars': No such file or directory 2025-10-13 06:08:50.154546 | controller | ls: cannot access '/root/src/github.com/openstack-k8s-operators/ci-framework/group_vars': Permission denied 2025-10-13 06:08:50.155925 | controller | ls: cannot access '/workspace/src/github.com/openstack-k8s-operators/ci-framework/group_vars': No such file or directory 2025-10-13 06:08:50.641196 | controller | ERROR 2025-10-13 06:08:50.641379 | controller | { 2025-10-13 06:08:50.641411 | controller | "delta": "0:00:00.009565", 2025-10-13 06:08:50.641435 | controller | "end": "2025-10-13 06:08:50.156269", 2025-10-13 06:08:50.641457 | controller | "msg": "non-zero return code", 2025-10-13 06:08:50.641483 | controller | "rc": 2, 2025-10-13 06:08:50.641505 | controller | "start": "2025-10-13 06:08:50.146704" 2025-10-13 06:08:50.641523 | controller | } 2025-10-13 06:08:50.641549 | controller | ERROR: Ignoring Errors 2025-10-13 06:08:50.647534 | 2025-10-13 06:08:50.647602 | TASK [cifmw_helpers : List available files] 2025-10-13 06:08:51.292597 | controller | skipping: Conditional result was False 2025-10-13 06:08:51.298363 | 2025-10-13 06:08:51.298450 | LOOP [cifmw_helpers : Read vars] 2025-10-13 06:08:51.358849 | 2025-10-13 06:08:51.359052 | LOOP [cifmw_helpers : Call task to parse all files as fact] 2025-10-13 06:08:51.471445 | 2025-10-13 06:08:51.471602 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh] 2025-10-13 06:08:51.714758 | controller | ok 2025-10-13 06:08:51.806722 | 2025-10-13 06:08:51.806831 | TASK [mirror-info-fork : Set mirror_fqdn if not defined] 2025-10-13 06:08:51.944455 | controller | skipping: Conditional result was False 2025-10-13 06:08:51.953502 | 2025-10-13 06:08:51.953599 | TASK [mirror-info-fork : Create /etc/ci] 2025-10-13 06:08:52.839097 | controller | ok 2025-10-13 06:08:52.854269 | 2025-10-13 06:08:52.854338 | TASK [mirror-info-fork : Install ci_mirror script] 2025-10-13 06:08:53.638532 | controller | ok 2025-10-13 06:08:53.690716 | 2025-10-13 06:08:53.690816 | TASK [Prepare workspace] 2025-10-13 06:08:53.743081 | controller | ok 2025-10-13 06:08:53.834081 | 2025-10-13 06:08:53.834171 | TASK [prepare-workspace : Start zuul_console daemon.] 2025-10-13 06:08:54.633320 | controller | ok 2025-10-13 06:08:54.639562 | 2025-10-13 06:08:54.639632 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2025-10-13 06:09:03.038271 | controller | Output suppressed because no_log was given 2025-10-13 06:09:03.061716 | 2025-10-13 06:09:03.061808 | LOOP [Create zuul-output directory] 2025-10-13 06:09:03.325437 | controller | changed: "/home/zuul/ci-framework-data/logs" 2025-10-13 06:09:03.325648 | 2025-10-13 06:09:03.501076 | controller | ok: "/home/zuul/zuul-output/logs" 2025-10-13 06:09:03.522547 | 2025-10-13 06:09:03.522626 | TASK [Install required packages] 2025-10-13 06:10:04.940504 | controller | changed 2025-10-13 06:10:04.945407 | 2025-10-13 06:10:04.945476 | TASK [Install venv] 2025-10-13 06:11:08.545200 | controller | changed 2025-10-13 06:11:08.569900 | 2025-10-13 06:11:08.569993 | PLAY RECAP 2025-10-13 06:11:08.570041 | controller | ok: 9 changed: 5 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1 2025-10-13 06:11:08.570066 | 2025-10-13 06:11:08.646177 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main] 2025-10-13 06:11:08.647022 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main] 2025-10-13 06:11:09.182864 | 2025-10-13 06:11:09.183008 | PLAY [Run ci/playbooks/molecule-test.yml] 2025-10-13 06:11:09.204433 | 2025-10-13 06:11:09.204530 | TASK [Gather required facts] 2025-10-13 06:11:09.716894 | controller | ok 2025-10-13 06:11:09.722760 | 2025-10-13 06:11:09.722837 | TASK [Load environment var if instructed to] 2025-10-13 06:11:09.746686 | controller | skipping: Conditional result was False 2025-10-13 06:11:09.752042 | 2025-10-13 06:11:09.752109 | TASK [Ensure group_vars dir exists] 2025-10-13 06:11:10.064517 | controller | ok 2025-10-13 06:11:10.070554 | 2025-10-13 06:11:10.070622 | TASK [Run molecule] 2025-10-13 06:11:11.018991 | controller | INFO Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml 2025-10-13 06:11:11.089634 | controller | WARNING Driver podman does not provide a schema. 2025-10-13 06:11:11.092294 | controller | INFO Performing prerun with role_name_check=0... 2025-10-13 06:11:33.588816 | 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-13 06:11:33.589282 | 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-13 06:11:33.589744 | 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-13 06:11:33.590208 | 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-13 06:11:33.590698 | controller | WARNING Another version of 'cifmw.general' 1.0.0+938aa47f was found installed in /home/zuul/.cache/ansible-compat/8256b2/collections/ansible_collections, only the first one will be used, 1.0.0+938aa47f (/home/zuul/.ansible/collections/ansible_collections). 2025-10-13 06:11:33.591157 | 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-13 06:11:33.591614 | 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-13 06:11:33.592066 | 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-13 06:11:33.592524 | 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-13 06:11:33.592971 | 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-13 06:11:33.593426 | 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-13 06:11:33.593856 | 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-13 06:11:33.594340 | 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-13 06:11:33.594823 | 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-13 06:11:33.595283 | 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-13 06:11:33.606430 | controller | INFO Running default > dependency 2025-10-13 06:11:33.607358 | controller | WARNING Skipping, missing the requirements file. 2025-10-13 06:11:33.608087 | controller | WARNING Skipping, missing the requirements file. 2025-10-13 06:11:33.617239 | controller | INFO Running default > create 2025-10-13 06:11:33.850093 | controller | INFO Sanity checks: 'podman' 2025-10-13 06:11:34.748756 | controller | 2025-10-13 06:11:34.748812 | controller | PLAY [Create] ****************************************************************** 2025-10-13 06:11:34.748916 | controller | 2025-10-13 06:11:34.749021 | controller | TASK [Get podman executable path] ********************************************** 2025-10-13 06:11:34.749114 | controller | Monday 13 October 2025 06:11:34 +0000 (0:00:00.015) 0:00:00.015 ******** 2025-10-13 06:11:35.045179 | controller | ok: [localhost] 2025-10-13 06:11:35.045249 | controller | 2025-10-13 06:11:35.045389 | controller | TASK [Register podman executable path] ***************************************** 2025-10-13 06:11:35.045512 | controller | Monday 13 October 2025 06:11:35 +0000 (0:00:00.297) 0:00:00.312 ******** 2025-10-13 06:11:35.084585 | controller | ok: [localhost] 2025-10-13 06:11:35.084675 | controller | 2025-10-13 06:11:35.084793 | controller | TASK [Get passwd entries for USER env] ***************************************** 2025-10-13 06:11:35.084913 | controller | Monday 13 October 2025 06:11:35 +0000 (0:00:00.039) 0:00:00.351 ******** 2025-10-13 06:11:35.374833 | controller | ok: [localhost] 2025-10-13 06:11:35.374920 | controller | 2025-10-13 06:11:35.375032 | controller | TASK [Get shell async_dir] ***************************************************** 2025-10-13 06:11:35.375138 | controller | Monday 13 October 2025 06:11:35 +0000 (0:00:00.290) 0:00:00.642 ******** 2025-10-13 06:11:35.419015 | controller | ok: [localhost] 2025-10-13 06:11:35.419098 | controller | 2025-10-13 06:11:35.419225 | controller | TASK [Set async_dir for HOME env] ********************************************** 2025-10-13 06:11:35.419336 | controller | Monday 13 October 2025 06:11:35 +0000 (0:00:00.042) 0:00:00.684 ******** 2025-10-13 06:11:35.453335 | controller | skipping: [localhost] 2025-10-13 06:11:35.496002 | controller | 2025-10-13 06:11:35.496030 | controller | TASK [Log into a container registry] ******************************************* 2025-10-13 06:11:35.496038 | controller | Monday 13 October 2025 06:11:35 +0000 (0:00:00.033) 0:00:00.717 ******** 2025-10-13 06:11:35.496048 | controller | skipping: [localhost] => (item="instance registry username: None specified") 2025-10-13 06:11:35.797552 | controller | skipping: [localhost] 2025-10-13 06:11:35.797583 | controller | 2025-10-13 06:11:35.797591 | controller | TASK [Check presence of custom Dockerfiles] ************************************ 2025-10-13 06:11:35.797598 | controller | Monday 13 October 2025 06:11:35 +0000 (0:00:00.045) 0:00:00.762 ******** 2025-10-13 06:11:35.797608 | controller | ok: [localhost] => (item=Dockerfile: None specified) 2025-10-13 06:11:35.839348 | controller | 2025-10-13 06:11:35.839378 | controller | TASK [Create Dockerfiles from image names] ************************************* 2025-10-13 06:11:35.839386 | controller | Monday 13 October 2025 06:11:35 +0000 (0:00:00.301) 0:00:01.064 ******** 2025-10-13 06:11:35.839396 | controller | skipping: [localhost] => (item="Dockerfile: None specified; Image: registry.access.redhat.com/ubi9/ubi:latest") 2025-10-13 06:11:36.677550 | controller | skipping: [localhost] 2025-10-13 06:11:36.677583 | controller | 2025-10-13 06:11:36.677591 | controller | TASK [Discover local Podman images] ******************************************** 2025-10-13 06:11:36.677597 | controller | Monday 13 October 2025 06:11:35 +0000 (0:00:00.041) 0:00:01.106 ******** 2025-10-13 06:11:36.677607 | controller | ok: [localhost] => (item=instance) 2025-10-13 06:11:36.719365 | controller | 2025-10-13 06:11:36.719402 | controller | TASK [Build an Ansible compatible image] *************************************** 2025-10-13 06:11:36.719411 | controller | Monday 13 October 2025 06:11:36 +0000 (0:00:00.838) 0:00:01.944 ******** 2025-10-13 06:11:36.719421 | controller | skipping: [localhost] => (item=registry.access.redhat.com/ubi9/ubi:latest) 2025-10-13 06:11:36.771395 | controller | skipping: [localhost] 2025-10-13 06:11:36.771421 | controller | 2025-10-13 06:11:36.771428 | controller | TASK [Determine the CMD directives] ******************************************** 2025-10-13 06:11:36.771438 | controller | Monday 13 October 2025 06:11:36 +0000 (0:00:00.041) 0:00:01.986 ******** 2025-10-13 06:11:36.771448 | controller | ok: [localhost] => (item="instance command: sleep infinity") 2025-10-13 06:11:37.013871 | controller | 2025-10-13 06:11:37.013917 | controller | TASK [Remove possible pre-existing containers] ********************************* 2025-10-13 06:11:37.013928 | controller | Monday 13 October 2025 06:11:36 +0000 (0:00:00.051) 0:00:02.038 ******** 2025-10-13 06:11:37.013939 | controller | changed: [localhost] 2025-10-13 06:11:37.059136 | controller | 2025-10-13 06:11:37.059164 | controller | TASK [Discover local podman networks] ****************************************** 2025-10-13 06:11:37.059172 | controller | Monday 13 October 2025 06:11:37 +0000 (0:00:00.242) 0:00:02.280 ******** 2025-10-13 06:11:37.059182 | controller | skipping: [localhost] => (item=instance: None specified) 2025-10-13 06:11:37.096604 | controller | skipping: [localhost] 2025-10-13 06:11:37.096633 | controller | 2025-10-13 06:11:37.096641 | controller | TASK [Create podman network dedicated to this scenario] ************************ 2025-10-13 06:11:37.096648 | controller | Monday 13 October 2025 06:11:37 +0000 (0:00:00.045) 0:00:02.325 ******** 2025-10-13 06:11:37.096658 | controller | skipping: [localhost] 2025-10-13 06:11:37.423842 | controller | 2025-10-13 06:11:37.423875 | controller | TASK [Create molecule instance(s)] ********************************************* 2025-10-13 06:11:37.423883 | controller | Monday 13 October 2025 06:11:37 +0000 (0:00:00.037) 0:00:02.363 ******** 2025-10-13 06:11:37.423908 | controller | changed: [localhost] => (item=instance) 2025-10-13 06:11:42.899930 | controller | 2025-10-13 06:11:42.899961 | controller | TASK [Wait for instance(s) creation to complete] ******************************* 2025-10-13 06:11:42.899970 | controller | Monday 13 October 2025 06:11:37 +0000 (0:00:00.327) 0:00:02.690 ******** 2025-10-13 06:11:42.899980 | controller | FAILED - RETRYING: [localhost]: Wait for instance(s) creation to complete (300 retries left). 2025-10-13 06:11:42.953257 | controller | changed: [localhost] => (item=instance) 2025-10-13 06:11:42.953288 | controller | 2025-10-13 06:11:42.953295 | controller | PLAY RECAP ********************************************************************* 2025-10-13 06:11:42.953302 | controller | localhost : ok=10 changed=3 unreachable=0 failed=0 skipped=6 rescued=0 ignored=0 2025-10-13 06:11:42.953307 | controller | 2025-10-13 06:11:42.953313 | controller | Monday 13 October 2025 06:11:42 +0000 (0:00:05.474) 0:00:08.164 ******** 2025-10-13 06:11:42.953318 | controller | =============================================================================== 2025-10-13 06:11:42.953323 | controller | Wait for instance(s) creation to complete ------------------------------- 5.47s 2025-10-13 06:11:42.953328 | controller | Discover local Podman images -------------------------------------------- 0.84s 2025-10-13 06:11:42.953334 | controller | Create molecule instance(s) --------------------------------------------- 0.33s 2025-10-13 06:11:42.953339 | controller | Check presence of custom Dockerfiles ------------------------------------ 0.30s 2025-10-13 06:11:42.953344 | controller | Get podman executable path ---------------------------------------------- 0.30s 2025-10-13 06:11:42.953349 | controller | Get passwd entries for USER env ----------------------------------------- 0.29s 2025-10-13 06:11:42.953357 | controller | Remove possible pre-existing containers --------------------------------- 0.24s 2025-10-13 06:11:42.953362 | controller | Determine the CMD directives -------------------------------------------- 0.05s 2025-10-13 06:11:42.953368 | controller | Discover local podman networks ------------------------------------------ 0.05s 2025-10-13 06:11:42.953373 | controller | Log into a container registry ------------------------------------------- 0.05s 2025-10-13 06:11:42.953379 | controller | Get shell async_dir ----------------------------------------------------- 0.04s 2025-10-13 06:11:42.953384 | controller | Build an Ansible compatible image --------------------------------------- 0.04s 2025-10-13 06:11:42.953389 | controller | Create Dockerfiles from image names ------------------------------------- 0.04s 2025-10-13 06:11:42.953399 | controller | Register podman executable path ----------------------------------------- 0.04s 2025-10-13 06:11:42.953405 | controller | Create podman network dedicated to this scenario ------------------------ 0.04s 2025-10-13 06:11:42.953410 | controller | Set async_dir for HOME env ---------------------------------------------- 0.03s 2025-10-13 06:11:42.953420 | controller | INFO Running default > prepare 2025-10-13 06:11:43.358959 | controller | 2025-10-13 06:11:44.469400 | controller | PLAY [Prepare] ***************************************************************** 2025-10-13 06:11:44.469433 | controller | 2025-10-13 06:11:44.469441 | controller | TASK [Gathering Facts] ********************************************************* 2025-10-13 06:11:44.469447 | controller | Monday 13 October 2025 06:11:43 +0000 (0:00:00.010) 0:00:00.010 ******** 2025-10-13 06:11:44.469457 | controller | ok: [instance] 2025-10-13 06:11:51.215883 | controller | 2025-10-13 06:11:51.215930 | controller | TASK [Install Python pip and dependencies using dnf] *************************** 2025-10-13 06:11:51.215938 | controller | Monday 13 October 2025 06:11:44 +0000 (0:00:01.110) 0:00:01.121 ******** 2025-10-13 06:11:51.215948 | controller | changed: [instance] 2025-10-13 06:11:56.539780 | controller | 2025-10-13 06:11:56.539811 | controller | TASK [Install Python dependencies] ********************************************* 2025-10-13 06:11:56.539820 | controller | Monday 13 October 2025 06:11:51 +0000 (0:00:06.746) 0:00:07.868 ******** 2025-10-13 06:11:56.539829 | controller | changed: [instance] 2025-10-13 06:11:58.164864 | controller | 2025-10-13 06:11:58.164970 | controller | TASK [Create mock kubeconfig file] ********************************************* 2025-10-13 06:11:58.164980 | controller | Monday 13 October 2025 06:11:56 +0000 (0:00:05.323) 0:00:13.192 ******** 2025-10-13 06:11:58.164994 | controller | changed: [instance] 2025-10-13 06:11:58.165074 | controller | 2025-10-13 06:11:58.165105 | controller | TASK [Create mock kubeadmin password file] ************************************* 2025-10-13 06:11:59.635733 | controller | Monday 13 October 2025 06:11:58 +0000 (0:00:01.625) 0:00:14.817 ******** 2025-10-13 06:11:59.635770 | controller | changed: [instance] 2025-10-13 06:11:59.689220 | controller | 2025-10-13 06:11:59.689265 | controller | PLAY RECAP ********************************************************************* 2025-10-13 06:11:59.689274 | controller | instance : ok=5 changed=4 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2025-10-13 06:11:59.689280 | controller | 2025-10-13 06:11:59.689286 | controller | Monday 13 October 2025 06:11:59 +0000 (0:00:01.470) 0:00:16.287 ******** 2025-10-13 06:11:59.689291 | controller | =============================================================================== 2025-10-13 06:11:59.689297 | controller | Install Python pip and dependencies using dnf --------------------------- 6.75s 2025-10-13 06:11:59.689302 | controller | Install Python dependencies --------------------------------------------- 5.32s 2025-10-13 06:11:59.689307 | controller | Create mock kubeconfig file --------------------------------------------- 1.63s 2025-10-13 06:11:59.689313 | controller | Create mock kubeadmin password file ------------------------------------- 1.47s 2025-10-13 06:11:59.689318 | controller | Gathering Facts --------------------------------------------------------- 1.11s 2025-10-13 06:11:59.689328 | controller | INFO Running default > converge 2025-10-13 06:12:00.147949 | controller | 2025-10-13 06:12:00.148206 | controller | PLAY [Converge] **************************************************************** 2025-10-13 06:12:00.148370 | controller | 2025-10-13 06:12:00.148518 | controller | TASK [Test that required variables are defined] ******************************** 2025-10-13 06:12:00.148667 | controller | Monday 13 October 2025 06:12:00 +0000 (0:00:00.022) 0:00:00.022 ******** 2025-10-13 06:12:00.173922 | controller | ok: [instance] => changed=false 2025-10-13 06:12:00.194496 | controller | msg: Required variables are defined 2025-10-13 06:12:00.194514 | controller | 2025-10-13 06:12:00.194521 | controller | TASK [Display test information] ************************************************ 2025-10-13 06:12:00.194527 | controller | Monday 13 October 2025 06:12:00 +0000 (0:00:00.026) 0:00:00.048 ******** 2025-10-13 06:12:00.194536 | controller | ok: [instance] => 2025-10-13 06:12:01.396758 | controller | msg: 'Testing role cifmw_snr_nhc with kubeconfig: /tmp/kubeconfig and namespace: workload-availability' 2025-10-13 06:12:01.396795 | controller | 2025-10-13 06:12:01.396803 | controller | TASK [Test that Python kubernetes library is available] ************************ 2025-10-13 06:12:01.396809 | controller | Monday 13 October 2025 06:12:00 +0000 (0:00:00.020) 0:00:00.068 ******** 2025-10-13 06:12:01.396818 | controller | ok: [instance] 2025-10-13 06:12:01.418512 | controller | 2025-10-13 06:12:01.418535 | controller | TASK [Display kubernetes library test result] ********************************** 2025-10-13 06:12:01.418542 | controller | Monday 13 October 2025 06:12:01 +0000 (0:00:01.202) 0:00:01.271 ******** 2025-10-13 06:12:01.418552 | controller | ok: [instance] => 2025-10-13 06:12:02.175815 | controller | msg: Library available 2025-10-13 06:12:02.175846 | controller | 2025-10-13 06:12:02.175853 | controller | TASK [Test that mock kubeconfig exists] **************************************** 2025-10-13 06:12:02.175860 | controller | Monday 13 October 2025 06:12:01 +0000 (0:00:00.021) 0:00:01.292 ******** 2025-10-13 06:12:02.175869 | controller | ok: [instance] 2025-10-13 06:12:02.201009 | controller | 2025-10-13 06:12:02.201054 | controller | TASK [Assert kubeconfig exists] ************************************************ 2025-10-13 06:12:02.201064 | controller | Monday 13 October 2025 06:12:02 +0000 (0:00:00.757) 0:00:02.050 ******** 2025-10-13 06:12:02.201077 | controller | ok: [instance] => changed=false 2025-10-13 06:12:02.201101 | controller | msg: Kubeconfig file exists 2025-10-13 06:12:02.201423 | controller | 2025-10-13 06:12:03.516451 | controller | TASK [Test kubernetes.core.k8s module availability] **************************** 2025-10-13 06:12:03.516481 | controller | Monday 13 October 2025 06:12:02 +0000 (0:00:00.025) 0:00:02.075 ******** 2025-10-13 06:12:03.516493 | controller | ok: [instance] 2025-10-13 06:12:03.537518 | controller | 2025-10-13 06:12:03.537541 | controller | TASK [Display k8s connection test result] ************************************** 2025-10-13 06:12:03.537549 | controller | Monday 13 October 2025 06:12:03 +0000 (0:00:01.315) 0:00:03.390 ******** 2025-10-13 06:12:03.537559 | controller | ok: [instance] => 2025-10-13 06:12:03.601015 | controller | msg: 'K8s connection test result: False' 2025-10-13 06:12:03.601051 | controller | 2025-10-13 06:12:03.601061 | controller | TASK [Include the cifmw_snr_nhc role] ****************************************** 2025-10-13 06:12:03.601068 | controller | Monday 13 October 2025 06:12:03 +0000 (0:00:00.021) 0:00:03.411 ******** 2025-10-13 06:12:03.601080 | controller | 2025-10-13 06:12:03.601136 | controller | TASK [cifmw_snr_nhc : Check if NodeHealthCheck exists and is active] *********** 2025-10-13 06:12:03.601168 | controller | Monday 13 October 2025 06:12:03 +0000 (0:00:00.063) 0:00:03.475 ******** 2025-10-13 06:12:03.628500 | controller | skipping: [instance] 2025-10-13 06:12:03.654443 | controller | 2025-10-13 06:12:03.654469 | controller | TASK [cifmw_snr_nhc : Check for active SelfNodeRemediations] ******************* 2025-10-13 06:12:03.654478 | controller | Monday 13 October 2025 06:12:03 +0000 (0:00:00.027) 0:00:03.502 ******** 2025-10-13 06:12:03.654490 | controller | skipping: [instance] 2025-10-13 06:12:03.680697 | controller | 2025-10-13 06:12:03.680726 | controller | TASK [cifmw_snr_nhc : Display active remediations info] ************************ 2025-10-13 06:12:03.680735 | controller | Monday 13 October 2025 06:12:03 +0000 (0:00:00.025) 0:00:03.528 ******** 2025-10-13 06:12:03.680747 | controller | skipping: [instance] 2025-10-13 06:12:03.707546 | controller | 2025-10-13 06:12:03.707568 | controller | TASK [cifmw_snr_nhc : Disable NodeHealthCheck to stop active remediations] ***** 2025-10-13 06:12:03.707576 | controller | Monday 13 October 2025 06:12:03 +0000 (0:00:00.026) 0:00:03.555 ******** 2025-10-13 06:12:03.707585 | controller | skipping: [instance] 2025-10-13 06:12:03.733533 | controller | 2025-10-13 06:12:03.733554 | controller | TASK [cifmw_snr_nhc : Wait for active remediations to stop] ******************** 2025-10-13 06:12:03.733562 | controller | Monday 13 October 2025 06:12:03 +0000 (0:00:00.026) 0:00:03.581 ******** 2025-10-13 06:12:03.733571 | controller | skipping: [instance] 2025-10-13 06:12:03.761403 | controller | 2025-10-13 06:12:03.761424 | controller | TASK [cifmw_snr_nhc : Delete existing NodeHealthCheck resources] *************** 2025-10-13 06:12:03.761432 | controller | Monday 13 October 2025 06:12:03 +0000 (0:00:00.025) 0:00:03.607 ******** 2025-10-13 06:12:03.761441 | controller | skipping: [instance] 2025-10-13 06:12:03.787735 | controller | 2025-10-13 06:12:03.787760 | controller | TASK [cifmw_snr_nhc : Check for blocking remediations when deletion fails] ***** 2025-10-13 06:12:03.787783 | controller | Monday 13 October 2025 06:12:03 +0000 (0:00:00.027) 0:00:03.635 ******** 2025-10-13 06:12:03.787795 | controller | skipping: [instance] 2025-10-13 06:12:03.813541 | controller | 2025-10-13 06:12:03.813560 | controller | TASK [cifmw_snr_nhc : Display blocking remediation details] ******************** 2025-10-13 06:12:03.813567 | controller | Monday 13 October 2025 06:12:03 +0000 (0:00:00.026) 0:00:03.662 ******** 2025-10-13 06:12:03.813576 | controller | skipping: [instance] 2025-10-13 06:12:03.839581 | controller | 2025-10-13 06:12:03.839599 | controller | TASK [cifmw_snr_nhc : Display warning if NodeHealthCheck deletion failed due to active remediation] *** 2025-10-13 06:12:03.839607 | controller | Monday 13 October 2025 06:12:03 +0000 (0:00:00.025) 0:00:03.688 ******** 2025-10-13 06:12:03.839616 | controller | skipping: [instance] 2025-10-13 06:12:03.866413 | controller | 2025-10-13 06:12:03.866436 | controller | TASK [cifmw_snr_nhc : Skip NodeHealthCheck deletion retry if webhook blocks it] *** 2025-10-13 06:12:03.866444 | controller | Monday 13 October 2025 06:12:03 +0000 (0:00:00.026) 0:00:03.714 ******** 2025-10-13 06:12:03.866453 | controller | skipping: [instance] 2025-10-13 06:12:03.893573 | controller | 2025-10-13 06:12:03.893592 | controller | TASK [cifmw_snr_nhc : Check if SelfNodeRemediationConfig exists] *************** 2025-10-13 06:12:03.893599 | controller | Monday 13 October 2025 06:12:03 +0000 (0:00:00.026) 0:00:03.741 ******** 2025-10-13 06:12:03.893608 | controller | skipping: [instance] 2025-10-13 06:12:03.920497 | controller | 2025-10-13 06:12:03.920516 | controller | TASK [cifmw_snr_nhc : Delete existing SelfNodeRemediationConfig resources] ***** 2025-10-13 06:12:03.920523 | controller | Monday 13 October 2025 06:12:03 +0000 (0:00:00.027) 0:00:03.768 ******** 2025-10-13 06:12:03.920532 | controller | skipping: [instance] 2025-10-13 06:12:03.951637 | controller | 2025-10-13 06:12:03.951655 | controller | TASK [cifmw_snr_nhc : Check if SelfNodeRemediationTemplate exists] ************* 2025-10-13 06:12:03.951662 | controller | Monday 13 October 2025 06:12:03 +0000 (0:00:00.026) 0:00:03.795 ******** 2025-10-13 06:12:03.951671 | controller | skipping: [instance] 2025-10-13 06:12:03.978767 | controller | 2025-10-13 06:12:03.978787 | controller | TASK [cifmw_snr_nhc : Delete existing SelfNodeRemediationTemplate resources] *** 2025-10-13 06:12:03.978794 | controller | Monday 13 October 2025 06:12:03 +0000 (0:00:00.031) 0:00:03.826 ******** 2025-10-13 06:12:03.978803 | controller | skipping: [instance] 2025-10-13 06:12:04.005185 | controller | 2025-10-13 06:12:04.005219 | controller | TASK [cifmw_snr_nhc : Check if Subscriptions exist] **************************** 2025-10-13 06:12:04.005227 | controller | Monday 13 October 2025 06:12:03 +0000 (0:00:00.027) 0:00:03.853 ******** 2025-10-13 06:12:04.005238 | controller | skipping: [instance] 2025-10-13 06:12:04.005417 | controller | 2025-10-13 06:12:04.005440 | controller | TASK [cifmw_snr_nhc : Delete existing Subscriptions] *************************** 2025-10-13 06:12:04.037007 | controller | Monday 13 October 2025 06:12:04 +0000 (0:00:00.026) 0:00:03.879 ******** 2025-10-13 06:12:04.037053 | controller | skipping: [instance] => (item=self-node-remediation-operator) 2025-10-13 06:12:04.063730 | controller | skipping: [instance] => (item=node-health-check-operator) 2025-10-13 06:12:04.063753 | controller | skipping: [instance] 2025-10-13 06:12:04.063760 | controller | 2025-10-13 06:12:04.063766 | controller | TASK [cifmw_snr_nhc : Check if OperatorGroup exists] *************************** 2025-10-13 06:12:04.063772 | controller | Monday 13 October 2025 06:12:04 +0000 (0:00:00.031) 0:00:03.911 ******** 2025-10-13 06:12:04.063781 | controller | skipping: [instance] 2025-10-13 06:12:04.090389 | controller | 2025-10-13 06:12:04.090411 | controller | TASK [cifmw_snr_nhc : Delete existing OperatorGroup] *************************** 2025-10-13 06:12:04.090418 | controller | Monday 13 October 2025 06:12:04 +0000 (0:00:00.026) 0:00:03.938 ******** 2025-10-13 06:12:04.090427 | controller | skipping: [instance] 2025-10-13 06:12:04.119049 | controller | 2025-10-13 06:12:04.119091 | controller | TASK [cifmw_snr_nhc : Delete the entire workload-availability namespace] ******* 2025-10-13 06:12:04.119101 | controller | Monday 13 October 2025 06:12:04 +0000 (0:00:00.026) 0:00:03.964 ******** 2025-10-13 06:12:04.119114 | controller | skipping: [instance] 2025-10-13 06:12:04.119143 | controller | 2025-10-13 06:12:04.119153 | controller | TASK [cifmw_snr_nhc : Wait for namespace deletion to complete] ***************** 2025-10-13 06:12:04.119189 | controller | Monday 13 October 2025 06:12:04 +0000 (0:00:00.028) 0:00:03.993 ******** 2025-10-13 06:12:04.146332 | controller | skipping: [instance] 2025-10-13 06:12:05.320845 | controller | 2025-10-13 06:12:05.320876 | controller | TASK [cifmw_snr_nhc : Create the workload-availability namespace] ************** 2025-10-13 06:12:05.320887 | controller | Monday 13 October 2025 06:12:04 +0000 (0:00:00.027) 0:00:04.020 ******** 2025-10-13 06:12:05.320924 | 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-13 06:12:05.324950 | controller | fatal: [instance]: FAILED! => changed=false 2025-10-13 06:12:05.325088 | controller | module_stderr: |- 2025-10-13 06:12:05.325101 | controller | Traceback (most recent call last): 2025-10-13 06:12:05.325108 | controller | File "/usr/lib/python3.9/site-packages/urllib3/connection.py", line 169, in _new_conn 2025-10-13 06:12:05.325114 | controller | conn = connection.create_connection( 2025-10-13 06:12:05.325119 | controller | File "/usr/lib/python3.9/site-packages/urllib3/util/connection.py", line 73, in create_connection 2025-10-13 06:12:05.325125 | controller | for res in socket.getaddrinfo(host, port, family, socket.SOCK_STREAM): 2025-10-13 06:12:05.325130 | controller | File "/usr/lib64/python3.9/socket.py", line 966, in getaddrinfo 2025-10-13 06:12:05.325136 | controller | for res in _socket.getaddrinfo(host, port, family, type, proto, flags): 2025-10-13 06:12:05.325141 | controller | socket.gaierror: [Errno -2] Name or service not known 2025-10-13 06:12:05.325147 | controller | 2025-10-13 06:12:05.325152 | controller | During handling of the above exception, another exception occurred: 2025-10-13 06:12:05.325158 | controller | 2025-10-13 06:12:05.325163 | controller | Traceback (most recent call last): 2025-10-13 06:12:05.325168 | controller | File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 700, in urlopen 2025-10-13 06:12:05.325173 | controller | httplib_response = self._make_request( 2025-10-13 06:12:05.325179 | controller | File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 383, in _make_request 2025-10-13 06:12:05.325184 | controller | self._validate_conn(conn) 2025-10-13 06:12:05.325189 | controller | File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 1015, in _validate_conn 2025-10-13 06:12:05.325194 | controller | conn.connect() 2025-10-13 06:12:05.325205 | controller | File "/usr/lib/python3.9/site-packages/urllib3/connection.py", line 353, in connect 2025-10-13 06:12:05.325210 | controller | conn = self._new_conn() 2025-10-13 06:12:05.325216 | controller | File "/usr/lib/python3.9/site-packages/urllib3/connection.py", line 181, in _new_conn 2025-10-13 06:12:05.325221 | controller | raise NewConnectionError( 2025-10-13 06:12:05.325226 | controller | urllib3.exceptions.NewConnectionError: : Failed to establish a new connection: [Errno -2] Name or service not known 2025-10-13 06:12:05.325232 | controller | 2025-10-13 06:12:05.325237 | controller | During handling of the above exception, another exception occurred: 2025-10-13 06:12:05.325243 | controller | 2025-10-13 06:12:05.325248 | controller | Traceback (most recent call last): 2025-10-13 06:12:05.325256 | controller | File "/tmp/ansible-tmp-1760335924.1592975-28247-213398245661210/AnsiballZ_k8s.py", line 107, in 2025-10-13 06:12:05.325421 | controller | _ansiballz_main() 2025-10-13 06:12:05.325573 | controller | File "/tmp/ansible-tmp-1760335924.1592975-28247-213398245661210/AnsiballZ_k8s.py", line 99, in _ansiballz_main 2025-10-13 06:12:05.325713 | controller | invoke_module(zipped_mod, temp_path, ANSIBALLZ_PARAMS) 2025-10-13 06:12:05.325861 | controller | File "/tmp/ansible-tmp-1760335924.1592975-28247-213398245661210/AnsiballZ_k8s.py", line 47, in invoke_module 2025-10-13 06:12:05.326035 | 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-13 06:12:05.326190 | controller | File "/usr/lib64/python3.9/runpy.py", line 225, in run_module 2025-10-13 06:12:05.326348 | controller | return _run_module_code(code, init_globals, run_name, mod_spec) 2025-10-13 06:12:05.326486 | controller | File "/usr/lib64/python3.9/runpy.py", line 97, in _run_module_code 2025-10-13 06:12:05.326622 | controller | _run_code(code, mod_globals, init_globals, 2025-10-13 06:12:05.326759 | controller | File "/usr/lib64/python3.9/runpy.py", line 87, in _run_code 2025-10-13 06:12:05.326908 | controller | exec(code, run_globals) 2025-10-13 06:12:05.327072 | controller | File "/tmp/ansible_kubernetes.core.k8s_payload_w1i9l_pq/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/modules/k8s.py", line 509, in 2025-10-13 06:12:05.327218 | controller | File "/tmp/ansible_kubernetes.core.k8s_payload_w1i9l_pq/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/modules/k8s.py", line 503, in main 2025-10-13 06:12:05.327381 | controller | File "/tmp/ansible_kubernetes.core.k8s_payload_w1i9l_pq/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/k8s/runner.py", line 88, in run_module 2025-10-13 06:12:05.327524 | controller | File "/tmp/ansible_kubernetes.core.k8s_payload_w1i9l_pq/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/k8s/client.py", line 352, in get_api_client 2025-10-13 06:12:05.327667 | controller | File "/tmp/ansible_kubernetes.core.k8s_payload_w1i9l_pq/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/k8s/client.py", line 246, in wrapper 2025-10-13 06:12:05.327807 | controller | File "/tmp/ansible_kubernetes.core.k8s_payload_w1i9l_pq/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/k8s/client.py", line 259, in create_api_client 2025-10-13 06:12:05.327977 | controller | File "/usr/local/lib/python3.9/site-packages/kubernetes/dynamic/client.py", line 84, in __init__ 2025-10-13 06:12:05.328126 | controller | self.__discoverer = discoverer(self, cache_file) 2025-10-13 06:12:05.328282 | controller | File "/tmp/ansible_kubernetes.core.k8s_payload_w1i9l_pq/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/client/discovery.py", line 190, in __init__ 2025-10-13 06:12:05.328427 | controller | File "/tmp/ansible_kubernetes.core.k8s_payload_w1i9l_pq/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/client/discovery.py", line 45, in __init__ 2025-10-13 06:12:05.328566 | controller | File "/tmp/ansible_kubernetes.core.k8s_payload_w1i9l_pq/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/client/discovery.py", line 92, in __init_cache 2025-10-13 06:12:05.328711 | controller | File "/usr/local/lib/python3.9/site-packages/kubernetes/dynamic/discovery.py", line 146, in _load_server_info 2025-10-13 06:12:05.328857 | controller | 'kubernetes': self.client.request('get', '/version', serializer=just_json) 2025-10-13 06:12:05.329033 | controller | File "/usr/local/lib/python3.9/site-packages/kubernetes/dynamic/client.py", line 55, in inner 2025-10-13 06:12:05.329177 | controller | resp = func(self, *args, **kwargs) 2025-10-13 06:12:05.329337 | controller | File "/usr/local/lib/python3.9/site-packages/kubernetes/dynamic/client.py", line 277, in request 2025-10-13 06:12:05.329474 | controller | api_response = self.client.call_api( 2025-10-13 06:12:05.329611 | controller | File "/usr/local/lib/python3.9/site-packages/kubernetes/client/api_client.py", line 348, in call_api 2025-10-13 06:12:05.329745 | controller | return self.__call_api(resource_path, method, 2025-10-13 06:12:05.329883 | controller | File "/usr/local/lib/python3.9/site-packages/kubernetes/client/api_client.py", line 180, in __call_api 2025-10-13 06:12:05.330048 | controller | response_data = self.request( 2025-10-13 06:12:05.330299 | controller | File "/usr/local/lib/python3.9/site-packages/kubernetes/client/api_client.py", line 373, in request 2025-10-13 06:12:05.330454 | controller | return self.rest_client.GET(url, 2025-10-13 06:12:05.330684 | controller | File "/usr/local/lib/python3.9/site-packages/kubernetes/client/rest.py", line 244, in GET 2025-10-13 06:12:05.330880 | controller | return self.request("GET", url, 2025-10-13 06:12:05.331047 | controller | File "/usr/local/lib/python3.9/site-packages/kubernetes/client/rest.py", line 217, in request 2025-10-13 06:12:05.331195 | controller | r = self.pool_manager.request(method, url, 2025-10-13 06:12:05.331351 | controller | File "/usr/lib/python3.9/site-packages/urllib3/request.py", line 74, in request 2025-10-13 06:12:05.331485 | controller | return self.request_encode_url( 2025-10-13 06:12:05.331630 | controller | File "/usr/lib/python3.9/site-packages/urllib3/request.py", line 96, in request_encode_url 2025-10-13 06:12:05.331764 | controller | return self.urlopen(method, url, **extra_kw) 2025-10-13 06:12:05.332224 | controller | File "/usr/lib/python3.9/site-packages/urllib3/poolmanager.py", line 376, in urlopen 2025-10-13 06:12:05.332601 | controller | response = conn.urlopen(method, u.request_uri, **kw) 2025-10-13 06:12:05.332758 | controller | File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 784, in urlopen 2025-10-13 06:12:05.332922 | controller | return self.urlopen( 2025-10-13 06:12:05.333078 | controller | File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 784, in urlopen 2025-10-13 06:12:05.333217 | controller | return self.urlopen( 2025-10-13 06:12:05.333373 | controller | File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 784, in urlopen 2025-10-13 06:12:05.333509 | controller | return self.urlopen( 2025-10-13 06:12:05.333646 | controller | File "/usr/lib/python3.9/site-packages/urllib3/connectionpool.py", line 756, in urlopen 2025-10-13 06:12:05.333783 | controller | retries = retries.increment( 2025-10-13 06:12:05.333953 | controller | File "/usr/lib/python3.9/site-packages/urllib3/util/retry.py", line 576, in increment 2025-10-13 06:12:05.334102 | controller | raise MaxRetryError(_pool, url, error or ResponseError(cause)) 2025-10-13 06:12:05.334286 | 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-13 06:12:05.334437 | controller | module_stdout: '' 2025-10-13 06:12:05.334573 | controller | msg: |- 2025-10-13 06:12:05.334709 | controller | MODULE FAILURE 2025-10-13 06:12:05.334843 | controller | See stdout/stderr for the exact error 2025-10-13 06:12:05.335002 | controller | rc: 1 2025-10-13 06:12:05.335192 | controller | 2025-10-13 06:12:05.335351 | controller | TASK [Capture role execution error] ******************************************** 2025-10-13 06:12:05.335498 | controller | Monday 13 October 2025 06:12:05 +0000 (0:00:01.182) 0:00:05.203 ******** 2025-10-13 06:12:05.352394 | controller | ok: [instance] 2025-10-13 06:12:05.352574 | controller | 2025-10-13 06:12:05.352746 | controller | TASK [Display role execution error details] ************************************ 2025-10-13 06:12:05.352937 | controller | Monday 13 October 2025 06:12:05 +0000 (0:00:00.023) 0:00:05.227 ******** 2025-10-13 06:12:05.374500 | controller | ok: [instance] => 2025-10-13 06:12:05.374680 | controller | msg: |- 2025-10-13 06:12:05.374847 | controller | Role execution failed with error: 2025-10-13 06:12:05.375091 | 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': 'fa163ec2-9a4c-116b-eecd-000000000166', 'finalized': True, 'squashed': False}, 'poll': 15, 'register': 'namespace_result', 'retries': 3, 'until': [], 'loop_with': None, 'uuid': 'fa163ec2-9a4c-116b-eecd-00000000008f', 'finalized': True, 'squashed': True} 2025-10-13 06:12:05.375250 | controller | 2025-10-13 06:12:05.375403 | controller | TASK [Analyze specific error patterns] ***************************************** 2025-10-13 06:12:05.375542 | controller | Monday 13 October 2025 06:12:05 +0000 (0:00:00.022) 0:00:05.249 ******** 2025-10-13 06:12:05.410519 | controller | ok: [instance] => 2025-10-13 06:12:05.410696 | controller | msg: |- 2025-10-13 06:12:05.410858 | controller | Error analysis: 2025-10-13 06:12:05.411064 | controller | - Connection error: False 2025-10-13 06:12:05.411232 | controller | - Authentication error: False 2025-10-13 06:12:05.411387 | controller | - API error: False 2025-10-13 06:12:05.411521 | controller | - Timeout error: False 2025-10-13 06:12:05.411650 | controller | 2025-10-13 06:12:05.411786 | controller | TASK [Continue with test evaluation] ******************************************* 2025-10-13 06:12:05.411941 | controller | Monday 13 October 2025 06:12:05 +0000 (0:00:00.036) 0:00:05.285 ******** 2025-10-13 06:12:05.431957 | controller | ok: [instance] => 2025-10-13 06:12:05.432122 | controller | msg: Role failed as expected in test environment - this is normal 2025-10-13 06:12:05.432256 | controller | 2025-10-13 06:12:05.432411 | controller | TASK [Verify namespace exists] ************************************************* 2025-10-13 06:12:05.432548 | controller | Monday 13 October 2025 06:12:05 +0000 (0:00:00.021) 0:00:05.306 ******** 2025-10-13 06:12:06.581035 | controller | ok: [instance] 2025-10-13 06:12:06.599152 | controller | 2025-10-13 06:12:06.599186 | controller | TASK [Display namespace verification result] *********************************** 2025-10-13 06:12:06.599194 | controller | Monday 13 October 2025 06:12:06 +0000 (0:00:01.148) 0:00:06.455 ******** 2025-10-13 06:12:06.599205 | controller | ok: [instance] => 2025-10-13 06:12:06.599212 | 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-13 06:12:06.599219 | 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-1760335925.4452617-28426-253486750129826/AnsiballZ_k8s.py", line 107, in \n _ansiballz_main()\n File "/tmp/ansible-tmp-1760335925.4452617-28426-253486750129826/AnsiballZ_k8s.py", line 99, in _ansiballz_main\n invoke_module(zipped_mod, temp_path, ANSIBALLZ_PARAMS)\n File "/tmp/ansible-tmp-1760335925.4452617-28426-253486750129826/AnsiballZ_k8s.py", 2025-10-13 06:12:06.599234 | 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_xd_vviia/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_xd_vviia/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_xd_vviia/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/k8s/runner.py", 2025-10-13 06:12:06.599247 | controller | line 88, in run_module\n File "/tmp/ansible_kubernetes.core.k8s_payload_xd_vviia/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_xd_vviia/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_xd_vviia/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_xd_vviia/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_xd_vviia/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/client/discovery.py", 2025-10-13 06:12:06.599285 | controller | line 45, in __init__\n File "/tmp/ansible_kubernetes.core.k8s_payload_xd_vviia/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-13 06:12:06.599293 | 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-13 06:12:06.599305 | 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-13 06:12:06.599313 | 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-13 06:12:06.599337 | 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-1760335925.4452617-28426-253486750129826/AnsiballZ_k8s.py", line 107, in \n _ansiballz_main()\n File "/tmp/ansible-tmp-1760335925.4452617-28426-253486750129826/AnsiballZ_k8s.py", line 99, in _ansiballz_main\n invoke_module(zipped_mod, temp_path, ANSIBALLZ_PARAMS)\n File "/tmp/ansible-tmp-1760335925.4452617-28426-253486750129826/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-13 06:12:06.600022 | 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_xd_vviia/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_xd_vviia/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_xd_vviia/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_xd_vviia/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/k8s/client.py", line 352, 2025-10-13 06:12:06.600051 | controller | in get_api_client\n File "/tmp/ansible_kubernetes.core.k8s_payload_xd_vviia/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_xd_vviia/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_xd_vviia/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_xd_vviia/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_xd_vviia/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/client/discovery.py", 2025-10-13 06:12:06.600059 | 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-13 06:12:06.600073 | 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-13 06:12:06.604610 | 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-13 06:12:06.604634 | controller | 2025-10-13 06:12:07.671482 | controller | TASK [Test namespace creation idempotency] ************************************* 2025-10-13 06:12:07.671513 | controller | Monday 13 October 2025 06:12:06 +0000 (0:00:00.023) 0:00:06.479 ******** 2025-10-13 06:12:07.671525 | controller | ok: [instance] 2025-10-13 06:12:07.694648 | controller | 2025-10-13 06:12:07.694674 | controller | TASK [Assert expected behavior for namespace creation] ************************* 2025-10-13 06:12:07.694682 | controller | Monday 13 October 2025 06:12:07 +0000 (0:00:01.066) 0:00:07.545 ******** 2025-10-13 06:12:07.694692 | controller | ok: [instance] => changed=false 2025-10-13 06:12:08.882079 | controller | msg: Namespace creation task behaves consistently 2025-10-13 06:12:08.882109 | controller | 2025-10-13 06:12:08.882117 | controller | TASK [Verify OperatorGroup exists] ********************************************* 2025-10-13 06:12:08.882123 | controller | Monday 13 October 2025 06:12:07 +0000 (0:00:00.023) 0:00:07.569 ******** 2025-10-13 06:12:08.882133 | controller | ok: [instance] 2025-10-13 06:12:08.898186 | controller | 2025-10-13 06:12:08.898220 | controller | TASK [Display OperatorGroup verification result] ******************************* 2025-10-13 06:12:08.898228 | controller | Monday 13 October 2025 06:12:08 +0000 (0:00:01.187) 0:00:08.756 ******** 2025-10-13 06:12:08.898239 | controller | ok: [instance] => 2025-10-13 06:12:08.898246 | 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-13 06:12:08.898253 | 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-1760335927.7073739-28769-159767661805402/AnsiballZ_k8s.py", line 107, in \n _ansiballz_main()\n File "/tmp/ansible-tmp-1760335927.7073739-28769-159767661805402/AnsiballZ_k8s.py", line 99, in _ansiballz_main\n invoke_module(zipped_mod, temp_path, ANSIBALLZ_PARAMS)\n File "/tmp/ansible-tmp-1760335927.7073739-28769-159767661805402/AnsiballZ_k8s.py", 2025-10-13 06:12:08.898264 | 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_m3szcu1s/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_m3szcu1s/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_m3szcu1s/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/k8s/runner.py", 2025-10-13 06:12:08.898294 | controller | line 88, in run_module\n File "/tmp/ansible_kubernetes.core.k8s_payload_m3szcu1s/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_m3szcu1s/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_m3szcu1s/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_m3szcu1s/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_m3szcu1s/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/client/discovery.py", 2025-10-13 06:12:08.898301 | controller | line 45, in __init__\n File "/tmp/ansible_kubernetes.core.k8s_payload_m3szcu1s/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-13 06:12:08.898309 | 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-13 06:12:08.898318 | 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-13 06:12:08.898327 | 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-13 06:12:08.898355 | 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-1760335927.7073739-28769-159767661805402/AnsiballZ_k8s.py", line 107, in \n _ansiballz_main()\n File "/tmp/ansible-tmp-1760335927.7073739-28769-159767661805402/AnsiballZ_k8s.py", line 99, in _ansiballz_main\n invoke_module(zipped_mod, temp_path, ANSIBALLZ_PARAMS)\n File "/tmp/ansible-tmp-1760335927.7073739-28769-159767661805402/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-13 06:12:08.898961 | 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_m3szcu1s/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_m3szcu1s/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_m3szcu1s/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_m3szcu1s/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/k8s/client.py", line 352, 2025-10-13 06:12:08.899000 | controller | in get_api_client\n File "/tmp/ansible_kubernetes.core.k8s_payload_m3szcu1s/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_m3szcu1s/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_m3szcu1s/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_m3szcu1s/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_m3szcu1s/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/client/discovery.py", 2025-10-13 06:12:08.899008 | 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-13 06:12:08.899023 | 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-13 06:12:08.903494 | 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-13 06:12:08.903522 | controller | 2025-10-13 06:12:10.059940 | controller | TASK [Test OperatorGroup creation idempotency] ********************************* 2025-10-13 06:12:10.059976 | controller | Monday 13 October 2025 06:12:08 +0000 (0:00:00.021) 0:00:08.778 ******** 2025-10-13 06:12:10.059989 | controller | ok: [instance] 2025-10-13 06:12:10.060036 | controller | 2025-10-13 06:12:10.060055 | controller | TASK [Assert expected behavior for OperatorGroup creation] ********************* 2025-10-13 06:12:10.060182 | controller | Monday 13 October 2025 06:12:10 +0000 (0:00:01.156) 0:00:09.934 ******** 2025-10-13 06:12:10.083002 | controller | ok: [instance] => changed=false 2025-10-13 06:12:11.175079 | controller | msg: OperatorGroup creation task behaves consistently 2025-10-13 06:12:11.175112 | controller | 2025-10-13 06:12:11.175119 | controller | TASK [Verify SNR Subscription exists] ****************************************** 2025-10-13 06:12:11.175125 | controller | Monday 13 October 2025 06:12:10 +0000 (0:00:00.022) 0:00:09.957 ******** 2025-10-13 06:12:11.175135 | controller | ok: [instance] 2025-10-13 06:12:11.193051 | controller | 2025-10-13 06:12:11.193086 | controller | TASK [Display SNR Subscription verification result] **************************** 2025-10-13 06:12:11.193094 | controller | Monday 13 October 2025 06:12:11 +0000 (0:00:01.092) 0:00:11.049 ******** 2025-10-13 06:12:11.193105 | controller | ok: [instance] => 2025-10-13 06:12:11.193113 | 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-13 06:12:11.193120 | 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-1760335930.0951703-29117-55161843154258/AnsiballZ_k8s.py", line 107, in \n _ansiballz_main()\n File "/tmp/ansible-tmp-1760335930.0951703-29117-55161843154258/AnsiballZ_k8s.py", line 99, in _ansiballz_main\n invoke_module(zipped_mod, temp_path, ANSIBALLZ_PARAMS)\n File "/tmp/ansible-tmp-1760335930.0951703-29117-55161843154258/AnsiballZ_k8s.py", 2025-10-13 06:12:11.193126 | 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_o1gy3qtg/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_o1gy3qtg/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_o1gy3qtg/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/k8s/runner.py", 2025-10-13 06:12:11.193139 | controller | line 88, in run_module\n File "/tmp/ansible_kubernetes.core.k8s_payload_o1gy3qtg/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_o1gy3qtg/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_o1gy3qtg/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_o1gy3qtg/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_o1gy3qtg/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/client/discovery.py", 2025-10-13 06:12:11.193163 | controller | line 45, in __init__\n File "/tmp/ansible_kubernetes.core.k8s_payload_o1gy3qtg/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-13 06:12:11.193177 | 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-13 06:12:11.194034 | 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-13 06:12:11.194068 | 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-13 06:12:11.194077 | 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-1760335930.0951703-29117-55161843154258/AnsiballZ_k8s.py", line 107, in \n _ansiballz_main()\n File "/tmp/ansible-tmp-1760335930.0951703-29117-55161843154258/AnsiballZ_k8s.py", line 99, in _ansiballz_main\n invoke_module(zipped_mod, temp_path, ANSIBALLZ_PARAMS)\n File "/tmp/ansible-tmp-1760335930.0951703-29117-55161843154258/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-13 06:12:11.194089 | 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_o1gy3qtg/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_o1gy3qtg/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_o1gy3qtg/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_o1gy3qtg/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/k8s/client.py", line 352, 2025-10-13 06:12:11.194098 | controller | in get_api_client\n File "/tmp/ansible_kubernetes.core.k8s_payload_o1gy3qtg/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_o1gy3qtg/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_o1gy3qtg/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_o1gy3qtg/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_o1gy3qtg/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/client/discovery.py", 2025-10-13 06:12:11.199704 | 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-13 06:12:11.199732 | 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-13 06:12:11.199742 | 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-13 06:12:11.199753 | controller | 2025-10-13 06:12:12.258674 | controller | TASK [Test SNR Subscription creation idempotency] ****************************** 2025-10-13 06:12:12.258704 | controller | Monday 13 October 2025 06:12:11 +0000 (0:00:00.025) 0:00:11.074 ******** 2025-10-13 06:12:12.258715 | controller | ok: [instance] 2025-10-13 06:12:12.282851 | controller | 2025-10-13 06:12:12.282876 | controller | TASK [Assert expected behavior for SNR Subscription creation] ****************** 2025-10-13 06:12:12.282883 | controller | Monday 13 October 2025 06:12:12 +0000 (0:00:01.058) 0:00:12.132 ******** 2025-10-13 06:12:12.282908 | controller | ok: [instance] => changed=false 2025-10-13 06:12:13.523972 | controller | msg: SNR Subscription creation task behaves consistently 2025-10-13 06:12:13.524006 | controller | 2025-10-13 06:12:13.524013 | controller | TASK [Verify SNR deployment status] ******************************************** 2025-10-13 06:12:13.524019 | controller | Monday 13 October 2025 06:12:12 +0000 (0:00:00.024) 0:00:12.157 ******** 2025-10-13 06:12:13.524032 | controller | ok: [instance] 2025-10-13 06:12:13.524065 | controller | 2025-10-13 06:12:13.524071 | controller | TASK [Display SNR deployment verification result] ****************************** 2025-10-13 06:12:13.524078 | controller | Monday 13 October 2025 06:12:13 +0000 (0:00:01.241) 0:00:13.398 ******** 2025-10-13 06:12:13.540003 | controller | ok: [instance] => 2025-10-13 06:12:13.540059 | 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-13 06:12:13.540202 | 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-1760335932.2946584-29459-209763363463722/AnsiballZ_k8s_info.py", line 107, in \n _ansiballz_main()\n File "/tmp/ansible-tmp-1760335932.2946584-29459-209763363463722/AnsiballZ_k8s_info.py", line 99, in _ansiballz_main\n invoke_module(zipped_mod, temp_path, ANSIBALLZ_PARAMS)\n File "/tmp/ansible-tmp-1760335932.2946584-29459-209763363463722/AnsiballZ_k8s_info.py", 2025-10-13 06:12:13.541735 | 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_2uwkp7j6/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_2uwkp7j6/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_2uwkp7j6/ansible_kubernetes.core.k8s_info_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/k8s/client.py", 2025-10-13 06:12:13.541763 | controller | line 352, in get_api_client\n File "/tmp/ansible_kubernetes.core.k8s_info_payload_2uwkp7j6/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_2uwkp7j6/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_2uwkp7j6/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_2uwkp7j6/ansible_kubernetes.core.k8s_info_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/client/discovery.py", line 2025-10-13 06:12:13.541771 | controller | 45, in __init__\n File "/tmp/ansible_kubernetes.core.k8s_info_payload_2uwkp7j6/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-13 06:12:13.541780 | 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-13 06:12:13.541791 | 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-13 06:12:13.541799 | 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-13 06:12:13.541805 | 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-1760335932.2946584-29459-209763363463722/AnsiballZ_k8s_info.py", line 107, in \n _ansiballz_main()\n File "/tmp/ansible-tmp-1760335932.2946584-29459-209763363463722/AnsiballZ_k8s_info.py", line 99, in _ansiballz_main\n invoke_module(zipped_mod, temp_path, ANSIBALLZ_PARAMS)\n File "/tmp/ansible-tmp-1760335932.2946584-29459-209763363463722/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-13 06:12:13.541815 | 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_2uwkp7j6/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_2uwkp7j6/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_2uwkp7j6/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_2uwkp7j6/ansible_kubernetes.core.k8s_info_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/k8s/client.py", 2025-10-13 06:12:13.545966 | controller | line 246, in wrapper\n File "/tmp/ansible_kubernetes.core.k8s_info_payload_2uwkp7j6/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_2uwkp7j6/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_2uwkp7j6/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_2uwkp7j6/ansible_kubernetes.core.k8s_info_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/client/discovery.py", line 2025-10-13 06:12:13.545988 | 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-13 06:12:13.545996 | 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-13 06:12:13.546006 | 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-13 06:12:13.546015 | controller | 2025-10-13 06:12:13.546039 | controller | TASK [Test deployment verification behavior] *********************************** 2025-10-13 06:12:13.546150 | controller | Monday 13 October 2025 06:12:13 +0000 (0:00:00.022) 0:00:13.420 ******** 2025-10-13 06:12:13.566603 | controller | ok: [instance] => 2025-10-13 06:12:13.588498 | controller | msg: Testing deployment verification logic - expected to fail in mock environment 2025-10-13 06:12:13.588532 | controller | 2025-10-13 06:12:13.588540 | controller | TASK [Assert SNR deployment verification behaves as expected] ****************** 2025-10-13 06:12:13.588548 | controller | Monday 13 October 2025 06:12:13 +0000 (0:00:00.020) 0:00:13.440 ******** 2025-10-13 06:12:13.588560 | controller | ok: [instance] => changed=false 2025-10-13 06:12:13.588738 | controller | msg: SNR deployment verification logic behaves as expected 2025-10-13 06:12:13.588883 | controller | 2025-10-13 06:12:13.589069 | controller | TASK [Verify NHC Subscription exists] ****************************************** 2025-10-13 06:12:13.589221 | controller | Monday 13 October 2025 06:12:13 +0000 (0:00:00.022) 0:00:13.463 ******** 2025-10-13 06:12:14.616318 | controller | ok: [instance] 2025-10-13 06:12:14.634168 | controller | 2025-10-13 06:12:14.634200 | controller | TASK [Display NHC Subscription verification result] **************************** 2025-10-13 06:12:14.634208 | controller | Monday 13 October 2025 06:12:14 +0000 (0:00:01.027) 0:00:14.490 ******** 2025-10-13 06:12:14.634219 | controller | ok: [instance] => 2025-10-13 06:12:14.634226 | 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-13 06:12:14.634233 | 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-1760335933.6015704-29639-211912761496934/AnsiballZ_k8s.py", line 107, in \n _ansiballz_main()\n File "/tmp/ansible-tmp-1760335933.6015704-29639-211912761496934/AnsiballZ_k8s.py", line 99, in _ansiballz_main\n invoke_module(zipped_mod, temp_path, ANSIBALLZ_PARAMS)\n File "/tmp/ansible-tmp-1760335933.6015704-29639-211912761496934/AnsiballZ_k8s.py", 2025-10-13 06:12:14.634239 | 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_34gaegfi/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_34gaegfi/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_34gaegfi/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/k8s/runner.py", 2025-10-13 06:12:14.634249 | controller | line 88, in run_module\n File "/tmp/ansible_kubernetes.core.k8s_payload_34gaegfi/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_34gaegfi/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_34gaegfi/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_34gaegfi/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_34gaegfi/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/client/discovery.py", 2025-10-13 06:12:14.634278 | controller | line 45, in __init__\n File "/tmp/ansible_kubernetes.core.k8s_payload_34gaegfi/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-13 06:12:14.634305 | 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-13 06:12:14.634314 | 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-13 06:12:14.634949 | 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-13 06:12:14.634965 | 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-1760335933.6015704-29639-211912761496934/AnsiballZ_k8s.py", line 107, in \n _ansiballz_main()\n File "/tmp/ansible-tmp-1760335933.6015704-29639-211912761496934/AnsiballZ_k8s.py", line 99, in _ansiballz_main\n invoke_module(zipped_mod, temp_path, ANSIBALLZ_PARAMS)\n File "/tmp/ansible-tmp-1760335933.6015704-29639-211912761496934/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-13 06:12:14.634975 | 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_34gaegfi/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_34gaegfi/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_34gaegfi/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_34gaegfi/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/k8s/client.py", line 352, 2025-10-13 06:12:14.634984 | controller | in get_api_client\n File "/tmp/ansible_kubernetes.core.k8s_payload_34gaegfi/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_34gaegfi/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_34gaegfi/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_34gaegfi/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_34gaegfi/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/client/discovery.py", 2025-10-13 06:12:14.635226 | 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-13 06:12:14.635254 | 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-13 06:12:14.639477 | 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-13 06:12:14.639498 | controller | 2025-10-13 06:12:15.838966 | controller | TASK [Test NHC Subscription creation idempotency] ****************************** 2025-10-13 06:12:15.838996 | controller | Monday 13 October 2025 06:12:14 +0000 (0:00:00.023) 0:00:14.514 ******** 2025-10-13 06:12:15.839008 | controller | ok: [instance] 2025-10-13 06:12:15.839068 | controller | 2025-10-13 06:12:15.839084 | controller | TASK [Assert expected behavior for NHC Subscription creation] ****************** 2025-10-13 06:12:15.839095 | controller | Monday 13 October 2025 06:12:15 +0000 (0:00:01.199) 0:00:15.713 ******** 2025-10-13 06:12:15.859949 | controller | ok: [instance] => changed=false 2025-10-13 06:12:17.015887 | controller | msg: NHC Subscription creation task behaves consistently 2025-10-13 06:12:17.015932 | controller | 2025-10-13 06:12:17.015939 | controller | TASK [Verify CSV status] ******************************************************* 2025-10-13 06:12:17.015945 | controller | Monday 13 October 2025 06:12:15 +0000 (0:00:00.020) 0:00:15.734 ******** 2025-10-13 06:12:17.015956 | controller | ok: [instance] 2025-10-13 06:12:17.016991 | controller | 2025-10-13 06:12:17.032488 | controller | TASK [Display CSV verification result] ***************************************** 2025-10-13 06:12:17.032519 | controller | Monday 13 October 2025 06:12:17 +0000 (0:00:01.156) 0:00:16.890 ******** 2025-10-13 06:12:17.032530 | controller | ok: [instance] => 2025-10-13 06:12:17.034438 | 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-13 06:12:17.034470 | 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-1760335935.8709812-29978-75255369286015/AnsiballZ_k8s_info.py", line 107, in \n _ansiballz_main()\n File "/tmp/ansible-tmp-1760335935.8709812-29978-75255369286015/AnsiballZ_k8s_info.py", line 99, in _ansiballz_main\n invoke_module(zipped_mod, temp_path, ANSIBALLZ_PARAMS)\n File "/tmp/ansible-tmp-1760335935.8709812-29978-75255369286015/AnsiballZ_k8s_info.py", 2025-10-13 06:12:17.034478 | 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_erm1zfqt/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_erm1zfqt/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_erm1zfqt/ansible_kubernetes.core.k8s_info_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/k8s/client.py", 2025-10-13 06:12:17.034493 | controller | line 352, in get_api_client\n File "/tmp/ansible_kubernetes.core.k8s_info_payload_erm1zfqt/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_erm1zfqt/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_erm1zfqt/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_erm1zfqt/ansible_kubernetes.core.k8s_info_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/client/discovery.py", line 2025-10-13 06:12:17.034502 | controller | 45, in __init__\n File "/tmp/ansible_kubernetes.core.k8s_info_payload_erm1zfqt/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-13 06:12:17.034507 | 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-13 06:12:17.034513 | 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-13 06:12:17.034527 | 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-13 06:12:17.034534 | 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-1760335935.8709812-29978-75255369286015/AnsiballZ_k8s_info.py", line 107, in \n _ansiballz_main()\n File "/tmp/ansible-tmp-1760335935.8709812-29978-75255369286015/AnsiballZ_k8s_info.py", line 99, in _ansiballz_main\n invoke_module(zipped_mod, temp_path, ANSIBALLZ_PARAMS)\n File "/tmp/ansible-tmp-1760335935.8709812-29978-75255369286015/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-13 06:12:17.034540 | 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_erm1zfqt/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_erm1zfqt/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_erm1zfqt/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_erm1zfqt/ansible_kubernetes.core.k8s_info_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/k8s/client.py", 2025-10-13 06:12:17.034548 | controller | line 246, in wrapper\n File "/tmp/ansible_kubernetes.core.k8s_info_payload_erm1zfqt/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_erm1zfqt/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_erm1zfqt/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_erm1zfqt/ansible_kubernetes.core.k8s_info_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/client/discovery.py", line 2025-10-13 06:12:17.034555 | 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-13 06:12:17.039773 | 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-13 06:12:17.039799 | 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-13 06:12:17.039811 | controller | 2025-10-13 06:12:17.059146 | controller | TASK [Test CSV verification behavior] ****************************************** 2025-10-13 06:12:17.059167 | controller | Monday 13 October 2025 06:12:17 +0000 (0:00:00.023) 0:00:16.914 ******** 2025-10-13 06:12:17.059179 | controller | ok: [instance] => 2025-10-13 06:12:17.080214 | controller | msg: Testing CSV verification logic - expected to fail in mock environment 2025-10-13 06:12:17.080237 | controller | 2025-10-13 06:12:17.080246 | controller | TASK [Assert CSV verification behaves as expected] ***************************** 2025-10-13 06:12:17.080253 | controller | Monday 13 October 2025 06:12:17 +0000 (0:00:00.019) 0:00:16.933 ******** 2025-10-13 06:12:17.080263 | controller | ok: [instance] => changed=false 2025-10-13 06:12:17.080436 | controller | msg: CSV verification logic behaves as expected 2025-10-13 06:12:17.080571 | controller | 2025-10-13 06:12:17.080712 | controller | TASK [Verify NHC CR exists] **************************************************** 2025-10-13 06:12:17.080852 | controller | Monday 13 October 2025 06:12:17 +0000 (0:00:00.021) 0:00:16.955 ******** 2025-10-13 06:12:18.146649 | controller | ok: [instance] 2025-10-13 06:12:18.161973 | controller | 2025-10-13 06:12:18.162006 | controller | TASK [Display NHC CR verification result] ************************************** 2025-10-13 06:12:18.162014 | controller | Monday 13 October 2025 06:12:18 +0000 (0:00:01.065) 0:00:18.020 ******** 2025-10-13 06:12:18.162025 | controller | ok: [instance] => 2025-10-13 06:12:18.162053 | 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-13 06:12:18.162087 | 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-1760335937.09297-30153-160242413164284/AnsiballZ_k8s.py", line 107, in \n _ansiballz_main()\n File "/tmp/ansible-tmp-1760335937.09297-30153-160242413164284/AnsiballZ_k8s.py", line 99, in _ansiballz_main\n invoke_module(zipped_mod, temp_path, ANSIBALLZ_PARAMS)\n File "/tmp/ansible-tmp-1760335937.09297-30153-160242413164284/AnsiballZ_k8s.py", 2025-10-13 06:12:18.162503 | 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_pr3v9kgd/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_pr3v9kgd/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_pr3v9kgd/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/k8s/runner.py", 2025-10-13 06:12:18.162962 | controller | line 88, in run_module\n File "/tmp/ansible_kubernetes.core.k8s_payload_pr3v9kgd/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_pr3v9kgd/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_pr3v9kgd/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_pr3v9kgd/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_pr3v9kgd/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/client/discovery.py", 2025-10-13 06:12:18.162981 | controller | line 45, in __init__\n File "/tmp/ansible_kubernetes.core.k8s_payload_pr3v9kgd/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-13 06:12:18.162991 | 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-13 06:12:18.163601 | 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-13 06:12:18.163622 | 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-13 06:12:18.163632 | 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-1760335937.09297-30153-160242413164284/AnsiballZ_k8s.py", line 107, in \n _ansiballz_main()\n File "/tmp/ansible-tmp-1760335937.09297-30153-160242413164284/AnsiballZ_k8s.py", line 99, in _ansiballz_main\n invoke_module(zipped_mod, temp_path, ANSIBALLZ_PARAMS)\n File "/tmp/ansible-tmp-1760335937.09297-30153-160242413164284/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-13 06:12:18.163642 | 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_pr3v9kgd/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_pr3v9kgd/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_pr3v9kgd/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_pr3v9kgd/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/k8s/client.py", line 352, 2025-10-13 06:12:18.163653 | controller | in get_api_client\n File "/tmp/ansible_kubernetes.core.k8s_payload_pr3v9kgd/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_pr3v9kgd/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_pr3v9kgd/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_pr3v9kgd/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_pr3v9kgd/ansible_kubernetes.core.k8s_payload.zip/ansible_collections/kubernetes/core/plugins/module_utils/client/discovery.py", 2025-10-13 06:12:18.163659 | 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-13 06:12:18.163670 | 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-13 06:12:18.167823 | 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-13 06:12:18.167848 | controller | 2025-10-13 06:12:19.304798 | controller | TASK [Test NHC CR creation idempotency] **************************************** 2025-10-13 06:12:19.304828 | controller | Monday 13 October 2025 06:12:18 +0000 (0:00:00.021) 0:00:18.042 ******** 2025-10-13 06:12:19.304840 | controller | ok: [instance] 2025-10-13 06:12:19.327386 | controller | 2025-10-13 06:12:19.327414 | controller | TASK [Assert expected behavior for NHC CR creation] **************************** 2025-10-13 06:12:19.327424 | controller | Monday 13 October 2025 06:12:19 +0000 (0:00:01.135) 0:00:19.178 ******** 2025-10-13 06:12:19.327435 | controller | ok: [instance] => changed=false 2025-10-13 06:12:19.359000 | controller | msg: NHC CR creation task behaves consistently 2025-10-13 06:12:19.359038 | controller | 2025-10-13 06:12:19.359048 | controller | TASK [Verify role structure and logic] ***************************************** 2025-10-13 06:12:19.359056 | controller | Monday 13 October 2025 06:12:19 +0000 (0:00:00.023) 0:00:19.201 ******** 2025-10-13 06:12:19.359067 | controller | ok: [instance] => 2025-10-13 06:12:19.359103 | controller | msg: Role execution and verification completed - errors are expected in test environment without real K8s cluster 2025-10-13 06:12:19.361609 | controller | 2025-10-13 06:12:19.361638 | controller | PLAY RECAP ********************************************************************* 2025-10-13 06:12:19.424063 | controller | instance : ok=41 changed=0 unreachable=0 failed=0 skipped=20 rescued=1 ignored=0 2025-10-13 06:12:19.424086 | controller | 2025-10-13 06:12:19.424093 | controller | Monday 13 October 2025 06:12:19 +0000 (0:00:00.031) 0:00:19.233 ******** 2025-10-13 06:12:19.424099 | controller | =============================================================================== 2025-10-13 06:12:19.424105 | controller | Test kubernetes.core.k8s module availability ---------------------------- 1.32s 2025-10-13 06:12:19.424110 | controller | Verify SNR deployment status -------------------------------------------- 1.24s 2025-10-13 06:12:19.424115 | controller | Test that Python kubernetes library is available ------------------------ 1.20s 2025-10-13 06:12:19.424121 | controller | Test NHC Subscription creation idempotency ------------------------------ 1.20s 2025-10-13 06:12:19.424129 | controller | Verify OperatorGroup exists --------------------------------------------- 1.19s 2025-10-13 06:12:19.424135 | controller | cifmw_snr_nhc : Create the workload-availability namespace -------------- 1.18s 2025-10-13 06:12:19.424140 | controller | Test OperatorGroup creation idempotency --------------------------------- 1.16s 2025-10-13 06:12:19.424145 | controller | Verify CSV status ------------------------------------------------------- 1.16s 2025-10-13 06:12:19.424150 | controller | Verify namespace exists ------------------------------------------------- 1.15s 2025-10-13 06:12:19.424155 | controller | Test NHC CR creation idempotency ---------------------------------------- 1.14s 2025-10-13 06:12:19.424160 | controller | Verify SNR Subscription exists ------------------------------------------ 1.09s 2025-10-13 06:12:19.424165 | controller | Test namespace creation idempotency ------------------------------------- 1.07s 2025-10-13 06:12:19.424171 | controller | Verify NHC CR exists ---------------------------------------------------- 1.07s 2025-10-13 06:12:19.424180 | controller | Test SNR Subscription creation idempotency ------------------------------ 1.06s 2025-10-13 06:12:19.424185 | controller | Verify NHC Subscription exists ------------------------------------------ 1.03s 2025-10-13 06:12:19.424191 | controller | Test that mock kubeconfig exists ---------------------------------------- 0.76s 2025-10-13 06:12:19.424196 | controller | Include the cifmw_snr_nhc role ------------------------------------------ 0.06s 2025-10-13 06:12:19.424201 | controller | Analyze specific error patterns ----------------------------------------- 0.04s 2025-10-13 06:12:19.424206 | controller | Verify role structure and logic ----------------------------------------- 0.03s 2025-10-13 06:12:19.424211 | controller | cifmw_snr_nhc : Delete existing Subscriptions --------------------------- 0.03s 2025-10-13 06:12:19.424220 | controller | INFO Running default > verify 2025-10-13 06:12:19.424593 | controller | INFO Running Ansible Verifier 2025-10-13 06:12:19.842548 | controller | 2025-10-13 06:12:19.842748 | controller | PLAY [Verify] ****************************************************************** 2025-10-13 06:12:19.842890 | controller | 2025-10-13 06:12:19.843072 | controller | TASK [Check if Python kubernetes library is installed] ************************* 2025-10-13 06:12:19.843219 | controller | Monday 13 October 2025 06:12:19 +0000 (0:00:00.021) 0:00:00.021 ******** 2025-10-13 06:12:21.058639 | controller | ok: [instance] 2025-10-13 06:12:21.080890 | controller | 2025-10-13 06:12:21.080936 | controller | TASK [Display kubernetes library version] ************************************** 2025-10-13 06:12:21.080944 | controller | Monday 13 October 2025 06:12:21 +0000 (0:00:01.216) 0:00:01.237 ******** 2025-10-13 06:12:21.080955 | controller | ok: [instance] => 2025-10-13 06:12:21.847192 | controller | msg: 'kubernetes library version: 34.1.0' 2025-10-13 06:12:21.847225 | controller | 2025-10-13 06:12:21.847233 | controller | TASK [Verify kubeconfig mock file exists] ************************************** 2025-10-13 06:12:21.847239 | controller | Monday 13 October 2025 06:12:21 +0000 (0:00:00.022) 0:00:01.259 ******** 2025-10-13 06:12:21.847249 | controller | ok: [instance] 2025-10-13 06:12:21.847267 | controller | 2025-10-13 06:12:21.847273 | controller | TASK [Assert kubeconfig mock file exists] ************************************** 2025-10-13 06:12:21.847280 | controller | Monday 13 October 2025 06:12:21 +0000 (0:00:00.766) 0:00:02.025 ******** 2025-10-13 06:12:21.868075 | controller | ok: [instance] => changed=false 2025-10-13 06:12:21.868119 | controller | msg: Mock kubeconfig file exists 2025-10-13 06:12:21.868188 | controller | 2025-10-13 06:12:21.868292 | controller | TASK [Verify kubeadmin password mock file exists] ****************************** 2025-10-13 06:12:21.868414 | controller | Monday 13 October 2025 06:12:21 +0000 (0:00:00.021) 0:00:02.047 ******** 2025-10-13 06:12:22.626744 | controller | ok: [instance] 2025-10-13 06:12:22.626797 | controller | 2025-10-13 06:12:22.626923 | controller | TASK [Assert kubeadmin password mock file exists] ****************************** 2025-10-13 06:12:22.627039 | controller | Monday 13 October 2025 06:12:22 +0000 (0:00:00.758) 0:00:02.805 ******** 2025-10-13 06:12:22.648956 | controller | ok: [instance] => changed=false 2025-10-13 06:12:22.649086 | controller | msg: Mock kubeadmin password file exists 2025-10-13 06:12:22.649204 | controller | 2025-10-13 06:12:22.649351 | controller | TASK [Test Python yaml library] ************************************************ 2025-10-13 06:12:22.649482 | controller | Monday 13 October 2025 06:12:22 +0000 (0:00:00.022) 0:00:02.828 ******** 2025-10-13 06:12:23.478875 | controller | ok: [instance] 2025-10-13 06:12:23.478968 | controller | 2025-10-13 06:12:23.479077 | controller | TASK [Display yaml test result] ************************************************ 2025-10-13 06:12:23.479178 | controller | Monday 13 October 2025 06:12:23 +0000 (0:00:00.829) 0:00:03.657 ******** 2025-10-13 06:12:23.512119 | controller | ok: [instance] => 2025-10-13 06:12:23.512212 | controller | msg: yaml library works 2025-10-13 06:12:23.512324 | controller | 2025-10-13 06:12:23.512430 | controller | PLAY RECAP ********************************************************************* 2025-10-13 06:12:23.512562 | controller | instance : ok=8 changed=0 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2025-10-13 06:12:23.512655 | controller | 2025-10-13 06:12:23.512754 | controller | Monday 13 October 2025 06:12:23 +0000 (0:00:00.033) 0:00:03.691 ******** 2025-10-13 06:12:23.512851 | controller | =============================================================================== 2025-10-13 06:12:23.512980 | controller | Check if Python kubernetes library is installed ------------------------- 1.22s 2025-10-13 06:12:23.513080 | controller | Test Python yaml library ------------------------------------------------ 0.83s 2025-10-13 06:12:23.513177 | controller | Verify kubeconfig mock file exists -------------------------------------- 0.77s 2025-10-13 06:12:23.513275 | controller | Verify kubeadmin password mock file exists ------------------------------ 0.76s 2025-10-13 06:12:23.513387 | controller | Display yaml test result ------------------------------------------------ 0.03s 2025-10-13 06:12:23.513493 | controller | Assert kubeadmin password mock file exists ------------------------------ 0.02s 2025-10-13 06:12:23.513589 | controller | Display kubernetes library version -------------------------------------- 0.02s 2025-10-13 06:12:23.513686 | controller | Assert kubeconfig mock file exists -------------------------------------- 0.02s 2025-10-13 06:12:23.557614 | controller | INFO Verifier completed successfully. 2025-10-13 06:12:23.567755 | controller | INFO Running default > destroy 2025-10-13 06:12:23.984226 | controller | 2025-10-13 06:12:23.984283 | controller | PLAY [Destroy] ***************************************************************** 2025-10-13 06:12:23.984447 | controller | 2025-10-13 06:12:23.984568 | controller | TASK [Get passwd entries for USER env] ***************************************** 2025-10-13 06:12:23.984687 | controller | Monday 13 October 2025 06:12:23 +0000 (0:00:00.014) 0:00:00.014 ******** 2025-10-13 06:12:24.270020 | controller | ok: [localhost] 2025-10-13 06:12:24.311824 | controller | 2025-10-13 06:12:24.311843 | controller | TASK [Get shell async_dir] ***************************************************** 2025-10-13 06:12:24.311850 | controller | Monday 13 October 2025 06:12:24 +0000 (0:00:00.285) 0:00:00.300 ******** 2025-10-13 06:12:24.311859 | controller | ok: [localhost] 2025-10-13 06:12:24.347488 | controller | 2025-10-13 06:12:24.347507 | controller | TASK [Set async_dir for HOME env] ********************************************** 2025-10-13 06:12:24.347514 | controller | Monday 13 October 2025 06:12:24 +0000 (0:00:00.042) 0:00:00.342 ******** 2025-10-13 06:12:24.347522 | controller | skipping: [localhost] 2025-10-13 06:12:24.724792 | controller | 2025-10-13 06:12:24.724825 | controller | TASK [Destroy molecule instance(s)] ******************************************** 2025-10-13 06:12:24.724833 | controller | Monday 13 October 2025 06:12:24 +0000 (0:00:00.035) 0:00:00.377 ******** 2025-10-13 06:12:24.724846 | 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-13 06:12:35.333736 | controller | 2025-10-13 06:12:35.333768 | controller | TASK [Wait for instance(s) deletion to complete] ******************************* 2025-10-13 06:12:35.333776 | controller | Monday 13 October 2025 06:12:24 +0000 (0:00:00.375) 0:00:00.753 ******** 2025-10-13 06:12:35.333787 | controller | FAILED - RETRYING: [localhost]: Wait for instance(s) deletion to complete (300 retries left). 2025-10-13 06:12:35.383552 | controller | FAILED - RETRYING: [localhost]: Wait for instance(s) deletion to complete (299 retries left). 2025-10-13 06:12:35.383584 | controller | changed: [localhost] => (item={'failed': 0, 'started': 1, 'finished': 0, 'ansible_job_id': 'j391572404979.31231', 'results_file': '/home/zuul/.ansible_async/j391572404979.31231', '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-13 06:12:35.383593 | controller | 2025-10-13 06:12:35.383599 | controller | TASK [Delete podman network dedicated to this scenario] ************************ 2025-10-13 06:12:35.383605 | controller | Monday 13 October 2025 06:12:35 +0000 (0:00:10.609) 0:00:11.363 ******** 2025-10-13 06:12:35.383614 | controller | skipping: [localhost] => (item=instance: None specified) 2025-10-13 06:12:35.428302 | controller | skipping: [localhost] 2025-10-13 06:12:35.428330 | controller | 2025-10-13 06:12:35.428338 | controller | PLAY RECAP ********************************************************************* 2025-10-13 06:12:35.428356 | controller | localhost : ok=4 changed=2 unreachable=0 failed=0 skipped=2 rescued=0 ignored=0 2025-10-13 06:12:35.428362 | controller | 2025-10-13 06:12:35.428367 | controller | Monday 13 October 2025 06:12:35 +0000 (0:00:00.049) 0:00:11.413 ******** 2025-10-13 06:12:35.428373 | controller | =============================================================================== 2025-10-13 06:12:35.428378 | controller | Wait for instance(s) deletion to complete ------------------------------ 10.61s 2025-10-13 06:12:35.428383 | controller | Destroy molecule instance(s) -------------------------------------------- 0.38s 2025-10-13 06:12:35.428388 | controller | Get passwd entries for USER env ----------------------------------------- 0.29s 2025-10-13 06:12:35.428394 | controller | Delete podman network dedicated to this scenario ------------------------ 0.05s 2025-10-13 06:12:35.428399 | controller | Get shell async_dir ----------------------------------------------------- 0.04s 2025-10-13 06:12:35.428404 | controller | Set async_dir for HOME env ---------------------------------------------- 0.04s 2025-10-13 06:12:35.428414 | controller | INFO Pruning extra files from scenario ephemeral directory 2025-10-13 06:12:35.430238 | controller | INFO Writing /tmp/report.html report. 2025-10-13 06:12:35.693821 | controller | changed 2025-10-13 06:12:35.726842 | 2025-10-13 06:12:35.726929 | PLAY RECAP 2025-10-13 06:12:35.726988 | controller | ok: 3 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2025-10-13 06:12:35.727012 | 2025-10-13 06:12:35.832359 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main] 2025-10-13 06:12:35.833275 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main] 2025-10-13 06:12:36.397939 | 2025-10-13 06:12:36.398049 | PLAY [Run ci/playbooks/collect-logs.yml] 2025-10-13 06:12:36.422784 | 2025-10-13 06:12:36.422865 | TASK [Filter out host if needed] 2025-10-13 06:12:36.431277 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller 2025-10-13 06:12:36.435647 | 2025-10-13 06:12:36.435713 | TASK [Ensure file is present] 2025-10-13 06:12:36.757993 | controller | ok 2025-10-13 06:12:36.764592 | 2025-10-13 06:12:36.764660 | TASK [Manage molecule report file] 2025-10-13 06:12:37.324004 | controller | changed 2025-10-13 06:12:37.330308 | 2025-10-13 06:12:37.330392 | TASK [Check if we get ci-framework-data basedir] 2025-10-13 06:12:37.541965 | controller | ok 2025-10-13 06:12:37.548555 | 2025-10-13 06:12:37.548624 | TASK [Create ci-framework-data log directory for zuul] 2025-10-13 06:12:37.891553 | controller | changed 2025-10-13 06:12:37.904314 | 2025-10-13 06:12:37.904389 | TASK [Copy ci-framework interesting files] 2025-10-13 06:12:38.109500 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory 2025-10-13 06:12:38.430164 | controller | changed 2025-10-13 06:12:38.435335 | 2025-10-13 06:12:38.435398 | TASK [Get SELinux listing] 2025-10-13 06:12:38.979040 | controller | changed 2025-10-13 06:12:38.986073 | 2025-10-13 06:12:38.986167 | TASK [Generate log index] 2025-10-13 06:12:39.697070 | controller | changed 2025-10-13 06:12:39.702329 | 2025-10-13 06:12:39.702405 | TASK [Get some env related data] 2025-10-13 06:12:40.229235 | controller | /home/zuul/.local/bin/ansible 2025-10-13 06:12:41.232666 | controller | changed 2025-10-13 06:12:41.237808 | 2025-10-13 06:12:41.237876 | TASK [Generate list of logs to collect in home directory] 2025-10-13 06:12:41.553000 | controller | ok: All paths examined 2025-10-13 06:12:41.558370 | 2025-10-13 06:12:41.558433 | LOOP [Copy logs from home directory] 2025-10-13 06:12:41.893471 | controller | changed: 2025-10-13 06:12:41.893588 | controller | { 2025-10-13 06:12:41.893617 | controller | "atime": 1760335849.5070558, 2025-10-13 06:12:41.893638 | controller | "ctime": 1760335868.1272871, 2025-10-13 06:12:41.893658 | controller | "dev": 64513, 2025-10-13 06:12:41.893676 | controller | "gid": 1000, 2025-10-13 06:12:41.893693 | controller | "gr_name": "zuul", 2025-10-13 06:12:41.893711 | controller | "inode": 4349749, 2025-10-13 06:12:41.893728 | controller | "isblk": false, 2025-10-13 06:12:41.893745 | controller | "ischr": false, 2025-10-13 06:12:41.893762 | controller | "isdir": false, 2025-10-13 06:12:41.893779 | controller | "isfifo": false, 2025-10-13 06:12:41.893795 | controller | "isgid": false, 2025-10-13 06:12:41.893811 | controller | "islnk": false, 2025-10-13 06:12:41.893827 | controller | "isreg": true, 2025-10-13 06:12:41.893842 | controller | "issock": false, 2025-10-13 06:12:41.893857 | controller | "isuid": false, 2025-10-13 06:12:41.893872 | controller | "mode": "0644", 2025-10-13 06:12:41.893887 | controller | "mtime": 1760335868.1272871, 2025-10-13 06:12:41.893902 | controller | "nlink": 1, 2025-10-13 06:12:41.893953 | controller | "path": "/home/zuul/ansible.log", 2025-10-13 06:12:41.893976 | controller | "pw_name": "zuul", 2025-10-13 06:12:41.893994 | controller | "rgrp": true, 2025-10-13 06:12:41.894011 | controller | "roth": true, 2025-10-13 06:12:41.894027 | controller | "rusr": true, 2025-10-13 06:12:41.894043 | controller | "size": 6749, 2025-10-13 06:12:41.894060 | controller | "uid": 1000, 2025-10-13 06:12:41.894077 | controller | "wgrp": false, 2025-10-13 06:12:41.894093 | controller | "woth": false, 2025-10-13 06:12:41.894111 | controller | "wusr": true, 2025-10-13 06:12:41.894127 | controller | "xgrp": false, 2025-10-13 06:12:41.894143 | controller | "xoth": false, 2025-10-13 06:12:41.894158 | controller | "xusr": false 2025-10-13 06:12:41.894173 | controller | } 2025-10-13 06:12:41.908075 | 2025-10-13 06:12:41.908177 | TASK [Copy crio stats log file] 2025-10-13 06:12:41.931761 | controller | skipping: Conditional result was False 2025-10-13 06:12:41.937768 | 2025-10-13 06:12:41.937841 | TASK [Get SELinux related data] 2025-10-13 06:12:42.155106 | controller | 2025-10-13 06:12:42.467239 | controller | ERROR 2025-10-13 06:12:42.467420 | controller | { 2025-10-13 06:12:42.467453 | controller | "delta": "0:00:00.008699", 2025-10-13 06:12:42.467474 | controller | "end": "2025-10-13 06:12:42.155910", 2025-10-13 06:12:42.467493 | controller | "msg": "non-zero return code", 2025-10-13 06:12:42.467512 | controller | "rc": 1, 2025-10-13 06:12:42.467530 | controller | "start": "2025-10-13 06:12:42.147211" 2025-10-13 06:12:42.467546 | controller | } 2025-10-13 06:12:42.467568 | controller | ERROR: Ignoring Errors 2025-10-13 06:12:42.472982 | 2025-10-13 06:12:42.473047 | TASK [Create system configuration directory] 2025-10-13 06:12:42.684300 | controller | changed 2025-10-13 06:12:42.689306 | 2025-10-13 06:12:42.689369 | TASK [Get some of the system configurations] 2025-10-13 06:12:43.219207 | controller | changed 2025-10-13 06:12:43.225229 | 2025-10-13 06:12:43.225300 | TASK [Copy generated documentation if available] 2025-10-13 06:12:43.249737 | controller | skipping: Conditional result was False 2025-10-13 06:12:43.265005 | 2025-10-13 06:12:43.265079 | TASK [Copy generated AsciiDoc documentation if available] 2025-10-13 06:12:43.280848 | controller | skipping: Conditional result was False 2025-10-13 06:12:43.287480 | 2025-10-13 06:12:43.287552 | TASK [Compress logs bigger than 2MB] 2025-10-13 06:12:43.821149 | controller | changed 2025-10-13 06:12:43.828444 | 2025-10-13 06:12:43.828520 | TASK [Copy files from workspace on node] 2025-10-13 06:12:43.853969 | controller | ok 2025-10-13 06:12:43.891103 | 2025-10-13 06:12:43.891205 | TASK [fetch-output : Set log path for multiple nodes] 2025-10-13 06:12:43.926337 | controller | skipping: Conditional result was False 2025-10-13 06:12:43.932230 | 2025-10-13 06:12:43.932308 | TASK [fetch-output : Set log path for single node] 2025-10-13 06:12:43.961416 | controller | ok 2025-10-13 06:12:43.966982 | 2025-10-13 06:12:43.967048 | LOOP [fetch-output : Ensure local output dirs] 2025-10-13 06:12:44.403454 | controller -> localhost | ok: "/var/lib/zuul/builds/c0345959015b47d798e63a5b7997f2e1/work/logs" 2025-10-13 06:12:44.403678 | controller -> localhost | changed: All items complete 2025-10-13 06:12:44.403709 | 2025-10-13 06:12:44.756432 | controller -> localhost | changed: "/var/lib/zuul/builds/c0345959015b47d798e63a5b7997f2e1/work/artifacts" 2025-10-13 06:12:45.208796 | controller -> localhost | changed: "/var/lib/zuul/builds/c0345959015b47d798e63a5b7997f2e1/work/docs" 2025-10-13 06:12:45.220636 | 2025-10-13 06:12:45.220762 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-10-13 06:12:46.118342 | controller | changed: 2025-10-13 06:12:46.118521 | controller | .d..t...... ./ 2025-10-13 06:12:46.118552 | controller | >f+++++++++ README.html 2025-10-13 06:12:46.118575 | controller | >f+++++++++ ansible-execution.log 2025-10-13 06:12:46.118598 | controller | >f+++++++++ ansible.log 2025-10-13 06:12:46.118618 | controller | >f+++++++++ dmesg.log 2025-10-13 06:12:46.118638 | controller | >f+++++++++ installed-pkgs.log 2025-10-13 06:12:46.118661 | controller | >f+++++++++ python.log 2025-10-13 06:12:46.118685 | controller | >f+++++++++ registries.conf 2025-10-13 06:12:46.118705 | controller | >f+++++++++ report.html 2025-10-13 06:12:46.118727 | controller | >f+++++++++ selinux-denials.log 2025-10-13 06:12:46.118746 | controller | >f+++++++++ selinux-listing.log 2025-10-13 06:12:46.118764 | controller | cd+++++++++ ci-framework-data/ 2025-10-13 06:12:46.118782 | controller | cd+++++++++ ci-framework-data/artifacts/ 2025-10-13 06:12:46.118800 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml 2025-10-13 06:12:46.118818 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/ 2025-10-13 06:12:46.118835 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml 2025-10-13 06:12:46.118853 | controller | cd+++++++++ ci-framework-data/logs/ 2025-10-13 06:12:46.118871 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log 2025-10-13 06:12:46.118888 | controller | cd+++++++++ registries.conf.d/ 2025-10-13 06:12:46.131992 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf 2025-10-13 06:12:46.132048 | controller | cd+++++++++ system-config/ 2025-10-13 06:12:46.132076 | controller | cd+++++++++ system-config/libvirt/ 2025-10-13 06:12:46.132098 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf 2025-10-13 06:12:46.132119 | controller | >f+++++++++ system-config/libvirt/libvirt.conf 2025-10-13 06:12:46.564088 | controller | changed: .d..t...... ./ 2025-10-13 06:12:47.005517 | controller | changed: .d..t...... ./ 2025-10-13 06:12:47.020696 | 2025-10-13 06:12:47.020802 | TASK [Return artifact to Zuul] 2025-10-13 06:12:47.062811 | controller | ok 2025-10-13 06:12:47.104262 | 2025-10-13 06:12:47.104358 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host] 2025-10-13 06:12:47.104505 | 2025-10-13 06:12:47.104537 | PLAY RECAP 2025-10-13 06:12:47.104596 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1 2025-10-13 06:12:47.104624 | 2025-10-13 06:12:47.200487 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main] 2025-10-13 06:12:47.201244 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2025-10-13 06:12:48.096503 | 2025-10-13 06:12:48.096637 | PLAY [all] 2025-10-13 06:12:48.115720 | 2025-10-13 06:12:48.115802 | TASK [include_role : fetch-output] 2025-10-13 06:12:48.164654 | controller | ok 2025-10-13 06:12:48.185373 | 2025-10-13 06:12:48.185481 | TASK [fetch-output : Set log path for multiple nodes] 2025-10-13 06:12:48.229273 | controller | skipping: Conditional result was False 2025-10-13 06:12:48.234800 | 2025-10-13 06:12:48.234871 | TASK [fetch-output : Set log path for single node] 2025-10-13 06:12:48.293948 | controller | ok 2025-10-13 06:12:48.305342 | 2025-10-13 06:12:48.305417 | LOOP [fetch-output : Ensure local output dirs] 2025-10-13 06:12:48.983483 | controller -> localhost | ok: "/var/lib/zuul/builds/c0345959015b47d798e63a5b7997f2e1/work/logs" 2025-10-13 06:12:49.296495 | controller -> localhost | ok: "/var/lib/zuul/builds/c0345959015b47d798e63a5b7997f2e1/work/artifacts" 2025-10-13 06:12:49.683481 | controller -> localhost | ok: "/var/lib/zuul/builds/c0345959015b47d798e63a5b7997f2e1/work/docs" 2025-10-13 06:12:49.694272 | 2025-10-13 06:12:49.694374 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-10-13 06:12:50.562770 | controller | ok 2025-10-13 06:12:50.562974 | controller | ok: All items complete 2025-10-13 06:12:50.563007 | 2025-10-13 06:12:51.214851 | controller | ok 2025-10-13 06:12:51.923648 | controller | ok 2025-10-13 06:12:51.937738 | 2025-10-13 06:12:51.937810 | TASK [include_role : fetch-output-openshift] 2025-10-13 06:12:51.971384 | controller | skipping: Conditional result was False 2025-10-13 06:12:51.982091 | 2025-10-13 06:12:51.982175 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-10-13 06:12:52.431184 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.017667 2025-10-13 06:12:52.671025 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.006732 2025-10-13 06:12:52.726841 | 2025-10-13 06:12:52.726974 | PLAY [all] 2025-10-13 06:12:52.785378 | 2025-10-13 06:12:52.785488 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-10-13 06:12:53.460240 | controller | changed 2025-10-13 06:12:53.501341 | 2025-10-13 06:12:53.501431 | PLAY RECAP 2025-10-13 06:12:53.501476 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2025-10-13 06:12:53.501499 | 2025-10-13 06:12:53.723492 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2025-10-13 06:12:53.724238 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master] 2025-10-13 06:12:54.453372 | 2025-10-13 06:12:54.453478 | PLAY [localhost] 2025-10-13 06:12:54.477284 | 2025-10-13 06:12:54.477387 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-10-13 06:12:55.463237 | localhost | changed 2025-10-13 06:12:55.468264 | 2025-10-13 06:12:55.468373 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-10-13 06:12:55.531152 | localhost | ok 2025-10-13 06:12:55.549077 | 2025-10-13 06:12:55.549181 | TASK [add-fileserver : Create SSH private key tempfile] 2025-10-13 06:12:56.384099 | localhost | changed 2025-10-13 06:12:56.389419 | 2025-10-13 06:12:56.389491 | TASK [add-fileserver : Create SSH private key from secret] 2025-10-13 06:12:58.019015 | localhost | changed 2025-10-13 06:12:58.026118 | 2025-10-13 06:12:58.026189 | TASK [add-fileserver : Add fileserver ssh key] 2025-10-13 06:12:59.125447 | localhost | Identity added: /var/lib/zuul/builds/c0345959015b47d798e63a5b7997f2e1/work/tmp/ansible.7_33nhej (/var/lib/zuul/builds/c0345959015b47d798e63a5b7997f2e1/work/tmp/ansible.7_33nhej) 2025-10-13 06:12:59.125637 | localhost | ok: Runtime: 0:00:00.039448 2025-10-13 06:12:59.130792 | 2025-10-13 06:12:59.130858 | TASK [add-fileserver : Remove SSH private key from disk] 2025-10-13 06:12:59.733334 | localhost | ok: Runtime: 0:00:00.004708 2025-10-13 06:12:59.746223 | 2025-10-13 06:12:59.746380 | TASK [add-fileserver : Add fileserver to inventory] 2025-10-13 06:12:59.849158 | localhost | changed 2025-10-13 06:12:59.854072 | 2025-10-13 06:12:59.854138 | TASK [add-fileserver : Add fileserver server to known hosts] 2025-10-13 06:13:00.649200 | localhost | changed 2025-10-13 06:13:00.719470 | 2025-10-13 06:13:00.719554 | PLAY [localhost] 2025-10-13 06:13:00.738775 | 2025-10-13 06:13:00.738858 | TASK [Generate bulk log download script] 2025-10-13 06:13:00.780793 | localhost | ok 2025-10-13 06:13:00.894300 | 2025-10-13 06:13:00.894412 | TASK [local-log-download : Check API endpoint is defined] 2025-10-13 06:13:00.937578 | localhost | ok: All assertions passed 2025-10-13 06:13:00.942093 | 2025-10-13 06:13:00.942157 | TASK [local-log-download : Create download script] 2025-10-13 06:13:01.922522 | localhost -> localhost | changed 2025-10-13 06:13:01.931773 | 2025-10-13 06:13:01.931841 | TASK [Register quick-download link] 2025-10-13 06:13:01.981470 | localhost | ok 2025-10-13 06:13:02.015159 | 2025-10-13 06:13:02.015224 | PLAY [logserver.rdoproject.org] 2025-10-13 06:13:02.034435 | 2025-10-13 06:13:02.034496 | TASK [Set zuul-log-path fact] 2025-10-13 06:13:02.062035 | logserver.rdoproject.org | ok 2025-10-13 06:13:02.073041 | 2025-10-13 06:13:02.073112 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-13 06:13:02.129069 | logserver.rdoproject.org | ok 2025-10-13 06:13:02.134837 | 2025-10-13 06:13:02.134902 | TASK [upload-logs : Create log directories] 2025-10-13 06:13:04.032265 | logserver.rdoproject.org | changed 2025-10-13 06:13:04.035737 | 2025-10-13 06:13:04.035812 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-10-13 06:13:04.343437 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.020149 2025-10-13 06:13:04.348522 | 2025-10-13 06:13:04.348589 | TASK [upload-logs : Upload logs to log server] 2025-10-13 06:13:06.622262 | logserver.rdoproject.org | Output suppressed because no_log was given 2025-10-13 06:13:06.627740 | 2025-10-13 06:13:06.627804 | LOOP [upload-logs : Compress console log and json output] 2025-10-13 06:13:06.727133 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-13 06:13:06.742602 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-13 06:13:06.746878 | 2025-10-13 06:13:06.747013 | LOOP [upload-logs : Upload compressed console log and json output] 2025-10-13 06:13:06.922165 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-13 06:13:06.922593 | 2025-10-13 06:13:06.928641 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-13 06:13:06.942087 | 2025-10-13 06:13:06.942201 | LOOP [upload-logs : Upload console log and json output]