2025-11-25 14:01:46.099196 | Job console starting...
2025-11-25 14:01:46.320858 | Updating repositories
2025-11-25 14:01:47.144335 | Preparing job workspace
2025-11-25 14:01:57.530325 | Running Ansible setup...
2025-11-25 14:02:02.691855 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-11-25 14:02:05.230270 | 
2025-11-25 14:02:05.230396 | PLAY [localhost]
2025-11-25 14:02:05.250865 | 
2025-11-25 14:02:05.250953 | TASK [Gathering Facts]
2025-11-25 14:02:07.569818 | localhost | ok
2025-11-25 14:02:07.599013 | 
2025-11-25 14:02:07.599099 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-11-25 14:02:08.854336 | localhost -> localhost | changed
2025-11-25 14:02:08.862372 | 
2025-11-25 14:02:08.862452 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-11-25 14:02:10.918403 | localhost -> localhost | changed
2025-11-25 14:02:10.926433 | 
2025-11-25 14:02:10.926501 | TASK [Setup log path fact]
2025-11-25 14:02:10.959589 | localhost | ok
2025-11-25 14:02:10.976680 | 
2025-11-25 14:02:10.976747 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-11-25 14:02:11.030091 | localhost | ok
2025-11-25 14:02:11.037485 | 
2025-11-25 14:02:11.037550 | TASK [emit-job-header : Print job information]
2025-11-25 14:02:11.204530 | # Job Information
2025-11-25 14:02:11.204782 | Ansible Version: 2.15.12
2025-11-25 14:02:11.204815 | Job: cifmw-molecule-dnsmasq
2025-11-25 14:02:11.204838 | Pipeline: github-check
2025-11-25 14:02:11.204858 | Executor: ibm-bm4-ze.softwarefactory-project.io
2025-11-25 14:02:11.204885 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3505
2025-11-25 14:02:11.205045 | Log URL (when completed): https://logserver.rdoproject.org/69c/rdoproject.org/69c19b2c62874aa0a2269ecdfe1f8840/
2025-11-25 14:02:11.205083 | Event ID: 1ddbeee0-ca07-11f0-9bf4-54479d946bd8
2025-11-25 14:02:11.209138 | 
2025-11-25 14:02:11.209211 | LOOP [emit-job-header : Print node information]
2025-11-25 14:02:11.587389 | localhost | ok:
2025-11-25 14:02:11.587602 | localhost | # Node Information
2025-11-25 14:02:11.587633 | localhost | Inventory Hostname: controller
2025-11-25 14:02:11.587657 | localhost | Hostname: np0005535270
2025-11-25 14:02:11.587677 | localhost | Username: zuul
2025-11-25 14:02:11.587699 | localhost | Distro: CentOS 9
2025-11-25 14:02:11.587717 | localhost | Provider: ibm-bm4-nodepool
2025-11-25 14:02:11.587735 | localhost | Region: regionOne
2025-11-25 14:02:11.587752 | localhost | Label: cloud-centos-9-stream-tripleo-ibm
2025-11-25 14:02:11.587769 | localhost | Product Name: OpenStack Compute
2025-11-25 14:02:11.587786 | localhost | Interface IP: 192.168.26.182
2025-11-25 14:02:11.621624 | 
2025-11-25 14:02:11.621882 | PLAY [all]
2025-11-25 14:02:11.648217 | 
2025-11-25 14:02:11.648293 | TASK [Gather network facts]
2025-11-25 14:02:12.064825 | controller | ok
2025-11-25 14:02:12.112600 | 
2025-11-25 14:02:12.112730 | TASK [include_role : start-zuul-console]
2025-11-25 14:02:12.178209 | controller | ok
2025-11-25 14:02:12.205390 | 
2025-11-25 14:02:12.205471 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-11-25 14:02:12.912571 | controller | ok
2025-11-25 14:02:12.931761 | 
2025-11-25 14:02:12.931834 | TASK [include_role : add-build-sshkey]
2025-11-25 14:02:12.973198 | controller | ok
2025-11-25 14:02:12.996701 | 
2025-11-25 14:02:12.996773 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-11-25 14:02:13.640378 | controller -> localhost | ok
2025-11-25 14:02:13.651736 | 
2025-11-25 14:02:13.651810 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-11-25 14:02:13.712428 | controller | ok
2025-11-25 14:02:13.745486 | controller | included: /var/lib/zuul/builds/69c19b2c62874aa0a2269ecdfe1f8840/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-11-25 14:02:13.758677 | 
2025-11-25 14:02:13.758742 | TASK [add-build-sshkey : Create Temp SSH key]
2025-11-25 14:02:15.437255 | controller -> localhost | Generating public/private rsa key pair.
2025-11-25 14:02:15.437459 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/69c19b2c62874aa0a2269ecdfe1f8840/work/69c19b2c62874aa0a2269ecdfe1f8840_id_rsa.
2025-11-25 14:02:15.437492 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/69c19b2c62874aa0a2269ecdfe1f8840/work/69c19b2c62874aa0a2269ecdfe1f8840_id_rsa.pub.
2025-11-25 14:02:15.437516 | controller -> localhost | The key fingerprint is:
2025-11-25 14:02:15.437537 | controller -> localhost | SHA256:4BkNCKiB2XeJDUzm7kijq7LvWmjaqHsJBkGUW4gAQo8 zuul-build-sshkey
2025-11-25 14:02:15.437556 | controller -> localhost | The key's randomart image is:
2025-11-25 14:02:15.437574 | controller -> localhost | +---[RSA 3072]----+
2025-11-25 14:02:15.437591 | controller -> localhost | |%B+++=..         |
2025-11-25 14:02:15.437610 | controller -> localhost | |Oo++= +o         |
2025-11-25 14:02:15.437626 | controller -> localhost | |.Eoo..o .        |
2025-11-25 14:02:15.437645 | controller -> localhost | |o. . . +         |
2025-11-25 14:02:15.437662 | controller -> localhost | |. o . o S        |
2025-11-25 14:02:15.437680 | controller -> localhost | |.= +             |
2025-11-25 14:02:15.437697 | controller -> localhost | |+oo..            |
2025-11-25 14:02:15.437716 | controller -> localhost | |+=o              |
2025-11-25 14:02:15.437735 | controller -> localhost | |&B+              |
2025-11-25 14:02:15.437752 | controller -> localhost | +----[SHA256]-----+
2025-11-25 14:02:15.437796 | controller -> localhost | ok: Runtime: 0:00:00.275634
2025-11-25 14:02:15.443379 | 
2025-11-25 14:02:15.443442 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-11-25 14:02:15.512579 | controller | ok
2025-11-25 14:02:15.527964 | controller | included: /var/lib/zuul/builds/69c19b2c62874aa0a2269ecdfe1f8840/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-11-25 14:02:15.564768 | 
2025-11-25 14:02:15.564866 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-11-25 14:02:15.614396 | controller | skipping: Conditional result was False
2025-11-25 14:02:15.620025 | 
2025-11-25 14:02:15.620094 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-11-25 14:02:16.293336 | controller | changed
2025-11-25 14:02:16.298825 | 
2025-11-25 14:02:16.298895 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-11-25 14:02:16.606159 | controller | ok
2025-11-25 14:02:16.615118 | 
2025-11-25 14:02:16.621322 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-11-25 14:02:17.539748 | controller | changed
2025-11-25 14:02:17.552185 | 
2025-11-25 14:02:17.554974 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-11-25 14:02:18.378183 | controller | changed
2025-11-25 14:02:18.383154 | 
2025-11-25 14:02:18.383220 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-11-25 14:02:18.447277 | controller | skipping: Conditional result was False
2025-11-25 14:02:18.452823 | 
2025-11-25 14:02:18.452886 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-11-25 14:02:19.933305 | controller -> localhost | changed
2025-11-25 14:02:19.945447 | 
2025-11-25 14:02:19.945519 | TASK [add-build-sshkey : Add back temp key]
2025-11-25 14:02:21.385085 | controller -> localhost | Identity added: /var/lib/zuul/builds/69c19b2c62874aa0a2269ecdfe1f8840/work/69c19b2c62874aa0a2269ecdfe1f8840_id_rsa (zuul-build-sshkey)
2025-11-25 14:02:21.385329 | controller -> localhost | ok: Runtime: 0:00:00.074234
2025-11-25 14:02:21.391947 | 
2025-11-25 14:02:21.392019 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-11-25 14:02:22.374345 | controller | ok
2025-11-25 14:02:22.379111 | 
2025-11-25 14:02:22.379187 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-11-25 14:02:22.486219 | controller | skipping: Conditional result was False
2025-11-25 14:02:22.502624 | 
2025-11-25 14:02:22.502691 | TASK [include_role : validate-host]
2025-11-25 14:02:22.762001 | controller | ok
2025-11-25 14:02:22.868816 | 
2025-11-25 14:02:22.868958 | TASK [validate-host : Define zuul_info_dir fact]
2025-11-25 14:02:23.027329 | controller | ok
2025-11-25 14:02:23.053220 | 
2025-11-25 14:02:23.053313 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-11-25 14:02:24.165973 | controller -> localhost | ok
2025-11-25 14:02:24.180922 | 
2025-11-25 14:02:24.180991 | TASK [validate-host : Collect information about the host]
2025-11-25 14:02:24.817388 | controller | ok
2025-11-25 14:02:24.842528 | 
2025-11-25 14:02:24.842605 | TASK [validate-host : Sanitize hostname]
2025-11-25 14:02:25.017472 | controller | ok
2025-11-25 14:02:25.022478 | 
2025-11-25 14:02:25.022540 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-11-25 14:02:26.992446 | controller -> localhost | changed
2025-11-25 14:02:26.998219 | 
2025-11-25 14:02:26.998297 | TASK [validate-host : Collect information about zuul worker]
2025-11-25 14:02:27.514785 | controller | ok
2025-11-25 14:02:27.530199 | 
2025-11-25 14:02:27.530288 | TASK [validate-host : Write out all zuul information for each host]
2025-11-25 14:02:29.379288 | controller -> localhost | changed
2025-11-25 14:02:29.392882 | 
2025-11-25 14:02:29.392975 | TASK [include_role : prepare-workspace-openshift]
2025-11-25 14:02:29.468774 | controller | skipping: Conditional result was False
2025-11-25 14:02:29.475155 | 
2025-11-25 14:02:29.475225 | TASK [include_role : remove-zuul-sshkey]
2025-11-25 14:02:29.561101 | controller | skipping: Conditional result was False
2025-11-25 14:02:29.567660 | 
2025-11-25 14:02:29.567733 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-11-25 14:02:29.914100 | controller | ok: "logs"
2025-11-25 14:02:29.914369 | controller | ok: All items complete
2025-11-25 14:02:29.914400 | 
2025-11-25 14:02:30.104277 | controller | ok: "artifacts"
2025-11-25 14:02:30.286361 | controller | ok: "docs"
2025-11-25 14:02:30.294844 | 
2025-11-25 14:02:30.295040 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-11-25 14:02:30.636754 | controller | changed: "logs"
2025-11-25 14:02:30.808306 | controller | changed: "artifacts"
2025-11-25 14:02:30.995808 | controller | changed: "docs"
2025-11-25 14:02:31.142411 | 
2025-11-25 14:02:31.142507 | PLAY RECAP
2025-11-25 14:02:31.142556 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-11-25 14:02:31.142586 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-11-25 14:02:31.142605 | 
2025-11-25 14:02:31.367342 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-11-25 14:02:31.368165 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-11-25 14:02:33.802619 | 
2025-11-25 14:02:33.802725 | PLAY [all]
2025-11-25 14:02:33.886489 | 
2025-11-25 14:02:33.886589 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-11-25 14:02:34.057293 | controller | ok
2025-11-25 14:02:34.062579 | 
2025-11-25 14:02:34.062677 | TASK [mirror-info-fork : Create /etc/ci]
2025-11-25 14:02:34.924650 | controller | changed
2025-11-25 14:02:34.945260 | 
2025-11-25 14:02:34.945361 | TASK [mirror-info-fork : Install ci_mirror script]
2025-11-25 14:02:36.932773 | controller | changed
2025-11-25 14:02:37.004987 | 
2025-11-25 14:02:37.005172 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-11-25 14:02:37.520517 | controller | changed:
2025-11-25 14:02:37.520696 | controller | {
2025-11-25 14:02:37.520730 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-11-25 14:02:37.520764 | controller | }
2025-11-25 14:02:37.752553 | controller | changed:
2025-11-25 14:02:37.752653 | controller | {
2025-11-25 14:02:37.752682 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-11-25 14:02:37.752704 | controller | }
2025-11-25 14:02:37.978619 | controller | changed:
2025-11-25 14:02:37.978730 | controller | {
2025-11-25 14:02:37.978765 | 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-25 14:02:37.978793 | controller | }
2025-11-25 14:02:38.207413 | controller | changed:
2025-11-25 14:02:38.207521 | controller | {
2025-11-25 14:02:38.207549 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-11-25 14:02:38.207571 | controller | }
2025-11-25 14:02:38.455600 | controller | changed:
2025-11-25 14:02:38.455714 | controller | {
2025-11-25 14:02:38.455742 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-11-25 14:02:38.455764 | controller | }
2025-11-25 14:02:38.686374 | controller | changed:
2025-11-25 14:02:38.690027 | controller | {
2025-11-25 14:02:38.690082 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-11-25 14:02:38.690110 | controller | }
2025-11-25 14:02:38.937673 | controller | changed:
2025-11-25 14:02:38.937757 | controller | {
2025-11-25 14:02:38.937785 | 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-25 14:02:38.937808 | controller | }
2025-11-25 14:02:39.164552 | controller | changed:
2025-11-25 14:02:39.164651 | controller | {
2025-11-25 14:02:39.164688 | 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-25 14:02:39.164712 | controller | }
2025-11-25 14:02:39.515850 | controller | changed:
2025-11-25 14:02:39.515959 | controller | {
2025-11-25 14:02:39.515990 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-11-25 14:02:39.516012 | controller | }
2025-11-25 14:02:39.620813 | controller | changed:
2025-11-25 14:02:39.620894 | controller | {
2025-11-25 14:02:39.621565 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-11-25 14:02:39.622618 | controller | }
2025-11-25 14:02:39.820311 | controller | changed:
2025-11-25 14:02:39.820394 | controller | {
2025-11-25 14:02:39.820421 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-11-25 14:02:39.820441 | controller | }
2025-11-25 14:02:40.023626 | controller | changed:
2025-11-25 14:02:40.023708 | controller | {
2025-11-25 14:02:40.023735 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-11-25 14:02:40.023755 | controller | }
2025-11-25 14:02:40.242711 | controller | changed:
2025-11-25 14:02:40.242802 | controller | {
2025-11-25 14:02:40.242829 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-11-25 14:02:40.242850 | controller | }
2025-11-25 14:02:40.457664 | controller | changed:
2025-11-25 14:02:40.457747 | controller | {
2025-11-25 14:02:40.457805 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-11-25 14:02:40.457833 | controller | }
2025-11-25 14:02:40.681854 | controller | changed:
2025-11-25 14:02:40.681948 | controller | {
2025-11-25 14:02:40.681977 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-11-25 14:02:40.681998 | controller | }
2025-11-25 14:02:40.900572 | controller | changed:
2025-11-25 14:02:40.900765 | controller | {
2025-11-25 14:02:40.900798 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-11-25 14:02:40.900820 | controller | }
2025-11-25 14:02:41.113133 | controller | changed:
2025-11-25 14:02:41.113212 | controller | {
2025-11-25 14:02:41.113246 | 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-25 14:02:41.113270 | controller | }
2025-11-25 14:02:41.334829 | controller | changed:
2025-11-25 14:02:41.334993 | controller | {
2025-11-25 14:02:41.335025 | 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-25 14:02:41.335055 | controller | }
2025-11-25 14:02:41.582298 | controller | changed:
2025-11-25 14:02:41.582383 | controller | {
2025-11-25 14:02:41.582409 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-11-25 14:02:41.582429 | controller | }
2025-11-25 14:02:41.789478 | controller | changed:
2025-11-25 14:02:41.789573 | controller | {
2025-11-25 14:02:41.789601 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-11-25 14:02:41.789622 | controller | }
2025-11-25 14:02:42.010051 | controller | changed:
2025-11-25 14:02:42.010187 | controller | {
2025-11-25 14:02:42.010217 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-11-25 14:02:42.010249 | controller | }
2025-11-25 14:02:42.247084 | controller | changed:
2025-11-25 14:02:42.247175 | controller | {
2025-11-25 14:02:42.247201 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-11-25 14:02:42.247222 | controller | }
2025-11-25 14:02:42.483033 | controller | changed:
2025-11-25 14:02:42.483116 | controller | {
2025-11-25 14:02:42.483142 | 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-25 14:02:42.483164 | controller | }
2025-11-25 14:02:42.712049 | controller | changed:
2025-11-25 14:02:42.712134 | controller | {
2025-11-25 14:02:42.712160 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-11-25 14:02:42.712182 | controller | }
2025-11-25 14:02:42.925540 | controller | changed:
2025-11-25 14:02:42.925615 | controller | {
2025-11-25 14:02:42.925642 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-11-25 14:02:42.925662 | controller | }
2025-11-25 14:02:43.140077 | controller | changed:
2025-11-25 14:02:43.140184 | controller | {
2025-11-25 14:02:43.140210 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-11-25 14:02:43.140230 | controller | }
2025-11-25 14:02:43.179217 | 
2025-11-25 14:02:43.179396 | TASK [Set timezone to UTC]
2025-11-25 14:02:44.258641 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-11-25 14:02:44.266628 | 
2025-11-25 14:02:44.266722 | TASK [Create nodepool directory]
2025-11-25 14:02:44.513725 | controller | changed
2025-11-25 14:02:44.520802 | 
2025-11-25 14:02:44.520868 | TASK [Create nodepool sub_nodes file]
2025-11-25 14:02:45.230214 | controller | changed
2025-11-25 14:02:45.236699 | 
2025-11-25 14:02:45.236776 | TASK [Create nodepool sub_nodes_private file]
2025-11-25 14:02:45.982754 | controller | changed
2025-11-25 14:02:46.021937 | 
2025-11-25 14:02:46.022069 | LOOP [Populate nodepool sub_nodes file]
2025-11-25 14:02:46.107885 | 
2025-11-25 14:02:46.108054 | LOOP [Populate nodepool sub_nodes_private file]
2025-11-25 14:02:46.192341 | 
2025-11-25 14:02:46.192489 | TASK [Create nodepool primary file]
2025-11-25 14:02:46.246875 | controller | skipping: Conditional result was False
2025-11-25 14:02:46.252579 | 
2025-11-25 14:02:46.252655 | TASK [Create nodepool node_private for this node]
2025-11-25 14:02:47.056399 | controller | changed
2025-11-25 14:02:47.074467 | 
2025-11-25 14:02:47.074598 | LOOP [Copy ssh keys to nodepool directory]
2025-11-25 14:02:48.101540 | controller | ok: Item: id_rsa Runtime: 0:00:00.003737
2025-11-25 14:02:48.101730 | 
2025-11-25 14:02:48.281179 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.004497
2025-11-25 14:02:48.291855 | 
2025-11-25 14:02:48.292000 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-11-25 14:02:49.035802 | controller | changed
2025-11-25 14:02:49.054963 | 
2025-11-25 14:02:49.055029 | TASK [Validate sudoers config after edits]
2025-11-25 14:02:49.472515 | controller | /etc/sudoers: parsed OK
2025-11-25 14:02:49.473556 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-11-25 14:02:49.473584 | controller | /etc/sudoers.d/zuul: parsed OK
2025-11-25 14:02:49.473591 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-11-25 14:02:49.711649 | controller | ok: Runtime: 0:00:00.006100
2025-11-25 14:02:49.717638 | 
2025-11-25 14:02:49.717705 | TASK [Show the environment passed in to job shell scripts]
2025-11-25 14:02:50.204252 | controller | SHELL=/bin/bash
2025-11-25 14:02:50.204288 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-11-25 14:02:50.204298 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-11-25 14:02:50.204304 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/05/3505/aecb71877114ccffda079e1e34f306d7ec6e9421
2025-11-25 14:02:50.204310 | controller | PWD=/home/zuul
2025-11-25 14:02:50.204316 | controller | ZUUL_PIPELINE=github-check
2025-11-25 14:02:50.204321 | controller | LOGNAME=zuul
2025-11-25 14:02:50.204349 | controller | XDG_SESSION_TYPE=tty
2025-11-25 14:02:50.204356 | controller | _=/usr/bin/env
2025-11-25 14:02:50.204361 | controller | MOTD_SHOWN=pam
2025-11-25 14:02:50.204366 | controller | HOME=/home/zuul
2025-11-25 14:02:50.204372 | controller | LANG=en_US.UTF-8
2025-11-25 14:02:50.204377 | controller | SSH_CONNECTION=192.168.26.12 55054 192.168.26.182 22
2025-11-25 14:02:50.204382 | 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-25 14:02:50.204390 | controller | ZUUL_CHANGE_IDS=3505,aecb71877114ccffda079e1e34f306d7ec6e9421
2025-11-25 14:02:50.204395 | controller | WORKSPACE=/home/zuul/workspace
2025-11-25 14:02:50.204401 | controller | XDG_SESSION_CLASS=user
2025-11-25 14:02:50.204406 | controller | SELINUX_ROLE_REQUESTED=
2025-11-25 14:02:50.204411 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-11-25 14:02:50.204416 | controller | USER=zuul
2025-11-25 14:02:50.204421 | controller | ZUUL_VOTING=True
2025-11-25 14:02:50.204427 | controller | BUILD_TIMEOUT=1800000
2025-11-25 14:02:50.204432 | controller | SELINUX_USE_CURRENT_RANGE=
2025-11-25 14:02:50.204437 | controller | SHLVL=1
2025-11-25 14:02:50.204442 | controller | ZUUL_PATCHSET=aecb71877114ccffda079e1e34f306d7ec6e9421
2025-11-25 14:02:50.204447 | controller | XDG_SESSION_ID=1
2025-11-25 14:02:50.204452 | controller | ZUUL_BRANCH=main
2025-11-25 14:02:50.204458 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-11-25 14:02:50.204463 | controller | SSH_CLIENT=192.168.26.12 55054 22
2025-11-25 14:02:50.204469 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-11-25 14:02:50.204475 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-11-25 14:02:50.204480 | controller | which_declare=declare -f
2025-11-25 14:02:50.204485 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2025-11-25 14:02:50.204490 | controller | SELINUX_LEVEL_REQUESTED=
2025-11-25 14:02:50.204495 | controller | ZUUL_CHANGE=3505
2025-11-25 14:02:50.204501 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-11-25 14:02:50.204506 | controller | ZUUL_UUID=69c19b2c62874aa0a2269ecdfe1f8840
2025-11-25 14:02:50.204512 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-11-25 14:02:50.204517 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-11-25 14:02:50.204523 | controller | }
2025-11-25 14:02:50.303739 | controller | ok: Runtime: 0:00:00.005031
2025-11-25 14:02:50.309614 | 
2025-11-25 14:02:50.309682 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-11-25 14:02:50.409727 | controller | skipping: Conditional result was False
2025-11-25 14:02:50.417049 | 
2025-11-25 14:02:50.417119 | TASK [Symlink /home/zuul-worker/workspace]
2025-11-25 14:02:51.166477 | controller | skipping: Conditional result was False
2025-11-25 14:02:51.173410 | 
2025-11-25 14:02:51.173523 | TASK [Ensure legacy workspace directory]
2025-11-25 14:02:51.480310 | controller | changed
2025-11-25 14:02:51.526443 | 
2025-11-25 14:02:51.526522 | PLAY RECAP
2025-11-25 14:02:51.526568 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-11-25 14:02:51.526592 | 
2025-11-25 14:02:51.894523 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-11-25 14:02:51.895355 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-11-25 14:02:54.360122 | 
2025-11-25 14:02:54.360286 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-11-25 14:02:54.485677 | 
2025-11-25 14:02:54.485813 | TASK [Create zuul-output directory]
2025-11-25 14:02:55.459979 | controller | changed
2025-11-25 14:02:55.476118 | 
2025-11-25 14:02:55.476200 | TASK [Slurp Zuul inventory test]
2025-11-25 14:02:56.774584 | controller -> localhost | ok
2025-11-25 14:02:56.781346 | 
2025-11-25 14:02:56.781415 | TASK [Save zuul inventory]
2025-11-25 14:02:58.762016 | controller | changed
2025-11-25 14:02:58.769332 | 
2025-11-25 14:02:58.769405 | TASK [Save zuul vars without the change_message]
2025-11-25 14:02:59.698616 | controller | changed
2025-11-25 14:02:59.778502 | 
2025-11-25 14:02:59.778606 | PLAY RECAP
2025-11-25 14:02:59.778651 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-11-25 14:02:59.778675 | 
2025-11-25 14:03:00.114482 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-11-25 14:03:00.115253 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-11-25 14:03:01.745485 | 
2025-11-25 14:03:01.745604 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2025-11-25 14:03:01.821458 | 
2025-11-25 14:03:01.821566 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2025-11-25 14:03:01.878196 | controller | ok
2025-11-25 14:03:01.931868 | 
2025-11-25 14:03:01.932029 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-11-25 14:03:02.064383 | controller | skipping: Conditional result was False
2025-11-25 14:03:02.070382 | 
2025-11-25 14:03:02.070454 | TASK [mirror-info-fork : Create /etc/ci]
2025-11-25 14:03:02.646551 | controller | ok
2025-11-25 14:03:02.659882 | 
2025-11-25 14:03:02.659981 | TASK [mirror-info-fork : Install ci_mirror script]
2025-11-25 14:03:03.578518 | controller | ok
2025-11-25 14:03:03.595689 | 
2025-11-25 14:03:03.595758 | TASK [Prepare workspace]
2025-11-25 14:03:03.681930 | controller | ok
2025-11-25 14:03:03.772646 | 
2025-11-25 14:03:03.772758 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-11-25 14:03:04.567504 | controller | ok
2025-11-25 14:03:04.589147 | 
2025-11-25 14:03:04.589295 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-11-25 14:03:10.938067 | controller | Output suppressed because no_log was given
2025-11-25 14:03:10.947684 | 
2025-11-25 14:03:10.947753 | LOOP [Create zuul-output directory]
2025-11-25 14:03:11.200668 | controller | changed: "/home/zuul/ci-framework-data/logs"
2025-11-25 14:03:11.355157 | controller | ok: "/home/zuul/zuul-output/logs"
2025-11-25 14:03:11.364707 | 
2025-11-25 14:03:11.364813 | TASK [Install required packages]
2025-11-25 14:03:59.629708 | controller | changed
2025-11-25 14:03:59.635149 | 
2025-11-25 14:03:59.635219 | TASK [Install venv]
2025-11-25 14:05:03.449228 | controller | changed
2025-11-25 14:05:03.473750 | 
2025-11-25 14:05:03.473815 | PLAY RECAP
2025-11-25 14:05:03.473857 | controller | ok: 7 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-11-25 14:05:03.473880 | 
2025-11-25 14:05:03.545733 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-11-25 14:05:03.546594 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-11-25 14:05:04.069576 | 
2025-11-25 14:05:04.069673 | PLAY [Run ci/playbooks/molecule-test.yml]
2025-11-25 14:05:04.090452 | 
2025-11-25 14:05:04.090534 | TASK [Gather required facts]
2025-11-25 14:05:04.565938 | controller | ok
2025-11-25 14:05:04.571276 | 
2025-11-25 14:05:04.571371 | TASK [Load environment var if instructed to]
2025-11-25 14:05:04.595227 | controller | skipping: Conditional result was False
2025-11-25 14:05:04.600426 | 
2025-11-25 14:05:04.600492 | TASK [Ensure group_vars dir exists]
2025-11-25 14:05:04.912300 | controller | ok
2025-11-25 14:05:04.918267 | 
2025-11-25 14:05:04.918352 | TASK [Print related variables]
2025-11-25 14:05:04.946776 | controller | ok:
2025-11-25 14:05:04.946884 | controller | mol_config_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config_local.yml
2025-11-25 14:05:04.946931 | controller | roles_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/dnsmasq
2025-11-25 14:05:04.951579 | 
2025-11-25 14:05:04.951642 | TASK [Run molecule]
2025-11-25 14:05:05.875093 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2025-11-25 14:05:05.945088 | controller | INFO     Performing prerun with role_name_check=0...
2025-11-25 14:05:22.628461 | 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-25 14:05:22.628908 | 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-25 14:05:22.629386 | 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-25 14:05:22.629840 | 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-25 14:05:22.630339 | controller | WARNING  Another version of 'cifmw.general' 1.0.0+0b589e62 was found installed in /home/zuul/.cache/ansible-compat/e24347/collections/ansible_collections, only the first one will be used, 1.0.0+0b589e62 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-25 14:05:22.630790 | 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-25 14:05:22.631262 | 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-25 14:05:22.631717 | 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-25 14:05:22.632204 | 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-25 14:05:22.632641 | 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-25 14:05:22.633134 | 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-25 14:05:22.633578 | 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-25 14:05:22.634032 | 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-25 14:05:22.634490 | 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-25 14:05:22.634945 | 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-25 14:05:22.644589 | controller | INFO     Running default > prepare
2025-11-25 14:05:23.292872 | controller |
2025-11-25 14:05:23.292953 | controller | PLAY [Prepare] *****************************************************************
2025-11-25 14:05:23.293080 | controller |
2025-11-25 14:05:23.293185 | controller | TASK [Gathering Facts] *********************************************************
2025-11-25 14:05:23.293282 | controller | Tuesday 25 November 2025  14:05:23 +0000 (0:00:00.012)       0:00:00.012 ******
2025-11-25 14:05:24.131757 | controller | ok: [instance]
2025-11-25 14:05:24.131812 | controller |
2025-11-25 14:05:24.131950 | controller | TASK [test_deps : Gather facts if they don't exist] ****************************
2025-11-25 14:05:24.132082 | controller | Tuesday 25 November 2025  14:05:24 +0000 (0:00:00.839)       0:00:00.851 ******
2025-11-25 14:05:24.148383 | controller | skipping: [instance]
2025-11-25 14:05:24.148469 | controller |
2025-11-25 14:05:24.148626 | controller | TASK [test_deps : Gather variables for each operating system] ******************
2025-11-25 14:05:24.148728 | controller | Tuesday 25 November 2025  14:05:24 +0000 (0:00:00.016)       0:00:00.868 ******
2025-11-25 14:05:24.189295 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/test_deps/vars/centos-9.yml)
2025-11-25 14:05:24.189356 | controller |
2025-11-25 14:05:24.189476 | controller | TASK [test_deps : Detect mirrors file] *****************************************
2025-11-25 14:05:24.189596 | controller | Tuesday 25 November 2025  14:05:24 +0000 (0:00:00.040)       0:00:00.909 ******
2025-11-25 14:05:24.465873 | controller | ok: [instance]
2025-11-25 14:05:24.465951 | controller |
2025-11-25 14:05:24.466099 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] ***********
2025-11-25 14:05:24.466200 | controller | Tuesday 25 November 2025  14:05:24 +0000 (0:00:00.276)       0:00:01.186 ******
2025-11-25 14:05:24.775734 | controller | ok: [instance]
2025-11-25 14:05:24.775785 | controller |
2025-11-25 14:05:24.775909 | controller | TASK [test_deps : Disable ubi 9 repos] *****************************************
2025-11-25 14:05:24.776039 | controller | Tuesday 25 November 2025  14:05:24 +0000 (0:00:00.309)       0:00:01.495 ******
2025-11-25 14:05:25.457233 | controller | ok: [instance] => (item=ubi-9-appstream)
2025-11-25 14:05:25.457324 | controller | ok: [instance] => (item=ubi-9-baseos)
2025-11-25 14:05:25.457436 | controller | ok: [instance] => (item=ubi-9-codeready-builder)
2025-11-25 14:05:25.457535 | controller |
2025-11-25 14:05:25.457645 | controller | TASK [Deploy repo-setup] *******************************************************
2025-11-25 14:05:25.457760 | controller | Tuesday 25 November 2025  14:05:25 +0000 (0:00:00.681)       0:00:02.177 ******
2025-11-25 14:05:25.515573 | controller |
2025-11-25 14:05:25.515693 | controller | TASK [repo_setup : Ensure directories are present] *****************************
2025-11-25 14:05:25.515827 | controller | Tuesday 25 November 2025  14:05:25 +0000 (0:00:00.058)       0:00:02.235 ******
2025-11-25 14:05:26.042496 | controller | changed: [instance] => (item=tmp)
2025-11-25 14:05:26.042588 | controller | changed: [instance] => (item=artifacts/repositories)
2025-11-25 14:05:26.042698 | controller | changed: [instance] => (item=venv/repo_setup)
2025-11-25 14:05:26.042797 | controller |
2025-11-25 14:05:26.042918 | controller | TASK [repo_setup : Make sure git-core package is installed] ********************
2025-11-25 14:05:26.043025 | controller | Tuesday 25 November 2025  14:05:26 +0000 (0:00:00.526)       0:00:02.762 ******
2025-11-25 14:05:26.811536 | controller | ok: [instance]
2025-11-25 14:05:27.892123 | controller |
2025-11-25 14:05:27.892152 | controller | TASK [repo_setup : Get repo-setup repository] **********************************
2025-11-25 14:05:27.892160 | controller | Tuesday 25 November 2025  14:05:26 +0000 (0:00:00.768)       0:00:03.531 ******
2025-11-25 14:05:27.892170 | controller | changed: [instance]
2025-11-25 14:05:27.892192 | controller |
2025-11-25 14:05:27.892406 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************
2025-11-25 14:05:35.737782 | controller | Tuesday 25 November 2025  14:05:27 +0000 (0:00:01.081)       0:00:04.612 ******
2025-11-25 14:05:35.737819 | controller | changed: [instance]
2025-11-25 14:05:36.441127 | controller |
2025-11-25 14:05:36.441161 | controller | TASK [repo_setup : Install repo-setup package] *********************************
2025-11-25 14:05:36.441170 | controller | Tuesday 25 November 2025  14:05:35 +0000 (0:00:07.845)       0:00:12.457 ******
2025-11-25 14:05:36.441180 | controller | changed: [instance]
2025-11-25 14:05:36.441205 | controller |
2025-11-25 14:05:36.441460 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
2025-11-25 14:05:36.463309 | controller | Tuesday 25 November 2025  14:05:36 +0000 (0:00:00.703)       0:00:13.161 ******
2025-11-25 14:05:36.463340 | controller | skipping: [instance]
2025-11-25 14:05:37.748973 | controller |
2025-11-25 14:05:37.749004 | controller | TASK [repo_setup : Run repo-setup] *********************************************
2025-11-25 14:05:37.749012 | controller | Tuesday 25 November 2025  14:05:36 +0000 (0:00:00.022)       0:00:13.183 ******
2025-11-25 14:05:37.749022 | controller | changed: [instance]
2025-11-25 14:05:37.784353 | controller |
2025-11-25 14:05:37.784378 | controller | TASK [repo_setup : Get component repo] *****************************************
2025-11-25 14:05:37.784388 | controller | Tuesday 25 November 2025  14:05:37 +0000 (0:00:01.285)       0:00:14.468 ******
2025-11-25 14:05:37.784399 | controller | skipping: [instance]
2025-11-25 14:05:37.820432 | controller |
2025-11-25 14:05:37.820454 | controller | TASK [repo_setup : Rename component repo] **************************************
2025-11-25 14:05:37.820463 | controller | Tuesday 25 November 2025  14:05:37 +0000 (0:00:00.035)       0:00:14.504 ******
2025-11-25 14:05:37.820474 | controller | skipping: [instance]
2025-11-25 14:05:37.857550 | controller |
2025-11-25 14:05:37.857568 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
2025-11-25 14:05:37.857576 | controller | Tuesday 25 November 2025  14:05:37 +0000 (0:00:00.035)       0:00:14.539 ******
2025-11-25 14:05:37.857584 | controller | skipping: [instance]
2025-11-25 14:05:38.412830 | controller |
2025-11-25 14:05:38.412861 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************
2025-11-25 14:05:38.412869 | controller | Tuesday 25 November 2025  14:05:37 +0000 (0:00:00.037)       0:00:14.577 ******
2025-11-25 14:05:38.412879 | controller | changed: [instance]
2025-11-25 14:05:38.843907 | controller |
2025-11-25 14:05:38.843967 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
2025-11-25 14:05:38.843975 | controller | Tuesday 25 November 2025  14:05:38 +0000 (0:00:00.554)       0:00:15.132 ******
2025-11-25 14:05:38.843986 | controller | changed: [instance]
2025-11-25 14:05:38.872148 | controller |
2025-11-25 14:05:38.872176 | controller | TASK [repo_setup : Dump current-podified hash] *********************************
2025-11-25 14:05:38.872184 | controller | Tuesday 25 November 2025  14:05:38 +0000 (0:00:00.431)       0:00:15.563 ******
2025-11-25 14:05:38.872195 | controller | skipping: [instance]
2025-11-25 14:05:38.900043 | controller |
2025-11-25 14:05:38.900080 | controller | TASK [repo_setup : Slurp current podified hash] ********************************
2025-11-25 14:05:38.900089 | controller | Tuesday 25 November 2025  14:05:38 +0000 (0:00:00.028)       0:00:15.592 ******
2025-11-25 14:05:38.900100 | controller | skipping: [instance]
2025-11-25 14:05:38.928024 | controller |
2025-11-25 14:05:38.928045 | controller | TASK [repo_setup : Update the value of full_hash] ******************************
2025-11-25 14:05:38.928071 | controller | Tuesday 25 November 2025  14:05:38 +0000 (0:00:00.027)       0:00:15.620 ******
2025-11-25 14:05:38.928090 | controller | skipping: [instance]
2025-11-25 14:05:38.965384 | controller |
2025-11-25 14:05:38.965403 | controller | TASK [repo_setup : Export hashes facts for further use] ************************
2025-11-25 14:05:38.965411 | controller | Tuesday 25 November 2025  14:05:38 +0000 (0:00:00.027)       0:00:15.648 ******
2025-11-25 14:05:38.965422 | controller | ok: [instance]
2025-11-25 14:05:38.991608 | controller |
2025-11-25 14:05:38.991626 | controller | TASK [repo_setup : Create download directory] **********************************
2025-11-25 14:05:38.991632 | controller | Tuesday 25 November 2025  14:05:38 +0000 (0:00:00.037)       0:00:15.685 ******
2025-11-25 14:05:38.991642 | controller | skipping: [instance]
2025-11-25 14:05:39.017794 | controller |
2025-11-25 14:05:39.017817 | controller | TASK [repo_setup : Print the URL to request] ***********************************
2025-11-25 14:05:39.017826 | controller | Tuesday 25 November 2025  14:05:38 +0000 (0:00:00.026)       0:00:15.711 ******
2025-11-25 14:05:39.017844 | controller | skipping: [instance]
2025-11-25 14:05:39.043901 | controller |
2025-11-25 14:05:39.043941 | controller | TASK [Download the RPM] ********************************************************
2025-11-25 14:05:39.043949 | controller | Tuesday 25 November 2025  14:05:39 +0000 (0:00:00.026)       0:00:15.737 ******
2025-11-25 14:05:39.043958 | controller | skipping: [instance]
2025-11-25 14:05:39.070784 | controller |
2025-11-25 14:05:39.070803 | controller | TASK [repo_setup : Install RHOS Release tool] **********************************
2025-11-25 14:05:39.070811 | controller | Tuesday 25 November 2025  14:05:39 +0000 (0:00:00.026)       0:00:15.764 ******
2025-11-25 14:05:39.070820 | controller | skipping: [instance]
2025-11-25 14:05:39.099330 | controller |
2025-11-25 14:05:39.099348 | controller | TASK [repo_setup : Get rhos-release tool version] ******************************
2025-11-25 14:05:39.099355 | controller | Tuesday 25 November 2025  14:05:39 +0000 (0:00:00.026)       0:00:15.790 ******
2025-11-25 14:05:39.099364 | controller | skipping: [instance]
2025-11-25 14:05:39.156270 | controller |
2025-11-25 14:05:39.156316 | controller | TASK [repo_setup : Print rhos-release tool version] ****************************
2025-11-25 14:05:39.156327 | controller | Tuesday 25 November 2025  14:05:39 +0000 (0:00:00.028)       0:00:15.819 ******
2025-11-25 14:05:39.156340 | controller | skipping: [instance]
2025-11-25 14:05:39.156360 | controller |
2025-11-25 14:05:39.156370 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
2025-11-25 14:05:39.156466 | controller | Tuesday 25 November 2025  14:05:39 +0000 (0:00:00.057)       0:00:15.876 ******
2025-11-25 14:05:39.183431 | controller | skipping: [instance]
2025-11-25 14:05:39.363625 | controller |
2025-11-25 14:05:39.363653 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
2025-11-25 14:05:39.363661 | controller | Tuesday 25 November 2025  14:05:39 +0000 (0:00:00.026)       0:00:15.903 ******
2025-11-25 14:05:39.363671 | controller | ok: [instance]
2025-11-25 14:05:39.578148 | controller |
2025-11-25 14:05:39.578178 | controller | TASK [repo_setup : Use RDO proxy mirrors] **************************************
2025-11-25 14:05:39.578186 | controller | Tuesday 25 November 2025  14:05:39 +0000 (0:00:00.179)       0:00:16.083 ******
2025-11-25 14:05:39.578196 | controller | changed: [instance]
2025-11-25 14:05:39.802668 | controller |
2025-11-25 14:05:39.802696 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
2025-11-25 14:05:39.802704 | controller | Tuesday 25 November 2025  14:05:39 +0000 (0:00:00.214)       0:00:16.297 ******
2025-11-25 14:05:39.802714 | controller | changed: [instance]
2025-11-25 14:05:39.824164 | controller |
2025-11-25 14:05:39.824199 | controller | TASK [repo_setup : Check for gating.repo file on content provider] *************
2025-11-25 14:05:39.824209 | controller | Tuesday 25 November 2025  14:05:39 +0000 (0:00:00.224)       0:00:16.522 ******
2025-11-25 14:05:39.824222 | controller | skipping: [instance]
2025-11-25 14:05:39.846789 | controller |
2025-11-25 14:05:39.846816 | controller | TASK [repo_setup : Populate gating repo from content provider ip] **************
2025-11-25 14:05:39.846825 | controller | Tuesday 25 November 2025  14:05:39 +0000 (0:00:00.021)       0:00:16.544 ******
2025-11-25 14:05:39.846838 | controller | skipping: [instance]
2025-11-25 14:05:39.867910 | controller |
2025-11-25 14:05:39.867947 | controller | TASK [repo_setup : Check for DLRN repo at the destination] *********************
2025-11-25 14:05:39.867955 | controller | Tuesday 25 November 2025  14:05:39 +0000 (0:00:00.022)       0:00:16.566 ******
2025-11-25 14:05:39.867966 | controller | skipping: [instance]
2025-11-25 14:05:39.889140 | controller |
2025-11-25 14:05:39.889172 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
2025-11-25 14:05:39.889181 | controller | Tuesday 25 November 2025  14:05:39 +0000 (0:00:00.021)       0:00:16.587 ******
2025-11-25 14:05:39.889191 | controller | skipping: [instance]
2025-11-25 14:05:39.910832 | controller |
2025-11-25 14:05:39.910857 | controller | TASK [repo_setup : Check for DLRN component repo] ******************************
2025-11-25 14:05:39.910868 | controller | Tuesday 25 November 2025  14:05:39 +0000 (0:00:00.021)       0:00:16.609 ******
2025-11-25 14:05:39.910878 | controller | skipping: [instance]
2025-11-25 14:05:39.932813 | controller |
2025-11-25 14:05:39.932830 | controller | TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] ***
2025-11-25 14:05:39.932845 | controller | Tuesday 25 November 2025  14:05:39 +0000 (0:00:00.021)       0:00:16.630 ******
2025-11-25 14:05:39.932855 | controller | skipping: [instance]
2025-11-25 14:05:39.949444 | controller |
2025-11-25 14:05:39.949463 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
2025-11-25 14:05:39.949470 | controller | Tuesday 25 November 2025  14:05:39 +0000 (0:00:00.021)       0:00:16.652 ******
2025-11-25 14:05:39.949479 | controller | skipping: [instance]
2025-11-25 14:05:39.977679 | controller |
2025-11-25 14:05:39.977699 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
2025-11-25 14:05:39.977706 | controller | Tuesday 25 November 2025  14:05:39 +0000 (0:00:00.016)       0:00:16.669 ******
2025-11-25 14:05:39.977715 | controller | skipping: [instance]
2025-11-25 14:05:39.993929 | controller |
2025-11-25 14:05:39.993948 | controller | TASK [repo_setup : Cleanup existing metadata] **********************************
2025-11-25 14:05:39.993955 | controller | Tuesday 25 November 2025  14:05:39 +0000 (0:00:00.028)       0:00:16.697 ******
2025-11-25 14:05:39.993964 | controller | skipping: [instance]
2025-11-25 14:05:40.014170 | controller |
2025-11-25 14:05:40.014204 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
2025-11-25 14:05:40.014212 | controller | Tuesday 25 November 2025  14:05:39 +0000 (0:00:00.016)       0:00:16.713 ******
2025-11-25 14:05:40.014223 | controller | skipping: [instance]
2025-11-25 14:05:40.014247 | controller |
2025-11-25 14:05:40.014255 | controller | TASK [test_deps : Look for redhat-release rpm] *********************************
2025-11-25 14:05:40.014330 | controller | Tuesday 25 November 2025  14:05:40 +0000 (0:00:00.020)       0:00:16.734 ******
2025-11-25 14:05:40.046618 | controller | skipping: [instance]
2025-11-25 14:05:40.075481 | controller |
2025-11-25 14:05:40.075499 | controller | TASK [test_deps : Remove redhat-release] ***************************************
2025-11-25 14:05:40.075506 | controller | Tuesday 25 November 2025  14:05:40 +0000 (0:00:00.032)       0:00:16.766 ******
2025-11-25 14:05:40.075515 | controller | skipping: [instance]
2025-11-25 14:05:40.104475 | controller |
2025-11-25 14:05:40.104492 | controller | TASK [test_deps : Install centos-stream-release] *******************************
2025-11-25 14:05:40.104499 | controller | Tuesday 25 November 2025  14:05:40 +0000 (0:00:00.029)       0:00:16.795 ******
2025-11-25 14:05:40.104508 | controller | skipping: [instance]
2025-11-25 14:05:40.132235 | controller |
2025-11-25 14:05:40.132252 | controller | TASK [test_deps : Install additional packages] *********************************
2025-11-25 14:05:40.132259 | controller | Tuesday 25 November 2025  14:05:40 +0000 (0:00:00.028)       0:00:16.824 ******
2025-11-25 14:05:40.132268 | controller | skipping: [instance]
2025-11-25 14:06:34.491617 | controller |
2025-11-25 14:06:34.491649 | controller | TASK [test_deps : Install selinux python libs] *********************************
2025-11-25 14:06:34.491658 | controller | Tuesday 25 November 2025  14:05:40 +0000 (0:00:00.027)       0:00:16.852 ******
2025-11-25 14:06:34.491668 | controller | ok: [instance]
2025-11-25 14:06:35.693954 | controller |
2025-11-25 14:06:35.693984 | controller | TASK [test_deps : Install python yaml libs] ************************************
2025-11-25 14:06:35.693992 | controller | Tuesday 25 November 2025  14:06:34 +0000 (0:00:54.359)       0:01:11.211 ******
2025-11-25 14:06:35.694002 | controller | ok: [instance]
2025-11-25 14:06:35.725536 | controller |
2025-11-25 14:06:35.725562 | controller | TASK [test_deps : Install extra packages] **************************************
2025-11-25 14:06:35.725570 | controller | Tuesday 25 November 2025  14:06:35 +0000 (0:00:01.202)       0:01:12.413 ******
2025-11-25 14:06:35.725579 | controller | skipping: [instance]
2025-11-25 14:06:36.098604 | controller |
2025-11-25 14:06:36.098633 | controller | TASK [Create 1st dummy interface with needed IPs] ******************************
2025-11-25 14:06:36.098642 | controller | Tuesday 25 November 2025  14:06:35 +0000 (0:00:00.031)       0:01:12.445 ******
2025-11-25 14:06:36.098652 | controller | changed: [instance]
2025-11-25 14:06:36.356710 | controller |
2025-11-25 14:06:36.356739 | controller | TASK [Create 2nd dummy interface with needed IPs] ******************************
2025-11-25 14:06:36.356747 | controller | Tuesday 25 November 2025  14:06:36 +0000 (0:00:00.371)       0:01:12.817 ******
2025-11-25 14:06:36.356757 | controller | changed: [instance]
2025-11-25 14:06:36.626161 | controller |
2025-11-25 14:06:36.626195 | controller | TASK [Create 3nd dummy interface with needed IPs] ******************************
2025-11-25 14:06:36.626205 | controller | Tuesday 25 November 2025  14:06:36 +0000 (0:00:00.259)       0:01:13.076 ******
2025-11-25 14:06:36.626217 | controller | changed: [instance]
2025-11-25 14:06:36.626245 | controller |
2025-11-25 14:06:36.626253 | controller | PLAY RECAP *********************************************************************
2025-11-25 14:06:36.626260 | controller | instance                   : ok=22   changed=12   unreachable=0    failed=0    skipped=30   rescued=0    ignored=0
2025-11-25 14:06:36.626274 | controller |
2025-11-25 14:06:36.627135 | controller | Tuesday 25 November 2025  14:06:36 +0000 (0:00:00.269)       0:01:13.345 ******
2025-11-25 14:06:36.627183 | controller | ===============================================================================
2025-11-25 14:06:36.627192 | controller | test_deps : Install selinux python libs -------------------------------- 54.36s
2025-11-25 14:06:36.627197 | controller | repo_setup : Initialize python venv and install requirements ------------ 7.85s
2025-11-25 14:06:36.627203 | controller | repo_setup : Run repo-setup --------------------------------------------- 1.29s
2025-11-25 14:06:36.627208 | controller | test_deps : Install python yaml libs ------------------------------------ 1.20s
2025-11-25 14:06:36.627213 | controller | repo_setup : Get repo-setup repository ---------------------------------- 1.08s
2025-11-25 14:06:36.627218 | controller | Gathering Facts --------------------------------------------------------- 0.84s
2025-11-25 14:06:36.627224 | controller | repo_setup : Make sure git-core package is installed -------------------- 0.77s
2025-11-25 14:06:36.627231 | controller | repo_setup : Install repo-setup package --------------------------------- 0.70s
2025-11-25 14:06:36.628136 | controller | test_deps : Disable ubi 9 repos ----------------------------------------- 0.68s
2025-11-25 14:06:36.628328 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.55s
2025-11-25 14:06:36.628344 | controller | repo_setup : Ensure directories are present ----------------------------- 0.53s
2025-11-25 14:06:36.628351 | controller | repo_setup : Dump full hash in delorean.repo.md5 file ------------------- 0.43s
2025-11-25 14:06:36.628357 | controller | Create 1st dummy interface with needed IPs ------------------------------ 0.37s
2025-11-25 14:06:36.628362 | controller | test_deps : Disable ubi host subscription-manager integration ----------- 0.31s
2025-11-25 14:06:36.628368 | controller | test_deps : Detect mirrors file ----------------------------------------- 0.28s
2025-11-25 14:06:36.628373 | controller | Create 3nd dummy interface with needed IPs ------------------------------ 0.27s
2025-11-25 14:06:36.628378 | controller | Create 2nd dummy interface with needed IPs ------------------------------ 0.26s
2025-11-25 14:06:36.628383 | controller | repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist) --- 0.22s
2025-11-25 14:06:36.628392 | controller | repo_setup : Use RDO proxy mirrors -------------------------------------- 0.21s
2025-11-25 14:06:36.679526 | controller | repo_setup : Check for /etc/ci/mirror_info.sh --------------------------- 0.18s
2025-11-25 14:06:36.679549 | controller | INFO     Running default > converge
2025-11-25 14:06:37.125757 | controller |
2025-11-25 14:06:37.934013 | controller | PLAY [Converge] ****************************************************************
2025-11-25 14:06:37.934047 | controller |
2025-11-25 14:06:37.934076 | controller | TASK [Gathering Facts] *********************************************************
2025-11-25 14:06:37.934087 | controller | Tuesday 25 November 2025  14:06:37 +0000 (0:00:00.017)       0:00:00.017 ******
2025-11-25 14:06:37.934100 | controller | ok: [instance]
2025-11-25 14:06:42.882423 | controller |
2025-11-25 14:06:42.882454 | controller | TASK [dnsmasq : Install needed packages] ***************************************
2025-11-25 14:06:42.882462 | controller | Tuesday 25 November 2025  14:06:37 +0000 (0:00:00.808)       0:00:00.826 ******
2025-11-25 14:06:42.882472 | controller | changed: [instance]
2025-11-25 14:06:46.308170 | controller |
2025-11-25 14:06:46.308202 | controller | TASK [dnsmasq : Ensure SELinux knows about the new files and directories] ******
2025-11-25 14:06:46.308210 | controller | Tuesday 25 November 2025  14:06:42 +0000 (0:00:04.948)       0:00:05.774 ******
2025-11-25 14:06:46.308221 | controller | changed: [instance] => (item={'target': '/etc/cifmw-dnsmasq.conf', 'setype': 'dnsmasq_etc_t'})
2025-11-25 14:06:46.628692 | controller | changed: [instance] => (item={'target': '/etc/cifmw-dnsmasq.d(/.*)?', 'setype': 'dnsmasq_etc_t'})
2025-11-25 14:06:46.628735 | controller | changed: [instance] => (item={'target': '/var/run/cifmw-dnsmasq.pid', 'setype': 'dnsmasq_var_run_t'})
2025-11-25 14:06:46.628744 | controller |
2025-11-25 14:06:46.628750 | controller | TASK [dnsmasq : Manage configuration directory] ********************************
2025-11-25 14:06:46.628756 | controller | Tuesday 25 November 2025  14:06:46 +0000 (0:00:03.425)       0:00:09.200 ******
2025-11-25 14:06:46.628773 | controller | changed: [instance]
2025-11-25 14:06:46.628793 | controller |
2025-11-25 14:06:46.628998 | controller | TASK [dnsmasq : Manage systemd unit file] **************************************
2025-11-25 14:06:47.230252 | controller | Tuesday 25 November 2025  14:06:46 +0000 (0:00:00.321)       0:00:09.521 ******
2025-11-25 14:06:47.230287 | controller | changed: [instance]
2025-11-25 14:06:47.612724 | controller |
2025-11-25 14:06:47.612755 | controller | TASK [dnsmasq : Manage base configuration file] ********************************
2025-11-25 14:06:47.612763 | controller | Tuesday 25 November 2025  14:06:47 +0000 (0:00:00.601)       0:00:10.122 ******
2025-11-25 14:06:47.612773 | controller | changed: [instance]
2025-11-25 14:06:47.635960 | controller |
2025-11-25 14:06:47.635985 | controller | TASK [dnsmasq : Render listener configuration] *********************************
2025-11-25 14:06:47.635993 | controller | Tuesday 25 November 2025  14:06:47 +0000 (0:00:00.382)       0:00:10.505 ******
2025-11-25 14:06:47.636003 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/dnsmasq/tasks/listener.yml for instance
2025-11-25 14:06:48.017163 | controller |
2025-11-25 14:06:48.017199 | controller | TASK [dnsmasq : Create listener configuration file] ****************************
2025-11-25 14:06:48.017207 | controller | Tuesday 25 November 2025  14:06:47 +0000 (0:00:00.023)       0:00:10.528 ******
2025-11-25 14:06:48.017218 | controller | changed: [instance]
2025-11-25 14:06:48.017301 | controller |
2025-11-25 14:06:48.017330 | controller | TASK [dnsmasq : Remove listener configuration file] ****************************
2025-11-25 14:06:48.017343 | controller | Tuesday 25 November 2025  14:06:48 +0000 (0:00:00.381)       0:00:10.909 ******
2025-11-25 14:06:48.033743 | controller | skipping: [instance]
2025-11-25 14:06:48.056188 | controller |
2025-11-25 14:06:48.056230 | controller | TASK [dnsmasq : Render dns configuration] **************************************
2025-11-25 14:06:48.056239 | controller | Tuesday 25 November 2025  14:06:48 +0000 (0:00:00.016)       0:00:10.926 ******
2025-11-25 14:06:48.056252 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/dnsmasq/tasks/dns.yml for instance
2025-11-25 14:06:48.056290 | controller |
2025-11-25 14:06:48.056499 | controller | TASK [dnsmasq : Create dns configuration file] *********************************
2025-11-25 14:06:48.427126 | controller | Tuesday 25 November 2025  14:06:48 +0000 (0:00:00.022)       0:00:10.949 ******
2025-11-25 14:06:48.427161 | controller | changed: [instance]
2025-11-25 14:06:48.427486 | controller |
2025-11-25 14:06:48.444164 | controller | TASK [dnsmasq : Remove dns configuration file] *********************************
2025-11-25 14:06:48.444202 | controller | Tuesday 25 November 2025  14:06:48 +0000 (0:00:00.370)       0:00:11.319 ******
2025-11-25 14:06:48.444217 | controller | skipping: [instance]
2025-11-25 14:06:48.444248 | controller |
2025-11-25 14:06:48.444469 | controller | TASK [dnsmasq : Add localhost addresses from defined dnsmasq listen addresses to loopback interface] ***
2025-11-25 14:06:48.469861 | controller | Tuesday 25 November 2025  14:06:48 +0000 (0:00:00.017)       0:00:11.337 ******
2025-11-25 14:06:48.469885 | controller | skipping: [instance] => (item=192.168.253.9)
2025-11-25 14:06:49.315232 | controller | skipping: [instance] => (item=192.168.254.9)
2025-11-25 14:06:49.315263 | controller | skipping: [instance]
2025-11-25 14:06:49.315271 | controller |
2025-11-25 14:06:49.315277 | controller | TASK [dnsmasq : Enable and start service] **************************************
2025-11-25 14:06:49.315283 | controller | Tuesday 25 November 2025  14:06:48 +0000 (0:00:00.025)       0:00:11.362 ******
2025-11-25 14:06:49.315293 | controller | changed: [instance]
2025-11-25 14:06:49.331183 | controller |
2025-11-25 14:06:49.331218 | controller | TASK [dnsmasq : Stop service] **************************************************
2025-11-25 14:06:49.331236 | controller | Tuesday 25 November 2025  14:06:49 +0000 (0:00:00.845)       0:00:12.207 ******
2025-11-25 14:06:49.331254 | controller | skipping: [instance]
2025-11-25 14:06:49.347424 | controller |
2025-11-25 14:06:49.347449 | controller | TASK [dnsmasq : Remove unit file] **********************************************
2025-11-25 14:06:49.347458 | controller | Tuesday 25 November 2025  14:06:49 +0000 (0:00:00.016)       0:00:12.223 ******
2025-11-25 14:06:49.347470 | controller | skipping: [instance]
2025-11-25 14:06:49.373163 | controller |
2025-11-25 14:06:49.373197 | controller | TASK [dnsmasq : Remove main configuration files] *******************************
2025-11-25 14:06:49.373206 | controller | Tuesday 25 November 2025  14:06:49 +0000 (0:00:00.016)       0:00:12.240 ******
2025-11-25 14:06:49.373218 | controller | skipping: [instance] => (item=/etc/cifmw-dnsmasq.conf)
2025-11-25 14:06:49.398720 | controller | skipping: [instance] => (item=/etc/cifmw-dnsmasq.d)
2025-11-25 14:06:49.398752 | controller | skipping: [instance]
2025-11-25 14:06:49.398762 | controller |
2025-11-25 14:06:49.398769 | controller | TASK [Create network] **********************************************************
2025-11-25 14:06:49.398776 | controller | Tuesday 25 November 2025  14:06:49 +0000 (0:00:00.025)       0:00:12.265 ******
2025-11-25 14:06:49.398789 | controller |
2025-11-25 14:06:49.398857 | controller | TASK [dnsmasq : Assert mandatory parameter] ************************************
2025-11-25 14:06:49.398995 | controller | Tuesday 25 November 2025  14:06:49 +0000 (0:00:00.026)       0:00:12.291 ******
2025-11-25 14:06:49.424422 | controller | ok: [instance] => changed=false
2025-11-25 14:06:49.424581 | controller |   msg: All assertions passed
2025-11-25 14:06:49.424718 | controller |
2025-11-25 14:06:49.424873 | controller | TASK [dnsmasq : Assert mandatory parameters for new network] *******************
2025-11-25 14:06:49.425053 | controller | Tuesday 25 November 2025  14:06:49 +0000 (0:00:00.025)       0:00:12.317 ******
2025-11-25 14:06:49.459881 | controller | ok: [instance] => changed=false
2025-11-25 14:06:49.460082 | controller |   msg: All assertions passed
2025-11-25 14:06:49.460249 | controller |
2025-11-25 14:06:49.460339 | controller | TASK [dnsmasq : Create network] ************************************************
2025-11-25 14:06:49.460470 | controller | Tuesday 25 November 2025  14:06:49 +0000 (0:00:00.035)       0:00:12.352 ******
2025-11-25 14:06:49.904476 | controller | changed: [instance]
2025-11-25 14:06:49.921948 | controller |
2025-11-25 14:06:49.921971 | controller | TASK [dnsmasq : Remove network] ************************************************
2025-11-25 14:06:49.921980 | controller | Tuesday 25 November 2025  14:06:49 +0000 (0:00:00.444)       0:00:12.797 ******
2025-11-25 14:06:49.921989 | controller | skipping: [instance]
2025-11-25 14:06:49.944124 | controller |
2025-11-25 14:06:49.944142 | controller | TASK [dnsmasq : Remove dhcp host files] ****************************************
2025-11-25 14:06:49.944149 | controller | Tuesday 25 November 2025  14:06:49 +0000 (0:00:00.017)       0:00:12.814 ******
2025-11-25 14:06:49.944157 | controller | skipping: [instance]
2025-11-25 14:06:49.972791 | controller |
2025-11-25 14:06:49.972810 | controller | TASK [Create second network] ***************************************************
2025-11-25 14:06:49.972818 | controller | Tuesday 25 November 2025  14:06:49 +0000 (0:00:00.021)       0:00:12.836 ******
2025-11-25 14:06:49.972827 | controller |
2025-11-25 14:06:49.999145 | controller | TASK [dnsmasq : Assert mandatory parameter] ************************************
2025-11-25 14:06:49.999173 | controller | Tuesday 25 November 2025  14:06:49 +0000 (0:00:00.028)       0:00:12.865 ******
2025-11-25 14:06:49.999191 | controller | ok: [instance] => changed=false
2025-11-25 14:06:50.030968 | controller |   msg: All assertions passed
2025-11-25 14:06:50.030985 | controller |
2025-11-25 14:06:50.030993 | controller | TASK [dnsmasq : Assert mandatory parameters for new network] *******************
2025-11-25 14:06:50.030999 | controller | Tuesday 25 November 2025  14:06:49 +0000 (0:00:00.026)       0:00:12.891 ******
2025-11-25 14:06:50.031008 | controller | ok: [instance] => changed=false
2025-11-25 14:06:50.478156 | controller |   msg: All assertions passed
2025-11-25 14:06:50.478187 | controller |
2025-11-25 14:06:50.478195 | controller | TASK [dnsmasq : Create network] ************************************************
2025-11-25 14:06:50.478202 | controller | Tuesday 25 November 2025  14:06:50 +0000 (0:00:00.031)       0:00:12.923 ******
2025-11-25 14:06:50.478218 | controller | changed: [instance]
2025-11-25 14:06:50.495451 | controller |
2025-11-25 14:06:50.495471 | controller | TASK [dnsmasq : Remove network] ************************************************
2025-11-25 14:06:50.495479 | controller | Tuesday 25 November 2025  14:06:50 +0000 (0:00:00.446)       0:00:13.370 ******
2025-11-25 14:06:50.495488 | controller | skipping: [instance]
2025-11-25 14:06:50.517252 | controller |
2025-11-25 14:06:50.517271 | controller | TASK [dnsmasq : Remove dhcp host files] ****************************************
2025-11-25 14:06:50.517278 | controller | Tuesday 25 November 2025  14:06:50 +0000 (0:00:00.017)       0:00:13.388 ******
2025-11-25 14:06:50.517287 | controller | skipping: [instance]
2025-11-25 14:06:50.805187 | controller |
2025-11-25 14:06:50.805221 | controller | TASK [Inject enable-ra in startrek] ********************************************
2025-11-25 14:06:50.805229 | controller | Tuesday 25 November 2025  14:06:50 +0000 (0:00:00.021)       0:00:13.409 ******
2025-11-25 14:06:50.805240 | controller | ok: [instance]
2025-11-25 14:06:50.805265 | controller |
2025-11-25 14:06:50.805473 | controller | TASK [Assert no change was done in startrek] ***********************************
2025-11-25 14:06:50.828081 | controller | Tuesday 25 November 2025  14:06:50 +0000 (0:00:00.287)       0:00:13.697 ******
2025-11-25 14:06:50.828105 | controller | ok: [instance] => changed=false
2025-11-25 14:06:50.853804 | controller |   msg: All assertions passed
2025-11-25 14:06:50.853824 | controller |
2025-11-25 14:06:50.853833 | controller | TASK [Create IPv4-only network] ************************************************
2025-11-25 14:06:50.853841 | controller | Tuesday 25 November 2025  14:06:50 +0000 (0:00:00.022)       0:00:13.720 ******
2025-11-25 14:06:50.853852 | controller |
2025-11-25 14:06:50.880055 | controller | TASK [dnsmasq : Assert mandatory parameter] ************************************
2025-11-25 14:06:50.880090 | controller | Tuesday 25 November 2025  14:06:50 +0000 (0:00:00.025)       0:00:13.746 ******
2025-11-25 14:06:50.880101 | controller | ok: [instance] => changed=false
2025-11-25 14:06:50.914141 | controller |   msg: All assertions passed
2025-11-25 14:06:50.914171 | controller |
2025-11-25 14:06:50.914179 | controller | TASK [dnsmasq : Assert mandatory parameters for new network] *******************
2025-11-25 14:06:50.914187 | controller | Tuesday 25 November 2025  14:06:50 +0000 (0:00:00.026)       0:00:13.772 ******
2025-11-25 14:06:50.914197 | controller | ok: [instance] => changed=false
2025-11-25 14:06:50.914489 | controller |   msg: All assertions passed
2025-11-25 14:06:50.914503 | controller |
2025-11-25 14:06:51.361750 | controller | TASK [dnsmasq : Create network] ************************************************
2025-11-25 14:06:51.361779 | controller | Tuesday 25 November 2025  14:06:50 +0000 (0:00:00.033)       0:00:13.806 ******
2025-11-25 14:06:51.361791 | controller | changed: [instance]
2025-11-25 14:06:51.379754 | controller |
2025-11-25 14:06:51.379775 | controller | TASK [dnsmasq : Remove network] ************************************************
2025-11-25 14:06:51.379788 | controller | Tuesday 25 November 2025  14:06:51 +0000 (0:00:00.447)       0:00:14.254 ******
2025-11-25 14:06:51.379798 | controller | skipping: [instance]
2025-11-25 14:06:51.401916 | controller |
2025-11-25 14:06:51.401945 | controller | TASK [dnsmasq : Remove dhcp host files] ****************************************
2025-11-25 14:06:51.401953 | controller | Tuesday 25 November 2025  14:06:51 +0000 (0:00:00.018)       0:00:14.272 ******
2025-11-25 14:06:51.401962 | controller | skipping: [instance]
2025-11-25 14:06:51.597731 | controller |
2025-11-25 14:06:51.597759 | controller | TASK [Inject enable-ra in oldies] **********************************************
2025-11-25 14:06:51.597767 | controller | Tuesday 25 November 2025  14:06:51 +0000 (0:00:00.022)       0:00:14.294 ******
2025-11-25 14:06:51.597777 | controller | changed: [instance]
2025-11-25 14:06:51.619116 | controller |
2025-11-25 14:06:51.619140 | controller | TASK [Debug] *******************************************************************
2025-11-25 14:06:51.619148 | controller | Tuesday 25 November 2025  14:06:51 +0000 (0:00:00.195)       0:00:14.490 ******
2025-11-25 14:06:51.619158 | controller | ok: [instance] =>
2025-11-25 14:06:51.619212 | controller |   _set_ra:
2025-11-25 14:06:51.619222 | controller |     backup: ''
2025-11-25 14:06:51.620137 | controller |     changed: true
2025-11-25 14:06:51.620484 | controller |     diff:
2025-11-25 14:06:51.620500 | controller |     - after: ''
2025-11-25 14:06:51.620508 | controller |       after_header: /etc/cifmw-dnsmasq.d/oldies.conf (content)
2025-11-25 14:06:51.620514 | controller |       before: ''
2025-11-25 14:06:51.620525 | controller |       before_header: /etc/cifmw-dnsmasq.d/oldies.conf (content)
2025-11-25 14:06:51.620530 | controller |     - after_header: /etc/cifmw-dnsmasq.d/oldies.conf (file attributes)
2025-11-25 14:06:51.620536 | controller |       before_header: /etc/cifmw-dnsmasq.d/oldies.conf (file attributes)
2025-11-25 14:06:51.620541 | controller |     failed: false
2025-11-25 14:06:51.620550 | controller |     msg: line added
2025-11-25 14:06:51.647216 | controller |
2025-11-25 14:06:51.647237 | controller | TASK [Assert oldies was changed] ***********************************************
2025-11-25 14:06:51.647247 | controller | Tuesday 25 November 2025  14:06:51 +0000 (0:00:00.021)       0:00:14.512 ******
2025-11-25 14:06:51.647258 | controller | ok: [instance] => changed=false
2025-11-25 14:06:51.647284 | controller |   msg: All assertions passed
2025-11-25 14:06:51.647292 | controller |
2025-11-25 14:06:51.647299 | controller | TASK [Inject some node in starwars network] ************************************
2025-11-25 14:06:51.647308 | controller | Tuesday 25 November 2025  14:06:51 +0000 (0:00:00.027)       0:00:14.539 ******
2025-11-25 14:06:51.677121 | controller |
2025-11-25 14:06:51.711489 | controller | TASK [dnsmasq : Ensure we have the right data and type] ************************
2025-11-25 14:06:51.711508 | controller | Tuesday 25 November 2025  14:06:51 +0000 (0:00:00.029)       0:00:14.569 ******
2025-11-25 14:06:51.711519 | controller | ok: [instance]
2025-11-25 14:06:51.711661 | controller |
2025-11-25 14:06:51.711806 | controller | TASK [dnsmasq : Assert we have needed host data] *******************************
2025-11-25 14:06:51.711972 | controller | Tuesday 25 November 2025  14:06:51 +0000 (0:00:00.034)       0:00:14.604 ******
2025-11-25 14:06:51.773472 | 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-25 14:06:51.773682 | 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-25 14:06:51.773831 | 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-25 14:06:51.773978 | controller |
2025-11-25 14:06:51.774142 | controller | TASK [dnsmasq : Ensure networks exists] ****************************************
2025-11-25 14:06:51.774285 | controller | Tuesday 25 November 2025  14:06:51 +0000 (0:00:00.061)       0:00:14.666 ******
2025-11-25 14:06:51.815801 | 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-25 14:06:51.816025 | 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-25 14:06:51.816218 | controller |
2025-11-25 14:06:51.816403 | controller | TASK [dnsmasq : Check network file status] *************************************
2025-11-25 14:06:51.816567 | controller | Tuesday 25 November 2025  14:06:51 +0000 (0:00:00.042)       0:00:14.708 ******
2025-11-25 14:06:51.985196 | controller | ok: [instance]
2025-11-25 14:06:52.007135 | controller |
2025-11-25 14:06:52.007158 | controller | TASK [dnsmasq : Assert network exists] *****************************************
2025-11-25 14:06:52.007166 | controller | Tuesday 25 November 2025  14:06:51 +0000 (0:00:00.169)       0:00:14.877 ******
2025-11-25 14:06:52.007175 | controller | ok: [instance]
2025-11-25 14:06:52.179133 | controller |
2025-11-25 14:06:52.179169 | controller | TASK [dnsmasq : Check network file status] *************************************
2025-11-25 14:06:52.179178 | controller | Tuesday 25 November 2025  14:06:52 +0000 (0:00:00.022)       0:00:14.899 ******
2025-11-25 14:06:52.179188 | controller | ok: [instance]
2025-11-25 14:06:52.179491 | controller |
2025-11-25 14:06:52.201878 | controller | TASK [dnsmasq : Assert network exists] *****************************************
2025-11-25 14:06:52.201904 | controller | Tuesday 25 November 2025  14:06:52 +0000 (0:00:00.172)       0:00:15.071 ******
2025-11-25 14:06:52.201915 | controller | ok: [instance]
2025-11-25 14:06:52.219651 | controller |
2025-11-25 14:06:52.219673 | controller | TASK [dnsmasq : Initialize empty dhcp_host_entries] ****************************
2025-11-25 14:06:52.219686 | controller | Tuesday 25 November 2025  14:06:52 +0000 (0:00:00.022)       0:00:15.094 ******
2025-11-25 14:06:52.219695 | controller | ok: [instance]
2025-11-25 14:06:52.338730 | controller |
2025-11-25 14:06:52.338758 | controller | TASK [dnsmasq : Compute entry] *************************************************
2025-11-25 14:06:52.338766 | controller | Tuesday 25 November 2025  14:06:52 +0000 (0:00:00.017)       0:00:15.112 ******
2025-11-25 14:06:52.338777 | 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-25 14:06:52.374685 | 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-25 14:06:52.374703 | 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-25 14:06:52.374710 | controller |
2025-11-25 14:06:52.374716 | controller | TASK [dnsmasq : Create add/remove sets] ****************************************
2025-11-25 14:06:52.374722 | controller | Tuesday 25 November 2025  14:06:52 +0000 (0:00:00.118)       0:00:15.230 ******
2025-11-25 14:06:52.374736 | controller | ok: [instance]
2025-11-25 14:06:53.406051 | controller |
2025-11-25 14:06:53.406100 | controller | TASK [dnsmasq : Add DHCP entries] **********************************************
2025-11-25 14:06:53.406109 | controller | Tuesday 25 November 2025  14:06:52 +0000 (0:00:00.036)       0:00:15.267 ******
2025-11-25 14:06:53.406120 | 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-25 14:06:53.425953 | 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-25 14:06:53.425982 | 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-25 14:06:53.425990 | controller |
2025-11-25 14:06:53.425996 | controller | TASK [dnsmasq : Remove DHCP entries] *******************************************
2025-11-25 14:06:53.426002 | controller | Tuesday 25 November 2025  14:06:53 +0000 (0:00:01.030)       0:00:16.298 ******
2025-11-25 14:06:53.426012 | controller | skipping: [instance]
2025-11-25 14:06:53.458076 | controller |
2025-11-25 14:06:53.458100 | controller | TASK [Inject nodes with tags for DHCP options] *********************************
2025-11-25 14:06:53.458109 | controller | Tuesday 25 November 2025  14:06:53 +0000 (0:00:00.020)       0:00:16.318 ******
2025-11-25 14:06:53.458120 | controller |
2025-11-25 14:06:53.492674 | controller | TASK [dnsmasq : Ensure we have the right data and type] ************************
2025-11-25 14:06:53.492699 | controller | Tuesday 25 November 2025  14:06:53 +0000 (0:00:00.032)       0:00:16.350 ******
2025-11-25 14:06:53.492712 | controller | ok: [instance]
2025-11-25 14:06:53.553157 | controller |
2025-11-25 14:06:53.553179 | controller | TASK [dnsmasq : Assert we have needed host data] *******************************
2025-11-25 14:06:53.553188 | controller | Tuesday 25 November 2025  14:06:53 +0000 (0:00:00.034)       0:00:16.385 ******
2025-11-25 14:06:53.553199 | 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-25 14:06:53.553386 | 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-25 14:06:53.553565 | 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-25 14:06:53.553725 | controller |
2025-11-25 14:06:53.553893 | controller | TASK [dnsmasq : Ensure networks exists] ****************************************
2025-11-25 14:06:53.554087 | controller | Tuesday 25 November 2025  14:06:53 +0000 (0:00:00.060)       0:00:16.446 ******
2025-11-25 14:06:53.596982 | 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-25 14:06:53.766837 | 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-25 14:06:53.766871 | controller |
2025-11-25 14:06:53.766880 | controller | TASK [dnsmasq : Check network file status] *************************************
2025-11-25 14:06:53.766886 | controller | Tuesday 25 November 2025  14:06:53 +0000 (0:00:00.043)       0:00:16.489 ******
2025-11-25 14:06:53.766896 | controller | ok: [instance]
2025-11-25 14:06:53.787739 | controller |
2025-11-25 14:06:53.787760 | controller | TASK [dnsmasq : Assert network exists] *****************************************
2025-11-25 14:06:53.787768 | controller | Tuesday 25 November 2025  14:06:53 +0000 (0:00:00.169)       0:00:16.659 ******
2025-11-25 14:06:53.787777 | controller | ok: [instance]
2025-11-25 14:06:53.956085 | controller |
2025-11-25 14:06:53.956115 | controller | TASK [dnsmasq : Check network file status] *************************************
2025-11-25 14:06:53.956123 | controller | Tuesday 25 November 2025  14:06:53 +0000 (0:00:00.021)       0:00:16.680 ******
2025-11-25 14:06:53.956134 | controller | ok: [instance]
2025-11-25 14:06:53.976869 | controller |
2025-11-25 14:06:53.976890 | controller | TASK [dnsmasq : Assert network exists] *****************************************
2025-11-25 14:06:53.976898 | controller | Tuesday 25 November 2025  14:06:53 +0000 (0:00:00.168)       0:00:16.848 ******
2025-11-25 14:06:53.976908 | controller | ok: [instance]
2025-11-25 14:06:53.993997 | controller |
2025-11-25 14:06:53.994015 | controller | TASK [dnsmasq : Initialize empty dhcp_host_entries] ****************************
2025-11-25 14:06:53.994022 | controller | Tuesday 25 November 2025  14:06:53 +0000 (0:00:00.020)       0:00:16.869 ******
2025-11-25 14:06:53.994031 | controller | ok: [instance]
2025-11-25 14:06:54.109250 | controller |
2025-11-25 14:06:54.109284 | controller | TASK [dnsmasq : Compute entry] *************************************************
2025-11-25 14:06:54.109293 | controller | Tuesday 25 November 2025  14:06:53 +0000 (0:00:00.017)       0:00:16.886 ******
2025-11-25 14:06:54.109303 | 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-25 14:06:54.145027 | 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-25 14:06:54.145074 | 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-25 14:06:54.145085 | controller |
2025-11-25 14:06:54.145091 | controller | TASK [dnsmasq : Create add/remove sets] ****************************************
2025-11-25 14:06:54.145097 | controller | Tuesday 25 November 2025  14:06:54 +0000 (0:00:00.114)       0:00:17.001 ******
2025-11-25 14:06:54.145107 | controller | ok: [instance]
2025-11-25 14:06:55.200945 | controller |
2025-11-25 14:06:55.200977 | controller | TASK [dnsmasq : Add DHCP entries] **********************************************
2025-11-25 14:06:55.200985 | controller | Tuesday 25 November 2025  14:06:54 +0000 (0:00:00.036)       0:00:17.037 ******
2025-11-25 14:06:55.200996 | 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-25 14:06:55.219836 | 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-25 14:06:55.219858 | 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-25 14:06:55.219867 | controller |
2025-11-25 14:06:55.219874 | controller | TASK [dnsmasq : Remove DHCP entries] *******************************************
2025-11-25 14:06:55.219881 | controller | Tuesday 25 November 2025  14:06:55 +0000 (0:00:01.055)       0:00:18.093 ******
2025-11-25 14:06:55.219892 | controller | skipping: [instance]
2025-11-25 14:06:55.499040 | controller |
2025-11-25 14:06:55.499085 | controller | TASK [Read r2d2 DHCP host entry] ***********************************************
2025-11-25 14:06:55.499103 | controller | Tuesday 25 November 2025  14:06:55 +0000 (0:00:00.019)       0:00:18.112 ******
2025-11-25 14:06:55.499114 | controller | ok: [instance]
2025-11-25 14:06:55.681772 | controller |
2025-11-25 14:06:55.681801 | controller | TASK [Read c3po DHCP host entry] ***********************************************
2025-11-25 14:06:55.681810 | controller | Tuesday 25 November 2025  14:06:55 +0000 (0:00:00.279)       0:00:18.391 ******
2025-11-25 14:06:55.681820 | controller | ok: [instance]
2025-11-25 14:06:55.867599 | controller |
2025-11-25 14:06:55.867629 | controller | TASK [Read data DHCP host entry] ***********************************************
2025-11-25 14:06:55.867637 | controller | Tuesday 25 November 2025  14:06:55 +0000 (0:00:00.182)       0:00:18.574 ******
2025-11-25 14:06:55.867647 | controller | ok: [instance]
2025-11-25 14:06:55.899358 | controller |
2025-11-25 14:06:55.899384 | controller | TASK [Decode entries] **********************************************************
2025-11-25 14:06:55.899391 | controller | Tuesday 25 November 2025  14:06:55 +0000 (0:00:00.185)       0:00:18.760 ******
2025-11-25 14:06:55.899401 | controller | ok: [instance]
2025-11-25 14:06:55.922647 | controller |
2025-11-25 14:06:55.922673 | controller | TASK [Assert r2d2 entry has droid tag] *****************************************
2025-11-25 14:06:55.922681 | controller | Tuesday 25 November 2025  14:06:55 +0000 (0:00:00.031)       0:00:18.791 ******
2025-11-25 14:06:55.922690 | controller | ok: [instance] => changed=false
2025-11-25 14:06:55.946033 | controller |   msg: All assertions passed
2025-11-25 14:06:55.946073 | controller |
2025-11-25 14:06:55.946084 | controller | TASK [Assert c3po entry has droid tag] *****************************************
2025-11-25 14:06:55.946091 | controller | Tuesday 25 November 2025  14:06:55 +0000 (0:00:00.023)       0:00:18.815 ******
2025-11-25 14:06:55.946100 | controller | ok: [instance] => changed=false
2025-11-25 14:06:55.969620 | controller |   msg: All assertions passed
2025-11-25 14:06:55.969642 | controller |
2025-11-25 14:06:55.969649 | controller | TASK [Assert data entry has android tag] ***************************************
2025-11-25 14:06:55.969655 | controller | Tuesday 25 November 2025  14:06:55 +0000 (0:00:00.023)       0:00:18.838 ******
2025-11-25 14:06:55.969665 | controller | ok: [instance] => changed=false
2025-11-25 14:06:56.154263 | controller |   msg: All assertions passed
2025-11-25 14:06:56.154292 | controller |
2025-11-25 14:06:56.154300 | controller | TASK [Verify entry without tag has no set: prefix] *****************************
2025-11-25 14:06:56.154307 | controller | Tuesday 25 November 2025  14:06:55 +0000 (0:00:00.023)       0:00:18.862 ******
2025-11-25 14:06:56.154316 | controller | ok: [instance]
2025-11-25 14:06:56.189610 | controller |
2025-11-25 14:06:56.189636 | controller | TASK [Assert solo entry does not have a tag] ***********************************
2025-11-25 14:06:56.189644 | controller | Tuesday 25 November 2025  14:06:56 +0000 (0:00:00.184)       0:00:19.046 ******
2025-11-25 14:06:56.189654 | controller | ok: [instance] => changed=false
2025-11-25 14:06:56.554563 | controller |   msg: All assertions passed
2025-11-25 14:06:56.554593 | controller |
2025-11-25 14:06:56.554601 | controller | TASK [Create DHCP options configuration for tagged hosts] **********************
2025-11-25 14:06:56.554607 | controller | Tuesday 25 November 2025  14:06:56 +0000 (0:00:00.035)       0:00:19.082 ******
2025-11-25 14:06:56.554617 | controller | changed: [instance]
2025-11-25 14:06:56.842656 | controller |
2025-11-25 14:06:56.842685 | controller | TASK [Verify dnsmasq configuration is valid] ***********************************
2025-11-25 14:06:56.842693 | controller | Tuesday 25 November 2025  14:06:56 +0000 (0:00:00.364)       0:00:19.447 ******
2025-11-25 14:06:56.842704 | controller | ok: [instance]
2025-11-25 14:06:56.868950 | controller |
2025-11-25 14:06:56.868977 | controller | TASK [Add a domain specific forwarder] *****************************************
2025-11-25 14:06:56.868987 | controller | Tuesday 25 November 2025  14:06:56 +0000 (0:00:00.288)       0:00:19.735 ******
2025-11-25 14:06:56.868999 | controller |
2025-11-25 14:06:56.903143 | controller | TASK [dnsmasq : Assert we have needed forwarder data] **************************
2025-11-25 14:06:56.903169 | controller | Tuesday 25 November 2025  14:06:56 +0000 (0:00:00.026)       0:00:19.761 ******
2025-11-25 14:06:56.903183 | controller | ok: [instance]
2025-11-25 14:06:56.903212 | controller |
2025-11-25 14:06:56.903442 | controller | TASK [dnsmasq : Assert each forwarder element have needed data] ****************
2025-11-25 14:06:56.943445 | controller | Tuesday 25 November 2025  14:06:56 +0000 (0:00:00.034)       0:00:19.796 ******
2025-11-25 14:06:56.943470 | controller | ok: [instance] => (item={'state': 'present', 'server': '192.168.254.100', 'domains': ['battlestar.galactica.lan']})
2025-11-25 14:06:57.179762 | controller |
2025-11-25 14:06:57.179792 | controller | TASK [dnsmasq : Add/Remove forwarder] ******************************************
2025-11-25 14:06:57.179801 | controller | Tuesday 25 November 2025  14:06:56 +0000 (0:00:00.040)       0:00:19.836 ******
2025-11-25 14:06:57.179811 | controller | changed: [instance] => (item={'state': 'present', 'server': '192.168.254.100', 'domains': ['battlestar.galactica.lan']})
2025-11-25 14:06:57.209018 | controller |
2025-11-25 14:06:57.209046 | controller | TASK [Add some host records] ***************************************************
2025-11-25 14:06:57.209077 | controller | Tuesday 25 November 2025  14:06:57 +0000 (0:00:00.235)       0:00:20.072 ******
2025-11-25 14:06:57.209098 | controller |
2025-11-25 14:06:57.245437 | controller | TASK [dnsmasq : Assert we have needed host record data] ************************
2025-11-25 14:06:57.245460 | controller | Tuesday 25 November 2025  14:06:57 +0000 (0:00:00.029)       0:00:20.101 ******
2025-11-25 14:06:57.245471 | controller | ok: [instance]
2025-11-25 14:06:57.298640 | controller |
2025-11-25 14:06:57.298662 | controller | TASK [dnsmasq : Assert each address element have needed data] ******************
2025-11-25 14:06:57.298670 | controller | Tuesday 25 November 2025  14:06:57 +0000 (0:00:00.036)       0:00:20.138 ******
2025-11-25 14:06:57.298682 | controller | ok: [instance] => (item={'state': 'present', 'ips': ['192.168.253.50', '2345:0425:2CA1::dead'], 'names': ['dead.battlestar.galactica.lan']})
2025-11-25 14:06:57.719676 | controller | ok: [instance] => (item={'state': 'present', 'ips': ['192.168.253.51', '2345:0425:2CA1::beef'], 'names': ['beef.battlestar.galactica.lan']})
2025-11-25 14:06:57.719705 | controller |
2025-11-25 14:06:57.719714 | controller | TASK [dnsmasq : Add/Remove address] ********************************************
2025-11-25 14:06:57.719720 | controller | Tuesday 25 November 2025  14:06:57 +0000 (0:00:00.053)       0:00:20.191 ******
2025-11-25 14:06:57.719730 | controller | changed: [instance] => (item={'state': 'present', 'ips': ['192.168.253.50', '2345:0425:2CA1::dead'], 'names': ['dead.battlestar.galactica.lan']})
2025-11-25 14:06:57.750966 | controller | changed: [instance] => (item={'state': 'present', 'ips': ['192.168.253.51', '2345:0425:2CA1::beef'], 'names': ['beef.battlestar.galactica.lan']})
2025-11-25 14:06:57.750991 | controller |
2025-11-25 14:06:57.750999 | controller | TASK [Add some wildcard addresses] *********************************************
2025-11-25 14:06:57.751005 | controller | Tuesday 25 November 2025  14:06:57 +0000 (0:00:00.420)       0:00:20.612 ******
2025-11-25 14:06:57.751014 | controller |
2025-11-25 14:06:57.788047 | controller | TASK [dnsmasq : Assert we have needed address data] ****************************
2025-11-25 14:06:57.788082 | controller | Tuesday 25 November 2025  14:06:57 +0000 (0:00:00.031)       0:00:20.643 ******
2025-11-25 14:06:57.788093 | controller | ok: [instance]
2025-11-25 14:06:57.871894 | controller |
2025-11-25 14:06:57.871914 | controller | TASK [dnsmasq : Assert each address element have needed data] ******************
2025-11-25 14:06:57.871937 | controller | Tuesday 25 November 2025  14:06:57 +0000 (0:00:00.037)       0:00:20.680 ******
2025-11-25 14:06:57.871948 | controller | ok: [instance] => (item={'state': 'present', 'ipaddr': '1.2.3.4', 'domains': ['apps.ocp.starwars.lan']})
2025-11-25 14:06:58.299158 | controller | ok: [instance] => (item={'state': 'present', 'ipaddr': '4.3.2.1', 'domains': ['apps.ocp.startrek.lan']})
2025-11-25 14:06:58.299191 | controller |
2025-11-25 14:06:58.299199 | controller | TASK [dnsmasq : Add/Remove address] ********************************************
2025-11-25 14:06:58.299205 | controller | Tuesday 25 November 2025  14:06:57 +0000 (0:00:00.083)       0:00:20.764 ******
2025-11-25 14:06:58.299215 | controller | changed: [instance] => (item={'state': 'present', 'ipaddr': '1.2.3.4', 'domains': ['apps.ocp.starwars.lan']})
2025-11-25 14:06:58.299411 | controller | changed: [instance] => (item={'state': 'present', 'ipaddr': '4.3.2.1', 'domains': ['apps.ocp.startrek.lan']})
2025-11-25 14:06:58.299428 | controller |
2025-11-25 14:06:58.299436 | controller | TASK [Force reload dnsmasq] ****************************************************
2025-11-25 14:06:58.299449 | controller | Tuesday 25 November 2025  14:06:58 +0000 (0:00:00.417)       0:00:21.182 ******
2025-11-25 14:06:58.299455 | controller |
2025-11-25 14:06:58.299464 | controller | RUNNING HANDLER [dnsmasq : Restart dnsmasq] ************************************
2025-11-25 14:06:58.651053 | controller | Tuesday 25 November 2025  14:06:58 +0000 (0:00:00.008)       0:00:21.191 ******
2025-11-25 14:06:58.651106 | controller | changed: [instance]
2025-11-25 14:06:58.997370 | controller |
2025-11-25 14:06:58.997404 | controller | RUNNING HANDLER [dnsmasq : Reload dnsmasq] *************************************
2025-11-25 14:06:58.997412 | controller | Tuesday 25 November 2025  14:06:58 +0000 (0:00:00.352)       0:00:21.543 ******
2025-11-25 14:06:58.997422 | controller | changed: [instance]
2025-11-25 14:06:59.056151 | controller |
2025-11-25 14:06:59.056190 | controller | PLAY RECAP *********************************************************************
2025-11-25 14:06:59.056199 | controller | instance                   : ok=71   changed=20   unreachable=0    failed=0    skipped=14   rescued=0    ignored=0
2025-11-25 14:06:59.056205 | controller |
2025-11-25 14:06:59.056210 | controller | Tuesday 25 November 2025  14:06:58 +0000 (0:00:00.344)       0:00:21.887 ******
2025-11-25 14:06:59.056216 | controller | ===============================================================================
2025-11-25 14:06:59.056221 | controller | dnsmasq : Install needed packages --------------------------------------- 4.95s
2025-11-25 14:06:59.056227 | controller | dnsmasq : Ensure SELinux knows about the new files and directories ------ 3.43s
2025-11-25 14:06:59.056232 | controller | dnsmasq : Add DHCP entries ---------------------------------------------- 1.06s
2025-11-25 14:06:59.056238 | controller | dnsmasq : Add DHCP entries ---------------------------------------------- 1.03s
2025-11-25 14:06:59.056243 | controller | dnsmasq : Enable and start service -------------------------------------- 0.85s
2025-11-25 14:06:59.056249 | controller | Gathering Facts --------------------------------------------------------- 0.81s
2025-11-25 14:06:59.056254 | controller | dnsmasq : Manage systemd unit file -------------------------------------- 0.60s
2025-11-25 14:06:59.056259 | controller | dnsmasq : Create network ------------------------------------------------ 0.45s
2025-11-25 14:06:59.056269 | controller | dnsmasq : Create network ------------------------------------------------ 0.45s
2025-11-25 14:06:59.056275 | controller | dnsmasq : Create network ------------------------------------------------ 0.44s
2025-11-25 14:06:59.056280 | controller | dnsmasq : Add/Remove address -------------------------------------------- 0.42s
2025-11-25 14:06:59.056286 | controller | dnsmasq : Add/Remove address -------------------------------------------- 0.42s
2025-11-25 14:06:59.056291 | controller | dnsmasq : Manage base configuration file -------------------------------- 0.38s
2025-11-25 14:06:59.056297 | controller | dnsmasq : Create listener configuration file ---------------------------- 0.38s
2025-11-25 14:06:59.056302 | controller | dnsmasq : Create dns configuration file --------------------------------- 0.37s
2025-11-25 14:06:59.056307 | controller | Create DHCP options configuration for tagged hosts ---------------------- 0.36s
2025-11-25 14:06:59.056312 | controller | dnsmasq : Restart dnsmasq ----------------------------------------------- 0.35s
2025-11-25 14:06:59.056318 | controller | dnsmasq : Reload dnsmasq ------------------------------------------------ 0.34s
2025-11-25 14:06:59.056323 | controller | dnsmasq : Manage configuration directory -------------------------------- 0.32s
2025-11-25 14:06:59.056328 | controller | Verify dnsmasq configuration is valid ----------------------------------- 0.29s
2025-11-25 14:06:59.056338 | controller | INFO     Running default > cleanup
2025-11-25 14:06:59.486340 | controller |
2025-11-25 14:06:59.486548 | controller | PLAY [Converge] ****************************************************************
2025-11-25 14:06:59.486687 | controller |
2025-11-25 14:06:59.486838 | controller | TASK [Gathering Facts] *********************************************************
2025-11-25 14:06:59.486998 | controller | Tuesday 25 November 2025  14:06:59 +0000 (0:00:00.015)       0:00:00.015 ******
2025-11-25 14:07:00.329715 | controller | ok: [instance]
2025-11-25 14:07:00.901811 | controller |
2025-11-25 14:07:00.901842 | controller | TASK [Copy generated content in ci-framework-data/artifacts] *******************
2025-11-25 14:07:00.901857 | controller | Tuesday 25 November 2025  14:07:00 +0000 (0:00:00.843)       0:00:00.858 ******
2025-11-25 14:07:00.901868 | controller | changed: [instance] => (item=/etc/cifmw-dnsmasq.conf)
2025-11-25 14:07:00.930491 | controller | changed: [instance] => (item=/etc/cifmw-dnsmasq.d/)
2025-11-25 14:07:00.930515 | controller |
2025-11-25 14:07:00.930523 | controller | TASK [Remove a domain specific forwarder] **************************************
2025-11-25 14:07:00.930530 | controller | Tuesday 25 November 2025  14:07:00 +0000 (0:00:00.571)       0:00:01.430 ******
2025-11-25 14:07:00.930539 | controller |
2025-11-25 14:07:00.965105 | controller | TASK [dnsmasq : Assert we have needed forwarder data] **************************
2025-11-25 14:07:00.965139 | controller | Tuesday 25 November 2025  14:07:00 +0000 (0:00:00.029)       0:00:01.459 ******
2025-11-25 14:07:00.965151 | controller | ok: [instance]
2025-11-25 14:07:01.004177 | controller |
2025-11-25 14:07:01.004215 | controller | TASK [dnsmasq : Assert each forwarder element have needed data] ****************
2025-11-25 14:07:01.004225 | controller | Tuesday 25 November 2025  14:07:00 +0000 (0:00:00.034)       0:00:01.494 ******
2025-11-25 14:07:01.004238 | controller | ok: [instance] => (item={'state': 'absent', 'server': '192.168.100.100', 'domains': ['battlestar.galactica.lan']})
2025-11-25 14:07:01.004266 | controller |
2025-11-25 14:07:01.004276 | controller | TASK [dnsmasq : Add/Remove forwarder] ******************************************
2025-11-25 14:07:01.004401 | controller | Tuesday 25 November 2025  14:07:01 +0000 (0:00:00.039)       0:00:01.533 ******
2025-11-25 14:07:01.312898 | controller | ok: [instance] => (item={'state': 'absent', 'server': '192.168.100.100', 'domains': ['battlestar.galactica.lan']})
2025-11-25 14:07:01.343750 | controller |
2025-11-25 14:07:01.343769 | controller | TASK [Remove some host records] ************************************************
2025-11-25 14:07:01.343778 | controller | Tuesday 25 November 2025  14:07:01 +0000 (0:00:00.308)       0:00:01.841 ******
2025-11-25 14:07:01.343787 | controller |
2025-11-25 14:07:01.378542 | controller | TASK [dnsmasq : Assert we have needed host record data] ************************
2025-11-25 14:07:01.378560 | controller | Tuesday 25 November 2025  14:07:01 +0000 (0:00:00.031)       0:00:01.873 ******
2025-11-25 14:07:01.378571 | controller | ok: [instance]
2025-11-25 14:07:01.430720 | controller |
2025-11-25 14:07:01.430744 | controller | TASK [dnsmasq : Assert each address element have needed data] ******************
2025-11-25 14:07:01.430752 | controller | Tuesday 25 November 2025  14:07:01 +0000 (0:00:00.034)       0:00:01.907 ******
2025-11-25 14:07:01.430767 | controller | ok: [instance] => (item={'state': 'absent', 'ips': ['192.168.253.50', '2345:0425:2CA1::dead'], 'names': ['dead.battlestar.galactica.lan']})
2025-11-25 14:07:01.843036 | controller | ok: [instance] => (item={'state': 'absent', 'ips': ['192.168.253.51', '2345:0425:2CA1::beef'], 'names': ['beef.battlestar.galactica.lan']})
2025-11-25 14:07:01.843086 | controller |
2025-11-25 14:07:01.843097 | controller | TASK [dnsmasq : Add/Remove address] ********************************************
2025-11-25 14:07:01.843104 | controller | Tuesday 25 November 2025  14:07:01 +0000 (0:00:00.051)       0:00:01.959 ******
2025-11-25 14:07:01.843115 | controller | changed: [instance] => (item={'state': 'absent', 'ips': ['192.168.253.50', '2345:0425:2CA1::dead'], 'names': ['dead.battlestar.galactica.lan']})
2025-11-25 14:07:01.876648 | controller | changed: [instance] => (item={'state': 'absent', 'ips': ['192.168.253.51', '2345:0425:2CA1::beef'], 'names': ['beef.battlestar.galactica.lan']})
2025-11-25 14:07:01.876677 | controller |
2025-11-25 14:07:01.876686 | controller | TASK [Remove some wildcard addresses] ******************************************
2025-11-25 14:07:01.876694 | controller | Tuesday 25 November 2025  14:07:01 +0000 (0:00:00.412)       0:00:02.371 ******
2025-11-25 14:07:01.876704 | controller |
2025-11-25 14:07:01.911793 | controller | TASK [dnsmasq : Assert we have needed address data] ****************************
2025-11-25 14:07:01.911815 | controller | Tuesday 25 November 2025  14:07:01 +0000 (0:00:00.033)       0:00:02.405 ******
2025-11-25 14:07:01.911824 | controller | ok: [instance]
2025-11-25 14:07:01.996998 | controller |
2025-11-25 14:07:01.997020 | controller | TASK [dnsmasq : Assert each address element have needed data] ******************
2025-11-25 14:07:01.997028 | controller | Tuesday 25 November 2025  14:07:01 +0000 (0:00:00.035)       0:00:02.441 ******
2025-11-25 14:07:01.997048 | controller | ok: [instance] => (item={'state': 'absent', 'ipaddr': '1.2.3.4', 'domains': ['apps.ocp.starwars.lan']})
2025-11-25 14:07:02.409130 | controller | ok: [instance] => (item={'state': 'absent', 'ipaddr': '4.3.2.1', 'domains': ['apps.ocp.startrek.lan']})
2025-11-25 14:07:02.409161 | controller |
2025-11-25 14:07:02.409170 | controller | TASK [dnsmasq : Add/Remove address] ********************************************
2025-11-25 14:07:02.409176 | controller | Tuesday 25 November 2025  14:07:01 +0000 (0:00:00.084)       0:00:02.525 ******
2025-11-25 14:07:02.409186 | controller | changed: [instance] => (item={'state': 'absent', 'ipaddr': '1.2.3.4', 'domains': ['apps.ocp.starwars.lan']})
2025-11-25 14:07:02.409209 | controller | changed: [instance] => (item={'state': 'absent', 'ipaddr': '4.3.2.1', 'domains': ['apps.ocp.startrek.lan']})
2025-11-25 14:07:02.409215 | controller |
2025-11-25 14:07:02.409222 | controller | TASK [dnsmasq : Cleaning dnsmasq] **********************************************
2025-11-25 14:07:02.409328 | controller | Tuesday 25 November 2025  14:07:02 +0000 (0:00:00.412)       0:00:02.938 ******
2025-11-25 14:07:02.439101 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/dnsmasq/tasks/configure.yml for instance
2025-11-25 14:07:03.805445 | controller |
2025-11-25 14:07:03.805477 | controller | TASK [dnsmasq : Install needed packages] ***************************************
2025-11-25 14:07:03.805485 | controller | Tuesday 25 November 2025  14:07:02 +0000 (0:00:00.029)       0:00:02.968 ******
2025-11-25 14:07:03.805496 | controller | ok: [instance]
2025-11-25 14:07:07.162132 | controller |
2025-11-25 14:07:07.162165 | controller | TASK [dnsmasq : Ensure SELinux knows about the new files and directories] ******
2025-11-25 14:07:07.162173 | controller | Tuesday 25 November 2025  14:07:03 +0000 (0:00:01.366)       0:00:04.334 ******
2025-11-25 14:07:07.162184 | controller | changed: [instance] => (item={'target': '/etc/cifmw-dnsmasq.conf', 'setype': 'dnsmasq_etc_t'})
2025-11-25 14:07:07.162263 | controller | changed: [instance] => (item={'target': '/etc/cifmw-dnsmasq.d(/.*)?', 'setype': 'dnsmasq_etc_t'})
2025-11-25 14:07:07.162292 | controller | changed: [instance] => (item={'target': '/var/run/cifmw-dnsmasq.pid', 'setype': 'dnsmasq_var_run_t'})
2025-11-25 14:07:07.162300 | controller |
2025-11-25 14:07:07.162306 | controller | TASK [dnsmasq : Manage configuration directory] ********************************
2025-11-25 14:07:07.162317 | controller | Tuesday 25 November 2025  14:07:07 +0000 (0:00:03.356)       0:00:07.691 ******
2025-11-25 14:07:07.476442 | controller | changed: [instance]
2025-11-25 14:07:07.494166 | controller |
2025-11-25 14:07:07.494202 | controller | TASK [dnsmasq : Manage systemd unit file] **************************************
2025-11-25 14:07:07.494212 | controller | Tuesday 25 November 2025  14:07:07 +0000 (0:00:00.314)       0:00:08.005 ******
2025-11-25 14:07:07.494224 | controller | skipping: [instance]
2025-11-25 14:07:07.511006 | controller |
2025-11-25 14:07:07.511031 | controller | TASK [dnsmasq : Manage base configuration file] ********************************
2025-11-25 14:07:07.511039 | controller | Tuesday 25 November 2025  14:07:07 +0000 (0:00:00.017)       0:00:08.023 ******
2025-11-25 14:07:07.511049 | controller | skipping: [instance]
2025-11-25 14:07:07.534603 | controller |
2025-11-25 14:07:07.534621 | controller | TASK [dnsmasq : Render listener configuration] *********************************
2025-11-25 14:07:07.534628 | controller | Tuesday 25 November 2025  14:07:07 +0000 (0:00:00.016)       0:00:08.040 ******
2025-11-25 14:07:07.534637 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/dnsmasq/tasks/listener.yml for instance
2025-11-25 14:07:07.551423 | controller |
2025-11-25 14:07:07.551440 | controller | TASK [dnsmasq : Create listener configuration file] ****************************
2025-11-25 14:07:07.551447 | controller | Tuesday 25 November 2025  14:07:07 +0000 (0:00:00.023)       0:00:08.063 ******
2025-11-25 14:07:07.551456 | controller | skipping: [instance]
2025-11-25 14:07:07.751503 | controller |
2025-11-25 14:07:07.751530 | controller | TASK [dnsmasq : Remove listener configuration file] ****************************
2025-11-25 14:07:07.751538 | controller | Tuesday 25 November 2025  14:07:07 +0000 (0:00:00.016)       0:00:08.080 ******
2025-11-25 14:07:07.751547 | controller | changed: [instance]
2025-11-25 14:07:07.777517 | controller |
2025-11-25 14:07:07.777547 | controller | TASK [dnsmasq : Render dns configuration] **************************************
2025-11-25 14:07:07.777555 | controller | Tuesday 25 November 2025  14:07:07 +0000 (0:00:00.199)       0:00:08.280 ******
2025-11-25 14:07:07.777569 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/dnsmasq/tasks/dns.yml for instance
2025-11-25 14:07:07.796745 | controller |
2025-11-25 14:07:07.796768 | controller | TASK [dnsmasq : Create dns configuration file] *********************************
2025-11-25 14:07:07.796777 | controller | Tuesday 25 November 2025  14:07:07 +0000 (0:00:00.026)       0:00:08.306 ******
2025-11-25 14:07:07.796786 | controller | skipping: [instance]
2025-11-25 14:07:08.005147 | controller |
2025-11-25 14:07:08.005177 | controller | TASK [dnsmasq : Remove dns configuration file] *********************************
2025-11-25 14:07:08.005185 | controller | Tuesday 25 November 2025  14:07:07 +0000 (0:00:00.019)       0:00:08.326 ******
2025-11-25 14:07:08.005194 | controller | changed: [instance]
2025-11-25 14:07:08.021684 | controller |
2025-11-25 14:07:08.021704 | controller | TASK [dnsmasq : Add localhost addresses from defined dnsmasq listen addresses to loopback interface] ***
2025-11-25 14:07:08.021712 | controller | Tuesday 25 November 2025  14:07:08 +0000 (0:00:00.208)       0:00:08.534 ******
2025-11-25 14:07:08.021721 | controller | skipping: [instance]
2025-11-25 14:07:08.039196 | controller |
2025-11-25 14:07:08.039241 | controller | TASK [dnsmasq : Enable and start service] **************************************
2025-11-25 14:07:08.039251 | controller | Tuesday 25 November 2025  14:07:08 +0000 (0:00:00.016)       0:00:08.550 ******
2025-11-25 14:07:08.039264 | controller | skipping: [instance]
2025-11-25 14:07:08.039285 | controller |
2025-11-25 14:07:08.039293 | controller | TASK [dnsmasq : Stop service] **************************************************
2025-11-25 14:07:08.039302 | controller | Tuesday 25 November 2025  14:07:08 +0000 (0:00:00.017)       0:00:08.568 ******
2025-11-25 14:07:08.736198 | controller | changed: [instance]
2025-11-25 14:07:08.736526 | controller |
2025-11-25 14:07:08.931131 | controller | TASK [dnsmasq : Remove unit file] **********************************************
2025-11-25 14:07:08.931160 | controller | Tuesday 25 November 2025  14:07:08 +0000 (0:00:00.696)       0:00:09.265 ******
2025-11-25 14:07:08.931172 | controller | changed: [instance]
2025-11-25 14:07:08.931198 | controller |
2025-11-25 14:07:08.931502 | controller | TASK [dnsmasq : Remove main configuration files] *******************************
2025-11-25 14:07:09.318143 | controller | Tuesday 25 November 2025  14:07:08 +0000 (0:00:00.194)       0:00:09.460 ******
2025-11-25 14:07:09.318184 | controller | changed: [instance] => (item=/etc/cifmw-dnsmasq.conf)
2025-11-25 14:07:09.318212 | controller | changed: [instance] => (item=/etc/cifmw-dnsmasq.d)
2025-11-25 14:07:09.318525 | controller |
2025-11-25 14:07:09.527352 | controller | TASK [dnsmasq : Remove lease file] *********************************************
2025-11-25 14:07:09.527385 | controller | Tuesday 25 November 2025  14:07:09 +0000 (0:00:00.387)       0:00:09.847 ******
2025-11-25 14:07:09.527397 | controller | changed: [instance]
2025-11-25 14:07:09.850167 | controller |
2025-11-25 14:07:09.850202 | controller | RUNNING HANDLER [dnsmasq : Restart dnsmasq] ************************************
2025-11-25 14:07:09.850213 | controller | Tuesday 25 November 2025  14:07:09 +0000 (0:00:00.208)       0:00:10.056 ******
2025-11-25 14:07:09.850225 | controller | ok: [instance]
2025-11-25 14:07:09.850276 | controller |
2025-11-25 14:07:09.850305 | controller | PLAY RECAP *********************************************************************
2025-11-25 14:07:09.850314 | controller | instance                   : ok=24   changed=11   unreachable=0    failed=0    skipped=6    rescued=0    ignored=0
2025-11-25 14:07:09.850319 | controller |
2025-11-25 14:07:09.850325 | controller | Tuesday 25 November 2025  14:07:09 +0000 (0:00:00.320)       0:00:10.377 ******
2025-11-25 14:07:09.850331 | controller | ===============================================================================
2025-11-25 14:07:09.850336 | controller | dnsmasq : Ensure SELinux knows about the new files and directories ------ 3.36s
2025-11-25 14:07:09.850342 | controller | dnsmasq : Install needed packages --------------------------------------- 1.37s
2025-11-25 14:07:09.850347 | controller | Gathering Facts --------------------------------------------------------- 0.84s
2025-11-25 14:07:09.850352 | controller | dnsmasq : Stop service -------------------------------------------------- 0.70s
2025-11-25 14:07:09.850362 | controller | Copy generated content in ci-framework-data/artifacts ------------------- 0.57s
2025-11-25 14:07:09.850368 | controller | dnsmasq : Add/Remove address -------------------------------------------- 0.41s
2025-11-25 14:07:09.850373 | controller | dnsmasq : Add/Remove address -------------------------------------------- 0.41s
2025-11-25 14:07:09.850379 | controller | dnsmasq : Remove main configuration files ------------------------------- 0.39s
2025-11-25 14:07:09.850384 | controller | dnsmasq : Restart dnsmasq ----------------------------------------------- 0.32s
2025-11-25 14:07:09.850389 | controller | dnsmasq : Manage configuration directory -------------------------------- 0.31s
2025-11-25 14:07:09.850395 | controller | dnsmasq : Add/Remove forwarder ------------------------------------------ 0.31s
2025-11-25 14:07:09.850405 | controller | dnsmasq : Remove lease file --------------------------------------------- 0.21s
2025-11-25 14:07:09.850410 | controller | dnsmasq : Remove dns configuration file --------------------------------- 0.21s
2025-11-25 14:07:09.850416 | controller | dnsmasq : Remove listener configuration file ---------------------------- 0.20s
2025-11-25 14:07:09.850421 | controller | dnsmasq : Remove unit file ---------------------------------------------- 0.19s
2025-11-25 14:07:09.850426 | controller | dnsmasq : Assert each address element have needed data ------------------ 0.08s
2025-11-25 14:07:09.850432 | controller | dnsmasq : Assert each address element have needed data ------------------ 0.05s
2025-11-25 14:07:09.850437 | controller | dnsmasq : Assert each forwarder element have needed data ---------------- 0.04s
2025-11-25 14:07:09.850443 | controller | dnsmasq : Assert we have needed address data ---------------------------- 0.04s
2025-11-25 14:07:09.850452 | controller | dnsmasq : Assert we have needed host record data ------------------------ 0.03s
2025-11-25 14:07:09.901779 | controller | INFO     Writing /tmp/report.html report.
2025-11-25 14:07:10.135260 | controller | changed
2025-11-25 14:07:10.159085 | 
2025-11-25 14:07:10.159374 | PLAY RECAP
2025-11-25 14:07:10.159428 | controller | ok: 4 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-11-25 14:07:10.159454 | 
2025-11-25 14:07:10.244613 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-11-25 14:07:10.245411 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-11-25 14:07:10.832373 | 
2025-11-25 14:07:10.832463 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-11-25 14:07:10.853835 | 
2025-11-25 14:07:10.853927 | TASK [Filter out host if needed]
2025-11-25 14:07:10.862523 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-11-25 14:07:10.866891 | 
2025-11-25 14:07:10.867032 | TASK [Ensure file is present]
2025-11-25 14:07:11.190764 | controller | ok
2025-11-25 14:07:11.197616 | 
2025-11-25 14:07:11.197686 | TASK [Manage molecule report file]
2025-11-25 14:07:11.723082 | controller | changed
2025-11-25 14:07:11.728512 | 
2025-11-25 14:07:11.728585 | TASK [Check if we get ci-framework-data basedir]
2025-11-25 14:07:11.910499 | controller | ok
2025-11-25 14:07:11.915949 | 
2025-11-25 14:07:11.916020 | TASK [Create ci-framework-data log directory for zuul]
2025-11-25 14:07:12.231273 | controller | changed
2025-11-25 14:07:12.236591 | 
2025-11-25 14:07:12.236657 | TASK [Copy ci-framework interesting files]
2025-11-25 14:07:12.441400 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2025-11-25 14:07:12.776748 | controller | changed
2025-11-25 14:07:12.782289 | 
2025-11-25 14:07:12.782379 | TASK [Get SELinux listing]
2025-11-25 14:07:13.332256 | controller | changed
2025-11-25 14:07:13.338209 | 
2025-11-25 14:07:13.338277 | TASK [Generate log index]
2025-11-25 14:07:14.040642 | controller | changed
2025-11-25 14:07:14.045842 | 
2025-11-25 14:07:14.045926 | TASK [Get some env related data]
2025-11-25 14:07:14.625263 | controller | /home/zuul/.local/bin/ansible
2025-11-25 14:07:15.575258 | controller | changed
2025-11-25 14:07:15.583585 | 
2025-11-25 14:07:15.583676 | TASK [Generate list of logs to collect in home directory]
2025-11-25 14:07:16.039594 | controller | ok: All paths examined
2025-11-25 14:07:16.047616 | 
2025-11-25 14:07:16.047700 | LOOP [Copy logs from home directory]
2025-11-25 14:07:16.479993 | controller | changed:
2025-11-25 14:07:16.480108 | controller | {
2025-11-25 14:07:16.480136 | controller |   "atime": 1764079485.156418,
2025-11-25 14:07:16.480158 | controller |   "ctime": 1764079503.0126429,
2025-11-25 14:07:16.480176 | controller |   "dev": 64513,
2025-11-25 14:07:16.480195 | controller |   "gid": 1000,
2025-11-25 14:07:16.480213 | controller |   "gr_name": "zuul",
2025-11-25 14:07:16.480231 | controller |   "inode": 4387109,
2025-11-25 14:07:16.480247 | controller |   "isblk": false,
2025-11-25 14:07:16.480263 | controller |   "ischr": false,
2025-11-25 14:07:16.480279 | controller |   "isdir": false,
2025-11-25 14:07:16.480294 | controller |   "isfifo": false,
2025-11-25 14:07:16.480310 | controller |   "isgid": false,
2025-11-25 14:07:16.480325 | controller |   "islnk": false,
2025-11-25 14:07:16.480349 | controller |   "isreg": true,
2025-11-25 14:07:16.480368 | controller |   "issock": false,
2025-11-25 14:07:16.480384 | controller |   "isuid": false,
2025-11-25 14:07:16.480401 | controller |   "mode": "0644",
2025-11-25 14:07:16.480417 | controller |   "mtime": 1764079503.0126429,
2025-11-25 14:07:16.480434 | controller |   "nlink": 1,
2025-11-25 14:07:16.480450 | controller |   "path": "/home/zuul/ansible.log",
2025-11-25 14:07:16.480468 | controller |   "pw_name": "zuul",
2025-11-25 14:07:16.480484 | controller |   "rgrp": true,
2025-11-25 14:07:16.480501 | controller |   "roth": true,
2025-11-25 14:07:16.480517 | controller |   "rusr": true,
2025-11-25 14:07:16.480533 | controller |   "size": 6749,
2025-11-25 14:07:16.480549 | controller |   "uid": 1000,
2025-11-25 14:07:16.480564 | controller |   "wgrp": false,
2025-11-25 14:07:16.480579 | controller |   "woth": false,
2025-11-25 14:07:16.480597 | controller |   "wusr": true,
2025-11-25 14:07:16.480614 | controller |   "xgrp": false,
2025-11-25 14:07:16.480629 | controller |   "xoth": false,
2025-11-25 14:07:16.480644 | controller |   "xusr": false
2025-11-25 14:07:16.480659 | controller | }
2025-11-25 14:07:16.491262 | 
2025-11-25 14:07:16.491372 | TASK [Copy crio stats log file]
2025-11-25 14:07:16.515684 | controller | skipping: Conditional result was False
2025-11-25 14:07:16.521008 | 
2025-11-25 14:07:16.521076 | TASK [Get SELinux related data]
2025-11-25 14:07:16.783464 | controller | <no matches>
2025-11-25 14:07:17.066223 | controller | ERROR
2025-11-25 14:07:17.066465 | controller | {
2025-11-25 14:07:17.066499 | controller |   "delta": "0:00:00.009071",
2025-11-25 14:07:17.066521 | controller |   "end": "2025-11-25 14:07:16.784303",
2025-11-25 14:07:17.066540 | controller |   "msg": "non-zero return code",
2025-11-25 14:07:17.066576 | controller |   "rc": 1,
2025-11-25 14:07:17.066598 | controller |   "start": "2025-11-25 14:07:16.775232"
2025-11-25 14:07:17.066615 | controller | }
2025-11-25 14:07:17.066653 | controller | ERROR: Ignoring Errors
2025-11-25 14:07:17.072092 | 
2025-11-25 14:07:17.072159 | TASK [Create system configuration directory]
2025-11-25 14:07:17.264867 | controller | changed
2025-11-25 14:07:17.269788 | 
2025-11-25 14:07:17.269859 | TASK [Get some of the system configurations]
2025-11-25 14:07:17.816092 | controller | changed
2025-11-25 14:07:17.821451 | 
2025-11-25 14:07:17.821519 | TASK [Copy generated documentation if available]
2025-11-25 14:07:17.868164 | controller | skipping: Conditional result was False
2025-11-25 14:07:17.877721 | 
2025-11-25 14:07:17.877843 | TASK [Copy generated AsciiDoc documentation if available]
2025-11-25 14:07:17.901647 | controller | skipping: Conditional result was False
2025-11-25 14:07:17.907040 | 
2025-11-25 14:07:17.907106 | TASK [Compress logs bigger than 2MB]
2025-11-25 14:07:18.460658 | controller | changed
2025-11-25 14:07:18.467544 | 
2025-11-25 14:07:18.467620 | TASK [Copy files from workspace on node]
2025-11-25 14:07:18.486732 | controller | ok
2025-11-25 14:07:18.531284 | 
2025-11-25 14:07:18.531395 | TASK [fetch-output : Set log path for multiple nodes]
2025-11-25 14:07:18.569269 | controller | skipping: Conditional result was False
2025-11-25 14:07:18.577892 | 
2025-11-25 14:07:18.578082 | TASK [fetch-output : Set log path for single node]
2025-11-25 14:07:18.627479 | controller | ok
2025-11-25 14:07:18.632434 | 
2025-11-25 14:07:18.632503 | LOOP [fetch-output : Ensure local output dirs]
2025-11-25 14:07:19.095309 | controller -> localhost | ok: "/var/lib/zuul/builds/69c19b2c62874aa0a2269ecdfe1f8840/work/logs"
2025-11-25 14:07:19.095554 | controller -> localhost | changed: All items complete
2025-11-25 14:07:19.095584 | 
2025-11-25 14:07:19.406901 | controller -> localhost | changed: "/var/lib/zuul/builds/69c19b2c62874aa0a2269ecdfe1f8840/work/artifacts"
2025-11-25 14:07:19.758518 | controller -> localhost | changed: "/var/lib/zuul/builds/69c19b2c62874aa0a2269ecdfe1f8840/work/docs"
2025-11-25 14:07:19.768813 | 
2025-11-25 14:07:19.768940 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-11-25 14:07:20.615073 | controller | changed:
2025-11-25 14:07:20.615233 | controller | .d..t...... ./
2025-11-25 14:07:20.615264 | controller | >f+++++++++ README.html
2025-11-25 14:07:20.615287 | controller | >f+++++++++ ansible-execution.log
2025-11-25 14:07:20.615309 | controller | >f+++++++++ ansible.log
2025-11-25 14:07:20.615329 | controller | >f+++++++++ dmesg.log
2025-11-25 14:07:20.615358 | controller | >f+++++++++ installed-pkgs.log
2025-11-25 14:07:20.615382 | controller | >f+++++++++ python.log
2025-11-25 14:07:20.615406 | controller | >f+++++++++ registries.conf
2025-11-25 14:07:20.615427 | controller | >f+++++++++ report.html
2025-11-25 14:07:20.615448 | controller | >f+++++++++ selinux-denials.log
2025-11-25 14:07:20.615467 | controller | >f+++++++++ selinux-listing.log
2025-11-25 14:07:20.615486 | controller | cd+++++++++ ci-framework-data/
2025-11-25 14:07:20.615503 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-11-25 14:07:20.615521 | controller | >f+++++++++ ci-framework-data/artifacts/addresses.conf
2025-11-25 14:07:20.615539 | controller | >f+++++++++ ci-framework-data/artifacts/cifmw-dnsmasq.conf
2025-11-25 14:07:20.615556 | controller | >f+++++++++ ci-framework-data/artifacts/dns.conf
2025-11-25 14:07:20.615574 | controller | >f+++++++++ ci-framework-data/artifacts/forwarders.conf
2025-11-25 14:07:20.615590 | controller | >f+++++++++ ci-framework-data/artifacts/host_records.conf
2025-11-25 14:07:20.615607 | controller | >f+++++++++ ci-framework-data/artifacts/listener.conf
2025-11-25 14:07:20.615624 | controller | >f+++++++++ ci-framework-data/artifacts/oldies.conf
2025-11-25 14:07:20.615641 | controller | >f+++++++++ ci-framework-data/artifacts/startrek.conf
2025-11-25 14:07:20.615657 | controller | >f+++++++++ ci-framework-data/artifacts/starwars.conf
2025-11-25 14:07:20.615674 | controller | >f+++++++++ ci-framework-data/artifacts/test-dhcp-options.conf
2025-11-25 14:07:20.615691 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-11-25 14:07:20.615708 | controller | cd+++++++++ ci-framework-data/artifacts/dhcp-hosts.d/
2025-11-25 14:07:20.615726 | controller | >f+++++++++ ci-framework-data/artifacts/dhcp-hosts.d/startrek_data_0a:19:02:f8:4c:b3
2025-11-25 14:07:20.615743 | controller | >f+++++++++ ci-framework-data/artifacts/dhcp-hosts.d/startrek_spock_0a:19:02:f8:4c:a8
2025-11-25 14:07:20.615767 | controller | >f+++++++++ ci-framework-data/artifacts/dhcp-hosts.d/starwars_0a:19:02:f8:4c:a7
2025-11-25 14:07:20.615786 | controller | >f+++++++++ ci-framework-data/artifacts/dhcp-hosts.d/starwars_c3po_0a:19:02:f8:4c:b2
2025-11-25 14:07:20.615804 | controller | >f+++++++++ ci-framework-data/artifacts/dhcp-hosts.d/starwars_r2d2_0a:19:02:f8:4c:b1
2025-11-25 14:07:20.615821 | controller | >f+++++++++ ci-framework-data/artifacts/dhcp-hosts.d/starwars_solo_0a:19:02:f8:4c:a8
2025-11-25 14:07:20.615837 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-11-25 14:07:20.615854 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-11-25 14:07:20.615872 | controller | cd+++++++++ ci-framework-data/artifacts/repositories/
2025-11-25 14:07:20.615890 | controller | >f+++++++++ ci-framework-data/artifacts/repositories/delorean.repo.md5
2025-11-25 14:07:20.615924 | controller | cd+++++++++ ci-framework-data/logs/
2025-11-25 14:07:20.615950 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2025-11-25 14:07:20.615970 | controller | cd+++++++++ registries.conf.d/
2025-11-25 14:07:20.615989 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-11-25 14:07:20.616007 | controller | cd+++++++++ system-config/
2025-11-25 14:07:20.616024 | controller | cd+++++++++ system-config/libvirt/
2025-11-25 14:07:20.616042 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-11-25 14:07:20.616060 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-11-25 14:07:21.199612 | controller | changed: .d..t...... ./
2025-11-25 14:07:21.725397 | controller | changed: .d..t...... ./
2025-11-25 14:07:21.748483 | 
2025-11-25 14:07:21.748562 | TASK [Return artifact to Zuul]
2025-11-25 14:07:21.817235 | controller | ok
2025-11-25 14:07:21.837378 | 
2025-11-25 14:07:21.837436 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-11-25 14:07:21.837530 | 
2025-11-25 14:07:21.837557 | PLAY RECAP
2025-11-25 14:07:21.837594 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2025-11-25 14:07:21.837615 | 
2025-11-25 14:07:21.980491 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-11-25 14:07:21.981277 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-11-25 14:07:22.798215 | 
2025-11-25 14:07:22.798395 | PLAY [all]
2025-11-25 14:07:22.818857 | 
2025-11-25 14:07:22.818970 | TASK [include_role : fetch-output]
2025-11-25 14:07:22.900607 | controller | ok
2025-11-25 14:07:22.924072 | 
2025-11-25 14:07:22.924167 | TASK [fetch-output : Set log path for multiple nodes]
2025-11-25 14:07:23.025748 | controller | skipping: Conditional result was False
2025-11-25 14:07:23.031774 | 
2025-11-25 14:07:23.031850 | TASK [fetch-output : Set log path for single node]
2025-11-25 14:07:23.101631 | controller | ok
2025-11-25 14:07:23.112980 | 
2025-11-25 14:07:23.113076 | LOOP [fetch-output : Ensure local output dirs]
2025-11-25 14:07:23.730888 | controller -> localhost | ok: "/var/lib/zuul/builds/69c19b2c62874aa0a2269ecdfe1f8840/work/logs"
2025-11-25 14:07:24.003960 | controller -> localhost | ok: "/var/lib/zuul/builds/69c19b2c62874aa0a2269ecdfe1f8840/work/artifacts"
2025-11-25 14:07:24.324111 | controller -> localhost | ok: "/var/lib/zuul/builds/69c19b2c62874aa0a2269ecdfe1f8840/work/docs"
2025-11-25 14:07:24.340966 | 
2025-11-25 14:07:24.341091 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-11-25 14:07:25.141047 | controller | ok
2025-11-25 14:07:25.141399 | controller | ok: All items complete
2025-11-25 14:07:25.141431 | 
2025-11-25 14:07:25.865038 | controller | ok
2025-11-25 14:07:26.341456 | controller | ok
2025-11-25 14:07:26.360196 | 
2025-11-25 14:07:26.360329 | TASK [include_role : fetch-output-openshift]
2025-11-25 14:07:26.395134 | controller | skipping: Conditional result was False
2025-11-25 14:07:26.401656 | 
2025-11-25 14:07:26.401745 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-11-25 14:07:26.854230 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.005984
2025-11-25 14:07:27.092399 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.009569
2025-11-25 14:07:27.129096 | 
2025-11-25 14:07:27.129193 | PLAY [all]
2025-11-25 14:07:27.144179 | 
2025-11-25 14:07:27.144250 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-11-25 14:07:27.547980 | controller | changed
2025-11-25 14:07:27.574372 | 
2025-11-25 14:07:27.574479 | PLAY RECAP
2025-11-25 14:07:27.574532 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-11-25 14:07:27.574556 | 
2025-11-25 14:07:27.696832 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-11-25 14:07:27.697639 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-11-25 14:07:28.294426 | 
2025-11-25 14:07:28.294534 | PLAY [localhost]
2025-11-25 14:07:28.313828 | 
2025-11-25 14:07:28.313902 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-11-25 14:07:28.814644 | localhost | changed
2025-11-25 14:07:28.819380 | 
2025-11-25 14:07:28.819461 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-11-25 14:07:28.851399 | localhost | ok
2025-11-25 14:07:28.862401 | 
2025-11-25 14:07:28.862473 | TASK [add-fileserver : Create SSH private key tempfile]
2025-11-25 14:07:29.363803 | localhost | changed
2025-11-25 14:07:29.370790 | 
2025-11-25 14:07:29.370879 | TASK [add-fileserver : Create SSH private key from secret]
2025-11-25 14:07:30.414770 | localhost | changed
2025-11-25 14:07:30.421305 | 
2025-11-25 14:07:30.421435 | TASK [add-fileserver : Add fileserver ssh key]
2025-11-25 14:07:30.991176 | localhost | Identity added: /var/lib/zuul/builds/69c19b2c62874aa0a2269ecdfe1f8840/work/tmp/ansible.v17xb3sx (/var/lib/zuul/builds/69c19b2c62874aa0a2269ecdfe1f8840/work/tmp/ansible.v17xb3sx)
2025-11-25 14:07:30.991392 | localhost | ok: Runtime: 0:00:00.007128
2025-11-25 14:07:30.995873 | 
2025-11-25 14:07:30.995963 | TASK [add-fileserver : Remove SSH private key from disk]
2025-11-25 14:07:31.258783 | localhost | ok: Runtime: 0:00:00.007647
2025-11-25 14:07:31.264607 | 
2025-11-25 14:07:31.264690 | TASK [add-fileserver : Add fileserver to inventory]
2025-11-25 14:07:31.324684 | localhost | changed
2025-11-25 14:07:31.329461 | 
2025-11-25 14:07:31.329531 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-11-25 14:07:31.736129 | localhost | changed
2025-11-25 14:07:31.764404 | 
2025-11-25 14:07:31.764481 | PLAY [localhost]
2025-11-25 14:07:31.776357 | 
2025-11-25 14:07:31.776421 | TASK [Generate bulk log download script]
2025-11-25 14:07:31.795252 | localhost | ok
2025-11-25 14:07:31.808831 | 
2025-11-25 14:07:31.808903 | TASK [local-log-download : Check API endpoint is defined]
2025-11-25 14:07:31.839312 | localhost | ok: All assertions passed
2025-11-25 14:07:31.844216 | 
2025-11-25 14:07:31.844285 | TASK [local-log-download : Create download script]
2025-11-25 14:07:32.225060 | localhost -> localhost | changed
2025-11-25 14:07:32.235272 | 
2025-11-25 14:07:32.235436 | TASK [Register quick-download link]
2025-11-25 14:07:32.274212 | localhost | ok
2025-11-25 14:07:32.289315 | 
2025-11-25 14:07:32.289378 | PLAY [logserver.rdoproject.org]
2025-11-25 14:07:32.299797 | 
2025-11-25 14:07:32.299856 | TASK [Set zuul-log-path fact]
2025-11-25 14:07:32.326957 | logserver.rdoproject.org | ok
2025-11-25 14:07:32.336307 | 
2025-11-25 14:07:32.336387 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-11-25 14:07:32.365390 | logserver.rdoproject.org | ok
2025-11-25 14:07:32.371163 | 
2025-11-25 14:07:32.371229 | TASK [upload-logs : Create log directories]
2025-11-25 14:07:34.437006 | logserver.rdoproject.org | changed
2025-11-25 14:07:34.440319 | 
2025-11-25 14:07:34.440420 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-11-25 14:07:34.736533 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.017522
2025-11-25 14:07:34.742017 | 
2025-11-25 14:07:34.742091 | TASK [upload-logs : Upload logs to log server]
2025-11-25 14:07:36.199603 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-11-25 14:07:36.202573 | 
2025-11-25 14:07:36.202643 | LOOP [upload-logs : Compress console log and json output]
2025-11-25 14:07:36.248435 | logserver.rdoproject.org | skipping: Conditional result was False
2025-11-25 14:07:36.251307 | logserver.rdoproject.org | skipping: Conditional result was False
2025-11-25 14:07:36.259559 | 
2025-11-25 14:07:36.259659 | LOOP [upload-logs : Upload compressed console log and json output]
2025-11-25 14:07:36.319512 | logserver.rdoproject.org | skipping: Conditional result was False
2025-11-25 14:07:36.319754 | 
2025-11-25 14:07:36.320360 | logserver.rdoproject.org | skipping: Conditional result was False
2025-11-25 14:07:36.327294 | 
2025-11-25 14:07:36.327404 | LOOP [upload-logs : Upload console log and json output]