2025-10-07 15:08:33.547795 | Job console starting... 2025-10-07 15:08:33.786838 | Updating repositories 2025-10-07 15:08:34.659379 | Preparing job workspace 2025-10-07 15:08:48.449039 | Running Ansible setup... 2025-10-07 15:08:55.123002 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2025-10-07 15:08:56.670057 | 2025-10-07 15:08:56.670150 | PLAY [localhost] 2025-10-07 15:08:56.683598 | 2025-10-07 15:08:56.683670 | TASK [Gathering Facts] 2025-10-07 15:08:57.991831 | localhost | ok 2025-10-07 15:08:58.007194 | 2025-10-07 15:08:58.007285 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-10-07 15:08:58.407222 | localhost -> localhost | changed 2025-10-07 15:08:58.413676 | 2025-10-07 15:08:58.413755 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-10-07 15:08:59.764377 | localhost -> localhost | changed 2025-10-07 15:08:59.773732 | 2025-10-07 15:08:59.773823 | TASK [Setup log path fact] 2025-10-07 15:08:59.797624 | localhost | ok 2025-10-07 15:08:59.811264 | 2025-10-07 15:08:59.811339 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-07 15:08:59.838572 | localhost | ok 2025-10-07 15:08:59.846782 | 2025-10-07 15:08:59.846852 | TASK [emit-job-header : Print job information] 2025-10-07 15:08:59.874040 | # Job Information 2025-10-07 15:08:59.874168 | Ansible Version: 2.15.12 2025-10-07 15:08:59.874199 | Job: cifmw-molecule-dnsmasq 2025-10-07 15:08:59.874222 | Pipeline: github-check 2025-10-07 15:08:59.874417 | Executor: ibm-bm4-ze.softwarefactory-project.io 2025-10-07 15:08:59.874471 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3368 2025-10-07 15:08:59.874499 | Log URL (when completed): https://logserver.rdoproject.org/c6a/rdoproject.org/c6a8f38485074d3a881ba1725ff81a91/ 2025-10-07 15:08:59.874521 | Event ID: 25975190-a38f-11f0-9c22-f9318513dd27 2025-10-07 15:08:59.878120 | 2025-10-07 15:08:59.878188 | LOOP [emit-job-header : Print node information] 2025-10-07 15:08:59.964407 | localhost | ok: 2025-10-07 15:08:59.964621 | localhost | # Node Information 2025-10-07 15:08:59.964654 | localhost | Inventory Hostname: controller 2025-10-07 15:08:59.964679 | localhost | Hostname: np0005474305 2025-10-07 15:08:59.964701 | localhost | Username: zuul 2025-10-07 15:08:59.964723 | localhost | Distro: CentOS 9 2025-10-07 15:08:59.964741 | localhost | Provider: ibm-bm4-nodepool 2025-10-07 15:08:59.964759 | localhost | Region: regionOne 2025-10-07 15:08:59.964776 | localhost | Label: cloud-centos-9-stream-tripleo-ibm 2025-10-07 15:08:59.964793 | localhost | Product Name: OpenStack Compute 2025-10-07 15:08:59.964810 | localhost | Interface IP: 192.168.26.207 2025-10-07 15:08:59.992531 | 2025-10-07 15:08:59.992618 | PLAY [all] 2025-10-07 15:09:00.027106 | 2025-10-07 15:09:00.027210 | TASK [Gather network facts] 2025-10-07 15:09:00.407549 | controller | ok 2025-10-07 15:09:00.431779 | 2025-10-07 15:09:00.431890 | TASK [include_role : start-zuul-console] 2025-10-07 15:09:00.457113 | controller | ok 2025-10-07 15:09:00.470720 | 2025-10-07 15:09:00.470798 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-10-07 15:09:00.802240 | controller | ok 2025-10-07 15:09:00.812029 | 2025-10-07 15:09:00.812131 | TASK [include_role : add-build-sshkey] 2025-10-07 15:09:00.840098 | controller | ok 2025-10-07 15:09:00.854883 | 2025-10-07 15:09:00.854995 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-07 15:09:01.092455 | controller -> localhost | ok 2025-10-07 15:09:01.098408 | 2025-10-07 15:09:01.098489 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-07 15:09:01.118319 | controller | ok 2025-10-07 15:09:01.133225 | controller | included: /var/lib/zuul/builds/c6a8f38485074d3a881ba1725ff81a91/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-07 15:09:01.139127 | 2025-10-07 15:09:01.139189 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-07 15:09:02.774957 | controller -> localhost | Generating public/private rsa key pair. 2025-10-07 15:09:02.775145 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/c6a8f38485074d3a881ba1725ff81a91/work/c6a8f38485074d3a881ba1725ff81a91_id_rsa. 2025-10-07 15:09:02.775182 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/c6a8f38485074d3a881ba1725ff81a91/work/c6a8f38485074d3a881ba1725ff81a91_id_rsa.pub. 2025-10-07 15:09:02.775207 | controller -> localhost | The key fingerprint is: 2025-10-07 15:09:02.775227 | controller -> localhost | SHA256:t5f4vAsQfeC+x6bL5OTIzYeO/yTyQGWLYnNSId6Q+Oo zuul-build-sshkey 2025-10-07 15:09:02.775248 | controller -> localhost | The key's randomart image is: 2025-10-07 15:09:02.775267 | controller -> localhost | +---[RSA 3072]----+ 2025-10-07 15:09:02.775286 | controller -> localhost | | .o.. . | 2025-10-07 15:09:02.775306 | controller -> localhost | | ...+ + . | 2025-10-07 15:09:02.775324 | controller -> localhost | | .. + = . | 2025-10-07 15:09:02.775341 | controller -> localhost | | .. * o | 2025-10-07 15:09:02.775359 | controller -> localhost | | .= S + | 2025-10-07 15:09:02.775375 | controller -> localhost | | .. * o = . | 2025-10-07 15:09:02.775392 | controller -> localhost | | . o O.B | 2025-10-07 15:09:02.775411 | controller -> localhost | | E . #.@. | 2025-10-07 15:09:02.775429 | controller -> localhost | | +o%+*o | 2025-10-07 15:09:02.775463 | controller -> localhost | +----[SHA256]-----+ 2025-10-07 15:09:02.775504 | controller -> localhost | ok: Runtime: 0:00:01.032567 2025-10-07 15:09:02.786696 | 2025-10-07 15:09:02.786763 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-07 15:09:02.841965 | controller | ok 2025-10-07 15:09:02.866311 | controller | included: /var/lib/zuul/builds/c6a8f38485074d3a881ba1725ff81a91/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-07 15:09:02.902737 | 2025-10-07 15:09:02.902837 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-07 15:09:02.957765 | controller | skipping: Conditional result was False 2025-10-07 15:09:02.963588 | 2025-10-07 15:09:02.963655 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-07 15:09:03.673029 | controller | changed 2025-10-07 15:09:03.678591 | 2025-10-07 15:09:03.678660 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-07 15:09:03.981020 | controller | ok 2025-10-07 15:09:03.986080 | 2025-10-07 15:09:03.986148 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-07 15:09:04.635083 | controller | changed 2025-10-07 15:09:04.645375 | 2025-10-07 15:09:04.645456 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-07 15:09:05.410959 | controller | changed 2025-10-07 15:09:05.416341 | 2025-10-07 15:09:05.416420 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-07 15:09:05.450314 | controller | skipping: Conditional result was False 2025-10-07 15:09:05.456240 | 2025-10-07 15:09:05.456308 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-07 15:09:05.848032 | controller -> localhost | changed 2025-10-07 15:09:05.861546 | 2025-10-07 15:09:05.861667 | TASK [add-build-sshkey : Add back temp key] 2025-10-07 15:09:06.162107 | controller -> localhost | Identity added: /var/lib/zuul/builds/c6a8f38485074d3a881ba1725ff81a91/work/c6a8f38485074d3a881ba1725ff81a91_id_rsa (zuul-build-sshkey) 2025-10-07 15:09:06.162283 | controller -> localhost | ok: Runtime: 0:00:00.006988 2025-10-07 15:09:06.170455 | 2025-10-07 15:09:06.170533 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-07 15:09:06.594102 | controller | ok 2025-10-07 15:09:06.599399 | 2025-10-07 15:09:06.599480 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-07 15:09:06.648485 | controller | skipping: Conditional result was False 2025-10-07 15:09:06.658617 | 2025-10-07 15:09:06.658692 | TASK [include_role : validate-host] 2025-10-07 15:09:06.689506 | controller | ok 2025-10-07 15:09:06.715103 | 2025-10-07 15:09:06.715183 | TASK [validate-host : Define zuul_info_dir fact] 2025-10-07 15:09:06.825788 | controller | ok 2025-10-07 15:09:06.830729 | 2025-10-07 15:09:06.830795 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-10-07 15:09:07.147580 | controller -> localhost | ok 2025-10-07 15:09:07.153838 | 2025-10-07 15:09:07.153942 | TASK [validate-host : Collect information about the host] 2025-10-07 15:09:07.816027 | controller | ok 2025-10-07 15:09:07.830477 | 2025-10-07 15:09:07.830566 | TASK [validate-host : Sanitize hostname] 2025-10-07 15:09:07.925986 | controller | ok 2025-10-07 15:09:07.931172 | 2025-10-07 15:09:07.931242 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-10-07 15:09:08.672216 | controller -> localhost | changed 2025-10-07 15:09:08.680069 | 2025-10-07 15:09:08.680135 | TASK [validate-host : Collect information about zuul worker] 2025-10-07 15:09:09.077339 | controller | ok 2025-10-07 15:09:09.083552 | 2025-10-07 15:09:09.083618 | TASK [validate-host : Write out all zuul information for each host] 2025-10-07 15:09:09.510841 | controller -> localhost | changed 2025-10-07 15:09:09.520651 | 2025-10-07 15:09:09.520726 | TASK [include_role : prepare-workspace-openshift] 2025-10-07 15:09:09.543875 | controller | skipping: Conditional result was False 2025-10-07 15:09:09.549389 | 2025-10-07 15:09:09.549470 | TASK [include_role : remove-zuul-sshkey] 2025-10-07 15:09:09.563088 | controller | skipping: Conditional result was False 2025-10-07 15:09:09.569463 | 2025-10-07 15:09:09.569532 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2025-10-07 15:09:09.795856 | controller | ok: "logs" 2025-10-07 15:09:09.796084 | controller | ok: All items complete 2025-10-07 15:09:09.796116 | 2025-10-07 15:09:09.983389 | controller | ok: "artifacts" 2025-10-07 15:09:10.181941 | controller | ok: "docs" 2025-10-07 15:09:10.188871 | 2025-10-07 15:09:10.188965 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2025-10-07 15:09:10.423774 | controller | changed: "logs" 2025-10-07 15:09:10.605394 | controller | changed: "artifacts" 2025-10-07 15:09:10.798766 | controller | changed: "docs" 2025-10-07 15:09:10.830132 | 2025-10-07 15:09:10.830197 | PLAY RECAP 2025-10-07 15:09:10.830783 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0 2025-10-07 15:09:10.830823 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-07 15:09:10.830845 | 2025-10-07 15:09:10.967204 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2025-10-07 15:09:10.967935 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master] 2025-10-07 15:09:11.734987 | 2025-10-07 15:09:11.735087 | PLAY [all] 2025-10-07 15:09:11.755058 | 2025-10-07 15:09:11.755145 | TASK [mirror-info-fork : Set mirror_fqdn if not defined] 2025-10-07 15:09:11.805164 | controller | ok 2025-10-07 15:09:11.810515 | 2025-10-07 15:09:11.810599 | TASK [mirror-info-fork : Create /etc/ci] 2025-10-07 15:09:12.215023 | controller | changed 2025-10-07 15:09:12.221401 | 2025-10-07 15:09:12.221546 | TASK [mirror-info-fork : Install ci_mirror script] 2025-10-07 15:09:13.325365 | controller | changed 2025-10-07 15:09:13.349503 | 2025-10-07 15:09:13.349639 | LOOP [add-authorized-keys : Enable access via build key on all nodes] 2025-10-07 15:09:13.908786 | controller | changed: 2025-10-07 15:09:13.908980 | controller | { 2025-10-07 15:09:13.909014 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com" 2025-10-07 15:09:13.909046 | controller | } 2025-10-07 15:09:14.137678 | controller | changed: 2025-10-07 15:09:14.137766 | controller | { 2025-10-07 15:09:14.137796 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com" 2025-10-07 15:09:14.137820 | controller | } 2025-10-07 15:09:14.351392 | controller | changed: 2025-10-07 15:09:14.351489 | controller | { 2025-10-07 15:09:14.351518 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com" 2025-10-07 15:09:14.351540 | controller | } 2025-10-07 15:09:14.571301 | controller | changed: 2025-10-07 15:09:14.571383 | controller | { 2025-10-07 15:09:14.571411 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com" 2025-10-07 15:09:14.571558 | controller | } 2025-10-07 15:09:14.777898 | controller | changed: 2025-10-07 15:09:14.777998 | controller | { 2025-10-07 15:09:14.778026 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com" 2025-10-07 15:09:14.778048 | controller | } 2025-10-07 15:09:14.994924 | controller | changed: 2025-10-07 15:09:14.995008 | controller | { 2025-10-07 15:09:14.995038 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com" 2025-10-07 15:09:14.995060 | controller | } 2025-10-07 15:09:15.219647 | controller | changed: 2025-10-07 15:09:15.219727 | controller | { 2025-10-07 15:09:15.219754 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDLOQd4ZLtkZXQGY6UwAr/06ppWQK4fDO3HaqxPk98csyOCBXsliSKK39Bso828+5srIXiW7aI6aC9P5mwi4mUZlGPfJlQbfrcGvY+b/SocuvaGK+1RrHLoJCT52LBhwgrzlXio2jeksZeein8iaTrhsPrOAs7KggIL/rB9hEiB3NaOPWhhoCP4vlW6MEMExGcqB/1FVxXFBPnLkEyW0Lk7ycVflZl2ocRxbfjZi0+tI1Wlinp8PvSQSc/WVrAcDgKjc/mB4ODPOyYy3G8FHgfMsrXSDEyjBKgLKMsdCrAUcqJQWjkqXleXSYOV4q3pzL+9umK+q/e3P/bIoSFQzmJKTU1eDfuvPXmow9F5H54fii/Da7ezlMJ+wPGHJrRAkmzvMbALy7xwswLhZMkOGNtRcPqaKYRmIBKpw3o6bCTtcNUHOtOQnzwY8JzrM2eBWJBXAANYw+9/ho80JIiwhg29CFNpVBuHbql2YxJQNrnl90guN65rYNpDxdIluweyUf8= anbanerj@kaermorhen" 2025-10-07 15:09:15.219778 | controller | } 2025-10-07 15:09:15.435266 | controller | changed: 2025-10-07 15:09:15.435348 | controller | { 2025-10-07 15:09:15.435376 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQC3VwV8Im9kRm49lt3tM36hj4Zv27FxGo4C1Q/0jqhzFmHY7RHbmeRr8ObhwWoHjXSozKWg8FL5ER0z3hTwL0W6lez3sL7hUaCmSuZmG5Hnl3x4vTSxDI9JZ/Y65rtYiiWQo2fC5xJhU/4+0e5e/pseCm8cKRSu+SaxhO+sd6FDojA2x1BzOzKiQRDy/1zWGp/cZkxcEuB1wHI5LMzN03c67vmbu+fhZRAUO4dQkvcnj2LrhQtpa+ytvnSjr8icMDosf1OsbSffwZFyHB/hfWGAfe0eIeSA2XPraxiPknXxiPKx2MJsaUTYbsZcm3EjFdHBBMumw5rBI74zLrMRvCO9GwBEmGT4rFng1nP+yw5DB8sn2zqpOsPg1LYRwCPOUveC13P6pgsZZPh812e8v5EKnETct+5XI3dVpdw6CnNiLwAyVAF15DJvBGT/u1k0Myg/bQn+Gv9k2MSj6LvQmf6WbZu2Wgjm30z3FyCneBqTL7mLF19YXzeC0ufHz5pnO1E= dasm@fedora" 2025-10-07 15:09:15.435399 | controller | } 2025-10-07 15:09:15.642933 | controller | changed: 2025-10-07 15:09:15.643031 | controller | { 2025-10-07 15:09:15.643059 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner" 2025-10-07 15:09:15.643082 | controller | } 2025-10-07 15:09:15.848178 | controller | changed: 2025-10-07 15:09:15.848509 | controller | { 2025-10-07 15:09:15.848552 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com" 2025-10-07 15:09:15.848580 | controller | } 2025-10-07 15:09:16.114096 | controller | changed: 2025-10-07 15:09:16.114232 | controller | { 2025-10-07 15:09:16.114269 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com" 2025-10-07 15:09:16.114295 | controller | } 2025-10-07 15:09:16.309984 | controller | changed: 2025-10-07 15:09:16.310077 | controller | { 2025-10-07 15:09:16.310104 | controller | "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com" 2025-10-07 15:09:16.310127 | controller | } 2025-10-07 15:09:16.551313 | controller | changed: 2025-10-07 15:09:16.551417 | controller | { 2025-10-07 15:09:16.551497 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith" 2025-10-07 15:09:16.551526 | controller | } 2025-10-07 15:09:16.789662 | controller | changed: 2025-10-07 15:09:16.790029 | controller | { 2025-10-07 15:09:16.790072 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel" 2025-10-07 15:09:16.790099 | controller | } 2025-10-07 15:09:17.026350 | controller | changed: 2025-10-07 15:09:17.029075 | controller | { 2025-10-07 15:09:17.029123 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux" 2025-10-07 15:09:17.029150 | controller | } 2025-10-07 15:09:17.251418 | controller | changed: 2025-10-07 15:09:17.251522 | controller | { 2025-10-07 15:09:17.251550 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr" 2025-10-07 15:09:17.251572 | controller | } 2025-10-07 15:09:17.480236 | controller | changed: 2025-10-07 15:09:17.480323 | controller | { 2025-10-07 15:09:17.480351 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQCfNtF7NvKl915TGsGGoseUb06Hj8L/S4toWf0hExeY+F00woL6NvBlJD0nDct+P5a22I4EhvoQCRQ8reaPCm1lybR3uiRIJsj+8zkVvLwby9LXzfZorlNG9ofjd00FEmB09uW/YvTl6Q9XwwwX6tInzIOv3TMqTHHGOL74ibbj8J/FJR0cFEyj0z4WQRvtkh32xAHl83gbuINryMt0sqRI+clj2381NKL55DRLQrVw0gsfqqxiHAnXg21qWmc4J+b9e9kiuAFQjcjwTVkwJCcg3xbPwC/qokYRby/Y5S40UUd7/jEARGXT7RZgpzTuDd1oZiCVrnrqJNPaMNdVv5MLeFdf1B7iIe5aa/fGouX7AO4SdKhZUdnJmCFAGvjC6S3JMZ2wAcUl+OHnssfmdj7XL50cLo27vjuzMtLAgSqi6N99m92WCF2s8J9aVzszX7Xz9OKZCeGsiVJp3/NdABKzSEAyM9xBD/5Vho894Sav+otpySHe3p6RUTgbB5Zu8VyZRZ/UtB3ueXxyo764yrc6qWIDqrehm84Xm9g+/jpIBzGPl07NUNJpdt/6Sgf9RIKXw/7XypO5yZfUcuFNGTxLfqjTNrtgLZNcjfav6sSdVXVcMPL//XNuRdKmVFaO76eV/oGMQGr1fGcCD+N+CpI7+Q+fCNB6VFWG4nZFuI/Iuw== averdagu@redhat.com" 2025-10-07 15:09:17.480373 | controller | } 2025-10-07 15:09:17.700490 | controller | changed: 2025-10-07 15:09:17.700579 | controller | { 2025-10-07 15:09:17.700607 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDq8l27xI+QlQVdS4djp9ogSoyrNE2+Ox6vKPdhSNL1J3PE5w+WCSvMz9A5gnNuH810zwbekEApbxTze/gLQJwBHA52CChfURpXrFaxY7ePXRElwKAL3mJfzBWY/c5jnNL9TCVmFJTGZkFZP3Nh+BMgZvL6xBkt3WKm6Uq18qzd9XeKcZusrA+O+uLv1fVeQnadY9RIqOCyeFYCzLWrUfTyE8x/XG0hAWIM7qpnF2cALQS2h9n4hW5ybiUN790H08wf9hFwEf5nxY9Z9dVkPFQiTSGKNBzmnCXU9skxS/xhpFjJ5duGSZdtAHe9O+nGZm9c67hxgtf8e5PDuqAdXEv2cf6e3VBAt+Bz8EKI3yosTj0oZHfwr42Yzb1l/SKy14Rggsrc9KAQlrGXan6+u2jcQqqx7l+SWmnpFiWTV9u5cWj2IgOhApOitmRBPYqk9rE2usfO0hLn/Pj/R/Nau4803e1/EikdLE7Ps95s9mX5jRDjAoUa2JwFF5RsVFyL910= ashigupt@ashigupt.remote.csb" 2025-10-07 15:09:17.700635 | controller | } 2025-10-07 15:09:17.909395 | controller | changed: 2025-10-07 15:09:17.909510 | controller | { 2025-10-07 15:09:17.909542 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9" 2025-10-07 15:09:17.909564 | controller | } 2025-10-07 15:09:18.130441 | controller | changed: 2025-10-07 15:09:18.130542 | controller | { 2025-10-07 15:09:18.130569 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora" 2025-10-07 15:09:18.130591 | controller | } 2025-10-07 15:09:18.345050 | controller | changed: 2025-10-07 15:09:18.345126 | controller | { 2025-10-07 15:09:18.345152 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac" 2025-10-07 15:09:18.345173 | controller | } 2025-10-07 15:09:18.552885 | controller | changed: 2025-10-07 15:09:18.552997 | controller | { 2025-10-07 15:09:18.553025 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop" 2025-10-07 15:09:18.553046 | controller | } 2025-10-07 15:09:18.765726 | controller | changed: 2025-10-07 15:09:18.765812 | controller | { 2025-10-07 15:09:18.765839 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQDZdI7t1cxYx65heVI24HTV4F7oQLW1zyfxHreL2TIJKxjyrUUKIFEUmTutcBlJRLNT2Eoix6x1sOw9YrchloCLcn//SGfTElr9mSc5jbjb7QXEU+zJMhtxyEJ1Po3CUGnj7ckiIXw7wcawZtrEOAQ9pH3ExYCJcEMiyNjRQZCxT3tPK+S4B95EWh5Fsrz9CkwpjNRPPH7LigCeQTM3Wc7r97utAslBUUvYceDSLA7rMgkitJE38b7rZBeYzsGQ8YYUBjTCtehqQXxCRjizbHWaaZkBU+N3zkKB6n/iCNGIO690NK7A/qb6msTijiz1PeuM8ThOsi9qXnbX5v0PoTpcFSojV7NHAQ71f0XXuS43FhZctT+Dcx44dT8Fb5vJu2cJGrk+qF8ZgJYNpRS7gPg0EG2EqjK7JMf9ULdjSu0r+KlqIAyLvtzT4eOnQipoKlb/WG5D/0ohKv7OMQ352ggfkBFIQsRXyyTCT98Ft9juqPuahi3CAQmP4H9dyE+7+Kz437PEtsxLmfm6naNmWi7Ee1DqWPwS8rEajsm4sNM4wW9gdBboJQtc0uZw0DfLj1I9r3Mc8Ol0jYtz0yNQDSzVLrGCaJlC311trU70tZ+ZkAVV6Mn8lOhSbj1cK0lvSr6ZK4dgqGl3I1eTZJJhbLNdg7UOVaiRx9543+C/p/As7w== brjackma@redhat.com" 2025-10-07 15:09:18.765861 | controller | } 2025-10-07 15:09:18.996105 | controller | changed: 2025-10-07 15:09:18.996191 | controller | { 2025-10-07 15:09:18.996219 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com" 2025-10-07 15:09:18.996241 | controller | } 2025-10-07 15:09:19.239732 | controller | changed: 2025-10-07 15:09:19.239815 | controller | { 2025-10-07 15:09:19.239843 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com" 2025-10-07 15:09:19.239864 | controller | } 2025-10-07 15:09:19.459874 | controller | changed: 2025-10-07 15:09:19.459982 | controller | { 2025-10-07 15:09:19.460011 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono" 2025-10-07 15:09:19.460032 | controller | } 2025-10-07 15:09:19.481063 | 2025-10-07 15:09:19.481139 | TASK [Set timezone to UTC] 2025-10-07 15:09:19.963681 | controller | changed: executed `/bin/timedatectl set-timezone UTC` 2025-10-07 15:09:19.968359 | 2025-10-07 15:09:19.968426 | TASK [Create nodepool directory] 2025-10-07 15:09:20.179227 | controller | changed 2025-10-07 15:09:20.185048 | 2025-10-07 15:09:20.185120 | TASK [Create nodepool sub_nodes file] 2025-10-07 15:09:20.732537 | controller | changed 2025-10-07 15:09:20.738795 | 2025-10-07 15:09:20.738894 | TASK [Create nodepool sub_nodes_private file] 2025-10-07 15:09:21.295495 | controller | changed 2025-10-07 15:09:21.301187 | 2025-10-07 15:09:21.301282 | LOOP [Populate nodepool sub_nodes file] 2025-10-07 15:09:21.332765 | 2025-10-07 15:09:21.332958 | LOOP [Populate nodepool sub_nodes_private file] 2025-10-07 15:09:21.363534 | 2025-10-07 15:09:21.363707 | TASK [Create nodepool primary file] 2025-10-07 15:09:21.387670 | controller | skipping: Conditional result was False 2025-10-07 15:09:21.394051 | 2025-10-07 15:09:21.394160 | TASK [Create nodepool node_private for this node] 2025-10-07 15:09:21.962076 | controller | changed 2025-10-07 15:09:21.967682 | 2025-10-07 15:09:21.967747 | LOOP [Copy ssh keys to nodepool directory] 2025-10-07 15:09:22.327232 | controller | ok: Item: id_rsa Runtime: 0:00:00.007227 2025-10-07 15:09:22.327404 | 2025-10-07 15:09:22.505641 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.007610 2025-10-07 15:09:22.512952 | 2025-10-07 15:09:22.513040 | TASK [Add sudoers role for zuul-sudo-grep.sh] 2025-10-07 15:09:23.129618 | controller | changed 2025-10-07 15:09:23.135659 | 2025-10-07 15:09:23.135783 | TASK [Validate sudoers config after edits] 2025-10-07 15:09:23.439501 | controller | /etc/sudoers: parsed OK 2025-10-07 15:09:23.439624 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK 2025-10-07 15:09:23.439642 | controller | /etc/sudoers.d/zuul: parsed OK 2025-10-07 15:09:23.439658 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK 2025-10-07 15:09:23.686416 | controller | ok: Runtime: 0:00:00.007389 2025-10-07 15:09:23.691755 | 2025-10-07 15:09:23.691982 | TASK [Show the environment passed in to job shell scripts] 2025-10-07 15:09:23.923245 | controller | SHELL=/bin/bash 2025-10-07 15:09:23.923326 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework 2025-10-07 15:09:23.923356 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework 2025-10-07 15:09:23.923364 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/68/3368/79d4826489f0aca45950dd469a4b40f124dba5de 2025-10-07 15:09:23.923377 | controller | PWD=/home/zuul 2025-10-07 15:09:23.923390 | controller | ZUUL_PIPELINE=github-check 2025-10-07 15:09:23.923405 | controller | LOGNAME=zuul 2025-10-07 15:09:23.923475 | controller | XDG_SESSION_TYPE=tty 2025-10-07 15:09:23.923485 | controller | _=/usr/bin/env 2025-10-07 15:09:23.923490 | controller | MOTD_SHOWN=pam 2025-10-07 15:09:23.923496 | controller | HOME=/home/zuul 2025-10-07 15:09:23.923501 | controller | LANG=en_US.UTF-8 2025-10-07 15:09:23.923506 | controller | SSH_CONNECTION=192.168.26.12 36642 192.168.26.207 22 2025-10-07 15:09:23.923513 | controller | TOX_TESTENV_PASSENV=ZUUL_UUID ZUUL_PROJECT ZUUL_SHORT_PROJECT_NAME ZUUL_PIPELINE ZUUL_VOTING WORKSPACE BUILD_TIMEOUT ZUUL_BRANCH ZUUL_CHANGES ZUUL_CHANGE_IDS ZUUL_CHANGE ZUUL_PATCHSET 2025-10-07 15:09:23.923524 | controller | ZUUL_CHANGE_IDS=3368,79d4826489f0aca45950dd469a4b40f124dba5de 2025-10-07 15:09:23.923529 | controller | WORKSPACE=/home/zuul/workspace 2025-10-07 15:09:23.923535 | controller | XDG_SESSION_CLASS=user 2025-10-07 15:09:23.923541 | controller | SELINUX_ROLE_REQUESTED= 2025-10-07 15:09:23.923546 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s 2025-10-07 15:09:23.923552 | controller | USER=zuul 2025-10-07 15:09:23.923557 | controller | ZUUL_VOTING=True 2025-10-07 15:09:23.923563 | controller | BUILD_TIMEOUT=1800000 2025-10-07 15:09:23.923568 | controller | SELINUX_USE_CURRENT_RANGE= 2025-10-07 15:09:23.923593 | controller | SHLVL=1 2025-10-07 15:09:23.923599 | controller | ZUUL_PATCHSET=79d4826489f0aca45950dd469a4b40f124dba5de 2025-10-07 15:09:23.923604 | controller | XDG_SESSION_ID=1 2025-10-07 15:09:23.923609 | controller | ZUUL_BRANCH=main 2025-10-07 15:09:23.923615 | controller | XDG_RUNTIME_DIR=/run/user/1000 2025-10-07 15:09:23.923621 | controller | SSH_CLIENT=192.168.26.12 36642 22 2025-10-07 15:09:23.923629 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/ 2025-10-07 15:09:23.923635 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima: 2025-10-07 15:09:23.923641 | controller | which_declare=declare -f 2025-10-07 15:09:23.923647 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin 2025-10-07 15:09:23.923653 | controller | SELINUX_LEVEL_REQUESTED= 2025-10-07 15:09:23.923659 | controller | ZUUL_CHANGE=3368 2025-10-07 15:09:23.923665 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus 2025-10-07 15:09:23.923670 | controller | ZUUL_UUID=c6a8f38485074d3a881ba1725ff81a91 2025-10-07 15:09:23.923676 | controller | BASH_FUNC_which%%=() { ( alias; 2025-10-07 15:09:23.923681 | controller | eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@ 2025-10-07 15:09:23.923687 | controller | } 2025-10-07 15:09:24.220978 | controller | ok: Runtime: 0:00:00.008814 2025-10-07 15:09:24.226843 | 2025-10-07 15:09:24.226927 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars] 2025-10-07 15:09:24.240363 | controller | skipping: Conditional result was False 2025-10-07 15:09:24.246405 | 2025-10-07 15:09:24.246491 | TASK [Symlink /home/zuul-worker/workspace] 2025-10-07 15:09:24.773848 | controller | skipping: Conditional result was False 2025-10-07 15:09:24.779690 | 2025-10-07 15:09:24.779777 | TASK [Ensure legacy workspace directory] 2025-10-07 15:09:24.991379 | controller | changed 2025-10-07 15:09:25.010327 | 2025-10-07 15:09:25.010421 | PLAY RECAP 2025-10-07 15:09:25.010498 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0 2025-10-07 15:09:25.010530 | 2025-10-07 15:09:25.088677 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master] 2025-10-07 15:09:25.089432 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main] 2025-10-07 15:09:25.637208 | 2025-10-07 15:09:25.637324 | PLAY [Run ci/playbooks/dump_zuul_data.yml] 2025-10-07 15:09:25.657922 | 2025-10-07 15:09:25.658001 | TASK [Create zuul-output directory] 2025-10-07 15:09:25.982949 | controller | changed 2025-10-07 15:09:25.988643 | 2025-10-07 15:09:25.988721 | TASK [Slurp Zuul inventory test] 2025-10-07 15:09:26.265442 | controller -> localhost | ok 2025-10-07 15:09:26.272553 | 2025-10-07 15:09:26.272642 | TASK [Save zuul inventory] 2025-10-07 15:09:27.010222 | controller | changed 2025-10-07 15:09:27.015471 | 2025-10-07 15:09:27.015543 | TASK [Save zuul vars without the change_message] 2025-10-07 15:09:27.592116 | controller | changed 2025-10-07 15:09:27.611494 | 2025-10-07 15:09:27.611563 | PLAY RECAP 2025-10-07 15:09:27.611606 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-07 15:09:27.611630 | 2025-10-07 15:09:27.679629 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main] 2025-10-07 15:09:27.680412 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main] 2025-10-07 15:09:28.314990 | 2025-10-07 15:09:28.315095 | PLAY [Run ci/playbooks/molecule-prepare.yml] 2025-10-07 15:09:28.347568 | 2025-10-07 15:09:28.347656 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh] 2025-10-07 15:09:28.366026 | controller | ok 2025-10-07 15:09:28.383789 | 2025-10-07 15:09:28.383871 | TASK [mirror-info-fork : Set mirror_fqdn if not defined] 2025-10-07 15:09:28.407115 | controller | skipping: Conditional result was False 2025-10-07 15:09:28.412935 | 2025-10-07 15:09:28.413008 | TASK [mirror-info-fork : Create /etc/ci] 2025-10-07 15:09:28.745617 | controller | ok 2025-10-07 15:09:28.751401 | 2025-10-07 15:09:28.751483 | TASK [mirror-info-fork : Install ci_mirror script] 2025-10-07 15:09:29.417815 | controller | ok 2025-10-07 15:09:29.427992 | 2025-10-07 15:09:29.428065 | TASK [Prepare workspace] 2025-10-07 15:09:29.445865 | controller | ok 2025-10-07 15:09:29.462626 | 2025-10-07 15:09:29.462698 | TASK [prepare-workspace : Start zuul_console daemon.] 2025-10-07 15:09:29.774613 | controller | ok 2025-10-07 15:09:29.781417 | 2025-10-07 15:09:29.781501 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2025-10-07 15:09:31.820215 | controller | Output suppressed because no_log was given 2025-10-07 15:09:31.830339 | 2025-10-07 15:09:31.830410 | LOOP [Create zuul-output directory] 2025-10-07 15:09:32.032556 | controller | changed: "/home/zuul/ci-framework-data/logs" 2025-10-07 15:09:32.212538 | controller | ok: "/home/zuul/zuul-output/logs" 2025-10-07 15:09:32.227482 | 2025-10-07 15:09:32.227587 | TASK [Install required packages] 2025-10-07 15:10:26.712818 | controller | changed 2025-10-07 15:10:26.719408 | 2025-10-07 15:10:26.719470 | TASK [Install venv] 2025-10-07 15:11:30.594866 | controller | changed 2025-10-07 15:11:30.625529 | 2025-10-07 15:11:30.625641 | PLAY RECAP 2025-10-07 15:11:30.625686 | controller | ok: 7 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2025-10-07 15:11:30.625710 | 2025-10-07 15:11:30.707336 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main] 2025-10-07 15:11:30.708165 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main] 2025-10-07 15:11:31.249215 | 2025-10-07 15:11:31.249314 | PLAY [Run ci/playbooks/molecule-test.yml] 2025-10-07 15:11:31.269807 | 2025-10-07 15:11:31.269880 | TASK [Gather required facts] 2025-10-07 15:11:31.784845 | controller | ok 2025-10-07 15:11:31.790373 | 2025-10-07 15:11:31.790447 | TASK [Load environment var if instructed to] 2025-10-07 15:11:31.814263 | controller | skipping: Conditional result was False 2025-10-07 15:11:31.820164 | 2025-10-07 15:11:31.820263 | TASK [Run molecule] 2025-10-07 15:11:32.806457 | controller | INFO Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml 2025-10-07 15:11:32.885248 | controller | INFO Performing prerun with role_name_check=0... 2025-10-07 15:11:50.049198 | controller | WARNING Another version of '@NAMESPACE@.@NAME@' 3.1.4 was found installed in /home/zuul/.cache/ansible-compat/e24347/collections/ansible_collections, only the first one will be used, 3.1.4 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-07 15:11:50.049843 | controller | WARNING Another version of 'ansible.netcommon' 7.1.0 was found installed in /home/zuul/.cache/ansible-compat/e24347/collections/ansible_collections, only the first one will be used, 7.1.0 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-07 15:11:50.050369 | controller | WARNING Another version of 'ansible.posix' 1.6.2 was found installed in /home/zuul/.cache/ansible-compat/e24347/collections/ansible_collections, only the first one will be used, 1.6.2 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-07 15:11:50.050897 | controller | WARNING Another version of 'ansible.utils' 5.1.2 was found installed in /home/zuul/.cache/ansible-compat/e24347/collections/ansible_collections, only the first one will be used, 5.1.2 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-07 15:11:50.051412 | controller | WARNING Another version of 'cifmw.general' 1.0.0+3a665326 was found installed in /home/zuul/.cache/ansible-compat/e24347/collections/ansible_collections, only the first one will be used, 1.0.0+3a665326 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-07 15:11:50.051955 | controller | WARNING Another version of 'cisco.ios' 9.0.3 was found installed in /home/zuul/.cache/ansible-compat/e24347/collections/ansible_collections, only the first one will be used, 9.0.3 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-07 15:11:50.052442 | controller | WARNING Another version of 'community.crypto' 2.22.3 was found installed in /home/zuul/.cache/ansible-compat/e24347/collections/ansible_collections, only the first one will be used, 2.22.3 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-07 15:11:50.052962 | controller | WARNING Another version of 'community.general' 10.0.1 was found installed in /home/zuul/.cache/ansible-compat/e24347/collections/ansible_collections, only the first one will be used, 10.0.1 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-07 15:11:50.053449 | controller | WARNING Another version of 'community.libvirt' 1.3.0 was found installed in /home/zuul/.cache/ansible-compat/e24347/collections/ansible_collections, only the first one will be used, 1.3.0 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-07 15:11:50.053953 | controller | WARNING Another version of 'community.okd' 4.0.0 was found installed in /home/zuul/.cache/ansible-compat/e24347/collections/ansible_collections, only the first one will be used, 4.0.0 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-07 15:11:50.054431 | controller | WARNING Another version of 'containers.podman' 1.16.2 was found installed in /home/zuul/.cache/ansible-compat/e24347/collections/ansible_collections, only the first one will be used, 1.16.2 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-07 15:11:50.054932 | controller | WARNING Another version of 'junipernetworks.junos' 9.1.0 was found installed in /home/zuul/.cache/ansible-compat/e24347/collections/ansible_collections, only the first one will be used, 9.1.0 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-07 15:11:50.055407 | controller | WARNING Another version of 'kubernetes.core' 5.0.0 was found installed in /home/zuul/.cache/ansible-compat/e24347/collections/ansible_collections, only the first one will be used, 5.0.0 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-07 15:11:50.055997 | controller | WARNING Another version of 'mellanox.onyx' 1.0.0 was found installed in /home/zuul/.cache/ansible-compat/e24347/collections/ansible_collections, only the first one will be used, 1.0.0 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-07 15:11:50.056491 | controller | WARNING Another version of 'openstack.config_template' 2.1.1 was found installed in /home/zuul/.cache/ansible-compat/e24347/collections/ansible_collections, only the first one will be used, 2.1.1 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-07 15:11:50.066371 | controller | INFO Running default > prepare 2025-10-07 15:11:50.724528 | controller | 2025-10-07 15:11:51.555708 | controller | PLAY [Prepare] ***************************************************************** 2025-10-07 15:11:51.555741 | controller | 2025-10-07 15:11:51.555770 | controller | TASK [Gathering Facts] ********************************************************* 2025-10-07 15:11:51.555778 | controller | Tuesday 07 October 2025 15:11:50 +0000 (0:00:00.012) 0:00:00.012 ******* 2025-10-07 15:11:51.555790 | controller | ok: [instance] 2025-10-07 15:11:51.555812 | controller | 2025-10-07 15:11:51.555821 | controller | TASK [test_deps : Gather facts if they don't exist] **************************** 2025-10-07 15:11:51.555914 | controller | Tuesday 07 October 2025 15:11:51 +0000 (0:00:00.832) 0:00:00.844 ******* 2025-10-07 15:11:51.572564 | controller | skipping: [instance] 2025-10-07 15:11:51.572709 | controller | 2025-10-07 15:11:51.572840 | controller | TASK [test_deps : Gather variables for each operating system] ****************** 2025-10-07 15:11:51.572942 | controller | Tuesday 07 October 2025 15:11:51 +0000 (0:00:00.017) 0:00:00.862 ******* 2025-10-07 15:11:51.613273 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/test_deps/vars/centos-9.yml) 2025-10-07 15:11:51.613385 | controller | 2025-10-07 15:11:51.613507 | controller | TASK [test_deps : Detect mirrors file] ***************************************** 2025-10-07 15:11:51.613637 | controller | Tuesday 07 October 2025 15:11:51 +0000 (0:00:00.040) 0:00:00.902 ******* 2025-10-07 15:11:51.887525 | controller | ok: [instance] 2025-10-07 15:11:51.887612 | controller | 2025-10-07 15:11:51.887724 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] *********** 2025-10-07 15:11:51.887839 | controller | Tuesday 07 October 2025 15:11:51 +0000 (0:00:00.274) 0:00:01.176 ******* 2025-10-07 15:11:52.196022 | controller | ok: [instance] 2025-10-07 15:11:52.196111 | controller | 2025-10-07 15:11:52.196228 | controller | TASK [test_deps : Disable ubi 9 repos] ***************************************** 2025-10-07 15:11:52.196344 | controller | Tuesday 07 October 2025 15:11:52 +0000 (0:00:00.308) 0:00:01.485 ******* 2025-10-07 15:11:52.888796 | controller | ok: [instance] => (item=ubi-9-appstream) 2025-10-07 15:11:52.888847 | controller | ok: [instance] => (item=ubi-9-baseos) 2025-10-07 15:11:52.888956 | controller | ok: [instance] => (item=ubi-9-codeready-builder) 2025-10-07 15:11:52.889047 | controller | 2025-10-07 15:11:52.889147 | controller | TASK [Deploy repo-setup] ******************************************************* 2025-10-07 15:11:52.889256 | controller | Tuesday 07 October 2025 15:11:52 +0000 (0:00:00.692) 0:00:02.178 ******* 2025-10-07 15:11:52.946276 | controller | 2025-10-07 15:11:52.946395 | controller | TASK [repo_setup : Ensure directories are present] ***************************** 2025-10-07 15:11:52.946503 | controller | Tuesday 07 October 2025 15:11:52 +0000 (0:00:00.057) 0:00:02.235 ******* 2025-10-07 15:11:53.511686 | controller | changed: [instance] => (item=tmp) 2025-10-07 15:11:53.511819 | controller | changed: [instance] => (item=artifacts/repositories) 2025-10-07 15:11:53.511955 | controller | changed: [instance] => (item=venv/repo_setup) 2025-10-07 15:11:53.512078 | controller | 2025-10-07 15:11:53.512207 | controller | TASK [repo_setup : Make sure git-core package is installed] ******************** 2025-10-07 15:11:53.512331 | controller | Tuesday 07 October 2025 15:11:53 +0000 (0:00:00.565) 0:00:02.800 ******* 2025-10-07 15:11:54.322920 | controller | ok: [instance] 2025-10-07 15:11:54.323363 | controller | 2025-10-07 15:11:55.459455 | controller | TASK [repo_setup : Get repo-setup repository] ********************************** 2025-10-07 15:11:55.459486 | controller | Tuesday 07 October 2025 15:11:54 +0000 (0:00:00.811) 0:00:03.612 ******* 2025-10-07 15:11:55.459498 | controller | changed: [instance] 2025-10-07 15:12:03.397944 | controller | 2025-10-07 15:12:03.397986 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************ 2025-10-07 15:12:03.397997 | controller | Tuesday 07 October 2025 15:11:55 +0000 (0:00:01.136) 0:00:04.748 ******* 2025-10-07 15:12:03.398008 | controller | changed: [instance] 2025-10-07 15:12:04.138186 | controller | 2025-10-07 15:12:04.138225 | controller | TASK [repo_setup : Install repo-setup package] ********************************* 2025-10-07 15:12:04.138233 | controller | Tuesday 07 October 2025 15:12:03 +0000 (0:00:07.937) 0:00:12.686 ******* 2025-10-07 15:12:04.138243 | controller | changed: [instance] 2025-10-07 15:12:04.162247 | controller | 2025-10-07 15:12:04.162274 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] *** 2025-10-07 15:12:04.162282 | controller | Tuesday 07 October 2025 15:12:04 +0000 (0:00:00.740) 0:00:13.426 ******* 2025-10-07 15:12:04.162291 | controller | skipping: [instance] 2025-10-07 15:12:05.037719 | controller | 2025-10-07 15:12:05.037749 | controller | TASK [repo_setup : Run repo-setup] ********************************************* 2025-10-07 15:12:05.037758 | controller | Tuesday 07 October 2025 15:12:04 +0000 (0:00:00.024) 0:00:13.451 ******* 2025-10-07 15:12:05.037767 | controller | changed: [instance] 2025-10-07 15:12:05.074519 | controller | 2025-10-07 15:12:05.074547 | controller | TASK [repo_setup : Get component repo] ***************************************** 2025-10-07 15:12:05.074554 | controller | Tuesday 07 October 2025 15:12:05 +0000 (0:00:00.875) 0:00:14.326 ******* 2025-10-07 15:12:05.074564 | controller | skipping: [instance] 2025-10-07 15:12:05.110777 | controller | 2025-10-07 15:12:05.110875 | controller | TASK [repo_setup : Rename component repo] ************************************** 2025-10-07 15:12:05.110885 | controller | Tuesday 07 October 2025 15:12:05 +0000 (0:00:00.036) 0:00:14.363 ******* 2025-10-07 15:12:05.110900 | controller | skipping: [instance] 2025-10-07 15:12:05.111004 | controller | 2025-10-07 15:12:05.111062 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] ******* 2025-10-07 15:12:05.111080 | controller | Tuesday 07 October 2025 15:12:05 +0000 (0:00:00.036) 0:00:14.399 ******* 2025-10-07 15:12:05.146735 | controller | skipping: [instance] 2025-10-07 15:12:05.720024 | controller | 2025-10-07 15:12:05.720057 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************ 2025-10-07 15:12:05.720066 | controller | Tuesday 07 October 2025 15:12:05 +0000 (0:00:00.036) 0:00:14.435 ******* 2025-10-07 15:12:05.720076 | controller | changed: [instance] 2025-10-07 15:12:06.192655 | controller | 2025-10-07 15:12:06.192687 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] ******************* 2025-10-07 15:12:06.192696 | controller | Tuesday 07 October 2025 15:12:05 +0000 (0:00:00.573) 0:00:15.008 ******* 2025-10-07 15:12:06.192706 | controller | changed: [instance] 2025-10-07 15:12:06.192724 | controller | 2025-10-07 15:12:06.192731 | controller | TASK [repo_setup : Dump current-podified hash] ********************************* 2025-10-07 15:12:06.192738 | controller | Tuesday 07 October 2025 15:12:06 +0000 (0:00:00.471) 0:00:15.480 ******* 2025-10-07 15:12:06.230653 | controller | skipping: [instance] 2025-10-07 15:12:06.261643 | controller | 2025-10-07 15:12:06.261669 | controller | TASK [repo_setup : Slurp current podified hash] ******************************** 2025-10-07 15:12:06.261676 | controller | Tuesday 07 October 2025 15:12:06 +0000 (0:00:00.038) 0:00:15.519 ******* 2025-10-07 15:12:06.261685 | controller | skipping: [instance] 2025-10-07 15:12:06.261771 | controller | 2025-10-07 15:12:06.261781 | controller | TASK [repo_setup : Update the value of full_hash] ****************************** 2025-10-07 15:12:06.261789 | controller | Tuesday 07 October 2025 15:12:06 +0000 (0:00:00.031) 0:00:15.550 ******* 2025-10-07 15:12:06.292632 | controller | skipping: [instance] 2025-10-07 15:12:06.292976 | controller | 2025-10-07 15:12:06.337645 | controller | TASK [repo_setup : Export hashes facts for further use] ************************ 2025-10-07 15:12:06.337709 | controller | Tuesday 07 October 2025 15:12:06 +0000 (0:00:00.031) 0:00:15.582 ******* 2025-10-07 15:12:06.337724 | controller | ok: [instance] 2025-10-07 15:12:06.372158 | controller | 2025-10-07 15:12:06.372189 | controller | TASK [repo_setup : Create download directory] ********************************** 2025-10-07 15:12:06.372197 | controller | Tuesday 07 October 2025 15:12:06 +0000 (0:00:00.043) 0:00:15.625 ******* 2025-10-07 15:12:06.372209 | controller | skipping: [instance] 2025-10-07 15:12:06.401049 | controller | 2025-10-07 15:12:06.401080 | controller | TASK [repo_setup : Print the URL to request] *********************************** 2025-10-07 15:12:06.401088 | controller | Tuesday 07 October 2025 15:12:06 +0000 (0:00:00.035) 0:00:15.661 ******* 2025-10-07 15:12:06.401111 | controller | skipping: [instance] 2025-10-07 15:12:06.429031 | controller | 2025-10-07 15:12:06.429074 | controller | TASK [Download the RPM] ******************************************************** 2025-10-07 15:12:06.429083 | controller | Tuesday 07 October 2025 15:12:06 +0000 (0:00:00.028) 0:00:15.689 ******* 2025-10-07 15:12:06.429094 | controller | skipping: [instance] 2025-10-07 15:12:06.456714 | controller | 2025-10-07 15:12:06.456763 | controller | TASK [repo_setup : Install RHOS Release tool] ********************************** 2025-10-07 15:12:06.456772 | controller | Tuesday 07 October 2025 15:12:06 +0000 (0:00:00.027) 0:00:15.717 ******* 2025-10-07 15:12:06.456784 | controller | skipping: [instance] 2025-10-07 15:12:06.456813 | controller | 2025-10-07 15:12:06.457023 | controller | TASK [repo_setup : Get rhos-release tool version] ****************************** 2025-10-07 15:12:06.486255 | controller | Tuesday 07 October 2025 15:12:06 +0000 (0:00:00.028) 0:00:15.746 ******* 2025-10-07 15:12:06.486284 | controller | skipping: [instance] 2025-10-07 15:12:06.517055 | controller | 2025-10-07 15:12:06.517077 | controller | TASK [repo_setup : Print rhos-release tool version] **************************** 2025-10-07 15:12:06.517086 | controller | Tuesday 07 October 2025 15:12:06 +0000 (0:00:00.029) 0:00:15.775 ******* 2025-10-07 15:12:06.517097 | controller | skipping: [instance] 2025-10-07 15:12:06.543685 | controller | 2025-10-07 15:12:06.543730 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] *** 2025-10-07 15:12:06.543739 | controller | Tuesday 07 October 2025 15:12:06 +0000 (0:00:00.030) 0:00:15.806 ******* 2025-10-07 15:12:06.543758 | controller | skipping: [instance] 2025-10-07 15:12:06.741515 | controller | 2025-10-07 15:12:06.741557 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] *************************** 2025-10-07 15:12:06.741565 | controller | Tuesday 07 October 2025 15:12:06 +0000 (0:00:00.026) 0:00:15.833 ******* 2025-10-07 15:12:06.741592 | controller | ok: [instance] 2025-10-07 15:12:06.988756 | controller | 2025-10-07 15:12:06.988793 | controller | TASK [repo_setup : Use RDO proxy mirrors] ************************************** 2025-10-07 15:12:06.988801 | controller | Tuesday 07 October 2025 15:12:06 +0000 (0:00:00.197) 0:00:16.030 ******* 2025-10-07 15:12:06.988811 | controller | changed: [instance] 2025-10-07 15:12:07.237593 | controller | 2025-10-07 15:12:07.237626 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] *** 2025-10-07 15:12:07.237634 | controller | Tuesday 07 October 2025 15:12:06 +0000 (0:00:00.246) 0:00:16.277 ******* 2025-10-07 15:12:07.237645 | controller | changed: [instance] 2025-10-07 15:12:07.261863 | controller | 2025-10-07 15:12:07.261894 | controller | TASK [repo_setup : Check for gating.repo file on content provider] ************* 2025-10-07 15:12:07.261902 | controller | Tuesday 07 October 2025 15:12:07 +0000 (0:00:00.249) 0:00:16.526 ******* 2025-10-07 15:12:07.261912 | controller | skipping: [instance] 2025-10-07 15:12:07.288550 | controller | 2025-10-07 15:12:07.288596 | controller | TASK [repo_setup : Populate gating repo from content provider ip] ************** 2025-10-07 15:12:07.288606 | controller | Tuesday 07 October 2025 15:12:07 +0000 (0:00:00.024) 0:00:16.550 ******* 2025-10-07 15:12:07.288616 | controller | skipping: [instance] 2025-10-07 15:12:07.314688 | controller | 2025-10-07 15:12:07.314723 | controller | TASK [repo_setup : Check for DLRN repo at the destination] ********************* 2025-10-07 15:12:07.314732 | controller | Tuesday 07 October 2025 15:12:07 +0000 (0:00:00.026) 0:00:16.577 ******* 2025-10-07 15:12:07.314742 | controller | skipping: [instance] 2025-10-07 15:12:07.314956 | controller | 2025-10-07 15:12:07.314986 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] *** 2025-10-07 15:12:07.343681 | controller | Tuesday 07 October 2025 15:12:07 +0000 (0:00:00.026) 0:00:16.604 ******* 2025-10-07 15:12:07.343714 | controller | skipping: [instance] 2025-10-07 15:12:07.343742 | controller | 2025-10-07 15:12:07.343753 | controller | TASK [repo_setup : Check for DLRN component repo] ****************************** 2025-10-07 15:12:07.343761 | controller | Tuesday 07 October 2025 15:12:07 +0000 (0:00:00.028) 0:00:16.633 ******* 2025-10-07 15:12:07.368016 | controller | skipping: [instance] 2025-10-07 15:12:07.392277 | controller | 2025-10-07 15:12:07.392300 | controller | TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] *** 2025-10-07 15:12:07.392317 | controller | Tuesday 07 October 2025 15:12:07 +0000 (0:00:00.024) 0:00:16.657 ******* 2025-10-07 15:12:07.392326 | controller | skipping: [instance] 2025-10-07 15:12:07.412085 | controller | 2025-10-07 15:12:07.412105 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ******** 2025-10-07 15:12:07.412112 | controller | Tuesday 07 October 2025 15:12:07 +0000 (0:00:00.024) 0:00:16.681 ******* 2025-10-07 15:12:07.412122 | controller | skipping: [instance] 2025-10-07 15:12:07.447118 | controller | 2025-10-07 15:12:07.447148 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ****** 2025-10-07 15:12:07.447156 | controller | Tuesday 07 October 2025 15:12:07 +0000 (0:00:00.019) 0:00:16.701 ******* 2025-10-07 15:12:07.447165 | controller | skipping: [instance] 2025-10-07 15:12:07.467822 | controller | 2025-10-07 15:12:07.467862 | controller | TASK [repo_setup : Cleanup existing metadata] ********************************** 2025-10-07 15:12:07.467871 | controller | Tuesday 07 October 2025 15:12:07 +0000 (0:00:00.034) 0:00:16.736 ******* 2025-10-07 15:12:07.467881 | controller | skipping: [instance] 2025-10-07 15:12:07.492383 | controller | 2025-10-07 15:12:07.492405 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] ********* 2025-10-07 15:12:07.492412 | controller | Tuesday 07 October 2025 15:12:07 +0000 (0:00:00.020) 0:00:16.757 ******* 2025-10-07 15:12:07.492421 | controller | skipping: [instance] 2025-10-07 15:12:07.532083 | controller | 2025-10-07 15:12:07.532105 | controller | TASK [test_deps : Look for redhat-release rpm] ********************************* 2025-10-07 15:12:07.532112 | controller | Tuesday 07 October 2025 15:12:07 +0000 (0:00:00.024) 0:00:16.781 ******* 2025-10-07 15:12:07.532120 | controller | skipping: [instance] 2025-10-07 15:12:07.570661 | controller | 2025-10-07 15:12:07.570689 | controller | TASK [test_deps : Remove redhat-release] *************************************** 2025-10-07 15:12:07.570699 | controller | Tuesday 07 October 2025 15:12:07 +0000 (0:00:00.039) 0:00:16.820 ******* 2025-10-07 15:12:07.570710 | controller | skipping: [instance] 2025-10-07 15:12:07.612258 | controller | 2025-10-07 15:12:07.612277 | controller | TASK [test_deps : Install centos-stream-release] ******************************* 2025-10-07 15:12:07.612284 | controller | Tuesday 07 October 2025 15:12:07 +0000 (0:00:00.038) 0:00:16.859 ******* 2025-10-07 15:12:07.612293 | controller | skipping: [instance] 2025-10-07 15:12:07.652746 | controller | 2025-10-07 15:12:07.652814 | controller | TASK [test_deps : Install additional packages] ********************************* 2025-10-07 15:12:07.652845 | controller | Tuesday 07 October 2025 15:12:07 +0000 (0:00:00.041) 0:00:16.901 ******* 2025-10-07 15:12:07.652862 | controller | skipping: [instance] 2025-10-07 15:13:07.440421 | controller | 2025-10-07 15:13:07.440454 | controller | TASK [test_deps : Install selinux python libs] ********************************* 2025-10-07 15:13:07.440462 | controller | Tuesday 07 October 2025 15:12:07 +0000 (0:00:00.040) 0:00:16.941 ******* 2025-10-07 15:13:07.440473 | controller | ok: [instance] 2025-10-07 15:13:08.617364 | controller | 2025-10-07 15:13:08.617394 | controller | TASK [test_deps : Install python yaml libs] ************************************ 2025-10-07 15:13:08.617403 | controller | Tuesday 07 October 2025 15:13:07 +0000 (0:00:59.787) 0:01:16.729 ******* 2025-10-07 15:13:08.617413 | controller | ok: [instance] 2025-10-07 15:13:08.649911 | controller | 2025-10-07 15:13:08.649930 | controller | TASK [test_deps : Install extra packages] ************************************** 2025-10-07 15:13:08.649938 | controller | Tuesday 07 October 2025 15:13:08 +0000 (0:00:01.176) 0:01:17.906 ******* 2025-10-07 15:13:08.649947 | controller | skipping: [instance] 2025-10-07 15:13:09.038283 | controller | 2025-10-07 15:13:09.038313 | controller | TASK [Create 1st dummy interface with needed IPs] ****************************** 2025-10-07 15:13:09.038321 | controller | Tuesday 07 October 2025 15:13:08 +0000 (0:00:00.032) 0:01:17.939 ******* 2025-10-07 15:13:09.038330 | controller | changed: [instance] 2025-10-07 15:13:09.302648 | controller | 2025-10-07 15:13:09.302677 | controller | TASK [Create 2nd dummy interface with needed IPs] ****************************** 2025-10-07 15:13:09.302685 | controller | Tuesday 07 October 2025 15:13:09 +0000 (0:00:00.387) 0:01:18.327 ******* 2025-10-07 15:13:09.302694 | controller | changed: [instance] 2025-10-07 15:13:09.302745 | controller | 2025-10-07 15:13:09.302753 | controller | TASK [Create 3nd dummy interface with needed IPs] ****************************** 2025-10-07 15:13:09.302761 | controller | Tuesday 07 October 2025 15:13:09 +0000 (0:00:00.264) 0:01:18.591 ******* 2025-10-07 15:13:09.580677 | controller | changed: [instance] 2025-10-07 15:13:09.580728 | controller | 2025-10-07 15:13:09.580738 | controller | PLAY RECAP ********************************************************************* 2025-10-07 15:13:09.580746 | controller | instance : ok=22 changed=12 unreachable=0 failed=0 skipped=30 rescued=0 ignored=0 2025-10-07 15:13:09.580754 | controller | 2025-10-07 15:13:09.580761 | controller | Tuesday 07 October 2025 15:13:09 +0000 (0:00:00.277) 0:01:18.869 ******* 2025-10-07 15:13:09.580774 | controller | =============================================================================== 2025-10-07 15:13:09.580781 | controller | test_deps : Install selinux python libs -------------------------------- 59.79s 2025-10-07 15:13:09.580787 | controller | repo_setup : Initialize python venv and install requirements ------------ 7.94s 2025-10-07 15:13:09.580794 | controller | test_deps : Install python yaml libs ------------------------------------ 1.18s 2025-10-07 15:13:09.580803 | controller | repo_setup : Get repo-setup repository ---------------------------------- 1.14s 2025-10-07 15:13:09.580828 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.88s 2025-10-07 15:13:09.582233 | controller | Gathering Facts --------------------------------------------------------- 0.83s 2025-10-07 15:13:09.649090 | controller | repo_setup : Make sure git-core package is installed -------------------- 0.81s 2025-10-07 15:13:09.649117 | controller | repo_setup : Install repo-setup package --------------------------------- 0.74s 2025-10-07 15:13:09.649124 | controller | test_deps : Disable ubi 9 repos ----------------------------------------- 0.69s 2025-10-07 15:13:09.649130 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.57s 2025-10-07 15:13:09.649135 | controller | repo_setup : Ensure directories are present ----------------------------- 0.57s 2025-10-07 15:13:09.649157 | controller | repo_setup : Dump full hash in delorean.repo.md5 file ------------------- 0.47s 2025-10-07 15:13:09.649164 | controller | Create 1st dummy interface with needed IPs ------------------------------ 0.39s 2025-10-07 15:13:09.649169 | controller | test_deps : Disable ubi host subscription-manager integration ----------- 0.31s 2025-10-07 15:13:09.649174 | controller | Create 3nd dummy interface with needed IPs ------------------------------ 0.28s 2025-10-07 15:13:09.649180 | controller | test_deps : Detect mirrors file ----------------------------------------- 0.27s 2025-10-07 15:13:09.649185 | controller | Create 2nd dummy interface with needed IPs ------------------------------ 0.26s 2025-10-07 15:13:09.649190 | controller | repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist) --- 0.25s 2025-10-07 15:13:09.649196 | controller | repo_setup : Use RDO proxy mirrors -------------------------------------- 0.25s 2025-10-07 15:13:09.649201 | controller | repo_setup : Check for /etc/ci/mirror_info.sh --------------------------- 0.20s 2025-10-07 15:13:09.649211 | controller | INFO Running default > converge 2025-10-07 15:13:10.095100 | controller | 2025-10-07 15:13:10.937502 | controller | PLAY [Converge] **************************************************************** 2025-10-07 15:13:10.937533 | controller | 2025-10-07 15:13:10.937541 | controller | TASK [Gathering Facts] ********************************************************* 2025-10-07 15:13:10.937547 | controller | Tuesday 07 October 2025 15:13:10 +0000 (0:00:00.017) 0:00:00.017 ******* 2025-10-07 15:13:10.937556 | controller | ok: [instance] 2025-10-07 15:13:14.496632 | controller | 2025-10-07 15:13:14.496663 | controller | TASK [dnsmasq : Install needed packages] *************************************** 2025-10-07 15:13:14.496672 | controller | Tuesday 07 October 2025 15:13:10 +0000 (0:00:00.842) 0:00:00.860 ******* 2025-10-07 15:13:14.496682 | controller | changed: [instance] 2025-10-07 15:13:17.996292 | controller | 2025-10-07 15:13:17.996321 | controller | TASK [dnsmasq : Ensure SELinux knows about the new files and directories] ****** 2025-10-07 15:13:17.996329 | controller | Tuesday 07 October 2025 15:13:14 +0000 (0:00:03.559) 0:00:04.419 ******* 2025-10-07 15:13:17.996340 | controller | changed: [instance] => (item={'target': '/etc/cifmw-dnsmasq.conf', 'setype': 'dnsmasq_etc_t'}) 2025-10-07 15:13:18.306663 | controller | changed: [instance] => (item={'target': '/etc/cifmw-dnsmasq.d(/.*)?', 'setype': 'dnsmasq_etc_t'}) 2025-10-07 15:13:18.306699 | controller | changed: [instance] => (item={'target': '/var/run/cifmw-dnsmasq.pid', 'setype': 'dnsmasq_var_run_t'}) 2025-10-07 15:13:18.306707 | controller | 2025-10-07 15:13:18.306713 | controller | TASK [dnsmasq : Manage configuration directory] ******************************** 2025-10-07 15:13:18.306719 | controller | Tuesday 07 October 2025 15:13:17 +0000 (0:00:03.499) 0:00:07.918 ******* 2025-10-07 15:13:18.306729 | controller | changed: [instance] 2025-10-07 15:13:18.306756 | controller | 2025-10-07 15:13:18.307027 | controller | TASK [dnsmasq : Manage systemd unit file] ************************************** 2025-10-07 15:13:18.895257 | controller | Tuesday 07 October 2025 15:13:18 +0000 (0:00:00.310) 0:00:08.229 ******* 2025-10-07 15:13:18.895293 | controller | changed: [instance] 2025-10-07 15:13:19.317100 | controller | 2025-10-07 15:13:19.317131 | controller | TASK [dnsmasq : Manage base configuration file] ******************************** 2025-10-07 15:13:19.317140 | controller | Tuesday 07 October 2025 15:13:18 +0000 (0:00:00.587) 0:00:08.817 ******* 2025-10-07 15:13:19.317150 | controller | changed: [instance] 2025-10-07 15:13:19.345812 | controller | 2025-10-07 15:13:19.345889 | controller | TASK [dnsmasq : Render listener configuration] ********************************* 2025-10-07 15:13:19.345899 | controller | Tuesday 07 October 2025 15:13:19 +0000 (0:00:00.422) 0:00:09.239 ******* 2025-10-07 15:13:19.345915 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/dnsmasq/tasks/listener.yml for instance 2025-10-07 15:13:19.346001 | controller | 2025-10-07 15:13:19.346045 | controller | TASK [dnsmasq : Create listener configuration file] **************************** 2025-10-07 15:13:19.786737 | controller | Tuesday 07 October 2025 15:13:19 +0000 (0:00:00.029) 0:00:09.268 ******* 2025-10-07 15:13:19.786771 | controller | changed: [instance] 2025-10-07 15:13:19.811824 | controller | 2025-10-07 15:13:19.811842 | controller | TASK [dnsmasq : Remove listener configuration file] **************************** 2025-10-07 15:13:19.811850 | controller | Tuesday 07 October 2025 15:13:19 +0000 (0:00:00.440) 0:00:09.709 ******* 2025-10-07 15:13:19.811859 | controller | skipping: [instance] 2025-10-07 15:13:19.842714 | controller | 2025-10-07 15:13:19.842837 | controller | TASK [dnsmasq : Render dns configuration] ************************************** 2025-10-07 15:13:19.842849 | controller | Tuesday 07 October 2025 15:13:19 +0000 (0:00:00.025) 0:00:09.734 ******* 2025-10-07 15:13:19.842873 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/dnsmasq/tasks/dns.yml for instance 2025-10-07 15:13:20.278219 | controller | 2025-10-07 15:13:20.278257 | controller | TASK [dnsmasq : Create dns configuration file] ********************************* 2025-10-07 15:13:20.278266 | controller | Tuesday 07 October 2025 15:13:19 +0000 (0:00:00.030) 0:00:09.765 ******* 2025-10-07 15:13:20.278278 | controller | changed: [instance] 2025-10-07 15:13:20.296735 | controller | 2025-10-07 15:13:20.296767 | controller | TASK [dnsmasq : Remove dns configuration file] ********************************* 2025-10-07 15:13:20.296775 | controller | Tuesday 07 October 2025 15:13:20 +0000 (0:00:00.435) 0:00:10.201 ******* 2025-10-07 15:13:20.296785 | controller | skipping: [instance] 2025-10-07 15:13:20.321669 | controller | 2025-10-07 15:13:20.321701 | controller | TASK [dnsmasq : Add localhost addresses from defined dnsmasq listen addresses to loopback interface] *** 2025-10-07 15:13:20.321709 | controller | Tuesday 07 October 2025 15:13:20 +0000 (0:00:00.018) 0:00:10.219 ******* 2025-10-07 15:13:20.321719 | controller | skipping: [instance] => (item=192.168.253.9) 2025-10-07 15:13:20.321745 | controller | skipping: [instance] => (item=192.168.254.9) 2025-10-07 15:13:20.321753 | controller | skipping: [instance] 2025-10-07 15:13:20.322141 | controller | 2025-10-07 15:13:21.201085 | controller | TASK [dnsmasq : Enable and start service] ************************************** 2025-10-07 15:13:21.201117 | controller | Tuesday 07 October 2025 15:13:20 +0000 (0:00:00.025) 0:00:10.244 ******* 2025-10-07 15:13:21.201129 | controller | changed: [instance] 2025-10-07 15:13:21.219047 | controller | 2025-10-07 15:13:21.219074 | controller | TASK [dnsmasq : Stop service] ************************************************** 2025-10-07 15:13:21.219088 | controller | Tuesday 07 October 2025 15:13:21 +0000 (0:00:00.879) 0:00:11.123 ******* 2025-10-07 15:13:21.219099 | controller | skipping: [instance] 2025-10-07 15:13:21.237390 | controller | 2025-10-07 15:13:21.237419 | controller | TASK [dnsmasq : Remove unit file] ********************************************** 2025-10-07 15:13:21.237427 | controller | Tuesday 07 October 2025 15:13:21 +0000 (0:00:00.018) 0:00:11.141 ******* 2025-10-07 15:13:21.237437 | controller | skipping: [instance] 2025-10-07 15:13:21.264506 | controller | 2025-10-07 15:13:21.264531 | controller | TASK [dnsmasq : Remove main configuration files] ******************************* 2025-10-07 15:13:21.264539 | controller | Tuesday 07 October 2025 15:13:21 +0000 (0:00:00.018) 0:00:11.160 ******* 2025-10-07 15:13:21.264549 | controller | skipping: [instance] => (item=/etc/cifmw-dnsmasq.conf) 2025-10-07 15:13:21.290620 | controller | skipping: [instance] => (item=/etc/cifmw-dnsmasq.d) 2025-10-07 15:13:21.290644 | controller | skipping: [instance] 2025-10-07 15:13:21.290653 | controller | 2025-10-07 15:13:21.290661 | controller | TASK [Create network] ********************************************************** 2025-10-07 15:13:21.290669 | controller | Tuesday 07 October 2025 15:13:21 +0000 (0:00:00.026) 0:00:11.187 ******* 2025-10-07 15:13:21.290679 | controller | 2025-10-07 15:13:21.317665 | controller | TASK [dnsmasq : Assert mandatory parameter] ************************************ 2025-10-07 15:13:21.317698 | controller | Tuesday 07 October 2025 15:13:21 +0000 (0:00:00.026) 0:00:11.213 ******* 2025-10-07 15:13:21.317710 | controller | ok: [instance] => changed=false 2025-10-07 15:13:21.317735 | controller | msg: All assertions passed 2025-10-07 15:13:21.317742 | controller | 2025-10-07 15:13:21.317748 | controller | TASK [dnsmasq : Assert mandatory parameters for new network] ******************* 2025-10-07 15:13:21.317755 | controller | Tuesday 07 October 2025 15:13:21 +0000 (0:00:00.026) 0:00:11.240 ******* 2025-10-07 15:13:21.349800 | controller | ok: [instance] => changed=false 2025-10-07 15:13:21.794223 | controller | msg: All assertions passed 2025-10-07 15:13:21.794253 | controller | 2025-10-07 15:13:21.794261 | controller | TASK [dnsmasq : Create network] ************************************************ 2025-10-07 15:13:21.794267 | controller | Tuesday 07 October 2025 15:13:21 +0000 (0:00:00.031) 0:00:11.272 ******* 2025-10-07 15:13:21.794277 | controller | changed: [instance] 2025-10-07 15:13:21.812219 | controller | 2025-10-07 15:13:21.812236 | controller | TASK [dnsmasq : Remove network] ************************************************ 2025-10-07 15:13:21.812243 | controller | Tuesday 07 October 2025 15:13:21 +0000 (0:00:00.444) 0:00:11.717 ******* 2025-10-07 15:13:21.812252 | controller | skipping: [instance] 2025-10-07 15:13:21.834380 | controller | 2025-10-07 15:13:21.834396 | controller | TASK [dnsmasq : Remove dhcp host files] **************************************** 2025-10-07 15:13:21.834403 | controller | Tuesday 07 October 2025 15:13:21 +0000 (0:00:00.018) 0:00:11.735 ******* 2025-10-07 15:13:21.834412 | controller | skipping: [instance] 2025-10-07 15:13:21.863124 | controller | 2025-10-07 15:13:21.863140 | controller | TASK [Create second network] *************************************************** 2025-10-07 15:13:21.863147 | controller | Tuesday 07 October 2025 15:13:21 +0000 (0:00:00.021) 0:00:11.757 ******* 2025-10-07 15:13:21.863156 | controller | 2025-10-07 15:13:21.892615 | controller | TASK [dnsmasq : Assert mandatory parameter] ************************************ 2025-10-07 15:13:21.892634 | controller | Tuesday 07 October 2025 15:13:21 +0000 (0:00:00.029) 0:00:11.786 ******* 2025-10-07 15:13:21.892644 | controller | ok: [instance] => changed=false 2025-10-07 15:13:21.925133 | controller | msg: All assertions passed 2025-10-07 15:13:21.925155 | controller | 2025-10-07 15:13:21.925164 | controller | TASK [dnsmasq : Assert mandatory parameters for new network] ******************* 2025-10-07 15:13:21.925172 | controller | Tuesday 07 October 2025 15:13:21 +0000 (0:00:00.029) 0:00:11.815 ******* 2025-10-07 15:13:21.925182 | controller | ok: [instance] => changed=false 2025-10-07 15:13:21.925371 | controller | msg: All assertions passed 2025-10-07 15:13:21.925507 | controller | 2025-10-07 15:13:21.925674 | controller | TASK [dnsmasq : Create network] ************************************************ 2025-10-07 15:13:21.925815 | controller | Tuesday 07 October 2025 15:13:21 +0000 (0:00:00.032) 0:00:11.848 ******* 2025-10-07 15:13:22.354700 | controller | changed: [instance] 2025-10-07 15:13:22.354729 | controller | 2025-10-07 15:13:22.354737 | controller | TASK [dnsmasq : Remove network] ************************************************ 2025-10-07 15:13:22.354745 | controller | Tuesday 07 October 2025 15:13:22 +0000 (0:00:00.429) 0:00:12.277 ******* 2025-10-07 15:13:22.372030 | controller | skipping: [instance] 2025-10-07 15:13:22.394282 | controller | 2025-10-07 15:13:22.394308 | controller | TASK [dnsmasq : Remove dhcp host files] **************************************** 2025-10-07 15:13:22.394316 | controller | Tuesday 07 October 2025 15:13:22 +0000 (0:00:00.017) 0:00:12.295 ******* 2025-10-07 15:13:22.394326 | controller | skipping: [instance] 2025-10-07 15:13:22.668660 | controller | 2025-10-07 15:13:22.668691 | controller | TASK [Inject enable-ra in startrek] ******************************************** 2025-10-07 15:13:22.668699 | controller | Tuesday 07 October 2025 15:13:22 +0000 (0:00:00.022) 0:00:12.317 ******* 2025-10-07 15:13:22.668710 | controller | ok: [instance] 2025-10-07 15:13:22.668716 | controller | 2025-10-07 15:13:22.668721 | controller | TASK [Assert no change was done in startrek] *********************************** 2025-10-07 15:13:22.668729 | controller | Tuesday 07 October 2025 15:13:22 +0000 (0:00:00.274) 0:00:12.591 ******* 2025-10-07 15:13:22.691685 | controller | ok: [instance] => changed=false 2025-10-07 15:13:22.691741 | controller | msg: All assertions passed 2025-10-07 15:13:22.692073 | controller | 2025-10-07 15:13:22.717999 | controller | TASK [Create IPv4-only network] ************************************************ 2025-10-07 15:13:22.718023 | controller | Tuesday 07 October 2025 15:13:22 +0000 (0:00:00.023) 0:00:12.614 ******* 2025-10-07 15:13:22.718036 | controller | 2025-10-07 15:13:22.744636 | controller | TASK [dnsmasq : Assert mandatory parameter] ************************************ 2025-10-07 15:13:22.744681 | controller | Tuesday 07 October 2025 15:13:22 +0000 (0:00:00.026) 0:00:12.641 ******* 2025-10-07 15:13:22.744696 | controller | ok: [instance] => changed=false 2025-10-07 15:13:22.778661 | controller | msg: All assertions passed 2025-10-07 15:13:22.778695 | controller | 2025-10-07 15:13:22.778703 | controller | TASK [dnsmasq : Assert mandatory parameters for new network] ******************* 2025-10-07 15:13:22.778710 | controller | Tuesday 07 October 2025 15:13:22 +0000 (0:00:00.026) 0:00:12.667 ******* 2025-10-07 15:13:22.778719 | controller | ok: [instance] => changed=false 2025-10-07 15:13:22.778743 | controller | msg: All assertions passed 2025-10-07 15:13:22.778750 | controller | 2025-10-07 15:13:22.778757 | controller | TASK [dnsmasq : Create network] ************************************************ 2025-10-07 15:13:22.778850 | controller | Tuesday 07 October 2025 15:13:22 +0000 (0:00:00.034) 0:00:12.701 ******* 2025-10-07 15:13:23.211951 | controller | changed: [instance] 2025-10-07 15:13:23.229229 | controller | 2025-10-07 15:13:23.229257 | controller | TASK [dnsmasq : Remove network] ************************************************ 2025-10-07 15:13:23.229271 | controller | Tuesday 07 October 2025 15:13:23 +0000 (0:00:00.433) 0:00:13.134 ******* 2025-10-07 15:13:23.229282 | controller | skipping: [instance] 2025-10-07 15:13:23.251561 | controller | 2025-10-07 15:13:23.251607 | controller | TASK [dnsmasq : Remove dhcp host files] **************************************** 2025-10-07 15:13:23.251616 | controller | Tuesday 07 October 2025 15:13:23 +0000 (0:00:00.017) 0:00:13.152 ******* 2025-10-07 15:13:23.251629 | controller | skipping: [instance] 2025-10-07 15:13:23.452656 | controller | 2025-10-07 15:13:23.452685 | controller | TASK [Inject enable-ra in oldies] ********************************************** 2025-10-07 15:13:23.452694 | controller | Tuesday 07 October 2025 15:13:23 +0000 (0:00:00.022) 0:00:13.174 ******* 2025-10-07 15:13:23.452703 | controller | changed: [instance] 2025-10-07 15:13:23.452963 | controller | 2025-10-07 15:13:23.452977 | controller | TASK [Debug] ******************************************************************* 2025-10-07 15:13:23.474386 | controller | Tuesday 07 October 2025 15:13:23 +0000 (0:00:00.201) 0:00:13.375 ******* 2025-10-07 15:13:23.474407 | controller | ok: [instance] => 2025-10-07 15:13:23.496428 | controller | _set_ra: 2025-10-07 15:13:23.496445 | controller | backup: '' 2025-10-07 15:13:23.496453 | controller | changed: true 2025-10-07 15:13:23.496458 | controller | diff: 2025-10-07 15:13:23.496464 | controller | - after: '' 2025-10-07 15:13:23.496470 | controller | after_header: /etc/cifmw-dnsmasq.d/oldies.conf (content) 2025-10-07 15:13:23.496476 | controller | before: '' 2025-10-07 15:13:23.496486 | controller | before_header: /etc/cifmw-dnsmasq.d/oldies.conf (content) 2025-10-07 15:13:23.496495 | controller | - after_header: /etc/cifmw-dnsmasq.d/oldies.conf (file attributes) 2025-10-07 15:13:23.496500 | controller | before_header: /etc/cifmw-dnsmasq.d/oldies.conf (file attributes) 2025-10-07 15:13:23.496506 | controller | failed: false 2025-10-07 15:13:23.496511 | controller | msg: line added 2025-10-07 15:13:23.496516 | controller | 2025-10-07 15:13:23.496522 | controller | TASK [Assert oldies was changed] *********************************************** 2025-10-07 15:13:23.496527 | controller | Tuesday 07 October 2025 15:13:23 +0000 (0:00:00.020) 0:00:13.396 ******* 2025-10-07 15:13:23.496536 | controller | ok: [instance] => changed=false 2025-10-07 15:13:23.525062 | controller | msg: All assertions passed 2025-10-07 15:13:23.525080 | controller | 2025-10-07 15:13:23.525089 | controller | TASK [Inject some node in starwars network] ************************************ 2025-10-07 15:13:23.525096 | controller | Tuesday 07 October 2025 15:13:23 +0000 (0:00:00.023) 0:00:13.419 ******* 2025-10-07 15:13:23.525106 | controller | 2025-10-07 15:13:23.525298 | controller | TASK [dnsmasq : Ensure we have the right data and type] ************************ 2025-10-07 15:13:23.525449 | controller | Tuesday 07 October 2025 15:13:23 +0000 (0:00:00.029) 0:00:13.448 ******* 2025-10-07 15:13:23.562190 | controller | ok: [instance] 2025-10-07 15:13:23.562406 | controller | 2025-10-07 15:13:23.562556 | controller | TASK [dnsmasq : Assert we have needed host data] ******************************* 2025-10-07 15:13:23.562730 | controller | Tuesday 07 October 2025 15:13:23 +0000 (0:00:00.037) 0:00:13.485 ******* 2025-10-07 15:13:23.626098 | controller | ok: [instance] => (item={'network': 'starwars', 'state': 'present', 'mac': '0a:19:02:f8:4c:a7', 'ips': ['2345:0425:2CA1::0567:5673:cafe', '192.168.254.11']}) 2025-10-07 15:13:23.626324 | controller | ok: [instance] => (item={'network': 'starwars', 'state': 'present', 'mac': '0a:19:02:f8:4c:a8', 'ips': ['2345:0425:2CA1::0567:5673:babe', '192.168.254.12'], 'name': 'solo'}) 2025-10-07 15:13:23.626511 | controller | ok: [instance] => (item={'network': 'startrek', 'state': 'present', 'mac': '0a:19:02:f8:4c:a8', 'ips': ['2345:0426:2CA1::0567:5673:babe', '192.168.253.12'], 'name': 'spock'}) 2025-10-07 15:13:23.626673 | controller | 2025-10-07 15:13:23.626817 | controller | TASK [dnsmasq : Ensure networks exists] **************************************** 2025-10-07 15:13:23.626954 | controller | Tuesday 07 October 2025 15:13:23 +0000 (0:00:00.063) 0:00:13.549 ******* 2025-10-07 15:13:23.668395 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/dnsmasq/tasks/_check_net_status.yml for instance => (item=starwars) 2025-10-07 15:13:23.850231 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/dnsmasq/tasks/_check_net_status.yml for instance => (item=startrek) 2025-10-07 15:13:23.850263 | controller | 2025-10-07 15:13:23.850271 | controller | TASK [dnsmasq : Check network file status] ************************************* 2025-10-07 15:13:23.850277 | controller | Tuesday 07 October 2025 15:13:23 +0000 (0:00:00.041) 0:00:13.591 ******* 2025-10-07 15:13:23.850287 | controller | ok: [instance] 2025-10-07 15:13:23.871424 | controller | 2025-10-07 15:13:23.871452 | controller | TASK [dnsmasq : Assert network exists] ***************************************** 2025-10-07 15:13:23.871460 | controller | Tuesday 07 October 2025 15:13:23 +0000 (0:00:00.181) 0:00:13.773 ******* 2025-10-07 15:13:23.871470 | controller | ok: [instance] 2025-10-07 15:13:24.047984 | controller | 2025-10-07 15:13:24.048015 | controller | TASK [dnsmasq : Check network file status] ************************************* 2025-10-07 15:13:24.048024 | controller | Tuesday 07 October 2025 15:13:23 +0000 (0:00:00.021) 0:00:13.794 ******* 2025-10-07 15:13:24.048035 | controller | ok: [instance] 2025-10-07 15:13:24.069144 | controller | 2025-10-07 15:13:24.069177 | controller | TASK [dnsmasq : Assert network exists] ***************************************** 2025-10-07 15:13:24.069185 | controller | Tuesday 07 October 2025 15:13:24 +0000 (0:00:00.176) 0:00:13.970 ******* 2025-10-07 15:13:24.069195 | controller | ok: [instance] 2025-10-07 15:13:24.086484 | controller | 2025-10-07 15:13:24.086506 | controller | TASK [dnsmasq : Initialize empty dhcp_host_entries] **************************** 2025-10-07 15:13:24.086520 | controller | Tuesday 07 October 2025 15:13:24 +0000 (0:00:00.021) 0:00:13.991 ******* 2025-10-07 15:13:24.086530 | controller | ok: [instance] 2025-10-07 15:13:24.198651 | controller | 2025-10-07 15:13:24.198684 | controller | TASK [dnsmasq : Compute entry] ************************************************* 2025-10-07 15:13:24.198692 | controller | Tuesday 07 October 2025 15:13:24 +0000 (0:00:00.017) 0:00:14.009 ******* 2025-10-07 15:13:24.198702 | controller | ok: [instance] => (item={'network': 'starwars', 'state': 'present', 'mac': '0a:19:02:f8:4c:a7', 'ips': ['2345:0425:2CA1::0567:5673:cafe', '192.168.254.11']}) 2025-10-07 15:13:24.198726 | controller | ok: [instance] => (item={'network': 'starwars', 'state': 'present', 'mac': '0a:19:02:f8:4c:a8', 'ips': ['2345:0425:2CA1::0567:5673:babe', '192.168.254.12'], 'name': 'solo'}) 2025-10-07 15:13:24.199063 | controller | ok: [instance] => (item={'network': 'startrek', 'state': 'present', 'mac': '0a:19:02:f8:4c:a8', 'ips': ['2345:0426:2CA1::0567:5673:babe', '192.168.253.12'], 'name': 'spock'}) 2025-10-07 15:13:24.234317 | controller | 2025-10-07 15:13:24.234335 | controller | TASK [dnsmasq : Create add/remove sets] **************************************** 2025-10-07 15:13:24.234343 | controller | Tuesday 07 October 2025 15:13:24 +0000 (0:00:00.112) 0:00:14.121 ******* 2025-10-07 15:13:24.234357 | controller | ok: [instance] 2025-10-07 15:13:25.317557 | controller | 2025-10-07 15:13:25.317648 | controller | TASK [dnsmasq : Add DHCP entries] ********************************************** 2025-10-07 15:13:25.317657 | controller | Tuesday 07 October 2025 15:13:24 +0000 (0:00:00.035) 0:00:14.157 ******* 2025-10-07 15:13:25.317667 | controller | changed: [instance] => (item={'file': 'starwars_0a:19:02:f8:4c:a7', 'entry': '0a:19:02:f8:4c:a7,[2345:425:2ca1::567:5673:cafe],192.168.254.11', 'state': 'present'}) 2025-10-07 15:13:25.337687 | controller | changed: [instance] => (item={'file': 'starwars_solo_0a:19:02:f8:4c:a8', 'entry': '0a:19:02:f8:4c:a8,[2345:425:2ca1::567:5673:babe],192.168.254.12,solo', 'state': 'present'}) 2025-10-07 15:13:25.337730 | controller | changed: [instance] => (item={'file': 'startrek_spock_0a:19:02:f8:4c:a8', 'entry': '0a:19:02:f8:4c:a8,[2345:426:2ca1::567:5673:babe],192.168.253.12,spock', 'state': 'present'}) 2025-10-07 15:13:25.337739 | controller | 2025-10-07 15:13:25.337747 | controller | TASK [dnsmasq : Remove DHCP entries] ******************************************* 2025-10-07 15:13:25.337755 | controller | Tuesday 07 October 2025 15:13:25 +0000 (0:00:01.082) 0:00:15.240 ******* 2025-10-07 15:13:25.337768 | controller | skipping: [instance] 2025-10-07 15:13:25.337798 | controller | 2025-10-07 15:13:25.337809 | controller | TASK [Add a domain specific forwarder] ***************************************** 2025-10-07 15:13:25.337854 | controller | Tuesday 07 October 2025 15:13:25 +0000 (0:00:00.020) 0:00:15.260 ******* 2025-10-07 15:13:25.370148 | controller | 2025-10-07 15:13:25.405688 | controller | TASK [dnsmasq : Assert we have needed forwarder data] ************************** 2025-10-07 15:13:25.405726 | controller | Tuesday 07 October 2025 15:13:25 +0000 (0:00:00.032) 0:00:15.293 ******* 2025-10-07 15:13:25.405739 | controller | ok: [instance] 2025-10-07 15:13:25.405755 | controller | 2025-10-07 15:13:25.405761 | controller | TASK [dnsmasq : Assert each forwarder element have needed data] **************** 2025-10-07 15:13:25.405769 | controller | Tuesday 07 October 2025 15:13:25 +0000 (0:00:00.035) 0:00:15.328 ******* 2025-10-07 15:13:25.447136 | controller | ok: [instance] => (item={'state': 'present', 'server': '192.168.254.100', 'domains': ['battlestar.galactica.lan']}) 2025-10-07 15:13:25.679368 | controller | 2025-10-07 15:13:25.679399 | controller | TASK [dnsmasq : Add/Remove forwarder] ****************************************** 2025-10-07 15:13:25.679407 | controller | Tuesday 07 October 2025 15:13:25 +0000 (0:00:00.041) 0:00:15.369 ******* 2025-10-07 15:13:25.679418 | controller | changed: [instance] => (item={'state': 'present', 'server': '192.168.254.100', 'domains': ['battlestar.galactica.lan']}) 2025-10-07 15:13:25.712745 | controller | 2025-10-07 15:13:25.712782 | controller | TASK [Add some host records] *************************************************** 2025-10-07 15:13:25.712792 | controller | Tuesday 07 October 2025 15:13:25 +0000 (0:00:00.232) 0:00:15.602 ******* 2025-10-07 15:13:25.712805 | controller | 2025-10-07 15:13:25.747655 | controller | TASK [dnsmasq : Assert we have needed host record data] ************************ 2025-10-07 15:13:25.747688 | controller | Tuesday 07 October 2025 15:13:25 +0000 (0:00:00.033) 0:00:15.635 ******* 2025-10-07 15:13:25.747699 | controller | ok: [instance] 2025-10-07 15:13:25.747798 | controller | 2025-10-07 15:13:25.747830 | controller | TASK [dnsmasq : Assert each address element have needed data] ****************** 2025-10-07 15:13:25.747842 | controller | Tuesday 07 October 2025 15:13:25 +0000 (0:00:00.034) 0:00:15.670 ******* 2025-10-07 15:13:25.800652 | controller | ok: [instance] => (item={'state': 'present', 'ips': ['192.168.253.50', '2345:0425:2CA1::dead'], 'names': ['dead.battlestar.galactica.lan']}) 2025-10-07 15:13:25.800690 | controller | ok: [instance] => (item={'state': 'present', 'ips': ['192.168.253.51', '2345:0425:2CA1::beef'], 'names': ['beef.battlestar.galactica.lan']}) 2025-10-07 15:13:25.801013 | controller | 2025-10-07 15:13:26.230651 | controller | TASK [dnsmasq : Add/Remove address] ******************************************** 2025-10-07 15:13:26.230679 | controller | Tuesday 07 October 2025 15:13:25 +0000 (0:00:00.053) 0:00:15.723 ******* 2025-10-07 15:13:26.230691 | controller | changed: [instance] => (item={'state': 'present', 'ips': ['192.168.253.50', '2345:0425:2CA1::dead'], 'names': ['dead.battlestar.galactica.lan']}) 2025-10-07 15:13:26.265197 | controller | changed: [instance] => (item={'state': 'present', 'ips': ['192.168.253.51', '2345:0425:2CA1::beef'], 'names': ['beef.battlestar.galactica.lan']}) 2025-10-07 15:13:26.265218 | controller | 2025-10-07 15:13:26.265238 | controller | TASK [Add some wildcard addresses] ********************************************* 2025-10-07 15:13:26.265244 | controller | Tuesday 07 October 2025 15:13:26 +0000 (0:00:00.429) 0:00:16.153 ******* 2025-10-07 15:13:26.265254 | controller | 2025-10-07 15:13:26.300791 | controller | TASK [dnsmasq : Assert we have needed address data] **************************** 2025-10-07 15:13:26.300814 | controller | Tuesday 07 October 2025 15:13:26 +0000 (0:00:00.034) 0:00:16.188 ******* 2025-10-07 15:13:26.300825 | controller | ok: [instance] 2025-10-07 15:13:26.387098 | controller | 2025-10-07 15:13:26.387124 | controller | TASK [dnsmasq : Assert each address element have needed data] ****************** 2025-10-07 15:13:26.387132 | controller | Tuesday 07 October 2025 15:13:26 +0000 (0:00:00.035) 0:00:16.223 ******* 2025-10-07 15:13:26.387143 | controller | ok: [instance] => (item={'state': 'present', 'ipaddr': '1.2.3.4', 'domains': ['apps.ocp.starwars.lan']}) 2025-10-07 15:13:26.818670 | controller | ok: [instance] => (item={'state': 'present', 'ipaddr': '4.3.2.1', 'domains': ['apps.ocp.startrek.lan']}) 2025-10-07 15:13:26.818705 | controller | 2025-10-07 15:13:26.818714 | controller | TASK [dnsmasq : Add/Remove address] ******************************************** 2025-10-07 15:13:26.818722 | controller | Tuesday 07 October 2025 15:13:26 +0000 (0:00:00.086) 0:00:16.309 ******* 2025-10-07 15:13:26.818734 | controller | changed: [instance] => (item={'state': 'present', 'ipaddr': '1.2.3.4', 'domains': ['apps.ocp.starwars.lan']}) 2025-10-07 15:13:26.818761 | controller | changed: [instance] => (item={'state': 'present', 'ipaddr': '4.3.2.1', 'domains': ['apps.ocp.startrek.lan']}) 2025-10-07 15:13:26.818770 | controller | 2025-10-07 15:13:26.818777 | controller | TASK [Force reload dnsmasq] **************************************************** 2025-10-07 15:13:26.818784 | controller | Tuesday 07 October 2025 15:13:26 +0000 (0:00:00.422) 0:00:16.732 ******* 2025-10-07 15:13:26.818791 | controller | 2025-10-07 15:13:26.818801 | controller | RUNNING HANDLER [dnsmasq : Restart dnsmasq] ************************************ 2025-10-07 15:13:27.168109 | controller | Tuesday 07 October 2025 15:13:26 +0000 (0:00:00.009) 0:00:16.741 ******* 2025-10-07 15:13:27.168167 | controller | changed: [instance] 2025-10-07 15:13:27.515646 | controller | 2025-10-07 15:13:27.515676 | controller | RUNNING HANDLER [dnsmasq : Reload dnsmasq] ************************************* 2025-10-07 15:13:27.515684 | controller | Tuesday 07 October 2025 15:13:27 +0000 (0:00:00.349) 0:00:17.090 ******* 2025-10-07 15:13:27.515694 | controller | changed: [instance] 2025-10-07 15:13:27.515717 | controller | 2025-10-07 15:13:27.518099 | controller | PLAY RECAP ********************************************************************* 2025-10-07 15:13:27.574699 | controller | instance : ok=48 changed=18 unreachable=0 failed=0 skipped=13 rescued=0 ignored=0 2025-10-07 15:13:27.574741 | controller | 2025-10-07 15:13:27.574750 | controller | Tuesday 07 October 2025 15:13:27 +0000 (0:00:00.347) 0:00:17.438 ******* 2025-10-07 15:13:27.574756 | controller | =============================================================================== 2025-10-07 15:13:27.574762 | controller | dnsmasq : Install needed packages --------------------------------------- 3.56s 2025-10-07 15:13:27.574767 | controller | dnsmasq : Ensure SELinux knows about the new files and directories ------ 3.50s 2025-10-07 15:13:27.574773 | controller | dnsmasq : Add DHCP entries ---------------------------------------------- 1.08s 2025-10-07 15:13:27.574778 | controller | dnsmasq : Enable and start service -------------------------------------- 0.88s 2025-10-07 15:13:27.574784 | controller | Gathering Facts --------------------------------------------------------- 0.84s 2025-10-07 15:13:27.574789 | controller | dnsmasq : Manage systemd unit file -------------------------------------- 0.59s 2025-10-07 15:13:27.574794 | controller | dnsmasq : Create network ------------------------------------------------ 0.44s 2025-10-07 15:13:27.574800 | controller | dnsmasq : Create listener configuration file ---------------------------- 0.44s 2025-10-07 15:13:27.574805 | controller | dnsmasq : Create dns configuration file --------------------------------- 0.44s 2025-10-07 15:13:27.574811 | controller | dnsmasq : Create network ------------------------------------------------ 0.43s 2025-10-07 15:13:27.574816 | controller | dnsmasq : Add/Remove address -------------------------------------------- 0.43s 2025-10-07 15:13:27.574821 | controller | dnsmasq : Create network ------------------------------------------------ 0.43s 2025-10-07 15:13:27.574827 | controller | dnsmasq : Add/Remove address -------------------------------------------- 0.42s 2025-10-07 15:13:27.574832 | controller | dnsmasq : Manage base configuration file -------------------------------- 0.42s 2025-10-07 15:13:27.574837 | controller | dnsmasq : Restart dnsmasq ----------------------------------------------- 0.35s 2025-10-07 15:13:27.574843 | controller | dnsmasq : Reload dnsmasq ------------------------------------------------ 0.35s 2025-10-07 15:13:27.574848 | controller | dnsmasq : Manage configuration directory -------------------------------- 0.31s 2025-10-07 15:13:27.574853 | controller | Inject enable-ra in startrek -------------------------------------------- 0.27s 2025-10-07 15:13:27.574858 | controller | dnsmasq : Add/Remove forwarder ------------------------------------------ 0.23s 2025-10-07 15:13:27.574864 | controller | Inject enable-ra in oldies ---------------------------------------------- 0.20s 2025-10-07 15:13:27.574874 | controller | INFO Running default > cleanup 2025-10-07 15:13:28.008207 | controller | 2025-10-07 15:13:28.834400 | controller | PLAY [Converge] **************************************************************** 2025-10-07 15:13:28.834433 | controller | 2025-10-07 15:13:28.834443 | controller | TASK [Gathering Facts] ********************************************************* 2025-10-07 15:13:28.834450 | controller | Tuesday 07 October 2025 15:13:28 +0000 (0:00:00.014) 0:00:00.014 ******* 2025-10-07 15:13:28.834463 | controller | ok: [instance] 2025-10-07 15:13:29.411214 | controller | 2025-10-07 15:13:29.411261 | controller | TASK [Copy generated content in ci-framework-data/artifacts] ******************* 2025-10-07 15:13:29.411271 | controller | Tuesday 07 October 2025 15:13:28 +0000 (0:00:00.826) 0:00:00.841 ******* 2025-10-07 15:13:29.411287 | controller | changed: [instance] => (item=/etc/cifmw-dnsmasq.conf) 2025-10-07 15:13:29.439664 | controller | changed: [instance] => (item=/etc/cifmw-dnsmasq.d/) 2025-10-07 15:13:29.439690 | controller | 2025-10-07 15:13:29.439698 | controller | TASK [Remove a domain specific forwarder] ************************************** 2025-10-07 15:13:29.439704 | controller | Tuesday 07 October 2025 15:13:29 +0000 (0:00:00.576) 0:00:01.418 ******* 2025-10-07 15:13:29.439714 | controller | 2025-10-07 15:13:29.439738 | controller | TASK [dnsmasq : Assert we have needed forwarder data] ************************** 2025-10-07 15:13:29.439824 | controller | Tuesday 07 October 2025 15:13:29 +0000 (0:00:00.028) 0:00:01.447 ******* 2025-10-07 15:13:29.473881 | controller | ok: [instance] 2025-10-07 15:13:29.514560 | controller | 2025-10-07 15:13:29.514619 | controller | TASK [dnsmasq : Assert each forwarder element have needed data] **************** 2025-10-07 15:13:29.514630 | controller | Tuesday 07 October 2025 15:13:29 +0000 (0:00:00.033) 0:00:01.481 ******* 2025-10-07 15:13:29.514651 | controller | ok: [instance] => (item={'state': 'absent', 'server': '192.168.100.100', 'domains': ['battlestar.galactica.lan']}) 2025-10-07 15:13:29.822083 | controller | 2025-10-07 15:13:29.822112 | controller | TASK [dnsmasq : Add/Remove forwarder] ****************************************** 2025-10-07 15:13:29.822121 | controller | Tuesday 07 October 2025 15:13:29 +0000 (0:00:00.040) 0:00:01.521 ******* 2025-10-07 15:13:29.822131 | controller | ok: [instance] => (item={'state': 'absent', 'server': '192.168.100.100', 'domains': ['battlestar.galactica.lan']}) 2025-10-07 15:13:29.851264 | controller | 2025-10-07 15:13:29.851290 | controller | TASK [Remove some host records] ************************************************ 2025-10-07 15:13:29.851299 | controller | Tuesday 07 October 2025 15:13:29 +0000 (0:00:00.307) 0:00:01.829 ******* 2025-10-07 15:13:29.851311 | controller | 2025-10-07 15:13:29.885612 | controller | TASK [dnsmasq : Assert we have needed host record data] ************************ 2025-10-07 15:13:29.885633 | controller | Tuesday 07 October 2025 15:13:29 +0000 (0:00:00.029) 0:00:01.858 ******* 2025-10-07 15:13:29.885644 | controller | ok: [instance] 2025-10-07 15:13:29.936119 | controller | 2025-10-07 15:13:29.936148 | controller | TASK [dnsmasq : Assert each address element have needed data] ****************** 2025-10-07 15:13:29.936156 | controller | Tuesday 07 October 2025 15:13:29 +0000 (0:00:00.034) 0:00:01.893 ******* 2025-10-07 15:13:29.936166 | controller | ok: [instance] => (item={'state': 'absent', 'ips': ['192.168.253.50', '2345:0425:2CA1::dead'], 'names': ['dead.battlestar.galactica.lan']}) 2025-10-07 15:13:30.347652 | controller | ok: [instance] => (item={'state': 'absent', 'ips': ['192.168.253.51', '2345:0425:2CA1::beef'], 'names': ['beef.battlestar.galactica.lan']}) 2025-10-07 15:13:30.347701 | controller | 2025-10-07 15:13:30.347710 | controller | TASK [dnsmasq : Add/Remove address] ******************************************** 2025-10-07 15:13:30.347716 | controller | Tuesday 07 October 2025 15:13:29 +0000 (0:00:00.050) 0:00:01.943 ******* 2025-10-07 15:13:30.347727 | controller | changed: [instance] => (item={'state': 'absent', 'ips': ['192.168.253.50', '2345:0425:2CA1::dead'], 'names': ['dead.battlestar.galactica.lan']}) 2025-10-07 15:13:30.347749 | controller | changed: [instance] => (item={'state': 'absent', 'ips': ['192.168.253.51', '2345:0425:2CA1::beef'], 'names': ['beef.battlestar.galactica.lan']}) 2025-10-07 15:13:30.347760 | controller | 2025-10-07 15:13:30.347767 | controller | TASK [Remove some wildcard addresses] ****************************************** 2025-10-07 15:13:30.347866 | controller | Tuesday 07 October 2025 15:13:30 +0000 (0:00:00.411) 0:00:02.355 ******* 2025-10-07 15:13:30.378687 | controller | 2025-10-07 15:13:30.378717 | controller | TASK [dnsmasq : Assert we have needed address data] **************************** 2025-10-07 15:13:30.378727 | controller | Tuesday 07 October 2025 15:13:30 +0000 (0:00:00.031) 0:00:02.386 ******* 2025-10-07 15:13:30.416275 | controller | ok: [instance] 2025-10-07 15:13:30.498643 | controller | 2025-10-07 15:13:30.498674 | controller | TASK [dnsmasq : Assert each address element have needed data] ****************** 2025-10-07 15:13:30.498682 | controller | Tuesday 07 October 2025 15:13:30 +0000 (0:00:00.036) 0:00:02.422 ******* 2025-10-07 15:13:30.498692 | controller | ok: [instance] => (item={'state': 'absent', 'ipaddr': '1.2.3.4', 'domains': ['apps.ocp.starwars.lan']}) 2025-10-07 15:13:30.498715 | controller | ok: [instance] => (item={'state': 'absent', 'ipaddr': '4.3.2.1', 'domains': ['apps.ocp.startrek.lan']}) 2025-10-07 15:13:30.498722 | controller | 2025-10-07 15:13:30.498932 | controller | TASK [dnsmasq : Add/Remove address] ******************************************** 2025-10-07 15:13:30.901144 | controller | Tuesday 07 October 2025 15:13:30 +0000 (0:00:00.083) 0:00:02.506 ******* 2025-10-07 15:13:30.901178 | controller | changed: [instance] => (item={'state': 'absent', 'ipaddr': '1.2.3.4', 'domains': ['apps.ocp.starwars.lan']}) 2025-10-07 15:13:30.931048 | controller | changed: [instance] => (item={'state': 'absent', 'ipaddr': '4.3.2.1', 'domains': ['apps.ocp.startrek.lan']}) 2025-10-07 15:13:30.931072 | controller | 2025-10-07 15:13:30.931080 | controller | TASK [dnsmasq : Cleaning dnsmasq] ********************************************** 2025-10-07 15:13:30.931087 | controller | Tuesday 07 October 2025 15:13:30 +0000 (0:00:00.402) 0:00:02.908 ******* 2025-10-07 15:13:30.931103 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/dnsmasq/tasks/configure.yml for instance 2025-10-07 15:13:32.185638 | controller | 2025-10-07 15:13:32.185668 | controller | TASK [dnsmasq : Install needed packages] *************************************** 2025-10-07 15:13:32.185676 | controller | Tuesday 07 October 2025 15:13:30 +0000 (0:00:00.029) 0:00:02.938 ******* 2025-10-07 15:13:32.185686 | controller | ok: [instance] 2025-10-07 15:13:35.593456 | controller | 2025-10-07 15:13:35.593487 | controller | TASK [dnsmasq : Ensure SELinux knows about the new files and directories] ****** 2025-10-07 15:13:35.593496 | controller | Tuesday 07 October 2025 15:13:32 +0000 (0:00:01.254) 0:00:04.192 ******* 2025-10-07 15:13:35.593506 | controller | changed: [instance] => (item={'target': '/etc/cifmw-dnsmasq.conf', 'setype': 'dnsmasq_etc_t'}) 2025-10-07 15:13:35.898232 | controller | changed: [instance] => (item={'target': '/etc/cifmw-dnsmasq.d(/.*)?', 'setype': 'dnsmasq_etc_t'}) 2025-10-07 15:13:35.898264 | controller | changed: [instance] => (item={'target': '/var/run/cifmw-dnsmasq.pid', 'setype': 'dnsmasq_var_run_t'}) 2025-10-07 15:13:35.898284 | controller | 2025-10-07 15:13:35.898298 | controller | TASK [dnsmasq : Manage configuration directory] ******************************** 2025-10-07 15:13:35.898305 | controller | Tuesday 07 October 2025 15:13:35 +0000 (0:00:03.407) 0:00:07.600 ******* 2025-10-07 15:13:35.898315 | controller | changed: [instance] 2025-10-07 15:13:35.914458 | controller | 2025-10-07 15:13:35.914484 | controller | TASK [dnsmasq : Manage systemd unit file] ************************************** 2025-10-07 15:13:35.914494 | controller | Tuesday 07 October 2025 15:13:35 +0000 (0:00:00.304) 0:00:07.905 ******* 2025-10-07 15:13:35.914506 | controller | skipping: [instance] 2025-10-07 15:13:35.930917 | controller | 2025-10-07 15:13:35.930946 | controller | TASK [dnsmasq : Manage base configuration file] ******************************** 2025-10-07 15:13:35.930953 | controller | Tuesday 07 October 2025 15:13:35 +0000 (0:00:00.016) 0:00:07.921 ******* 2025-10-07 15:13:35.930964 | controller | skipping: [instance] 2025-10-07 15:13:35.953615 | controller | 2025-10-07 15:13:35.953639 | controller | TASK [dnsmasq : Render listener configuration] ********************************* 2025-10-07 15:13:35.953648 | controller | Tuesday 07 October 2025 15:13:35 +0000 (0:00:00.016) 0:00:07.938 ******* 2025-10-07 15:13:35.953660 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/dnsmasq/tasks/listener.yml for instance 2025-10-07 15:13:35.969407 | controller | 2025-10-07 15:13:35.969426 | controller | TASK [dnsmasq : Create listener configuration file] **************************** 2025-10-07 15:13:35.969434 | controller | Tuesday 07 October 2025 15:13:35 +0000 (0:00:00.022) 0:00:07.961 ******* 2025-10-07 15:13:35.969443 | controller | skipping: [instance] 2025-10-07 15:13:35.969608 | controller | 2025-10-07 15:13:35.969764 | controller | TASK [dnsmasq : Remove listener configuration file] **************************** 2025-10-07 15:13:35.969906 | controller | Tuesday 07 October 2025 15:13:35 +0000 (0:00:00.016) 0:00:07.977 ******* 2025-10-07 15:13:36.162227 | controller | changed: [instance] 2025-10-07 15:13:36.187328 | controller | 2025-10-07 15:13:36.187356 | controller | TASK [dnsmasq : Render dns configuration] ************************************** 2025-10-07 15:13:36.187364 | controller | Tuesday 07 October 2025 15:13:36 +0000 (0:00:00.192) 0:00:08.169 ******* 2025-10-07 15:13:36.187374 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/dnsmasq/tasks/dns.yml for instance 2025-10-07 15:13:36.205306 | controller | 2025-10-07 15:13:36.205331 | controller | TASK [dnsmasq : Create dns configuration file] ********************************* 2025-10-07 15:13:36.205339 | controller | Tuesday 07 October 2025 15:13:36 +0000 (0:00:00.025) 0:00:08.194 ******* 2025-10-07 15:13:36.205349 | controller | skipping: [instance] 2025-10-07 15:13:36.400174 | controller | 2025-10-07 15:13:36.400203 | controller | TASK [dnsmasq : Remove dns configuration file] ********************************* 2025-10-07 15:13:36.400211 | controller | Tuesday 07 October 2025 15:13:36 +0000 (0:00:00.018) 0:00:08.212 ******* 2025-10-07 15:13:36.400221 | controller | changed: [instance] 2025-10-07 15:13:36.415107 | controller | 2025-10-07 15:13:36.415131 | controller | TASK [dnsmasq : Add localhost addresses from defined dnsmasq listen addresses to loopback interface] *** 2025-10-07 15:13:36.415145 | controller | Tuesday 07 October 2025 15:13:36 +0000 (0:00:00.194) 0:00:08.407 ******* 2025-10-07 15:13:36.415155 | controller | skipping: [instance] 2025-10-07 15:13:36.431253 | controller | 2025-10-07 15:13:36.431293 | controller | TASK [dnsmasq : Enable and start service] ************************************** 2025-10-07 15:13:36.431303 | controller | Tuesday 07 October 2025 15:13:36 +0000 (0:00:00.015) 0:00:08.422 ******* 2025-10-07 15:13:36.431314 | controller | skipping: [instance] 2025-10-07 15:13:37.114516 | controller | 2025-10-07 15:13:37.114547 | controller | TASK [dnsmasq : Stop service] ************************************************** 2025-10-07 15:13:37.114555 | controller | Tuesday 07 October 2025 15:13:36 +0000 (0:00:00.016) 0:00:08.438 ******* 2025-10-07 15:13:37.114566 | controller | changed: [instance] 2025-10-07 15:13:37.303014 | controller | 2025-10-07 15:13:37.303044 | controller | TASK [dnsmasq : Remove unit file] ********************************************** 2025-10-07 15:13:37.303052 | controller | Tuesday 07 October 2025 15:13:37 +0000 (0:00:00.683) 0:00:09.121 ******* 2025-10-07 15:13:37.303062 | controller | changed: [instance] 2025-10-07 15:13:37.671453 | controller | 2025-10-07 15:13:37.671483 | controller | TASK [dnsmasq : Remove main configuration files] ******************************* 2025-10-07 15:13:37.671491 | controller | Tuesday 07 October 2025 15:13:37 +0000 (0:00:00.188) 0:00:09.310 ******* 2025-10-07 15:13:37.671501 | controller | changed: [instance] => (item=/etc/cifmw-dnsmasq.conf) 2025-10-07 15:13:37.869672 | controller | changed: [instance] => (item=/etc/cifmw-dnsmasq.d) 2025-10-07 15:13:37.869706 | controller | 2025-10-07 15:13:37.869716 | controller | TASK [dnsmasq : Remove lease file] ********************************************* 2025-10-07 15:13:37.869724 | controller | Tuesday 07 October 2025 15:13:37 +0000 (0:00:00.368) 0:00:09.678 ******* 2025-10-07 15:13:37.869736 | controller | changed: [instance] 2025-10-07 15:13:38.189687 | controller | 2025-10-07 15:13:38.189717 | controller | RUNNING HANDLER [dnsmasq : Restart dnsmasq] ************************************ 2025-10-07 15:13:38.189726 | controller | Tuesday 07 October 2025 15:13:37 +0000 (0:00:00.198) 0:00:09.876 ******* 2025-10-07 15:13:38.189741 | controller | ok: [instance] 2025-10-07 15:13:38.189764 | controller | 2025-10-07 15:13:38.189771 | controller | PLAY RECAP ********************************************************************* 2025-10-07 15:13:38.189777 | controller | instance : ok=24 changed=11 unreachable=0 failed=0 skipped=6 rescued=0 ignored=0 2025-10-07 15:13:38.189784 | controller | 2025-10-07 15:13:38.190621 | controller | Tuesday 07 October 2025 15:13:38 +0000 (0:00:00.319) 0:00:10.196 ******* 2025-10-07 15:13:38.191844 | controller | =============================================================================== 2025-10-07 15:13:38.191865 | controller | dnsmasq : Ensure SELinux knows about the new files and directories ------ 3.41s 2025-10-07 15:13:38.191872 | controller | dnsmasq : Install needed packages --------------------------------------- 1.25s 2025-10-07 15:13:38.191878 | controller | Gathering Facts --------------------------------------------------------- 0.83s 2025-10-07 15:13:38.191884 | controller | dnsmasq : Stop service -------------------------------------------------- 0.68s 2025-10-07 15:13:38.191889 | controller | Copy generated content in ci-framework-data/artifacts ------------------- 0.58s 2025-10-07 15:13:38.191895 | controller | dnsmasq : Add/Remove address -------------------------------------------- 0.41s 2025-10-07 15:13:38.191900 | controller | dnsmasq : Add/Remove address -------------------------------------------- 0.40s 2025-10-07 15:13:38.191909 | controller | dnsmasq : Remove main configuration files ------------------------------- 0.37s 2025-10-07 15:13:38.239504 | controller | dnsmasq : Restart dnsmasq ----------------------------------------------- 0.32s 2025-10-07 15:13:38.239537 | controller | dnsmasq : Add/Remove forwarder ------------------------------------------ 0.31s 2025-10-07 15:13:38.239545 | controller | dnsmasq : Manage configuration directory -------------------------------- 0.30s 2025-10-07 15:13:38.239551 | controller | dnsmasq : Remove lease file --------------------------------------------- 0.20s 2025-10-07 15:13:38.239556 | controller | dnsmasq : Remove dns configuration file --------------------------------- 0.19s 2025-10-07 15:13:38.239562 | controller | dnsmasq : Remove listener configuration file ---------------------------- 0.19s 2025-10-07 15:13:38.239596 | controller | dnsmasq : Remove unit file ---------------------------------------------- 0.19s 2025-10-07 15:13:38.239605 | controller | dnsmasq : Assert each address element have needed data ------------------ 0.08s 2025-10-07 15:13:38.239611 | controller | dnsmasq : Assert each address element have needed data ------------------ 0.05s 2025-10-07 15:13:38.239616 | controller | dnsmasq : Assert each forwarder element have needed data ---------------- 0.04s 2025-10-07 15:13:38.239622 | controller | dnsmasq : Assert we have needed address data ---------------------------- 0.04s 2025-10-07 15:13:38.239627 | controller | dnsmasq : Assert we have needed host record data ------------------------ 0.03s 2025-10-07 15:13:38.239638 | controller | INFO Writing /tmp/report.html report. 2025-10-07 15:13:38.505942 | controller | changed 2025-10-07 15:13:38.541765 | 2025-10-07 15:13:38.541837 | PLAY RECAP 2025-10-07 15:13:38.541877 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2025-10-07 15:13:38.541901 | 2025-10-07 15:13:38.659613 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main] 2025-10-07 15:13:38.660502 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main] 2025-10-07 15:13:39.362583 | 2025-10-07 15:13:39.362698 | PLAY [Run ci/playbooks/collect-logs.yml] 2025-10-07 15:13:39.383730 | 2025-10-07 15:13:39.383807 | TASK [Filter out host if needed] 2025-10-07 15:13:39.394731 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller 2025-10-07 15:13:39.399179 | 2025-10-07 15:13:39.399246 | TASK [Ensure file is present] 2025-10-07 15:13:39.898865 | controller | ok 2025-10-07 15:13:39.910031 | 2025-10-07 15:13:39.910118 | TASK [Manage molecule report file] 2025-10-07 15:13:40.444722 | controller | changed 2025-10-07 15:13:40.454936 | 2025-10-07 15:13:40.455124 | TASK [Check if we get ci-framework-data basedir] 2025-10-07 15:13:40.650008 | controller | ok 2025-10-07 15:13:40.655148 | 2025-10-07 15:13:40.655215 | TASK [Create ci-framework-data log directory for zuul] 2025-10-07 15:13:41.049368 | controller | changed 2025-10-07 15:13:41.064399 | 2025-10-07 15:13:41.064489 | TASK [Copy ci-framework interesting files] 2025-10-07 15:13:41.287682 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory 2025-10-07 15:13:41.613445 | controller | changed 2025-10-07 15:13:41.619874 | 2025-10-07 15:13:41.619998 | TASK [Get SELinux listing] 2025-10-07 15:13:42.211216 | controller | changed 2025-10-07 15:13:42.217891 | 2025-10-07 15:13:42.218006 | TASK [Generate log index] 2025-10-07 15:13:43.191278 | controller | changed 2025-10-07 15:13:43.197318 | 2025-10-07 15:13:43.197387 | TASK [Get some env related data] 2025-10-07 15:13:43.786769 | controller | /home/zuul/.local/bin/ansible 2025-10-07 15:13:44.768604 | controller | changed 2025-10-07 15:13:44.774453 | 2025-10-07 15:13:44.774523 | TASK [Generate list of logs to collect in home directory] 2025-10-07 15:13:45.271302 | controller | ok: All paths examined 2025-10-07 15:13:45.320234 | 2025-10-07 15:13:45.320341 | LOOP [Copy logs from home directory] 2025-10-07 15:13:45.621307 | controller | changed: 2025-10-07 15:13:45.621446 | controller | { 2025-10-07 15:13:45.621477 | controller | "atime": 1759849872.3831809, 2025-10-07 15:13:45.621498 | controller | "ctime": 1759849890.1324468, 2025-10-07 15:13:45.621516 | controller | "dev": 64513, 2025-10-07 15:13:45.621534 | controller | "gid": 1000, 2025-10-07 15:13:45.621551 | controller | "gr_name": "zuul", 2025-10-07 15:13:45.621569 | controller | "inode": 4349130, 2025-10-07 15:13:45.621586 | controller | "isblk": false, 2025-10-07 15:13:45.621603 | controller | "ischr": false, 2025-10-07 15:13:45.621620 | controller | "isdir": false, 2025-10-07 15:13:45.621650 | controller | "isfifo": false, 2025-10-07 15:13:45.621667 | controller | "isgid": false, 2025-10-07 15:13:45.621683 | controller | "islnk": false, 2025-10-07 15:13:45.621699 | controller | "isreg": true, 2025-10-07 15:13:45.621714 | controller | "issock": false, 2025-10-07 15:13:45.621730 | controller | "isuid": false, 2025-10-07 15:13:45.621745 | controller | "mode": "0644", 2025-10-07 15:13:45.621760 | controller | "mtime": 1759849890.1324468, 2025-10-07 15:13:45.621775 | controller | "nlink": 1, 2025-10-07 15:13:45.621791 | controller | "path": "/home/zuul/ansible.log", 2025-10-07 15:13:45.621811 | controller | "pw_name": "zuul", 2025-10-07 15:13:45.621829 | controller | "rgrp": true, 2025-10-07 15:13:45.621845 | controller | "roth": true, 2025-10-07 15:13:45.621861 | controller | "rusr": true, 2025-10-07 15:13:45.621876 | controller | "size": 6749, 2025-10-07 15:13:45.621892 | controller | "uid": 1000, 2025-10-07 15:13:45.621937 | controller | "wgrp": false, 2025-10-07 15:13:45.621965 | controller | "woth": false, 2025-10-07 15:13:45.621987 | controller | "wusr": true, 2025-10-07 15:13:45.622005 | controller | "xgrp": false, 2025-10-07 15:13:45.622021 | controller | "xoth": false, 2025-10-07 15:13:45.622036 | controller | "xusr": false 2025-10-07 15:13:45.622052 | controller | } 2025-10-07 15:13:45.628888 | 2025-10-07 15:13:45.629015 | TASK [Copy crio stats log file] 2025-10-07 15:13:45.643384 | controller | skipping: Conditional result was False 2025-10-07 15:13:45.649789 | 2025-10-07 15:13:45.649859 | TASK [Get SELinux related data] 2025-10-07 15:13:45.869401 | controller | 2025-10-07 15:13:46.183245 | controller | ERROR 2025-10-07 15:13:46.183442 | controller | { 2025-10-07 15:13:46.183477 | controller | "delta": "0:00:00.008464", 2025-10-07 15:13:46.183500 | controller | "end": "2025-10-07 15:13:45.870316", 2025-10-07 15:13:46.183521 | controller | "msg": "non-zero return code", 2025-10-07 15:13:46.183541 | controller | "rc": 1, 2025-10-07 15:13:46.183559 | controller | "start": "2025-10-07 15:13:45.861852" 2025-10-07 15:13:46.183577 | controller | } 2025-10-07 15:13:46.183602 | controller | ERROR: Ignoring Errors 2025-10-07 15:13:46.189201 | 2025-10-07 15:13:46.189269 | TASK [Create system configuration directory] 2025-10-07 15:13:46.411385 | controller | changed 2025-10-07 15:13:46.440940 | 2025-10-07 15:13:46.441039 | TASK [Get some of the system configurations] 2025-10-07 15:13:47.000483 | controller | changed 2025-10-07 15:13:47.006193 | 2025-10-07 15:13:47.006265 | TASK [Copy generated documentation if available] 2025-10-07 15:13:47.050224 | controller | skipping: Conditional result was False 2025-10-07 15:13:47.055835 | 2025-10-07 15:13:47.055903 | TASK [Copy generated AsciiDoc documentation if available] 2025-10-07 15:13:47.101494 | controller | skipping: Conditional result was False 2025-10-07 15:13:47.107047 | 2025-10-07 15:13:47.107124 | TASK [Compress logs bigger than 2MB] 2025-10-07 15:13:47.651307 | controller | changed 2025-10-07 15:13:47.657673 | 2025-10-07 15:13:47.657806 | TASK [Copy files from workspace on node] 2025-10-07 15:13:47.688037 | controller | ok 2025-10-07 15:13:47.772377 | 2025-10-07 15:13:47.772489 | TASK [fetch-output : Set log path for multiple nodes] 2025-10-07 15:13:47.796509 | controller | skipping: Conditional result was False 2025-10-07 15:13:47.803316 | 2025-10-07 15:13:47.803397 | TASK [fetch-output : Set log path for single node] 2025-10-07 15:13:47.833095 | controller | ok 2025-10-07 15:13:47.837957 | 2025-10-07 15:13:47.838022 | LOOP [fetch-output : Ensure local output dirs] 2025-10-07 15:13:48.172737 | controller -> localhost | ok: "/var/lib/zuul/builds/c6a8f38485074d3a881ba1725ff81a91/work/logs" 2025-10-07 15:13:48.172999 | controller -> localhost | changed: All items complete 2025-10-07 15:13:48.173031 | 2025-10-07 15:13:48.760112 | controller -> localhost | changed: "/var/lib/zuul/builds/c6a8f38485074d3a881ba1725ff81a91/work/artifacts" 2025-10-07 15:13:49.151509 | controller -> localhost | changed: "/var/lib/zuul/builds/c6a8f38485074d3a881ba1725ff81a91/work/docs" 2025-10-07 15:13:49.164491 | 2025-10-07 15:13:49.164616 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-10-07 15:13:50.071519 | controller | changed: 2025-10-07 15:13:50.071719 | controller | .d..t...... ./ 2025-10-07 15:13:50.071751 | controller | >f+++++++++ README.html 2025-10-07 15:13:50.071774 | controller | >f+++++++++ ansible-execution.log 2025-10-07 15:13:50.071798 | controller | >f+++++++++ ansible.log 2025-10-07 15:13:50.071819 | controller | >f+++++++++ dmesg.log 2025-10-07 15:13:50.071838 | controller | >f+++++++++ installed-pkgs.log 2025-10-07 15:13:50.071858 | controller | >f+++++++++ python.log 2025-10-07 15:13:50.071877 | controller | >f+++++++++ registries.conf 2025-10-07 15:13:50.071896 | controller | >f+++++++++ report.html 2025-10-07 15:13:50.071940 | controller | >f+++++++++ selinux-denials.log 2025-10-07 15:13:50.071964 | controller | >f+++++++++ selinux-listing.log 2025-10-07 15:13:50.071983 | controller | cd+++++++++ ci-framework-data/ 2025-10-07 15:13:50.072001 | controller | cd+++++++++ ci-framework-data/artifacts/ 2025-10-07 15:13:50.072019 | controller | >f+++++++++ ci-framework-data/artifacts/addresses.conf 2025-10-07 15:13:50.072038 | controller | >f+++++++++ ci-framework-data/artifacts/cifmw-dnsmasq.conf 2025-10-07 15:13:50.072062 | controller | >f+++++++++ ci-framework-data/artifacts/dns.conf 2025-10-07 15:13:50.072080 | controller | >f+++++++++ ci-framework-data/artifacts/forwarders.conf 2025-10-07 15:13:50.072097 | controller | >f+++++++++ ci-framework-data/artifacts/host_records.conf 2025-10-07 15:13:50.072114 | controller | >f+++++++++ ci-framework-data/artifacts/listener.conf 2025-10-07 15:13:50.072131 | controller | >f+++++++++ ci-framework-data/artifacts/oldies.conf 2025-10-07 15:13:50.072147 | controller | >f+++++++++ ci-framework-data/artifacts/startrek.conf 2025-10-07 15:13:50.072163 | controller | >f+++++++++ ci-framework-data/artifacts/starwars.conf 2025-10-07 15:13:50.072179 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml 2025-10-07 15:13:50.072196 | controller | cd+++++++++ ci-framework-data/artifacts/dhcp-hosts.d/ 2025-10-07 15:13:50.072212 | controller | >f+++++++++ ci-framework-data/artifacts/dhcp-hosts.d/startrek_spock_0a:19:02:f8:4c:a8 2025-10-07 15:13:50.072229 | controller | >f+++++++++ ci-framework-data/artifacts/dhcp-hosts.d/starwars_0a:19:02:f8:4c:a7 2025-10-07 15:13:50.072244 | controller | >f+++++++++ ci-framework-data/artifacts/dhcp-hosts.d/starwars_solo_0a:19:02:f8:4c:a8 2025-10-07 15:13:50.072260 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/ 2025-10-07 15:13:50.072276 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml 2025-10-07 15:13:50.072292 | controller | cd+++++++++ ci-framework-data/artifacts/repositories/ 2025-10-07 15:13:50.072308 | controller | >f+++++++++ ci-framework-data/artifacts/repositories/delorean.repo.md5 2025-10-07 15:13:50.072324 | controller | cd+++++++++ ci-framework-data/logs/ 2025-10-07 15:13:50.072340 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log 2025-10-07 15:13:50.072356 | controller | cd+++++++++ registries.conf.d/ 2025-10-07 15:13:50.072371 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf 2025-10-07 15:13:50.072387 | controller | cd+++++++++ system-config/ 2025-10-07 15:13:50.072402 | controller | cd+++++++++ system-config/libvirt/ 2025-10-07 15:13:50.072418 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf 2025-10-07 15:13:50.072433 | controller | >f+++++++++ system-config/libvirt/libvirt.conf 2025-10-07 15:13:50.806114 | controller | changed: .d..t...... ./ 2025-10-07 15:13:51.399887 | controller | changed: .d..t...... ./ 2025-10-07 15:13:51.426345 | 2025-10-07 15:13:51.426432 | TASK [Return artifact to Zuul] 2025-10-07 15:13:51.466784 | controller | ok 2025-10-07 15:13:51.490337 | 2025-10-07 15:13:51.490400 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host] 2025-10-07 15:13:51.490498 | 2025-10-07 15:13:51.490526 | PLAY RECAP 2025-10-07 15:13:51.490564 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1 2025-10-07 15:13:51.490585 | 2025-10-07 15:13:51.614619 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main] 2025-10-07 15:13:51.615962 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2025-10-07 15:13:52.240215 | 2025-10-07 15:13:52.240331 | PLAY [all] 2025-10-07 15:13:52.274056 | 2025-10-07 15:13:52.274169 | TASK [include_role : fetch-output] 2025-10-07 15:13:52.315592 | controller | ok 2025-10-07 15:13:52.333466 | 2025-10-07 15:13:52.333574 | TASK [fetch-output : Set log path for multiple nodes] 2025-10-07 15:13:52.401427 | controller | skipping: Conditional result was False 2025-10-07 15:13:52.407988 | 2025-10-07 15:13:52.408186 | TASK [fetch-output : Set log path for single node] 2025-10-07 15:13:52.457495 | controller | ok 2025-10-07 15:13:52.462645 | 2025-10-07 15:13:52.462717 | LOOP [fetch-output : Ensure local output dirs] 2025-10-07 15:13:52.971243 | controller -> localhost | ok: "/var/lib/zuul/builds/c6a8f38485074d3a881ba1725ff81a91/work/logs" 2025-10-07 15:13:53.162245 | controller -> localhost | ok: "/var/lib/zuul/builds/c6a8f38485074d3a881ba1725ff81a91/work/artifacts" 2025-10-07 15:13:53.505487 | controller -> localhost | ok: "/var/lib/zuul/builds/c6a8f38485074d3a881ba1725ff81a91/work/docs" 2025-10-07 15:13:53.517236 | 2025-10-07 15:13:53.517357 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-10-07 15:13:54.369582 | controller | ok 2025-10-07 15:13:54.369777 | controller | ok: All items complete 2025-10-07 15:13:54.369810 | 2025-10-07 15:13:55.037012 | controller | ok 2025-10-07 15:13:55.574013 | controller | ok 2025-10-07 15:13:55.593259 | 2025-10-07 15:13:55.593355 | TASK [include_role : fetch-output-openshift] 2025-10-07 15:13:55.629763 | controller | skipping: Conditional result was False 2025-10-07 15:13:55.642061 | 2025-10-07 15:13:55.642157 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-10-07 15:13:56.479403 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.031277 2025-10-07 15:13:56.692824 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.011600 2025-10-07 15:13:56.749367 | 2025-10-07 15:13:56.749470 | PLAY [all] 2025-10-07 15:13:56.775433 | 2025-10-07 15:13:56.775538 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-10-07 15:13:57.204786 | controller | changed 2025-10-07 15:13:57.249364 | 2025-10-07 15:13:57.249434 | PLAY RECAP 2025-10-07 15:13:57.249478 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2025-10-07 15:13:57.249501 | 2025-10-07 15:13:57.423829 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2025-10-07 15:13:57.424570 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master] 2025-10-07 15:13:58.228768 | 2025-10-07 15:13:58.228892 | PLAY [localhost] 2025-10-07 15:13:58.247744 | 2025-10-07 15:13:58.247845 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-10-07 15:13:58.611366 | localhost | changed 2025-10-07 15:13:58.616461 | 2025-10-07 15:13:58.616564 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-10-07 15:13:58.659552 | localhost | ok 2025-10-07 15:13:58.667842 | 2025-10-07 15:13:58.667958 | TASK [add-fileserver : Create SSH private key tempfile] 2025-10-07 15:13:59.241047 | localhost | changed 2025-10-07 15:13:59.245762 | 2025-10-07 15:13:59.245833 | TASK [add-fileserver : Create SSH private key from secret] 2025-10-07 15:14:00.319368 | localhost | changed 2025-10-07 15:14:00.324904 | 2025-10-07 15:14:00.324992 | TASK [add-fileserver : Add fileserver ssh key] 2025-10-07 15:14:01.106956 | localhost | Identity added: /var/lib/zuul/builds/c6a8f38485074d3a881ba1725ff81a91/work/tmp/ansible.zp1d670k (/var/lib/zuul/builds/c6a8f38485074d3a881ba1725ff81a91/work/tmp/ansible.zp1d670k) 2025-10-07 15:14:01.107128 | localhost | ok: Runtime: 0:00:00.032497 2025-10-07 15:14:01.111395 | 2025-10-07 15:14:01.111473 | TASK [add-fileserver : Remove SSH private key from disk] 2025-10-07 15:14:01.423520 | localhost | ok: Runtime: 0:00:00.006093 2025-10-07 15:14:01.428359 | 2025-10-07 15:14:01.428435 | TASK [add-fileserver : Add fileserver to inventory] 2025-10-07 15:14:01.516978 | localhost | changed 2025-10-07 15:14:01.522650 | 2025-10-07 15:14:01.522724 | TASK [add-fileserver : Add fileserver server to known hosts] 2025-10-07 15:14:02.658231 | localhost | changed 2025-10-07 15:14:02.749490 | 2025-10-07 15:14:02.749570 | PLAY [localhost] 2025-10-07 15:14:02.768508 | 2025-10-07 15:14:02.768584 | TASK [Generate bulk log download script] 2025-10-07 15:14:02.809896 | localhost | ok 2025-10-07 15:14:02.830206 | 2025-10-07 15:14:02.830292 | TASK [local-log-download : Check API endpoint is defined] 2025-10-07 15:14:02.891352 | localhost | ok: All assertions passed 2025-10-07 15:14:02.899469 | 2025-10-07 15:14:02.899537 | TASK [local-log-download : Create download script] 2025-10-07 15:14:03.934158 | localhost -> localhost | changed 2025-10-07 15:14:03.943327 | 2025-10-07 15:14:03.943405 | TASK [Register quick-download link] 2025-10-07 15:14:03.980754 | localhost | ok 2025-10-07 15:14:04.002273 | 2025-10-07 15:14:04.002346 | PLAY [logserver.rdoproject.org] 2025-10-07 15:14:04.021943 | 2025-10-07 15:14:04.022021 | TASK [Set zuul-log-path fact] 2025-10-07 15:14:04.038511 | logserver.rdoproject.org | ok 2025-10-07 15:14:04.054095 | 2025-10-07 15:14:04.054174 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-07 15:14:04.084681 | logserver.rdoproject.org | ok 2025-10-07 15:14:04.090395 | 2025-10-07 15:14:04.090473 | TASK [upload-logs : Create log directories] 2025-10-07 15:14:06.059542 | logserver.rdoproject.org | changed 2025-10-07 15:14:06.062820 | 2025-10-07 15:14:06.062892 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-10-07 15:14:06.376137 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.020706 2025-10-07 15:14:06.381304 | 2025-10-07 15:14:06.381379 | TASK [upload-logs : Upload logs to log server] 2025-10-07 15:14:07.955769 | logserver.rdoproject.org | Output suppressed because no_log was given 2025-10-07 15:14:07.958977 | 2025-10-07 15:14:07.959052 | LOOP [upload-logs : Compress console log and json output] 2025-10-07 15:14:08.033194 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-07 15:14:08.043265 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-07 15:14:08.047483 | 2025-10-07 15:14:08.047590 | LOOP [upload-logs : Upload compressed console log and json output] 2025-10-07 15:14:08.097519 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-07 15:14:08.097803 | 2025-10-07 15:14:08.099687 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-07 15:14:08.109380 | 2025-10-07 15:14:08.109484 | LOOP [upload-logs : Upload console log and json output]