2026-03-11 13:37:21.353593 | Job console starting...
2026-03-11 13:37:21.563269 | Updating repositories
2026-03-11 13:37:22.257314 | Preparing job workspace
2026-03-11 13:37:29.261170 | Running Ansible setup...
2026-03-11 13:37:31.995945 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2026-03-11 13:37:32.482861 | 
2026-03-11 13:37:32.482955 | PLAY [localhost]
2026-03-11 13:37:32.490995 | 
2026-03-11 13:37:32.491061 | TASK [Gathering Facts]
2026-03-11 13:37:33.264483 | localhost | ok
2026-03-11 13:37:33.276330 | 
2026-03-11 13:37:33.276428 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-03-11 13:37:33.583249 | localhost -> localhost | changed
2026-03-11 13:37:33.588900 | 
2026-03-11 13:37:33.588994 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-03-11 13:37:34.240256 | localhost -> localhost | changed
2026-03-11 13:37:34.251992 | 
2026-03-11 13:37:34.252119 | TASK [Setup log path fact]
2026-03-11 13:37:34.269809 | localhost | ok
2026-03-11 13:37:34.281073 | 
2026-03-11 13:37:34.281160 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-03-11 13:37:34.297554 | localhost | ok
2026-03-11 13:37:34.304746 | 
2026-03-11 13:37:34.304811 | TASK [emit-job-header : Print job information]
2026-03-11 13:37:34.350841 | # Job Information
2026-03-11 13:37:34.350966 | Ansible Version: 2.15.12
2026-03-11 13:37:34.350996 | Job: cifmw-molecule-dnsmasq
2026-03-11 13:37:34.351020 | Pipeline: github-check
2026-03-11 13:37:34.351042 | Executor: ibm-bm4-ze.softwarefactory-project.io
2026-03-11 13:37:34.351063 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3739
2026-03-11 13:37:34.351084 | Log URL (when completed): https://logserver.rdoproject.org/a2f/rdoproject.org/a2f71dd2d5e44ad6b4deedccae2d93d1/
2026-03-11 13:37:34.351104 | Event ID: 4b4b6450-1d4f-11f1-8860-794c26e414c1
2026-03-11 13:37:34.354693 | 
2026-03-11 13:37:34.354763 | LOOP [emit-job-header : Print node information]
2026-03-11 13:37:34.463604 | localhost | ok:
2026-03-11 13:37:34.463748 | localhost | # Node Information
2026-03-11 13:37:34.463779 | localhost | Inventory Hostname: controller
2026-03-11 13:37:34.463803 | localhost | Hostname: np0005644596
2026-03-11 13:37:34.463825 | localhost | Username: zuul
2026-03-11 13:37:34.463847 | localhost | Distro: CentOS 9
2026-03-11 13:37:34.463866 | localhost | Provider: ibm-bm4-nodepool
2026-03-11 13:37:34.463885 | localhost | Region: regionOne
2026-03-11 13:37:34.463903 | localhost | Label: cloud-centos-9-stream-tripleo-ibm
2026-03-11 13:37:34.463921 | localhost | Product Name: OpenStack Compute
2026-03-11 13:37:34.463939 | localhost | Interface IP: 192.168.26.243
2026-03-11 13:37:34.487047 | 
2026-03-11 13:37:34.487120 | PLAY [all]
2026-03-11 13:37:34.494093 | 
2026-03-11 13:37:34.494163 | TASK [Gather network facts]
2026-03-11 13:37:34.847475 | controller | ok
2026-03-11 13:37:34.860812 | 
2026-03-11 13:37:34.860875 | TASK [include_role : start-zuul-console]
2026-03-11 13:37:34.877424 | controller | ok
2026-03-11 13:37:34.889033 | 
2026-03-11 13:37:34.889098 | TASK [start-zuul-console : Start zuul_console daemon.]
2026-03-11 13:37:35.180606 | controller | ok
2026-03-11 13:37:35.188920 | 
2026-03-11 13:37:35.188994 | TASK [include_role : add-build-sshkey]
2026-03-11 13:37:35.206924 | controller | ok
2026-03-11 13:37:35.219598 | 
2026-03-11 13:37:35.219668 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-03-11 13:37:35.404968 | controller -> localhost | ok
2026-03-11 13:37:35.410269 | 
2026-03-11 13:37:35.410419 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-03-11 13:37:35.427827 | controller | ok
2026-03-11 13:37:35.440809 | controller | included: /var/lib/zuul/builds/a2f71dd2d5e44ad6b4deedccae2d93d1/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-03-11 13:37:35.445972 | 
2026-03-11 13:37:35.446030 | TASK [add-build-sshkey : Create Temp SSH key]
2026-03-11 13:37:35.874168 | controller -> localhost | Generating public/private rsa key pair.
2026-03-11 13:37:35.874406 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/a2f71dd2d5e44ad6b4deedccae2d93d1/work/a2f71dd2d5e44ad6b4deedccae2d93d1_id_rsa.
2026-03-11 13:37:35.874445 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/a2f71dd2d5e44ad6b4deedccae2d93d1/work/a2f71dd2d5e44ad6b4deedccae2d93d1_id_rsa.pub.
2026-03-11 13:37:35.874472 | controller -> localhost | The key fingerprint is:
2026-03-11 13:37:35.874494 | controller -> localhost | SHA256:NH21B1szC5pGiQVYeSd71ksc10qHBc+XUuxdNMxZxnw zuul-build-sshkey
2026-03-11 13:37:35.874516 | controller -> localhost | The key's randomart image is:
2026-03-11 13:37:35.874537 | controller -> localhost | +---[RSA 3072]----+
2026-03-11 13:37:35.874558 | controller -> localhost | |        oo=o..B%X|
2026-03-11 13:37:35.874580 | controller -> localhost | |       . +.+oo=#E|
2026-03-11 13:37:35.874600 | controller -> localhost | |        o o+=o*=@|
2026-03-11 13:37:35.874620 | controller -> localhost | |       . ..o oo*o|
2026-03-11 13:37:35.874639 | controller -> localhost | |        S   o . .|
2026-03-11 13:37:35.874659 | controller -> localhost | |               . |
2026-03-11 13:37:35.874677 | controller -> localhost | |                 |
2026-03-11 13:37:35.874696 | controller -> localhost | |                 |
2026-03-11 13:37:35.874715 | controller -> localhost | |                 |
2026-03-11 13:37:35.874733 | controller -> localhost | +----[SHA256]-----+
2026-03-11 13:37:35.874783 | controller -> localhost | ok: Runtime: 0:00:00.108697
2026-03-11 13:37:35.880655 | 
2026-03-11 13:37:35.880723 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-03-11 13:37:35.912966 | controller | ok
2026-03-11 13:37:35.922130 | controller | included: /var/lib/zuul/builds/a2f71dd2d5e44ad6b4deedccae2d93d1/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-03-11 13:37:35.947513 | 
2026-03-11 13:37:35.947613 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-03-11 13:37:35.960842 | controller | skipping: Conditional result was False
2026-03-11 13:37:35.966891 | 
2026-03-11 13:37:35.966980 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-03-11 13:37:36.384688 | controller | changed
2026-03-11 13:37:36.389649 | 
2026-03-11 13:37:36.389712 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-03-11 13:37:36.589376 | controller | ok
2026-03-11 13:37:36.594219 | 
2026-03-11 13:37:36.594310 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-03-11 13:37:37.138065 | controller | changed
2026-03-11 13:37:37.143135 | 
2026-03-11 13:37:37.143212 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-03-11 13:37:37.687056 | controller | changed
2026-03-11 13:37:37.692714 | 
2026-03-11 13:37:37.692794 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-03-11 13:37:37.726751 | controller | skipping: Conditional result was False
2026-03-11 13:37:37.731768 | 
2026-03-11 13:37:37.731837 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-03-11 13:37:38.039200 | controller -> localhost | changed
2026-03-11 13:37:38.047495 | 
2026-03-11 13:37:38.047553 | TASK [add-build-sshkey : Add back temp key]
2026-03-11 13:37:38.293077 | controller -> localhost | Identity added: /var/lib/zuul/builds/a2f71dd2d5e44ad6b4deedccae2d93d1/work/a2f71dd2d5e44ad6b4deedccae2d93d1_id_rsa (zuul-build-sshkey)
2026-03-11 13:37:38.293252 | controller -> localhost | ok: Runtime: 0:00:00.006601
2026-03-11 13:37:38.298403 | 
2026-03-11 13:37:38.298467 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-03-11 13:37:38.589429 | controller | ok
2026-03-11 13:37:38.594200 | 
2026-03-11 13:37:38.594268 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-03-11 13:37:38.618281 | controller | skipping: Conditional result was False
2026-03-11 13:37:38.627065 | 
2026-03-11 13:37:38.627130 | TASK [include_role : validate-host]
2026-03-11 13:37:38.644585 | controller | ok
2026-03-11 13:37:38.662962 | 
2026-03-11 13:37:38.663032 | TASK [validate-host : Define zuul_info_dir fact]
2026-03-11 13:37:38.690540 | controller | ok
2026-03-11 13:37:38.694917 | 
2026-03-11 13:37:38.694981 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2026-03-11 13:37:38.891532 | controller -> localhost | ok
2026-03-11 13:37:38.896987 | 
2026-03-11 13:37:38.897057 | TASK [validate-host : Collect information about the host]
2026-03-11 13:37:39.482840 | controller | ok
2026-03-11 13:37:39.491010 | 
2026-03-11 13:37:39.491094 | TASK [validate-host : Sanitize hostname]
2026-03-11 13:37:39.559378 | controller | ok
2026-03-11 13:37:39.565247 | 
2026-03-11 13:37:39.565344 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2026-03-11 13:37:39.976713 | controller -> localhost | changed
2026-03-11 13:37:39.981680 | 
2026-03-11 13:37:39.981746 | TASK [validate-host : Collect information about zuul worker]
2026-03-11 13:37:40.284116 | controller | ok
2026-03-11 13:37:40.288730 | 
2026-03-11 13:37:40.288812 | TASK [validate-host : Write out all zuul information for each host]
2026-03-11 13:37:40.637442 | controller -> localhost | changed
2026-03-11 13:37:40.646278 | 
2026-03-11 13:37:40.646375 | TASK [include_role : prepare-workspace-openshift]
2026-03-11 13:37:40.659722 | controller | skipping: Conditional result was False
2026-03-11 13:37:40.664766 | 
2026-03-11 13:37:40.664825 | TASK [include_role : remove-zuul-sshkey]
2026-03-11 13:37:40.677811 | controller | skipping: Conditional result was False
2026-03-11 13:37:40.682577 | 
2026-03-11 13:37:40.682649 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-03-11 13:37:40.863308 | controller | ok: "logs"
2026-03-11 13:37:40.863572 | controller | ok: All items complete
2026-03-11 13:37:40.863602 | 
2026-03-11 13:37:41.016824 | controller | ok: "artifacts"
2026-03-11 13:37:41.175648 | controller | ok: "docs"
2026-03-11 13:37:41.188852 | 
2026-03-11 13:37:41.188958 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-03-11 13:37:41.372937 | controller | changed: "logs"
2026-03-11 13:37:41.545990 | controller | changed: "artifacts"
2026-03-11 13:37:41.704082 | controller | changed: "docs"
2026-03-11 13:37:41.730838 | 
2026-03-11 13:37:41.730925 | PLAY RECAP
2026-03-11 13:37:41.730972 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2026-03-11 13:37:41.731002 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-03-11 13:37:41.731023 | 
2026-03-11 13:37:41.802457 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2026-03-11 13:37:41.803167 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2026-03-11 13:37:42.329996 | 
2026-03-11 13:37:42.330135 | PLAY [all]
2026-03-11 13:37:42.349415 | 
2026-03-11 13:37:42.349493 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2026-03-11 13:37:42.397912 | controller | ok
2026-03-11 13:37:42.406447 | 
2026-03-11 13:37:42.406574 | TASK [mirror-info-fork : Create /etc/ci]
2026-03-11 13:37:42.729223 | controller | changed
2026-03-11 13:37:42.734827 | 
2026-03-11 13:37:42.734911 | TASK [mirror-info-fork : Install ci_mirror script]
2026-03-11 13:37:43.492748 | controller | changed
2026-03-11 13:37:43.501889 | 
2026-03-11 13:37:43.501963 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2026-03-11 13:37:43.855488 | controller | changed:
2026-03-11 13:37:43.855657 | controller | {
2026-03-11 13:37:43.855689 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2026-03-11 13:37:43.855721 | controller | }
2026-03-11 13:37:44.043685 | controller | changed:
2026-03-11 13:37:44.043741 | controller | {
2026-03-11 13:37:44.043771 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2026-03-11 13:37:44.043794 | controller | }
2026-03-11 13:37:44.238432 | controller | changed:
2026-03-11 13:37:44.238480 | controller | {
2026-03-11 13:37:44.238504 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com"
2026-03-11 13:37:44.238523 | controller | }
2026-03-11 13:37:44.440960 | controller | changed:
2026-03-11 13:37:44.441015 | controller | {
2026-03-11 13:37:44.441043 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2026-03-11 13:37:44.441063 | controller | }
2026-03-11 13:37:44.641409 | controller | changed:
2026-03-11 13:37:44.641497 | controller | {
2026-03-11 13:37:44.641531 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2026-03-11 13:37:44.641559 | controller | }
2026-03-11 13:37:44.836404 | controller | changed:
2026-03-11 13:37:44.836474 | controller | {
2026-03-11 13:37:44.836503 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2026-03-11 13:37:44.836525 | controller | }
2026-03-11 13:37:45.028180 | controller | changed:
2026-03-11 13:37:45.028256 | controller | {
2026-03-11 13:37:45.028290 | 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"
2026-03-11 13:37:45.028341 | controller | }
2026-03-11 13:37:45.227083 | controller | changed:
2026-03-11 13:37:45.227143 | controller | {
2026-03-11 13:37:45.227172 | 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"
2026-03-11 13:37:45.227194 | controller | }
2026-03-11 13:37:45.418833 | controller | changed:
2026-03-11 13:37:45.418917 | controller | {
2026-03-11 13:37:45.418946 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2026-03-11 13:37:45.418967 | controller | }
2026-03-11 13:37:45.619751 | controller | changed:
2026-03-11 13:37:45.619820 | controller | {
2026-03-11 13:37:45.619848 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2026-03-11 13:37:45.619869 | controller | }
2026-03-11 13:37:45.813608 | controller | changed:
2026-03-11 13:37:45.813677 | controller | {
2026-03-11 13:37:45.813703 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2026-03-11 13:37:45.813725 | controller | }
2026-03-11 13:37:46.035117 | controller | changed:
2026-03-11 13:37:46.035205 | controller | {
2026-03-11 13:37:46.035233 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2026-03-11 13:37:46.035255 | controller | }
2026-03-11 13:37:46.211056 | controller | changed:
2026-03-11 13:37:46.211138 | controller | {
2026-03-11 13:37:46.211165 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICWBreHW95Wz2Toz5YwCGQwFcUG8oFYkienDh9tntmDc ralfieri@redhat.com"
2026-03-11 13:37:46.211186 | controller | }
2026-03-11 13:37:46.417161 | controller | changed:
2026-03-11 13:37:46.417255 | controller | {
2026-03-11 13:37:46.417283 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2026-03-11 13:37:46.417326 | controller | }
2026-03-11 13:37:46.618684 | controller | changed:
2026-03-11 13:37:46.618782 | controller | {
2026-03-11 13:37:46.618809 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2026-03-11 13:37:46.618828 | controller | }
2026-03-11 13:37:46.821287 | controller | changed:
2026-03-11 13:37:46.821464 | controller | {
2026-03-11 13:37:46.821490 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2026-03-11 13:37:46.821509 | controller | }
2026-03-11 13:37:47.017938 | controller | changed:
2026-03-11 13:37:47.018028 | controller | {
2026-03-11 13:37:47.018056 | 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"
2026-03-11 13:37:47.018078 | controller | }
2026-03-11 13:37:47.218447 | controller | changed:
2026-03-11 13:37:47.218549 | controller | {
2026-03-11 13:37:47.218578 | 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"
2026-03-11 13:37:47.218607 | controller | }
2026-03-11 13:37:47.422153 | controller | changed:
2026-03-11 13:37:47.422332 | controller | {
2026-03-11 13:37:47.422376 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2026-03-11 13:37:47.422411 | controller | }
2026-03-11 13:37:47.618176 | controller | changed:
2026-03-11 13:37:47.618271 | controller | {
2026-03-11 13:37:47.618324 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2026-03-11 13:37:47.618352 | controller | }
2026-03-11 13:37:47.816765 | controller | changed:
2026-03-11 13:37:47.816855 | controller | {
2026-03-11 13:37:47.816882 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2026-03-11 13:37:47.816904 | controller | }
2026-03-11 13:37:48.012088 | controller | changed:
2026-03-11 13:37:48.012158 | controller | {
2026-03-11 13:37:48.012186 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2026-03-11 13:37:48.012207 | controller | }
2026-03-11 13:37:48.211805 | controller | changed:
2026-03-11 13:37:48.211860 | controller | {
2026-03-11 13:37:48.211887 | 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"
2026-03-11 13:37:48.211910 | controller | }
2026-03-11 13:37:48.410000 | controller | changed:
2026-03-11 13:37:48.410063 | controller | {
2026-03-11 13:37:48.410089 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2026-03-11 13:37:48.410109 | controller | }
2026-03-11 13:37:48.609527 | controller | changed:
2026-03-11 13:37:48.609705 | controller | {
2026-03-11 13:37:48.609742 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2026-03-11 13:37:48.609766 | controller | }
2026-03-11 13:37:48.814231 | controller | changed:
2026-03-11 13:37:48.814331 | controller | {
2026-03-11 13:37:48.814361 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2026-03-11 13:37:48.814381 | controller | }
2026-03-11 13:37:48.838748 | 
2026-03-11 13:37:48.838831 | TASK [Set timezone to UTC]
2026-03-11 13:37:49.212452 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2026-03-11 13:37:49.217256 | 
2026-03-11 13:37:49.217350 | TASK [Create nodepool directory]
2026-03-11 13:37:49.408749 | controller | changed
2026-03-11 13:37:49.414200 | 
2026-03-11 13:37:49.414270 | TASK [Create nodepool sub_nodes file]
2026-03-11 13:37:49.887909 | controller | changed
2026-03-11 13:37:49.892775 | 
2026-03-11 13:37:49.892842 | TASK [Create nodepool sub_nodes_private file]
2026-03-11 13:37:50.376265 | controller | changed
2026-03-11 13:37:50.381627 | 
2026-03-11 13:37:50.381693 | LOOP [Populate nodepool sub_nodes file]
2026-03-11 13:37:50.410714 | 
2026-03-11 13:37:50.410835 | LOOP [Populate nodepool sub_nodes_private file]
2026-03-11 13:37:50.439415 | 
2026-03-11 13:37:50.439537 | TASK [Create nodepool primary file]
2026-03-11 13:37:50.463572 | controller | skipping: Conditional result was False
2026-03-11 13:37:50.468405 | 
2026-03-11 13:37:50.468470 | TASK [Create nodepool node_private for this node]
2026-03-11 13:37:50.985089 | controller | changed
2026-03-11 13:37:50.990810 | 
2026-03-11 13:37:50.990885 | LOOP [Copy ssh keys to nodepool directory]
2026-03-11 13:37:51.301863 | controller | ok: Item: id_rsa Runtime: 0:00:00.004308
2026-03-11 13:37:51.302280 | 
2026-03-11 13:37:51.455458 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.004240
2026-03-11 13:37:51.466867 | 
2026-03-11 13:37:51.466937 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2026-03-11 13:37:51.982584 | controller | changed
2026-03-11 13:37:51.987494 | 
2026-03-11 13:37:51.987558 | TASK [Validate sudoers config after edits]
2026-03-11 13:37:52.198134 | controller | /etc/sudoers: parsed OK
2026-03-11 13:37:52.198211 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2026-03-11 13:37:52.198222 | controller | /etc/sudoers.d/zuul: parsed OK
2026-03-11 13:37:52.198228 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2026-03-11 13:37:52.512593 | controller | ok: Runtime: 0:00:00.005430
2026-03-11 13:37:52.517755 | 
2026-03-11 13:37:52.517820 | TASK [Show the environment passed in to job shell scripts]
2026-03-11 13:37:52.721526 | controller | SHELL=/bin/bash
2026-03-11 13:37:52.721598 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2026-03-11 13:37:52.721617 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2026-03-11 13:37:52.721668 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/39/3739/7929c2f3dcbffd8aa11d086b20be58a43108ce8b
2026-03-11 13:37:52.721677 | controller | PWD=/home/zuul
2026-03-11 13:37:52.721683 | controller | ZUUL_PIPELINE=github-check
2026-03-11 13:37:52.721689 | controller | LOGNAME=zuul
2026-03-11 13:37:52.721694 | controller | XDG_SESSION_TYPE=tty
2026-03-11 13:37:52.721700 | controller | _=/usr/bin/env
2026-03-11 13:37:52.721705 | controller | MOTD_SHOWN=pam
2026-03-11 13:37:52.721710 | controller | HOME=/home/zuul
2026-03-11 13:37:52.721715 | controller | LANG=en_US.UTF-8
2026-03-11 13:37:52.721721 | controller | SSH_CONNECTION=192.168.26.12 33694 192.168.26.243 22
2026-03-11 13:37:52.721726 | 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
2026-03-11 13:37:52.721735 | controller | ZUUL_CHANGE_IDS=3739,7929c2f3dcbffd8aa11d086b20be58a43108ce8b
2026-03-11 13:37:52.721740 | controller | WORKSPACE=/home/zuul/workspace
2026-03-11 13:37:52.721745 | controller | XDG_SESSION_CLASS=user
2026-03-11 13:37:52.721751 | controller | SELINUX_ROLE_REQUESTED=
2026-03-11 13:37:52.721756 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2026-03-11 13:37:52.721761 | controller | USER=zuul
2026-03-11 13:37:52.721767 | controller | ZUUL_VOTING=True
2026-03-11 13:37:52.721772 | controller | BUILD_TIMEOUT=1800000
2026-03-11 13:37:52.721777 | controller | SELINUX_USE_CURRENT_RANGE=
2026-03-11 13:37:52.721782 | controller | SHLVL=1
2026-03-11 13:37:52.721788 | controller | ZUUL_PATCHSET=7929c2f3dcbffd8aa11d086b20be58a43108ce8b
2026-03-11 13:37:52.721793 | controller | XDG_SESSION_ID=1
2026-03-11 13:37:52.721799 | controller | ZUUL_BRANCH=main
2026-03-11 13:37:52.721804 | controller | XDG_RUNTIME_DIR=/run/user/1000
2026-03-11 13:37:52.721809 | controller | SSH_CLIENT=192.168.26.12 33694 22
2026-03-11 13:37:52.721815 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2026-03-11 13:37:52.721820 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2026-03-11 13:37:52.721825 | controller | which_declare=declare -f
2026-03-11 13:37:52.721831 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2026-03-11 13:37:52.721837 | controller | SELINUX_LEVEL_REQUESTED=
2026-03-11 13:37:52.721842 | controller | ZUUL_CHANGE=3739
2026-03-11 13:37:52.721847 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2026-03-11 13:37:52.721852 | controller | ZUUL_UUID=a2f71dd2d5e44ad6b4deedccae2d93d1
2026-03-11 13:37:52.721858 | controller | BASH_FUNC_which%%=() {  ( alias;
2026-03-11 13:37:52.721863 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2026-03-11 13:37:52.721869 | controller | }
2026-03-11 13:37:53.042252 | controller | ok: Runtime: 0:00:00.005258
2026-03-11 13:37:53.047885 | 
2026-03-11 13:37:53.047951 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2026-03-11 13:37:53.062102 | controller | skipping: Conditional result was False
2026-03-11 13:37:53.067591 | 
2026-03-11 13:37:53.067860 | TASK [Symlink /home/zuul-worker/workspace]
2026-03-11 13:37:53.590046 | controller | skipping: Conditional result was False
2026-03-11 13:37:53.595850 | 
2026-03-11 13:37:53.595916 | TASK [Ensure legacy workspace directory]
2026-03-11 13:37:53.776412 | controller | changed
2026-03-11 13:37:53.793845 | 
2026-03-11 13:37:53.793900 | PLAY RECAP
2026-03-11 13:37:53.793948 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2026-03-11 13:37:53.793970 | 
2026-03-11 13:37:53.863224 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2026-03-11 13:37:53.864017 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2026-03-11 13:37:54.370703 | 
2026-03-11 13:37:54.370796 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2026-03-11 13:37:54.396895 | 
2026-03-11 13:37:54.396967 | TASK [Create zuul-output directory]
2026-03-11 13:37:54.689911 | controller | changed
2026-03-11 13:37:54.694958 | 
2026-03-11 13:37:54.695042 | TASK [Slurp Zuul inventory test]
2026-03-11 13:37:55.003615 | controller -> localhost | ok
2026-03-11 13:37:55.011733 | 
2026-03-11 13:37:55.011820 | TASK [Save zuul inventory]
2026-03-11 13:37:55.732367 | controller | changed
2026-03-11 13:37:55.737269 | 
2026-03-11 13:37:55.737351 | TASK [Save zuul vars without the change_message]
2026-03-11 13:37:56.293369 | controller | changed
2026-03-11 13:37:56.311481 | 
2026-03-11 13:37:56.311546 | PLAY RECAP
2026-03-11 13:37:56.311589 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-03-11 13:37:56.311611 | 
2026-03-11 13:37:56.373581 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2026-03-11 13:37:56.374375 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2026-03-11 13:37:56.932167 | 
2026-03-11 13:37:56.932273 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2026-03-11 13:37:56.952992 | 
2026-03-11 13:37:56.953063 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2026-03-11 13:37:56.976815 | controller | ok
2026-03-11 13:37:56.993261 | 
2026-03-11 13:37:56.993358 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2026-03-11 13:37:57.016273 | controller | skipping: Conditional result was False
2026-03-11 13:37:57.021819 | 
2026-03-11 13:37:57.021891 | TASK [mirror-info-fork : Create /etc/ci]
2026-03-11 13:37:57.322982 | controller | ok
2026-03-11 13:37:57.328390 | 
2026-03-11 13:37:57.328460 | TASK [mirror-info-fork : Install ci_mirror script]
2026-03-11 13:37:57.914474 | controller | ok
2026-03-11 13:37:57.924261 | 
2026-03-11 13:37:57.924339 | TASK [Prepare workspace]
2026-03-11 13:37:57.942206 | controller | ok
2026-03-11 13:37:57.959499 | 
2026-03-11 13:37:57.959567 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-03-11 13:37:58.245679 | controller | ok
2026-03-11 13:37:58.251948 | 
2026-03-11 13:37:58.252023 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-03-11 13:38:00.675979 | controller | Output suppressed because no_log was given
2026-03-11 13:38:00.685219 | 
2026-03-11 13:38:00.685624 | LOOP [Create zuul-output directory]
2026-03-11 13:38:00.870919 | controller | changed: "/home/zuul/ci-framework-data/logs"
2026-03-11 13:38:01.023930 | controller | ok: "/home/zuul/zuul-output/logs"
2026-03-11 13:38:01.034038 | 
2026-03-11 13:38:01.034131 | TASK [Install required packages]
2026-03-11 13:38:35.803696 | controller | changed
2026-03-11 13:38:35.808644 | 
2026-03-11 13:38:35.808704 | TASK [Install venv]
2026-03-11 13:39:40.700578 | controller | changed
2026-03-11 13:39:40.724520 | 
2026-03-11 13:39:40.724585 | PLAY RECAP
2026-03-11 13:39:40.724630 | controller | ok: 7 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-03-11 13:39:40.724654 | 
2026-03-11 13:39:40.796115 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2026-03-11 13:39:40.797042 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2026-03-11 13:39:41.304249 | 
2026-03-11 13:39:41.304388 | PLAY [Run ci/playbooks/molecule-test.yml]
2026-03-11 13:39:41.324772 | 
2026-03-11 13:39:41.324845 | TASK [Gather required facts]
2026-03-11 13:39:41.789518 | controller | ok
2026-03-11 13:39:41.794884 | 
2026-03-11 13:39:41.794965 | TASK [Load environment var if instructed to]
2026-03-11 13:39:41.818528 | controller | skipping: Conditional result was False
2026-03-11 13:39:41.823850 | 
2026-03-11 13:39:41.823918 | TASK [Ensure group_vars dir exists]
2026-03-11 13:39:42.115364 | controller | ok
2026-03-11 13:39:42.121013 | 
2026-03-11 13:39:42.121078 | TASK [Print related variables]
2026-03-11 13:39:42.149274 | controller | ok:
2026-03-11 13:39:42.149412 | controller | mol_config_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config_local.yml
2026-03-11 13:39:42.149442 | controller | roles_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/dnsmasq
2026-03-11 13:39:42.154127 | 
2026-03-11 13:39:42.154212 | TASK [Run molecule]
2026-03-11 13:39:43.069026 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2026-03-11 13:39:43.138700 | controller | INFO     Performing prerun with role_name_check=0...
2026-03-11 13:40:00.663130 | 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).
2026-03-11 13:40:00.663520 | 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).
2026-03-11 13:40:00.663923 | 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).
2026-03-11 13:40:00.664269 | 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).
2026-03-11 13:40:00.664711 | controller | WARNING  Another version of 'cifmw.general' 1.0.0+956555d5 was found installed in /home/zuul/.cache/ansible-compat/e24347/collections/ansible_collections, only the first one will be used, 1.0.0+956555d5 (/home/zuul/.ansible/collections/ansible_collections).
2026-03-11 13:40:00.665103 | 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).
2026-03-11 13:40:00.665510 | 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).
2026-03-11 13:40:00.665898 | 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).
2026-03-11 13:40:00.666275 | 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).
2026-03-11 13:40:00.666714 | 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).
2026-03-11 13:40:00.667104 | 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).
2026-03-11 13:40:00.667570 | 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).
2026-03-11 13:40:00.667984 | 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).
2026-03-11 13:40:00.668577 | 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).
2026-03-11 13:40:00.668981 | 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).
2026-03-11 13:40:00.678226 | controller | INFO     Running default > prepare
2026-03-11 13:40:01.320844 | controller |
2026-03-11 13:40:01.320924 | controller | PLAY [Prepare] *****************************************************************
2026-03-11 13:40:01.321013 | controller |
2026-03-11 13:40:01.321105 | controller | TASK [Gathering Facts] *********************************************************
2026-03-11 13:40:01.321196 | controller | Wednesday 11 March 2026  13:40:01 +0000 (0:00:00.012)       0:00:00.012 *******
2026-03-11 13:40:02.141141 | controller | ok: [instance]
2026-03-11 13:40:02.141217 | controller |
2026-03-11 13:40:02.141370 | controller | TASK [test_deps : Gather facts if they don't exist] ****************************
2026-03-11 13:40:02.141504 | controller | Wednesday 11 March 2026  13:40:02 +0000 (0:00:00.820)       0:00:00.833 *******
2026-03-11 13:40:02.158045 | controller | skipping: [instance]
2026-03-11 13:40:02.158149 | controller |
2026-03-11 13:40:02.158269 | controller | TASK [test_deps : Gather variables for each operating system] ******************
2026-03-11 13:40:02.158430 | controller | Wednesday 11 March 2026  13:40:02 +0000 (0:00:00.017)       0:00:00.850 *******
2026-03-11 13:40:02.197882 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/test_deps/vars/centos-9.yml)
2026-03-11 13:40:02.197986 | controller |
2026-03-11 13:40:02.198101 | controller | TASK [test_deps : Detect mirrors file] *****************************************
2026-03-11 13:40:02.198215 | controller | Wednesday 11 March 2026  13:40:02 +0000 (0:00:00.039)       0:00:00.890 *******
2026-03-11 13:40:02.467477 | controller | ok: [instance]
2026-03-11 13:40:02.467548 | controller |
2026-03-11 13:40:02.467655 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] ***********
2026-03-11 13:40:02.467753 | controller | Wednesday 11 March 2026  13:40:02 +0000 (0:00:00.269)       0:00:01.159 *******
2026-03-11 13:40:02.771110 | controller | ok: [instance]
2026-03-11 13:40:02.771179 | controller |
2026-03-11 13:40:02.771280 | controller | TASK [test_deps : Disable ubi 9 repos] *****************************************
2026-03-11 13:40:02.771416 | controller | Wednesday 11 March 2026  13:40:02 +0000 (0:00:00.303)       0:00:01.463 *******
2026-03-11 13:40:03.449334 | controller | ok: [instance] => (item=ubi-9-appstream)
2026-03-11 13:40:03.449422 | controller | ok: [instance] => (item=ubi-9-baseos)
2026-03-11 13:40:03.449521 | controller | ok: [instance] => (item=ubi-9-codeready-builder)
2026-03-11 13:40:03.449614 | controller |
2026-03-11 13:40:03.449710 | controller | TASK [Deploy repo-setup] *******************************************************
2026-03-11 13:40:03.449803 | controller | Wednesday 11 March 2026  13:40:03 +0000 (0:00:00.678)       0:00:02.141 *******
2026-03-11 13:40:03.507408 | controller |
2026-03-11 13:40:03.507544 | controller | TASK [repo_setup : Ensure directories are present] *****************************
2026-03-11 13:40:03.507676 | controller | Wednesday 11 March 2026  13:40:03 +0000 (0:00:00.058)       0:00:02.199 *******
2026-03-11 13:40:04.022640 | controller | changed: [instance] => (item=tmp)
2026-03-11 13:40:04.022713 | controller | changed: [instance] => (item=artifacts/repositories)
2026-03-11 13:40:04.022819 | controller | changed: [instance] => (item=venv/repo_setup)
2026-03-11 13:40:04.022914 | controller |
2026-03-11 13:40:04.023016 | controller | TASK [repo_setup : Make sure git-core package is installed] ********************
2026-03-11 13:40:04.023114 | controller | Wednesday 11 March 2026  13:40:04 +0000 (0:00:00.515)       0:00:02.714 *******
2026-03-11 13:40:04.800941 | controller | ok: [instance]
2026-03-11 13:40:04.801241 | controller |
2026-03-11 13:40:05.939763 | controller | TASK [repo_setup : Get repo-setup repository] **********************************
2026-03-11 13:40:05.939792 | controller | Wednesday 11 March 2026  13:40:04 +0000 (0:00:00.778)       0:00:03.493 *******
2026-03-11 13:40:05.939807 | controller | changed: [instance]
2026-03-11 13:40:13.508621 | controller |
2026-03-11 13:40:13.508660 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************
2026-03-11 13:40:13.508669 | controller | Wednesday 11 March 2026  13:40:05 +0000 (0:00:01.138)       0:00:04.631 *******
2026-03-11 13:40:13.508679 | controller | changed: [instance]
2026-03-11 13:40:14.186376 | controller |
2026-03-11 13:40:14.186457 | controller | TASK [repo_setup : Install repo-setup package] *********************************
2026-03-11 13:40:14.186466 | controller | Wednesday 11 March 2026  13:40:13 +0000 (0:00:07.568)       0:00:12.200 *******
2026-03-11 13:40:14.186478 | controller | changed: [instance]
2026-03-11 13:40:14.186739 | controller |
2026-03-11 13:40:14.207631 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
2026-03-11 13:40:14.207653 | controller | Wednesday 11 March 2026  13:40:14 +0000 (0:00:00.678)       0:00:12.878 *******
2026-03-11 13:40:14.207663 | controller | skipping: [instance]
2026-03-11 13:40:15.010366 | controller |
2026-03-11 13:40:15.010401 | controller | TASK [repo_setup : Run repo-setup] *********************************************
2026-03-11 13:40:15.010409 | controller | Wednesday 11 March 2026  13:40:14 +0000 (0:00:00.021)       0:00:12.899 *******
2026-03-11 13:40:15.010430 | controller | changed: [instance]
2026-03-11 13:40:15.010544 | controller |
2026-03-11 13:40:15.010555 | controller | TASK [repo_setup : Get component repo] *****************************************
2026-03-11 13:40:15.010563 | controller | Wednesday 11 March 2026  13:40:15 +0000 (0:00:00.802)       0:00:13.702 *******
2026-03-11 13:40:15.045579 | controller | skipping: [instance]
2026-03-11 13:40:15.080391 | controller |
2026-03-11 13:40:15.080434 | controller | TASK [repo_setup : Rename component repo] **************************************
2026-03-11 13:40:15.080443 | controller | Wednesday 11 March 2026  13:40:15 +0000 (0:00:00.035)       0:00:13.737 *******
2026-03-11 13:40:15.080453 | controller | skipping: [instance]
2026-03-11 13:40:15.080477 | controller |
2026-03-11 13:40:15.080485 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
2026-03-11 13:40:15.080581 | controller | Wednesday 11 March 2026  13:40:15 +0000 (0:00:00.034)       0:00:13.772 *******
2026-03-11 13:40:15.115004 | controller | skipping: [instance]
2026-03-11 13:40:15.646162 | controller |
2026-03-11 13:40:15.646192 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************
2026-03-11 13:40:15.646200 | controller | Wednesday 11 March 2026  13:40:15 +0000 (0:00:00.034)       0:00:13.806 *******
2026-03-11 13:40:15.646210 | controller | changed: [instance]
2026-03-11 13:40:16.080876 | controller |
2026-03-11 13:40:16.080908 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
2026-03-11 13:40:16.080916 | controller | Wednesday 11 March 2026  13:40:15 +0000 (0:00:00.530)       0:00:14.337 *******
2026-03-11 13:40:16.080926 | controller | changed: [instance]
2026-03-11 13:40:16.109900 | controller |
2026-03-11 13:40:16.109925 | controller | TASK [repo_setup : Dump current-podified hash] *********************************
2026-03-11 13:40:16.109934 | controller | Wednesday 11 March 2026  13:40:16 +0000 (0:00:00.434)       0:00:14.772 *******
2026-03-11 13:40:16.109943 | controller | skipping: [instance]
2026-03-11 13:40:16.138728 | controller |
2026-03-11 13:40:16.138752 | controller | TASK [repo_setup : Slurp current podified hash] ********************************
2026-03-11 13:40:16.138759 | controller | Wednesday 11 March 2026  13:40:16 +0000 (0:00:00.029)       0:00:14.801 *******
2026-03-11 13:40:16.138769 | controller | skipping: [instance]
2026-03-11 13:40:16.166891 | controller |
2026-03-11 13:40:16.166911 | controller | TASK [repo_setup : Update the value of full_hash] ******************************
2026-03-11 13:40:16.166919 | controller | Wednesday 11 March 2026  13:40:16 +0000 (0:00:00.028)       0:00:14.830 *******
2026-03-11 13:40:16.166931 | controller | skipping: [instance]
2026-03-11 13:40:16.204451 | controller |
2026-03-11 13:40:16.204503 | controller | TASK [repo_setup : Export hashes facts for further use] ************************
2026-03-11 13:40:16.204528 | controller | Wednesday 11 March 2026  13:40:16 +0000 (0:00:00.028)       0:00:14.859 *******
2026-03-11 13:40:16.204546 | controller | ok: [instance]
2026-03-11 13:40:16.204576 | controller |
2026-03-11 13:40:16.204589 | controller | TASK [repo_setup : Create download directory] **********************************
2026-03-11 13:40:16.204662 | controller | Wednesday 11 March 2026  13:40:16 +0000 (0:00:00.037)       0:00:14.896 *******
2026-03-11 13:40:16.231405 | controller | skipping: [instance]
2026-03-11 13:40:16.231464 | controller |
2026-03-11 13:40:16.231475 | controller | TASK [repo_setup : Print the URL to request] ***********************************
2026-03-11 13:40:16.231592 | controller | Wednesday 11 March 2026  13:40:16 +0000 (0:00:00.026)       0:00:14.923 *******
2026-03-11 13:40:16.256896 | controller | skipping: [instance]
2026-03-11 13:40:16.282957 | controller |
2026-03-11 13:40:16.282976 | controller | TASK [Download the RPM] ********************************************************
2026-03-11 13:40:16.282986 | controller | Wednesday 11 March 2026  13:40:16 +0000 (0:00:00.025)       0:00:14.949 *******
2026-03-11 13:40:16.282997 | controller | skipping: [instance]
2026-03-11 13:40:16.308382 | controller |
2026-03-11 13:40:16.308414 | controller | TASK [repo_setup : Install RHOS Release tool] **********************************
2026-03-11 13:40:16.308432 | controller | Wednesday 11 March 2026  13:40:16 +0000 (0:00:00.025)       0:00:14.975 *******
2026-03-11 13:40:16.308443 | controller | skipping: [instance]
2026-03-11 13:40:16.308469 | controller |
2026-03-11 13:40:16.308477 | controller | TASK [repo_setup : Get rhos-release tool version] ******************************
2026-03-11 13:40:16.308539 | controller | Wednesday 11 March 2026  13:40:16 +0000 (0:00:00.025)       0:00:15.000 *******
2026-03-11 13:40:16.334560 | controller | skipping: [instance]
2026-03-11 13:40:16.361980 | controller |
2026-03-11 13:40:16.362000 | controller | TASK [repo_setup : Print rhos-release tool version] ****************************
2026-03-11 13:40:16.362010 | controller | Wednesday 11 March 2026  13:40:16 +0000 (0:00:00.025)       0:00:15.026 *******
2026-03-11 13:40:16.362020 | controller | skipping: [instance]
2026-03-11 13:40:16.386960 | controller |
2026-03-11 13:40:16.386978 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
2026-03-11 13:40:16.386986 | controller | Wednesday 11 March 2026  13:40:16 +0000 (0:00:00.027)       0:00:15.054 *******
2026-03-11 13:40:16.386998 | controller | skipping: [instance]
2026-03-11 13:40:16.565698 | controller |
2026-03-11 13:40:16.565728 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
2026-03-11 13:40:16.565737 | controller | Wednesday 11 March 2026  13:40:16 +0000 (0:00:00.024)       0:00:15.079 *******
2026-03-11 13:40:16.565747 | controller | ok: [instance]
2026-03-11 13:40:16.770074 | controller |
2026-03-11 13:40:16.770103 | controller | TASK [repo_setup : Use RDO proxy mirrors] **************************************
2026-03-11 13:40:16.770111 | controller | Wednesday 11 March 2026  13:40:16 +0000 (0:00:00.178)       0:00:15.257 *******
2026-03-11 13:40:16.770121 | controller | changed: [instance]
2026-03-11 13:40:16.989995 | controller |
2026-03-11 13:40:16.990027 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
2026-03-11 13:40:16.990036 | controller | Wednesday 11 March 2026  13:40:16 +0000 (0:00:00.204)       0:00:15.461 *******
2026-03-11 13:40:16.990046 | controller | changed: [instance]
2026-03-11 13:40:17.010388 | controller |
2026-03-11 13:40:17.010416 | controller | TASK [repo_setup : Check for gating.repo file on content provider] *************
2026-03-11 13:40:17.010433 | controller | Wednesday 11 March 2026  13:40:16 +0000 (0:00:00.219)       0:00:15.681 *******
2026-03-11 13:40:17.010445 | controller | skipping: [instance]
2026-03-11 13:40:17.031842 | controller |
2026-03-11 13:40:17.031864 | controller | TASK [repo_setup : Populate gating repo from content provider ip] **************
2026-03-11 13:40:17.031871 | controller | Wednesday 11 March 2026  13:40:17 +0000 (0:00:00.020)       0:00:15.702 *******
2026-03-11 13:40:17.031880 | controller | skipping: [instance]
2026-03-11 13:40:17.052695 | controller |
2026-03-11 13:40:17.052712 | controller | TASK [repo_setup : Check for DLRN repo at the destination] *********************
2026-03-11 13:40:17.052720 | controller | Wednesday 11 March 2026  13:40:17 +0000 (0:00:00.021)       0:00:15.724 *******
2026-03-11 13:40:17.052728 | controller | skipping: [instance]
2026-03-11 13:40:17.073806 | controller |
2026-03-11 13:40:17.073823 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
2026-03-11 13:40:17.073831 | controller | Wednesday 11 March 2026  13:40:17 +0000 (0:00:00.020)       0:00:15.744 *******
2026-03-11 13:40:17.073839 | controller | skipping: [instance]
2026-03-11 13:40:17.095968 | controller |
2026-03-11 13:40:17.095985 | controller | TASK [repo_setup : Check for DLRN component repo] ******************************
2026-03-11 13:40:17.095992 | controller | Wednesday 11 March 2026  13:40:17 +0000 (0:00:00.021)       0:00:15.766 *******
2026-03-11 13:40:17.096001 | controller | skipping: [instance]
2026-03-11 13:40:17.117379 | controller |
2026-03-11 13:40:17.117400 | controller | TASK [repo_setup : Lower the priority of component repos to allow installation from gating repo] ***
2026-03-11 13:40:17.117413 | controller | Wednesday 11 March 2026  13:40:17 +0000 (0:00:00.022)       0:00:15.788 *******
2026-03-11 13:40:17.117423 | controller | skipping: [instance]
2026-03-11 13:40:17.133834 | controller |
2026-03-11 13:40:17.133852 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
2026-03-11 13:40:17.133859 | controller | Wednesday 11 March 2026  13:40:17 +0000 (0:00:00.021)       0:00:15.809 *******
2026-03-11 13:40:17.133867 | controller | skipping: [instance]
2026-03-11 13:40:17.161827 | controller |
2026-03-11 13:40:17.161848 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
2026-03-11 13:40:17.161857 | controller | Wednesday 11 March 2026  13:40:17 +0000 (0:00:00.016)       0:00:15.826 *******
2026-03-11 13:40:17.161867 | controller | skipping: [instance]
2026-03-11 13:40:17.178030 | controller |
2026-03-11 13:40:17.178048 | controller | TASK [repo_setup : Cleanup existing metadata] **********************************
2026-03-11 13:40:17.178055 | controller | Wednesday 11 March 2026  13:40:17 +0000 (0:00:00.027)       0:00:15.853 *******
2026-03-11 13:40:17.178064 | controller | skipping: [instance]
2026-03-11 13:40:17.198317 | controller |
2026-03-11 13:40:17.198339 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
2026-03-11 13:40:17.198346 | controller | Wednesday 11 March 2026  13:40:17 +0000 (0:00:00.016)       0:00:15.870 *******
2026-03-11 13:40:17.198355 | controller | skipping: [instance]
2026-03-11 13:40:17.226859 | controller |
2026-03-11 13:40:17.226876 | controller | TASK [test_deps : Look for redhat-release rpm] *********************************
2026-03-11 13:40:17.226883 | controller | Wednesday 11 March 2026  13:40:17 +0000 (0:00:00.020)       0:00:15.890 *******
2026-03-11 13:40:17.226892 | controller | skipping: [instance]
2026-03-11 13:40:17.256386 | controller |
2026-03-11 13:40:17.256420 | controller | TASK [test_deps : Remove redhat-release] ***************************************
2026-03-11 13:40:17.256438 | controller | Wednesday 11 March 2026  13:40:17 +0000 (0:00:00.028)       0:00:15.919 *******
2026-03-11 13:40:17.256449 | controller | skipping: [instance]
2026-03-11 13:40:17.256475 | controller |
2026-03-11 13:40:17.256481 | controller | TASK [test_deps : Install centos-stream-release] *******************************
2026-03-11 13:40:17.256488 | controller | Wednesday 11 March 2026  13:40:17 +0000 (0:00:00.029)       0:00:15.948 *******
2026-03-11 13:40:17.284877 | controller | skipping: [instance]
2026-03-11 13:40:17.312801 | controller |
2026-03-11 13:40:17.312820 | controller | TASK [test_deps : Install additional packages] *********************************
2026-03-11 13:40:17.312827 | controller | Wednesday 11 March 2026  13:40:17 +0000 (0:00:00.028)       0:00:15.976 *******
2026-03-11 13:40:17.312836 | controller | skipping: [instance]
2026-03-11 13:41:11.003235 | controller |
2026-03-11 13:41:11.003269 | controller | TASK [test_deps : Install selinux python libs] *********************************
2026-03-11 13:41:11.003278 | controller | Wednesday 11 March 2026  13:40:17 +0000 (0:00:00.027)       0:00:16.004 *******
2026-03-11 13:41:11.003288 | controller | ok: [instance]
2026-03-11 13:41:12.196375 | controller |
2026-03-11 13:41:12.196413 | controller | TASK [test_deps : Install python yaml libs] ************************************
2026-03-11 13:41:12.196422 | controller | Wednesday 11 March 2026  13:41:11 +0000 (0:00:53.690)       0:01:09.695 *******
2026-03-11 13:41:12.196433 | controller | ok: [instance]
2026-03-11 13:41:12.196452 | controller |
2026-03-11 13:41:12.196684 | controller | TASK [test_deps : Install extra packages] **************************************
2026-03-11 13:41:12.228883 | controller | Wednesday 11 March 2026  13:41:12 +0000 (0:00:01.193)       0:01:10.888 *******
2026-03-11 13:41:12.228903 | controller | skipping: [instance]
2026-03-11 13:41:12.605326 | controller |
2026-03-11 13:41:12.605358 | controller | TASK [Create 1st dummy interface with needed IPs] ******************************
2026-03-11 13:41:12.605367 | controller | Wednesday 11 March 2026  13:41:12 +0000 (0:00:00.032)       0:01:10.920 *******
2026-03-11 13:41:12.605377 | controller | changed: [instance]
2026-03-11 13:41:12.864721 | controller |
2026-03-11 13:41:12.864751 | controller | TASK [Create 2nd dummy interface with needed IPs] ******************************
2026-03-11 13:41:12.864759 | controller | Wednesday 11 March 2026  13:41:12 +0000 (0:00:00.376)       0:01:11.297 *******
2026-03-11 13:41:12.864771 | controller | changed: [instance]
2026-03-11 13:41:13.149382 | controller |
2026-03-11 13:41:13.149423 | controller | TASK [Create 3nd dummy interface with needed IPs] ******************************
2026-03-11 13:41:13.149433 | controller | Wednesday 11 March 2026  13:41:12 +0000 (0:00:00.259)       0:01:11.556 *******
2026-03-11 13:41:13.149448 | controller | changed: [instance]
2026-03-11 13:41:13.149467 | controller |
2026-03-11 13:41:13.149475 | controller | PLAY RECAP *********************************************************************
2026-03-11 13:41:13.150382 | controller | instance                   : ok=22   changed=12   unreachable=0    failed=0    skipped=30   rescued=0    ignored=0
2026-03-11 13:41:13.150430 | controller |
2026-03-11 13:41:13.150439 | controller | Wednesday 11 March 2026  13:41:13 +0000 (0:00:00.284)       0:01:11.841 *******
2026-03-11 13:41:13.150446 | controller | ===============================================================================
2026-03-11 13:41:13.150451 | controller | test_deps : Install selinux python libs -------------------------------- 53.69s
2026-03-11 13:41:13.150457 | controller | repo_setup : Initialize python venv and install requirements ------------ 7.57s
2026-03-11 13:41:13.150462 | controller | test_deps : Install python yaml libs ------------------------------------ 1.19s
2026-03-11 13:41:13.150467 | controller | repo_setup : Get repo-setup repository ---------------------------------- 1.14s
2026-03-11 13:41:13.150473 | controller | Gathering Facts --------------------------------------------------------- 0.82s
2026-03-11 13:41:13.150480 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.80s
2026-03-11 13:41:13.150501 | controller | repo_setup : Make sure git-core package is installed -------------------- 0.78s
2026-03-11 13:41:13.151391 | controller | repo_setup : Install repo-setup package --------------------------------- 0.68s
2026-03-11 13:41:13.151437 | controller | test_deps : Disable ubi 9 repos ----------------------------------------- 0.68s
2026-03-11 13:41:13.151446 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.53s
2026-03-11 13:41:13.151452 | controller | repo_setup : Ensure directories are present ----------------------------- 0.52s
2026-03-11 13:41:13.151458 | controller | repo_setup : Dump full hash in delorean.repo.md5 file ------------------- 0.43s
2026-03-11 13:41:13.151463 | controller | Create 1st dummy interface with needed IPs ------------------------------ 0.38s
2026-03-11 13:41:13.151469 | controller | test_deps : Disable ubi host subscription-manager integration ----------- 0.30s
2026-03-11 13:41:13.151474 | controller | Create 3nd dummy interface with needed IPs ------------------------------ 0.28s
2026-03-11 13:41:13.151479 | controller | test_deps : Detect mirrors file ----------------------------------------- 0.27s
2026-03-11 13:41:13.151487 | controller | Create 2nd dummy interface with needed IPs ------------------------------ 0.26s
2026-03-11 13:41:13.151509 | controller | repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist) --- 0.22s
2026-03-11 13:41:13.151716 | controller | repo_setup : Use RDO proxy mirrors -------------------------------------- 0.20s
2026-03-11 13:41:13.216010 | controller | repo_setup : Check for /etc/ci/mirror_info.sh --------------------------- 0.18s
2026-03-11 13:41:13.216044 | controller | INFO     Running default > converge
2026-03-11 13:41:13.645256 | controller |
2026-03-11 13:41:14.466239 | controller | PLAY [Converge] ****************************************************************
2026-03-11 13:41:14.466268 | controller |
2026-03-11 13:41:14.466276 | controller | TASK [Gathering Facts] *********************************************************
2026-03-11 13:41:14.466282 | controller | Wednesday 11 March 2026  13:41:13 +0000 (0:00:00.017)       0:00:00.017 *******
2026-03-11 13:41:14.466292 | controller | ok: [instance]
2026-03-11 13:41:18.205292 | controller |
2026-03-11 13:41:18.205338 | controller | TASK [dnsmasq : Install needed packages] ***************************************
2026-03-11 13:41:18.205347 | controller | Wednesday 11 March 2026  13:41:14 +0000 (0:00:00.821)       0:00:00.839 *******
2026-03-11 13:41:18.205357 | controller | changed: [instance]
2026-03-11 13:41:21.563376 | controller |
2026-03-11 13:41:21.563407 | controller | TASK [dnsmasq : Ensure SELinux knows about the new files and directories] ******
2026-03-11 13:41:21.563415 | controller | Wednesday 11 March 2026  13:41:18 +0000 (0:00:03.738)       0:00:04.578 *******
2026-03-11 13:41:21.563425 | controller | changed: [instance] => (item={'target': '/etc/cifmw-dnsmasq.conf', 'setype': 'dnsmasq_etc_t'})
2026-03-11 13:41:21.563569 | controller | changed: [instance] => (item={'target': '/etc/cifmw-dnsmasq.d(/.*)?', 'setype': 'dnsmasq_etc_t'})
2026-03-11 13:41:21.563586 | controller | changed: [instance] => (item={'target': '/var/run/cifmw-dnsmasq.pid', 'setype': 'dnsmasq_var_run_t'})
2026-03-11 13:41:21.563594 | controller |
2026-03-11 13:41:21.563603 | controller | TASK [dnsmasq : Manage configuration directory] ********************************
2026-03-11 13:41:21.879594 | controller | Wednesday 11 March 2026  13:41:21 +0000 (0:00:03.358)       0:00:07.936 *******
2026-03-11 13:41:21.879634 | controller | changed: [instance]
2026-03-11 13:41:22.422923 | controller |
2026-03-11 13:41:22.422951 | controller | TASK [dnsmasq : Manage systemd unit file] **************************************
2026-03-11 13:41:22.422960 | controller | Wednesday 11 March 2026  13:41:21 +0000 (0:00:00.316)       0:00:08.252 *******
2026-03-11 13:41:22.422970 | controller | changed: [instance]
2026-03-11 13:41:22.815978 | controller |
2026-03-11 13:41:22.816008 | controller | TASK [dnsmasq : Manage base configuration file] ********************************
2026-03-11 13:41:22.816016 | controller | Wednesday 11 March 2026  13:41:22 +0000 (0:00:00.543)       0:00:08.795 *******
2026-03-11 13:41:22.816026 | controller | changed: [instance]
2026-03-11 13:41:22.837993 | controller |
2026-03-11 13:41:22.838014 | controller | TASK [dnsmasq : Render listener configuration] *********************************
2026-03-11 13:41:22.838021 | controller | Wednesday 11 March 2026  13:41:22 +0000 (0:00:00.393)       0:00:09.188 *******
2026-03-11 13:41:22.838031 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/dnsmasq/tasks/listener.yml for instance
2026-03-11 13:41:23.226276 | controller |
2026-03-11 13:41:23.226320 | controller | TASK [dnsmasq : Create listener configuration file] ****************************
2026-03-11 13:41:23.226331 | controller | Wednesday 11 March 2026  13:41:22 +0000 (0:00:00.022)       0:00:09.211 *******
2026-03-11 13:41:23.226342 | controller | changed: [instance]
2026-03-11 13:41:23.243849 | controller |
2026-03-11 13:41:23.243867 | controller | TASK [dnsmasq : Remove listener configuration file] ****************************
2026-03-11 13:41:23.243875 | controller | Wednesday 11 March 2026  13:41:23 +0000 (0:00:00.387)       0:00:09.599 *******
2026-03-11 13:41:23.243883 | controller | skipping: [instance]
2026-03-11 13:41:23.267111 | controller |
2026-03-11 13:41:23.267134 | controller | TASK [dnsmasq : Render dns configuration] **************************************
2026-03-11 13:41:23.267143 | controller | Wednesday 11 March 2026  13:41:23 +0000 (0:00:00.017)       0:00:09.617 *******
2026-03-11 13:41:23.267154 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/dnsmasq/tasks/dns.yml for instance
2026-03-11 13:41:23.626018 | controller |
2026-03-11 13:41:23.626048 | controller | TASK [dnsmasq : Create dns configuration file] *********************************
2026-03-11 13:41:23.626057 | controller | Wednesday 11 March 2026  13:41:23 +0000 (0:00:00.023)       0:00:09.640 *******
2026-03-11 13:41:23.626066 | controller | changed: [instance]
2026-03-11 13:41:23.643052 | controller |
2026-03-11 13:41:23.643074 | controller | TASK [dnsmasq : Remove dns configuration file] *********************************
2026-03-11 13:41:23.643082 | controller | Wednesday 11 March 2026  13:41:23 +0000 (0:00:00.358)       0:00:09.998 *******
2026-03-11 13:41:23.643091 | controller | skipping: [instance]
2026-03-11 13:41:23.669154 | controller |
2026-03-11 13:41:23.669172 | controller | TASK [dnsmasq : Add localhost addresses from defined dnsmasq listen addresses to loopback interface] ***
2026-03-11 13:41:23.669179 | controller | Wednesday 11 March 2026  13:41:23 +0000 (0:00:00.017)       0:00:10.016 *******
2026-03-11 13:41:23.669188 | controller | skipping: [instance] => (item=192.168.253.9)
2026-03-11 13:41:24.539824 | controller | skipping: [instance] => (item=192.168.254.9)
2026-03-11 13:41:24.539856 | controller | skipping: [instance]
2026-03-11 13:41:24.539864 | controller |
2026-03-11 13:41:24.539871 | controller | TASK [dnsmasq : Enable and start service] **************************************
2026-03-11 13:41:24.539877 | controller | Wednesday 11 March 2026  13:41:23 +0000 (0:00:00.025)       0:00:10.042 *******
2026-03-11 13:41:24.539887 | controller | changed: [instance]
2026-03-11 13:41:24.558403 | controller |
2026-03-11 13:41:24.558440 | controller | TASK [dnsmasq : Stop service] **************************************************
2026-03-11 13:41:24.558458 | controller | Wednesday 11 March 2026  13:41:24 +0000 (0:00:00.870)       0:00:10.912 *******
2026-03-11 13:41:24.558470 | controller | skipping: [instance]
2026-03-11 13:41:24.575359 | controller |
2026-03-11 13:41:24.575384 | controller | TASK [dnsmasq : Remove unit file] **********************************************
2026-03-11 13:41:24.575392 | controller | Wednesday 11 March 2026  13:41:24 +0000 (0:00:00.019)       0:00:10.931 *******
2026-03-11 13:41:24.575404 | controller | skipping: [instance]
2026-03-11 13:41:24.575586 | controller |
2026-03-11 13:41:24.575609 | controller | TASK [dnsmasq : Remove main configuration files] *******************************
2026-03-11 13:41:24.602862 | controller | Wednesday 11 March 2026  13:41:24 +0000 (0:00:00.017)       0:00:10.948 *******
2026-03-11 13:41:24.602884 | controller | skipping: [instance] => (item=/etc/cifmw-dnsmasq.conf)
2026-03-11 13:41:24.630235 | controller | skipping: [instance] => (item=/etc/cifmw-dnsmasq.d)
2026-03-11 13:41:24.630259 | controller | skipping: [instance]
2026-03-11 13:41:24.630269 | controller |
2026-03-11 13:41:24.630277 | controller | TASK [Create network] **********************************************************
2026-03-11 13:41:24.630285 | controller | Wednesday 11 March 2026  13:41:24 +0000 (0:00:00.026)       0:00:10.975 *******
2026-03-11 13:41:24.630317 | controller |
2026-03-11 13:41:24.656174 | controller | TASK [dnsmasq : Assert mandatory parameter] ************************************
2026-03-11 13:41:24.656203 | controller | Wednesday 11 March 2026  13:41:24 +0000 (0:00:00.027)       0:00:11.003 *******
2026-03-11 13:41:24.656215 | controller | ok: [instance] => changed=false
2026-03-11 13:41:24.687394 | controller |   msg: All assertions passed
2026-03-11 13:41:24.687417 | controller |
2026-03-11 13:41:24.687426 | controller | TASK [dnsmasq : Assert mandatory parameters for new network] *******************
2026-03-11 13:41:24.687438 | controller | Wednesday 11 March 2026  13:41:24 +0000 (0:00:00.025)       0:00:11.028 *******
2026-03-11 13:41:24.687449 | controller | ok: [instance] => changed=false
2026-03-11 13:41:24.687478 | controller |   msg: All assertions passed
2026-03-11 13:41:24.687503 | controller |
2026-03-11 13:41:24.687738 | controller | TASK [dnsmasq : Create network] ************************************************
2026-03-11 13:41:25.125166 | controller | Wednesday 11 March 2026  13:41:24 +0000 (0:00:00.031)       0:00:11.060 *******
2026-03-11 13:41:25.125206 | controller | changed: [instance]
2026-03-11 13:41:25.142112 | controller |
2026-03-11 13:41:25.142135 | controller | TASK [dnsmasq : Remove network] ************************************************
2026-03-11 13:41:25.142143 | controller | Wednesday 11 March 2026  13:41:25 +0000 (0:00:00.437)       0:00:11.498 *******
2026-03-11 13:41:25.142152 | controller | skipping: [instance]
2026-03-11 13:41:25.163943 | controller |
2026-03-11 13:41:25.163961 | controller | TASK [dnsmasq : Remove dhcp host files] ****************************************
2026-03-11 13:41:25.163969 | controller | Wednesday 11 March 2026  13:41:25 +0000 (0:00:00.017)       0:00:11.515 *******
2026-03-11 13:41:25.163978 | controller | skipping: [instance]
2026-03-11 13:41:25.192385 | controller |
2026-03-11 13:41:25.192407 | controller | TASK [Create second network] ***************************************************
2026-03-11 13:41:25.192417 | controller | Wednesday 11 March 2026  13:41:25 +0000 (0:00:00.021)       0:00:11.537 *******
2026-03-11 13:41:25.192428 | controller |
2026-03-11 13:41:25.192460 | controller | TASK [dnsmasq : Assert mandatory parameter] ************************************
2026-03-11 13:41:25.192588 | controller | Wednesday 11 March 2026  13:41:25 +0000 (0:00:00.028)       0:00:11.565 *******
2026-03-11 13:41:25.218810 | controller | ok: [instance] => changed=false
2026-03-11 13:41:25.250755 | controller |   msg: All assertions passed
2026-03-11 13:41:25.250772 | controller |
2026-03-11 13:41:25.250780 | controller | TASK [dnsmasq : Assert mandatory parameters for new network] *******************
2026-03-11 13:41:25.250786 | controller | Wednesday 11 March 2026  13:41:25 +0000 (0:00:00.026)       0:00:11.591 *******
2026-03-11 13:41:25.250795 | controller | ok: [instance] => changed=false
2026-03-11 13:41:25.678333 | controller |   msg: All assertions passed
2026-03-11 13:41:25.678367 | controller |
2026-03-11 13:41:25.678376 | controller | TASK [dnsmasq : Create network] ************************************************
2026-03-11 13:41:25.678383 | controller | Wednesday 11 March 2026  13:41:25 +0000 (0:00:00.031)       0:00:11.623 *******
2026-03-11 13:41:25.678399 | controller | changed: [instance]
2026-03-11 13:41:25.695344 | controller |
2026-03-11 13:41:25.695365 | controller | TASK [dnsmasq : Remove network] ************************************************
2026-03-11 13:41:25.695373 | controller | Wednesday 11 March 2026  13:41:25 +0000 (0:00:00.427)       0:00:12.051 *******
2026-03-11 13:41:25.695383 | controller | skipping: [instance]
2026-03-11 13:41:25.695456 | controller |
2026-03-11 13:41:25.695471 | controller | TASK [dnsmasq : Remove dhcp host files] ****************************************
2026-03-11 13:41:25.695481 | controller | Wednesday 11 March 2026  13:41:25 +0000 (0:00:00.017)       0:00:12.068 *******
2026-03-11 13:41:25.716389 | controller | skipping: [instance]
2026-03-11 13:41:25.716553 | controller |
2026-03-11 13:41:25.716571 | controller | TASK [Inject enable-ra in startrek] ********************************************
2026-03-11 13:41:25.989280 | controller | Wednesday 11 March 2026  13:41:25 +0000 (0:00:00.021)       0:00:12.089 *******
2026-03-11 13:41:25.989334 | controller | ok: [instance]
2026-03-11 13:41:26.011371 | controller |
2026-03-11 13:41:26.011404 | controller | TASK [Assert no change was done in startrek] ***********************************
2026-03-11 13:41:26.011413 | controller | Wednesday 11 March 2026  13:41:25 +0000 (0:00:00.272)       0:00:12.362 *******
2026-03-11 13:41:26.011423 | controller | ok: [instance] => changed=false
2026-03-11 13:41:26.011450 | controller |   msg: All assertions passed
2026-03-11 13:41:26.011821 | controller |
2026-03-11 13:41:26.036575 | controller | TASK [Create IPv4-only network] ************************************************
2026-03-11 13:41:26.036593 | controller | Wednesday 11 March 2026  13:41:26 +0000 (0:00:00.022)       0:00:12.384 *******
2026-03-11 13:41:26.036604 | controller |
2026-03-11 13:41:26.062355 | controller | TASK [dnsmasq : Assert mandatory parameter] ************************************
2026-03-11 13:41:26.062376 | controller | Wednesday 11 March 2026  13:41:26 +0000 (0:00:00.025)       0:00:12.409 *******
2026-03-11 13:41:26.062387 | controller | ok: [instance] => changed=false
2026-03-11 13:41:26.062822 | controller |   msg: All assertions passed
2026-03-11 13:41:26.093955 | controller |
2026-03-11 13:41:26.093979 | controller | TASK [dnsmasq : Assert mandatory parameters for new network] *******************
2026-03-11 13:41:26.093989 | controller | Wednesday 11 March 2026  13:41:26 +0000 (0:00:00.025)       0:00:12.435 *******
2026-03-11 13:41:26.094000 | controller | ok: [instance] => changed=false
2026-03-11 13:41:26.510998 | controller |   msg: All assertions passed
2026-03-11 13:41:26.511030 | controller |
2026-03-11 13:41:26.511037 | controller | TASK [dnsmasq : Create network] ************************************************
2026-03-11 13:41:26.511044 | controller | Wednesday 11 March 2026  13:41:26 +0000 (0:00:00.031)       0:00:12.466 *******
2026-03-11 13:41:26.511060 | controller | changed: [instance]
2026-03-11 13:41:26.528135 | controller |
2026-03-11 13:41:26.528163 | controller | TASK [dnsmasq : Remove network] ************************************************
2026-03-11 13:41:26.528171 | controller | Wednesday 11 March 2026  13:41:26 +0000 (0:00:00.416)       0:00:12.883 *******
2026-03-11 13:41:26.528180 | controller | skipping: [instance]
2026-03-11 13:41:26.548894 | controller |
2026-03-11 13:41:26.548921 | controller | TASK [dnsmasq : Remove dhcp host files] ****************************************
2026-03-11 13:41:26.548929 | controller | Wednesday 11 March 2026  13:41:26 +0000 (0:00:00.017)       0:00:12.901 *******
2026-03-11 13:41:26.548939 | controller | skipping: [instance]
2026-03-11 13:41:26.737173 | controller |
2026-03-11 13:41:26.737204 | controller | TASK [Inject enable-ra in oldies] **********************************************
2026-03-11 13:41:26.737212 | controller | Wednesday 11 March 2026  13:41:26 +0000 (0:00:00.020)       0:00:12.922 *******
2026-03-11 13:41:26.737223 | controller | changed: [instance]
2026-03-11 13:41:26.770368 | controller |
2026-03-11 13:41:26.770400 | controller | TASK [Debug] *******************************************************************
2026-03-11 13:41:26.770408 | controller | Wednesday 11 March 2026  13:41:26 +0000 (0:00:00.188)       0:00:13.110 *******
2026-03-11 13:41:26.770419 | controller | ok: [instance] =>
2026-03-11 13:41:26.770942 | controller |   _set_ra:
2026-03-11 13:41:26.770955 | controller |     backup: ''
2026-03-11 13:41:26.770962 | controller |     changed: true
2026-03-11 13:41:26.770968 | controller |     diff:
2026-03-11 13:41:26.770974 | controller |     - after: ''
2026-03-11 13:41:26.770980 | controller |       after_header: /etc/cifmw-dnsmasq.d/oldies.conf (content)
2026-03-11 13:41:26.770985 | controller |       before: ''
2026-03-11 13:41:26.770996 | controller |       before_header: /etc/cifmw-dnsmasq.d/oldies.conf (content)
2026-03-11 13:41:26.771002 | controller |     - after_header: /etc/cifmw-dnsmasq.d/oldies.conf (file attributes)
2026-03-11 13:41:26.771007 | controller |       before_header: /etc/cifmw-dnsmasq.d/oldies.conf (file attributes)
2026-03-11 13:41:26.771015 | controller |     failed: false
2026-03-11 13:41:26.793322 | controller |     msg: line added
2026-03-11 13:41:26.793350 | controller |
2026-03-11 13:41:26.793358 | controller | TASK [Assert oldies was changed] ***********************************************
2026-03-11 13:41:26.793365 | controller | Wednesday 11 March 2026  13:41:26 +0000 (0:00:00.032)       0:00:13.142 *******
2026-03-11 13:41:26.793374 | controller | ok: [instance] => changed=false
2026-03-11 13:41:26.821359 | controller |   msg: All assertions passed
2026-03-11 13:41:26.821385 | controller |
2026-03-11 13:41:26.821394 | controller | TASK [Inject some node in starwars network] ************************************
2026-03-11 13:41:26.821402 | controller | Wednesday 11 March 2026  13:41:26 +0000 (0:00:00.023)       0:00:13.166 *******
2026-03-11 13:41:26.821412 | controller |
2026-03-11 13:41:26.821539 | controller | TASK [dnsmasq : Ensure we have the right data and type] ************************
2026-03-11 13:41:26.821552 | controller | Wednesday 11 March 2026  13:41:26 +0000 (0:00:00.028)       0:00:13.194 *******
2026-03-11 13:41:26.854244 | controller | ok: [instance]
2026-03-11 13:41:26.916991 | controller |
2026-03-11 13:41:26.917020 | controller | TASK [dnsmasq : Assert we have needed host data] *******************************
2026-03-11 13:41:26.917028 | controller | Wednesday 11 March 2026  13:41:26 +0000 (0:00:00.032)       0:00:13.227 *******
2026-03-11 13:41:26.917039 | 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']})
2026-03-11 13:41:26.961413 | 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'})
2026-03-11 13:41:26.961442 | 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'})
2026-03-11 13:41:26.961452 | controller |
2026-03-11 13:41:26.961461 | controller | TASK [dnsmasq : Ensure networks exists] ****************************************
2026-03-11 13:41:26.961469 | controller | Wednesday 11 March 2026  13:41:26 +0000 (0:00:00.062)       0:00:13.289 *******
2026-03-11 13:41:26.961481 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/dnsmasq/tasks/_check_net_status.yml for instance => (item=starwars)
2026-03-11 13:41:26.961682 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/dnsmasq/tasks/_check_net_status.yml for instance => (item=startrek)
2026-03-11 13:41:26.961715 | controller |
2026-03-11 13:41:26.961729 | controller | TASK [dnsmasq : Check network file status] *************************************
2026-03-11 13:41:27.132330 | controller | Wednesday 11 March 2026  13:41:26 +0000 (0:00:00.044)       0:00:13.334 *******
2026-03-11 13:41:27.132367 | controller | ok: [instance]
2026-03-11 13:41:27.154338 | controller |
2026-03-11 13:41:27.154356 | controller | TASK [dnsmasq : Assert network exists] *****************************************
2026-03-11 13:41:27.154363 | controller | Wednesday 11 March 2026  13:41:27 +0000 (0:00:00.170)       0:00:13.504 *******
2026-03-11 13:41:27.154372 | controller | ok: [instance]
2026-03-11 13:41:27.154828 | controller |
2026-03-11 13:41:27.154843 | controller | TASK [dnsmasq : Check network file status] *************************************
2026-03-11 13:41:27.324407 | controller | Wednesday 11 March 2026  13:41:27 +0000 (0:00:00.022)       0:00:13.527 *******
2026-03-11 13:41:27.324439 | controller | ok: [instance]
2026-03-11 13:41:27.324465 | controller |
2026-03-11 13:41:27.324653 | controller | TASK [dnsmasq : Assert network exists] *****************************************
2026-03-11 13:41:27.345570 | controller | Wednesday 11 March 2026  13:41:27 +0000 (0:00:00.170)       0:00:13.697 *******
2026-03-11 13:41:27.345592 | controller | ok: [instance]
2026-03-11 13:41:27.361989 | controller |
2026-03-11 13:41:27.362008 | controller | TASK [dnsmasq : Initialize empty dhcp_host_entries] ****************************
2026-03-11 13:41:27.362025 | controller | Wednesday 11 March 2026  13:41:27 +0000 (0:00:00.020)       0:00:13.718 *******
2026-03-11 13:41:27.362036 | controller | ok: [instance]
2026-03-11 13:41:27.476958 | controller |
2026-03-11 13:41:27.476985 | controller | TASK [dnsmasq : Compute entry] *************************************************
2026-03-11 13:41:27.476994 | controller | Wednesday 11 March 2026  13:41:27 +0000 (0:00:00.016)       0:00:13.734 *******
2026-03-11 13:41:27.477004 | 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']})
2026-03-11 13:41:27.512431 | 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'})
2026-03-11 13:41:27.512461 | 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'})
2026-03-11 13:41:27.512470 | controller |
2026-03-11 13:41:27.512478 | controller | TASK [dnsmasq : Create add/remove sets] ****************************************
2026-03-11 13:41:27.512492 | controller | Wednesday 11 March 2026  13:41:27 +0000 (0:00:00.114)       0:00:13.849 *******
2026-03-11 13:41:27.512505 | controller | ok: [instance]
2026-03-11 13:41:27.512525 | controller |
2026-03-11 13:41:27.512533 | controller | TASK [dnsmasq : Add DHCP entries] **********************************************
2026-03-11 13:41:27.512542 | controller | Wednesday 11 March 2026  13:41:27 +0000 (0:00:00.035)       0:00:13.885 *******
2026-03-11 13:41:28.550232 | 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'})
2026-03-11 13:41:28.550329 | 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'})
2026-03-11 13:41:28.550786 | 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'})
2026-03-11 13:41:28.571223 | controller |
2026-03-11 13:41:28.571246 | controller | TASK [dnsmasq : Remove DHCP entries] *******************************************
2026-03-11 13:41:28.571255 | controller | Wednesday 11 March 2026  13:41:28 +0000 (0:00:01.037)       0:00:14.923 *******
2026-03-11 13:41:28.571265 | controller | skipping: [instance]
2026-03-11 13:41:28.601845 | controller |
2026-03-11 13:41:28.601870 | controller | TASK [Inject nodes with tags for DHCP options] *********************************
2026-03-11 13:41:28.601879 | controller | Wednesday 11 March 2026  13:41:28 +0000 (0:00:00.020)       0:00:14.944 *******
2026-03-11 13:41:28.601888 | controller |
2026-03-11 13:41:28.635044 | controller | TASK [dnsmasq : Ensure we have the right data and type] ************************
2026-03-11 13:41:28.635063 | controller | Wednesday 11 March 2026  13:41:28 +0000 (0:00:00.030)       0:00:14.975 *******
2026-03-11 13:41:28.635073 | controller | ok: [instance]
2026-03-11 13:41:28.697747 | controller |
2026-03-11 13:41:28.697765 | controller | TASK [dnsmasq : Assert we have needed host data] *******************************
2026-03-11 13:41:28.697773 | controller | Wednesday 11 March 2026  13:41:28 +0000 (0:00:00.033)       0:00:15.008 *******
2026-03-11 13:41:28.697782 | controller | ok: [instance] => (item={'network': 'starwars', 'state': 'present', 'mac': '0a:19:02:f8:4c:b1', 'ips': ['192.168.254.21', '2345:0425:2CA1::0567:5673:0021'], 'name': 'r2d2', 'tag': 'droid'})
2026-03-11 13:41:28.739410 | controller | ok: [instance] => (item={'network': 'starwars', 'state': 'present', 'mac': '0a:19:02:f8:4c:b2', 'ips': ['192.168.254.22'], 'name': 'c3po', 'tag': 'droid'})
2026-03-11 13:41:28.739445 | controller | ok: [instance] => (item={'network': 'startrek', 'state': 'present', 'mac': '0a:19:02:f8:4c:b3', 'ips': ['192.168.253.31'], 'name': 'data', 'tag': 'android'})
2026-03-11 13:41:28.739455 | controller |
2026-03-11 13:41:28.739463 | controller | TASK [dnsmasq : Ensure networks exists] ****************************************
2026-03-11 13:41:28.739477 | controller | Wednesday 11 March 2026  13:41:28 +0000 (0:00:00.062)       0:00:15.070 *******
2026-03-11 13:41:28.739490 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/dnsmasq/tasks/_check_net_status.yml for instance => (item=starwars)
2026-03-11 13:41:28.739525 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/dnsmasq/tasks/_check_net_status.yml for instance => (item=startrek)
2026-03-11 13:41:28.739539 | controller |
2026-03-11 13:41:28.739779 | controller | TASK [dnsmasq : Check network file status] *************************************
2026-03-11 13:41:28.913777 | controller | Wednesday 11 March 2026  13:41:28 +0000 (0:00:00.042)       0:00:15.112 *******
2026-03-11 13:41:28.913802 | controller | ok: [instance]
2026-03-11 13:41:28.934378 | controller |
2026-03-11 13:41:28.934411 | controller | TASK [dnsmasq : Assert network exists] *****************************************
2026-03-11 13:41:28.934419 | controller | Wednesday 11 March 2026  13:41:28 +0000 (0:00:00.174)       0:00:15.286 *******
2026-03-11 13:41:28.934430 | controller | ok: [instance]
2026-03-11 13:41:28.934453 | controller |
2026-03-11 13:41:28.934462 | controller | TASK [dnsmasq : Check network file status] *************************************
2026-03-11 13:41:28.934484 | controller | Wednesday 11 March 2026  13:41:28 +0000 (0:00:00.020)       0:00:15.307 *******
2026-03-11 13:41:29.105668 | controller | ok: [instance]
2026-03-11 13:41:29.126790 | controller |
2026-03-11 13:41:29.126809 | controller | TASK [dnsmasq : Assert network exists] *****************************************
2026-03-11 13:41:29.126817 | controller | Wednesday 11 March 2026  13:41:29 +0000 (0:00:00.171)       0:00:15.478 *******
2026-03-11 13:41:29.126826 | controller | ok: [instance]
2026-03-11 13:41:29.143866 | controller |
2026-03-11 13:41:29.143883 | controller | TASK [dnsmasq : Initialize empty dhcp_host_entries] ****************************
2026-03-11 13:41:29.143890 | controller | Wednesday 11 March 2026  13:41:29 +0000 (0:00:00.021)       0:00:15.500 *******
2026-03-11 13:41:29.143899 | controller | ok: [instance]
2026-03-11 13:41:29.257966 | controller |
2026-03-11 13:41:29.257985 | controller | TASK [dnsmasq : Compute entry] *************************************************
2026-03-11 13:41:29.257993 | controller | Wednesday 11 March 2026  13:41:29 +0000 (0:00:00.017)       0:00:15.517 *******
2026-03-11 13:41:29.258001 | controller | ok: [instance] => (item={'network': 'starwars', 'state': 'present', 'mac': '0a:19:02:f8:4c:b1', 'ips': ['192.168.254.21', '2345:0425:2CA1::0567:5673:0021'], 'name': 'r2d2', 'tag': 'droid'})
2026-03-11 13:41:29.292355 | controller | ok: [instance] => (item={'network': 'starwars', 'state': 'present', 'mac': '0a:19:02:f8:4c:b2', 'ips': ['192.168.254.22'], 'name': 'c3po', 'tag': 'droid'})
2026-03-11 13:41:29.292377 | controller | ok: [instance] => (item={'network': 'startrek', 'state': 'present', 'mac': '0a:19:02:f8:4c:b3', 'ips': ['192.168.253.31'], 'name': 'data', 'tag': 'android'})
2026-03-11 13:41:29.292384 | controller |
2026-03-11 13:41:29.292391 | controller | TASK [dnsmasq : Create add/remove sets] ****************************************
2026-03-11 13:41:29.292396 | controller | Wednesday 11 March 2026  13:41:29 +0000 (0:00:00.113)       0:00:15.630 *******
2026-03-11 13:41:29.292405 | controller | ok: [instance]
2026-03-11 13:41:30.316253 | controller |
2026-03-11 13:41:30.316283 | controller | TASK [dnsmasq : Add DHCP entries] **********************************************
2026-03-11 13:41:30.316292 | controller | Wednesday 11 March 2026  13:41:29 +0000 (0:00:00.034)       0:00:15.665 *******
2026-03-11 13:41:30.316318 | controller | changed: [instance] => (item={'file': 'starwars_r2d2_0a:19:02:f8:4c:b1', 'entry': 'set:droid,0a:19:02:f8:4c:b1,192.168.254.21,[2345:425:2ca1::567:5673:21],r2d2', 'state': 'present'})
2026-03-11 13:41:30.335421 | controller | changed: [instance] => (item={'file': 'starwars_c3po_0a:19:02:f8:4c:b2', 'entry': 'set:droid,0a:19:02:f8:4c:b2,192.168.254.22,c3po', 'state': 'present'})
2026-03-11 13:41:30.335459 | controller | changed: [instance] => (item={'file': 'startrek_data_0a:19:02:f8:4c:b3', 'entry': 'set:android,0a:19:02:f8:4c:b3,192.168.253.31,data', 'state': 'present'})
2026-03-11 13:41:30.335469 | controller |
2026-03-11 13:41:30.335478 | controller | TASK [dnsmasq : Remove DHCP entries] *******************************************
2026-03-11 13:41:30.335486 | controller | Wednesday 11 March 2026  13:41:30 +0000 (0:00:01.023)       0:00:16.688 *******
2026-03-11 13:41:30.335498 | controller | skipping: [instance]
2026-03-11 13:41:30.610441 | controller |
2026-03-11 13:41:30.610469 | controller | TASK [Read r2d2 DHCP host entry] ***********************************************
2026-03-11 13:41:30.610487 | controller | Wednesday 11 March 2026  13:41:30 +0000 (0:00:00.019)       0:00:16.708 *******
2026-03-11 13:41:30.610497 | controller | ok: [instance]
2026-03-11 13:41:30.801112 | controller |
2026-03-11 13:41:30.801137 | controller | TASK [Read c3po DHCP host entry] ***********************************************
2026-03-11 13:41:30.801145 | controller | Wednesday 11 March 2026  13:41:30 +0000 (0:00:00.274)       0:00:16.983 *******
2026-03-11 13:41:30.801154 | controller | ok: [instance]
2026-03-11 13:41:30.980414 | controller |
2026-03-11 13:41:30.980446 | controller | TASK [Read data DHCP host entry] ***********************************************
2026-03-11 13:41:30.980455 | controller | Wednesday 11 March 2026  13:41:30 +0000 (0:00:00.190)       0:00:17.174 *******
2026-03-11 13:41:30.980465 | controller | ok: [instance]
2026-03-11 13:41:30.980488 | controller |
2026-03-11 13:41:30.980496 | controller | TASK [Decode entries] **********************************************************
2026-03-11 13:41:30.980608 | controller | Wednesday 11 March 2026  13:41:30 +0000 (0:00:00.179)       0:00:17.353 *******
2026-03-11 13:41:31.010823 | controller | ok: [instance]
2026-03-11 13:41:31.032387 | controller |
2026-03-11 13:41:31.032419 | controller | TASK [Assert r2d2 entry has droid tag] *****************************************
2026-03-11 13:41:31.032427 | controller | Wednesday 11 March 2026  13:41:31 +0000 (0:00:00.030)       0:00:17.383 *******
2026-03-11 13:41:31.032438 | controller | ok: [instance] => changed=false
2026-03-11 13:41:31.032462 | controller |   msg: All assertions passed
2026-03-11 13:41:31.032470 | controller |
2026-03-11 13:41:31.032663 | controller | TASK [Assert c3po entry has droid tag] *****************************************
2026-03-11 13:41:31.054567 | controller | Wednesday 11 March 2026  13:41:31 +0000 (0:00:00.021)       0:00:17.405 *******
2026-03-11 13:41:31.054590 | controller | ok: [instance] => changed=false
2026-03-11 13:41:31.076182 | controller |   msg: All assertions passed
2026-03-11 13:41:31.076202 | controller |
2026-03-11 13:41:31.076212 | controller | TASK [Assert data entry has android tag] ***************************************
2026-03-11 13:41:31.076219 | controller | Wednesday 11 March 2026  13:41:31 +0000 (0:00:00.021)       0:00:17.427 *******
2026-03-11 13:41:31.076230 | controller | ok: [instance] => changed=false
2026-03-11 13:41:31.260825 | controller |   msg: All assertions passed
2026-03-11 13:41:31.260846 | controller |
2026-03-11 13:41:31.260854 | controller | TASK [Verify entry without tag has no set: prefix] *****************************
2026-03-11 13:41:31.260860 | controller | Wednesday 11 March 2026  13:41:31 +0000 (0:00:00.021)       0:00:17.449 *******
2026-03-11 13:41:31.260869 | controller | ok: [instance]
2026-03-11 13:41:31.297392 | controller |
2026-03-11 13:41:31.297434 | controller | TASK [Assert solo entry does not have a tag] ***********************************
2026-03-11 13:41:31.297445 | controller | Wednesday 11 March 2026  13:41:31 +0000 (0:00:00.184)       0:00:17.633 *******
2026-03-11 13:41:31.297457 | controller | ok: [instance] => changed=false
2026-03-11 13:41:31.297480 | controller |   msg: All assertions passed
2026-03-11 13:41:31.297750 | controller |
2026-03-11 13:41:31.655531 | controller | TASK [Create DHCP options configuration for tagged hosts] **********************
2026-03-11 13:41:31.655563 | controller | Wednesday 11 March 2026  13:41:31 +0000 (0:00:00.036)       0:00:17.670 *******
2026-03-11 13:41:31.655575 | controller | changed: [instance]
2026-03-11 13:41:31.938371 | controller |
2026-03-11 13:41:31.938402 | controller | TASK [Verify dnsmasq configuration is valid] ***********************************
2026-03-11 13:41:31.938411 | controller | Wednesday 11 March 2026  13:41:31 +0000 (0:00:00.357)       0:00:18.028 *******
2026-03-11 13:41:31.938421 | controller | ok: [instance]
2026-03-11 13:41:31.938728 | controller |
2026-03-11 13:41:31.965697 | controller | TASK [Add a domain specific forwarder] *****************************************
2026-03-11 13:41:31.965730 | controller | Wednesday 11 March 2026  13:41:31 +0000 (0:00:00.283)       0:00:18.311 *******
2026-03-11 13:41:31.965742 | controller |
2026-03-11 13:41:32.000347 | controller | TASK [dnsmasq : Assert we have needed forwarder data] **************************
2026-03-11 13:41:32.000366 | controller | Wednesday 11 March 2026  13:41:31 +0000 (0:00:00.027)       0:00:18.338 *******
2026-03-11 13:41:32.000377 | controller | ok: [instance]
2026-03-11 13:41:32.000429 | controller |
2026-03-11 13:41:32.000438 | controller | TASK [dnsmasq : Assert each forwarder element have needed data] ****************
2026-03-11 13:41:32.000452 | controller | Wednesday 11 March 2026  13:41:31 +0000 (0:00:00.034)       0:00:18.373 *******
2026-03-11 13:41:32.040373 | controller | ok: [instance] => (item={'state': 'present', 'server': '192.168.254.100', 'domains': ['battlestar.galactica.lan']})
2026-03-11 13:41:32.040456 | controller |
2026-03-11 13:41:32.040471 | controller | TASK [dnsmasq : Add/Remove forwarder] ******************************************
2026-03-11 13:41:32.040549 | controller | Wednesday 11 March 2026  13:41:32 +0000 (0:00:00.040)       0:00:18.413 *******
2026-03-11 13:41:32.276664 | controller | changed: [instance] => (item={'state': 'present', 'server': '192.168.254.100', 'domains': ['battlestar.galactica.lan']})
2026-03-11 13:41:32.276738 | controller |
2026-03-11 13:41:32.276953 | controller | TASK [Add some host records] ***************************************************
2026-03-11 13:41:32.308435 | controller | Wednesday 11 March 2026  13:41:32 +0000 (0:00:00.236)       0:00:18.649 *******
2026-03-11 13:41:32.308480 | controller |
2026-03-11 13:41:32.342534 | controller | TASK [dnsmasq : Assert we have needed host record data] ************************
2026-03-11 13:41:32.342560 | controller | Wednesday 11 March 2026  13:41:32 +0000 (0:00:00.031)       0:00:18.681 *******
2026-03-11 13:41:32.342572 | controller | ok: [instance]
2026-03-11 13:41:32.393344 | controller |
2026-03-11 13:41:32.393363 | controller | TASK [dnsmasq : Assert each address element have needed data] ******************
2026-03-11 13:41:32.393371 | controller | Wednesday 11 March 2026  13:41:32 +0000 (0:00:00.034)       0:00:18.715 *******
2026-03-11 13:41:32.393380 | controller | ok: [instance] => (item={'state': 'present', 'ips': ['192.168.253.50', '2345:0425:2CA1::dead'], 'names': ['dead.battlestar.galactica.lan']})
2026-03-11 13:41:32.799768 | controller | ok: [instance] => (item={'state': 'present', 'ips': ['192.168.253.51', '2345:0425:2CA1::beef'], 'names': ['beef.battlestar.galactica.lan']})
2026-03-11 13:41:32.799795 | controller |
2026-03-11 13:41:32.799803 | controller | TASK [dnsmasq : Add/Remove address] ********************************************
2026-03-11 13:41:32.799809 | controller | Wednesday 11 March 2026  13:41:32 +0000 (0:00:00.050)       0:00:18.766 *******
2026-03-11 13:41:32.799819 | controller | changed: [instance] => (item={'state': 'present', 'ips': ['192.168.253.50', '2345:0425:2CA1::dead'], 'names': ['dead.battlestar.galactica.lan']})
2026-03-11 13:41:32.799967 | controller | changed: [instance] => (item={'state': 'present', 'ips': ['192.168.253.51', '2345:0425:2CA1::beef'], 'names': ['beef.battlestar.galactica.lan']})
2026-03-11 13:41:32.799983 | controller |
2026-03-11 13:41:32.799994 | controller | TASK [dnsmasq : Remove crc.testing from host_records] **************************
2026-03-11 13:41:32.828809 | controller | Wednesday 11 March 2026  13:41:32 +0000 (0:00:00.406)       0:00:19.172 *******
2026-03-11 13:41:32.828831 | controller | skipping: [instance]
2026-03-11 13:41:32.877013 | controller |
2026-03-11 13:41:32.877031 | controller | TASK [dnsmasq : Add controller-0 short hostname record when 'utility' in the name into /etc/hosts] ***
2026-03-11 13:41:32.877040 | controller | Wednesday 11 March 2026  13:41:32 +0000 (0:00:00.029)       0:00:19.201 *******
2026-03-11 13:41:32.877048 | controller | skipping: [instance] => (item={'state': 'present', 'ips': ['192.168.253.50', '2345:0425:2CA1::dead'], 'names': ['dead.battlestar.galactica.lan']})
2026-03-11 13:41:32.907952 | controller | skipping: [instance] => (item={'state': 'present', 'ips': ['192.168.253.51', '2345:0425:2CA1::beef'], 'names': ['beef.battlestar.galactica.lan']})
2026-03-11 13:41:32.907973 | controller | skipping: [instance]
2026-03-11 13:41:32.907983 | controller |
2026-03-11 13:41:32.907990 | controller | TASK [Add some wildcard addresses] *********************************************
2026-03-11 13:41:32.907997 | controller | Wednesday 11 March 2026  13:41:32 +0000 (0:00:00.048)       0:00:19.249 *******
2026-03-11 13:41:32.908007 | controller |
2026-03-11 13:41:32.941169 | controller | TASK [dnsmasq : Assert we have needed address data] ****************************
2026-03-11 13:41:32.941187 | controller | Wednesday 11 March 2026  13:41:32 +0000 (0:00:00.031)       0:00:19.281 *******
2026-03-11 13:41:32.941197 | controller | ok: [instance]
2026-03-11 13:41:33.023918 | controller |
2026-03-11 13:41:33.023936 | controller | TASK [dnsmasq : Assert each address element have needed data] ******************
2026-03-11 13:41:33.023951 | controller | Wednesday 11 March 2026  13:41:32 +0000 (0:00:00.033)       0:00:19.314 *******
2026-03-11 13:41:33.023960 | controller | ok: [instance] => (item={'state': 'present', 'ipaddr': '1.2.3.4', 'domains': ['apps.ocp.starwars.lan']})
2026-03-11 13:41:33.438375 | controller | ok: [instance] => (item={'state': 'present', 'ipaddr': '4.3.2.1', 'domains': ['apps.ocp.startrek.lan']})
2026-03-11 13:41:33.438408 | controller |
2026-03-11 13:41:33.438417 | controller | TASK [dnsmasq : Add/Remove address] ********************************************
2026-03-11 13:41:33.438423 | controller | Wednesday 11 March 2026  13:41:33 +0000 (0:00:00.082)       0:00:19.396 *******
2026-03-11 13:41:33.438433 | controller | changed: [instance] => (item={'state': 'present', 'ipaddr': '1.2.3.4', 'domains': ['apps.ocp.starwars.lan']})
2026-03-11 13:41:33.438456 | controller | changed: [instance] => (item={'state': 'present', 'ipaddr': '4.3.2.1', 'domains': ['apps.ocp.startrek.lan']})
2026-03-11 13:41:33.438462 | controller |
2026-03-11 13:41:33.438470 | controller | TASK [Force reload dnsmasq] ****************************************************
2026-03-11 13:41:33.438854 | controller | Wednesday 11 March 2026  13:41:33 +0000 (0:00:00.405)       0:00:19.802 *******
2026-03-11 13:41:33.774941 | controller |
2026-03-11 13:41:33.774970 | controller | RUNNING HANDLER [dnsmasq : Restart dnsmasq] ************************************
2026-03-11 13:41:33.774978 | controller | Wednesday 11 March 2026  13:41:33 +0000 (0:00:00.009)       0:00:19.811 *******
2026-03-11 13:41:33.774988 | controller | changed: [instance]
2026-03-11 13:41:34.129423 | controller |
2026-03-11 13:41:34.129455 | controller | RUNNING HANDLER [dnsmasq : Reload dnsmasq] *************************************
2026-03-11 13:41:34.129463 | controller | Wednesday 11 March 2026  13:41:33 +0000 (0:00:00.336)       0:00:20.147 *******
2026-03-11 13:41:34.129473 | controller | changed: [instance]
2026-03-11 13:41:34.131759 | controller |
2026-03-11 13:41:34.131779 | controller | PLAY RECAP *********************************************************************
2026-03-11 13:41:34.192756 | controller | instance                   : ok=71   changed=20   unreachable=0    failed=0    skipped=16   rescued=0    ignored=0
2026-03-11 13:41:34.192779 | controller |
2026-03-11 13:41:34.192787 | controller | Wednesday 11 March 2026  13:41:34 +0000 (0:00:00.354)       0:00:20.502 *******
2026-03-11 13:41:34.192793 | controller | ===============================================================================
2026-03-11 13:41:34.192798 | controller | dnsmasq : Install needed packages --------------------------------------- 3.74s
2026-03-11 13:41:34.192804 | controller | dnsmasq : Ensure SELinux knows about the new files and directories ------ 3.36s
2026-03-11 13:41:34.192809 | controller | dnsmasq : Add DHCP entries ---------------------------------------------- 1.04s
2026-03-11 13:41:34.192815 | controller | dnsmasq : Add DHCP entries ---------------------------------------------- 1.02s
2026-03-11 13:41:34.192824 | controller | dnsmasq : Enable and start service -------------------------------------- 0.87s
2026-03-11 13:41:34.192830 | controller | Gathering Facts --------------------------------------------------------- 0.82s
2026-03-11 13:41:34.192835 | controller | dnsmasq : Manage systemd unit file -------------------------------------- 0.54s
2026-03-11 13:41:34.192841 | controller | dnsmasq : Create network ------------------------------------------------ 0.44s
2026-03-11 13:41:34.192846 | controller | dnsmasq : Create network ------------------------------------------------ 0.43s
2026-03-11 13:41:34.192852 | controller | dnsmasq : Create network ------------------------------------------------ 0.42s
2026-03-11 13:41:34.192857 | controller | dnsmasq : Add/Remove address -------------------------------------------- 0.41s
2026-03-11 13:41:34.192862 | controller | dnsmasq : Add/Remove address -------------------------------------------- 0.41s
2026-03-11 13:41:34.192868 | controller | dnsmasq : Manage base configuration file -------------------------------- 0.39s
2026-03-11 13:41:34.192873 | controller | dnsmasq : Create listener configuration file ---------------------------- 0.39s
2026-03-11 13:41:34.192878 | controller | dnsmasq : Create dns configuration file --------------------------------- 0.36s
2026-03-11 13:41:34.192884 | controller | Create DHCP options configuration for tagged hosts ---------------------- 0.36s
2026-03-11 13:41:34.192889 | controller | dnsmasq : Reload dnsmasq ------------------------------------------------ 0.35s
2026-03-11 13:41:34.192899 | controller | dnsmasq : Restart dnsmasq ----------------------------------------------- 0.34s
2026-03-11 13:41:34.192904 | controller | dnsmasq : Manage configuration directory -------------------------------- 0.32s
2026-03-11 13:41:34.192909 | controller | Verify dnsmasq configuration is valid ----------------------------------- 0.28s
2026-03-11 13:41:34.192919 | controller | INFO     Running default > cleanup
2026-03-11 13:41:34.596582 | controller |
2026-03-11 13:41:34.596768 | controller | PLAY [Converge] ****************************************************************
2026-03-11 13:41:34.596916 | controller |
2026-03-11 13:41:34.597047 | controller | TASK [Gathering Facts] *********************************************************
2026-03-11 13:41:34.597183 | controller | Wednesday 11 March 2026  13:41:34 +0000 (0:00:00.013)       0:00:00.013 *******
2026-03-11 13:41:35.425746 | controller | ok: [instance]
2026-03-11 13:41:35.989769 | controller |
2026-03-11 13:41:35.989801 | controller | TASK [Copy generated content in ci-framework-data/artifacts] *******************
2026-03-11 13:41:35.989809 | controller | Wednesday 11 March 2026  13:41:35 +0000 (0:00:00.829)       0:00:00.842 *******
2026-03-11 13:41:35.989820 | controller | changed: [instance] => (item=/etc/cifmw-dnsmasq.conf)
2026-03-11 13:41:36.018344 | controller | changed: [instance] => (item=/etc/cifmw-dnsmasq.d/)
2026-03-11 13:41:36.018368 | controller |
2026-03-11 13:41:36.018377 | controller | TASK [Remove a domain specific forwarder] **************************************
2026-03-11 13:41:36.018385 | controller | Wednesday 11 March 2026  13:41:35 +0000 (0:00:00.563)       0:00:01.406 *******
2026-03-11 13:41:36.018396 | controller |
2026-03-11 13:41:36.052400 | controller | TASK [dnsmasq : Assert we have needed forwarder data] **************************
2026-03-11 13:41:36.052433 | controller | Wednesday 11 March 2026  13:41:36 +0000 (0:00:00.028)       0:00:01.435 *******
2026-03-11 13:41:36.052448 | controller | ok: [instance]
2026-03-11 13:41:36.052477 | controller |
2026-03-11 13:41:36.052485 | controller | TASK [dnsmasq : Assert each forwarder element have needed data] ****************
2026-03-11 13:41:36.052494 | controller | Wednesday 11 March 2026  13:41:36 +0000 (0:00:00.034)       0:00:01.469 *******
2026-03-11 13:41:36.091373 | controller | ok: [instance] => (item={'state': 'absent', 'server': '192.168.100.100', 'domains': ['battlestar.galactica.lan']})
2026-03-11 13:41:36.091414 | controller |
2026-03-11 13:41:36.091424 | controller | TASK [dnsmasq : Add/Remove forwarder] ******************************************
2026-03-11 13:41:36.091434 | controller | Wednesday 11 March 2026  13:41:36 +0000 (0:00:00.038)       0:00:01.508 *******
2026-03-11 13:41:36.404670 | controller | ok: [instance] => (item={'state': 'absent', 'server': '192.168.100.100', 'domains': ['battlestar.galactica.lan']})
2026-03-11 13:41:36.404725 | controller |
2026-03-11 13:41:36.404967 | controller | TASK [Remove some host records] ************************************************
2026-03-11 13:41:36.436982 | controller | Wednesday 11 March 2026  13:41:36 +0000 (0:00:00.313)       0:00:01.821 *******
2026-03-11 13:41:36.437013 | controller |
2026-03-11 13:41:36.470679 | controller | TASK [dnsmasq : Assert we have needed host record data] ************************
2026-03-11 13:41:36.470702 | controller | Wednesday 11 March 2026  13:41:36 +0000 (0:00:00.032)       0:00:01.854 *******
2026-03-11 13:41:36.470715 | controller | ok: [instance]
2026-03-11 13:41:36.520402 | controller |
2026-03-11 13:41:36.520435 | controller | TASK [dnsmasq : Assert each address element have needed data] ******************
2026-03-11 13:41:36.520445 | controller | Wednesday 11 March 2026  13:41:36 +0000 (0:00:00.033)       0:00:01.887 *******
2026-03-11 13:41:36.520457 | controller | ok: [instance] => (item={'state': 'absent', 'ips': ['192.168.253.50', '2345:0425:2CA1::dead'], 'names': ['dead.battlestar.galactica.lan']})
2026-03-11 13:41:36.520480 | controller | ok: [instance] => (item={'state': 'absent', 'ips': ['192.168.253.51', '2345:0425:2CA1::beef'], 'names': ['beef.battlestar.galactica.lan']})
2026-03-11 13:41:36.520507 | controller |
2026-03-11 13:41:36.520616 | controller | TASK [dnsmasq : Add/Remove address] ********************************************
2026-03-11 13:41:36.520728 | controller | Wednesday 11 March 2026  13:41:36 +0000 (0:00:00.049)       0:00:01.937 *******
2026-03-11 13:41:36.936906 | controller | changed: [instance] => (item={'state': 'absent', 'ips': ['192.168.253.50', '2345:0425:2CA1::dead'], 'names': ['dead.battlestar.galactica.lan']})
2026-03-11 13:41:36.964993 | controller | changed: [instance] => (item={'state': 'absent', 'ips': ['192.168.253.51', '2345:0425:2CA1::beef'], 'names': ['beef.battlestar.galactica.lan']})
2026-03-11 13:41:36.965023 | controller |
2026-03-11 13:41:36.965031 | controller | TASK [dnsmasq : Remove crc.testing from host_records] **************************
2026-03-11 13:41:36.965038 | controller | Wednesday 11 March 2026  13:41:36 +0000 (0:00:00.415)       0:00:02.353 *******
2026-03-11 13:41:36.965047 | controller | skipping: [instance]
2026-03-11 13:41:37.012080 | controller |
2026-03-11 13:41:37.012108 | controller | TASK [dnsmasq : Add controller-0 short hostname record when 'utility' in the name into /etc/hosts] ***
2026-03-11 13:41:37.012116 | controller | Wednesday 11 March 2026  13:41:36 +0000 (0:00:00.028)       0:00:02.382 *******
2026-03-11 13:41:37.012127 | controller | skipping: [instance] => (item={'state': 'absent', 'ips': ['192.168.253.50', '2345:0425:2CA1::dead'], 'names': ['dead.battlestar.galactica.lan']})
2026-03-11 13:41:37.044403 | controller | skipping: [instance] => (item={'state': 'absent', 'ips': ['192.168.253.51', '2345:0425:2CA1::beef'], 'names': ['beef.battlestar.galactica.lan']})
2026-03-11 13:41:37.044430 | controller | skipping: [instance]
2026-03-11 13:41:37.044439 | controller |
2026-03-11 13:41:37.044447 | controller | TASK [Remove some wildcard addresses] ******************************************
2026-03-11 13:41:37.044455 | controller | Wednesday 11 March 2026  13:41:37 +0000 (0:00:00.046)       0:00:02.429 *******
2026-03-11 13:41:37.044466 | controller |
2026-03-11 13:41:37.076694 | controller | TASK [dnsmasq : Assert we have needed address data] ****************************
2026-03-11 13:41:37.076716 | controller | Wednesday 11 March 2026  13:41:37 +0000 (0:00:00.032)       0:00:02.461 *******
2026-03-11 13:41:37.076727 | controller | ok: [instance]
2026-03-11 13:41:37.076781 | controller |
2026-03-11 13:41:37.076879 | controller | TASK [dnsmasq : Assert each address element have needed data] ******************
2026-03-11 13:41:37.076986 | controller | Wednesday 11 March 2026  13:41:37 +0000 (0:00:00.032)       0:00:02.494 *******
2026-03-11 13:41:37.162814 | controller | ok: [instance] => (item={'state': 'absent', 'ipaddr': '1.2.3.4', 'domains': ['apps.ocp.starwars.lan']})
2026-03-11 13:41:37.162912 | controller | ok: [instance] => (item={'state': 'absent', 'ipaddr': '4.3.2.1', 'domains': ['apps.ocp.startrek.lan']})
2026-03-11 13:41:37.163014 | controller |
2026-03-11 13:41:37.163113 | controller | TASK [dnsmasq : Add/Remove address] ********************************************
2026-03-11 13:41:37.163231 | controller | Wednesday 11 March 2026  13:41:37 +0000 (0:00:00.086)       0:00:02.580 *******
2026-03-11 13:41:37.580015 | controller | changed: [instance] => (item={'state': 'absent', 'ipaddr': '1.2.3.4', 'domains': ['apps.ocp.starwars.lan']})
2026-03-11 13:41:37.622415 | controller | changed: [instance] => (item={'state': 'absent', 'ipaddr': '4.3.2.1', 'domains': ['apps.ocp.startrek.lan']})
2026-03-11 13:41:37.622452 | controller |
2026-03-11 13:41:37.622463 | controller | TASK [dnsmasq : Cleaning dnsmasq] **********************************************
2026-03-11 13:41:37.622471 | controller | Wednesday 11 March 2026  13:41:37 +0000 (0:00:00.416)       0:00:02.996 *******
2026-03-11 13:41:37.622484 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/dnsmasq/tasks/configure.yml for instance
2026-03-11 13:41:38.921002 | controller |
2026-03-11 13:41:38.921032 | controller | TASK [dnsmasq : Install needed packages] ***************************************
2026-03-11 13:41:38.921040 | controller | Wednesday 11 March 2026  13:41:37 +0000 (0:00:00.042)       0:00:03.039 *******
2026-03-11 13:41:38.921051 | controller | ok: [instance]
2026-03-11 13:41:42.237389 | controller |
2026-03-11 13:41:42.237420 | controller | TASK [dnsmasq : Ensure SELinux knows about the new files and directories] ******
2026-03-11 13:41:42.237429 | controller | Wednesday 11 March 2026  13:41:38 +0000 (0:00:01.298)       0:00:04.338 *******
2026-03-11 13:41:42.237439 | controller | changed: [instance] => (item={'target': '/etc/cifmw-dnsmasq.conf', 'setype': 'dnsmasq_etc_t'})
2026-03-11 13:41:42.237462 | controller | changed: [instance] => (item={'target': '/etc/cifmw-dnsmasq.d(/.*)?', 'setype': 'dnsmasq_etc_t'})
2026-03-11 13:41:42.237471 | controller | changed: [instance] => (item={'target': '/var/run/cifmw-dnsmasq.pid', 'setype': 'dnsmasq_var_run_t'})
2026-03-11 13:41:42.237750 | controller |
2026-03-11 13:41:42.541206 | controller | TASK [dnsmasq : Manage configuration directory] ********************************
2026-03-11 13:41:42.541235 | controller | Wednesday 11 March 2026  13:41:42 +0000 (0:00:03.316)       0:00:07.654 *******
2026-03-11 13:41:42.541247 | controller | changed: [instance]
2026-03-11 13:41:42.558713 | controller |
2026-03-11 13:41:42.558738 | controller | TASK [dnsmasq : Manage systemd unit file] **************************************
2026-03-11 13:41:42.558745 | controller | Wednesday 11 March 2026  13:41:42 +0000 (0:00:00.303)       0:00:07.958 *******
2026-03-11 13:41:42.558755 | controller | skipping: [instance]
2026-03-11 13:41:42.575628 | controller |
2026-03-11 13:41:42.575648 | controller | TASK [dnsmasq : Manage base configuration file] ********************************
2026-03-11 13:41:42.575656 | controller | Wednesday 11 March 2026  13:41:42 +0000 (0:00:00.017)       0:00:07.976 *******
2026-03-11 13:41:42.575665 | controller | skipping: [instance]
2026-03-11 13:41:42.599311 | controller |
2026-03-11 13:41:42.599330 | controller | TASK [dnsmasq : Render listener configuration] *********************************
2026-03-11 13:41:42.599342 | controller | Wednesday 11 March 2026  13:41:42 +0000 (0:00:00.017)       0:00:07.993 *******
2026-03-11 13:41:42.599352 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/dnsmasq/tasks/listener.yml for instance
2026-03-11 13:41:42.617402 | controller |
2026-03-11 13:41:42.617422 | controller | TASK [dnsmasq : Create listener configuration file] ****************************
2026-03-11 13:41:42.617430 | controller | Wednesday 11 March 2026  13:41:42 +0000 (0:00:00.023)       0:00:08.016 *******
2026-03-11 13:41:42.617441 | controller | skipping: [instance]
2026-03-11 13:41:42.812357 | controller |
2026-03-11 13:41:42.812392 | controller | TASK [dnsmasq : Remove listener configuration file] ****************************
2026-03-11 13:41:42.812401 | controller | Wednesday 11 March 2026  13:41:42 +0000 (0:00:00.018)       0:00:08.034 *******
2026-03-11 13:41:42.812411 | controller | changed: [instance]
2026-03-11 13:41:42.836404 | controller |
2026-03-11 13:41:42.836436 | controller | TASK [dnsmasq : Render dns configuration] **************************************
2026-03-11 13:41:42.836446 | controller | Wednesday 11 March 2026  13:41:42 +0000 (0:00:00.194)       0:00:08.229 *******
2026-03-11 13:41:42.836459 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/dnsmasq/tasks/dns.yml for instance
2026-03-11 13:41:42.836653 | controller |
2026-03-11 13:41:42.836666 | controller | TASK [dnsmasq : Create dns configuration file] *********************************
2026-03-11 13:41:42.855366 | controller | Wednesday 11 March 2026  13:41:42 +0000 (0:00:00.024)       0:00:08.253 *******
2026-03-11 13:41:42.855399 | controller | skipping: [instance]
2026-03-11 13:41:42.855471 | controller |
2026-03-11 13:41:42.855481 | controller | TASK [dnsmasq : Remove dns configuration file] *********************************
2026-03-11 13:41:42.855490 | controller | Wednesday 11 March 2026  13:41:42 +0000 (0:00:00.018)       0:00:08.272 *******
2026-03-11 13:41:43.055101 | controller | changed: [instance]
2026-03-11 13:41:43.070236 | controller |
2026-03-11 13:41:43.070263 | controller | TASK [dnsmasq : Add localhost addresses from defined dnsmasq listen addresses to loopback interface] ***
2026-03-11 13:41:43.070271 | controller | Wednesday 11 March 2026  13:41:43 +0000 (0:00:00.199)       0:00:08.472 *******
2026-03-11 13:41:43.070284 | controller | skipping: [instance]
2026-03-11 13:41:43.087189 | controller |
2026-03-11 13:41:43.087212 | controller | TASK [dnsmasq : Enable and start service] **************************************
2026-03-11 13:41:43.087220 | controller | Wednesday 11 March 2026  13:41:43 +0000 (0:00:00.015)       0:00:08.487 *******
2026-03-11 13:41:43.087230 | controller | skipping: [instance]
2026-03-11 13:41:43.791497 | controller |
2026-03-11 13:41:43.791532 | controller | TASK [dnsmasq : Stop service] **************************************************
2026-03-11 13:41:43.791541 | controller | Wednesday 11 March 2026  13:41:43 +0000 (0:00:00.017)       0:00:08.504 *******
2026-03-11 13:41:43.791552 | controller | changed: [instance]
2026-03-11 13:41:43.984307 | controller |
2026-03-11 13:41:43.984337 | controller | TASK [dnsmasq : Remove unit file] **********************************************
2026-03-11 13:41:43.984346 | controller | Wednesday 11 March 2026  13:41:43 +0000 (0:00:00.703)       0:00:09.208 *******
2026-03-11 13:41:43.984357 | controller | changed: [instance]
2026-03-11 13:41:44.354397 | controller |
2026-03-11 13:41:44.354430 | controller | TASK [dnsmasq : Remove main configuration files] *******************************
2026-03-11 13:41:44.354438 | controller | Wednesday 11 March 2026  13:41:43 +0000 (0:00:00.192)       0:00:09.401 *******
2026-03-11 13:41:44.354450 | controller | changed: [instance] => (item=/etc/cifmw-dnsmasq.conf)
2026-03-11 13:41:44.566932 | controller | changed: [instance] => (item=/etc/cifmw-dnsmasq.d)
2026-03-11 13:41:44.566964 | controller |
2026-03-11 13:41:44.566972 | controller | TASK [dnsmasq : Remove lease file] *********************************************
2026-03-11 13:41:44.566978 | controller | Wednesday 11 March 2026  13:41:44 +0000 (0:00:00.369)       0:00:09.771 *******
2026-03-11 13:41:44.566989 | controller | changed: [instance]
2026-03-11 13:41:44.886045 | controller |
2026-03-11 13:41:44.886078 | controller | RUNNING HANDLER [dnsmasq : Restart dnsmasq] ************************************
2026-03-11 13:41:44.886086 | controller | Wednesday 11 March 2026  13:41:44 +0000 (0:00:00.212)       0:00:09.984 *******
2026-03-11 13:41:44.886098 | controller | ok: [instance]
2026-03-11 13:41:44.937663 | controller |
2026-03-11 13:41:44.937688 | controller | PLAY RECAP *********************************************************************
2026-03-11 13:41:44.937697 | controller | instance                   : ok=24   changed=11   unreachable=0    failed=0    skipped=8    rescued=0    ignored=0
2026-03-11 13:41:44.937705 | controller |
2026-03-11 13:41:44.937713 | controller | Wednesday 11 March 2026  13:41:44 +0000 (0:00:00.316)       0:00:10.301 *******
2026-03-11 13:41:44.937721 | controller | ===============================================================================
2026-03-11 13:41:44.937728 | controller | dnsmasq : Ensure SELinux knows about the new files and directories ------ 3.32s
2026-03-11 13:41:44.937735 | controller | dnsmasq : Install needed packages --------------------------------------- 1.30s
2026-03-11 13:41:44.937742 | controller | Gathering Facts --------------------------------------------------------- 0.83s
2026-03-11 13:41:44.937749 | controller | dnsmasq : Stop service -------------------------------------------------- 0.70s
2026-03-11 13:41:44.937765 | controller | Copy generated content in ci-framework-data/artifacts ------------------- 0.56s
2026-03-11 13:41:44.937773 | controller | dnsmasq : Add/Remove address -------------------------------------------- 0.42s
2026-03-11 13:41:44.937780 | controller | dnsmasq : Add/Remove address -------------------------------------------- 0.42s
2026-03-11 13:41:44.937787 | controller | dnsmasq : Remove main configuration files ------------------------------- 0.37s
2026-03-11 13:41:44.937794 | controller | dnsmasq : Restart dnsmasq ----------------------------------------------- 0.32s
2026-03-11 13:41:44.937802 | controller | dnsmasq : Add/Remove forwarder ------------------------------------------ 0.31s
2026-03-11 13:41:44.937809 | controller | dnsmasq : Manage configuration directory -------------------------------- 0.30s
2026-03-11 13:41:44.937816 | controller | dnsmasq : Remove lease file --------------------------------------------- 0.21s
2026-03-11 13:41:44.937823 | controller | dnsmasq : Remove dns configuration file --------------------------------- 0.20s
2026-03-11 13:41:44.937830 | controller | dnsmasq : Remove listener configuration file ---------------------------- 0.19s
2026-03-11 13:41:44.937838 | controller | dnsmasq : Remove unit file ---------------------------------------------- 0.19s
2026-03-11 13:41:44.937845 | controller | dnsmasq : Assert each address element have needed data ------------------ 0.09s
2026-03-11 13:41:44.937852 | controller | dnsmasq : Assert each address element have needed data ------------------ 0.05s
2026-03-11 13:41:44.937859 | controller | dnsmasq : Add controller-0 short hostname record when 'utility' in the name into /etc/hosts --- 0.05s
2026-03-11 13:41:44.937867 | controller | dnsmasq : Cleaning dnsmasq ---------------------------------------------- 0.04s
2026-03-11 13:41:44.937874 | controller | dnsmasq : Assert each forwarder element have needed data ---------------- 0.04s
2026-03-11 13:41:44.937886 | controller | INFO     Writing /tmp/report.html report.
2026-03-11 13:41:45.315350 | controller | changed
2026-03-11 13:41:45.337569 | 
2026-03-11 13:41:45.337639 | PLAY RECAP
2026-03-11 13:41:45.337678 | controller | ok: 4 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-03-11 13:41:45.337701 | 
2026-03-11 13:41:45.401998 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2026-03-11 13:41:45.402892 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2026-03-11 13:41:45.926789 | 
2026-03-11 13:41:45.926924 | PLAY [Run ci/playbooks/collect-logs.yml]
2026-03-11 13:41:45.947148 | 
2026-03-11 13:41:45.947223 | TASK [Filter out host if needed]
2026-03-11 13:41:45.956077 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2026-03-11 13:41:45.960898 | 
2026-03-11 13:41:45.960966 | TASK [Ensure file is present]
2026-03-11 13:41:46.252765 | controller | ok
2026-03-11 13:41:46.260051 | 
2026-03-11 13:41:46.260140 | TASK [Manage molecule report file]
2026-03-11 13:41:46.785490 | controller | changed
2026-03-11 13:41:46.790609 | 
2026-03-11 13:41:46.790673 | TASK [Check if we get ci-framework-data basedir]
2026-03-11 13:41:46.971566 | controller | ok
2026-03-11 13:41:46.976574 | 
2026-03-11 13:41:46.976647 | TASK [Create ci-framework-data log directory for zuul]
2026-03-11 13:41:47.268289 | controller | changed
2026-03-11 13:41:47.273452 | 
2026-03-11 13:41:47.273519 | TASK [Copy ci-framework interesting files]
2026-03-11 13:41:47.465640 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2026-03-11 13:41:47.798839 | controller | changed
2026-03-11 13:41:47.804158 | 
2026-03-11 13:41:47.804224 | TASK [Get SELinux listing]
2026-03-11 13:41:48.329172 | controller | changed
2026-03-11 13:41:48.334856 | 
2026-03-11 13:41:48.334922 | TASK [Generate log index]
2026-03-11 13:41:48.931290 | controller | changed
2026-03-11 13:41:48.936024 | 
2026-03-11 13:41:48.936158 | TASK [Get some env related data]
2026-03-11 13:41:49.457801 | controller | /home/zuul/.local/bin/ansible
2026-03-11 13:41:50.490274 | controller | changed
2026-03-11 13:41:50.495336 | 
2026-03-11 13:41:50.495400 | TASK [Generate list of logs to collect in home directory]
2026-03-11 13:41:50.767404 | controller | ok: All paths examined
2026-03-11 13:41:50.772285 | 
2026-03-11 13:41:50.772372 | LOOP [Copy logs from home directory]
2026-03-11 13:41:51.078436 | controller | changed:
2026-03-11 13:41:51.078548 | controller | {
2026-03-11 13:41:51.078575 | controller |   "atime": 1773236362.952983,
2026-03-11 13:41:51.078595 | controller |   "ctime": 1773236380.2912354,
2026-03-11 13:41:51.078612 | controller |   "dev": 64513,
2026-03-11 13:41:51.078629 | controller |   "gid": 1000,
2026-03-11 13:41:51.078645 | controller |   "gr_name": "zuul",
2026-03-11 13:41:51.078662 | controller |   "inode": 1197203,
2026-03-11 13:41:51.078679 | controller |   "isblk": false,
2026-03-11 13:41:51.078694 | controller |   "ischr": false,
2026-03-11 13:41:51.078709 | controller |   "isdir": false,
2026-03-11 13:41:51.078723 | controller |   "isfifo": false,
2026-03-11 13:41:51.078738 | controller |   "isgid": false,
2026-03-11 13:41:51.078752 | controller |   "islnk": false,
2026-03-11 13:41:51.078767 | controller |   "isreg": true,
2026-03-11 13:41:51.078781 | controller |   "issock": false,
2026-03-11 13:41:51.078800 | controller |   "isuid": false,
2026-03-11 13:41:51.078815 | controller |   "mode": "0644",
2026-03-11 13:41:51.078829 | controller |   "mtime": 1773236380.2912354,
2026-03-11 13:41:51.078842 | controller |   "nlink": 1,
2026-03-11 13:41:51.078856 | controller |   "path": "/home/zuul/ansible.log",
2026-03-11 13:41:51.078870 | controller |   "pw_name": "zuul",
2026-03-11 13:41:51.078885 | controller |   "rgrp": true,
2026-03-11 13:41:51.078899 | controller |   "roth": true,
2026-03-11 13:41:51.078913 | controller |   "rusr": true,
2026-03-11 13:41:51.078929 | controller |   "size": 6749,
2026-03-11 13:41:51.078943 | controller |   "uid": 1000,
2026-03-11 13:41:51.078957 | controller |   "wgrp": false,
2026-03-11 13:41:51.078971 | controller |   "woth": false,
2026-03-11 13:41:51.078988 | controller |   "wusr": true,
2026-03-11 13:41:51.079003 | controller |   "xgrp": false,
2026-03-11 13:41:51.079017 | controller |   "xoth": false,
2026-03-11 13:41:51.079031 | controller |   "xusr": false
2026-03-11 13:41:51.079045 | controller | }
2026-03-11 13:41:51.092013 | 
2026-03-11 13:41:51.092117 | TASK [Copy crio stats log file]
2026-03-11 13:41:51.105758 | controller | skipping: Conditional result was False
2026-03-11 13:41:51.111318 | 
2026-03-11 13:41:51.111382 | TASK [Get SELinux related data]
2026-03-11 13:41:51.307778 | controller | <no matches>
2026-03-11 13:41:51.637016 | controller | ERROR
2026-03-11 13:41:51.637214 | controller | {
2026-03-11 13:41:51.637246 | controller |   "delta": "0:00:00.008103",
2026-03-11 13:41:51.637268 | controller |   "end": "2026-03-11 13:41:51.308621",
2026-03-11 13:41:51.637287 | controller |   "msg": "non-zero return code",
2026-03-11 13:41:51.637349 | controller |   "rc": 1,
2026-03-11 13:41:51.637371 | controller |   "start": "2026-03-11 13:41:51.300518"
2026-03-11 13:41:51.637387 | controller | }
2026-03-11 13:41:51.637409 | controller | ERROR: Ignoring Errors
2026-03-11 13:41:51.642873 | 
2026-03-11 13:41:51.642936 | TASK [Create system configuration directory]
2026-03-11 13:41:51.823745 | controller | changed
2026-03-11 13:41:51.828519 | 
2026-03-11 13:41:51.828579 | TASK [Get some of the system configurations]
2026-03-11 13:41:52.355016 | controller | changed
2026-03-11 13:41:52.360449 | 
2026-03-11 13:41:52.360513 | TASK [Copy generated documentation if available]
2026-03-11 13:41:52.373860 | controller | skipping: Conditional result was False
2026-03-11 13:41:52.379192 | 
2026-03-11 13:41:52.379256 | TASK [Copy generated AsciiDoc documentation if available]
2026-03-11 13:41:52.392480 | controller | skipping: Conditional result was False
2026-03-11 13:41:52.398154 | 
2026-03-11 13:41:52.398223 | TASK [Compress logs bigger than 2MB]
2026-03-11 13:41:52.923482 | controller | changed
2026-03-11 13:41:52.928932 | 
2026-03-11 13:41:52.928993 | TASK [Copy files from workspace on node]
2026-03-11 13:41:52.947076 | controller | ok
2026-03-11 13:41:52.968923 | 
2026-03-11 13:41:52.968990 | TASK [fetch-output : Set log path for multiple nodes]
2026-03-11 13:41:52.982585 | controller | skipping: Conditional result was False
2026-03-11 13:41:52.987851 | 
2026-03-11 13:41:52.987921 | TASK [fetch-output : Set log path for single node]
2026-03-11 13:41:53.015370 | controller | ok
2026-03-11 13:41:53.021590 | 
2026-03-11 13:41:53.021652 | LOOP [fetch-output : Ensure local output dirs]
2026-03-11 13:41:53.213156 | controller -> localhost | ok: "/var/lib/zuul/builds/a2f71dd2d5e44ad6b4deedccae2d93d1/work/logs"
2026-03-11 13:41:53.213403 | controller -> localhost | changed: All items complete
2026-03-11 13:41:53.213439 | 
2026-03-11 13:41:53.389591 | controller -> localhost | changed: "/var/lib/zuul/builds/a2f71dd2d5e44ad6b4deedccae2d93d1/work/artifacts"
2026-03-11 13:41:53.563552 | controller -> localhost | changed: "/var/lib/zuul/builds/a2f71dd2d5e44ad6b4deedccae2d93d1/work/docs"
2026-03-11 13:41:53.579620 | 
2026-03-11 13:41:53.579723 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-03-11 13:41:54.094657 | controller | changed:
2026-03-11 13:41:54.094822 | controller | .d..t...... ./
2026-03-11 13:41:54.094859 | controller | >f+++++++++ README.html
2026-03-11 13:41:54.094882 | controller | >f+++++++++ ansible-execution.log
2026-03-11 13:41:54.094903 | controller | >f+++++++++ ansible.log
2026-03-11 13:41:54.094922 | controller | >f+++++++++ dmesg.log
2026-03-11 13:41:54.094939 | controller | >f+++++++++ installed-pkgs.log
2026-03-11 13:41:54.094959 | controller | >f+++++++++ python.log
2026-03-11 13:41:54.094979 | controller | >f+++++++++ registries.conf
2026-03-11 13:41:54.094998 | controller | >f+++++++++ report.html
2026-03-11 13:41:54.095017 | controller | >f+++++++++ selinux-denials.log
2026-03-11 13:41:54.095034 | controller | >f+++++++++ selinux-listing.log
2026-03-11 13:41:54.095051 | controller | cd+++++++++ ci-framework-data/
2026-03-11 13:41:54.095068 | controller | cd+++++++++ ci-framework-data/artifacts/
2026-03-11 13:41:54.095085 | controller | >f+++++++++ ci-framework-data/artifacts/addresses.conf
2026-03-11 13:41:54.095103 | controller | >f+++++++++ ci-framework-data/artifacts/cifmw-dnsmasq.conf
2026-03-11 13:41:54.095120 | controller | >f+++++++++ ci-framework-data/artifacts/dns.conf
2026-03-11 13:41:54.095137 | controller | >f+++++++++ ci-framework-data/artifacts/forwarders.conf
2026-03-11 13:41:54.095154 | controller | >f+++++++++ ci-framework-data/artifacts/host_records.conf
2026-03-11 13:41:54.095171 | controller | >f+++++++++ ci-framework-data/artifacts/listener.conf
2026-03-11 13:41:54.095187 | controller | >f+++++++++ ci-framework-data/artifacts/oldies.conf
2026-03-11 13:41:54.095204 | controller | >f+++++++++ ci-framework-data/artifacts/startrek.conf
2026-03-11 13:41:54.095220 | controller | >f+++++++++ ci-framework-data/artifacts/starwars.conf
2026-03-11 13:41:54.095236 | controller | >f+++++++++ ci-framework-data/artifacts/test-dhcp-options.conf
2026-03-11 13:41:54.095252 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2026-03-11 13:41:54.095267 | controller | cd+++++++++ ci-framework-data/artifacts/dhcp-hosts.d/
2026-03-11 13:41:54.095282 | controller | >f+++++++++ ci-framework-data/artifacts/dhcp-hosts.d/startrek_data_0a:19:02:f8:4c:b3
2026-03-11 13:41:54.095329 | controller | >f+++++++++ ci-framework-data/artifacts/dhcp-hosts.d/startrek_spock_0a:19:02:f8:4c:a8
2026-03-11 13:41:54.095353 | controller | >f+++++++++ ci-framework-data/artifacts/dhcp-hosts.d/starwars_0a:19:02:f8:4c:a7
2026-03-11 13:41:54.095371 | controller | >f+++++++++ ci-framework-data/artifacts/dhcp-hosts.d/starwars_c3po_0a:19:02:f8:4c:b2
2026-03-11 13:41:54.095388 | controller | >f+++++++++ ci-framework-data/artifacts/dhcp-hosts.d/starwars_r2d2_0a:19:02:f8:4c:b1
2026-03-11 13:41:54.095405 | controller | >f+++++++++ ci-framework-data/artifacts/dhcp-hosts.d/starwars_solo_0a:19:02:f8:4c:a8
2026-03-11 13:41:54.095420 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2026-03-11 13:41:54.095435 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2026-03-11 13:41:54.095450 | controller | cd+++++++++ ci-framework-data/artifacts/repositories/
2026-03-11 13:41:54.095465 | controller | >f+++++++++ ci-framework-data/artifacts/repositories/delorean.repo.md5
2026-03-11 13:41:54.095480 | controller | cd+++++++++ ci-framework-data/logs/
2026-03-11 13:41:54.095495 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2026-03-11 13:41:54.095510 | controller | cd+++++++++ registries.conf.d/
2026-03-11 13:41:54.095525 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2026-03-11 13:41:54.095540 | controller | cd+++++++++ system-config/
2026-03-11 13:41:54.095556 | controller | cd+++++++++ system-config/libvirt/
2026-03-11 13:41:54.095572 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2026-03-11 13:41:54.095588 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2026-03-11 13:41:54.443256 | controller | changed: .d..t...... ./
2026-03-11 13:41:54.786531 | controller | changed: .d..t...... ./
2026-03-11 13:41:54.803580 | 
2026-03-11 13:41:54.803677 | TASK [Return artifact to Zuul]
2026-03-11 13:41:54.831969 | controller | ok
2026-03-11 13:41:54.850404 | 
2026-03-11 13:41:54.850460 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2026-03-11 13:41:54.850557 | 
2026-03-11 13:41:54.850584 | PLAY RECAP
2026-03-11 13:41:54.850621 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2026-03-11 13:41:54.850643 | 
2026-03-11 13:41:54.919445 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2026-03-11 13:41:54.920138 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2026-03-11 13:41:55.417188 | 
2026-03-11 13:41:55.417286 | PLAY [all]
2026-03-11 13:41:55.435071 | 
2026-03-11 13:41:55.435144 | TASK [include_role : fetch-output]
2026-03-11 13:41:55.463062 | controller | ok
2026-03-11 13:41:55.478755 | 
2026-03-11 13:41:55.478837 | TASK [fetch-output : Set log path for multiple nodes]
2026-03-11 13:41:55.522265 | controller | skipping: Conditional result was False
2026-03-11 13:41:55.527570 | 
2026-03-11 13:41:55.527639 | TASK [fetch-output : Set log path for single node]
2026-03-11 13:41:55.555710 | controller | ok
2026-03-11 13:41:55.560510 | 
2026-03-11 13:41:55.560581 | LOOP [fetch-output : Ensure local output dirs]
2026-03-11 13:41:55.873861 | controller -> localhost | ok: "/var/lib/zuul/builds/a2f71dd2d5e44ad6b4deedccae2d93d1/work/logs"
2026-03-11 13:41:56.051114 | controller -> localhost | ok: "/var/lib/zuul/builds/a2f71dd2d5e44ad6b4deedccae2d93d1/work/artifacts"
2026-03-11 13:41:56.232963 | controller -> localhost | ok: "/var/lib/zuul/builds/a2f71dd2d5e44ad6b4deedccae2d93d1/work/docs"
2026-03-11 13:41:56.247968 | 
2026-03-11 13:41:56.248076 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-03-11 13:41:56.737072 | controller | ok
2026-03-11 13:41:56.737278 | controller | ok: All items complete
2026-03-11 13:41:56.737369 | 
2026-03-11 13:41:57.096360 | controller | ok
2026-03-11 13:41:57.453504 | controller | ok
2026-03-11 13:41:57.467639 | 
2026-03-11 13:41:57.467745 | TASK [include_role : fetch-output-openshift]
2026-03-11 13:41:57.480683 | controller | skipping: Conditional result was False
2026-03-11 13:41:57.486046 | 
2026-03-11 13:41:57.486114 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-03-11 13:41:57.810791 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.006091
2026-03-11 13:41:57.993396 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.005732
2026-03-11 13:41:58.019714 | 
2026-03-11 13:41:58.019801 | PLAY [all]
2026-03-11 13:41:58.033156 | 
2026-03-11 13:41:58.033230 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-03-11 13:41:58.416719 | controller | changed
2026-03-11 13:41:58.437812 | 
2026-03-11 13:41:58.437883 | PLAY RECAP
2026-03-11 13:41:58.437937 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2026-03-11 13:41:58.437965 | 
2026-03-11 13:41:58.507113 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2026-03-11 13:41:58.507847 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2026-03-11 13:41:59.053951 | 
2026-03-11 13:41:59.054116 | PLAY [localhost]
2026-03-11 13:41:59.071543 | 
2026-03-11 13:41:59.071618 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-03-11 13:41:59.362022 | localhost | changed
2026-03-11 13:41:59.366442 | 
2026-03-11 13:41:59.366524 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-03-11 13:41:59.383982 | localhost | ok
2026-03-11 13:41:59.392603 | 
2026-03-11 13:41:59.392677 | TASK [add-fileserver : Create SSH private key tempfile]
2026-03-11 13:41:59.672818 | localhost | changed
2026-03-11 13:41:59.678141 | 
2026-03-11 13:41:59.678212 | TASK [add-fileserver : Create SSH private key from secret]
2026-03-11 13:42:00.201051 | localhost | changed
2026-03-11 13:42:00.205971 | 
2026-03-11 13:42:00.206048 | TASK [add-fileserver : Add fileserver ssh key]
2026-03-11 13:42:00.526372 | localhost | Identity added: /var/lib/zuul/builds/a2f71dd2d5e44ad6b4deedccae2d93d1/work/tmp/ansible.ffvjixem (/var/lib/zuul/builds/a2f71dd2d5e44ad6b4deedccae2d93d1/work/tmp/ansible.ffvjixem)
2026-03-11 13:42:00.526531 | localhost | ok: Runtime: 0:00:00.006580
2026-03-11 13:42:00.530582 | 
2026-03-11 13:42:00.530651 | TASK [add-fileserver : Remove SSH private key from disk]
2026-03-11 13:42:00.739705 | localhost | ok: Runtime: 0:00:00.003542
2026-03-11 13:42:00.744150 | 
2026-03-11 13:42:00.744221 | TASK [add-fileserver : Add fileserver to inventory]
2026-03-11 13:42:00.791703 | localhost | changed
2026-03-11 13:42:00.796363 | 
2026-03-11 13:42:00.796430 | TASK [add-fileserver : Add fileserver server to known hosts]
2026-03-11 13:42:01.097482 | localhost | changed
2026-03-11 13:42:01.116699 | 
2026-03-11 13:42:01.116764 | PLAY [localhost]
2026-03-11 13:42:01.128447 | 
2026-03-11 13:42:01.128512 | TASK [Generate bulk log download script]
2026-03-11 13:42:01.146767 | localhost | ok
2026-03-11 13:42:01.157618 | 
2026-03-11 13:42:01.157688 | TASK [local-log-download : Check API endpoint is defined]
2026-03-11 13:42:01.184497 | localhost | ok: All assertions passed
2026-03-11 13:42:01.188810 | 
2026-03-11 13:42:01.188879 | TASK [local-log-download : Create download script]
2026-03-11 13:42:01.525554 | localhost -> localhost | changed
2026-03-11 13:42:01.533744 | 
2026-03-11 13:42:01.533814 | TASK [Register quick-download link]
2026-03-11 13:42:01.549890 | localhost | ok
2026-03-11 13:42:01.581777 | 
2026-03-11 13:42:01.581851 | PLAY [logserver.rdoproject.org]
2026-03-11 13:42:01.590761 | 
2026-03-11 13:42:01.590824 | TASK [Set zuul-log-path fact]
2026-03-11 13:42:01.606496 | logserver.rdoproject.org | ok
2026-03-11 13:42:01.614800 | 
2026-03-11 13:42:01.614898 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-03-11 13:42:01.640695 | logserver.rdoproject.org | ok
2026-03-11 13:42:01.645708 | 
2026-03-11 13:42:01.645775 | TASK [upload-logs : Create log directories]
2026-03-11 13:42:03.744423 | logserver.rdoproject.org | changed
2026-03-11 13:42:03.747646 | 
2026-03-11 13:42:03.747718 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-03-11 13:42:03.963508 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.004348
2026-03-11 13:42:03.968167 | 
2026-03-11 13:42:03.968252 | TASK [upload-logs : Upload logs to log server]
2026-03-11 13:42:05.396243 | logserver.rdoproject.org | Output suppressed because no_log was given
2026-03-11 13:42:05.399047 | 
2026-03-11 13:42:05.399110 | LOOP [upload-logs : Compress console log and json output]
2026-03-11 13:42:05.435361 | logserver.rdoproject.org | skipping: Conditional result was False
2026-03-11 13:42:05.443428 | logserver.rdoproject.org | skipping: Conditional result was False
2026-03-11 13:42:05.456364 | 
2026-03-11 13:42:05.456449 | LOOP [upload-logs : Upload compressed console log and json output]
2026-03-11 13:42:05.490367 | logserver.rdoproject.org | skipping: Conditional result was False
2026-03-11 13:42:05.490580 | 
2026-03-11 13:42:05.493664 | logserver.rdoproject.org | skipping: Conditional result was False
2026-03-11 13:42:05.504330 | 
2026-03-11 13:42:05.504413 | LOOP [upload-logs : Upload console log and json output]