2025-11-27 17:50:25.530707 | Job console starting...
2025-11-27 17:50:25.753201 | Updating repositories
2025-11-27 17:50:26.468128 | Preparing job workspace
2025-11-27 17:50:34.688021 | Running Ansible setup...
2025-11-27 17:50:37.601377 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-11-27 17:50:38.113133 | 
2025-11-27 17:50:38.113233 | PLAY [localhost]
2025-11-27 17:50:38.121781 | 
2025-11-27 17:50:38.121853 | TASK [Gathering Facts]
2025-11-27 17:50:38.933351 | localhost | ok
2025-11-27 17:50:38.950729 | 
2025-11-27 17:50:38.950835 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-11-27 17:50:39.288108 | localhost -> localhost | changed
2025-11-27 17:50:39.293601 | 
2025-11-27 17:50:39.293678 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-11-27 17:50:40.114440 | localhost -> localhost | changed
2025-11-27 17:50:40.122557 | 
2025-11-27 17:50:40.122638 | TASK [Setup log path fact]
2025-11-27 17:50:40.142266 | localhost | ok
2025-11-27 17:50:40.154394 | 
2025-11-27 17:50:40.154528 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-11-27 17:50:40.173721 | localhost | ok
2025-11-27 17:50:40.181494 | 
2025-11-27 17:50:40.181570 | TASK [emit-job-header : Print job information]
2025-11-27 17:50:40.208467 | # Job Information
2025-11-27 17:50:40.208583 | Ansible Version: 2.15.12
2025-11-27 17:50:40.208612 | Job: cifmw-molecule-dnsmasq
2025-11-27 17:50:40.208634 | Pipeline: github-check
2025-11-27 17:50:40.208653 | Executor: ibm-bm4-ze.softwarefactory-project.io
2025-11-27 17:50:40.208671 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3505
2025-11-27 17:50:40.208689 | Log URL (when completed): https://logserver.rdoproject.org/2ad/rdoproject.org/2adaea711d3b4eaa99992765d3967e56/
2025-11-27 17:50:40.208707 | Event ID: 603045d0-cbb9-11f0-9783-d4f4a5f493fb
2025-11-27 17:50:40.212038 | 
2025-11-27 17:50:40.212098 | LOOP [emit-job-header : Print node information]
2025-11-27 17:50:40.291076 | localhost | ok:
2025-11-27 17:50:40.291279 | localhost | # Node Information
2025-11-27 17:50:40.291309 | localhost | Inventory Hostname: controller
2025-11-27 17:50:40.291332 | localhost | Hostname: np0005538155
2025-11-27 17:50:40.291352 | localhost | Username: zuul
2025-11-27 17:50:40.291372 | localhost | Distro: CentOS 9
2025-11-27 17:50:40.291390 | localhost | Provider: ibm-bm4-nodepool
2025-11-27 17:50:40.291407 | localhost | Region: regionOne
2025-11-27 17:50:40.291433 | localhost | Label: cloud-centos-9-stream-tripleo-ibm
2025-11-27 17:50:40.291453 | localhost | Product Name: OpenStack Compute
2025-11-27 17:50:40.291470 | localhost | Interface IP: 192.168.26.245
2025-11-27 17:50:40.313077 | 
2025-11-27 17:50:40.313153 | PLAY [all]
2025-11-27 17:50:40.319098 | 
2025-11-27 17:50:40.319156 | TASK [Gather network facts]
2025-11-27 17:50:40.664749 | controller | ok
2025-11-27 17:50:40.678432 | 
2025-11-27 17:50:40.678494 | TASK [include_role : start-zuul-console]
2025-11-27 17:50:40.696902 | controller | ok
2025-11-27 17:50:40.708874 | 
2025-11-27 17:50:40.708964 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-11-27 17:50:41.010558 | controller | ok
2025-11-27 17:50:41.019190 | 
2025-11-27 17:50:41.019266 | TASK [include_role : add-build-sshkey]
2025-11-27 17:50:41.037597 | controller | ok
2025-11-27 17:50:41.050611 | 
2025-11-27 17:50:41.050676 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-11-27 17:50:41.237129 | controller -> localhost | ok
2025-11-27 17:50:41.242398 | 
2025-11-27 17:50:41.242461 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-11-27 17:50:41.260546 | controller | ok
2025-11-27 17:50:41.273342 | controller | included: /var/lib/zuul/builds/2adaea711d3b4eaa99992765d3967e56/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-11-27 17:50:41.278593 | 
2025-11-27 17:50:41.278656 | TASK [add-build-sshkey : Create Temp SSH key]
2025-11-27 17:50:41.891362 | controller -> localhost | Generating public/private rsa key pair.
2025-11-27 17:50:41.891586 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/2adaea711d3b4eaa99992765d3967e56/work/2adaea711d3b4eaa99992765d3967e56_id_rsa.
2025-11-27 17:50:41.891621 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/2adaea711d3b4eaa99992765d3967e56/work/2adaea711d3b4eaa99992765d3967e56_id_rsa.pub.
2025-11-27 17:50:41.891647 | controller -> localhost | The key fingerprint is:
2025-11-27 17:50:41.891669 | controller -> localhost | SHA256:s4Lv0tuk9ikOmzolJndC65r5BrEIqkE2VlEVhvMlPcg zuul-build-sshkey
2025-11-27 17:50:41.891689 | controller -> localhost | The key's randomart image is:
2025-11-27 17:50:41.891708 | controller -> localhost | +---[RSA 3072]----+
2025-11-27 17:50:41.891727 | controller -> localhost | |   .o.+++        |
2025-11-27 17:50:41.891747 | controller -> localhost | |   . o.E +       |
2025-11-27 17:50:41.891766 | controller -> localhost | |  .   o o .      |
2025-11-27 17:50:41.891787 | controller -> localhost | |o=.    .         |
2025-11-27 17:50:41.891807 | controller -> localhost | |B+..    S        |
2025-11-27 17:50:41.891827 | controller -> localhost | |B * o.   o       |
2025-11-27 17:50:41.891845 | controller -> localhost | |.B =o.. o        |
2025-11-27 17:50:41.891863 | controller -> localhost | |.o+ .=+= .       |
2025-11-27 17:50:41.891881 | controller -> localhost | |++ooo=*++        |
2025-11-27 17:50:41.891899 | controller -> localhost | +----[SHA256]-----+
2025-11-27 17:50:41.891973 | controller -> localhost | ok: Runtime: 0:00:00.158841
2025-11-27 17:50:41.897727 | 
2025-11-27 17:50:41.897789 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-11-27 17:50:41.925585 | controller | ok
2025-11-27 17:50:41.934090 | controller | included: /var/lib/zuul/builds/2adaea711d3b4eaa99992765d3967e56/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-11-27 17:50:41.941276 | 
2025-11-27 17:50:41.941335 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-11-27 17:50:41.954165 | controller | skipping: Conditional result was False
2025-11-27 17:50:41.959059 | 
2025-11-27 17:50:41.959126 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-11-27 17:50:42.336348 | controller | changed
2025-11-27 17:50:42.341099 | 
2025-11-27 17:50:42.341161 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-11-27 17:50:42.521658 | controller | ok
2025-11-27 17:50:42.526632 | 
2025-11-27 17:50:42.526699 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-11-27 17:50:43.054018 | controller | changed
2025-11-27 17:50:43.069019 | 
2025-11-27 17:50:43.069126 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-11-27 17:50:43.603513 | controller | changed
2025-11-27 17:50:43.612279 | 
2025-11-27 17:50:43.612394 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-11-27 17:50:43.627043 | controller | skipping: Conditional result was False
2025-11-27 17:50:43.634014 | 
2025-11-27 17:50:43.634099 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-11-27 17:50:43.974296 | controller -> localhost | changed
2025-11-27 17:50:43.983834 | 
2025-11-27 17:50:43.983902 | TASK [add-build-sshkey : Add back temp key]
2025-11-27 17:50:44.291518 | controller -> localhost | Identity added: /var/lib/zuul/builds/2adaea711d3b4eaa99992765d3967e56/work/2adaea711d3b4eaa99992765d3967e56_id_rsa (zuul-build-sshkey)
2025-11-27 17:50:44.291718 | controller -> localhost | ok: Runtime: 0:00:00.008093
2025-11-27 17:50:44.298956 | 
2025-11-27 17:50:44.299020 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-11-27 17:50:44.601206 | controller | ok
2025-11-27 17:50:44.605779 | 
2025-11-27 17:50:44.605843 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-11-27 17:50:44.629341 | controller | skipping: Conditional result was False
2025-11-27 17:50:44.638182 | 
2025-11-27 17:50:44.638247 | TASK [include_role : validate-host]
2025-11-27 17:50:44.655882 | controller | ok
2025-11-27 17:50:44.676380 | 
2025-11-27 17:50:44.676447 | TASK [validate-host : Define zuul_info_dir fact]
2025-11-27 17:50:44.705090 | controller | ok
2025-11-27 17:50:44.709494 | 
2025-11-27 17:50:44.709569 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-11-27 17:50:44.930067 | controller -> localhost | ok
2025-11-27 17:50:44.935841 | 
2025-11-27 17:50:44.935930 | TASK [validate-host : Collect information about the host]
2025-11-27 17:50:45.592084 | controller | ok
2025-11-27 17:50:45.600799 | 
2025-11-27 17:50:45.600865 | TASK [validate-host : Sanitize hostname]
2025-11-27 17:50:45.669051 | controller | ok
2025-11-27 17:50:45.675041 | 
2025-11-27 17:50:45.675115 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-11-27 17:50:46.106673 | controller -> localhost | changed
2025-11-27 17:50:46.111788 | 
2025-11-27 17:50:46.111850 | TASK [validate-host : Collect information about zuul worker]
2025-11-27 17:50:46.424093 | controller | ok
2025-11-27 17:50:46.429279 | 
2025-11-27 17:50:46.429363 | TASK [validate-host : Write out all zuul information for each host]
2025-11-27 17:50:46.788131 | controller -> localhost | changed
2025-11-27 17:50:46.797751 | 
2025-11-27 17:50:46.797815 | TASK [include_role : prepare-workspace-openshift]
2025-11-27 17:50:46.811558 | controller | skipping: Conditional result was False
2025-11-27 17:50:46.816232 | 
2025-11-27 17:50:46.816294 | TASK [include_role : remove-zuul-sshkey]
2025-11-27 17:50:46.829985 | controller | skipping: Conditional result was False
2025-11-27 17:50:46.835701 | 
2025-11-27 17:50:46.835772 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-11-27 17:50:47.021316 | controller | ok: "logs"
2025-11-27 17:50:47.021555 | controller | ok: All items complete
2025-11-27 17:50:47.021590 | 
2025-11-27 17:50:47.175073 | controller | ok: "artifacts"
2025-11-27 17:50:47.343611 | controller | ok: "docs"
2025-11-27 17:50:47.352753 | 
2025-11-27 17:50:47.352819 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-11-27 17:50:47.541960 | controller | changed: "logs"
2025-11-27 17:50:47.703984 | controller | changed: "artifacts"
2025-11-27 17:50:47.863979 | controller | changed: "docs"
2025-11-27 17:50:47.885382 | 
2025-11-27 17:50:47.885432 | PLAY RECAP
2025-11-27 17:50:47.885475 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-11-27 17:50:47.885502 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-11-27 17:50:47.885533 | 
2025-11-27 17:50:47.959448 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-11-27 17:50:47.960216 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-11-27 17:50:48.490775 | 
2025-11-27 17:50:48.490949 | PLAY [all]
2025-11-27 17:50:48.509163 | 
2025-11-27 17:50:48.509233 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-11-27 17:50:48.558431 | controller | ok
2025-11-27 17:50:48.563400 | 
2025-11-27 17:50:48.563483 | TASK [mirror-info-fork : Create /etc/ci]
2025-11-27 17:50:48.886417 | controller | changed
2025-11-27 17:50:48.891981 | 
2025-11-27 17:50:48.892067 | TASK [mirror-info-fork : Install ci_mirror script]
2025-11-27 17:50:49.639850 | controller | changed
2025-11-27 17:50:49.649349 | 
2025-11-27 17:50:49.649425 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-11-27 17:50:49.994019 | controller | changed:
2025-11-27 17:50:49.994240 | controller | {
2025-11-27 17:50:49.994271 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-11-27 17:50:49.994302 | controller | }
2025-11-27 17:50:50.187496 | controller | changed:
2025-11-27 17:50:50.187561 | controller | {
2025-11-27 17:50:50.187591 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-11-27 17:50:50.187614 | controller | }
2025-11-27 17:50:50.381602 | controller | changed:
2025-11-27 17:50:50.381656 | controller | {
2025-11-27 17:50:50.381682 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com"
2025-11-27 17:50:50.381702 | controller | }
2025-11-27 17:50:50.576931 | controller | changed:
2025-11-27 17:50:50.576983 | controller | {
2025-11-27 17:50:50.577009 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-11-27 17:50:50.577030 | controller | }
2025-11-27 17:50:50.773842 | controller | changed:
2025-11-27 17:50:50.773933 | controller | {
2025-11-27 17:50:50.773965 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-11-27 17:50:50.773988 | controller | }
2025-11-27 17:50:50.970413 | controller | changed:
2025-11-27 17:50:50.970470 | controller | {
2025-11-27 17:50:50.970498 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-11-27 17:50:50.970519 | controller | }
2025-11-27 17:50:51.160994 | controller | changed:
2025-11-27 17:50:51.161071 | controller | {
2025-11-27 17:50:51.161098 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDLOQd4ZLtkZXQGY6UwAr/06ppWQK4fDO3HaqxPk98csyOCBXsliSKK39Bso828+5srIXiW7aI6aC9P5mwi4mUZlGPfJlQbfrcGvY+b/SocuvaGK+1RrHLoJCT52LBhwgrzlXio2jeksZeein8iaTrhsPrOAs7KggIL/rB9hEiB3NaOPWhhoCP4vlW6MEMExGcqB/1FVxXFBPnLkEyW0Lk7ycVflZl2ocRxbfjZi0+tI1Wlinp8PvSQSc/WVrAcDgKjc/mB4ODPOyYy3G8FHgfMsrXSDEyjBKgLKMsdCrAUcqJQWjkqXleXSYOV4q3pzL+9umK+q/e3P/bIoSFQzmJKTU1eDfuvPXmow9F5H54fii/Da7ezlMJ+wPGHJrRAkmzvMbALy7xwswLhZMkOGNtRcPqaKYRmIBKpw3o6bCTtcNUHOtOQnzwY8JzrM2eBWJBXAANYw+9/ho80JIiwhg29CFNpVBuHbql2YxJQNrnl90guN65rYNpDxdIluweyUf8= anbanerj@kaermorhen"
2025-11-27 17:50:51.161121 | controller | }
2025-11-27 17:50:51.362691 | controller | changed:
2025-11-27 17:50:51.362788 | controller | {
2025-11-27 17:50:51.362819 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQC3VwV8Im9kRm49lt3tM36hj4Zv27FxGo4C1Q/0jqhzFmHY7RHbmeRr8ObhwWoHjXSozKWg8FL5ER0z3hTwL0W6lez3sL7hUaCmSuZmG5Hnl3x4vTSxDI9JZ/Y65rtYiiWQo2fC5xJhU/4+0e5e/pseCm8cKRSu+SaxhO+sd6FDojA2x1BzOzKiQRDy/1zWGp/cZkxcEuB1wHI5LMzN03c67vmbu+fhZRAUO4dQkvcnj2LrhQtpa+ytvnSjr8icMDosf1OsbSffwZFyHB/hfWGAfe0eIeSA2XPraxiPknXxiPKx2MJsaUTYbsZcm3EjFdHBBMumw5rBI74zLrMRvCO9GwBEmGT4rFng1nP+yw5DB8sn2zqpOsPg1LYRwCPOUveC13P6pgsZZPh812e8v5EKnETct+5XI3dVpdw6CnNiLwAyVAF15DJvBGT/u1k0Myg/bQn+Gv9k2MSj6LvQmf6WbZu2Wgjm30z3FyCneBqTL7mLF19YXzeC0ufHz5pnO1E= dasm@fedora"
2025-11-27 17:50:51.362842 | controller | }
2025-11-27 17:50:51.555205 | controller | changed:
2025-11-27 17:50:51.555306 | controller | {
2025-11-27 17:50:51.555337 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-11-27 17:50:51.555359 | controller | }
2025-11-27 17:50:51.746362 | controller | changed:
2025-11-27 17:50:51.746453 | controller | {
2025-11-27 17:50:51.746479 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-11-27 17:50:51.746499 | controller | }
2025-11-27 17:50:51.935451 | controller | changed:
2025-11-27 17:50:51.935546 | controller | {
2025-11-27 17:50:51.935576 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-11-27 17:50:51.935599 | controller | }
2025-11-27 17:50:52.162147 | controller | changed:
2025-11-27 17:50:52.162290 | controller | {
2025-11-27 17:50:52.162338 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-11-27 17:50:52.162364 | controller | }
2025-11-27 17:50:52.333685 | controller | changed:
2025-11-27 17:50:52.333757 | controller | {
2025-11-27 17:50:52.333784 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-11-27 17:50:52.333804 | controller | }
2025-11-27 17:50:52.539450 | controller | changed:
2025-11-27 17:50:52.539538 | controller | {
2025-11-27 17:50:52.539566 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-11-27 17:50:52.539587 | controller | }
2025-11-27 17:50:52.742042 | controller | changed:
2025-11-27 17:50:52.742180 | controller | {
2025-11-27 17:50:52.742213 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-11-27 17:50:52.742235 | controller | }
2025-11-27 17:50:52.943487 | controller | changed:
2025-11-27 17:50:52.943578 | controller | {
2025-11-27 17:50:52.943604 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-11-27 17:50:52.943624 | controller | }
2025-11-27 17:50:53.140989 | controller | changed:
2025-11-27 17:50:53.141071 | controller | {
2025-11-27 17:50:53.141098 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQCfNtF7NvKl915TGsGGoseUb06Hj8L/S4toWf0hExeY+F00woL6NvBlJD0nDct+P5a22I4EhvoQCRQ8reaPCm1lybR3uiRIJsj+8zkVvLwby9LXzfZorlNG9ofjd00FEmB09uW/YvTl6Q9XwwwX6tInzIOv3TMqTHHGOL74ibbj8J/FJR0cFEyj0z4WQRvtkh32xAHl83gbuINryMt0sqRI+clj2381NKL55DRLQrVw0gsfqqxiHAnXg21qWmc4J+b9e9kiuAFQjcjwTVkwJCcg3xbPwC/qokYRby/Y5S40UUd7/jEARGXT7RZgpzTuDd1oZiCVrnrqJNPaMNdVv5MLeFdf1B7iIe5aa/fGouX7AO4SdKhZUdnJmCFAGvjC6S3JMZ2wAcUl+OHnssfmdj7XL50cLo27vjuzMtLAgSqi6N99m92WCF2s8J9aVzszX7Xz9OKZCeGsiVJp3/NdABKzSEAyM9xBD/5Vho894Sav+otpySHe3p6RUTgbB5Zu8VyZRZ/UtB3ueXxyo764yrc6qWIDqrehm84Xm9g+/jpIBzGPl07NUNJpdt/6Sgf9RIKXw/7XypO5yZfUcuFNGTxLfqjTNrtgLZNcjfav6sSdVXVcMPL//XNuRdKmVFaO76eV/oGMQGr1fGcCD+N+CpI7+Q+fCNB6VFWG4nZFuI/Iuw== averdagu@redhat.com"
2025-11-27 17:50:53.141120 | controller | }
2025-11-27 17:50:53.357713 | controller | changed:
2025-11-27 17:50:53.357789 | controller | {
2025-11-27 17:50:53.357816 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDq8l27xI+QlQVdS4djp9ogSoyrNE2+Ox6vKPdhSNL1J3PE5w+WCSvMz9A5gnNuH810zwbekEApbxTze/gLQJwBHA52CChfURpXrFaxY7ePXRElwKAL3mJfzBWY/c5jnNL9TCVmFJTGZkFZP3Nh+BMgZvL6xBkt3WKm6Uq18qzd9XeKcZusrA+O+uLv1fVeQnadY9RIqOCyeFYCzLWrUfTyE8x/XG0hAWIM7qpnF2cALQS2h9n4hW5ybiUN790H08wf9hFwEf5nxY9Z9dVkPFQiTSGKNBzmnCXU9skxS/xhpFjJ5duGSZdtAHe9O+nGZm9c67hxgtf8e5PDuqAdXEv2cf6e3VBAt+Bz8EKI3yosTj0oZHfwr42Yzb1l/SKy14Rggsrc9KAQlrGXan6+u2jcQqqx7l+SWmnpFiWTV9u5cWj2IgOhApOitmRBPYqk9rE2usfO0hLn/Pj/R/Nau4803e1/EikdLE7Ps95s9mX5jRDjAoUa2JwFF5RsVFyL910= ashigupt@ashigupt.remote.csb"
2025-11-27 17:50:53.357844 | controller | }
2025-11-27 17:50:53.559470 | controller | changed:
2025-11-27 17:50:53.559564 | controller | {
2025-11-27 17:50:53.559593 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-11-27 17:50:53.559613 | controller | }
2025-11-27 17:50:53.761514 | controller | changed:
2025-11-27 17:50:53.761666 | controller | {
2025-11-27 17:50:53.761700 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-11-27 17:50:53.761722 | controller | }
2025-11-27 17:50:53.958470 | controller | changed:
2025-11-27 17:50:53.958573 | controller | {
2025-11-27 17:50:53.958601 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-11-27 17:50:53.958622 | controller | }
2025-11-27 17:50:54.156254 | controller | changed:
2025-11-27 17:50:54.156546 | controller | {
2025-11-27 17:50:54.156585 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-11-27 17:50:54.156610 | controller | }
2025-11-27 17:50:54.353568 | controller | changed:
2025-11-27 17:50:54.353645 | controller | {
2025-11-27 17:50:54.353672 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQDZdI7t1cxYx65heVI24HTV4F7oQLW1zyfxHreL2TIJKxjyrUUKIFEUmTutcBlJRLNT2Eoix6x1sOw9YrchloCLcn//SGfTElr9mSc5jbjb7QXEU+zJMhtxyEJ1Po3CUGnj7ckiIXw7wcawZtrEOAQ9pH3ExYCJcEMiyNjRQZCxT3tPK+S4B95EWh5Fsrz9CkwpjNRPPH7LigCeQTM3Wc7r97utAslBUUvYceDSLA7rMgkitJE38b7rZBeYzsGQ8YYUBjTCtehqQXxCRjizbHWaaZkBU+N3zkKB6n/iCNGIO690NK7A/qb6msTijiz1PeuM8ThOsi9qXnbX5v0PoTpcFSojV7NHAQ71f0XXuS43FhZctT+Dcx44dT8Fb5vJu2cJGrk+qF8ZgJYNpRS7gPg0EG2EqjK7JMf9ULdjSu0r+KlqIAyLvtzT4eOnQipoKlb/WG5D/0ohKv7OMQ352ggfkBFIQsRXyyTCT98Ft9juqPuahi3CAQmP4H9dyE+7+Kz437PEtsxLmfm6naNmWi7Ee1DqWPwS8rEajsm4sNM4wW9gdBboJQtc0uZw0DfLj1I9r3Mc8Ol0jYtz0yNQDSzVLrGCaJlC311trU70tZ+ZkAVV6Mn8lOhSbj1cK0lvSr6ZK4dgqGl3I1eTZJJhbLNdg7UOVaiRx9543+C/p/As7w== brjackma@redhat.com"
2025-11-27 17:50:54.353696 | controller | }
2025-11-27 17:50:54.558347 | controller | changed:
2025-11-27 17:50:54.558440 | controller | {
2025-11-27 17:50:54.558469 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-11-27 17:50:54.558490 | controller | }
2025-11-27 17:50:54.765330 | controller | changed:
2025-11-27 17:50:54.765439 | controller | {
2025-11-27 17:50:54.765469 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-11-27 17:50:54.765492 | controller | }
2025-11-27 17:50:54.970442 | controller | changed:
2025-11-27 17:50:54.970554 | controller | {
2025-11-27 17:50:54.970583 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-11-27 17:50:54.970605 | controller | }
2025-11-27 17:50:54.988260 | 
2025-11-27 17:50:54.988329 | TASK [Set timezone to UTC]
2025-11-27 17:50:55.372799 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-11-27 17:50:55.377492 | 
2025-11-27 17:50:55.377574 | TASK [Create nodepool directory]
2025-11-27 17:50:55.580214 | controller | changed
2025-11-27 17:50:55.585427 | 
2025-11-27 17:50:55.585494 | TASK [Create nodepool sub_nodes file]
2025-11-27 17:50:56.135150 | controller | changed
2025-11-27 17:50:56.145542 | 
2025-11-27 17:50:56.145617 | TASK [Create nodepool sub_nodes_private file]
2025-11-27 17:50:56.694990 | controller | changed
2025-11-27 17:50:56.700438 | 
2025-11-27 17:50:56.700501 | LOOP [Populate nodepool sub_nodes file]
2025-11-27 17:50:56.729300 | 
2025-11-27 17:50:56.729423 | LOOP [Populate nodepool sub_nodes_private file]
2025-11-27 17:50:56.762837 | 
2025-11-27 17:50:56.763683 | TASK [Create nodepool primary file]
2025-11-27 17:50:56.798436 | controller | skipping: Conditional result was False
2025-11-27 17:50:56.803717 | 
2025-11-27 17:50:56.803803 | TASK [Create nodepool node_private for this node]
2025-11-27 17:50:57.338422 | controller | changed
2025-11-27 17:50:57.343697 | 
2025-11-27 17:50:57.343760 | LOOP [Copy ssh keys to nodepool directory]
2025-11-27 17:50:57.671106 | controller | ok: Item: id_rsa Runtime: 0:00:00.004033
2025-11-27 17:50:57.671358 | 
2025-11-27 17:50:57.830662 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.003813
2025-11-27 17:50:57.839711 | 
2025-11-27 17:50:57.839781 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-11-27 17:50:58.365464 | controller | changed
2025-11-27 17:50:58.370434 | 
2025-11-27 17:50:58.370497 | TASK [Validate sudoers config after edits]
2025-11-27 17:50:58.586190 | controller | /etc/sudoers: parsed OK
2025-11-27 17:50:58.586273 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-11-27 17:50:58.586285 | controller | /etc/sudoers.d/zuul: parsed OK
2025-11-27 17:50:58.586292 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-11-27 17:50:58.896713 | controller | ok: Runtime: 0:00:00.005338
2025-11-27 17:50:58.903058 | 
2025-11-27 17:50:58.903123 | TASK [Show the environment passed in to job shell scripts]
2025-11-27 17:50:59.107628 | controller | SHELL=/bin/bash
2025-11-27 17:50:59.107705 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-11-27 17:50:59.107756 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-11-27 17:50:59.107765 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/05/3505/807b4ef228d549ad33d40713ee2153047fbe59fd
2025-11-27 17:50:59.107771 | controller | PWD=/home/zuul
2025-11-27 17:50:59.107777 | controller | ZUUL_PIPELINE=github-check
2025-11-27 17:50:59.107782 | controller | LOGNAME=zuul
2025-11-27 17:50:59.107788 | controller | XDG_SESSION_TYPE=tty
2025-11-27 17:50:59.107793 | controller | _=/usr/bin/env
2025-11-27 17:50:59.107799 | controller | MOTD_SHOWN=pam
2025-11-27 17:50:59.107804 | controller | HOME=/home/zuul
2025-11-27 17:50:59.107809 | controller | LANG=en_US.UTF-8
2025-11-27 17:50:59.107814 | controller | SSH_CONNECTION=192.168.26.12 45152 192.168.26.245 22
2025-11-27 17:50:59.107820 | controller | TOX_TESTENV_PASSENV=ZUUL_UUID ZUUL_PROJECT ZUUL_SHORT_PROJECT_NAME ZUUL_PIPELINE ZUUL_VOTING WORKSPACE BUILD_TIMEOUT ZUUL_BRANCH ZUUL_CHANGES ZUUL_CHANGE_IDS ZUUL_CHANGE ZUUL_PATCHSET
2025-11-27 17:50:59.107828 | controller | ZUUL_CHANGE_IDS=3505,807b4ef228d549ad33d40713ee2153047fbe59fd
2025-11-27 17:50:59.107834 | controller | WORKSPACE=/home/zuul/workspace
2025-11-27 17:50:59.107839 | controller | XDG_SESSION_CLASS=user
2025-11-27 17:50:59.107844 | controller | SELINUX_ROLE_REQUESTED=
2025-11-27 17:50:59.107850 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-11-27 17:50:59.107855 | controller | USER=zuul
2025-11-27 17:50:59.107860 | controller | ZUUL_VOTING=True
2025-11-27 17:50:59.107865 | controller | BUILD_TIMEOUT=1800000
2025-11-27 17:50:59.107870 | controller | SELINUX_USE_CURRENT_RANGE=
2025-11-27 17:50:59.107875 | controller | SHLVL=1
2025-11-27 17:50:59.107881 | controller | ZUUL_PATCHSET=807b4ef228d549ad33d40713ee2153047fbe59fd
2025-11-27 17:50:59.107886 | controller | XDG_SESSION_ID=1
2025-11-27 17:50:59.107891 | controller | ZUUL_BRANCH=main
2025-11-27 17:50:59.107896 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-11-27 17:50:59.107902 | controller | SSH_CLIENT=192.168.26.12 45152 22
2025-11-27 17:50:59.107907 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-11-27 17:50:59.107912 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-11-27 17:50:59.107918 | controller | which_declare=declare -f
2025-11-27 17:50:59.107923 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2025-11-27 17:50:59.107929 | controller | SELINUX_LEVEL_REQUESTED=
2025-11-27 17:50:59.107934 | controller | ZUUL_CHANGE=3505
2025-11-27 17:50:59.107939 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-11-27 17:50:59.107944 | controller | ZUUL_UUID=2adaea711d3b4eaa99992765d3967e56
2025-11-27 17:50:59.107951 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-11-27 17:50:59.107957 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-11-27 17:50:59.107962 | controller | }
2025-11-27 17:50:59.426337 | controller | ok: Runtime: 0:00:00.005611
2025-11-27 17:50:59.431330 | 
2025-11-27 17:50:59.431392 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-11-27 17:50:59.445081 | controller | skipping: Conditional result was False
2025-11-27 17:50:59.450436 | 
2025-11-27 17:50:59.450503 | TASK [Symlink /home/zuul-worker/workspace]
2025-11-27 17:50:59.973164 | controller | skipping: Conditional result was False
2025-11-27 17:50:59.978363 | 
2025-11-27 17:50:59.978428 | TASK [Ensure legacy workspace directory]
2025-11-27 17:51:00.158715 | controller | changed
2025-11-27 17:51:00.175978 | 
2025-11-27 17:51:00.176033 | PLAY RECAP
2025-11-27 17:51:00.176075 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-11-27 17:51:00.176098 | 
2025-11-27 17:51:00.245955 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-11-27 17:51:00.246716 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-11-27 17:51:00.755398 | 
2025-11-27 17:51:00.755495 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-11-27 17:51:00.776133 | 
2025-11-27 17:51:00.776213 | TASK [Create zuul-output directory]
2025-11-27 17:51:01.079044 | controller | changed
2025-11-27 17:51:01.083872 | 
2025-11-27 17:51:01.083966 | TASK [Slurp Zuul inventory test]
2025-11-27 17:51:01.351646 | controller -> localhost | ok
2025-11-27 17:51:01.357777 | 
2025-11-27 17:51:01.357860 | TASK [Save zuul inventory]
2025-11-27 17:51:02.064175 | controller | changed
2025-11-27 17:51:02.069075 | 
2025-11-27 17:51:02.069142 | TASK [Save zuul vars without the change_message]
2025-11-27 17:51:02.623857 | controller | changed
2025-11-27 17:51:02.641146 | 
2025-11-27 17:51:02.641195 | PLAY RECAP
2025-11-27 17:51:02.641238 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-11-27 17:51:02.641261 | 
2025-11-27 17:51:02.704073 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-11-27 17:51:02.704830 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-11-27 17:51:03.247279 | 
2025-11-27 17:51:03.247376 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2025-11-27 17:51:03.267402 | 
2025-11-27 17:51:03.267473 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2025-11-27 17:51:03.285837 | controller | ok
2025-11-27 17:51:03.302442 | 
2025-11-27 17:51:03.302519 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-11-27 17:51:03.325744 | controller | skipping: Conditional result was False
2025-11-27 17:51:03.331334 | 
2025-11-27 17:51:03.331404 | TASK [mirror-info-fork : Create /etc/ci]
2025-11-27 17:51:03.632810 | controller | ok
2025-11-27 17:51:03.638187 | 
2025-11-27 17:51:03.638258 | TASK [mirror-info-fork : Install ci_mirror script]
2025-11-27 17:51:04.234079 | controller | ok
2025-11-27 17:51:04.243957 | 
2025-11-27 17:51:04.244021 | TASK [Prepare workspace]
2025-11-27 17:51:04.262143 | controller | ok
2025-11-27 17:51:04.279065 | 
2025-11-27 17:51:04.279133 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-11-27 17:51:04.570652 | controller | ok
2025-11-27 17:51:04.576458 | 
2025-11-27 17:51:04.576536 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-11-27 17:51:07.076605 | controller | Output suppressed because no_log was given
2025-11-27 17:51:07.085477 | 
2025-11-27 17:51:07.085554 | LOOP [Create zuul-output directory]
2025-11-27 17:51:07.274887 | controller | changed: "/home/zuul/ci-framework-data/logs"
2025-11-27 17:51:07.430021 | controller | ok: "/home/zuul/zuul-output/logs"
2025-11-27 17:51:07.440394 | 
2025-11-27 17:51:07.440457 | TASK [Install required packages]
2025-11-27 17:51:56.590294 | controller | changed
2025-11-27 17:51:56.595156 | 
2025-11-27 17:51:56.595217 | TASK [Install venv]
2025-11-27 17:52:56.653920 | controller | changed
2025-11-27 17:52:56.677391 | 
2025-11-27 17:52:56.677451 | PLAY RECAP
2025-11-27 17:52:56.677494 | controller | ok: 7 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-11-27 17:52:56.677517 | 
2025-11-27 17:52:56.742498 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-11-27 17:52:56.743373 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-11-27 17:52:57.250609 | 
2025-11-27 17:52:57.250708 | PLAY [Run ci/playbooks/molecule-test.yml]
2025-11-27 17:52:57.271290 | 
2025-11-27 17:52:57.271365 | TASK [Gather required facts]
2025-11-27 17:52:57.735925 | controller | ok
2025-11-27 17:52:57.741040 | 
2025-11-27 17:52:57.741115 | TASK [Load environment var if instructed to]
2025-11-27 17:52:57.765058 | controller | skipping: Conditional result was False
2025-11-27 17:52:57.770182 | 
2025-11-27 17:52:57.770246 | TASK [Ensure group_vars dir exists]
2025-11-27 17:52:58.072036 | controller | ok
2025-11-27 17:52:58.077745 | 
2025-11-27 17:52:58.077809 | TASK [Print related variables]
2025-11-27 17:52:58.106345 | controller | ok:
2025-11-27 17:52:58.106454 | controller | mol_config_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config_local.yml
2025-11-27 17:52:58.106484 | controller | roles_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/dnsmasq
2025-11-27 17:52:58.111373 | 
2025-11-27 17:52:58.111439 | TASK [Run molecule]
2025-11-27 17:52:59.028592 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2025-11-27 17:52:59.097927 | controller | INFO     Performing prerun with role_name_check=0...
2025-11-27 17:53:15.438308 | controller | WARNING  Another version of '@NAMESPACE@.@NAME@' 3.1.4 was found installed in /home/zuul/.cache/ansible-compat/e24347/collections/ansible_collections, only the first one will be used, 3.1.4 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-27 17:53:15.438732 | controller | WARNING  Another version of 'ansible.netcommon' 7.1.0 was found installed in /home/zuul/.cache/ansible-compat/e24347/collections/ansible_collections, only the first one will be used, 7.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-27 17:53:15.439196 | controller | WARNING  Another version of 'ansible.posix' 1.6.2 was found installed in /home/zuul/.cache/ansible-compat/e24347/collections/ansible_collections, only the first one will be used, 1.6.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-27 17:53:15.439645 | controller | WARNING  Another version of 'ansible.utils' 5.1.2 was found installed in /home/zuul/.cache/ansible-compat/e24347/collections/ansible_collections, only the first one will be used, 5.1.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-27 17:53:15.440124 | controller | WARNING  Another version of 'cifmw.general' 1.0.0+807b4ef2 was found installed in /home/zuul/.cache/ansible-compat/e24347/collections/ansible_collections, only the first one will be used, 1.0.0+807b4ef2 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-27 17:53:15.440561 | controller | WARNING  Another version of 'cisco.ios' 9.0.3 was found installed in /home/zuul/.cache/ansible-compat/e24347/collections/ansible_collections, only the first one will be used, 9.0.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-27 17:53:15.441023 | controller | WARNING  Another version of 'community.crypto' 2.22.3 was found installed in /home/zuul/.cache/ansible-compat/e24347/collections/ansible_collections, only the first one will be used, 2.22.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-27 17:53:15.441467 | controller | WARNING  Another version of 'community.general' 10.0.1 was found installed in /home/zuul/.cache/ansible-compat/e24347/collections/ansible_collections, only the first one will be used, 10.0.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-27 17:53:15.441925 | controller | WARNING  Another version of 'community.libvirt' 1.3.0 was found installed in /home/zuul/.cache/ansible-compat/e24347/collections/ansible_collections, only the first one will be used, 1.3.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-27 17:53:15.442373 | controller | WARNING  Another version of 'community.okd' 4.0.0 was found installed in /home/zuul/.cache/ansible-compat/e24347/collections/ansible_collections, only the first one will be used, 4.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-27 17:53:15.442823 | controller | WARNING  Another version of 'containers.podman' 1.16.2 was found installed in /home/zuul/.cache/ansible-compat/e24347/collections/ansible_collections, only the first one will be used, 1.16.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-27 17:53:15.443278 | controller | WARNING  Another version of 'junipernetworks.junos' 9.1.0 was found installed in /home/zuul/.cache/ansible-compat/e24347/collections/ansible_collections, only the first one will be used, 9.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-27 17:53:15.443732 | controller | WARNING  Another version of 'kubernetes.core' 5.0.0 was found installed in /home/zuul/.cache/ansible-compat/e24347/collections/ansible_collections, only the first one will be used, 5.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-27 17:53:15.444180 | controller | WARNING  Another version of 'mellanox.onyx' 1.0.0 was found installed in /home/zuul/.cache/ansible-compat/e24347/collections/ansible_collections, only the first one will be used, 1.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-27 17:53:15.444617 | controller | WARNING  Another version of 'openstack.config_template' 2.1.1 was found installed in /home/zuul/.cache/ansible-compat/e24347/collections/ansible_collections, only the first one will be used, 2.1.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-27 17:53:15.453988 | controller | INFO     Running default > prepare
2025-11-27 17:53:16.099931 | controller |
2025-11-27 17:53:16.100070 | controller | PLAY [Prepare] *****************************************************************
2025-11-27 17:53:16.100181 | controller |
2025-11-27 17:53:16.100298 | controller | TASK [Gathering Facts] *********************************************************
2025-11-27 17:53:16.100415 | controller | Thursday 27 November 2025  17:53:16 +0000 (0:00:00.012)       0:00:00.012 *****
2025-11-27 17:53:16.906437 | controller | ok: [instance]
2025-11-27 17:53:16.906539 | controller |
2025-11-27 17:53:16.906709 | controller | TASK [test_deps : Gather facts if they don't exist] ****************************
2025-11-27 17:53:16.906851 | controller | Thursday 27 November 2025  17:53:16 +0000 (0:00:00.807)       0:00:00.819 *****
2025-11-27 17:53:16.924205 | controller | skipping: [instance]
2025-11-27 17:53:16.924321 | controller |
2025-11-27 17:53:16.924456 | controller | TASK [test_deps : Gather variables for each operating system] ******************
2025-11-27 17:53:16.924587 | controller | Thursday 27 November 2025  17:53:16 +0000 (0:00:00.017)       0:00:00.837 *****
2025-11-27 17:53:16.965111 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/test_deps/vars/centos-9.yml)
2025-11-27 17:53:16.965204 | controller |
2025-11-27 17:53:16.965308 | controller | TASK [test_deps : Detect mirrors file] *****************************************
2025-11-27 17:53:16.965407 | controller | Thursday 27 November 2025  17:53:16 +0000 (0:00:00.040)       0:00:00.878 *****
2025-11-27 17:53:17.238934 | controller | ok: [instance]
2025-11-27 17:53:17.239043 | controller |
2025-11-27 17:53:17.239156 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] ***********
2025-11-27 17:53:17.239263 | controller | Thursday 27 November 2025  17:53:17 +0000 (0:00:00.273)       0:00:01.152 *****
2025-11-27 17:53:17.538771 | controller | ok: [instance]
2025-11-27 17:53:17.538841 | controller |
2025-11-27 17:53:17.538945 | controller | TASK [test_deps : Disable ubi 9 repos] *****************************************
2025-11-27 17:53:17.539067 | controller | Thursday 27 November 2025  17:53:17 +0000 (0:00:00.299)       0:00:01.452 *****
2025-11-27 17:53:18.211523 | controller | ok: [instance] => (item=ubi-9-appstream)
2025-11-27 17:53:18.211674 | controller | ok: [instance] => (item=ubi-9-baseos)
2025-11-27 17:53:18.211821 | controller | ok: [instance] => (item=ubi-9-codeready-builder)
2025-11-27 17:53:18.211945 | controller |
2025-11-27 17:53:18.212091 | controller | TASK [Deploy repo-setup] *******************************************************
2025-11-27 17:53:18.212236 | controller | Thursday 27 November 2025  17:53:18 +0000 (0:00:00.672)       0:00:02.125 *****
2025-11-27 17:53:18.268382 | controller |
2025-11-27 17:53:18.268512 | controller | TASK [repo_setup : Ensure directories are present] *****************************
2025-11-27 17:53:18.268677 | controller | Thursday 27 November 2025  17:53:18 +0000 (0:00:00.057)       0:00:02.182 *****
2025-11-27 17:53:18.797566 | controller | changed: [instance] => (item=tmp)
2025-11-27 17:53:18.797660 | controller | changed: [instance] => (item=artifacts/repositories)
2025-11-27 17:53:18.797773 | controller | changed: [instance] => (item=venv/repo_setup)
2025-11-27 17:53:18.797867 | controller |
2025-11-27 17:53:18.797976 | controller | TASK [repo_setup : Make sure git-core package is installed] ********************
2025-11-27 17:53:18.798083 | controller | Thursday 27 November 2025  17:53:18 +0000 (0:00:00.528)       0:00:02.711 *****
2025-11-27 17:53:19.546486 | controller | ok: [instance]
2025-11-27 17:53:19.546644 | controller |
2025-11-27 17:53:19.546680 | controller | TASK [repo_setup : Get repo-setup repository] **********************************
2025-11-27 17:53:19.546836 | controller | Thursday 27 November 2025  17:53:19 +0000 (0:00:00.748)       0:00:03.460 *****
2025-11-27 17:53:20.612744 | controller | changed: [instance]
2025-11-27 17:53:20.612776 | controller |
2025-11-27 17:53:20.612792 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************
2025-11-27 17:53:20.612806 | controller | Thursday 27 November 2025  17:53:20 +0000 (0:00:01.065)       0:00:04.525 *****
2025-11-27 17:53:28.438081 | controller | changed: [instance]
2025-11-27 17:53:29.134525 | controller |
2025-11-27 17:53:29.134560 | controller | TASK [repo_setup : Install repo-setup package] *********************************
2025-11-27 17:53:29.134568 | controller | Thursday 27 November 2025  17:53:28 +0000 (0:00:07.825)       0:00:12.351 *****
2025-11-27 17:53:29.134577 | controller | changed: [instance]
2025-11-27 17:53:29.156297 | controller |
2025-11-27 17:53:29.156315 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
2025-11-27 17:53:29.156323 | controller | Thursday 27 November 2025  17:53:29 +0000 (0:00:00.696)       0:00:13.047 *****
2025-11-27 17:53:29.156332 | controller | skipping: [instance]
2025-11-27 17:53:29.952865 | controller |
2025-11-27 17:53:29.952887 | controller | TASK [repo_setup : Run repo-setup] *********************************************
2025-11-27 17:53:29.952894 | controller | Thursday 27 November 2025  17:53:29 +0000 (0:00:00.022)       0:00:13.069 *****
2025-11-27 17:53:29.952904 | controller | changed: [instance]
2025-11-27 17:53:29.989230 | controller |
2025-11-27 17:53:29.989249 | controller | TASK [repo_setup : Get component repo] *****************************************
2025-11-27 17:53:29.989257 | controller | Thursday 27 November 2025  17:53:29 +0000 (0:00:00.796)       0:00:13.866 *****
2025-11-27 17:53:29.989265 | controller | skipping: [instance]
2025-11-27 17:53:30.024931 | controller |
2025-11-27 17:53:30.024949 | controller | TASK [repo_setup : Rename component repo] **************************************
2025-11-27 17:53:30.024957 | controller | Thursday 27 November 2025  17:53:29 +0000 (0:00:00.036)       0:00:13.902 *****
2025-11-27 17:53:30.024966 | controller | skipping: [instance]
2025-11-27 17:53:30.061270 | controller |
2025-11-27 17:53:30.061288 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
2025-11-27 17:53:30.061295 | controller | Thursday 27 November 2025  17:53:30 +0000 (0:00:00.035)       0:00:13.938 *****
2025-11-27 17:53:30.061305 | controller | skipping: [instance]
2025-11-27 17:53:30.600576 | controller |
2025-11-27 17:53:30.600615 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************
2025-11-27 17:53:30.600625 | controller | Thursday 27 November 2025  17:53:30 +0000 (0:00:00.036)       0:00:13.974 *****
2025-11-27 17:53:30.600634 | controller | changed: [instance]
2025-11-27 17:53:31.039678 | controller |
2025-11-27 17:53:31.039708 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
2025-11-27 17:53:31.039716 | controller | Thursday 27 November 2025  17:53:30 +0000 (0:00:00.539)       0:00:14.513 *****
2025-11-27 17:53:31.039726 | controller | changed: [instance]
2025-11-27 17:53:31.039745 | controller |
2025-11-27 17:53:31.039971 | controller | TASK [repo_setup : Dump current-podified hash] *********************************
2025-11-27 17:53:31.070120 | controller | Thursday 27 November 2025  17:53:31 +0000 (0:00:00.439)       0:00:14.953 *****
2025-11-27 17:53:31.070142 | controller | skipping: [instance]
2025-11-27 17:53:31.098875 | controller |
2025-11-27 17:53:31.098893 | controller | TASK [repo_setup : Slurp current podified hash] ********************************
2025-11-27 17:53:31.098900 | controller | Thursday 27 November 2025  17:53:31 +0000 (0:00:00.030)       0:00:14.983 *****
2025-11-27 17:53:31.098909 | controller | skipping: [instance]
2025-11-27 17:53:31.127157 | controller |
2025-11-27 17:53:31.127173 | controller | TASK [repo_setup : Update the value of full_hash] ******************************
2025-11-27 17:53:31.127180 | controller | Thursday 27 November 2025  17:53:31 +0000 (0:00:00.028)       0:00:15.012 *****
2025-11-27 17:53:31.127191 | controller | skipping: [instance]
2025-11-27 17:53:31.164677 | controller |
2025-11-27 17:53:31.164704 | controller | TASK [repo_setup : Export hashes facts for further use] ************************
2025-11-27 17:53:31.164712 | controller | Thursday 27 November 2025  17:53:31 +0000 (0:00:00.028)       0:00:15.040 *****
2025-11-27 17:53:31.164722 | controller | ok: [instance]
2025-11-27 17:53:31.164752 | controller |
2025-11-27 17:53:31.165022 | controller | TASK [repo_setup : Create download directory] **********************************
2025-11-27 17:53:31.192680 | controller | Thursday 27 November 2025  17:53:31 +0000 (0:00:00.037)       0:00:15.078 *****
2025-11-27 17:53:31.192708 | controller | skipping: [instance]
2025-11-27 17:53:31.192771 | controller |
2025-11-27 17:53:31.192791 | controller | TASK [repo_setup : Print the URL to request] ***********************************
2025-11-27 17:53:31.192802 | controller | Thursday 27 November 2025  17:53:31 +0000 (0:00:00.027)       0:00:15.106 *****
2025-11-27 17:53:31.221480 | controller | skipping: [instance]
2025-11-27 17:53:31.270792 | controller |
2025-11-27 17:53:31.270814 | controller | TASK [Download the RPM] ********************************************************
2025-11-27 17:53:31.270824 | controller | Thursday 27 November 2025  17:53:31 +0000 (0:00:00.028)       0:00:15.134 *****
2025-11-27 17:53:31.270835 | controller | skipping: [instance]
2025-11-27 17:53:31.297540 | controller |
2025-11-27 17:53:31.297563 | controller | TASK [repo_setup : Install RHOS Release tool] **********************************
2025-11-27 17:53:31.297572 | controller | Thursday 27 November 2025  17:53:31 +0000 (0:00:00.049)       0:00:15.184 *****
2025-11-27 17:53:31.297583 | controller | skipping: [instance]
2025-11-27 17:53:31.322724 | controller |
2025-11-27 17:53:31.322758 | controller | TASK [repo_setup : Get rhos-release tool version] ******************************
2025-11-27 17:53:31.322767 | controller | Thursday 27 November 2025  17:53:31 +0000 (0:00:00.026)       0:00:15.210 *****
2025-11-27 17:53:31.322780 | controller | skipping: [instance]
2025-11-27 17:53:31.322948 | controller |
2025-11-27 17:53:31.322970 | controller | TASK [repo_setup : Print rhos-release tool version] ****************************
2025-11-27 17:53:31.322994 | controller | Thursday 27 November 2025  17:53:31 +0000 (0:00:00.025)       0:00:15.236 *****
2025-11-27 17:53:31.351169 | controller | skipping: [instance]
2025-11-27 17:53:31.377222 | controller |
2025-11-27 17:53:31.377239 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
2025-11-27 17:53:31.377246 | controller | Thursday 27 November 2025  17:53:31 +0000 (0:00:00.028)       0:00:15.264 *****
2025-11-27 17:53:31.377258 | controller | skipping: [instance]
2025-11-27 17:53:31.555991 | controller |
2025-11-27 17:53:31.556011 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
2025-11-27 17:53:31.556019 | controller | Thursday 27 November 2025  17:53:31 +0000 (0:00:00.026)       0:00:15.290 *****
2025-11-27 17:53:31.556028 | controller | ok: [instance]
2025-11-27 17:53:31.766360 | controller |
2025-11-27 17:53:31.766380 | controller | TASK [repo_setup : Use RDO proxy mirrors] **************************************
2025-11-27 17:53:31.766387 | controller | Thursday 27 November 2025  17:53:31 +0000 (0:00:00.178)       0:00:15.469 *****
2025-11-27 17:53:31.766396 | controller | changed: [instance]
2025-11-27 17:53:31.985501 | controller |
2025-11-27 17:53:31.985523 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
2025-11-27 17:53:31.985531 | controller | Thursday 27 November 2025  17:53:31 +0000 (0:00:00.210)       0:00:15.679 *****
2025-11-27 17:53:31.985540 | controller | changed: [instance]
2025-11-27 17:53:32.007101 | controller |
2025-11-27 17:53:32.007124 | controller | TASK [repo_setup : Check for gating.repo file on content provider] *************
2025-11-27 17:53:32.007133 | controller | Thursday 27 November 2025  17:53:31 +0000 (0:00:00.219)       0:00:15.898 *****
2025-11-27 17:53:32.007143 | controller | skipping: [instance]
2025-11-27 17:53:32.027940 | controller |
2025-11-27 17:53:32.027955 | controller | TASK [repo_setup : Populate gating repo from content provider ip] **************
2025-11-27 17:53:32.027962 | controller | Thursday 27 November 2025  17:53:32 +0000 (0:00:00.021)       0:00:15.920 *****
2025-11-27 17:53:32.027970 | controller | skipping: [instance]
2025-11-27 17:53:32.048699 | controller |
2025-11-27 17:53:32.048733 | controller | TASK [repo_setup : Check for DLRN repo at the destination] *********************
2025-11-27 17:53:32.048743 | controller | Thursday 27 November 2025  17:53:32 +0000 (0:00:00.020)       0:00:15.941 *****
2025-11-27 17:53:32.048755 | controller | skipping: [instance]
2025-11-27 17:53:32.048785 | controller |
2025-11-27 17:53:32.049080 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
2025-11-27 17:53:32.069629 | controller | Thursday 27 November 2025  17:53:32 +0000 (0:00:00.020)       0:00:15.962 *****
2025-11-27 17:53:32.069650 | controller | skipping: [instance]
2025-11-27 17:53:32.090281 | controller |
2025-11-27 17:53:32.090298 | controller | TASK [repo_setup : Check for DLRN component repo] ******************************
2025-11-27 17:53:32.090305 | controller | Thursday 27 November 2025  17:53:32 +0000 (0:00:00.020)       0:00:15.983 *****
2025-11-27 17:53:32.090319 | controller | skipping: [instance]
2025-11-27 17:53:32.111685 | controller |
2025-11-27 17:53:32.111710 | controller | TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] ***
2025-11-27 17:53:32.111727 | controller | Thursday 27 November 2025  17:53:32 +0000 (0:00:00.020)       0:00:16.003 *****
2025-11-27 17:53:32.111739 | controller | skipping: [instance]
2025-11-27 17:53:32.111767 | controller |
2025-11-27 17:53:32.111777 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
2025-11-27 17:53:32.111894 | controller | Thursday 27 November 2025  17:53:32 +0000 (0:00:00.021)       0:00:16.025 *****
2025-11-27 17:53:32.128175 | controller | skipping: [instance]
2025-11-27 17:53:32.156521 | controller |
2025-11-27 17:53:32.156538 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
2025-11-27 17:53:32.156545 | controller | Thursday 27 November 2025  17:53:32 +0000 (0:00:00.016)       0:00:16.041 *****
2025-11-27 17:53:32.156553 | controller | skipping: [instance]
2025-11-27 17:53:32.173494 | controller |
2025-11-27 17:53:32.173513 | controller | TASK [repo_setup : Cleanup existing metadata] **********************************
2025-11-27 17:53:32.173520 | controller | Thursday 27 November 2025  17:53:32 +0000 (0:00:00.028)       0:00:16.070 *****
2025-11-27 17:53:32.173529 | controller | skipping: [instance]
2025-11-27 17:53:32.193136 | controller |
2025-11-27 17:53:32.193152 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
2025-11-27 17:53:32.193159 | controller | Thursday 27 November 2025  17:53:32 +0000 (0:00:00.016)       0:00:16.086 *****
2025-11-27 17:53:32.193167 | controller | skipping: [instance]
2025-11-27 17:53:32.220449 | controller |
2025-11-27 17:53:32.220464 | controller | TASK [test_deps : Look for redhat-release rpm] *********************************
2025-11-27 17:53:32.220470 | controller | Thursday 27 November 2025  17:53:32 +0000 (0:00:00.019)       0:00:16.106 *****
2025-11-27 17:53:32.220478 | controller | skipping: [instance]
2025-11-27 17:53:32.248482 | controller |
2025-11-27 17:53:32.248498 | controller | TASK [test_deps : Remove redhat-release] ***************************************
2025-11-27 17:53:32.248505 | controller | Thursday 27 November 2025  17:53:32 +0000 (0:00:00.027)       0:00:16.134 *****
2025-11-27 17:53:32.248514 | controller | skipping: [instance]
2025-11-27 17:53:32.275496 | controller |
2025-11-27 17:53:32.275512 | controller | TASK [test_deps : Install centos-stream-release] *******************************
2025-11-27 17:53:32.275518 | controller | Thursday 27 November 2025  17:53:32 +0000 (0:00:00.028)       0:00:16.162 *****
2025-11-27 17:53:32.275526 | controller | skipping: [instance]
2025-11-27 17:53:32.303039 | controller |
2025-11-27 17:53:32.303054 | controller | TASK [test_deps : Install additional packages] *********************************
2025-11-27 17:53:32.303060 | controller | Thursday 27 November 2025  17:53:32 +0000 (0:00:00.027)       0:00:16.189 *****
2025-11-27 17:53:32.303068 | controller | skipping: [instance]
2025-11-27 17:54:20.591818 | controller |
2025-11-27 17:54:20.591850 | controller | TASK [test_deps : Install selinux python libs] *********************************
2025-11-27 17:54:20.591858 | controller | Thursday 27 November 2025  17:53:32 +0000 (0:00:00.027)       0:00:16.216 *****
2025-11-27 17:54:20.591868 | controller | ok: [instance]
2025-11-27 17:54:21.796519 | controller |
2025-11-27 17:54:21.796548 | controller | TASK [test_deps : Install python yaml libs] ************************************
2025-11-27 17:54:21.796556 | controller | Thursday 27 November 2025  17:54:20 +0000 (0:00:48.288)       0:01:04.505 *****
2025-11-27 17:54:21.796566 | controller | ok: [instance]
2025-11-27 17:54:21.827749 | controller |
2025-11-27 17:54:21.827771 | controller | TASK [test_deps : Install extra packages] **************************************
2025-11-27 17:54:21.827780 | controller | Thursday 27 November 2025  17:54:21 +0000 (0:00:01.204)       0:01:05.709 *****
2025-11-27 17:54:21.827792 | controller | skipping: [instance]
2025-11-27 17:54:22.201936 | controller |
2025-11-27 17:54:22.201959 | controller | TASK [Create 1st dummy interface with needed IPs] ******************************
2025-11-27 17:54:22.201966 | controller | Thursday 27 November 2025  17:54:21 +0000 (0:00:00.031)       0:01:05.741 *****
2025-11-27 17:54:22.201976 | controller | changed: [instance]
2025-11-27 17:54:22.461496 | controller |
2025-11-27 17:54:22.461522 | controller | TASK [Create 2nd dummy interface with needed IPs] ******************************
2025-11-27 17:54:22.461530 | controller | Thursday 27 November 2025  17:54:22 +0000 (0:00:00.374)       0:01:06.115 *****
2025-11-27 17:54:22.461540 | controller | changed: [instance]
2025-11-27 17:54:22.740656 | controller |
2025-11-27 17:54:22.740684 | controller | TASK [Create 3nd dummy interface with needed IPs] ******************************
2025-11-27 17:54:22.740692 | controller | Thursday 27 November 2025  17:54:22 +0000 (0:00:00.259)       0:01:06.374 *****
2025-11-27 17:54:22.740702 | controller | changed: [instance]
2025-11-27 17:54:22.741646 | controller |
2025-11-27 17:54:22.741661 | controller | PLAY RECAP *********************************************************************
2025-11-27 17:54:22.741668 | controller | instance                   : ok=22   changed=12   unreachable=0    failed=0    skipped=30   rescued=0    ignored=0
2025-11-27 17:54:22.741674 | controller |
2025-11-27 17:54:22.741679 | controller | Thursday 27 November 2025  17:54:22 +0000 (0:00:00.278)       0:01:06.653 *****
2025-11-27 17:54:22.741693 | controller | ===============================================================================
2025-11-27 17:54:22.741698 | controller | test_deps : Install selinux python libs -------------------------------- 48.29s
2025-11-27 17:54:22.741703 | controller | repo_setup : Initialize python venv and install requirements ------------ 7.83s
2025-11-27 17:54:22.741709 | controller | test_deps : Install python yaml libs ------------------------------------ 1.20s
2025-11-27 17:54:22.741714 | controller | repo_setup : Get repo-setup repository ---------------------------------- 1.07s
2025-11-27 17:54:22.741719 | controller | Gathering Facts --------------------------------------------------------- 0.81s
2025-11-27 17:54:22.741728 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.80s
2025-11-27 17:54:22.742343 | controller | repo_setup : Make sure git-core package is installed -------------------- 0.75s
2025-11-27 17:54:22.742355 | controller | repo_setup : Install repo-setup package --------------------------------- 0.70s
2025-11-27 17:54:22.742361 | controller | test_deps : Disable ubi 9 repos ----------------------------------------- 0.67s
2025-11-27 17:54:22.742367 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.54s
2025-11-27 17:54:22.742372 | controller | repo_setup : Ensure directories are present ----------------------------- 0.53s
2025-11-27 17:54:22.742377 | controller | repo_setup : Dump full hash in delorean.repo.md5 file ------------------- 0.44s
2025-11-27 17:54:22.742383 | controller | Create 1st dummy interface with needed IPs ------------------------------ 0.37s
2025-11-27 17:54:22.742388 | controller | test_deps : Disable ubi host subscription-manager integration ----------- 0.30s
2025-11-27 17:54:22.742396 | controller | Create 3nd dummy interface with needed IPs ------------------------------ 0.28s
2025-11-27 17:54:22.799510 | controller | test_deps : Detect mirrors file ----------------------------------------- 0.27s
2025-11-27 17:54:22.799530 | controller | Create 2nd dummy interface with needed IPs ------------------------------ 0.26s
2025-11-27 17:54:22.799538 | controller | repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist) --- 0.22s
2025-11-27 17:54:22.799544 | controller | repo_setup : Use RDO proxy mirrors -------------------------------------- 0.21s
2025-11-27 17:54:22.799549 | controller | repo_setup : Check for /etc/ci/mirror_info.sh --------------------------- 0.18s
2025-11-27 17:54:22.799558 | controller | INFO     Running default > converge
2025-11-27 17:54:23.230314 | controller |
2025-11-27 17:54:24.052684 | controller | PLAY [Converge] ****************************************************************
2025-11-27 17:54:24.052718 | controller |
2025-11-27 17:54:24.052728 | controller | TASK [Gathering Facts] *********************************************************
2025-11-27 17:54:24.052735 | controller | Thursday 27 November 2025  17:54:23 +0000 (0:00:00.017)       0:00:00.017 *****
2025-11-27 17:54:24.052747 | controller | ok: [instance]
2025-11-27 17:54:24.053121 | controller |
2025-11-27 17:54:27.888463 | controller | TASK [dnsmasq : Install needed packages] ***************************************
2025-11-27 17:54:27.888492 | controller | Thursday 27 November 2025  17:54:24 +0000 (0:00:00.822)       0:00:00.840 *****
2025-11-27 17:54:27.888504 | controller | changed: [instance]
2025-11-27 17:54:31.260543 | controller |
2025-11-27 17:54:31.260572 | controller | TASK [dnsmasq : Ensure SELinux knows about the new files and directories] ******
2025-11-27 17:54:31.260581 | controller | Thursday 27 November 2025  17:54:27 +0000 (0:00:03.835)       0:00:04.676 *****
2025-11-27 17:54:31.260590 | controller | changed: [instance] => (item={'target': '/etc/cifmw-dnsmasq.conf', 'setype': 'dnsmasq_etc_t'})
2025-11-27 17:54:31.560390 | controller | changed: [instance] => (item={'target': '/etc/cifmw-dnsmasq.d(/.*)?', 'setype': 'dnsmasq_etc_t'})
2025-11-27 17:54:31.560418 | controller | changed: [instance] => (item={'target': '/var/run/cifmw-dnsmasq.pid', 'setype': 'dnsmasq_var_run_t'})
2025-11-27 17:54:31.560426 | controller |
2025-11-27 17:54:31.560432 | controller | TASK [dnsmasq : Manage configuration directory] ********************************
2025-11-27 17:54:31.560438 | controller | Thursday 27 November 2025  17:54:31 +0000 (0:00:03.371)       0:00:08.048 *****
2025-11-27 17:54:31.560448 | controller | changed: [instance]
2025-11-27 17:54:32.110995 | controller |
2025-11-27 17:54:32.111028 | controller | TASK [dnsmasq : Manage systemd unit file] **************************************
2025-11-27 17:54:32.111037 | controller | Thursday 27 November 2025  17:54:31 +0000 (0:00:00.300)       0:00:08.348 *****
2025-11-27 17:54:32.111047 | controller | changed: [instance]
2025-11-27 17:54:32.483751 | controller |
2025-11-27 17:54:32.483779 | controller | TASK [dnsmasq : Manage base configuration file] ********************************
2025-11-27 17:54:32.483789 | controller | Thursday 27 November 2025  17:54:32 +0000 (0:00:00.550)       0:00:08.898 *****
2025-11-27 17:54:32.483798 | controller | changed: [instance]
2025-11-27 17:54:32.505351 | controller |
2025-11-27 17:54:32.505373 | controller | TASK [dnsmasq : Render listener configuration] *********************************
2025-11-27 17:54:32.505380 | controller | Thursday 27 November 2025  17:54:32 +0000 (0:00:00.372)       0:00:09.271 *****
2025-11-27 17:54:32.505390 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/dnsmasq/tasks/listener.yml for instance
2025-11-27 17:54:32.882816 | controller |
2025-11-27 17:54:32.882844 | controller | TASK [dnsmasq : Create listener configuration file] ****************************
2025-11-27 17:54:32.882852 | controller | Thursday 27 November 2025  17:54:32 +0000 (0:00:00.021)       0:00:09.293 *****
2025-11-27 17:54:32.882863 | controller | changed: [instance]
2025-11-27 17:54:32.900580 | controller |
2025-11-27 17:54:32.900618 | controller | TASK [dnsmasq : Remove listener configuration file] ****************************
2025-11-27 17:54:32.900629 | controller | Thursday 27 November 2025  17:54:32 +0000 (0:00:00.377)       0:00:09.670 *****
2025-11-27 17:54:32.900639 | controller | skipping: [instance]
2025-11-27 17:54:32.924471 | controller |
2025-11-27 17:54:32.924490 | controller | TASK [dnsmasq : Render dns configuration] **************************************
2025-11-27 17:54:32.924498 | controller | Thursday 27 November 2025  17:54:32 +0000 (0:00:00.017)       0:00:09.688 *****
2025-11-27 17:54:32.924507 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/dnsmasq/tasks/dns.yml for instance
2025-11-27 17:54:33.297708 | controller |
2025-11-27 17:54:33.297745 | controller | TASK [dnsmasq : Create dns configuration file] *********************************
2025-11-27 17:54:33.297755 | controller | Thursday 27 November 2025  17:54:32 +0000 (0:00:00.023)       0:00:09.712 *****
2025-11-27 17:54:33.297768 | controller | changed: [instance]
2025-11-27 17:54:33.297797 | controller |
2025-11-27 17:54:33.297807 | controller | TASK [dnsmasq : Remove dns configuration file] *********************************
2025-11-27 17:54:33.297960 | controller | Thursday 27 November 2025  17:54:33 +0000 (0:00:00.373)       0:00:10.085 *****
2025-11-27 17:54:33.315421 | controller | skipping: [instance]
2025-11-27 17:54:33.340068 | controller |
2025-11-27 17:54:33.340100 | controller | TASK [dnsmasq : Add localhost addresses from defined dnsmasq listen addresses to loopback interface] ***
2025-11-27 17:54:33.340109 | controller | Thursday 27 November 2025  17:54:33 +0000 (0:00:00.017)       0:00:10.103 *****
2025-11-27 17:54:33.340118 | controller | skipping: [instance] => (item=192.168.253.9)
2025-11-27 17:54:34.169042 | controller | skipping: [instance] => (item=192.168.254.9)
2025-11-27 17:54:34.169073 | controller | skipping: [instance]
2025-11-27 17:54:34.169090 | controller |
2025-11-27 17:54:34.169097 | controller | TASK [dnsmasq : Enable and start service] **************************************
2025-11-27 17:54:34.169103 | controller | Thursday 27 November 2025  17:54:33 +0000 (0:00:00.024)       0:00:10.127 *****
2025-11-27 17:54:34.169113 | controller | changed: [instance]
2025-11-27 17:54:34.186617 | controller |
2025-11-27 17:54:34.186639 | controller | TASK [dnsmasq : Stop service] **************************************************
2025-11-27 17:54:34.186654 | controller | Thursday 27 November 2025  17:54:34 +0000 (0:00:00.828)       0:00:10.956 *****
2025-11-27 17:54:34.186664 | controller | skipping: [instance]
2025-11-27 17:54:34.202676 | controller |
2025-11-27 17:54:34.202700 | controller | TASK [dnsmasq : Remove unit file] **********************************************
2025-11-27 17:54:34.202709 | controller | Thursday 27 November 2025  17:54:34 +0000 (0:00:00.017)       0:00:10.974 *****
2025-11-27 17:54:34.202721 | controller | skipping: [instance]
2025-11-27 17:54:34.202748 | controller |
2025-11-27 17:54:34.202758 | controller | TASK [dnsmasq : Remove main configuration files] *******************************
2025-11-27 17:54:34.202840 | controller | Thursday 27 November 2025  17:54:34 +0000 (0:00:00.016)       0:00:10.990 *****
2025-11-27 17:54:34.228680 | controller | skipping: [instance] => (item=/etc/cifmw-dnsmasq.conf)
2025-11-27 17:54:34.228715 | controller | skipping: [instance] => (item=/etc/cifmw-dnsmasq.d)
2025-11-27 17:54:34.228723 | controller | skipping: [instance]
2025-11-27 17:54:34.228730 | controller |
2025-11-27 17:54:34.228739 | controller | TASK [Create network] **********************************************************
2025-11-27 17:54:34.228838 | controller | Thursday 27 November 2025  17:54:34 +0000 (0:00:00.025)       0:00:11.016 *****
2025-11-27 17:54:34.255150 | controller |
2025-11-27 17:54:34.280724 | controller | TASK [dnsmasq : Assert mandatory parameter] ************************************
2025-11-27 17:54:34.280747 | controller | Thursday 27 November 2025  17:54:34 +0000 (0:00:00.026)       0:00:11.043 *****
2025-11-27 17:54:34.280761 | controller | ok: [instance] => changed=false
2025-11-27 17:54:34.312693 | controller |   msg: All assertions passed
2025-11-27 17:54:34.312714 | controller |
2025-11-27 17:54:34.312722 | controller | TASK [dnsmasq : Assert mandatory parameters for new network] *******************
2025-11-27 17:54:34.312728 | controller | Thursday 27 November 2025  17:54:34 +0000 (0:00:00.025)       0:00:11.068 *****
2025-11-27 17:54:34.312743 | controller | ok: [instance] => changed=false
2025-11-27 17:54:34.312908 | controller |   msg: All assertions passed
2025-11-27 17:54:34.312923 | controller |
2025-11-27 17:54:34.312934 | controller | TASK [dnsmasq : Create network] ************************************************
2025-11-27 17:54:34.742688 | controller | Thursday 27 November 2025  17:54:34 +0000 (0:00:00.032)       0:00:11.100 *****
2025-11-27 17:54:34.742722 | controller | changed: [instance]
2025-11-27 17:54:34.742748 | controller |
2025-11-27 17:54:34.742757 | controller | TASK [dnsmasq : Remove network] ************************************************
2025-11-27 17:54:34.742845 | controller | Thursday 27 November 2025  17:54:34 +0000 (0:00:00.429)       0:00:11.530 *****
2025-11-27 17:54:34.759323 | controller | skipping: [instance]
2025-11-27 17:54:34.780536 | controller |
2025-11-27 17:54:34.780553 | controller | TASK [dnsmasq : Remove dhcp host files] ****************************************
2025-11-27 17:54:34.780561 | controller | Thursday 27 November 2025  17:54:34 +0000 (0:00:00.016)       0:00:11.547 *****
2025-11-27 17:54:34.780569 | controller | skipping: [instance]
2025-11-27 17:54:34.808662 | controller |
2025-11-27 17:54:34.808687 | controller | TASK [Create second network] ***************************************************
2025-11-27 17:54:34.808697 | controller | Thursday 27 November 2025  17:54:34 +0000 (0:00:00.021)       0:00:11.568 *****
2025-11-27 17:54:34.808709 | controller |
2025-11-27 17:54:34.808739 | controller | TASK [dnsmasq : Assert mandatory parameter] ************************************
2025-11-27 17:54:34.808889 | controller | Thursday 27 November 2025  17:54:34 +0000 (0:00:00.028)       0:00:11.597 *****
2025-11-27 17:54:34.833658 | controller | ok: [instance] => changed=false
2025-11-27 17:54:34.834059 | controller |   msg: All assertions passed
2025-11-27 17:54:34.867670 | controller |
2025-11-27 17:54:34.867699 | controller | TASK [dnsmasq : Assert mandatory parameters for new network] *******************
2025-11-27 17:54:34.867707 | controller | Thursday 27 November 2025  17:54:34 +0000 (0:00:00.025)       0:00:11.622 *****
2025-11-27 17:54:34.867717 | controller | ok: [instance] => changed=false
2025-11-27 17:54:35.280758 | controller |   msg: All assertions passed
2025-11-27 17:54:35.280785 | controller |
2025-11-27 17:54:35.280793 | controller | TASK [dnsmasq : Create network] ************************************************
2025-11-27 17:54:35.280800 | controller | Thursday 27 November 2025  17:54:34 +0000 (0:00:00.033)       0:00:11.655 *****
2025-11-27 17:54:35.280815 | controller | changed: [instance]
2025-11-27 17:54:35.298071 | controller |
2025-11-27 17:54:35.298105 | controller | TASK [dnsmasq : Remove network] ************************************************
2025-11-27 17:54:35.298114 | controller | Thursday 27 November 2025  17:54:35 +0000 (0:00:00.413)       0:00:12.068 *****
2025-11-27 17:54:35.298125 | controller | skipping: [instance]
2025-11-27 17:54:35.320071 | controller |
2025-11-27 17:54:35.320097 | controller | TASK [dnsmasq : Remove dhcp host files] ****************************************
2025-11-27 17:54:35.320104 | controller | Thursday 27 November 2025  17:54:35 +0000 (0:00:00.017)       0:00:12.086 *****
2025-11-27 17:54:35.320113 | controller | skipping: [instance]
2025-11-27 17:54:35.606848 | controller |
2025-11-27 17:54:35.606877 | controller | TASK [Inject enable-ra in startrek] ********************************************
2025-11-27 17:54:35.606885 | controller | Thursday 27 November 2025  17:54:35 +0000 (0:00:00.022)       0:00:12.108 *****
2025-11-27 17:54:35.606895 | controller | ok: [instance]
2025-11-27 17:54:35.630246 | controller |
2025-11-27 17:54:35.630263 | controller | TASK [Assert no change was done in startrek] ***********************************
2025-11-27 17:54:35.630270 | controller | Thursday 27 November 2025  17:54:35 +0000 (0:00:00.286)       0:00:12.394 *****
2025-11-27 17:54:35.630278 | controller | ok: [instance] => changed=false
2025-11-27 17:54:35.656215 | controller |   msg: All assertions passed
2025-11-27 17:54:35.656234 | controller |
2025-11-27 17:54:35.656242 | controller | TASK [Create IPv4-only network] ************************************************
2025-11-27 17:54:35.656250 | controller | Thursday 27 November 2025  17:54:35 +0000 (0:00:00.023)       0:00:12.418 *****
2025-11-27 17:54:35.656260 | controller |
2025-11-27 17:54:35.682475 | controller | TASK [dnsmasq : Assert mandatory parameter] ************************************
2025-11-27 17:54:35.682493 | controller | Thursday 27 November 2025  17:54:35 +0000 (0:00:00.026)       0:00:12.444 *****
2025-11-27 17:54:35.682503 | controller | ok: [instance] => changed=false
2025-11-27 17:54:35.716678 | controller |   msg: All assertions passed
2025-11-27 17:54:35.716730 | controller |
2025-11-27 17:54:35.716740 | controller | TASK [dnsmasq : Assert mandatory parameters for new network] *******************
2025-11-27 17:54:35.716749 | controller | Thursday 27 November 2025  17:54:35 +0000 (0:00:00.026)       0:00:12.470 *****
2025-11-27 17:54:35.716762 | controller | ok: [instance] => changed=false
2025-11-27 17:54:36.148399 | controller |   msg: All assertions passed
2025-11-27 17:54:36.148422 | controller |
2025-11-27 17:54:36.148430 | controller | TASK [dnsmasq : Create network] ************************************************
2025-11-27 17:54:36.148437 | controller | Thursday 27 November 2025  17:54:35 +0000 (0:00:00.034)       0:00:12.504 *****
2025-11-27 17:54:36.148446 | controller | changed: [instance]
2025-11-27 17:54:36.165168 | controller |
2025-11-27 17:54:36.165184 | controller | TASK [dnsmasq : Remove network] ************************************************
2025-11-27 17:54:36.165197 | controller | Thursday 27 November 2025  17:54:36 +0000 (0:00:00.431)       0:00:12.936 *****
2025-11-27 17:54:36.165206 | controller | skipping: [instance]
2025-11-27 17:54:36.186214 | controller |
2025-11-27 17:54:36.186230 | controller | TASK [dnsmasq : Remove dhcp host files] ****************************************
2025-11-27 17:54:36.186238 | controller | Thursday 27 November 2025  17:54:36 +0000 (0:00:00.016)       0:00:12.953 *****
2025-11-27 17:54:36.186246 | controller | skipping: [instance]
2025-11-27 17:54:36.374104 | controller |
2025-11-27 17:54:36.374141 | controller | TASK [Inject enable-ra in oldies] **********************************************
2025-11-27 17:54:36.374149 | controller | Thursday 27 November 2025  17:54:36 +0000 (0:00:00.020)       0:00:12.974 *****
2025-11-27 17:54:36.374158 | controller | changed: [instance]
2025-11-27 17:54:36.392677 | controller |
2025-11-27 17:54:36.392729 | controller | TASK [Debug] *******************************************************************
2025-11-27 17:54:36.392740 | controller | Thursday 27 November 2025  17:54:36 +0000 (0:00:00.187)       0:00:13.161 *****
2025-11-27 17:54:36.392754 | controller | ok: [instance] =>
2025-11-27 17:54:36.393658 | controller |   _set_ra:
2025-11-27 17:54:36.393670 | controller |     backup: ''
2025-11-27 17:54:36.393676 | controller |     changed: true
2025-11-27 17:54:36.393682 | controller |     diff:
2025-11-27 17:54:36.393691 | controller |     - after: ''
2025-11-27 17:54:36.393721 | controller |       after_header: /etc/cifmw-dnsmasq.d/oldies.conf (content)
2025-11-27 17:54:36.393728 | controller |       before: ''
2025-11-27 17:54:36.393739 | controller |       before_header: /etc/cifmw-dnsmasq.d/oldies.conf (content)
2025-11-27 17:54:36.393744 | controller |     - after_header: /etc/cifmw-dnsmasq.d/oldies.conf (file attributes)
2025-11-27 17:54:36.393750 | controller |       before_header: /etc/cifmw-dnsmasq.d/oldies.conf (file attributes)
2025-11-27 17:54:36.393755 | controller |     failed: false
2025-11-27 17:54:36.393761 | controller |     msg: line added
2025-11-27 17:54:36.393766 | controller |
2025-11-27 17:54:36.393771 | controller | TASK [Assert oldies was changed] ***********************************************
2025-11-27 17:54:36.393779 | controller | Thursday 27 November 2025  17:54:36 +0000 (0:00:00.018)       0:00:13.180 *****
2025-11-27 17:54:36.414130 | controller | ok: [instance] => changed=false
2025-11-27 17:54:36.441170 | controller |   msg: All assertions passed
2025-11-27 17:54:36.441194 | controller |
2025-11-27 17:54:36.441204 | controller | TASK [Inject some node in starwars network] ************************************
2025-11-27 17:54:36.441212 | controller | Thursday 27 November 2025  17:54:36 +0000 (0:00:00.021)       0:00:13.202 *****
2025-11-27 17:54:36.441222 | controller |
2025-11-27 17:54:36.441404 | controller | TASK [dnsmasq : Ensure we have the right data and type] ************************
2025-11-27 17:54:36.441570 | controller | Thursday 27 November 2025  17:54:36 +0000 (0:00:00.027)       0:00:13.229 *****
2025-11-27 17:54:36.473423 | controller | ok: [instance]
2025-11-27 17:54:36.473612 | controller |
2025-11-27 17:54:36.473764 | controller | TASK [dnsmasq : Assert we have needed host data] *******************************
2025-11-27 17:54:36.473905 | controller | Thursday 27 November 2025  17:54:36 +0000 (0:00:00.032)       0:00:13.261 *****
2025-11-27 17:54:36.562979 | controller | ok: [instance] => (item={'network': 'starwars', 'state': 'present', 'mac': '0a:19:02:f8:4c:a7', 'ips': ['2345:0425:2CA1::0567:5673:cafe', '192.168.254.11']})
2025-11-27 17:54:36.563205 | controller | ok: [instance] => (item={'network': 'starwars', 'state': 'present', 'mac': '0a:19:02:f8:4c:a8', 'ips': ['2345:0425:2CA1::0567:5673:babe', '192.168.254.12'], 'name': 'solo'})
2025-11-27 17:54:36.563388 | controller | ok: [instance] => (item={'network': 'startrek', 'state': 'present', 'mac': '0a:19:02:f8:4c:a8', 'ips': ['2345:0426:2CA1::0567:5673:babe', '192.168.253.12'], 'name': 'spock'})
2025-11-27 17:54:36.563558 | controller |
2025-11-27 17:54:36.563750 | controller | TASK [dnsmasq : Ensure networks exists] ****************************************
2025-11-27 17:54:36.563909 | controller | Thursday 27 November 2025  17:54:36 +0000 (0:00:00.089)       0:00:13.351 *****
2025-11-27 17:54:36.605281 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/dnsmasq/tasks/_check_net_status.yml for instance => (item=starwars)
2025-11-27 17:54:36.781485 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/dnsmasq/tasks/_check_net_status.yml for instance => (item=startrek)
2025-11-27 17:54:36.781517 | controller |
2025-11-27 17:54:36.781525 | controller | TASK [dnsmasq : Check network file status] *************************************
2025-11-27 17:54:36.781531 | controller | Thursday 27 November 2025  17:54:36 +0000 (0:00:00.041)       0:00:13.393 *****
2025-11-27 17:54:36.781541 | controller | ok: [instance]
2025-11-27 17:54:36.801499 | controller |
2025-11-27 17:54:36.801521 | controller | TASK [dnsmasq : Assert network exists] *****************************************
2025-11-27 17:54:36.801529 | controller | Thursday 27 November 2025  17:54:36 +0000 (0:00:00.176)       0:00:13.569 *****
2025-11-27 17:54:36.801540 | controller | ok: [instance]
2025-11-27 17:54:36.972491 | controller |
2025-11-27 17:54:36.972522 | controller | TASK [dnsmasq : Check network file status] *************************************
2025-11-27 17:54:36.972530 | controller | Thursday 27 November 2025  17:54:36 +0000 (0:00:00.020)       0:00:13.589 *****
2025-11-27 17:54:36.972540 | controller | ok: [instance]
2025-11-27 17:54:36.992109 | controller |
2025-11-27 17:54:36.992130 | controller | TASK [dnsmasq : Assert network exists] *****************************************
2025-11-27 17:54:36.992139 | controller | Thursday 27 November 2025  17:54:36 +0000 (0:00:00.170)       0:00:13.760 *****
2025-11-27 17:54:36.992149 | controller | ok: [instance]
2025-11-27 17:54:37.007539 | controller |
2025-11-27 17:54:37.007558 | controller | TASK [dnsmasq : Initialize empty dhcp_host_entries] ****************************
2025-11-27 17:54:37.007572 | controller | Thursday 27 November 2025  17:54:36 +0000 (0:00:00.019)       0:00:13.780 *****
2025-11-27 17:54:37.007581 | controller | ok: [instance]
2025-11-27 17:54:37.123582 | controller |
2025-11-27 17:54:37.123629 | controller | TASK [dnsmasq : Compute entry] *************************************************
2025-11-27 17:54:37.123639 | controller | Thursday 27 November 2025  17:54:37 +0000 (0:00:00.015)       0:00:13.795 *****
2025-11-27 17:54:37.123650 | controller | ok: [instance] => (item={'network': 'starwars', 'state': 'present', 'mac': '0a:19:02:f8:4c:a7', 'ips': ['2345:0425:2CA1::0567:5673:cafe', '192.168.254.11']})
2025-11-27 17:54:37.156513 | controller | ok: [instance] => (item={'network': 'starwars', 'state': 'present', 'mac': '0a:19:02:f8:4c:a8', 'ips': ['2345:0425:2CA1::0567:5673:babe', '192.168.254.12'], 'name': 'solo'})
2025-11-27 17:54:37.156533 | controller | ok: [instance] => (item={'network': 'startrek', 'state': 'present', 'mac': '0a:19:02:f8:4c:a8', 'ips': ['2345:0426:2CA1::0567:5673:babe', '192.168.253.12'], 'name': 'spock'})
2025-11-27 17:54:37.156540 | controller |
2025-11-27 17:54:37.156546 | controller | TASK [dnsmasq : Create add/remove sets] ****************************************
2025-11-27 17:54:37.156552 | controller | Thursday 27 November 2025  17:54:37 +0000 (0:00:00.115)       0:00:13.911 *****
2025-11-27 17:54:37.156567 | controller | ok: [instance]
2025-11-27 17:54:38.188254 | controller |
2025-11-27 17:54:38.188287 | controller | TASK [dnsmasq : Add DHCP entries] **********************************************
2025-11-27 17:54:38.188295 | controller | Thursday 27 November 2025  17:54:37 +0000 (0:00:00.033)       0:00:13.944 *****
2025-11-27 17:54:38.188305 | controller | changed: [instance] => (item={'file': 'starwars_0a:19:02:f8:4c:a7', 'entry': '0a:19:02:f8:4c:a7,[2345:425:2ca1::567:5673:cafe],192.168.254.11', 'state': 'present'})
2025-11-27 17:54:38.208396 | controller | changed: [instance] => (item={'file': 'starwars_solo_0a:19:02:f8:4c:a8', 'entry': '0a:19:02:f8:4c:a8,[2345:425:2ca1::567:5673:babe],192.168.254.12,solo', 'state': 'present'})
2025-11-27 17:54:38.208413 | controller | changed: [instance] => (item={'file': 'startrek_spock_0a:19:02:f8:4c:a8', 'entry': '0a:19:02:f8:4c:a8,[2345:426:2ca1::567:5673:babe],192.168.253.12,spock', 'state': 'present'})
2025-11-27 17:54:38.208419 | controller |
2025-11-27 17:54:38.208425 | controller | TASK [dnsmasq : Remove DHCP entries] *******************************************
2025-11-27 17:54:38.208431 | controller | Thursday 27 November 2025  17:54:38 +0000 (0:00:01.031)       0:00:14.975 *****
2025-11-27 17:54:38.208440 | controller | skipping: [instance]
2025-11-27 17:54:38.240376 | controller |
2025-11-27 17:54:38.240404 | controller | TASK [Inject nodes with tags for DHCP options] *********************************
2025-11-27 17:54:38.240413 | controller | Thursday 27 November 2025  17:54:38 +0000 (0:00:00.020)       0:00:14.996 *****
2025-11-27 17:54:38.240424 | controller |
2025-11-27 17:54:38.273389 | controller | TASK [dnsmasq : Ensure we have the right data and type] ************************
2025-11-27 17:54:38.273412 | controller | Thursday 27 November 2025  17:54:38 +0000 (0:00:00.031)       0:00:15.028 *****
2025-11-27 17:54:38.273424 | controller | ok: [instance]
2025-11-27 17:54:38.333470 | controller |
2025-11-27 17:54:38.333493 | controller | TASK [dnsmasq : Assert we have needed host data] *******************************
2025-11-27 17:54:38.333507 | controller | Thursday 27 November 2025  17:54:38 +0000 (0:00:00.032)       0:00:15.061 *****
2025-11-27 17:54:38.333516 | 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'})
2025-11-27 17:54:38.372718 | controller | ok: [instance] => (item={'network': 'starwars', 'state': 'present', 'mac': '0a:19:02:f8:4c:b2', 'ips': ['192.168.254.22'], 'name': 'c3po', 'tag': 'droid'})
2025-11-27 17:54:38.372752 | controller | ok: [instance] => (item={'network': 'startrek', 'state': 'present', 'mac': '0a:19:02:f8:4c:b3', 'ips': ['192.168.253.31'], 'name': 'data', 'tag': 'android'})
2025-11-27 17:54:38.372761 | controller |
2025-11-27 17:54:38.372769 | controller | TASK [dnsmasq : Ensure networks exists] ****************************************
2025-11-27 17:54:38.372783 | controller | Thursday 27 November 2025  17:54:38 +0000 (0:00:00.059)       0:00:15.121 *****
2025-11-27 17:54:38.372796 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/dnsmasq/tasks/_check_net_status.yml for instance => (item=starwars)
2025-11-27 17:54:38.545003 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/dnsmasq/tasks/_check_net_status.yml for instance => (item=startrek)
2025-11-27 17:54:38.545037 | controller |
2025-11-27 17:54:38.545046 | controller | TASK [dnsmasq : Check network file status] *************************************
2025-11-27 17:54:38.545053 | controller | Thursday 27 November 2025  17:54:38 +0000 (0:00:00.039)       0:00:15.160 *****
2025-11-27 17:54:38.545063 | controller | ok: [instance]
2025-11-27 17:54:38.564694 | controller |
2025-11-27 17:54:38.564726 | controller | TASK [dnsmasq : Assert network exists] *****************************************
2025-11-27 17:54:38.564734 | controller | Thursday 27 November 2025  17:54:38 +0000 (0:00:00.171)       0:00:15.332 *****
2025-11-27 17:54:38.564745 | controller | ok: [instance]
2025-11-27 17:54:38.564766 | controller |
2025-11-27 17:54:38.564773 | controller | TASK [dnsmasq : Check network file status] *************************************
2025-11-27 17:54:38.564781 | controller | Thursday 27 November 2025  17:54:38 +0000 (0:00:00.020)       0:00:15.352 *****
2025-11-27 17:54:38.736427 | controller | ok: [instance]
2025-11-27 17:54:38.757778 | controller |
2025-11-27 17:54:38.757812 | controller | TASK [dnsmasq : Assert network exists] *****************************************
2025-11-27 17:54:38.757822 | controller | Thursday 27 November 2025  17:54:38 +0000 (0:00:00.171)       0:00:15.524 *****
2025-11-27 17:54:38.757835 | controller | ok: [instance]
2025-11-27 17:54:38.757859 | controller |
2025-11-27 17:54:38.757868 | controller | TASK [dnsmasq : Initialize empty dhcp_host_entries] ****************************
2025-11-27 17:54:38.757878 | controller | Thursday 27 November 2025  17:54:38 +0000 (0:00:00.021)       0:00:15.545 *****
2025-11-27 17:54:38.775210 | controller | ok: [instance]
2025-11-27 17:54:38.891720 | controller |
2025-11-27 17:54:38.891760 | controller | TASK [dnsmasq : Compute entry] *************************************************
2025-11-27 17:54:38.891772 | controller | Thursday 27 November 2025  17:54:38 +0000 (0:00:00.017)       0:00:15.563 *****
2025-11-27 17:54:38.891786 | 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'})
2025-11-27 17:54:38.891817 | controller | ok: [instance] => (item={'network': 'starwars', 'state': 'present', 'mac': '0a:19:02:f8:4c:b2', 'ips': ['192.168.254.22'], 'name': 'c3po', 'tag': 'droid'})
2025-11-27 17:54:38.891826 | controller | ok: [instance] => (item={'network': 'startrek', 'state': 'present', 'mac': '0a:19:02:f8:4c:b3', 'ips': ['192.168.253.31'], 'name': 'data', 'tag': 'android'})
2025-11-27 17:54:38.891833 | controller |
2025-11-27 17:54:38.891843 | controller | TASK [dnsmasq : Create add/remove sets] ****************************************
2025-11-27 17:54:38.891853 | controller | Thursday 27 November 2025  17:54:38 +0000 (0:00:00.116)       0:00:15.679 *****
2025-11-27 17:54:38.926833 | controller | ok: [instance]
2025-11-27 17:54:39.971074 | controller |
2025-11-27 17:54:39.971117 | controller | TASK [dnsmasq : Add DHCP entries] **********************************************
2025-11-27 17:54:39.971125 | controller | Thursday 27 November 2025  17:54:38 +0000 (0:00:00.035)       0:00:15.714 *****
2025-11-27 17:54:39.971136 | 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'})
2025-11-27 17:54:39.990109 | 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'})
2025-11-27 17:54:39.990129 | 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'})
2025-11-27 17:54:39.990137 | controller |
2025-11-27 17:54:39.990145 | controller | TASK [dnsmasq : Remove DHCP entries] *******************************************
2025-11-27 17:54:39.990152 | controller | Thursday 27 November 2025  17:54:39 +0000 (0:00:01.043)       0:00:16.758 *****
2025-11-27 17:54:39.990163 | controller | skipping: [instance]
2025-11-27 17:54:40.268317 | controller |
2025-11-27 17:54:40.268338 | controller | TASK [Read r2d2 DHCP host entry] ***********************************************
2025-11-27 17:54:40.268351 | controller | Thursday 27 November 2025  17:54:39 +0000 (0:00:00.019)       0:00:16.778 *****
2025-11-27 17:54:40.268365 | controller | ok: [instance]
2025-11-27 17:54:40.452992 | controller |
2025-11-27 17:54:40.453011 | controller | TASK [Read c3po DHCP host entry] ***********************************************
2025-11-27 17:54:40.453018 | controller | Thursday 27 November 2025  17:54:40 +0000 (0:00:00.278)       0:00:17.056 *****
2025-11-27 17:54:40.453027 | controller | ok: [instance]
2025-11-27 17:54:40.631647 | controller |
2025-11-27 17:54:40.631667 | controller | TASK [Read data DHCP host entry] ***********************************************
2025-11-27 17:54:40.631675 | controller | Thursday 27 November 2025  17:54:40 +0000 (0:00:00.184)       0:00:17.241 *****
2025-11-27 17:54:40.631684 | controller | ok: [instance]
2025-11-27 17:54:40.632052 | controller |
2025-11-27 17:54:40.664537 | controller | TASK [Decode entries] **********************************************************
2025-11-27 17:54:40.664555 | controller | Thursday 27 November 2025  17:54:40 +0000 (0:00:00.178)       0:00:17.419 *****
2025-11-27 17:54:40.664566 | controller | ok: [instance]
2025-11-27 17:54:40.688584 | controller |
2025-11-27 17:54:40.688622 | controller | TASK [Assert r2d2 entry has droid tag] *****************************************
2025-11-27 17:54:40.688632 | controller | Thursday 27 November 2025  17:54:40 +0000 (0:00:00.032)       0:00:17.452 *****
2025-11-27 17:54:40.688641 | controller | ok: [instance] => changed=false
2025-11-27 17:54:40.712696 | controller |   msg: All assertions passed
2025-11-27 17:54:40.712728 | controller |
2025-11-27 17:54:40.712736 | controller | TASK [Assert c3po entry has droid tag] *****************************************
2025-11-27 17:54:40.712743 | controller | Thursday 27 November 2025  17:54:40 +0000 (0:00:00.023)       0:00:17.476 *****
2025-11-27 17:54:40.712753 | controller | ok: [instance] => changed=false
2025-11-27 17:54:40.712777 | controller |   msg: All assertions passed
2025-11-27 17:54:40.712784 | controller |
2025-11-27 17:54:40.712791 | controller | TASK [Assert data entry has android tag] ***************************************
2025-11-27 17:54:40.712893 | controller | Thursday 27 November 2025  17:54:40 +0000 (0:00:00.024)       0:00:17.500 *****
2025-11-27 17:54:40.735677 | controller | ok: [instance] => changed=false
2025-11-27 17:54:40.735716 | controller |   msg: All assertions passed
2025-11-27 17:54:40.735724 | controller |
2025-11-27 17:54:40.735732 | controller | TASK [Verify entry without tag has no set: prefix] *****************************
2025-11-27 17:54:40.735835 | controller | Thursday 27 November 2025  17:54:40 +0000 (0:00:00.022)       0:00:17.523 *****
2025-11-27 17:54:40.924372 | controller | ok: [instance]
2025-11-27 17:54:40.960472 | controller |
2025-11-27 17:54:40.960492 | controller | TASK [Assert solo entry does not have a tag] ***********************************
2025-11-27 17:54:40.960500 | controller | Thursday 27 November 2025  17:54:40 +0000 (0:00:00.188)       0:00:17.712 *****
2025-11-27 17:54:40.960509 | controller | ok: [instance] => changed=false
2025-11-27 17:54:41.329160 | controller |   msg: All assertions passed
2025-11-27 17:54:41.329190 | controller |
2025-11-27 17:54:41.329198 | controller | TASK [Create DHCP options configuration for tagged hosts] **********************
2025-11-27 17:54:41.329205 | controller | Thursday 27 November 2025  17:54:40 +0000 (0:00:00.036)       0:00:17.748 *****
2025-11-27 17:54:41.329214 | controller | changed: [instance]
2025-11-27 17:54:41.614035 | controller |
2025-11-27 17:54:41.614065 | controller | TASK [Verify dnsmasq configuration is valid] ***********************************
2025-11-27 17:54:41.614073 | controller | Thursday 27 November 2025  17:54:41 +0000 (0:00:00.368)       0:00:18.116 *****
2025-11-27 17:54:41.614083 | controller | ok: [instance]
2025-11-27 17:54:41.639691 | controller |
2025-11-27 17:54:41.639717 | controller | TASK [Add a domain specific forwarder] *****************************************
2025-11-27 17:54:41.639725 | controller | Thursday 27 November 2025  17:54:41 +0000 (0:00:00.284)       0:00:18.401 *****
2025-11-27 17:54:41.639735 | controller |
2025-11-27 17:54:41.639795 | controller | TASK [dnsmasq : Assert we have needed forwarder data] **************************
2025-11-27 17:54:41.639807 | controller | Thursday 27 November 2025  17:54:41 +0000 (0:00:00.026)       0:00:18.428 *****
2025-11-27 17:54:41.673381 | controller | ok: [instance]
2025-11-27 17:54:41.673461 | controller |
2025-11-27 17:54:41.673565 | controller | TASK [dnsmasq : Assert each forwarder element have needed data] ****************
2025-11-27 17:54:41.673712 | controller | Thursday 27 November 2025  17:54:41 +0000 (0:00:00.033)       0:00:18.461 *****
2025-11-27 17:54:41.713359 | controller | ok: [instance] => (item={'state': 'present', 'server': '192.168.254.100', 'domains': ['battlestar.galactica.lan']})
2025-11-27 17:54:41.931692 | controller |
2025-11-27 17:54:41.931725 | controller | TASK [dnsmasq : Add/Remove forwarder] ******************************************
2025-11-27 17:54:41.931735 | controller | Thursday 27 November 2025  17:54:41 +0000 (0:00:00.039)       0:00:18.501 *****
2025-11-27 17:54:41.931747 | controller | changed: [instance] => (item={'state': 'present', 'server': '192.168.254.100', 'domains': ['battlestar.galactica.lan']})
2025-11-27 17:54:41.931774 | controller |
2025-11-27 17:54:41.931784 | controller | TASK [Add some host records] ***************************************************
2025-11-27 17:54:41.931850 | controller | Thursday 27 November 2025  17:54:41 +0000 (0:00:00.218)       0:00:18.719 *****
2025-11-27 17:54:41.959697 | controller |
2025-11-27 17:54:41.959776 | controller | TASK [dnsmasq : Assert we have needed host record data] ************************
2025-11-27 17:54:41.959794 | controller | Thursday 27 November 2025  17:54:41 +0000 (0:00:00.028)       0:00:18.748 *****
2025-11-27 17:54:41.991651 | controller | ok: [instance]
2025-11-27 17:54:41.991818 | controller |
2025-11-27 17:54:41.991835 | controller | TASK [dnsmasq : Assert each address element have needed data] ******************
2025-11-27 17:54:42.040410 | controller | Thursday 27 November 2025  17:54:41 +0000 (0:00:00.031)       0:00:18.779 *****
2025-11-27 17:54:42.040434 | controller | ok: [instance] => (item={'state': 'present', 'ips': ['192.168.253.50', '2345:0425:2CA1::dead'], 'names': ['dead.battlestar.galactica.lan']})
2025-11-27 17:54:42.448566 | controller | ok: [instance] => (item={'state': 'present', 'ips': ['192.168.253.51', '2345:0425:2CA1::beef'], 'names': ['beef.battlestar.galactica.lan']})
2025-11-27 17:54:42.448594 | controller |
2025-11-27 17:54:42.448631 | controller | TASK [dnsmasq : Add/Remove address] ********************************************
2025-11-27 17:54:42.448638 | controller | Thursday 27 November 2025  17:54:42 +0000 (0:00:00.048)       0:00:18.828 *****
2025-11-27 17:54:42.448648 | controller | changed: [instance] => (item={'state': 'present', 'ips': ['192.168.253.50', '2345:0425:2CA1::dead'], 'names': ['dead.battlestar.galactica.lan']})
2025-11-27 17:54:42.479416 | controller | changed: [instance] => (item={'state': 'present', 'ips': ['192.168.253.51', '2345:0425:2CA1::beef'], 'names': ['beef.battlestar.galactica.lan']})
2025-11-27 17:54:42.479437 | controller |
2025-11-27 17:54:42.479446 | controller | TASK [Add some wildcard addresses] *********************************************
2025-11-27 17:54:42.479453 | controller | Thursday 27 November 2025  17:54:42 +0000 (0:00:00.408)       0:00:19.236 *****
2025-11-27 17:54:42.479464 | controller |
2025-11-27 17:54:42.512167 | controller | TASK [dnsmasq : Assert we have needed address data] ****************************
2025-11-27 17:54:42.512189 | controller | Thursday 27 November 2025  17:54:42 +0000 (0:00:00.031)       0:00:19.267 *****
2025-11-27 17:54:42.512200 | controller | ok: [instance]
2025-11-27 17:54:42.595959 | controller |
2025-11-27 17:54:42.595983 | controller | TASK [dnsmasq : Assert each address element have needed data] ******************
2025-11-27 17:54:42.595991 | controller | Thursday 27 November 2025  17:54:42 +0000 (0:00:00.032)       0:00:19.300 *****
2025-11-27 17:54:42.596000 | controller | ok: [instance] => (item={'state': 'present', 'ipaddr': '1.2.3.4', 'domains': ['apps.ocp.starwars.lan']})
2025-11-27 17:54:43.015708 | controller | ok: [instance] => (item={'state': 'present', 'ipaddr': '4.3.2.1', 'domains': ['apps.ocp.startrek.lan']})
2025-11-27 17:54:43.015739 | controller |
2025-11-27 17:54:43.015749 | controller | TASK [dnsmasq : Add/Remove address] ********************************************
2025-11-27 17:54:43.015757 | controller | Thursday 27 November 2025  17:54:42 +0000 (0:00:00.083)       0:00:19.383 *****
2025-11-27 17:54:43.015769 | controller | changed: [instance] => (item={'state': 'present', 'ipaddr': '1.2.3.4', 'domains': ['apps.ocp.starwars.lan']})
2025-11-27 17:54:43.016173 | controller | changed: [instance] => (item={'state': 'present', 'ipaddr': '4.3.2.1', 'domains': ['apps.ocp.startrek.lan']})
2025-11-27 17:54:43.016189 | controller |
2025-11-27 17:54:43.016196 | controller | TASK [Force reload dnsmasq] ****************************************************
2025-11-27 17:54:43.016211 | controller | Thursday 27 November 2025  17:54:43 +0000 (0:00:00.410)       0:00:19.794 *****
2025-11-27 17:54:43.348669 | controller |
2025-11-27 17:54:43.348700 | controller | RUNNING HANDLER [dnsmasq : Restart dnsmasq] ************************************
2025-11-27 17:54:43.348708 | controller | Thursday 27 November 2025  17:54:43 +0000 (0:00:00.009)       0:00:19.803 *****
2025-11-27 17:54:43.348718 | controller | changed: [instance]
2025-11-27 17:54:43.685722 | controller |
2025-11-27 17:54:43.685751 | controller | RUNNING HANDLER [dnsmasq : Reload dnsmasq] *************************************
2025-11-27 17:54:43.685759 | controller | Thursday 27 November 2025  17:54:43 +0000 (0:00:00.333)       0:00:20.136 *****
2025-11-27 17:54:43.685770 | controller | changed: [instance]
2025-11-27 17:54:43.687645 | controller |
2025-11-27 17:54:43.687673 | controller | PLAY RECAP *********************************************************************
2025-11-27 17:54:43.688010 | controller | instance                   : ok=71   changed=20   unreachable=0    failed=0    skipped=14   rescued=0    ignored=0
2025-11-27 17:54:43.688026 | controller |
2025-11-27 17:54:43.688033 | controller | Thursday 27 November 2025  17:54:43 +0000 (0:00:00.337)       0:00:20.473 *****
2025-11-27 17:54:43.688039 | controller | ===============================================================================
2025-11-27 17:54:43.688045 | controller | dnsmasq : Install needed packages --------------------------------------- 3.84s
2025-11-27 17:54:43.688050 | controller | dnsmasq : Ensure SELinux knows about the new files and directories ------ 3.37s
2025-11-27 17:54:43.688055 | controller | dnsmasq : Add DHCP entries ---------------------------------------------- 1.04s
2025-11-27 17:54:43.688061 | controller | dnsmasq : Add DHCP entries ---------------------------------------------- 1.03s
2025-11-27 17:54:43.688066 | controller | dnsmasq : Enable and start service -------------------------------------- 0.83s
2025-11-27 17:54:43.688071 | controller | Gathering Facts --------------------------------------------------------- 0.82s
2025-11-27 17:54:43.688077 | controller | dnsmasq : Manage systemd unit file -------------------------------------- 0.55s
2025-11-27 17:54:43.688082 | controller | dnsmasq : Create network ------------------------------------------------ 0.43s
2025-11-27 17:54:43.688108 | controller | dnsmasq : Create network ------------------------------------------------ 0.43s
2025-11-27 17:54:43.688114 | controller | dnsmasq : Create network ------------------------------------------------ 0.41s
2025-11-27 17:54:43.688120 | controller | dnsmasq : Add/Remove address -------------------------------------------- 0.41s
2025-11-27 17:54:43.688125 | controller | dnsmasq : Add/Remove address -------------------------------------------- 0.41s
2025-11-27 17:54:43.688131 | controller | dnsmasq : Create listener configuration file ---------------------------- 0.38s
2025-11-27 17:54:43.688136 | controller | dnsmasq : Create dns configuration file --------------------------------- 0.37s
2025-11-27 17:54:43.688141 | controller | dnsmasq : Manage base configuration file -------------------------------- 0.37s
2025-11-27 17:54:43.688147 | controller | Create DHCP options configuration for tagged hosts ---------------------- 0.37s
2025-11-27 17:54:43.688155 | controller | dnsmasq : Reload dnsmasq ------------------------------------------------ 0.34s
2025-11-27 17:54:43.740760 | controller | dnsmasq : Restart dnsmasq ----------------------------------------------- 0.33s
2025-11-27 17:54:43.740781 | controller | dnsmasq : Manage configuration directory -------------------------------- 0.30s
2025-11-27 17:54:43.740788 | controller | Inject enable-ra in startrek -------------------------------------------- 0.29s
2025-11-27 17:54:43.740798 | controller | INFO     Running default > cleanup
2025-11-27 17:54:44.147022 | controller |
2025-11-27 17:54:44.147256 | controller | PLAY [Converge] ****************************************************************
2025-11-27 17:54:44.147390 | controller |
2025-11-27 17:54:44.147537 | controller | TASK [Gathering Facts] *********************************************************
2025-11-27 17:54:44.147704 | controller | Thursday 27 November 2025  17:54:44 +0000 (0:00:00.013)       0:00:00.013 *****
2025-11-27 17:54:44.972367 | controller | ok: [instance]
2025-11-27 17:54:45.524998 | controller |
2025-11-27 17:54:45.525026 | controller | TASK [Copy generated content in ci-framework-data/artifacts] *******************
2025-11-27 17:54:45.525040 | controller | Thursday 27 November 2025  17:54:44 +0000 (0:00:00.825)       0:00:00.839 *****
2025-11-27 17:54:45.525051 | controller | changed: [instance] => (item=/etc/cifmw-dnsmasq.conf)
2025-11-27 17:54:45.552389 | controller | changed: [instance] => (item=/etc/cifmw-dnsmasq.d/)
2025-11-27 17:54:45.552412 | controller |
2025-11-27 17:54:45.552420 | controller | TASK [Remove a domain specific forwarder] **************************************
2025-11-27 17:54:45.552426 | controller | Thursday 27 November 2025  17:54:45 +0000 (0:00:00.552)       0:00:01.391 *****
2025-11-27 17:54:45.552436 | controller |
2025-11-27 17:54:45.584563 | controller | TASK [dnsmasq : Assert we have needed forwarder data] **************************
2025-11-27 17:54:45.584588 | controller | Thursday 27 November 2025  17:54:45 +0000 (0:00:00.027)       0:00:01.419 *****
2025-11-27 17:54:45.584619 | controller | ok: [instance]
2025-11-27 17:54:45.620964 | controller |
2025-11-27 17:54:45.620986 | controller | TASK [dnsmasq : Assert each forwarder element have needed data] ****************
2025-11-27 17:54:45.620994 | controller | Thursday 27 November 2025  17:54:45 +0000 (0:00:00.032)       0:00:01.451 *****
2025-11-27 17:54:45.621003 | controller | ok: [instance] => (item={'state': 'absent', 'server': '192.168.100.100', 'domains': ['battlestar.galactica.lan']})
2025-11-27 17:54:45.908712 | controller |
2025-11-27 17:54:45.908741 | controller | TASK [dnsmasq : Add/Remove forwarder] ******************************************
2025-11-27 17:54:45.908749 | controller | Thursday 27 November 2025  17:54:45 +0000 (0:00:00.036)       0:00:01.488 *****
2025-11-27 17:54:45.908759 | controller | ok: [instance] => (item={'state': 'absent', 'server': '192.168.100.100', 'domains': ['battlestar.galactica.lan']})
2025-11-27 17:54:45.908781 | controller |
2025-11-27 17:54:45.908790 | controller | TASK [Remove some host records] ************************************************
2025-11-27 17:54:45.908895 | controller | Thursday 27 November 2025  17:54:45 +0000 (0:00:00.287)       0:00:01.775 *****
2025-11-27 17:54:45.937035 | controller |
2025-11-27 17:54:45.970243 | controller | TASK [dnsmasq : Assert we have needed host record data] ************************
2025-11-27 17:54:45.970266 | controller | Thursday 27 November 2025  17:54:45 +0000 (0:00:00.028)       0:00:01.804 *****
2025-11-27 17:54:45.970279 | controller | ok: [instance]
2025-11-27 17:54:46.019843 | controller |
2025-11-27 17:54:46.019871 | controller | TASK [dnsmasq : Assert each address element have needed data] ******************
2025-11-27 17:54:46.019879 | controller | Thursday 27 November 2025  17:54:45 +0000 (0:00:00.033)       0:00:01.837 *****
2025-11-27 17:54:46.019895 | controller | ok: [instance] => (item={'state': 'absent', 'ips': ['192.168.253.50', '2345:0425:2CA1::dead'], 'names': ['dead.battlestar.galactica.lan']})
2025-11-27 17:54:46.418792 | controller | ok: [instance] => (item={'state': 'absent', 'ips': ['192.168.253.51', '2345:0425:2CA1::beef'], 'names': ['beef.battlestar.galactica.lan']})
2025-11-27 17:54:46.418820 | controller |
2025-11-27 17:54:46.418828 | controller | TASK [dnsmasq : Add/Remove address] ********************************************
2025-11-27 17:54:46.418834 | controller | Thursday 27 November 2025  17:54:46 +0000 (0:00:00.049)       0:00:01.886 *****
2025-11-27 17:54:46.418845 | controller | changed: [instance] => (item={'state': 'absent', 'ips': ['192.168.253.50', '2345:0425:2CA1::dead'], 'names': ['dead.battlestar.galactica.lan']})
2025-11-27 17:54:46.450112 | controller | changed: [instance] => (item={'state': 'absent', 'ips': ['192.168.253.51', '2345:0425:2CA1::beef'], 'names': ['beef.battlestar.galactica.lan']})
2025-11-27 17:54:46.450137 | controller |
2025-11-27 17:54:46.450146 | controller | TASK [Remove some wildcard addresses] ******************************************
2025-11-27 17:54:46.450153 | controller | Thursday 27 November 2025  17:54:46 +0000 (0:00:00.398)       0:00:02.285 *****
2025-11-27 17:54:46.450165 | controller |
2025-11-27 17:54:46.482146 | controller | TASK [dnsmasq : Assert we have needed address data] ****************************
2025-11-27 17:54:46.482167 | controller | Thursday 27 November 2025  17:54:46 +0000 (0:00:00.031)       0:00:02.317 *****
2025-11-27 17:54:46.482179 | controller | ok: [instance]
2025-11-27 17:54:46.562916 | controller |
2025-11-27 17:54:46.562940 | controller | TASK [dnsmasq : Assert each address element have needed data] ******************
2025-11-27 17:54:46.562948 | controller | Thursday 27 November 2025  17:54:46 +0000 (0:00:00.031)       0:00:02.349 *****
2025-11-27 17:54:46.562966 | controller | ok: [instance] => (item={'state': 'absent', 'ipaddr': '1.2.3.4', 'domains': ['apps.ocp.starwars.lan']})
2025-11-27 17:54:46.955665 | controller | ok: [instance] => (item={'state': 'absent', 'ipaddr': '4.3.2.1', 'domains': ['apps.ocp.startrek.lan']})
2025-11-27 17:54:46.955693 | controller |
2025-11-27 17:54:46.955701 | controller | TASK [dnsmasq : Add/Remove address] ********************************************
2025-11-27 17:54:46.955707 | controller | Thursday 27 November 2025  17:54:46 +0000 (0:00:00.080)       0:00:02.429 *****
2025-11-27 17:54:46.955717 | controller | changed: [instance] => (item={'state': 'absent', 'ipaddr': '1.2.3.4', 'domains': ['apps.ocp.starwars.lan']})
2025-11-27 17:54:46.955956 | controller | changed: [instance] => (item={'state': 'absent', 'ipaddr': '4.3.2.1', 'domains': ['apps.ocp.startrek.lan']})
2025-11-27 17:54:46.955976 | controller |
2025-11-27 17:54:46.983683 | controller | TASK [dnsmasq : Cleaning dnsmasq] **********************************************
2025-11-27 17:54:46.983707 | controller | Thursday 27 November 2025  17:54:46 +0000 (0:00:00.392)       0:00:02.822 *****
2025-11-27 17:54:46.983720 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/dnsmasq/tasks/configure.yml for instance
2025-11-27 17:54:46.983781 | controller |
2025-11-27 17:54:46.983796 | controller | TASK [dnsmasq : Install needed packages] ***************************************
2025-11-27 17:54:46.983806 | controller | Thursday 27 November 2025  17:54:46 +0000 (0:00:00.028)       0:00:02.850 *****
2025-11-27 17:54:48.244589 | controller | ok: [instance]
2025-11-27 17:54:51.526681 | controller |
2025-11-27 17:54:51.526710 | controller | TASK [dnsmasq : Ensure SELinux knows about the new files and directories] ******
2025-11-27 17:54:51.526719 | controller | Thursday 27 November 2025  17:54:48 +0000 (0:00:01.260)       0:00:04.111 *****
2025-11-27 17:54:51.526729 | controller | changed: [instance] => (item={'target': '/etc/cifmw-dnsmasq.conf', 'setype': 'dnsmasq_etc_t'})
2025-11-27 17:54:51.526752 | controller | changed: [instance] => (item={'target': '/etc/cifmw-dnsmasq.d(/.*)?', 'setype': 'dnsmasq_etc_t'})
2025-11-27 17:54:51.526758 | controller | changed: [instance] => (item={'target': '/var/run/cifmw-dnsmasq.pid', 'setype': 'dnsmasq_var_run_t'})
2025-11-27 17:54:51.526765 | controller |
2025-11-27 17:54:51.526993 | controller | TASK [dnsmasq : Manage configuration directory] ********************************
2025-11-27 17:54:51.823815 | controller | Thursday 27 November 2025  17:54:51 +0000 (0:00:03.282)       0:00:07.393 *****
2025-11-27 17:54:51.823850 | controller | changed: [instance]
2025-11-27 17:54:51.840029 | controller |
2025-11-27 17:54:51.840050 | controller | TASK [dnsmasq : Manage systemd unit file] **************************************
2025-11-27 17:54:51.840057 | controller | Thursday 27 November 2025  17:54:51 +0000 (0:00:00.297)       0:00:07.690 *****
2025-11-27 17:54:51.840066 | controller | skipping: [instance]
2025-11-27 17:54:51.856648 | controller |
2025-11-27 17:54:51.856672 | controller | TASK [dnsmasq : Manage base configuration file] ********************************
2025-11-27 17:54:51.856681 | controller | Thursday 27 November 2025  17:54:51 +0000 (0:00:00.016)       0:00:07.707 *****
2025-11-27 17:54:51.856693 | controller | skipping: [instance]
2025-11-27 17:54:51.878484 | controller |
2025-11-27 17:54:51.878505 | controller | TASK [dnsmasq : Render listener configuration] *********************************
2025-11-27 17:54:51.878513 | controller | Thursday 27 November 2025  17:54:51 +0000 (0:00:00.016)       0:00:07.723 *****
2025-11-27 17:54:51.878525 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/dnsmasq/tasks/listener.yml for instance
2025-11-27 17:54:51.894115 | controller |
2025-11-27 17:54:51.894136 | controller | TASK [dnsmasq : Create listener configuration file] ****************************
2025-11-27 17:54:51.894145 | controller | Thursday 27 November 2025  17:54:51 +0000 (0:00:00.021)       0:00:07.745 *****
2025-11-27 17:54:51.894156 | controller | skipping: [instance]
2025-11-27 17:54:52.082476 | controller |
2025-11-27 17:54:52.082508 | controller | TASK [dnsmasq : Remove listener configuration file] ****************************
2025-11-27 17:54:52.082516 | controller | Thursday 27 November 2025  17:54:51 +0000 (0:00:00.015)       0:00:07.761 *****
2025-11-27 17:54:52.082526 | controller | changed: [instance]
2025-11-27 17:54:52.106294 | controller |
2025-11-27 17:54:52.106320 | controller | TASK [dnsmasq : Render dns configuration] **************************************
2025-11-27 17:54:52.106327 | controller | Thursday 27 November 2025  17:54:52 +0000 (0:00:00.188)       0:00:07.949 *****
2025-11-27 17:54:52.106341 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/dnsmasq/tasks/dns.yml for instance
2025-11-27 17:54:52.121549 | controller |
2025-11-27 17:54:52.121570 | controller | TASK [dnsmasq : Create dns configuration file] *********************************
2025-11-27 17:54:52.121579 | controller | Thursday 27 November 2025  17:54:52 +0000 (0:00:00.024)       0:00:07.973 *****
2025-11-27 17:54:52.121589 | controller | skipping: [instance]
2025-11-27 17:54:52.314552 | controller |
2025-11-27 17:54:52.314580 | controller | TASK [dnsmasq : Remove dns configuration file] *********************************
2025-11-27 17:54:52.314588 | controller | Thursday 27 November 2025  17:54:52 +0000 (0:00:00.015)       0:00:07.988 *****
2025-11-27 17:54:52.314615 | controller | changed: [instance]
2025-11-27 17:54:52.328946 | controller |
2025-11-27 17:54:52.328964 | controller | TASK [dnsmasq : Add localhost addresses from defined dnsmasq listen addresses to loopback interface] ***
2025-11-27 17:54:52.328972 | controller | Thursday 27 November 2025  17:54:52 +0000 (0:00:00.192)       0:00:08.181 *****
2025-11-27 17:54:52.328981 | controller | skipping: [instance]
2025-11-27 17:54:52.344507 | controller |
2025-11-27 17:54:52.344528 | controller | TASK [dnsmasq : Enable and start service] **************************************
2025-11-27 17:54:52.344536 | controller | Thursday 27 November 2025  17:54:52 +0000 (0:00:00.014)       0:00:08.196 *****
2025-11-27 17:54:52.344546 | controller | skipping: [instance]
2025-11-27 17:54:53.017533 | controller |
2025-11-27 17:54:53.017561 | controller | TASK [dnsmasq : Stop service] **************************************************
2025-11-27 17:54:53.017569 | controller | Thursday 27 November 2025  17:54:52 +0000 (0:00:00.015)       0:00:08.211 *****
2025-11-27 17:54:53.017580 | controller | changed: [instance]
2025-11-27 17:54:53.203541 | controller |
2025-11-27 17:54:53.203570 | controller | TASK [dnsmasq : Remove unit file] **********************************************
2025-11-27 17:54:53.203579 | controller | Thursday 27 November 2025  17:54:53 +0000 (0:00:00.672)       0:00:08.884 *****
2025-11-27 17:54:53.203589 | controller | changed: [instance]
2025-11-27 17:54:53.559665 | controller |
2025-11-27 17:54:53.559694 | controller | TASK [dnsmasq : Remove main configuration files] *******************************
2025-11-27 17:54:53.559702 | controller | Thursday 27 November 2025  17:54:53 +0000 (0:00:00.185)       0:00:09.070 *****
2025-11-27 17:54:53.559712 | controller | changed: [instance] => (item=/etc/cifmw-dnsmasq.conf)
2025-11-27 17:54:53.560053 | controller | changed: [instance] => (item=/etc/cifmw-dnsmasq.d)
2025-11-27 17:54:53.560068 | controller |
2025-11-27 17:54:53.760017 | controller | TASK [dnsmasq : Remove lease file] *********************************************
2025-11-27 17:54:53.760048 | controller | Thursday 27 November 2025  17:54:53 +0000 (0:00:00.356)       0:00:09.426 *****
2025-11-27 17:54:53.760060 | controller | changed: [instance]
2025-11-27 17:54:54.057696 | controller |
2025-11-27 17:54:54.057733 | controller | RUNNING HANDLER [dnsmasq : Restart dnsmasq] ************************************
2025-11-27 17:54:54.057742 | controller | Thursday 27 November 2025  17:54:53 +0000 (0:00:00.200)       0:00:09.627 *****
2025-11-27 17:54:54.057752 | controller | ok: [instance]
2025-11-27 17:54:54.057771 | controller |
2025-11-27 17:54:54.058654 | controller | PLAY RECAP *********************************************************************
2025-11-27 17:54:54.060187 | controller | instance                   : ok=24   changed=11   unreachable=0    failed=0    skipped=6    rescued=0    ignored=0
2025-11-27 17:54:54.060205 | controller |
2025-11-27 17:54:54.060213 | controller | Thursday 27 November 2025  17:54:54 +0000 (0:00:00.297)       0:00:09.924 *****
2025-11-27 17:54:54.060219 | controller | ===============================================================================
2025-11-27 17:54:54.060224 | controller | dnsmasq : Ensure SELinux knows about the new files and directories ------ 3.28s
2025-11-27 17:54:54.060230 | controller | dnsmasq : Install needed packages --------------------------------------- 1.26s
2025-11-27 17:54:54.060235 | controller | Gathering Facts --------------------------------------------------------- 0.83s
2025-11-27 17:54:54.060241 | controller | dnsmasq : Stop service -------------------------------------------------- 0.67s
2025-11-27 17:54:54.060253 | controller | Copy generated content in ci-framework-data/artifacts ------------------- 0.55s
2025-11-27 17:54:54.104190 | controller | dnsmasq : Add/Remove address -------------------------------------------- 0.40s
2025-11-27 17:54:54.104219 | controller | dnsmasq : Add/Remove address -------------------------------------------- 0.39s
2025-11-27 17:54:54.104227 | controller | dnsmasq : Remove main configuration files ------------------------------- 0.36s
2025-11-27 17:54:54.104232 | controller | dnsmasq : Restart dnsmasq ----------------------------------------------- 0.30s
2025-11-27 17:54:54.104238 | controller | dnsmasq : Manage configuration directory -------------------------------- 0.30s
2025-11-27 17:54:54.104243 | controller | dnsmasq : Add/Remove forwarder ------------------------------------------ 0.29s
2025-11-27 17:54:54.104257 | controller | dnsmasq : Remove lease file --------------------------------------------- 0.20s
2025-11-27 17:54:54.104263 | controller | dnsmasq : Remove dns configuration file --------------------------------- 0.19s
2025-11-27 17:54:54.104268 | controller | dnsmasq : Remove listener configuration file ---------------------------- 0.19s
2025-11-27 17:54:54.104274 | controller | dnsmasq : Remove unit file ---------------------------------------------- 0.19s
2025-11-27 17:54:54.104279 | controller | dnsmasq : Assert each address element have needed data ------------------ 0.08s
2025-11-27 17:54:54.104284 | controller | dnsmasq : Assert each address element have needed data ------------------ 0.05s
2025-11-27 17:54:54.104290 | controller | dnsmasq : Assert each forwarder element have needed data ---------------- 0.04s
2025-11-27 17:54:54.104296 | controller | dnsmasq : Assert we have needed host record data ------------------------ 0.03s
2025-11-27 17:54:54.104301 | controller | dnsmasq : Assert we have needed forwarder data -------------------------- 0.03s
2025-11-27 17:54:54.104311 | controller | INFO     Writing /tmp/report.html report.
2025-11-27 17:54:54.264410 | controller | changed
2025-11-27 17:54:54.286076 | 
2025-11-27 17:54:54.286127 | PLAY RECAP
2025-11-27 17:54:54.286167 | controller | ok: 4 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-11-27 17:54:54.286188 | 
2025-11-27 17:54:54.349775 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-11-27 17:54:54.350578 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-11-27 17:54:54.875928 | 
2025-11-27 17:54:54.876035 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-11-27 17:54:54.895453 | 
2025-11-27 17:54:54.895525 | TASK [Filter out host if needed]
2025-11-27 17:54:54.903482 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-11-27 17:54:54.907535 | 
2025-11-27 17:54:54.907607 | TASK [Ensure file is present]
2025-11-27 17:54:55.199418 | controller | ok
2025-11-27 17:54:55.206454 | 
2025-11-27 17:54:55.206547 | TASK [Manage molecule report file]
2025-11-27 17:54:55.731881 | controller | changed
2025-11-27 17:54:55.737062 | 
2025-11-27 17:54:55.737129 | TASK [Check if we get ci-framework-data basedir]
2025-11-27 17:54:55.917283 | controller | ok
2025-11-27 17:54:55.922212 | 
2025-11-27 17:54:55.922342 | TASK [Create ci-framework-data log directory for zuul]
2025-11-27 17:54:56.215028 | controller | changed
2025-11-27 17:54:56.220336 | 
2025-11-27 17:54:56.220401 | TASK [Copy ci-framework interesting files]
2025-11-27 17:54:56.405559 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2025-11-27 17:54:56.746711 | controller | changed
2025-11-27 17:54:56.751477 | 
2025-11-27 17:54:56.751544 | TASK [Get SELinux listing]
2025-11-27 17:54:57.276517 | controller | changed
2025-11-27 17:54:57.282166 | 
2025-11-27 17:54:57.282233 | TASK [Generate log index]
2025-11-27 17:54:57.889185 | controller | changed
2025-11-27 17:54:57.893963 | 
2025-11-27 17:54:57.894029 | TASK [Get some env related data]
2025-11-27 17:54:58.408856 | controller | /home/zuul/.local/bin/ansible
2025-11-27 17:54:59.420516 | controller | changed
2025-11-27 17:54:59.425793 | 
2025-11-27 17:54:59.425856 | TASK [Generate list of logs to collect in home directory]
2025-11-27 17:54:59.750019 | controller | ok: All paths examined
2025-11-27 17:54:59.755187 | 
2025-11-27 17:54:59.755256 | LOOP [Copy logs from home directory]
2025-11-27 17:55:00.100089 | controller | changed:
2025-11-27 17:55:00.100287 | controller | {
2025-11-27 17:55:00.100315 | controller |   "atime": 1764265960.7935371,
2025-11-27 17:55:00.100335 | controller |   "ctime": 1764265976.2396905,
2025-11-27 17:55:00.100352 | controller |   "dev": 64513,
2025-11-27 17:55:00.100369 | controller |   "gid": 1000,
2025-11-27 17:55:00.100385 | controller |   "gr_name": "zuul",
2025-11-27 17:55:00.100402 | controller |   "inode": 4329743,
2025-11-27 17:55:00.100418 | controller |   "isblk": false,
2025-11-27 17:55:00.100433 | controller |   "ischr": false,
2025-11-27 17:55:00.100447 | controller |   "isdir": false,
2025-11-27 17:55:00.100461 | controller |   "isfifo": false,
2025-11-27 17:55:00.100476 | controller |   "isgid": false,
2025-11-27 17:55:00.100490 | controller |   "islnk": false,
2025-11-27 17:55:00.100504 | controller |   "isreg": true,
2025-11-27 17:55:00.100518 | controller |   "issock": false,
2025-11-27 17:55:00.100532 | controller |   "isuid": false,
2025-11-27 17:55:00.100546 | controller |   "mode": "0644",
2025-11-27 17:55:00.100560 | controller |   "mtime": 1764265976.2396905,
2025-11-27 17:55:00.100574 | controller |   "nlink": 1,
2025-11-27 17:55:00.100588 | controller |   "path": "/home/zuul/ansible.log",
2025-11-27 17:55:00.100618 | controller |   "pw_name": "zuul",
2025-11-27 17:55:00.100637 | controller |   "rgrp": true,
2025-11-27 17:55:00.100653 | controller |   "roth": true,
2025-11-27 17:55:00.100669 | controller |   "rusr": true,
2025-11-27 17:55:00.100684 | controller |   "size": 6749,
2025-11-27 17:55:00.100699 | controller |   "uid": 1000,
2025-11-27 17:55:00.100713 | controller |   "wgrp": false,
2025-11-27 17:55:00.100728 | controller |   "woth": false,
2025-11-27 17:55:00.100745 | controller |   "wusr": true,
2025-11-27 17:55:00.100760 | controller |   "xgrp": false,
2025-11-27 17:55:00.100775 | controller |   "xoth": false,
2025-11-27 17:55:00.100789 | controller |   "xusr": false
2025-11-27 17:55:00.100803 | controller | }
2025-11-27 17:55:00.114732 | 
2025-11-27 17:55:00.114868 | TASK [Copy crio stats log file]
2025-11-27 17:55:00.140157 | controller | skipping: Conditional result was False
2025-11-27 17:55:00.146038 | 
2025-11-27 17:55:00.146106 | TASK [Get SELinux related data]
2025-11-27 17:55:00.359796 | controller | <no matches>
2025-11-27 17:55:00.672775 | controller | ERROR
2025-11-27 17:55:00.673010 | controller | {
2025-11-27 17:55:00.673053 | controller |   "delta": "0:00:00.009142",
2025-11-27 17:55:00.673076 | controller |   "end": "2025-11-27 17:55:00.360755",
2025-11-27 17:55:00.673094 | controller |   "msg": "non-zero return code",
2025-11-27 17:55:00.673110 | controller |   "rc": 1,
2025-11-27 17:55:00.673129 | controller |   "start": "2025-11-27 17:55:00.351613"
2025-11-27 17:55:00.673146 | controller | }
2025-11-27 17:55:00.673167 | controller | ERROR: Ignoring Errors
2025-11-27 17:55:00.678120 | 
2025-11-27 17:55:00.678182 | TASK [Create system configuration directory]
2025-11-27 17:55:00.870613 | controller | changed
2025-11-27 17:55:00.875658 | 
2025-11-27 17:55:00.875730 | TASK [Get some of the system configurations]
2025-11-27 17:55:01.400424 | controller | changed
2025-11-27 17:55:01.406326 | 
2025-11-27 17:55:01.406439 | TASK [Copy generated documentation if available]
2025-11-27 17:55:01.430636 | controller | skipping: Conditional result was False
2025-11-27 17:55:01.436448 | 
2025-11-27 17:55:01.436518 | TASK [Copy generated AsciiDoc documentation if available]
2025-11-27 17:55:01.460595 | controller | skipping: Conditional result was False
2025-11-27 17:55:01.467976 | 
2025-11-27 17:55:01.468066 | TASK [Compress logs bigger than 2MB]
2025-11-27 17:55:01.998035 | controller | changed
2025-11-27 17:55:02.003323 | 
2025-11-27 17:55:02.003391 | TASK [Copy files from workspace on node]
2025-11-27 17:55:02.024690 | controller | ok
2025-11-27 17:55:02.048778 | 
2025-11-27 17:55:02.048847 | TASK [fetch-output : Set log path for multiple nodes]
2025-11-27 17:55:02.074224 | controller | skipping: Conditional result was False
2025-11-27 17:55:02.080027 | 
2025-11-27 17:55:02.080094 | TASK [fetch-output : Set log path for single node]
2025-11-27 17:55:02.112502 | controller | ok
2025-11-27 17:55:02.117090 | 
2025-11-27 17:55:02.117156 | LOOP [fetch-output : Ensure local output dirs]
2025-11-27 17:55:02.334385 | controller -> localhost | ok: "/var/lib/zuul/builds/2adaea711d3b4eaa99992765d3967e56/work/logs"
2025-11-27 17:55:02.334967 | controller -> localhost | changed: All items complete
2025-11-27 17:55:02.335009 | 
2025-11-27 17:55:02.528733 | controller -> localhost | changed: "/var/lib/zuul/builds/2adaea711d3b4eaa99992765d3967e56/work/artifacts"
2025-11-27 17:55:02.709562 | controller -> localhost | changed: "/var/lib/zuul/builds/2adaea711d3b4eaa99992765d3967e56/work/docs"
2025-11-27 17:55:02.721294 | 
2025-11-27 17:55:02.721427 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-11-27 17:55:03.265056 | controller | changed:
2025-11-27 17:55:03.265279 | controller | .d..t...... ./
2025-11-27 17:55:03.265318 | controller | >f+++++++++ README.html
2025-11-27 17:55:03.265343 | controller | >f+++++++++ ansible-execution.log
2025-11-27 17:55:03.265366 | controller | >f+++++++++ ansible.log
2025-11-27 17:55:03.265385 | controller | >f+++++++++ dmesg.log
2025-11-27 17:55:03.265403 | controller | >f+++++++++ installed-pkgs.log
2025-11-27 17:55:03.265423 | controller | >f+++++++++ python.log
2025-11-27 17:55:03.265440 | controller | >f+++++++++ registries.conf
2025-11-27 17:55:03.265457 | controller | >f+++++++++ report.html
2025-11-27 17:55:03.265476 | controller | >f+++++++++ selinux-denials.log
2025-11-27 17:55:03.265492 | controller | >f+++++++++ selinux-listing.log
2025-11-27 17:55:03.265509 | controller | cd+++++++++ ci-framework-data/
2025-11-27 17:55:03.265525 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-11-27 17:55:03.265541 | controller | >f+++++++++ ci-framework-data/artifacts/addresses.conf
2025-11-27 17:55:03.265557 | controller | >f+++++++++ ci-framework-data/artifacts/cifmw-dnsmasq.conf
2025-11-27 17:55:03.265572 | controller | >f+++++++++ ci-framework-data/artifacts/dns.conf
2025-11-27 17:55:03.265587 | controller | >f+++++++++ ci-framework-data/artifacts/forwarders.conf
2025-11-27 17:55:03.265613 | controller | >f+++++++++ ci-framework-data/artifacts/host_records.conf
2025-11-27 17:55:03.265631 | controller | >f+++++++++ ci-framework-data/artifacts/listener.conf
2025-11-27 17:55:03.265647 | controller | >f+++++++++ ci-framework-data/artifacts/oldies.conf
2025-11-27 17:55:03.265662 | controller | >f+++++++++ ci-framework-data/artifacts/startrek.conf
2025-11-27 17:55:03.265678 | controller | >f+++++++++ ci-framework-data/artifacts/starwars.conf
2025-11-27 17:55:03.265694 | controller | >f+++++++++ ci-framework-data/artifacts/test-dhcp-options.conf
2025-11-27 17:55:03.265711 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-11-27 17:55:03.265726 | controller | cd+++++++++ ci-framework-data/artifacts/dhcp-hosts.d/
2025-11-27 17:55:03.265741 | controller | >f+++++++++ ci-framework-data/artifacts/dhcp-hosts.d/startrek_data_0a:19:02:f8:4c:b3
2025-11-27 17:55:03.265757 | controller | >f+++++++++ ci-framework-data/artifacts/dhcp-hosts.d/startrek_spock_0a:19:02:f8:4c:a8
2025-11-27 17:55:03.265781 | controller | >f+++++++++ ci-framework-data/artifacts/dhcp-hosts.d/starwars_0a:19:02:f8:4c:a7
2025-11-27 17:55:03.265798 | controller | >f+++++++++ ci-framework-data/artifacts/dhcp-hosts.d/starwars_c3po_0a:19:02:f8:4c:b2
2025-11-27 17:55:03.265814 | controller | >f+++++++++ ci-framework-data/artifacts/dhcp-hosts.d/starwars_r2d2_0a:19:02:f8:4c:b1
2025-11-27 17:55:03.265828 | controller | >f+++++++++ ci-framework-data/artifacts/dhcp-hosts.d/starwars_solo_0a:19:02:f8:4c:a8
2025-11-27 17:55:03.265843 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-11-27 17:55:03.265857 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-11-27 17:55:03.265871 | controller | cd+++++++++ ci-framework-data/artifacts/repositories/
2025-11-27 17:55:03.265885 | controller | >f+++++++++ ci-framework-data/artifacts/repositories/delorean.repo.md5
2025-11-27 17:55:03.265900 | controller | cd+++++++++ ci-framework-data/logs/
2025-11-27 17:55:03.265951 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2025-11-27 17:55:03.265974 | controller | cd+++++++++ registries.conf.d/
2025-11-27 17:55:03.265992 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-11-27 17:55:03.266009 | controller | cd+++++++++ system-config/
2025-11-27 17:55:03.266025 | controller | cd+++++++++ system-config/libvirt/
2025-11-27 17:55:03.266040 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-11-27 17:55:03.266055 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-11-27 17:55:03.663835 | controller | changed: .d..t...... ./
2025-11-27 17:55:04.026056 | controller | changed: .d..t...... ./
2025-11-27 17:55:04.039761 | 
2025-11-27 17:55:04.039834 | TASK [Return artifact to Zuul]
2025-11-27 17:55:04.072773 | controller | ok
2025-11-27 17:55:04.094271 | 
2025-11-27 17:55:04.094366 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-11-27 17:55:04.094497 | 
2025-11-27 17:55:04.094524 | PLAY RECAP
2025-11-27 17:55:04.094565 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2025-11-27 17:55:04.094585 | 
2025-11-27 17:55:04.204535 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-11-27 17:55:04.207147 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-11-27 17:55:04.775027 | 
2025-11-27 17:55:04.775180 | PLAY [all]
2025-11-27 17:55:04.795130 | 
2025-11-27 17:55:04.795207 | TASK [include_role : fetch-output]
2025-11-27 17:55:04.826559 | controller | ok
2025-11-27 17:55:04.844070 | 
2025-11-27 17:55:04.844154 | TASK [fetch-output : Set log path for multiple nodes]
2025-11-27 17:55:04.891136 | controller | skipping: Conditional result was False
2025-11-27 17:55:04.897432 | 
2025-11-27 17:55:04.897523 | TASK [fetch-output : Set log path for single node]
2025-11-27 17:55:04.929385 | controller | ok
2025-11-27 17:55:04.934637 | 
2025-11-27 17:55:04.934725 | LOOP [fetch-output : Ensure local output dirs]
2025-11-27 17:55:05.268993 | controller -> localhost | ok: "/var/lib/zuul/builds/2adaea711d3b4eaa99992765d3967e56/work/logs"
2025-11-27 17:55:05.481112 | controller -> localhost | ok: "/var/lib/zuul/builds/2adaea711d3b4eaa99992765d3967e56/work/artifacts"
2025-11-27 17:55:05.680106 | controller -> localhost | ok: "/var/lib/zuul/builds/2adaea711d3b4eaa99992765d3967e56/work/docs"
2025-11-27 17:55:05.693029 | 
2025-11-27 17:55:05.693201 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-11-27 17:55:06.233959 | controller | ok
2025-11-27 17:55:06.234290 | controller | ok: All items complete
2025-11-27 17:55:06.234320 | 
2025-11-27 17:55:06.614956 | controller | ok
2025-11-27 17:55:07.002191 | controller | ok
2025-11-27 17:55:07.023242 | 
2025-11-27 17:55:07.023357 | TASK [include_role : fetch-output-openshift]
2025-11-27 17:55:07.048574 | controller | skipping: Conditional result was False
2025-11-27 17:55:07.054730 | 
2025-11-27 17:55:07.054806 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-11-27 17:55:07.449010 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.007321
2025-11-27 17:55:07.654878 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.009393
2025-11-27 17:55:07.684893 | 
2025-11-27 17:55:07.685022 | PLAY [all]
2025-11-27 17:55:07.698889 | 
2025-11-27 17:55:07.698976 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-11-27 17:55:08.134799 | controller | changed
2025-11-27 17:55:08.156302 | 
2025-11-27 17:55:08.156360 | PLAY RECAP
2025-11-27 17:55:08.156404 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-11-27 17:55:08.156426 | 
2025-11-27 17:55:08.252511 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-11-27 17:55:08.253544 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-11-27 17:55:08.877323 | 
2025-11-27 17:55:08.877483 | PLAY [localhost]
2025-11-27 17:55:08.896244 | 
2025-11-27 17:55:08.896338 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-11-27 17:55:09.235499 | localhost | changed
2025-11-27 17:55:09.240729 | 
2025-11-27 17:55:09.240819 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-11-27 17:55:09.271714 | localhost | ok
2025-11-27 17:55:09.279955 | 
2025-11-27 17:55:09.280040 | TASK [add-fileserver : Create SSH private key tempfile]
2025-11-27 17:55:09.603937 | localhost | changed
2025-11-27 17:55:09.610814 | 
2025-11-27 17:55:09.610897 | TASK [add-fileserver : Create SSH private key from secret]
2025-11-27 17:55:10.208813 | localhost | changed
2025-11-27 17:55:10.213882 | 
2025-11-27 17:55:10.213973 | TASK [add-fileserver : Add fileserver ssh key]
2025-11-27 17:55:10.568797 | localhost | Identity added: /var/lib/zuul/builds/2adaea711d3b4eaa99992765d3967e56/work/tmp/ansible.9z9b5_tw (/var/lib/zuul/builds/2adaea711d3b4eaa99992765d3967e56/work/tmp/ansible.9z9b5_tw)
2025-11-27 17:55:10.569035 | localhost | ok: Runtime: 0:00:00.008977
2025-11-27 17:55:10.573494 | 
2025-11-27 17:55:10.573566 | TASK [add-fileserver : Remove SSH private key from disk]
2025-11-27 17:55:10.827499 | localhost | ok: Runtime: 0:00:00.006644
2025-11-27 17:55:10.833209 | 
2025-11-27 17:55:10.833279 | TASK [add-fileserver : Add fileserver to inventory]
2025-11-27 17:55:10.883218 | localhost | changed
2025-11-27 17:55:10.887164 | 
2025-11-27 17:55:10.887225 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-11-27 17:55:11.251427 | localhost | changed
2025-11-27 17:55:11.271388 | 
2025-11-27 17:55:11.271447 | PLAY [localhost]
2025-11-27 17:55:11.282841 | 
2025-11-27 17:55:11.282952 | TASK [Generate bulk log download script]
2025-11-27 17:55:11.304569 | localhost | ok
2025-11-27 17:55:11.316536 | 
2025-11-27 17:55:11.316627 | TASK [local-log-download : Check API endpoint is defined]
2025-11-27 17:55:11.348267 | localhost | ok: All assertions passed
2025-11-27 17:55:11.353032 | 
2025-11-27 17:55:11.353102 | TASK [local-log-download : Create download script]
2025-11-27 17:55:11.743705 | localhost -> localhost | changed
2025-11-27 17:55:11.753852 | 
2025-11-27 17:55:11.753987 | TASK [Register quick-download link]
2025-11-27 17:55:11.778998 | localhost | ok
2025-11-27 17:55:11.822966 | 
2025-11-27 17:55:11.823042 | PLAY [logserver.rdoproject.org]
2025-11-27 17:55:11.832440 | 
2025-11-27 17:55:11.832500 | TASK [Set zuul-log-path fact]
2025-11-27 17:55:11.849217 | logserver.rdoproject.org | ok
2025-11-27 17:55:11.858324 | 
2025-11-27 17:55:11.858392 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-11-27 17:55:11.885377 | logserver.rdoproject.org | ok
2025-11-27 17:55:11.890864 | 
2025-11-27 17:55:11.890951 | TASK [upload-logs : Create log directories]
2025-11-27 17:55:13.607212 | logserver.rdoproject.org | changed
2025-11-27 17:55:13.611008 | 
2025-11-27 17:55:13.611082 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-11-27 17:55:13.854198 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.006636
2025-11-27 17:55:13.859124 | 
2025-11-27 17:55:13.859185 | TASK [upload-logs : Upload logs to log server]
2025-11-27 17:55:15.342964 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-11-27 17:55:15.345792 | 
2025-11-27 17:55:15.345862 | LOOP [upload-logs : Compress console log and json output]
2025-11-27 17:55:15.383505 | logserver.rdoproject.org | skipping: Conditional result was False
2025-11-27 17:55:15.391677 | logserver.rdoproject.org | skipping: Conditional result was False
2025-11-27 17:55:15.404018 | 
2025-11-27 17:55:15.404143 | LOOP [upload-logs : Upload compressed console log and json output]
2025-11-27 17:55:15.438906 | logserver.rdoproject.org | skipping: Conditional result was False
2025-11-27 17:55:15.439133 | 
2025-11-27 17:55:15.442391 | logserver.rdoproject.org | skipping: Conditional result was False
2025-11-27 17:55:15.451248 | 
2025-11-27 17:55:15.451340 | LOOP [upload-logs : Upload console log and json output]