2026-06-12 14:09:54.083074 | Job console starting
2026-06-12 14:09:54.210694 | Updating git repos
2026-06-12 14:09:54.264639 | Cloning repos into workspace
2026-06-12 14:09:56.590084 | Restoring repo states
2026-06-12 14:09:56.612229 | Merging changes
2026-06-12 14:09:57.575852 | Checking out repos
2026-06-12 14:09:58.385705 | Preparing playbooks
2026-06-12 14:10:04.624447 | Running Ansible setup
2026-06-12 14:10:08.566718 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2026-06-12 14:10:09.243126 | 
2026-06-12 14:10:09.243263 | PLAY [localhost]
2026-06-12 14:10:09.251348 | 
2026-06-12 14:10:09.251416 | TASK [Gathering Facts]
2026-06-12 14:10:10.284559 | localhost | ok
2026-06-12 14:10:10.298390 | 
2026-06-12 14:10:10.298658 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-06-12 14:10:10.805006 | localhost -> localhost | changed
2026-06-12 14:10:10.815777 | 
2026-06-12 14:10:10.815946 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-06-12 14:10:11.838482 | localhost -> localhost | changed
2026-06-12 14:10:11.852724 | 
2026-06-12 14:10:11.852868 | TASK [Setup log path fact]
2026-06-12 14:10:11.888076 | localhost | ok
2026-06-12 14:10:11.909783 | 
2026-06-12 14:10:11.909943 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-06-12 14:10:11.951081 | localhost | ok
2026-06-12 14:10:11.958954 | 
2026-06-12 14:10:11.959037 | TASK [emit-job-header : Print job information]
2026-06-12 14:10:12.010986 | # Job Information
2026-06-12 14:10:12.011238 | Ansible Version: 2.16.18
2026-06-12 14:10:12.011280 | Job: edpm-ansible-molecule-edpm_frr
2026-06-12 14:10:12.011308 | Pipeline: github-check
2026-06-12 14:10:12.011332 | Executor: 38.102.83.40
2026-06-12 14:10:12.011357 | Triggered by: https://github.com/openstack-k8s-operators/edpm-ansible/pull/1188
2026-06-12 14:10:12.011382 | Log URL (when completed): https://gateway-cloud-softwarefactory.apps.ocp.cloud.ci.centos.org/logs/51f/rdoproject.org/51f4563f9d0a44f49d26838494b1b441/
2026-06-12 14:10:12.011409 | Event ID: 2b895b10-6668-11f1-981a-277a6eb09646
2026-06-12 14:10:12.015948 | 
2026-06-12 14:10:12.016039 | LOOP [emit-job-header : Print node information]
2026-06-12 14:10:12.129968 | localhost | ok:
2026-06-12 14:10:12.130261 | localhost | # Node Information
2026-06-12 14:10:12.130318 | localhost | Inventory Hostname: controller
2026-06-12 14:10:12.130367 | localhost | Hostname: np0000071956
2026-06-12 14:10:12.130407 | localhost | Username: zuul
2026-06-12 14:10:12.130449 | localhost | Distro: CentOS 9
2026-06-12 14:10:12.130487 | localhost | Provider: vexxhost-nodepool-tripleo
2026-06-12 14:10:12.130522 | localhost | Region: RegionOne
2026-06-12 14:10:12.130557 | localhost | Label: cloud-centos-9-stream-tripleo
2026-06-12 14:10:12.130641 | localhost | Product Name: OpenStack Nova
2026-06-12 14:10:12.130681 | localhost | Interface IP: 38.102.83.102
2026-06-12 14:10:12.142675 | 
2026-06-12 14:10:12.142867 | PLAY [all]
2026-06-12 14:10:12.163812 | 
2026-06-12 14:10:12.163908 | TASK [Gather network facts]
2026-06-12 14:10:12.651793 | controller | ok
2026-06-12 14:10:12.664029 | 
2026-06-12 14:10:12.664154 | TASK [include_role : start-zuul-console]
2026-06-12 14:10:12.700071 | controller | ok
2026-06-12 14:10:12.720827 | 
2026-06-12 14:10:12.720963 | TASK [start-zuul-console : Start zuul_console daemon.]
2026-06-12 14:10:13.159659 | controller | ok
2026-06-12 14:10:13.172395 | 
2026-06-12 14:10:13.172519 | TASK [include_role : add-build-sshkey]
2026-06-12 14:10:13.206307 | controller | ok
2026-06-12 14:10:13.228359 | 
2026-06-12 14:10:13.228525 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-06-12 14:10:13.529559 | controller -> localhost | ok
2026-06-12 14:10:13.538874 | 
2026-06-12 14:10:13.539043 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-06-12 14:10:13.573292 | controller | ok
2026-06-12 14:10:13.601995 | controller | included: /var/lib/zuul/builds/51f4563f9d0a44f49d26838494b1b441/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-06-12 14:10:13.611569 | 
2026-06-12 14:10:13.611880 | TASK [add-build-sshkey : Create Temp SSH key]
2026-06-12 14:10:14.510746 | controller -> localhost | Generating public/private rsa key pair.
2026-06-12 14:10:14.511052 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/51f4563f9d0a44f49d26838494b1b441/work/51f4563f9d0a44f49d26838494b1b441_id_rsa
2026-06-12 14:10:14.511117 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/51f4563f9d0a44f49d26838494b1b441/work/51f4563f9d0a44f49d26838494b1b441_id_rsa.pub
2026-06-12 14:10:14.511164 | controller -> localhost | The key fingerprint is:
2026-06-12 14:10:14.511210 | controller -> localhost | SHA256:xPM1eYC/fJq65vESulHix3Dipok49SYUoMChGOKVFA0 zuul-build-sshkey
2026-06-12 14:10:14.511252 | controller -> localhost | The key's randomart image is:
2026-06-12 14:10:14.511291 | controller -> localhost | +---[RSA 3072]----+
2026-06-12 14:10:14.511330 | controller -> localhost | |+.oE=      ..    |
2026-06-12 14:10:14.511371 | controller -> localhost | |*oo. . .  .  o   |
2026-06-12 14:10:14.511410 | controller -> localhost | |+o .    +  .+ .  |
2026-06-12 14:10:14.511448 | controller -> localhost | |.   .  . o ..o   |
2026-06-12 14:10:14.511487 | controller -> localhost | |     .  S +. .   |
2026-06-12 14:10:14.511525 | controller -> localhost | |    o  o B. o .  |
2026-06-12 14:10:14.511615 | controller -> localhost | |   o .  =.+. +   |
2026-06-12 14:10:14.511680 | controller -> localhost | |  ....o+.oooo    |
2026-06-12 14:10:14.511723 | controller -> localhost | |  ...oo .+++.    |
2026-06-12 14:10:14.511761 | controller -> localhost | +----[SHA256]-----+
2026-06-12 14:10:14.511847 | controller -> localhost | ok: Runtime: 0:00:00.415756
2026-06-12 14:10:14.519561 | 
2026-06-12 14:10:14.519722 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-06-12 14:10:14.563494 | controller | ok
2026-06-12 14:10:14.582434 | controller | included: /var/lib/zuul/builds/51f4563f9d0a44f49d26838494b1b441/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-06-12 14:10:14.597187 | 
2026-06-12 14:10:14.597312 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-06-12 14:10:14.625264 | controller | skipping: Conditional result was False
2026-06-12 14:10:14.638054 | 
2026-06-12 14:10:14.638222 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-06-12 14:10:15.134950 | controller | changed
2026-06-12 14:10:15.143275 | 
2026-06-12 14:10:15.143422 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-06-12 14:10:15.397099 | controller | ok
2026-06-12 14:10:15.405155 | 
2026-06-12 14:10:15.405293 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-06-12 14:10:16.228289 | controller | changed
2026-06-12 14:10:16.237042 | 
2026-06-12 14:10:16.237263 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-06-12 14:10:17.069484 | controller | changed
2026-06-12 14:10:17.077500 | 
2026-06-12 14:10:17.077671 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-06-12 14:10:17.113654 | controller | skipping: Conditional result was False
2026-06-12 14:10:17.122858 | 
2026-06-12 14:10:17.122993 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-06-12 14:10:17.605501 | controller -> localhost | changed
2026-06-12 14:10:17.621492 | 
2026-06-12 14:10:17.621688 | TASK [add-build-sshkey : Add back temp key]
2026-06-12 14:10:17.957706 | controller -> localhost | Identity added: /var/lib/zuul/builds/51f4563f9d0a44f49d26838494b1b441/work/51f4563f9d0a44f49d26838494b1b441_id_rsa (zuul-build-sshkey)
2026-06-12 14:10:17.957980 | controller -> localhost | ok: Runtime: 0:00:00.018722
2026-06-12 14:10:17.962798 | 
2026-06-12 14:10:17.962879 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-06-12 14:10:18.371367 | controller | ok
2026-06-12 14:10:18.379197 | 
2026-06-12 14:10:18.379390 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-06-12 14:10:18.416995 | controller | skipping: Conditional result was False
2026-06-12 14:10:18.432304 | 
2026-06-12 14:10:18.432431 | TASK [include_role : validate-host]
2026-06-12 14:10:18.469681 | controller | ok
2026-06-12 14:10:18.500251 | 
2026-06-12 14:10:18.500348 | TASK [validate-host : Define zuul_info_dir fact]
2026-06-12 14:10:18.540521 | controller | ok
2026-06-12 14:10:18.544970 | 
2026-06-12 14:10:18.545054 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2026-06-12 14:10:18.865675 | controller -> localhost | ok
2026-06-12 14:10:18.875738 | 
2026-06-12 14:10:18.875877 | TASK [validate-host : Collect information about the host]
2026-06-12 14:10:19.633070 | controller | ok
2026-06-12 14:10:19.649641 | 
2026-06-12 14:10:19.649779 | TASK [validate-host : Sanitize hostname]
2026-06-12 14:10:19.734258 | controller | ok
2026-06-12 14:10:19.742126 | 
2026-06-12 14:10:19.742259 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2026-06-12 14:10:20.296524 | controller -> localhost | changed
2026-06-12 14:10:20.303034 | 
2026-06-12 14:10:20.303099 | TASK [validate-host : Collect information about zuul worker]
2026-06-12 14:10:20.800889 | controller | ok
2026-06-12 14:10:20.809689 | 
2026-06-12 14:10:20.809830 | TASK [validate-host : Write out all zuul information for each host]
2026-06-12 14:10:21.428513 | controller -> localhost | changed
2026-06-12 14:10:21.435965 | 
2026-06-12 14:10:21.436033 | TASK [include_role : prepare-workspace-openshift]
2026-06-12 14:10:21.461508 | controller | skipping: Conditional result was False
2026-06-12 14:10:21.466474 | 
2026-06-12 14:10:21.466555 | TASK [include_role : remove-zuul-sshkey]
2026-06-12 14:10:21.483014 | controller | skipping: Conditional result was False
2026-06-12 14:10:21.487826 | 
2026-06-12 14:10:21.487896 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-06-12 14:10:21.691157 | controller | ok: "logs"
2026-06-12 14:10:21.691412 | controller | ok: All items complete
2026-06-12 14:10:21.691437 | 
2026-06-12 14:10:21.908343 | controller | ok: "artifacts"
2026-06-12 14:10:22.140039 | controller | ok: "docs"
2026-06-12 14:10:22.149537 | 
2026-06-12 14:10:22.149762 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-06-12 14:10:22.453017 | controller | changed: "logs"
2026-06-12 14:10:22.684464 | controller | changed: "artifacts"
2026-06-12 14:10:22.911961 | controller | changed: "docs"
2026-06-12 14:10:22.925216 | 
2026-06-12 14:10:22.925430 | PLAY RECAP
2026-06-12 14:10:22.925519 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2026-06-12 14:10:22.925611 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-06-12 14:10:22.925659 | 
2026-06-12 14:10:23.140468 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2026-06-12 14:10:23.141830 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2026-06-12 14:10:23.825287 | 
2026-06-12 14:10:23.825435 | PLAY [all]
2026-06-12 14:10:23.836923 | 
2026-06-12 14:10:23.837033 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2026-06-12 14:10:23.919479 | controller | ok
2026-06-12 14:10:23.924781 | 
2026-06-12 14:10:23.924894 | TASK [mirror-info-fork : Create /etc/ci]
2026-06-12 14:10:24.367279 | controller | changed
2026-06-12 14:10:24.377021 | 
2026-06-12 14:10:24.377168 | TASK [mirror-info-fork : Install ci_mirror script]
2026-06-12 14:10:25.572226 | controller | changed
2026-06-12 14:10:25.580522 | 
2026-06-12 14:10:25.580842 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2026-06-12 14:10:26.047904 | controller | changed:
2026-06-12 14:10:26.048405 | controller | {
2026-06-12 14:10:26.048482 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2026-06-12 14:10:26.048553 | controller | }
2026-06-12 14:10:26.345835 | controller | changed:
2026-06-12 14:10:26.346032 | controller | {
2026-06-12 14:10:26.346082 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2026-06-12 14:10:26.346122 | controller | }
2026-06-12 14:10:26.621564 | controller | changed:
2026-06-12 14:10:26.621758 | controller | {
2026-06-12 14:10:26.621791 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com"
2026-06-12 14:10:26.621820 | controller | }
2026-06-12 14:10:26.901354 | controller | changed:
2026-06-12 14:10:26.901641 | controller | {
2026-06-12 14:10:26.901693 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2026-06-12 14:10:26.901721 | controller | }
2026-06-12 14:10:27.152178 | controller | changed:
2026-06-12 14:10:27.152341 | controller | {
2026-06-12 14:10:27.152372 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2026-06-12 14:10:27.152396 | controller | }
2026-06-12 14:10:27.439846 | controller | changed:
2026-06-12 14:10:27.440094 | controller | {
2026-06-12 14:10:27.440173 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2026-06-12 14:10:27.440234 | controller | }
2026-06-12 14:10:27.719466 | controller | changed:
2026-06-12 14:10:27.719715 | controller | {
2026-06-12 14:10:27.719786 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDLOQd4ZLtkZXQGY6UwAr/06ppWQK4fDO3HaqxPk98csyOCBXsliSKK39Bso828+5srIXiW7aI6aC9P5mwi4mUZlGPfJlQbfrcGvY+b/SocuvaGK+1RrHLoJCT52LBhwgrzlXio2jeksZeein8iaTrhsPrOAs7KggIL/rB9hEiB3NaOPWhhoCP4vlW6MEMExGcqB/1FVxXFBPnLkEyW0Lk7ycVflZl2ocRxbfjZi0+tI1Wlinp8PvSQSc/WVrAcDgKjc/mB4ODPOyYy3G8FHgfMsrXSDEyjBKgLKMsdCrAUcqJQWjkqXleXSYOV4q3pzL+9umK+q/e3P/bIoSFQzmJKTU1eDfuvPXmow9F5H54fii/Da7ezlMJ+wPGHJrRAkmzvMbALy7xwswLhZMkOGNtRcPqaKYRmIBKpw3o6bCTtcNUHOtOQnzwY8JzrM2eBWJBXAANYw+9/ho80JIiwhg29CFNpVBuHbql2YxJQNrnl90guN65rYNpDxdIluweyUf8= anbanerj@kaermorhen"
2026-06-12 14:10:27.719834 | controller | }
2026-06-12 14:10:28.000041 | controller | changed:
2026-06-12 14:10:28.000259 | controller | {
2026-06-12 14:10:28.000322 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQC3VwV8Im9kRm49lt3tM36hj4Zv27FxGo4C1Q/0jqhzFmHY7RHbmeRr8ObhwWoHjXSozKWg8FL5ER0z3hTwL0W6lez3sL7hUaCmSuZmG5Hnl3x4vTSxDI9JZ/Y65rtYiiWQo2fC5xJhU/4+0e5e/pseCm8cKRSu+SaxhO+sd6FDojA2x1BzOzKiQRDy/1zWGp/cZkxcEuB1wHI5LMzN03c67vmbu+fhZRAUO4dQkvcnj2LrhQtpa+ytvnSjr8icMDosf1OsbSffwZFyHB/hfWGAfe0eIeSA2XPraxiPknXxiPKx2MJsaUTYbsZcm3EjFdHBBMumw5rBI74zLrMRvCO9GwBEmGT4rFng1nP+yw5DB8sn2zqpOsPg1LYRwCPOUveC13P6pgsZZPh812e8v5EKnETct+5XI3dVpdw6CnNiLwAyVAF15DJvBGT/u1k0Myg/bQn+Gv9k2MSj6LvQmf6WbZu2Wgjm30z3FyCneBqTL7mLF19YXzeC0ufHz5pnO1E= dasm@fedora"
2026-06-12 14:10:28.000392 | controller | }
2026-06-12 14:10:28.271239 | controller | changed:
2026-06-12 14:10:28.271431 | controller | {
2026-06-12 14:10:28.271480 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2026-06-12 14:10:28.271518 | controller | }
2026-06-12 14:10:28.519532 | controller | changed:
2026-06-12 14:10:28.519764 | controller | {
2026-06-12 14:10:28.519815 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2026-06-12 14:10:28.519854 | controller | }
2026-06-12 14:10:28.788244 | controller | changed:
2026-06-12 14:10:28.788440 | controller | {
2026-06-12 14:10:28.788491 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2026-06-12 14:10:28.788531 | controller | }
2026-06-12 14:10:29.047747 | controller | changed:
2026-06-12 14:10:29.047916 | controller | {
2026-06-12 14:10:29.047968 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2026-06-12 14:10:29.048008 | controller | }
2026-06-12 14:10:29.330513 | controller | changed:
2026-06-12 14:10:29.330836 | controller | {
2026-06-12 14:10:29.330905 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICWBreHW95Wz2Toz5YwCGQwFcUG8oFYkienDh9tntmDc ralfieri@redhat.com"
2026-06-12 14:10:29.330949 | controller | }
2026-06-12 14:10:29.662402 | controller | changed:
2026-06-12 14:10:29.662533 | controller | {
2026-06-12 14:10:29.662564 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2026-06-12 14:10:29.662656 | controller | }
2026-06-12 14:10:29.897606 | controller | changed:
2026-06-12 14:10:29.897721 | controller | {
2026-06-12 14:10:29.897746 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2026-06-12 14:10:29.897766 | controller | }
2026-06-12 14:10:30.191100 | controller | changed:
2026-06-12 14:10:30.191266 | controller | {
2026-06-12 14:10:30.191315 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2026-06-12 14:10:30.191381 | controller | }
2026-06-12 14:10:30.490329 | controller | changed:
2026-06-12 14:10:30.490468 | controller | {
2026-06-12 14:10:30.490500 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQCfNtF7NvKl915TGsGGoseUb06Hj8L/S4toWf0hExeY+F00woL6NvBlJD0nDct+P5a22I4EhvoQCRQ8reaPCm1lybR3uiRIJsj+8zkVvLwby9LXzfZorlNG9ofjd00FEmB09uW/YvTl6Q9XwwwX6tInzIOv3TMqTHHGOL74ibbj8J/FJR0cFEyj0z4WQRvtkh32xAHl83gbuINryMt0sqRI+clj2381NKL55DRLQrVw0gsfqqxiHAnXg21qWmc4J+b9e9kiuAFQjcjwTVkwJCcg3xbPwC/qokYRby/Y5S40UUd7/jEARGXT7RZgpzTuDd1oZiCVrnrqJNPaMNdVv5MLeFdf1B7iIe5aa/fGouX7AO4SdKhZUdnJmCFAGvjC6S3JMZ2wAcUl+OHnssfmdj7XL50cLo27vjuzMtLAgSqi6N99m92WCF2s8J9aVzszX7Xz9OKZCeGsiVJp3/NdABKzSEAyM9xBD/5Vho894Sav+otpySHe3p6RUTgbB5Zu8VyZRZ/UtB3ueXxyo764yrc6qWIDqrehm84Xm9g+/jpIBzGPl07NUNJpdt/6Sgf9RIKXw/7XypO5yZfUcuFNGTxLfqjTNrtgLZNcjfav6sSdVXVcMPL//XNuRdKmVFaO76eV/oGMQGr1fGcCD+N+CpI7+Q+fCNB6VFWG4nZFuI/Iuw== averdagu@redhat.com"
2026-06-12 14:10:30.490526 | controller | }
2026-06-12 14:10:30.774093 | controller | changed:
2026-06-12 14:10:30.774259 | controller | {
2026-06-12 14:10:30.774308 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDq8l27xI+QlQVdS4djp9ogSoyrNE2+Ox6vKPdhSNL1J3PE5w+WCSvMz9A5gnNuH810zwbekEApbxTze/gLQJwBHA52CChfURpXrFaxY7ePXRElwKAL3mJfzBWY/c5jnNL9TCVmFJTGZkFZP3Nh+BMgZvL6xBkt3WKm6Uq18qzd9XeKcZusrA+O+uLv1fVeQnadY9RIqOCyeFYCzLWrUfTyE8x/XG0hAWIM7qpnF2cALQS2h9n4hW5ybiUN790H08wf9hFwEf5nxY9Z9dVkPFQiTSGKNBzmnCXU9skxS/xhpFjJ5duGSZdtAHe9O+nGZm9c67hxgtf8e5PDuqAdXEv2cf6e3VBAt+Bz8EKI3yosTj0oZHfwr42Yzb1l/SKy14Rggsrc9KAQlrGXan6+u2jcQqqx7l+SWmnpFiWTV9u5cWj2IgOhApOitmRBPYqk9rE2usfO0hLn/Pj/R/Nau4803e1/EikdLE7Ps95s9mX5jRDjAoUa2JwFF5RsVFyL910= ashigupt@ashigupt.remote.csb"
2026-06-12 14:10:30.774364 | controller | }
2026-06-12 14:10:31.076434 | controller | changed:
2026-06-12 14:10:31.076595 | controller | {
2026-06-12 14:10:31.076622 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2026-06-12 14:10:31.076640 | controller | }
2026-06-12 14:10:31.333711 | controller | changed:
2026-06-12 14:10:31.333920 | controller | {
2026-06-12 14:10:31.333970 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2026-06-12 14:10:31.334011 | controller | }
2026-06-12 14:10:31.598075 | controller | changed:
2026-06-12 14:10:31.598286 | controller | {
2026-06-12 14:10:31.598338 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2026-06-12 14:10:31.598379 | controller | }
2026-06-12 14:10:31.863224 | controller | changed:
2026-06-12 14:10:31.863400 | controller | {
2026-06-12 14:10:31.863422 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2026-06-12 14:10:31.863440 | controller | }
2026-06-12 14:10:32.145453 | controller | changed:
2026-06-12 14:10:32.145640 | controller | {
2026-06-12 14:10:32.145668 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQDZdI7t1cxYx65heVI24HTV4F7oQLW1zyfxHreL2TIJKxjyrUUKIFEUmTutcBlJRLNT2Eoix6x1sOw9YrchloCLcn//SGfTElr9mSc5jbjb7QXEU+zJMhtxyEJ1Po3CUGnj7ckiIXw7wcawZtrEOAQ9pH3ExYCJcEMiyNjRQZCxT3tPK+S4B95EWh5Fsrz9CkwpjNRPPH7LigCeQTM3Wc7r97utAslBUUvYceDSLA7rMgkitJE38b7rZBeYzsGQ8YYUBjTCtehqQXxCRjizbHWaaZkBU+N3zkKB6n/iCNGIO690NK7A/qb6msTijiz1PeuM8ThOsi9qXnbX5v0PoTpcFSojV7NHAQ71f0XXuS43FhZctT+Dcx44dT8Fb5vJu2cJGrk+qF8ZgJYNpRS7gPg0EG2EqjK7JMf9ULdjSu0r+KlqIAyLvtzT4eOnQipoKlb/WG5D/0ohKv7OMQ352ggfkBFIQsRXyyTCT98Ft9juqPuahi3CAQmP4H9dyE+7+Kz437PEtsxLmfm6naNmWi7Ee1DqWPwS8rEajsm4sNM4wW9gdBboJQtc0uZw0DfLj1I9r3Mc8Ol0jYtz0yNQDSzVLrGCaJlC311trU70tZ+ZkAVV6Mn8lOhSbj1cK0lvSr6ZK4dgqGl3I1eTZJJhbLNdg7UOVaiRx9543+C/p/As7w== brjackma@redhat.com"
2026-06-12 14:10:32.145688 | controller | }
2026-06-12 14:10:32.405435 | controller | changed:
2026-06-12 14:10:32.405767 | controller | {
2026-06-12 14:10:32.405830 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2026-06-12 14:10:32.405869 | controller | }
2026-06-12 14:10:32.684107 | controller | changed:
2026-06-12 14:10:32.684233 | controller | {
2026-06-12 14:10:32.684254 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2026-06-12 14:10:32.684272 | controller | }
2026-06-12 14:10:32.963077 | controller | changed:
2026-06-12 14:10:32.963300 | controller | {
2026-06-12 14:10:32.963371 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2026-06-12 14:10:32.963438 | controller | }
2026-06-12 14:10:32.987552 | 
2026-06-12 14:10:32.987717 | TASK [Set timezone to UTC]
2026-06-12 14:10:33.509938 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2026-06-12 14:10:33.517723 | 
2026-06-12 14:10:33.517857 | TASK [Create nodepool directory]
2026-06-12 14:10:33.811905 | controller | changed
2026-06-12 14:10:33.823181 | 
2026-06-12 14:10:33.823321 | TASK [Create nodepool sub_nodes file]
2026-06-12 14:10:34.576330 | controller | changed
2026-06-12 14:10:34.584502 | 
2026-06-12 14:10:34.584669 | TASK [Create nodepool sub_nodes_private file]
2026-06-12 14:10:35.434247 | controller | changed
2026-06-12 14:10:35.442196 | 
2026-06-12 14:10:35.442321 | LOOP [Populate nodepool sub_nodes file]
2026-06-12 14:10:35.490058 | 
2026-06-12 14:10:35.490341 | LOOP [Populate nodepool sub_nodes_private file]
2026-06-12 14:10:35.544709 | 
2026-06-12 14:10:35.545027 | TASK [Create nodepool primary file]
2026-06-12 14:10:35.592291 | controller | skipping: Conditional result was False
2026-06-12 14:10:35.600951 | 
2026-06-12 14:10:35.601090 | TASK [Create nodepool node_private for this node]
2026-06-12 14:10:36.415375 | controller | changed
2026-06-12 14:10:36.421393 | 
2026-06-12 14:10:36.421475 | LOOP [Copy ssh keys to nodepool directory]
2026-06-12 14:10:36.905498 | controller | ok: Item: id_rsa Runtime: 0:00:00.006648
2026-06-12 14:10:36.905907 | 
2026-06-12 14:10:37.125167 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.006130
2026-06-12 14:10:37.142795 | 
2026-06-12 14:10:37.143066 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2026-06-12 14:10:37.929819 | controller | changed
2026-06-12 14:10:37.937379 | 
2026-06-12 14:10:37.937506 | TASK [Validate sudoers config after edits]
2026-06-12 14:10:38.250230 | controller | /etc/sudoers: parsed OK
2026-06-12 14:10:38.250288 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2026-06-12 14:10:38.250417 | controller | /etc/sudoers.d/zuul: parsed OK
2026-06-12 14:10:38.250425 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2026-06-12 14:10:38.487465 | controller | ok: Runtime: 0:00:00.018833
2026-06-12 14:10:38.496215 | 
2026-06-12 14:10:38.496366 | TASK [Show the environment passed in to job shell scripts]
2026-06-12 14:10:38.800384 | controller | SHELL=/bin/bash
2026-06-12 14:10:38.800523 | controller | ZUUL_PROJECT=openstack-k8s-operators/edpm-ansible
2026-06-12 14:10:38.800539 | controller | ZUUL_SHORT_PROJECT_NAME=edpm-ansible
2026-06-12 14:10:38.800553 | controller | ZUUL_CHANGES=openstack-k8s-operators/edpm-ansible:main:refs/changes/88/1188/38484009bd61ab2f31ba8b1a9e562b434578f8d3
2026-06-12 14:10:38.800562 | controller | PWD=/home/zuul
2026-06-12 14:10:38.800571 | controller | ZUUL_PIPELINE=github-check
2026-06-12 14:10:38.800584 | controller | LOGNAME=zuul
2026-06-12 14:10:38.800592 | controller | XDG_SESSION_TYPE=tty
2026-06-12 14:10:38.800600 | controller | _=/usr/bin/env
2026-06-12 14:10:38.800614 | controller | MOTD_SHOWN=pam
2026-06-12 14:10:38.800622 | controller | HOME=/home/zuul
2026-06-12 14:10:38.800629 | controller | LANG=en_US.UTF-8
2026-06-12 14:10:38.800638 | controller | SSH_CONNECTION=38.102.83.40 36592 38.102.83.102 22
2026-06-12 14:10:38.800648 | controller | TOX_TESTENV_PASSENV=ZUUL_UUID ZUUL_PROJECT ZUUL_SHORT_PROJECT_NAME ZUUL_PIPELINE ZUUL_VOTING WORKSPACE BUILD_TIMEOUT ZUUL_BRANCH ZUUL_CHANGES ZUUL_CHANGE_IDS ZUUL_CHANGE ZUUL_PATCHSET
2026-06-12 14:10:38.800657 | controller | ZUUL_CHANGE_IDS=1188,38484009bd61ab2f31ba8b1a9e562b434578f8d3
2026-06-12 14:10:38.800665 | controller | WORKSPACE=/home/zuul/workspace
2026-06-12 14:10:38.800673 | controller | XDG_SESSION_CLASS=user
2026-06-12 14:10:38.800681 | controller | SELINUX_ROLE_REQUESTED=
2026-06-12 14:10:38.800689 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2026-06-12 14:10:38.800697 | controller | USER=zuul
2026-06-12 14:10:38.800705 | controller | ZUUL_VOTING=True
2026-06-12 14:10:38.800713 | controller | BUILD_TIMEOUT=1800000
2026-06-12 14:10:38.800720 | controller | SELINUX_USE_CURRENT_RANGE=
2026-06-12 14:10:38.800728 | controller | SHLVL=1
2026-06-12 14:10:38.800736 | controller | ZUUL_PATCHSET=38484009bd61ab2f31ba8b1a9e562b434578f8d3
2026-06-12 14:10:38.800744 | controller | XDG_SESSION_ID=1
2026-06-12 14:10:38.800751 | controller | ZUUL_BRANCH=main
2026-06-12 14:10:38.800759 | controller | XDG_RUNTIME_DIR=/run/user/1000
2026-06-12 14:10:38.800767 | controller | SSH_CLIENT=38.102.83.40 36592 22
2026-06-12 14:10:38.800779 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2026-06-12 14:10:38.800787 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2026-06-12 14:10:38.800795 | controller | which_declare=declare -f
2026-06-12 14:10:38.800803 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2026-06-12 14:10:38.800811 | controller | SELINUX_LEVEL_REQUESTED=
2026-06-12 14:10:38.800819 | controller | ZUUL_CHANGE=1188
2026-06-12 14:10:38.800826 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2026-06-12 14:10:38.800834 | controller | ZUUL_UUID=51f4563f9d0a44f49d26838494b1b441
2026-06-12 14:10:38.800842 | controller | BASH_FUNC_which%%=() {  ( alias;
2026-06-12 14:10:38.800851 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2026-06-12 14:10:38.800859 | controller | }
2026-06-12 14:10:39.035224 | controller | ok: Runtime: 0:00:00.007557
2026-06-12 14:10:39.043429 | 
2026-06-12 14:10:39.043554 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2026-06-12 14:10:39.071030 | controller | skipping: Conditional result was False
2026-06-12 14:10:39.080244 | 
2026-06-12 14:10:39.080376 | TASK [Symlink /home/zuul-worker/workspace]
2026-06-12 14:10:39.619465 | controller | skipping: Conditional result was False
2026-06-12 14:10:39.629726 | 
2026-06-12 14:10:39.629867 | TASK [Ensure legacy workspace directory]
2026-06-12 14:10:39.898239 | controller | changed
2026-06-12 14:10:39.900833 | 
2026-06-12 14:10:39.900923 | PLAY RECAP
2026-06-12 14:10:39.901005 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2026-06-12 14:10:39.901048 | 
2026-06-12 14:10:40.037349 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2026-06-12 14:10:40.040058 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2026-06-12 14:10:40.651273 | 
2026-06-12 14:10:40.651400 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2026-06-12 14:10:40.662569 | 
2026-06-12 14:10:40.662656 | TASK [Create zuul-output directory]
2026-06-12 14:10:41.097084 | controller | changed
2026-06-12 14:10:41.102395 | 
2026-06-12 14:10:41.102499 | TASK [Slurp Zuul inventory test]
2026-06-12 14:10:41.636858 | controller -> localhost | ok
2026-06-12 14:10:41.649424 | 
2026-06-12 14:10:41.649607 | TASK [Save zuul inventory]
2026-06-12 14:10:42.838829 | controller | changed
2026-06-12 14:10:42.847789 | 
2026-06-12 14:10:42.848117 | TASK [Save zuul vars without the change_message]
2026-06-12 14:10:43.795186 | controller | changed
2026-06-12 14:10:43.797496 | 
2026-06-12 14:10:43.797643 | PLAY RECAP
2026-06-12 14:10:43.797734 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-06-12 14:10:43.797778 | 
2026-06-12 14:10:43.925654 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2026-06-12 14:10:43.928095 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2026-06-12 14:10:44.599246 | 
2026-06-12 14:10:44.599392 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2026-06-12 14:10:44.611147 | 
2026-06-12 14:10:44.611221 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2026-06-12 14:10:44.639384 | controller | ok
2026-06-12 14:10:44.653605 | 
2026-06-12 14:10:44.653679 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2026-06-12 14:10:44.698735 | controller | skipping: Conditional result was False
2026-06-12 14:10:44.705191 | 
2026-06-12 14:10:44.705284 | TASK [mirror-info-fork : Create /etc/ci]
2026-06-12 14:10:45.177380 | controller | ok
2026-06-12 14:10:45.186815 | 
2026-06-12 14:10:45.186960 | TASK [mirror-info-fork : Install ci_mirror script]
2026-06-12 14:10:48.320041 | controller | ok
2026-06-12 14:10:48.335694 | 
2026-06-12 14:10:48.335832 | TASK [Prepare workspace]
2026-06-12 14:10:48.363530 | controller | ok
2026-06-12 14:10:48.394916 | 
2026-06-12 14:10:48.395013 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-06-12 14:10:48.867146 | controller | ok
2026-06-12 14:10:48.877187 | 
2026-06-12 14:10:48.877322 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-06-12 14:10:51.155371 | controller | Output suppressed because no_log was given
2026-06-12 14:10:51.171169 | 
2026-06-12 14:10:51.171410 | LOOP [Create zuul-output directory]
2026-06-12 14:10:51.464090 | controller | changed: "/home/zuul/ci-framework-data/logs"
2026-06-12 14:10:51.666471 | controller | ok: "/home/zuul/zuul-output/logs"
2026-06-12 14:10:51.678673 | 
2026-06-12 14:10:51.678921 | TASK [Install required packages]
2026-06-12 14:11:42.204921 | controller | changed
2026-06-12 14:11:42.213964 | 
2026-06-12 14:11:42.214101 | TASK [Install venv]
2026-06-12 14:13:21.230107 | controller | changed
2026-06-12 14:13:21.242481 | 
2026-06-12 14:13:21.242536 | PLAY RECAP
2026-06-12 14:13:21.242611 | controller | ok: 7 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-06-12 14:13:21.242638 | 
2026-06-12 14:13:21.405867 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2026-06-12 14:13:21.408694 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2026-06-12 14:13:22.034916 | 
2026-06-12 14:13:22.035022 | PLAY [Run ci/playbooks/molecule-test.yml]
2026-06-12 14:13:22.045641 | 
2026-06-12 14:13:22.045709 | TASK [Gather required facts]
2026-06-12 14:13:22.701394 | controller | ok
2026-06-12 14:13:22.710956 | 
2026-06-12 14:13:22.711142 | TASK [Load environment var if instructed to]
2026-06-12 14:13:22.750933 | controller | skipping: Conditional result was False
2026-06-12 14:13:22.760120 | 
2026-06-12 14:13:22.760251 | TASK [Ensure group_vars dir exists]
2026-06-12 14:13:23.212031 | controller | changed
2026-06-12 14:13:23.221083 | 
2026-06-12 14:13:23.221214 | TASK [Print related variables]
2026-06-12 14:13:23.267481 | controller | ok:
2026-06-12 14:13:23.267824 | controller | mol_config_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config_edpm_ansible.yml
2026-06-12 14:13:23.267887 | controller | roles_dir: /home/zuul/src/github.com/openstack-k8s-operators/edpm-ansible/roles/edpm_frr
2026-06-12 14:13:23.275803 | 
2026-06-12 14:13:23.275934 | TASK [Run molecule]
2026-06-12 14:13:24.683933 | controller | WARNING  Driver podman does not provide a schema.
2026-06-12 14:13:24.687661 | controller | INFO     Performing prerun with role_name_check=0...
2026-06-12 14:13:25.571203 | controller | INFO     Running default > dependency
2026-06-12 14:13:25.573081 | controller | WARNING  Skipping, missing the requirements file.
2026-06-12 14:13:26.096214 | controller | [WARNING]: The specified collections path
2026-06-12 14:13:26.096322 | controller | '/home/zuul/.cache/molecule/edpm_frr/default/collections' is not part of the
2026-06-12 14:13:26.096725 | controller | configured Ansible collections paths '/home/zuul/.cache/ansible-compat/8075a4/c
2026-06-12 14:13:26.096869 | controller | ollections:/home/zuul/.ansible/collections:/usr/share/ansible/collections'. The
2026-06-12 14:13:26.097033 | controller | installed collection will not be picked up in an Ansible run, unless within a
2026-06-12 14:13:26.097198 | controller | playbook-adjacent collections directory.
2026-06-12 14:13:36.339194 | controller | Starting galaxy collection install process
2026-06-12 14:13:36.339321 | controller | Process install dependency map
2026-06-12 14:13:36.339633 | controller | Starting collection install process
2026-06-12 14:13:36.339911 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/community-general-13.0.1.tar.gz to /home/zuul/.ansible/tmp/ansible-local-29714rxje2lfi/tmpqe_ci1zj/community-general-13.0.1-bk5i3pie
2026-06-12 14:13:36.340203 | controller | Installing 'community.general:13.0.1' to '/home/zuul/.cache/molecule/edpm_frr/default/collections/ansible_collections/community/general'
2026-06-12 14:13:36.340541 | controller | community.general:13.0.1 was installed successfully
2026-06-12 14:13:36.414524 | controller | INFO     Dependency completed successfully.
2026-06-12 14:13:36.436535 | controller | INFO     Running default > destroy
2026-06-12 14:13:36.439872 | controller | INFO     Sanity checks: 'podman'
2026-06-12 14:13:37.510476 | controller |
2026-06-12 14:13:37.510647 | controller | PLAY [Destroy] *****************************************************************
2026-06-12 14:13:37.510856 | controller |
2026-06-12 14:13:37.511084 | controller | TASK [Get passwd entries for USER env] *****************************************
2026-06-12 14:13:37.511387 | controller | Friday 12 June 2026  14:13:37 +0000 (0:00:00.013)       0:00:00.013 ***********
2026-06-12 14:13:37.950263 | controller | ok: [localhost]
2026-06-12 14:13:37.950402 | controller |
2026-06-12 14:13:37.950510 | controller | TASK [Get shell async_dir] *****************************************************
2026-06-12 14:13:37.950626 | controller | Friday 12 June 2026  14:13:37 +0000 (0:00:00.440)       0:00:00.454 ***********
2026-06-12 14:13:38.012777 | controller | ok: [localhost]
2026-06-12 14:13:38.012834 | controller |
2026-06-12 14:13:38.013029 | controller | TASK [Set async_dir for HOME env] **********************************************
2026-06-12 14:13:38.013129 | controller | Friday 12 June 2026  14:13:38 +0000 (0:00:00.062)       0:00:00.516 ***********
2026-06-12 14:13:38.076246 | controller | skipping: [localhost]
2026-06-12 14:13:38.076447 | controller |
2026-06-12 14:13:38.076644 | controller | TASK [Destroy molecule instance(s)] ********************************************
2026-06-12 14:13:38.076892 | controller | Friday 12 June 2026  14:13:38 +0000 (0:00:00.063)       0:00:00.580 ***********
2026-06-12 14:13:38.594782 | controller | changed: [localhost] => (item={'command': '/sbin/init', 'dockerfile': '../../../../molecule/common/Containerfile.j2', 'image': 'ubi9/ubi-init', 'name': 'instance', 'privileged': True, 'registry': {'url': 'registry.access.redhat.com'}, 'ulimits': ['host']})
2026-06-12 14:13:38.595029 | controller |
2026-06-12 14:13:38.595060 | controller | TASK [Wait for instance(s) deletion to complete] *******************************
2026-06-12 14:13:38.595065 | controller | Friday 12 June 2026  14:13:38 +0000 (0:00:00.518)       0:00:01.098 ***********
2026-06-12 14:13:44.283351 | controller | FAILED - RETRYING: [localhost]: Wait for instance(s) deletion to complete (300 retries left).
2026-06-12 14:13:44.283391 | controller | changed: [localhost] => (item={'failed': 0, 'started': 1, 'finished': 0, 'ansible_job_id': 'j78250753939.29757', 'results_file': '/home/zuul/.ansible_async/j78250753939.29757', 'changed': True, 'item': {'command': '/sbin/init', 'dockerfile': '../../../../molecule/common/Containerfile.j2', 'image': 'ubi9/ubi-init', 'name': 'instance', 'privileged': True, 'registry': {'url': 'registry.access.redhat.com'}, 'ulimits': ['host']}, 'ansible_loop_var': 'item'})
2026-06-12 14:13:44.283406 | controller |
2026-06-12 14:13:44.283411 | controller | TASK [Delete podman network dedicated to this scenario] ************************
2026-06-12 14:13:44.283415 | controller | Friday 12 June 2026  14:13:44 +0000 (0:00:05.687)       0:00:06.786 ***********
2026-06-12 14:13:44.380410 | controller | skipping: [localhost] => (item=instance: None specified)
2026-06-12 14:13:44.380459 | controller | skipping: [localhost]
2026-06-12 14:13:44.380470 | controller |
2026-06-12 14:13:44.380479 | controller | PLAY RECAP *********************************************************************
2026-06-12 14:13:44.382763 | controller | localhost                  : ok=4    changed=2    unreachable=0    failed=0    skipped=2    rescued=0    ignored=0
2026-06-12 14:13:44.382811 | controller |
2026-06-12 14:13:44.382822 | controller | Friday 12 June 2026  14:13:44 +0000 (0:00:00.097)       0:00:06.884 ***********
2026-06-12 14:13:44.382830 | controller | ===============================================================================
2026-06-12 14:13:44.382838 | controller | Wait for instance(s) deletion to complete ------------------------------- 5.69s
2026-06-12 14:13:44.382846 | controller | Destroy molecule instance(s) -------------------------------------------- 0.52s
2026-06-12 14:13:44.382854 | controller | Get passwd entries for USER env ----------------------------------------- 0.44s
2026-06-12 14:13:44.382862 | controller | Delete podman network dedicated to this scenario ------------------------ 0.10s
2026-06-12 14:13:44.382869 | controller | Set async_dir for HOME env ---------------------------------------------- 0.06s
2026-06-12 14:13:44.382877 | controller | Get shell async_dir ----------------------------------------------------- 0.06s
2026-06-12 14:13:44.546882 | controller | INFO     Running default > create
2026-06-12 14:13:45.091613 | controller |
2026-06-12 14:13:45.091974 | controller | PLAY [Create] ******************************************************************
2026-06-12 14:13:45.092332 | controller |
2026-06-12 14:13:45.092622 | controller | TASK [Get podman executable path] **********************************************
2026-06-12 14:13:45.092898 | controller | Friday 12 June 2026  14:13:45 +0000 (0:00:00.015)       0:00:00.015 ***********
2026-06-12 14:13:45.464409 | controller | ok: [localhost]
2026-06-12 14:13:45.464454 | controller |
2026-06-12 14:13:45.464781 | controller | TASK [Register podman executable path] *****************************************
2026-06-12 14:13:45.464807 | controller | Friday 12 June 2026  14:13:45 +0000 (0:00:00.371)       0:00:00.387 ***********
2026-06-12 14:13:45.526445 | controller | ok: [localhost]
2026-06-12 14:13:45.526485 | controller |
2026-06-12 14:13:45.526490 | controller | TASK [Get passwd entries for USER env] *****************************************
2026-06-12 14:13:45.526495 | controller | Friday 12 June 2026  14:13:45 +0000 (0:00:00.062)       0:00:00.450 ***********
2026-06-12 14:13:45.942758 | controller | ok: [localhost]
2026-06-12 14:13:45.942803 | controller |
2026-06-12 14:13:45.942814 | controller | TASK [Get shell async_dir] *****************************************************
2026-06-12 14:13:45.942824 | controller | Friday 12 June 2026  14:13:45 +0000 (0:00:00.416)       0:00:00.866 ***********
2026-06-12 14:13:45.997409 | controller | ok: [localhost]
2026-06-12 14:13:45.997483 | controller |
2026-06-12 14:13:45.997490 | controller | TASK [Set async_dir for HOME env] **********************************************
2026-06-12 14:13:45.997527 | controller | Friday 12 June 2026  14:13:45 +0000 (0:00:00.054)       0:00:00.921 ***********
2026-06-12 14:13:46.061426 | controller | skipping: [localhost]
2026-06-12 14:13:46.061477 | controller |
2026-06-12 14:13:46.061489 | controller | TASK [Log into a container registry] *******************************************
2026-06-12 14:13:46.061499 | controller | Friday 12 June 2026  14:13:46 +0000 (0:00:00.063)       0:00:00.984 ***********
2026-06-12 14:13:46.133223 | controller | skipping: [localhost] => (item="instance registry username: None specified")
2026-06-12 14:13:46.133497 | controller | skipping: [localhost]
2026-06-12 14:13:46.133721 | controller |
2026-06-12 14:13:46.133960 | controller | TASK [Check presence of custom Dockerfiles] ************************************
2026-06-12 14:13:46.134193 | controller | Friday 12 June 2026  14:13:46 +0000 (0:00:00.072)       0:00:01.057 ***********
2026-06-12 14:13:46.519624 | controller | ok: [localhost] => (item=Dockerfile: ../../../../molecule/common/Containerfile.j2)
2026-06-12 14:13:46.519661 | controller |
2026-06-12 14:13:46.519667 | controller | TASK [Create Dockerfiles from image names] *************************************
2026-06-12 14:13:46.519672 | controller | Friday 12 June 2026  14:13:46 +0000 (0:00:00.385)       0:00:01.443 ***********
2026-06-12 14:13:47.096472 | controller | changed: [localhost] => (item="Dockerfile: ../../../../molecule/common/Containerfile.j2; Image: ubi9/ubi-init")
2026-06-12 14:13:47.096519 | controller |
2026-06-12 14:13:47.096529 | controller | TASK [Discover local Podman images] ********************************************
2026-06-12 14:13:47.096538 | controller | Friday 12 June 2026  14:13:47 +0000 (0:00:00.576)       0:00:02.019 ***********
2026-06-12 14:13:47.557844 | controller | ok: [localhost] => (item=instance)
2026-06-12 14:13:47.557886 | controller |
2026-06-12 14:13:47.557893 | controller | TASK [Build an Ansible compatible image] ***************************************
2026-06-12 14:13:47.557900 | controller | Friday 12 June 2026  14:13:47 +0000 (0:00:00.462)       0:00:02.481 ***********
2026-06-12 14:14:18.993713 | controller | ok: [localhost] => (item=ubi9/ubi-init)
2026-06-12 14:14:18.993771 | controller |
2026-06-12 14:14:18.993782 | controller | TASK [Determine the CMD directives] ********************************************
2026-06-12 14:14:18.993790 | controller | Friday 12 June 2026  14:14:18 +0000 (0:00:31.432)       0:00:33.914 ***********
2026-06-12 14:14:19.078409 | controller | ok: [localhost] => (item="instance command: /sbin/init")
2026-06-12 14:14:19.078464 | controller |
2026-06-12 14:14:19.078480 | controller | TASK [Remove possible pre-existing containers] *********************************
2026-06-12 14:14:19.078499 | controller | Friday 12 June 2026  14:14:19 +0000 (0:00:00.087)       0:00:34.001 ***********
2026-06-12 14:14:19.430392 | controller | changed: [localhost]
2026-06-12 14:14:19.430438 | controller |
2026-06-12 14:14:19.430449 | controller | TASK [Discover local podman networks] ******************************************
2026-06-12 14:14:19.430457 | controller | Friday 12 June 2026  14:14:19 +0000 (0:00:00.351)       0:00:34.353 ***********
2026-06-12 14:14:19.508079 | controller | skipping: [localhost] => (item=instance: None specified)
2026-06-12 14:14:19.508127 | controller | skipping: [localhost]
2026-06-12 14:14:19.508138 | controller |
2026-06-12 14:14:19.508148 | controller | TASK [Create podman network dedicated to this scenario] ************************
2026-06-12 14:14:19.508158 | controller | Friday 12 June 2026  14:14:19 +0000 (0:00:00.077)       0:00:34.431 ***********
2026-06-12 14:14:19.577216 | controller | skipping: [localhost]
2026-06-12 14:14:19.577270 | controller |
2026-06-12 14:14:19.577284 | controller | TASK [Create molecule instance(s)] *********************************************
2026-06-12 14:14:19.577297 | controller | Friday 12 June 2026  14:14:19 +0000 (0:00:00.068)       0:00:34.500 ***********
2026-06-12 14:14:19.999372 | controller | changed: [localhost] => (item=instance)
2026-06-12 14:14:19.999413 | controller |
2026-06-12 14:14:19.999425 | controller | TASK [Wait for instance(s) creation to complete] *******************************
2026-06-12 14:14:19.999439 | controller | Friday 12 June 2026  14:14:19 +0000 (0:00:00.422)       0:00:34.923 ***********
2026-06-12 14:14:25.611321 | controller | FAILED - RETRYING: [localhost]: Wait for instance(s) creation to complete (300 retries left).
2026-06-12 14:14:25.611372 | controller | changed: [localhost] => (item=instance)
2026-06-12 14:14:25.611383 | controller |
2026-06-12 14:14:25.615796 | controller | PLAY RECAP *********************************************************************
2026-06-12 14:14:25.615844 | controller | localhost                  : ok=12   changed=4    unreachable=0    failed=0    skipped=4    rescued=0    ignored=0
2026-06-12 14:14:25.615855 | controller |
2026-06-12 14:14:25.615864 | controller | Friday 12 June 2026  14:14:25 +0000 (0:00:05.611)       0:00:40.534 ***********
2026-06-12 14:14:25.615872 | controller | ===============================================================================
2026-06-12 14:14:25.615880 | controller | Build an Ansible compatible image -------------------------------------- 31.43s
2026-06-12 14:14:25.615890 | controller | Wait for instance(s) creation to complete ------------------------------- 5.61s
2026-06-12 14:14:25.615900 | controller | Create Dockerfiles from image names ------------------------------------- 0.58s
2026-06-12 14:14:25.615910 | controller | Discover local Podman images -------------------------------------------- 0.46s
2026-06-12 14:14:25.615925 | controller | Create molecule instance(s) --------------------------------------------- 0.42s
2026-06-12 14:14:25.615933 | controller | Get passwd entries for USER env ----------------------------------------- 0.42s
2026-06-12 14:14:25.615940 | controller | Check presence of custom Dockerfiles ------------------------------------ 0.39s
2026-06-12 14:14:25.615948 | controller | Get podman executable path ---------------------------------------------- 0.37s
2026-06-12 14:14:25.615956 | controller | Remove possible pre-existing containers --------------------------------- 0.35s
2026-06-12 14:14:25.615964 | controller | Determine the CMD directives -------------------------------------------- 0.09s
2026-06-12 14:14:25.615971 | controller | Discover local podman networks ------------------------------------------ 0.08s
2026-06-12 14:14:25.615979 | controller | Log into a container registry ------------------------------------------- 0.07s
2026-06-12 14:14:25.615987 | controller | Create podman network dedicated to this scenario ------------------------ 0.07s
2026-06-12 14:14:25.615995 | controller | Set async_dir for HOME env ---------------------------------------------- 0.06s
2026-06-12 14:14:25.616002 | controller | Register podman executable path ----------------------------------------- 0.06s
2026-06-12 14:14:25.616010 | controller | Get shell async_dir ----------------------------------------------------- 0.05s
2026-06-12 14:14:25.717036 | controller | INFO     Running default > prepare
2026-06-12 14:14:26.221234 | controller |
2026-06-12 14:14:26.221601 | controller | PLAY [Prepare] *****************************************************************
2026-06-12 14:14:26.221868 | controller |
2026-06-12 14:14:26.222157 | controller | TASK [Gathering Facts] *********************************************************
2026-06-12 14:14:26.222510 | controller | Friday 12 June 2026  14:14:26 +0000 (0:00:00.012)       0:00:00.012 ***********
2026-06-12 14:14:28.985012 | controller | ok: [instance]
2026-06-12 14:14:28.985054 | controller |
2026-06-12 14:14:28.985061 | controller | TASK [../../../../molecule/common/test_deps : Validating arguments against arg spec 'main' - The main entry point for the test_deps role.] ***
2026-06-12 14:14:28.985067 | controller | Friday 12 June 2026  14:14:28 +0000 (0:00:02.763)       0:00:02.776 ***********
2026-06-12 14:14:29.020372 | controller | ok: [instance]
2026-06-12 14:14:29.020422 | controller |
2026-06-12 14:14:29.020433 | controller | TASK [../../../../molecule/common/test_deps : Gather facts if they don't exist] ***
2026-06-12 14:14:29.020442 | controller | Friday 12 June 2026  14:14:29 +0000 (0:00:00.035)       0:00:02.811 ***********
2026-06-12 14:14:29.049621 | controller | skipping: [instance]
2026-06-12 14:14:29.049657 | controller |
2026-06-12 14:14:29.049662 | controller | TASK [../../../../molecule/common/test_deps : Gather variables for each operating system] ***
2026-06-12 14:14:29.049667 | controller | Friday 12 June 2026  14:14:29 +0000 (0:00:00.029)       0:00:02.841 ***********
2026-06-12 14:14:29.128407 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/edpm-ansible/molecule/common/test_deps/vars/redhat-9.yml)
2026-06-12 14:14:29.128442 | controller |
2026-06-12 14:14:29.128449 | controller | TASK [../../../../molecule/common/test_deps : Detect mirrors file] *************
2026-06-12 14:14:29.128454 | controller | Friday 12 June 2026  14:14:29 +0000 (0:00:00.077)       0:00:02.918 ***********
2026-06-12 14:14:30.612431 | controller | ok: [instance]
2026-06-12 14:14:30.612524 | controller |
2026-06-12 14:14:30.612995 | controller | TASK [../../../../molecule/common/test_deps : Disable ubi host subscription-manager integration] ***
2026-06-12 14:14:30.613557 | controller | Friday 12 June 2026  14:14:30 +0000 (0:00:01.485)       0:00:04.404 ***********
2026-06-12 14:14:33.112254 | controller | changed: [instance]
2026-06-12 14:14:33.112309 | controller |
2026-06-12 14:14:33.112320 | controller | TASK [../../../../molecule/common/test_deps : Remove ubi 9 repos] **************
2026-06-12 14:14:33.112330 | controller | Friday 12 June 2026  14:14:33 +0000 (0:00:02.497)       0:00:06.902 ***********
2026-06-12 14:14:34.498991 | controller | changed: [instance]
2026-06-12 14:14:34.499039 | controller |
2026-06-12 14:14:34.499323 | controller | TASK [../../../../molecule/common/test_deps : Fetch latest repo version] *******
2026-06-12 14:14:34.499376 | controller | Friday 12 June 2026  14:14:34 +0000 (0:00:01.388)       0:00:08.290 ***********
2026-06-12 14:14:36.609415 | controller | ok: [instance]
2026-06-12 14:14:36.609453 | controller |
2026-06-12 14:14:36.609471 | controller | TASK [../../../../molecule/common/test_deps : Create default repo file] ********
2026-06-12 14:14:36.609482 | controller | Friday 12 June 2026  14:14:36 +0000 (0:00:02.109)       0:00:10.400 ***********
2026-06-12 14:14:41.263734 | controller | changed: [instance]
2026-06-12 14:14:41.263786 | controller |
2026-06-12 14:14:41.263794 | controller | TASK [../../../../molecule/common/test_deps : Install repo-setup pre-requirements] ***
2026-06-12 14:14:41.263801 | controller | Friday 12 June 2026  14:14:41 +0000 (0:00:04.653)       0:00:15.053 ***********
2026-06-12 14:14:44.552362 | controller | ok: [instance]
2026-06-12 14:14:44.552434 | controller |
2026-06-12 14:14:44.555237 | controller | TASK [../../../../molecule/common/test_deps : Get repo-setup repository] *******
2026-06-12 14:14:44.555585 | controller | Friday 12 June 2026  14:14:44 +0000 (0:00:03.289)       0:00:18.343 ***********
2026-06-12 14:14:46.345744 | controller | changed: [instance]
2026-06-12 14:14:46.345799 | controller |
2026-06-12 14:14:46.345810 | controller | TASK [../../../../molecule/common/test_deps : Install repo-setup] **************
2026-06-12 14:14:46.345819 | controller | Friday 12 June 2026  14:14:46 +0000 (0:00:01.789)       0:00:20.133 ***********
2026-06-12 14:14:48.299320 | controller | changed: [instance]
2026-06-12 14:14:48.299372 | controller |
2026-06-12 14:14:48.299387 | controller | TASK [../../../../molecule/common/test_deps : Create repos] ********************
2026-06-12 14:14:48.299560 | controller | Friday 12 June 2026  14:14:48 +0000 (0:00:01.957)       0:00:22.091 ***********
2026-06-12 14:14:50.042796 | controller | changed: [instance]
2026-06-12 14:14:50.042831 | controller |
2026-06-12 14:14:50.042839 | controller | TASK [../../../../molecule/common/test_deps : Look for redhat-release rpm] *****
2026-06-12 14:14:50.042845 | controller | Friday 12 June 2026  14:14:50 +0000 (0:00:01.742)       0:00:23.833 ***********
2026-06-12 14:14:51.371867 | controller | changed: [instance]
2026-06-12 14:14:51.371906 | controller |
2026-06-12 14:14:51.371914 | controller | TASK [../../../../molecule/common/test_deps : Workaround of redhat-release binary on ubi9] ***
2026-06-12 14:14:51.371920 | controller | Friday 12 June 2026  14:14:51 +0000 (0:00:01.329)       0:00:25.163 ***********
2026-06-12 14:14:53.640777 | controller | changed: [instance]
2026-06-12 14:14:53.640803 | controller |
2026-06-12 14:14:53.640808 | controller | TASK [../../../../molecule/common/test_deps : Install edpm packages] ***********
2026-06-12 14:14:53.640812 | controller | Friday 12 June 2026  14:14:53 +0000 (0:00:02.269)       0:00:27.432 ***********
2026-06-12 14:16:01.239643 | controller | changed: [instance]
2026-06-12 14:16:01.239689 | controller |
2026-06-12 14:16:01.239699 | controller | TASK [../../../../molecule/common/test_deps : Install selinux python libs] *****
2026-06-12 14:16:01.239708 | controller | Friday 12 June 2026  14:16:01 +0000 (0:01:07.597)       0:01:35.030 ***********
2026-06-12 14:16:18.338286 | controller | ok: [instance]
2026-06-12 14:16:18.338949 | controller |
2026-06-12 14:16:18.338992 | controller | TASK [../../../../molecule/common/test_deps : Install python yaml libs] ********
2026-06-12 14:16:18.339003 | controller | Friday 12 June 2026  14:16:18 +0000 (0:00:17.099)       0:01:52.130 ***********
2026-06-12 14:16:22.302894 | controller | ok: [instance]
2026-06-12 14:16:22.302927 | controller |
2026-06-12 14:16:22.302934 | controller | TASK [../../../../molecule/common/test_deps : Install extra packages] **********
2026-06-12 14:16:22.302940 | controller | Friday 12 June 2026  14:16:22 +0000 (0:00:03.964)       0:01:56.094 ***********
2026-06-12 14:17:02.606058 | controller | changed: [instance]
2026-06-12 14:17:02.606102 | controller |
2026-06-12 14:17:02.606111 | controller | TASK [../../../../molecule/common/test_deps : Check for /etc/ansible/facts.d/bootc.fact] ***
2026-06-12 14:17:02.606119 | controller | Friday 12 June 2026  14:17:02 +0000 (0:00:40.301)       0:02:36.395 ***********
2026-06-12 14:17:03.722559 | controller | ok: [instance]
2026-06-12 14:17:03.722601 | controller |
2026-06-12 14:17:03.722611 | controller | TASK [../../../../molecule/common/test_deps : Create /etc/ansible/facts.d] *****
2026-06-12 14:17:03.722620 | controller | Friday 12 June 2026  14:17:03 +0000 (0:00:01.117)       0:02:37.513 ***********
2026-06-12 14:17:04.977691 | controller | changed: [instance]
2026-06-12 14:17:04.977725 | controller |
2026-06-12 14:17:04.977732 | controller | TASK [../../../../molecule/common/test_deps : Create /etc/ansible/facts.d/bootc.fact] ***
2026-06-12 14:17:04.977747 | controller | Friday 12 June 2026  14:17:04 +0000 (0:00:01.256)       0:02:38.769 ***********
2026-06-12 14:17:07.431808 | controller | changed: [instance]
2026-06-12 14:17:07.431862 | controller |
2026-06-12 14:17:07.431869 | controller | TASK [ansible.builtin.include_role : osp.edpm.env_data] ************************
2026-06-12 14:17:07.431876 | controller | Friday 12 June 2026  14:17:07 +0000 (0:00:02.452)       0:02:41.221 ***********
2026-06-12 14:17:07.467864 | controller |
2026-06-12 14:17:07.467971 | controller | TASK [osp.edpm.env_data : Validating arguments against arg spec 'main' - The main entry point for the osp.edpm.env_data role.] ***
2026-06-12 14:17:07.467980 | controller | Friday 12 June 2026  14:17:07 +0000 (0:00:00.037)       0:02:41.259 ***********
2026-06-12 14:17:07.493373 | controller | ok: [instance]
2026-06-12 14:17:07.493493 | controller |
2026-06-12 14:17:07.493724 | controller | TASK [osp.edpm.env_data : Gather all installed packages] ***********************
2026-06-12 14:17:07.493887 | controller | Friday 12 June 2026  14:17:07 +0000 (0:00:00.025)       0:02:41.284 ***********
2026-06-12 14:17:09.351389 | controller | ok: [instance]
2026-06-12 14:17:09.351439 | controller |
2026-06-12 14:17:09.351450 | controller | TASK [osp.edpm.env_data : Gather repository list] ******************************
2026-06-12 14:17:09.351465 | controller | Friday 12 June 2026  14:17:09 +0000 (0:00:01.858)       0:02:43.143 ***********
2026-06-12 14:17:11.717677 | controller | ok: [instance]
2026-06-12 14:17:11.717729 | controller |
2026-06-12 14:17:11.717737 | controller | TASK [osp.edpm.env_data : Output installed packages] ***************************
2026-06-12 14:17:11.717743 | controller | Friday 12 June 2026  14:17:11 +0000 (0:00:02.365)       0:02:45.508 ***********
2026-06-12 14:17:11.820458 | controller | ok: [instance] =>
2026-06-12 14:17:11.820515 | controller |   msg:
2026-06-12 14:17:11.822453 | controller |   - gpg-pubkey
2026-06-12 14:17:11.822526 | controller |   - setup
2026-06-12 14:17:11.822532 | controller |   - filesystem
2026-06-12 14:17:11.822537 | controller |   - tzdata
2026-06-12 14:17:11.822541 | controller |   - basesystem
2026-06-12 14:17:11.822546 | controller |   - python3-setuptools-wheel
2026-06-12 14:17:11.822552 | controller |   - pcre2-syntax
2026-06-12 14:17:11.822556 | controller |   - ncurses-base
2026-06-12 14:17:11.822561 | controller |   - ncurses-libs
2026-06-12 14:17:11.822565 | controller |   - bash
2026-06-12 14:17:11.822569 | controller |   - libgcc
2026-06-12 14:17:11.822574 | controller |   - glibc-minimal-langpack
2026-06-12 14:17:11.822585 | controller |   - glibc-common
2026-06-12 14:17:11.822743 | controller |   - glibc
2026-06-12 14:17:11.822896 | controller |   - zlib
2026-06-12 14:17:11.823065 | controller |   - bzip2-libs
2026-06-12 14:17:11.823290 | controller |   - xz-libs
2026-06-12 14:17:11.823490 | controller |   - libzstd
2026-06-12 14:17:11.823668 | controller |   - sqlite-libs
2026-06-12 14:17:11.823887 | controller |   - libgpg-error
2026-06-12 14:17:11.824102 | controller |   - libxml2
2026-06-12 14:17:11.824309 | controller |   - libattr
2026-06-12 14:17:11.824495 | controller |   - libacl
2026-06-12 14:17:11.824674 | controller |   - libcap
2026-06-12 14:17:11.824827 | controller |   - libffi
2026-06-12 14:17:11.825031 | controller |   - libstdc++
2026-06-12 14:17:11.825265 | controller |   - popt
2026-06-12 14:17:11.825489 | controller |   - crypto-policies
2026-06-12 14:17:11.825668 | controller |   - json-c
2026-06-12 14:17:11.825825 | controller |   - keyutils-libs
2026-06-12 14:17:11.825976 | controller |   - libcom_err
2026-06-12 14:17:11.826127 | controller |   - libtasn1
2026-06-12 14:17:11.826300 | controller |   - libuuid
2026-06-12 14:17:11.826456 | controller |   - libxcrypt
2026-06-12 14:17:11.826605 | controller |   - lua-libs
2026-06-12 14:17:11.826755 | controller |   - readline
2026-06-12 14:17:11.826908 | controller |   - p11-kit
2026-06-12 14:17:11.827057 | controller |   - libassuan
2026-06-12 14:17:11.827233 | controller |   - elfutils-libelf
2026-06-12 14:17:11.827386 | controller |   - file-libs
2026-06-12 14:17:11.827543 | controller |   - expat
2026-06-12 14:17:11.827691 | controller |   - gdbm-libs
2026-06-12 14:17:11.827837 | controller |   - gmp
2026-06-12 14:17:11.827986 | controller |   - libsepol
2026-06-12 14:17:11.828135 | controller |   - libsigsegv
2026-06-12 14:17:11.828306 | controller |   - libsmartcols
2026-06-12 14:17:11.828486 | controller |   - libunistring
2026-06-12 14:17:11.828666 | controller |   - pcre
2026-06-12 14:17:11.828825 | controller |   - grep
2026-06-12 14:17:11.828993 | controller |   - libidn2
2026-06-12 14:17:11.829142 | controller |   - mpfr
2026-06-12 14:17:11.829313 | controller |   - gawk
2026-06-12 14:17:11.829469 | controller |   - libcomps
2026-06-12 14:17:11.829622 | controller |   - keyutils
2026-06-12 14:17:11.829774 | controller |   - attr
2026-06-12 14:17:11.829932 | controller |   - libgcrypt
2026-06-12 14:17:11.830084 | controller |   - libksba
2026-06-12 14:17:11.830270 | controller |   - alternatives
2026-06-12 14:17:11.830434 | controller |   - p11-kit-trust
2026-06-12 14:17:11.830589 | controller |   - libcap-ng
2026-06-12 14:17:11.830745 | controller |   - audit-libs
2026-06-12 14:17:11.830897 | controller |   - libgomp
2026-06-12 14:17:11.831055 | controller |   - libnghttp2
2026-06-12 14:17:11.831245 | controller |   - libtool-ltdl
2026-06-12 14:17:11.831407 | controller |   - libverto
2026-06-12 14:17:11.831557 | controller |   - libyaml
2026-06-12 14:17:11.831706 | controller |   - lz4-libs
2026-06-12 14:17:11.831855 | controller |   - nettle
2026-06-12 14:17:11.832004 | controller |   - gnutls
2026-06-12 14:17:11.832178 | controller |   - npth
2026-06-12 14:17:11.832365 | controller |   - pcre2
2026-06-12 14:17:11.832538 | controller |   - libselinux
2026-06-12 14:17:11.832693 | controller |   - coreutils-single
2026-06-12 14:17:11.832841 | controller |   - sed
2026-06-12 14:17:11.833018 | controller |   - libblkid
2026-06-12 14:17:11.833175 | controller |   - libmount
2026-06-12 14:17:11.833364 | controller |   - glib2
2026-06-12 14:17:11.833520 | controller |   - json-glib
2026-06-12 14:17:11.833672 | controller |   - openssl-fips-provider-so
2026-06-12 14:17:11.833824 | controller |   - openssl-fips-provider
2026-06-12 14:17:11.833981 | controller |   - findutils
2026-06-12 14:17:11.834130 | controller |   - ca-certificates
2026-06-12 14:17:11.834317 | controller |   - krb5-libs
2026-06-12 14:17:11.834475 | controller |   - libcurl-minimal
2026-06-12 14:17:11.834624 | controller |   - curl-minimal
2026-06-12 14:17:11.834774 | controller |   - cyrus-sasl-lib
2026-06-12 14:17:11.834922 | controller |   - libarchive
2026-06-12 14:17:11.835078 | controller |   - rpm
2026-06-12 14:17:11.835261 | controller |   - rpm-libs
2026-06-12 14:17:11.835426 | controller |   - libmodulemd
2026-06-12 14:17:11.835577 | controller |   - libsolv
2026-06-12 14:17:11.835726 | controller |   - libevent
2026-06-12 14:17:11.835873 | controller |   - librhsm
2026-06-12 14:17:11.836021 | controller |   - python3-pip-wheel
2026-06-12 14:17:11.836181 | controller |   - python3
2026-06-12 14:17:11.836386 | controller |   - python3-libs
2026-06-12 14:17:11.836542 | controller |   - python3-libcomps
2026-06-12 14:17:11.836691 | controller |   - shadow-utils
2026-06-12 14:17:11.836840 | controller |   - openldap
2026-06-12 14:17:11.837015 | controller |   - gnupg2
2026-06-12 14:17:11.837176 | controller |   - gpgme
2026-06-12 14:17:11.837350 | controller |   - librepo
2026-06-12 14:17:11.837509 | controller |   - libdnf
2026-06-12 14:17:11.837661 | controller |   - python3-libdnf
2026-06-12 14:17:11.837813 | controller |   - python3-hawkey
2026-06-12 14:17:11.838150 | controller |   - python3-gpg
2026-06-12 14:17:11.838329 | controller |   - tpm2-tss
2026-06-12 14:17:11.838499 | controller |   - ima-evm-utils
2026-06-12 14:17:11.838651 | controller |   - rpm-sign-libs
2026-06-12 14:17:11.838804 | controller |   - elfutils-default-yama-scope
2026-06-12 14:17:11.839139 | controller |   - elfutils-libs
2026-06-12 14:17:11.839318 | controller |   - rpm-build-libs
2026-06-12 14:17:11.839492 | controller |   - python3-rpm
2026-06-12 14:17:11.839700 | controller |   - libreport-filesystem
2026-06-12 14:17:11.839855 | controller |   - dnf-data
2026-06-12 14:17:11.840014 | controller |   - python3-dnf
2026-06-12 14:17:11.840155 | controller |   - dnf
2026-06-12 14:17:11.840328 | controller |   - fonts-filesystem
2026-06-12 14:17:11.840501 | controller |   - dejavu-sans-fonts
2026-06-12 14:17:11.840653 | controller |   - langpacks-core-font-en
2026-06-12 14:17:11.840810 | controller |   - langpacks-core-en
2026-06-12 14:17:11.840980 | controller |   - langpacks-en
2026-06-12 14:17:11.841129 | controller |   - yum
2026-06-12 14:17:11.841326 | controller |   - libdnf-plugin-subscription-manager
2026-06-12 14:17:11.841492 | controller |   - crypto-policies-scripts
2026-06-12 14:17:11.841661 | controller |   - gzip
2026-06-12 14:17:11.841815 | controller |   - tar
2026-06-12 14:17:11.842013 | controller |   - vim-minimal
2026-06-12 14:17:11.842123 | controller |   - gdb-gdbserver
2026-06-12 14:17:11.842321 | controller |   - rootfiles
2026-06-12 14:17:11.842490 | controller |   - python3-six
2026-06-12 14:17:11.842643 | controller |   - python3-dateutil
2026-06-12 14:17:11.842792 | controller |   - dmidecode
2026-06-12 14:17:11.842941 | controller |   - python3-iniparse
2026-06-12 14:17:11.843090 | controller |   - subscription-manager-rhsm-certificates
2026-06-12 14:17:11.843261 | controller |   - python3-setuptools
2026-06-12 14:17:11.843415 | controller |   - python3-idna
2026-06-12 14:17:11.843606 | controller |   - libfdisk
2026-06-12 14:17:11.843785 | controller |   - cracklib
2026-06-12 14:17:11.843945 | controller |   - cracklib-dicts
2026-06-12 14:17:11.844095 | controller |   - python3-inotify
2026-06-12 14:17:11.844267 | controller |   - dbus-libs
2026-06-12 14:17:11.844423 | controller |   - python3-dbus
2026-06-12 14:17:11.844571 | controller |   - python3-systemd
2026-06-12 14:17:11.844733 | controller |   - util-linux-core
2026-06-12 14:17:11.844892 | controller |   - which
2026-06-12 14:17:11.845067 | controller |   - python3-pysocks
2026-06-12 14:17:11.845236 | controller |   - python3-urllib3
2026-06-12 14:17:11.845400 | controller |   - python3-librepo
2026-06-12 14:17:11.845547 | controller |   - python3-decorator
2026-06-12 14:17:11.845710 | controller |   - python3-chardet
2026-06-12 14:17:11.845865 | controller |   - python3-requests
2026-06-12 14:17:11.846072 | controller |   - python3-cloud-what
2026-06-12 14:17:11.846288 | controller |   - python3-subscription-manager-rhsm
2026-06-12 14:17:11.846455 | controller |   - psmisc
2026-06-12 14:17:11.846605 | controller |   - libutempter
2026-06-12 14:17:11.846770 | controller |   - libseccomp
2026-06-12 14:17:11.846924 | controller |   - libmnl
2026-06-12 14:17:11.847075 | controller |   - libeconf
2026-06-12 14:17:11.847242 | controller |   - libdb
2026-06-12 14:17:11.847397 | controller |   - pam
2026-06-12 14:17:11.847546 | controller |   - libpwquality
2026-06-12 14:17:11.847692 | controller |   - util-linux
2026-06-12 14:17:11.847852 | controller |   - libuser
2026-06-12 14:17:11.848000 | controller |   - passwd
2026-06-12 14:17:11.848147 | controller |   - usermode
2026-06-12 14:17:11.848318 | controller |   - virt-what
2026-06-12 14:17:11.848493 | controller |   - libbpf
2026-06-12 14:17:11.848642 | controller |   - iproute
2026-06-12 14:17:11.848807 | controller |   - kmod-libs
2026-06-12 14:17:11.848979 | controller |   - gobject-introspection
2026-06-12 14:17:11.849147 | controller |   - python3-gobject-base-noarch
2026-06-12 14:17:11.849317 | controller |   - python3-gobject-base
2026-06-12 14:17:11.849472 | controller |   - acl
2026-06-12 14:17:11.849658 | controller |   - dbus
2026-06-12 14:17:11.849845 | controller |   - dbus-common
2026-06-12 14:17:11.850001 | controller |   - dbus-broker
2026-06-12 14:17:11.850231 | controller |   - python3-dnf-plugins-core
2026-06-12 14:17:11.850394 | controller |   - subscription-manager
2026-06-12 14:17:11.850543 | controller |   - procps-ng
2026-06-12 14:17:11.850691 | controller |   - openssl-libs
2026-06-12 14:17:11.850852 | controller |   - openssl
2026-06-12 14:17:11.851005 | controller |   - libxcrypt-compat
2026-06-12 14:17:11.851239 | controller |   - openssh
2026-06-12 14:17:11.851409 | controller |   - ncurses
2026-06-12 14:17:11.851588 | controller |   - libselinux-utils
2026-06-12 14:17:11.851741 | controller |   - libedit
2026-06-12 14:17:11.851905 | controller |   - libcbor
2026-06-12 14:17:11.852056 | controller |   - libfido2
2026-06-12 14:17:11.852224 | controller |   - openssh-clients
2026-06-12 14:17:11.852375 | controller |   - less
2026-06-12 14:17:11.852524 | controller |   - git-core
2026-06-12 14:17:11.852722 | controller |   - git-core-doc
2026-06-12 14:17:11.852926 | controller |   - groff-base
2026-06-12 14:17:11.853137 | controller |   - perl-Digest
2026-06-12 14:17:11.853312 | controller |   - perl-Digest-MD5
2026-06-12 14:17:11.853464 | controller |   - perl-B
2026-06-12 14:17:11.853614 | controller |   - perl-FileHandle
2026-06-12 14:17:11.853807 | controller |   - perl-Data-Dumper
2026-06-12 14:17:11.853982 | controller |   - perl-libnet
2026-06-12 14:17:11.854135 | controller |   - perl-AutoLoader
2026-06-12 14:17:11.854304 | controller |   - perl-base
2026-06-12 14:17:11.854456 | controller |   - perl-URI
2026-06-12 14:17:11.854602 | controller |   - perl-Time-Local
2026-06-12 14:17:11.854749 | controller |   - perl-Mozilla-CA
2026-06-12 14:17:11.854906 | controller |   - perl-if
2026-06-12 14:17:11.855065 | controller |   - perl-IO-Socket-IP
2026-06-12 14:17:11.855241 | controller |   - perl-File-Path
2026-06-12 14:17:11.855400 | controller |   - perl-Pod-Escapes
2026-06-12 14:17:11.855548 | controller |   - perl-Text-Tabs+Wrap
2026-06-12 14:17:11.855698 | controller |   - perl-IO-Socket-SSL
2026-06-12 14:17:11.855846 | controller |   - perl-Net-SSLeay
2026-06-12 14:17:11.856016 | controller |   - perl-Term-ANSIColor
2026-06-12 14:17:11.856183 | controller |   - perl-Class-Struct
2026-06-12 14:17:11.856357 | controller |   - perl-POSIX
2026-06-12 14:17:11.856508 | controller |   - perl-IPC-Open3
2026-06-12 14:17:11.856684 | controller |   - perl-subs
2026-06-12 14:17:11.856869 | controller |   - perl-File-Temp
2026-06-12 14:17:11.857114 | controller |   - perl-Term-Cap
2026-06-12 14:17:11.857351 | controller |   - perl-HTTP-Tiny
2026-06-12 14:17:11.857551 | controller |   - perl-Pod-Simple
2026-06-12 14:17:11.857703 | controller |   - perl-Socket
2026-06-12 14:17:11.857859 | controller |   - perl-SelectSaver
2026-06-12 14:17:11.858000 | controller |   - perl-Symbol
2026-06-12 14:17:11.858147 | controller |   - perl-File-stat
2026-06-12 14:17:11.858327 | controller |   - perl-podlators
2026-06-12 14:17:11.858481 | controller |   - perl-Pod-Perldoc
2026-06-12 14:17:11.858630 | controller |   - perl-Text-ParseWords
2026-06-12 14:17:11.858776 | controller |   - perl-Fcntl
2026-06-12 14:17:11.858930 | controller |   - perl-mro
2026-06-12 14:17:11.859076 | controller |   - perl-IO
2026-06-12 14:17:11.859245 | controller |   - perl-overloading
2026-06-12 14:17:11.859400 | controller |   - perl-Pod-Usage
2026-06-12 14:17:11.859546 | controller |   - perl-MIME-Base64
2026-06-12 14:17:11.859692 | controller |   - perl-Scalar-List-Utils
2026-06-12 14:17:11.859871 | controller |   - perl-constant
2026-06-12 14:17:11.860025 | controller |   - perl-parent
2026-06-12 14:17:11.860200 | controller |   - perl-Errno
2026-06-12 14:17:11.860426 | controller |   - perl-File-Basename
2026-06-12 14:17:11.860611 | controller |   - perl-Getopt-Std
2026-06-12 14:17:11.860784 | controller |   - perl-Storable
2026-06-12 14:17:11.860975 | controller |   - perl-overload
2026-06-12 14:17:11.861188 | controller |   - perl-vars
2026-06-12 14:17:11.861405 | controller |   - perl-Getopt-Long
2026-06-12 14:17:11.861564 | controller |   - perl-Carp
2026-06-12 14:17:11.861712 | controller |   - perl-Exporter
2026-06-12 14:17:11.861859 | controller |   - perl-PathTools
2026-06-12 14:17:11.862010 | controller |   - perl-NDBM_File
2026-06-12 14:17:11.862170 | controller |   - perl-Encode
2026-06-12 14:17:11.862329 | controller |   - perl-libs
2026-06-12 14:17:11.862484 | controller |   - perl-interpreter
2026-06-12 14:17:11.862633 | controller |   - perl-Error
2026-06-12 14:17:11.862791 | controller |   - perl-DynaLoader
2026-06-12 14:17:11.862941 | controller |   - perl-TermReadKey
2026-06-12 14:17:11.863088 | controller |   - perl-lib
2026-06-12 14:17:11.863284 | controller |   - perl-Git
2026-06-12 14:17:11.863439 | controller |   - git
2026-06-12 14:17:11.863585 | controller |   - diffutils
2026-06-12 14:17:11.863732 | controller |   - policycoreutils
2026-06-12 14:17:11.863880 | controller |   - selinux-policy
2026-06-12 14:17:11.864029 | controller |   - selinux-policy-targeted
2026-06-12 14:17:11.864206 | controller |   - rpm-plugin-selinux
2026-06-12 14:17:11.864363 | controller |   - python3-pip
2026-06-12 14:17:11.864511 | controller |   - python3-libselinux
2026-06-12 14:17:11.864657 | controller |   - sudo
2026-06-12 14:17:11.864804 | controller |   - systemd-libs
2026-06-12 14:17:11.864977 | controller |   - python3-pbr
2026-06-12 14:17:11.865125 | controller |   - python3-pyyaml
2026-06-12 14:17:11.865291 | controller |   - kmod
2026-06-12 14:17:11.865451 | controller |   - python3-netaddr
2026-06-12 14:17:11.865598 | controller |   - chkconfig
2026-06-12 14:17:11.865747 | controller |   - NetworkManager-config-server
2026-06-12 14:17:11.865893 | controller |   - python3-stevedore
2026-06-12 14:17:11.866040 | controller |   - NetworkManager-libnm
2026-06-12 14:17:11.866213 | controller |   - python3-pyudev
2026-06-12 14:17:11.866367 | controller |   - systemd-rpm-macros
2026-06-12 14:17:11.866513 | controller |   - systemd-pam
2026-06-12 14:17:11.866659 | controller |   - systemd
2026-06-12 14:17:11.866802 | controller |   - iputils
2026-06-12 14:17:11.866948 | controller |   - device-mapper-libs
2026-06-12 14:17:11.867094 | controller |   - device-mapper
2026-06-12 14:17:11.867286 | controller |   - cryptsetup-libs
2026-06-12 14:17:11.867446 | controller |   - initscripts-service
2026-06-12 14:17:11.867599 | controller |   - python3-setools
2026-06-12 14:17:11.867752 | controller |   - protobuf-c
2026-06-12 14:17:11.867905 | controller |   - unbound-libs
2026-06-12 14:17:11.868053 | controller |   - numactl-libs
2026-06-12 14:17:11.868216 | controller |   - libnl3
2026-06-12 14:17:11.868384 | controller |   - libibverbs
2026-06-12 14:17:11.868535 | controller |   - libndp
2026-06-12 14:17:11.868707 | controller |   - kbd-misc
2026-06-12 14:17:11.868829 | controller |   - kbd-legacy
2026-06-12 14:17:11.868999 | controller |   - kbd
2026-06-12 14:17:11.869148 | controller |   - systemd-udev
2026-06-12 14:17:11.869338 | controller |   - NetworkManager
2026-06-12 14:17:11.869487 | controller |   - nmstate-libs
2026-06-12 14:17:11.869632 | controller |   - nmstate
2026-06-12 14:17:11.869779 | controller |   - jansson
2026-06-12 14:17:11.869927 | controller |   - NetworkManager-ovs
2026-06-12 14:17:11.870073 | controller |   - python3-libnmstate
2026-06-12 14:17:11.870247 | controller |   - initscripts-rename-device
2026-06-12 14:17:11.870419 | controller |   - initscripts
2026-06-12 14:17:11.870571 | controller |   - hostname
2026-06-12 14:17:11.870715 | controller |   - ethtool
2026-06-12 14:17:11.870863 | controller |   - dhcp-common
2026-06-12 14:17:11.871015 | controller |   - centos-gpg-keys
2026-06-12 14:17:11.871176 | controller |   - centos-stream-repos
2026-06-12 14:17:11.871349 | controller |   - bc
2026-06-12 14:17:11.871512 | controller |   - python3-pyrsistent
2026-06-12 14:17:11.871659 | controller |   - python3-psutil
2026-06-12 14:17:11.871804 | controller |   - python3-audit
2026-06-12 14:17:11.871952 | controller |   - python3-attrs
2026-06-12 14:17:11.872096 | controller |   - python3-jsonschema
2026-06-12 14:17:11.872272 | controller |   - mstflint
2026-06-12 14:17:11.872438 | controller |   - libmaxminddb
2026-06-12 14:17:11.872589 | controller |   - geolite2-country
2026-06-12 14:17:11.872743 | controller |   - geolite2-city
2026-06-12 14:17:11.872893 | controller |   - ipcalc
2026-06-12 14:17:11.873060 | controller |   - openstack-network-scripts
2026-06-12 14:17:11.873236 | controller |   - openstack-network-scripts-openvswitch3.4
2026-06-12 14:17:11.873538 | controller |   - dhcp-client
2026-06-12 14:17:11.873709 | controller |   - checkpolicy
2026-06-12 14:17:11.873863 | controller |   - python3-wrapt
2026-06-12 14:17:11.874025 | controller |   - python3-debtcollector
2026-06-12 14:17:11.874199 | controller |   - python3-rfc3986
2026-06-12 14:17:11.874377 | controller |   - python3-pyroute2
2026-06-12 14:17:11.874519 | controller |   - python3-pyparsing
2026-06-12 14:17:11.874666 | controller |   - python3-packaging
2026-06-12 14:17:11.874814 | controller |   - python3-iso8601
2026-06-12 14:17:11.874959 | controller |   - python3-fasteners
2026-06-12 14:17:11.875111 | controller |   - python3-distro
2026-06-12 14:17:11.875295 | controller |   - python-oslo-utils-lang
2026-06-12 14:17:11.875450 | controller |   - python-oslo-i18n-lang
2026-06-12 14:17:11.875597 | controller |   - python3-oslo-i18n
2026-06-12 14:17:11.875743 | controller |   - python3-oslo-utils
2026-06-12 14:17:11.875888 | controller |   - python3-oslo-config
2026-06-12 14:17:11.876045 | controller |   - python-oslo-concurrency-lang
2026-06-12 14:17:11.876210 | controller |   - python3-oslo-concurrency
2026-06-12 14:17:11.876363 | controller |   - libsemanage
2026-06-12 14:17:11.876511 | controller |   - python3-libsemanage
2026-06-12 14:17:11.876656 | controller |   - python3-policycoreutils
2026-06-12 14:17:11.876803 | controller |   - policycoreutils-python-utils
2026-06-12 14:17:11.877010 | controller |   - openvswitch-selinux-extra-policy
2026-06-12 14:17:11.877151 | controller |   - openvswitch3.4
2026-06-12 14:17:11.877386 | controller |   - rdo-openvswitch
2026-06-12 14:17:11.877573 | controller |   - os-net-config
2026-06-12 14:17:11.877725 | controller |   - centos-stream-release
2026-06-12 14:17:11.877876 | controller |   - container-selinux
2026-06-12 14:17:11.877980 | controller |   - fuse3-libs
2026-06-12 14:17:11.878086 | controller |   - libnftnl
2026-06-12 14:17:11.878202 | controller |   - passt-selinux
2026-06-12 14:17:11.878311 | controller |   - passt
2026-06-12 14:17:11.878413 | controller |   - yajl
2026-06-12 14:17:11.878517 | controller |   - libslirp
2026-06-12 14:17:11.878622 | controller |   - slirp4netns
2026-06-12 14:17:11.878727 | controller |   - libnet
2026-06-12 14:17:11.878832 | controller |   - conmon
2026-06-12 14:17:11.878938 | controller |   - aardvark-dns
2026-06-12 14:17:11.879043 | controller |   - shadow-utils-subid
2026-06-12 14:17:11.879149 | controller |   - libnfnetlink
2026-06-12 14:17:11.879277 | controller |   - libnetfilter_conntrack
2026-06-12 14:17:11.879392 | controller |   - iptables-libs
2026-06-12 14:17:11.879498 | controller |   - iptables-nft
2026-06-12 14:17:11.879603 | controller |   - criu
2026-06-12 14:17:11.879707 | controller |   - criu-libs
2026-06-12 14:17:11.879812 | controller |   - crun
2026-06-12 14:17:11.879922 | controller |   - netavark
2026-06-12 14:17:11.880028 | controller |   - nftables
2026-06-12 14:17:11.880141 | controller |   - fuse-common
2026-06-12 14:17:11.880275 | controller |   - fuse3
2026-06-12 14:17:11.880391 | controller |   - fuse-overlayfs
2026-06-12 14:17:11.880496 | controller |   - containers-common
2026-06-12 14:17:11.880600 | controller |   - podman
2026-06-12 14:17:11.880701 | controller |
2026-06-12 14:17:11.880813 | controller | TASK [osp.edpm.env_data : Output installed repositories] ***********************
2026-06-12 14:17:11.880957 | controller | Friday 12 June 2026  14:17:11 +0000 (0:00:00.102)       0:02:45.611 ***********
2026-06-12 14:17:11.889323 | controller | ok: [instance] =>
2026-06-12 14:17:11.889415 | controller |   msg: |-
2026-06-12 14:17:11.889434 | controller |     #### REPOSITORIES
2026-06-12 14:17:11.889557 | controller |     Updating Subscription Management repositories.
2026-06-12 14:17:11.889708 | controller |     Unable to read consumer identity
2026-06-12 14:17:11.889853 | controller |
2026-06-12 14:17:11.890045 | controller |     This system is not registered with an entitlement server. You can use subscription-manager to register.
2026-06-12 14:17:11.890174 | controller |
2026-06-12 14:17:11.890351 | controller |     repo id                            repo name
2026-06-12 14:17:11.890498 | controller |     appstream                          CentOS Stream 9 - AppStream
2026-06-12 14:17:11.890696 | controller |     baseos                             CentOS Stream 9 - BaseOS
2026-06-12 14:17:11.890847 | controller |     centos9-nfv-ovs                    NFV SIG OpenvSwitch
2026-06-12 14:17:11.890996 | controller |     centos9-opstools                   centos9-opstools
2026-06-12 14:17:11.891144 | controller |     centos9-rabbitmq                   centos9-rabbitmq
2026-06-12 14:17:11.891317 | controller |     centos9-storage                    centos9-storage
2026-06-12 14:17:11.891475 | controller |     delorean-component-barbican        delorean-python-castellan-609f4ea667df386849930cf61d875b5c9e16abbb
2026-06-12 14:17:11.891628 | controller |     delorean-component-baremetal       delorean-openstack-ironic-c525a16b06266b6b474c99b13301d5b4409e92a0
2026-06-12 14:17:11.891777 | controller |     delorean-component-cinder          delorean-openstack-cinder-92c645f1f1e913b5b1cd8ad0227a251f03adec04
2026-06-12 14:17:11.891927 | controller |     delorean-component-clients         delorean-ansible-collections-openstack-f584c54dfd03e81c1e9c30e2d22f19d2d17a4353
2026-06-12 14:17:11.892078 | controller |     delorean-component-cloudops        delorean-openstack-ceilometer-60803e710e7f5b3cd6b1f2175bc41b67ac456667
2026-06-12 14:17:11.892257 | controller |     delorean-component-common          delorean-openstack-kolla-e7bd46dad0b62ff151667be2a6f5c8a7be41911e
2026-06-12 14:17:11.892415 | controller |     delorean-component-compute         delorean-openstack-nova-3e7017eb2952d5258d96e27b6c16aae1ed472b21
2026-06-12 14:17:11.892564 | controller |     delorean-component-designate       delorean-openstack-designate-82652559ea8641b11c4227f0e9cf273b4c567b96
2026-06-12 14:17:11.892715 | controller |     delorean-component-glance          delorean-openstack-glance-e055873be4079bc9d37165cf75de5495603133b0
2026-06-12 14:17:11.892936 | controller |     delorean-component-keystone        delorean-openstack-keystone-4f1b7e96e38463d5fcd9143446b408bc4c0360d1
2026-06-12 14:17:11.893101 | controller |     delorean-component-manila          delorean-openstack-manila-70623bb84e7880f7f2f750bda2774e5e5151c900
2026-06-12 14:17:11.893282 | controller |     delorean-component-network         delorean-python-networking-mlnx-7139a7f0bce9d6a1f5fe919f623386a55396229d
2026-06-12 14:17:11.893444 | controller |     delorean-component-octavia         delorean-openstack-octavia-e981d3e172b8e4471f97251afc491141f06ec867
2026-06-12 14:17:11.893604 | controller |     delorean-component-optimize        delorean-openstack-watcher-71470dac73abba9e5dcf284462b4e1d167777745
2026-06-12 14:17:11.893758 | controller |     delorean-component-podified        delorean-python-tcib-9aecfe867ca44ca276dd228be612c156cf3842f7
2026-06-12 14:17:11.893915 | controller |     delorean-component-puppet          delorean-puppet-ceph-9b6c881f94cc890560eb6739d709712a0438389a
2026-06-12 14:17:11.894123 | controller |     delorean-component-swift           delorean-openstack-swift-e10c2bafcb8fc80929bce3e064fee5f86a49e718
2026-06-12 14:17:11.894333 | controller |     delorean-component-tempest         delorean-python-mistral-tests-tempest-900580c9530660d21ca20063459f673803b7a299
2026-06-12 14:17:11.894499 | controller |     delorean-component-ui              delorean-python-django-horizon-915b939b342dc65f46cca345b93deaaee675e778
2026-06-12 14:17:11.894658 | controller |     delorean-master-build-deps         dlrn-master-build-deps
2026-06-12 14:17:11.894820 | controller |     delorean-master-testing            dlrn-master-testing
2026-06-12 14:17:11.894976 | controller |     epel-low-priority                  Extra Packages for Enterprise Linux 9 - x86_64
2026-06-12 14:17:11.895151 | controller |     extras-common                      CentOS Stream 9 - Extras packages
2026-06-12 14:17:11.895341 | controller |     repo-setup-centos-appstream        repo-setup-centos-appstream
2026-06-12 14:17:11.895508 | controller |     repo-setup-centos-baseos           repo-setup-centos-baseos
2026-06-12 14:17:11.895666 | controller |     repo-setup-centos-highavailability repo-setup-centos-highavailability
2026-06-12 14:17:11.895818 | controller |     repo-setup-centos-powertools       repo-setup-centos-powertools
2026-06-12 14:17:11.895971 | controller |
2026-06-12 14:17:11.896145 | controller | TASK [Ensure /etc/localtime exists] ********************************************
2026-06-12 14:17:11.896328 | controller | Friday 12 June 2026  14:17:11 +0000 (0:00:00.069)       0:02:45.681 ***********
2026-06-12 14:17:13.120970 | controller | changed: [instance]
2026-06-12 14:17:13.121029 | controller |
2026-06-12 14:17:13.121035 | controller | TASK [Force systemd to reread configs] *****************************************
2026-06-12 14:17:13.121039 | controller | Friday 12 June 2026  14:17:13 +0000 (0:00:01.231)       0:02:46.912 ***********
2026-06-12 14:17:14.983307 | controller | ok: [instance]
2026-06-12 14:17:14.983349 | controller |
2026-06-12 14:17:14.983395 | controller | PLAY RECAP *********************************************************************
2026-06-12 14:17:14.983575 | controller | instance                   : ok=28   changed=13   unreachable=0    failed=0    skipped=1    rescued=0    ignored=0
2026-06-12 14:17:14.983761 | controller |
2026-06-12 14:17:14.983962 | controller | Friday 12 June 2026  14:17:14 +0000 (0:00:01.862)       0:02:48.775 ***********
2026-06-12 14:17:14.984180 | controller | ===============================================================================
2026-06-12 14:17:14.984401 | controller | ../../../../molecule/common/test_deps : Install edpm packages ---------- 67.60s
2026-06-12 14:17:14.984578 | controller | ../../../../molecule/common/test_deps : Install extra packages --------- 40.30s
2026-06-12 14:17:14.984781 | controller | ../../../../molecule/common/test_deps : Install selinux python libs ---- 17.10s
2026-06-12 14:17:14.984974 | controller | ../../../../molecule/common/test_deps : Create default repo file -------- 4.65s
2026-06-12 14:17:14.985198 | controller | ../../../../molecule/common/test_deps : Install python yaml libs -------- 3.96s
2026-06-12 14:17:14.985619 | controller | ../../../../molecule/common/test_deps : Install repo-setup pre-requirements --- 3.29s
2026-06-12 14:17:14.985942 | controller | Gathering Facts --------------------------------------------------------- 2.76s
2026-06-12 14:17:14.986313 | controller | ../../../../molecule/common/test_deps : Disable ubi host subscription-manager integration --- 2.50s
2026-06-12 14:17:14.986636 | controller | ../../../../molecule/common/test_deps : Create /etc/ansible/facts.d/bootc.fact --- 2.45s
2026-06-12 14:17:14.986918 | controller | osp.edpm.env_data : Gather repository list ------------------------------ 2.37s
2026-06-12 14:17:14.987245 | controller | ../../../../molecule/common/test_deps : Workaround of redhat-release binary on ubi9 --- 2.27s
2026-06-12 14:17:14.987568 | controller | ../../../../molecule/common/test_deps : Fetch latest repo version ------- 2.11s
2026-06-12 14:17:14.987861 | controller | ../../../../molecule/common/test_deps : Install repo-setup -------------- 1.96s
2026-06-12 14:17:14.988187 | controller | Force systemd to reread configs ----------------------------------------- 1.86s
2026-06-12 14:17:14.988514 | controller | osp.edpm.env_data : Gather all installed packages ----------------------- 1.86s
2026-06-12 14:17:14.988806 | controller | ../../../../molecule/common/test_deps : Get repo-setup repository ------- 1.79s
2026-06-12 14:17:14.989100 | controller | ../../../../molecule/common/test_deps : Create repos -------------------- 1.74s
2026-06-12 14:17:14.989446 | controller | ../../../../molecule/common/test_deps : Detect mirrors file ------------- 1.49s
2026-06-12 14:17:14.989768 | controller | ../../../../molecule/common/test_deps : Remove ubi 9 repos -------------- 1.39s
2026-06-12 14:17:14.990054 | controller | ../../../../molecule/common/test_deps : Look for redhat-release rpm ----- 1.33s
2026-06-12 14:17:15.066014 | controller | INFO     Running default > converge
2026-06-12 14:17:15.600426 | controller |
2026-06-12 14:17:15.600619 | controller | PLAY [Converge] ****************************************************************
2026-06-12 14:17:15.600854 | controller |
2026-06-12 14:17:15.601152 | controller | TASK [Download required role packages] *****************************************
2026-06-12 14:17:15.601430 | controller | Friday 12 June 2026  14:17:15 +0000 (0:00:00.080)       0:00:00.080 ***********
2026-06-12 14:17:15.657356 | controller |
2026-06-12 14:17:15.657546 | controller | TASK [osp.edpm.edpm_frr : Download needed container images] ********************
2026-06-12 14:17:15.657803 | controller | Friday 12 June 2026  14:17:15 +0000 (0:00:00.057)       0:00:00.137 ***********
2026-06-12 14:17:26.814973 | controller | changed: [instance] => (item=quay.io/podified-antelope-centos9/openstack-frr:current-podified)
2026-06-12 14:17:26.815400 | controller |
2026-06-12 14:17:26.815454 | controller | TASK [ansible.builtin.include_role : osp.edpm.edpm_frr] ************************
2026-06-12 14:17:26.815465 | controller | Friday 12 June 2026  14:17:26 +0000 (0:00:11.157)       0:00:11.294 ***********
2026-06-12 14:17:26.896372 | controller |
2026-06-12 14:17:26.896595 | controller | TASK [osp.edpm.edpm_frr : Validating arguments against arg spec 'main' - The main entry point for the edpm_frr role.] ***
2026-06-12 14:17:26.896630 | controller | Friday 12 June 2026  14:17:26 +0000 (0:00:00.081)       0:00:11.376 ***********
2026-06-12 14:17:26.988875 | controller | ok: [instance]
2026-06-12 14:17:26.988906 | controller |
2026-06-12 14:17:26.988912 | controller | TASK [osp.edpm.edpm_frr : Gather user fact] ************************************
2026-06-12 14:17:26.988927 | controller | Friday 12 June 2026  14:17:26 +0000 (0:00:00.092)       0:00:11.468 ***********
2026-06-12 14:17:28.461432 | controller | ok: [instance]
2026-06-12 14:17:28.461478 | controller |
2026-06-12 14:17:28.461489 | controller | TASK [osp.edpm.edpm_frr : Create persistent directories] ***********************
2026-06-12 14:17:28.461606 | controller | Friday 12 June 2026  14:17:28 +0000 (0:00:01.472)       0:00:12.940 ***********
2026-06-12 14:17:32.077322 | controller | changed: [instance] => (item={'path': '/var/lib/openstack/frr', 'setype': 'container_file_t', 'mode': '0750'})
2026-06-12 14:17:32.077364 | controller | changed: [instance] => (item={'path': '/run/frr', 'setype': 'container_file_t', 'mode': '0755'})
2026-06-12 14:17:32.077373 | controller | changed: [instance] => (item={'path': '/var/lib/edpm-config/firewall', 'setype': 'container_file_t', 'mode': '0750'})
2026-06-12 14:17:32.077380 | controller |
2026-06-12 14:17:32.077387 | controller | TASK [osp.edpm.edpm_frr : Ensure /run/frr is present upon reboot] **************
2026-06-12 14:17:32.077398 | controller | Friday 12 June 2026  14:17:32 +0000 (0:00:03.615)       0:00:16.556 ***********
2026-06-12 14:17:34.648088 | controller | changed: [instance]
2026-06-12 14:17:34.648119 | controller |
2026-06-12 14:17:34.648125 | controller | TASK [osp.edpm.edpm_frr : Gather SELinux fact if needed] ***********************
2026-06-12 14:17:34.648130 | controller | Friday 12 June 2026  14:17:34 +0000 (0:00:02.571)       0:00:19.127 ***********
2026-06-12 14:17:34.717532 | controller | skipping: [instance]
2026-06-12 14:17:34.717572 | controller |
2026-06-12 14:17:34.717582 | controller | TASK [osp.edpm.edpm_frr : Create directory /var/lib/openstack/frr] *************
2026-06-12 14:17:34.717591 | controller | Friday 12 June 2026  14:17:34 +0000 (0:00:00.069)       0:00:19.197 ***********
2026-06-12 14:17:35.613269 | controller | changed: [instance]
2026-06-12 14:17:35.613303 | controller |
2026-06-12 14:17:35.613309 | controller | TASK [osp.edpm.edpm_frr : Inject firewall rules for frr BGP and BFD] ***********
2026-06-12 14:17:35.613315 | controller | Friday 12 June 2026  14:17:35 +0000 (0:00:00.895)       0:00:20.093 ***********
2026-06-12 14:17:36.617708 | controller | changed: [instance]
2026-06-12 14:17:36.617741 | controller |
2026-06-12 14:17:36.617746 | controller | TASK [Configure firewall for frr BGP and BFD] **********************************
2026-06-12 14:17:36.617751 | controller | Friday 12 June 2026  14:17:36 +0000 (0:00:01.004)       0:00:21.097 ***********
2026-06-12 14:17:36.696050 | controller |
2026-06-12 14:17:36.696088 | controller | TASK [osp.edpm.edpm_nftables : Create snippets directory] **********************
2026-06-12 14:17:36.696095 | controller | Friday 12 June 2026  14:17:36 +0000 (0:00:00.078)       0:00:21.176 ***********
2026-06-12 14:17:37.554332 | controller | ok: [instance]
2026-06-12 14:17:37.554383 | controller |
2026-06-12 14:17:37.554398 | controller | TASK [osp.edpm.edpm_nftables : Push default ruleset snipet] ********************
2026-06-12 14:17:37.554409 | controller | Friday 12 June 2026  14:17:37 +0000 (0:00:00.856)       0:00:22.032 ***********
2026-06-12 14:17:40.252079 | controller | changed: [instance]
2026-06-12 14:17:40.252106 | controller |
2026-06-12 14:17:40.252112 | controller | TASK [osp.edpm.edpm_nftables : Write user rules snippet] ***********************
2026-06-12 14:17:40.252117 | controller | Friday 12 June 2026  14:17:40 +0000 (0:00:02.699)       0:00:24.732 ***********
2026-06-12 14:17:42.343312 | controller | changed: [instance]
2026-06-12 14:17:42.343352 | controller |
2026-06-12 14:17:42.343428 | controller | TASK [osp.edpm.edpm_nftables : Push empty ruleset] *****************************
2026-06-12 14:17:42.343442 | controller | Friday 12 June 2026  14:17:42 +0000 (0:00:02.091)       0:00:26.823 ***********
2026-06-12 14:17:44.170221 | controller | changed: [instance]
2026-06-12 14:17:44.170254 | controller |
2026-06-12 14:17:44.170261 | controller | TASK [osp.edpm.edpm_nftables : Load empty ruleset] *****************************
2026-06-12 14:17:44.170267 | controller | Friday 12 June 2026  14:17:44 +0000 (0:00:01.826)       0:00:28.649 ***********
2026-06-12 14:17:45.424909 | controller | changed: [instance]
2026-06-12 14:17:45.424941 | controller |
2026-06-12 14:17:45.424948 | controller | TASK [osp.edpm.edpm_nftables : Delete iptables-compat tables if load failed due to bare chains] ***
2026-06-12 14:17:45.424954 | controller | Friday 12 June 2026  14:17:45 +0000 (0:00:01.254)       0:00:29.904 ***********
2026-06-12 14:17:45.497352 | controller | skipping: [instance] => (item=inet filter)
2026-06-12 14:17:45.497404 | controller | skipping: [instance] => (item=inet raw)
2026-06-12 14:17:45.497414 | controller | skipping: [instance] => (item=inet nat)
2026-06-12 14:17:45.497422 | controller | skipping: [instance] => (item=ip filter)
2026-06-12 14:17:45.497429 | controller | skipping: [instance] => (item=ip raw)
2026-06-12 14:17:45.497436 | controller | skipping: [instance] => (item=ip nat)
2026-06-12 14:17:45.497464 | controller | skipping: [instance] => (item=ip6 filter)
2026-06-12 14:17:45.497473 | controller | skipping: [instance] => (item=ip6 raw)
2026-06-12 14:17:45.497737 | controller | skipping: [instance]
2026-06-12 14:17:45.497764 | controller |
2026-06-12 14:17:45.497770 | controller | TASK [osp.edpm.edpm_nftables : Retry loading empty ruleset after cleanup] ******
2026-06-12 14:17:45.497775 | controller | Friday 12 June 2026  14:17:45 +0000 (0:00:00.072)       0:00:29.976 ***********
2026-06-12 14:17:45.545757 | controller | skipping: [instance]
2026-06-12 14:17:45.545790 | controller |
2026-06-12 14:17:45.545796 | controller | TASK [osp.edpm.edpm_nftables : Get current nftables content] *******************
2026-06-12 14:17:45.545802 | controller | Friday 12 June 2026  14:17:45 +0000 (0:00:00.048)       0:00:30.025 ***********
2026-06-12 14:17:46.460646 | controller | changed: [instance]
2026-06-12 14:17:46.460680 | controller |
2026-06-12 14:17:46.460686 | controller | TASK [osp.edpm.edpm_nftables : Load firewall snippets] *************************
2026-06-12 14:17:46.460691 | controller | Friday 12 June 2026  14:17:46 +0000 (0:00:00.914)       0:00:30.940 ***********
2026-06-12 14:17:47.698603 | controller | ok: [instance]
2026-06-12 14:17:47.698645 | controller |
2026-06-12 14:17:47.698655 | controller | TASK [osp.edpm.edpm_nftables : Generate chain jumps] ***************************
2026-06-12 14:17:47.698675 | controller | Friday 12 June 2026  14:17:47 +0000 (0:00:01.238)       0:00:32.178 ***********
2026-06-12 14:17:49.867607 | controller | changed: [instance]
2026-06-12 14:17:49.867638 | controller |
2026-06-12 14:17:49.867645 | controller | TASK [osp.edpm.edpm_nftables : Generate chain jumps] ***************************
2026-06-12 14:17:49.867651 | controller | Friday 12 June 2026  14:17:49 +0000 (0:00:02.169)       0:00:34.347 ***********
2026-06-12 14:17:52.502406 | controller | changed: [instance]
2026-06-12 14:17:52.502458 | controller |
2026-06-12 14:17:52.503125 | controller | TASK [osp.edpm.edpm_nftables : Generate nft flushes] ***************************
2026-06-12 14:17:52.503232 | controller | Friday 12 June 2026  14:17:52 +0000 (0:00:02.634)       0:00:36.981 ***********
2026-06-12 14:17:54.556737 | controller | changed: [instance]
2026-06-12 14:17:54.556775 | controller |
2026-06-12 14:17:54.556786 | controller | TASK [osp.edpm.edpm_nftables : Generate nft edpm chains] ***********************
2026-06-12 14:17:54.556794 | controller | Friday 12 June 2026  14:17:54 +0000 (0:00:02.054)       0:00:39.036 ***********
2026-06-12 14:17:56.734278 | controller | changed: [instance]
2026-06-12 14:17:56.734318 | controller |
2026-06-12 14:17:56.734323 | controller | TASK [osp.edpm.edpm_nftables : Generate nft ruleset in static file] ************
2026-06-12 14:17:56.734327 | controller | Friday 12 June 2026  14:17:56 +0000 (0:00:02.178)       0:00:41.214 ***********
2026-06-12 14:17:58.995467 | controller | changed: [instance]
2026-06-12 14:17:58.995570 | controller |
2026-06-12 14:17:58.995585 | controller | TASK [osp.edpm.edpm_nftables : Create a sentinel file when nft rules are changed] ***
2026-06-12 14:17:58.995605 | controller | Friday 12 June 2026  14:17:58 +0000 (0:00:02.260)       0:00:43.474 ***********
2026-06-12 14:18:00.322361 | controller | changed: [instance]
2026-06-12 14:18:00.322402 | controller |
2026-06-12 14:18:00.322431 | controller | TASK [osp.edpm.edpm_nftables : Validate all of the generated content before loading] ***
2026-06-12 14:18:00.322730 | controller | Friday 12 June 2026  14:18:00 +0000 (0:00:01.326)       0:00:44.801 ***********
2026-06-12 14:18:01.432890 | controller | changed: [instance]
2026-06-12 14:18:01.432922 | controller |
2026-06-12 14:18:01.432929 | controller | TASK [osp.edpm.edpm_nftables : Ensure we load our different nft rulesets on boot] ***
2026-06-12 14:18:01.432934 | controller | Friday 12 June 2026  14:18:01 +0000 (0:00:01.111)       0:00:45.912 ***********
2026-06-12 14:18:02.584533 | controller | changed: [instance]
2026-06-12 14:18:02.584574 | controller |
2026-06-12 14:18:02.584585 | controller | TASK [Apply firewall for frr BGP and BFD] **************************************
2026-06-12 14:18:02.584594 | controller | Friday 12 June 2026  14:18:02 +0000 (0:00:01.151)       0:00:47.064 ***********
2026-06-12 14:18:02.661190 | controller |
2026-06-12 14:18:02.661231 | controller | TASK [osp.edpm.edpm_nftables : Inject our custom chains in nftables] ***********
2026-06-12 14:18:02.661245 | controller | Friday 12 June 2026  14:18:02 +0000 (0:00:00.076)       0:00:47.141 ***********
2026-06-12 14:18:03.699333 | controller | changed: [instance]
2026-06-12 14:18:03.699365 | controller |
2026-06-12 14:18:03.699380 | controller | TASK [osp.edpm.edpm_nftables : Check if rules are changed] *********************
2026-06-12 14:18:03.699386 | controller | Friday 12 June 2026  14:18:03 +0000 (0:00:01.037)       0:00:48.178 ***********
2026-06-12 14:18:04.944387 | controller | ok: [instance]
2026-06-12 14:18:04.944428 | controller |
2026-06-12 14:18:04.944439 | controller | TASK [osp.edpm.edpm_nftables : Reload ruleset] *********************************
2026-06-12 14:18:04.944596 | controller | Friday 12 June 2026  14:18:04 +0000 (0:00:01.245)       0:00:49.423 ***********
2026-06-12 14:18:06.278393 | controller | changed: [instance]
2026-06-12 14:18:06.278457 | controller |
2026-06-12 14:18:06.279118 | controller | TASK [osp.edpm.edpm_nftables : Delete nft_ruleset_changed file] ****************
2026-06-12 14:18:06.279145 | controller | Friday 12 June 2026  14:18:06 +0000 (0:00:01.333)       0:00:50.757 ***********
2026-06-12 14:18:07.498357 | controller | changed: [instance]
2026-06-12 14:18:07.498395 | controller |
2026-06-12 14:18:07.498400 | controller | TASK [osp.edpm.edpm_frr : Identify the real interfaces via os-net-config] ******
2026-06-12 14:18:07.498545 | controller | Friday 12 June 2026  14:18:07 +0000 (0:00:01.220)       0:00:51.977 ***********
2026-06-12 14:18:08.812369 | controller | changed: [instance]
2026-06-12 14:18:08.812420 | controller |
2026-06-12 14:18:08.812858 | controller | TASK [osp.edpm.edpm_frr : Os-net-config from json] *****************************
2026-06-12 14:18:08.812884 | controller | Friday 12 June 2026  14:18:08 +0000 (0:00:01.314)       0:00:53.292 ***********
2026-06-12 14:18:08.858426 | controller | ok: [instance]
2026-06-12 14:18:08.858482 | controller |
2026-06-12 14:18:08.858500 | controller | TASK [osp.edpm.edpm_frr : Assert either edpm_frr_bgp_peers or edpm_frr_bgp_uplinks configured] ***
2026-06-12 14:18:08.858607 | controller | Friday 12 June 2026  14:18:08 +0000 (0:00:00.045)       0:00:53.337 ***********
2026-06-12 14:18:08.931759 | controller | ok: [instance] => changed=false
2026-06-12 14:18:08.931791 | controller |   msg: All assertions passed
2026-06-12 14:18:08.931798 | controller |
2026-06-12 14:18:08.931803 | controller | TASK [osp.edpm.edpm_frr : Construct FRR uplink interfaces from os-net-config mappings] ***
2026-06-12 14:18:08.931809 | controller | Friday 12 June 2026  14:18:08 +0000 (0:00:00.073)       0:00:53.411 ***********
2026-06-12 14:18:08.998263 | controller | skipping: [instance]
2026-06-12 14:18:08.998304 | controller |
2026-06-12 14:18:08.998315 | controller | TASK [osp.edpm.edpm_frr : Failed to construct FRR uplink interfaces] ***********
2026-06-12 14:18:08.998325 | controller | Friday 12 June 2026  14:18:08 +0000 (0:00:00.066)       0:00:53.477 ***********
2026-06-12 14:18:09.045517 | controller | skipping: [instance]
2026-06-12 14:18:09.045843 | controller |
2026-06-12 14:18:09.046203 | controller | TASK [osp.edpm.edpm_frr : Gather facts if they don't exist] ********************
2026-06-12 14:18:09.046555 | controller | Friday 12 June 2026  14:18:09 +0000 (0:00:00.048)       0:00:53.525 ***********
2026-06-12 14:18:09.086605 | controller | skipping: [instance]
2026-06-12 14:18:09.086894 | controller |
2026-06-12 14:18:09.087271 | controller | TASK [osp.edpm.edpm_frr : Configure FRR] ***************************************
2026-06-12 14:18:09.087553 | controller | Friday 12 June 2026  14:18:09 +0000 (0:00:00.040)       0:00:53.566 ***********
2026-06-12 14:18:11.129643 | controller | changed: [instance]
2026-06-12 14:18:11.129673 | controller |
2026-06-12 14:18:11.129679 | controller | TASK [osp.edpm.edpm_frr : Configure FRR daemons] *******************************
2026-06-12 14:18:11.129685 | controller | Friday 12 June 2026  14:18:11 +0000 (0:00:02.042)       0:00:55.609 ***********
2026-06-12 14:18:13.005698 | controller | changed: [instance]
2026-06-12 14:18:13.005722 | controller |
2026-06-12 14:18:13.005728 | controller | TASK [osp.edpm.edpm_frr : Set role path for template resolution] ***************
2026-06-12 14:18:13.005732 | controller | Friday 12 June 2026  14:18:13 +0000 (0:00:01.875)       0:00:57.485 ***********
2026-06-12 14:18:13.055449 | controller | ok: [instance]
2026-06-12 14:18:13.055502 | controller |
2026-06-12 14:18:13.055624 | controller | TASK [Run frr container] *******************************************************
2026-06-12 14:18:13.055653 | controller | Friday 12 June 2026  14:18:13 +0000 (0:00:00.050)       0:00:57.535 ***********
2026-06-12 14:18:13.151924 | controller |
2026-06-12 14:18:13.151956 | controller | TASK [osp.edpm.edpm_container_standalone : Validating arguments against arg spec 'main' - The main entry point for the edpm_container_standalone role.] ***
2026-06-12 14:18:13.151974 | controller | Friday 12 June 2026  14:18:13 +0000 (0:00:00.096)       0:00:57.631 ***********
2026-06-12 14:18:13.233428 | controller | ok: [instance]
2026-06-12 14:18:13.233482 | controller |
2026-06-12 14:18:13.233493 | controller | TASK [osp.edpm.edpm_container_standalone : Ensure /var/lib/edpm-config exists with correct ownership] ***
2026-06-12 14:18:13.233502 | controller | Friday 12 June 2026  14:18:13 +0000 (0:00:00.081)       0:00:57.712 ***********
2026-06-12 14:18:14.233011 | controller | changed: [instance]
2026-06-12 14:18:14.233044 | controller |
2026-06-12 14:18:14.233051 | controller | TASK [osp.edpm.edpm_container_standalone : Ensure directory exists: /var/lib/kolla/config_files] ***
2026-06-12 14:18:14.233058 | controller | Friday 12 June 2026  14:18:14 +0000 (0:00:00.999)       0:00:58.712 ***********
2026-06-12 14:18:15.360354 | controller | changed: [instance]
2026-06-12 14:18:15.360392 | controller |
2026-06-12 14:18:15.360518 | controller | TASK [osp.edpm.edpm_container_standalone : Create kolla config files] **********
2026-06-12 14:18:15.360550 | controller | Friday 12 June 2026  14:18:15 +0000 (0:00:01.127)       0:00:59.839 ***********
2026-06-12 14:18:17.353287 | controller | changed: [instance] => (item={'key': 'frr', 'value': {'command': 'bash -c $* -- eval if [ -f /usr/libexec/frr/frrinit.sh ]; then /usr/libexec/frr/frrinit.sh start; else /usr/lib/frr/frr start; fi  && exec /bin/sleep infinity', 'config_files': [{'source': '/var/lib/kolla/config_files/src/etc/frr/*', 'dest': '/etc/frr/', 'merge': True, 'preserve_properties': True}], 'permissions': [{'path': '/run/frr', 'owner': 'frr:frrvty', 'recurse': True}]}})
2026-06-12 14:18:17.353324 | controller |
2026-06-12 14:18:17.353331 | controller | TASK [osp.edpm.edpm_container_standalone : Deploy containers via Quadlet: [ frr ]] ***
2026-06-12 14:18:17.353336 | controller | Friday 12 June 2026  14:18:17 +0000 (0:00:01.992)       0:01:01.832 ***********
2026-06-12 14:18:17.432425 | controller | included: /home/zuul/.cache/ansible-compat/8075a4/collections/ansible_collections/osp/edpm/roles/edpm_container_standalone/tasks/quadlet.yml for instance
2026-06-12 14:18:17.432480 | controller |
2026-06-12 14:18:17.432491 | controller | TASK [osp.edpm.edpm_container_standalone : Ensure quadlet directory exists] ****
2026-06-12 14:18:17.432727 | controller | Friday 12 June 2026  14:18:17 +0000 (0:00:00.079)       0:01:01.911 ***********
2026-06-12 14:18:19.236039 | controller | ok: [instance]
2026-06-12 14:18:19.236078 | controller |
2026-06-12 14:18:19.236089 | controller | TASK [osp.edpm.edpm_container_standalone : Ensure quadlet staging directory exists] ***
2026-06-12 14:18:19.236098 | controller | Friday 12 June 2026  14:18:19 +0000 (0:00:01.803)       0:01:03.715 ***********
2026-06-12 14:18:20.309010 | controller | changed: [instance]
2026-06-12 14:18:20.309052 | controller |
2026-06-12 14:18:20.309063 | controller | TASK [osp.edpm.edpm_container_standalone : Render Quadlet templates for frr] ***
2026-06-12 14:18:20.309071 | controller | Friday 12 June 2026  14:18:20 +0000 (0:00:01.073)       0:01:04.788 ***********
2026-06-12 14:18:22.504247 | controller | changed: [instance] => (item=frr)
2026-06-12 14:18:22.504286 | controller |
2026-06-12 14:18:22.504296 | controller | TASK [osp.edpm.edpm_container_standalone : Compute and inject config hashes for frr] ***
2026-06-12 14:18:22.504305 | controller | Friday 12 June 2026  14:18:22 +0000 (0:00:02.195)       0:01:06.983 ***********
2026-06-12 14:18:23.853630 | controller | ok: [instance]
2026-06-12 14:18:23.853662 | controller |
2026-06-12 14:18:23.853668 | controller | TASK [osp.edpm.edpm_container_standalone : Deploy Quadlet .container files for frr] ***
2026-06-12 14:18:23.853673 | controller | Friday 12 June 2026  14:18:23 +0000 (0:00:01.349)       0:01:08.333 ***********
2026-06-12 14:18:24.925362 | controller | changed: [instance] => (item=frr)
2026-06-12 14:18:24.925395 | controller |
2026-06-12 14:18:24.925402 | controller | TASK [osp.edpm.edpm_container_standalone : Identify changed containers] ********
2026-06-12 14:18:24.925467 | controller | Friday 12 June 2026  14:18:24 +0000 (0:00:01.071)       0:01:09.404 ***********
2026-06-12 14:18:25.010327 | controller | ok: [instance]
2026-06-12 14:18:25.010359 | controller |
2026-06-12 14:18:25.010522 | controller | TASK [osp.edpm.edpm_container_standalone : Reload systemd daemon for quadlet generator] ***
2026-06-12 14:18:25.010535 | controller | Friday 12 June 2026  14:18:25 +0000 (0:00:00.085)       0:01:09.490 ***********
2026-06-12 14:18:26.994464 | controller | ok: [instance]
2026-06-12 14:18:26.994536 | controller |
2026-06-12 14:18:26.994753 | controller | TASK [osp.edpm.edpm_container_standalone : Restart changed Quadlet containers for frr] ***
2026-06-12 14:18:26.994793 | controller | Friday 12 June 2026  14:18:26 +0000 (0:00:01.983)       0:01:11.473 ***********
2026-06-12 14:18:28.769239 | controller | changed: [instance] => (item=frr)
2026-06-12 14:18:28.769279 | controller |
2026-06-12 14:18:28.769289 | controller | TASK [osp.edpm.edpm_container_standalone : Create config dir /var/lib/edpm-config/container-startup-config/frr] ***
2026-06-12 14:18:28.769297 | controller | Friday 12 June 2026  14:18:28 +0000 (0:00:01.775)       0:01:13.248 ***********
2026-06-12 14:18:28.817110 | controller | skipping: [instance]
2026-06-12 14:18:28.817137 | controller |
2026-06-12 14:18:28.817143 | controller | TASK [osp.edpm.edpm_container_standalone : Render container definitions: [frr ]] ***
2026-06-12 14:18:28.817147 | controller | Friday 12 June 2026  14:18:28 +0000 (0:00:00.048)       0:01:13.297 ***********
2026-06-12 14:18:28.860585 | controller | skipping: [instance]
2026-06-12 14:18:28.860615 | controller |
2026-06-12 14:18:28.860621 | controller | TASK [Run containers: [ frr ]] *************************************************
2026-06-12 14:18:28.860626 | controller | Friday 12 June 2026  14:18:28 +0000 (0:00:00.043)       0:01:13.340 ***********
2026-06-12 14:18:28.916222 | controller | skipping: [instance]
2026-06-12 14:18:28.916253 | controller |
2026-06-12 14:18:28.916259 | controller | TASK [osp.edpm.edpm_container_standalone : Update service state file] **********
2026-06-12 14:18:28.916265 | controller | Friday 12 June 2026  14:18:28 +0000 (0:00:00.055)       0:01:13.395 ***********
2026-06-12 14:18:29.021737 | controller | included: /home/zuul/.cache/ansible-compat/8075a4/collections/ansible_collections/osp/edpm/roles/edpm_container_standalone/tasks/state_file_update.yml for instance
2026-06-12 14:18:29.021768 | controller |
2026-06-12 14:18:29.021773 | controller | TASK [osp.edpm.edpm_container_standalone : Read current state file] ************
2026-06-12 14:18:29.021778 | controller | Friday 12 June 2026  14:18:29 +0000 (0:00:00.105)       0:01:13.501 ***********
2026-06-12 14:18:30.244003 | controller | ok: [instance]
2026-06-12 14:18:30.244040 | controller |
2026-06-12 14:18:30.244050 | controller | TASK [osp.edpm.edpm_container_standalone : Parse state file data] **************
2026-06-12 14:18:30.244069 | controller | Friday 12 June 2026  14:18:30 +0000 (0:00:01.221)       0:01:14.723 ***********
2026-06-12 14:18:30.328413 | controller | ok: [instance]
2026-06-12 14:18:30.328457 | controller |
2026-06-12 14:18:30.328463 | controller | TASK [osp.edpm.edpm_container_standalone : Determine service name for state file] ***
2026-06-12 14:18:30.328468 | controller | Friday 12 June 2026  14:18:30 +0000 (0:00:00.084)       0:01:14.807 ***********
2026-06-12 14:18:30.441418 | controller | ok: [instance]
2026-06-12 14:18:30.441470 | controller |
2026-06-12 14:18:30.441853 | controller | TASK [osp.edpm.edpm_container_standalone : Remove container from service containers list] ***
2026-06-12 14:18:30.441876 | controller | Friday 12 June 2026  14:18:30 +0000 (0:00:00.113)       0:01:14.921 ***********
2026-06-12 14:18:30.514286 | controller | skipping: [instance]
2026-06-12 14:18:30.514308 | controller |
2026-06-12 14:18:30.514532 | controller | TASK [osp.edpm.edpm_container_standalone : Update service with remaining containers] ***
2026-06-12 14:18:30.514727 | controller | Friday 12 June 2026  14:18:30 +0000 (0:00:00.073)       0:01:14.994 ***********
2026-06-12 14:18:30.581910 | controller | skipping: [instance]
2026-06-12 14:18:30.582080 | controller |
2026-06-12 14:18:30.582326 | controller | TASK [osp.edpm.edpm_container_standalone : Build filtered services dict (exclude empty service)] ***
2026-06-12 14:18:30.582531 | controller | Friday 12 June 2026  14:18:30 +0000 (0:00:00.067)       0:01:15.062 ***********
2026-06-12 14:18:30.643192 | controller | skipping: [instance]
2026-06-12 14:18:30.643358 | controller |
2026-06-12 14:18:30.643568 | controller | TASK [osp.edpm.edpm_container_standalone : Update state data without empty service] ***
2026-06-12 14:18:30.643812 | controller | Friday 12 June 2026  14:18:30 +0000 (0:00:00.061)       0:01:15.123 ***********
2026-06-12 14:18:30.715584 | controller | skipping: [instance]
2026-06-12 14:18:30.715844 | controller |
2026-06-12 14:18:30.716100 | controller | TASK [osp.edpm.edpm_container_standalone : Determine container names from definitions] ***
2026-06-12 14:18:30.716387 | controller | Friday 12 June 2026  14:18:30 +0000 (0:00:00.072)       0:01:15.195 ***********
2026-06-12 14:18:30.777301 | controller | ok: [instance]
2026-06-12 14:18:30.777469 | controller |
2026-06-12 14:18:30.777663 | controller | TASK [osp.edpm.edpm_container_standalone : Update service in state data] *******
2026-06-12 14:18:30.777867 | controller | Friday 12 June 2026  14:18:30 +0000 (0:00:00.061)       0:01:15.257 ***********
2026-06-12 14:18:30.860943 | controller | ok: [instance]
2026-06-12 14:18:30.861102 | controller |
2026-06-12 14:18:30.861334 | controller | TASK [osp.edpm.edpm_container_standalone : Write updated state file] ***********
2026-06-12 14:18:30.861531 | controller | Friday 12 June 2026  14:18:30 +0000 (0:00:00.083)       0:01:15.341 ***********
2026-06-12 14:18:33.824019 | controller | changed: [instance]
2026-06-12 14:18:33.824057 | controller |
2026-06-12 14:18:33.824064 | controller | PLAY RECAP *********************************************************************
2026-06-12 14:18:33.824070 | controller | instance                   : ok=52   changed=32   unreachable=0    failed=0    skipped=13   rescued=0    ignored=0
2026-06-12 14:18:33.824075 | controller |
2026-06-12 14:18:33.824080 | controller | Friday 12 June 2026  14:18:33 +0000 (0:00:02.960)       0:01:18.301 ***********
2026-06-12 14:18:33.824085 | controller | ===============================================================================
2026-06-12 14:18:33.824090 | controller | osp.edpm.edpm_frr : Download needed container images ------------------- 11.16s
2026-06-12 14:18:33.824095 | controller | osp.edpm.edpm_frr : Create persistent directories ----------------------- 3.62s
2026-06-12 14:18:33.824100 | controller | osp.edpm.edpm_container_standalone : Write updated state file ----------- 2.96s
2026-06-12 14:18:33.824104 | controller | osp.edpm.edpm_nftables : Push default ruleset snipet -------------------- 2.70s
2026-06-12 14:18:33.824109 | controller | osp.edpm.edpm_nftables : Generate chain jumps --------------------------- 2.63s
2026-06-12 14:18:33.824114 | controller | osp.edpm.edpm_frr : Ensure /run/frr is present upon reboot -------------- 2.57s
2026-06-12 14:18:33.824119 | controller | osp.edpm.edpm_nftables : Generate nft ruleset in static file ------------ 2.26s
2026-06-12 14:18:33.824124 | controller | osp.edpm.edpm_container_standalone : Render Quadlet templates for frr --- 2.20s
2026-06-12 14:18:33.824129 | controller | osp.edpm.edpm_nftables : Generate nft edpm chains ----------------------- 2.18s
2026-06-12 14:18:33.824134 | controller | osp.edpm.edpm_nftables : Generate chain jumps --------------------------- 2.17s
2026-06-12 14:18:33.824138 | controller | osp.edpm.edpm_nftables : Write user rules snippet ----------------------- 2.09s
2026-06-12 14:18:33.824143 | controller | osp.edpm.edpm_nftables : Generate nft flushes --------------------------- 2.05s
2026-06-12 14:18:33.824148 | controller | osp.edpm.edpm_frr : Configure FRR --------------------------------------- 2.04s
2026-06-12 14:18:33.824153 | controller | osp.edpm.edpm_container_standalone : Create kolla config files ---------- 1.99s
2026-06-12 14:18:33.824185 | controller | osp.edpm.edpm_container_standalone : Reload systemd daemon for quadlet generator --- 1.98s
2026-06-12 14:18:33.824196 | controller | osp.edpm.edpm_frr : Configure FRR daemons ------------------------------- 1.88s
2026-06-12 14:18:33.824201 | controller | osp.edpm.edpm_nftables : Push empty ruleset ----------------------------- 1.83s
2026-06-12 14:18:33.824206 | controller | osp.edpm.edpm_container_standalone : Ensure quadlet directory exists ---- 1.80s
2026-06-12 14:18:33.824211 | controller | osp.edpm.edpm_container_standalone : Restart changed Quadlet containers for frr --- 1.78s
2026-06-12 14:18:33.824216 | controller | osp.edpm.edpm_frr : Gather user fact ------------------------------------ 1.47s
2026-06-12 14:18:33.917635 | controller | INFO     Running default > verify
2026-06-12 14:18:33.917957 | controller | INFO     Running Ansible Verifier
2026-06-12 14:18:34.468998 | controller |
2026-06-12 14:18:34.469206 | controller | PLAY [Verify frr] **************************************************************
2026-06-12 14:18:34.469341 | controller |
2026-06-12 14:18:34.469476 | controller | TASK [Ensure expected directories exist] ***************************************
2026-06-12 14:18:34.469623 | controller | Friday 12 June 2026  14:18:34 +0000 (0:00:00.068)       0:00:00.068 ***********
2026-06-12 14:18:34.538263 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/edpm-ansible/molecule/test-helpers/verify_dir.yaml for instance => (item=/var/lib/openstack/frr)
2026-06-12 14:18:34.538714 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/edpm-ansible/molecule/test-helpers/verify_dir.yaml for instance => (item=/etc/tmpfiles.d/)
2026-06-12 14:18:34.539104 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/edpm-ansible/molecule/test-helpers/verify_dir.yaml for instance => (item=/var/lib/openstack/frr/etc/frr)
2026-06-12 14:18:34.539483 | controller |
2026-06-12 14:18:34.539785 | controller | TASK [Check if directory exists /var/lib/openstack/frr] ************************
2026-06-12 14:18:34.540079 | controller | Friday 12 June 2026  14:18:34 +0000 (0:00:00.068)       0:00:00.137 ***********
2026-06-12 14:18:35.612949 | controller | ok: [instance]
2026-06-12 14:18:35.612978 | controller |
2026-06-12 14:18:35.612985 | controller | TASK [Assert directory exists /var/lib/openstack/frr] **************************
2026-06-12 14:18:35.612991 | controller | Friday 12 June 2026  14:18:35 +0000 (0:00:01.074)       0:00:01.212 ***********
2026-06-12 14:18:35.669450 | controller | ok: [instance] => changed=false
2026-06-12 14:18:35.669497 | controller |   msg: All assertions passed
2026-06-12 14:18:35.669507 | controller |
2026-06-12 14:18:35.669516 | controller | TASK [Check if directory exists /etc/tmpfiles.d/] ******************************
2026-06-12 14:18:35.669524 | controller | Friday 12 June 2026  14:18:35 +0000 (0:00:00.056)       0:00:01.268 ***********
2026-06-12 14:18:36.746403 | controller | ok: [instance]
2026-06-12 14:18:36.746446 | controller |
2026-06-12 14:18:36.746456 | controller | TASK [Assert directory exists /etc/tmpfiles.d/] ********************************
2026-06-12 14:18:36.746475 | controller | Friday 12 June 2026  14:18:36 +0000 (0:00:01.076)       0:00:02.345 ***********
2026-06-12 14:18:36.813356 | controller | ok: [instance] => changed=false
2026-06-12 14:18:36.813395 | controller |   msg: All assertions passed
2026-06-12 14:18:36.813404 | controller |
2026-06-12 14:18:36.813411 | controller | TASK [Check if directory exists /var/lib/openstack/frr/etc/frr] ****************
2026-06-12 14:18:36.813582 | controller | Friday 12 June 2026  14:18:36 +0000 (0:00:00.067)       0:00:02.412 ***********
2026-06-12 14:18:37.847350 | controller | ok: [instance]
2026-06-12 14:18:37.847392 | controller |
2026-06-12 14:18:37.847403 | controller | TASK [Assert directory exists /var/lib/openstack/frr/etc/frr] ******************
2026-06-12 14:18:37.847716 | controller | Friday 12 June 2026  14:18:37 +0000 (0:00:01.032)       0:00:03.445 ***********
2026-06-12 14:18:37.891349 | controller | ok: [instance] => changed=false
2026-06-12 14:18:37.891393 | controller |   msg: All assertions passed
2026-06-12 14:18:37.891404 | controller |
2026-06-12 14:18:37.891416 | controller | TASK [Ensure Quadlet .container file exists] ***********************************
2026-06-12 14:18:37.891428 | controller | Friday 12 June 2026  14:18:37 +0000 (0:00:00.044)       0:00:03.490 ***********
2026-06-12 14:18:38.927000 | controller | ok: [instance]
2026-06-12 14:18:38.927062 | controller |
2026-06-12 14:18:38.927286 | controller | TASK [Ensure staged Quadlet file exists] ***************************************
2026-06-12 14:18:38.927406 | controller | Friday 12 June 2026  14:18:38 +0000 (0:00:01.036)       0:00:04.527 ***********
2026-06-12 14:18:40.068352 | controller | ok: [instance]
2026-06-12 14:18:40.068406 | controller |
2026-06-12 14:18:40.068420 | controller | TASK [Read Quadlet file content] ***********************************************
2026-06-12 14:18:40.068432 | controller | Friday 12 June 2026  14:18:40 +0000 (0:00:01.139)       0:00:05.666 ***********
2026-06-12 14:18:41.358774 | controller | ok: [instance]
2026-06-12 14:18:41.358811 | controller |
2026-06-12 14:18:41.358822 | controller | TASK [Verify config_hash is populated in Quadlet file] *************************
2026-06-12 14:18:41.358831 | controller | Friday 12 June 2026  14:18:41 +0000 (0:00:01.290)       0:00:06.957 ***********
2026-06-12 14:18:41.453403 | controller | ok: [instance] => changed=false
2026-06-12 14:18:41.453452 | controller |   msg: All assertions passed
2026-06-12 14:18:41.453463 | controller |
2026-06-12 14:18:41.453472 | controller | TASK [Check if /etc/tmpfiles.d/run-frr.conf exists] ****************************
2026-06-12 14:18:41.453481 | controller | Friday 12 June 2026  14:18:41 +0000 (0:00:00.094)       0:00:07.052 ***********
2026-06-12 14:18:42.476551 | controller | ok: [instance]
2026-06-12 14:18:42.476584 | controller |
2026-06-12 14:18:42.476592 | controller | TASK [Assert /etc/tmpfiles.d/run-frr.conf exists] ******************************
2026-06-12 14:18:42.476599 | controller | Friday 12 June 2026  14:18:42 +0000 (0:00:01.023)       0:00:08.075 ***********
2026-06-12 14:18:42.540354 | controller | ok: [instance] => changed=false
2026-06-12 14:18:42.540385 | controller |   msg: All assertions passed
2026-06-12 14:18:42.540392 | controller |
2026-06-12 14:18:42.540423 | controller | TASK [Check if frr.conf exists] ************************************************
2026-06-12 14:18:42.540429 | controller | Friday 12 June 2026  14:18:42 +0000 (0:00:00.063)       0:00:08.139 ***********
2026-06-12 14:18:43.449330 | controller | ok: [instance]
2026-06-12 14:18:43.449375 | controller |
2026-06-12 14:18:43.449487 | controller | TASK [Assert frr.conf exists] **************************************************
2026-06-12 14:18:43.449515 | controller | Friday 12 June 2026  14:18:43 +0000 (0:00:00.909)       0:00:09.048 ***********
2026-06-12 14:18:43.513405 | controller | ok: [instance] => changed=false
2026-06-12 14:18:43.513454 | controller |   msg: All assertions passed
2026-06-12 14:18:43.513465 | controller |
2026-06-12 14:18:43.513474 | controller | TASK [Check if daemons file exists] ********************************************
2026-06-12 14:18:43.513483 | controller | Friday 12 June 2026  14:18:43 +0000 (0:00:00.063)       0:00:09.112 ***********
2026-06-12 14:18:44.488327 | controller | ok: [instance]
2026-06-12 14:18:44.488367 | controller |
2026-06-12 14:18:44.488372 | controller | TASK [Assert daemons file exists] **********************************************
2026-06-12 14:18:44.488381 | controller | Friday 12 June 2026  14:18:44 +0000 (0:00:00.975)       0:00:10.088 ***********
2026-06-12 14:18:44.527779 | controller | ok: [instance] => changed=false
2026-06-12 14:18:44.527842 | controller |   msg: All assertions passed
2026-06-12 14:18:44.527848 | controller |
2026-06-12 14:18:44.527852 | controller | TASK [Rule source file exists] *************************************************
2026-06-12 14:18:44.527857 | controller | Friday 12 June 2026  14:18:44 +0000 (0:00:00.039)       0:00:10.127 ***********
2026-06-12 14:18:45.383039 | controller | ok: [instance]
2026-06-12 14:18:45.383073 | controller |
2026-06-12 14:18:45.383079 | controller | TASK [Assert FRR BGP and BFD rule source file exists] **************************
2026-06-12 14:18:45.383084 | controller | Friday 12 June 2026  14:18:45 +0000 (0:00:00.855)       0:00:10.982 ***********
2026-06-12 14:18:45.453801 | controller | ok: [instance] => changed=false
2026-06-12 14:18:45.453831 | controller |   msg: All assertions passed
2026-06-12 14:18:45.453836 | controller |
2026-06-12 14:18:45.453841 | controller | TASK [Check if nftables rule exists in /etc/nftables/edpm-rules.nft] ***********
2026-06-12 14:18:45.453846 | controller | Friday 12 June 2026  14:18:45 +0000 (0:00:00.070)       0:00:11.053 ***********
2026-06-12 14:18:47.457659 | controller | changed: [instance] => (item=121 frr bgp port)
2026-06-12 14:18:47.457696 | controller | changed: [instance] => (item=122 frr bfd ports)
2026-06-12 14:18:47.457701 | controller |
2026-06-12 14:18:47.457706 | controller | TASK [Assert nftables rules exist] *********************************************
2026-06-12 14:18:47.457711 | controller | Friday 12 June 2026  14:18:47 +0000 (0:00:02.003)       0:00:13.057 ***********
2026-06-12 14:18:47.524325 | controller | ok: [instance] => (item=121 frr bgp port) => changed=false
2026-06-12 14:18:47.524362 | controller |   ansible_loop_var: item
2026-06-12 14:18:47.524368 | controller |   item:
2026-06-12 14:18:47.524373 | controller |     ansible_loop_var: item
2026-06-12 14:18:47.524377 | controller |     changed: true
2026-06-12 14:18:47.524382 | controller |     cmd: grep -q "121 frr bgp port" /etc/nftables/edpm-rules.nft
2026-06-12 14:18:47.524387 | controller |     delta: '0:00:00.004401'
2026-06-12 14:18:47.524392 | controller |     end: '2026-06-12 14:18:46.389590'
2026-06-12 14:18:47.524397 | controller |     failed: false
2026-06-12 14:18:47.524402 | controller |     invocation:
2026-06-12 14:18:47.525243 | controller |       module_args:
2026-06-12 14:18:47.525272 | controller |         _raw_params: grep -q "121 frr bgp port" /etc/nftables/edpm-rules.nft
2026-06-12 14:18:47.525278 | controller |         _uses_shell: true
2026-06-12 14:18:47.525282 | controller |         argv: null
2026-06-12 14:18:47.525285 | controller |         chdir: null
2026-06-12 14:18:47.525289 | controller |         creates: null
2026-06-12 14:18:47.525292 | controller |         executable: null
2026-06-12 14:18:47.525296 | controller |         removes: null
2026-06-12 14:18:47.529757 | controller |         stdin: null
2026-06-12 14:18:47.529796 | controller |         stdin_add_newline: true
2026-06-12 14:18:47.529803 | controller |         strip_empty_ends: true
2026-06-12 14:18:47.529809 | controller |     item: 121 frr bgp port
2026-06-12 14:18:47.529814 | controller |     msg: ''
2026-06-12 14:18:47.529819 | controller |     rc: 0
2026-06-12 14:18:47.529827 | controller |     start: '2026-06-12 14:18:46.385189'
2026-06-12 14:18:47.529832 | controller |     stderr: ''
2026-06-12 14:18:47.529837 | controller |     stderr_lines: []
2026-06-12 14:18:47.529841 | controller |     stdout: ''
2026-06-12 14:18:47.529845 | controller |     stdout_lines: []
2026-06-12 14:18:47.529849 | controller |   msg: All assertions passed
2026-06-12 14:18:47.529853 | controller | ok: [instance] => (item=122 frr bfd ports) => changed=false
2026-06-12 14:18:47.529857 | controller |   ansible_loop_var: item
2026-06-12 14:18:47.529862 | controller |   item:
2026-06-12 14:18:47.529867 | controller |     ansible_loop_var: item
2026-06-12 14:18:47.529871 | controller |     changed: true
2026-06-12 14:18:47.529876 | controller |     cmd: grep -q "122 frr bfd ports" /etc/nftables/edpm-rules.nft
2026-06-12 14:18:47.529881 | controller |     delta: '0:00:00.003735'
2026-06-12 14:18:47.529885 | controller |     end: '2026-06-12 14:18:47.274207'
2026-06-12 14:18:47.529890 | controller |     failed: false
2026-06-12 14:18:47.529894 | controller |     invocation:
2026-06-12 14:18:47.529898 | controller |       module_args:
2026-06-12 14:18:47.529902 | controller |         _raw_params: grep -q "122 frr bfd ports" /etc/nftables/edpm-rules.nft
2026-06-12 14:18:47.529906 | controller |         _uses_shell: true
2026-06-12 14:18:47.529910 | controller |         argv: null
2026-06-12 14:18:47.529914 | controller |         chdir: null
2026-06-12 14:18:47.529918 | controller |         creates: null
2026-06-12 14:18:47.529922 | controller |         executable: null
2026-06-12 14:18:47.529926 | controller |         removes: null
2026-06-12 14:18:47.529930 | controller |         stdin: null
2026-06-12 14:18:47.529934 | controller |         stdin_add_newline: true
2026-06-12 14:18:47.529938 | controller |         strip_empty_ends: true
2026-06-12 14:18:47.529942 | controller |     item: 122 frr bfd ports
2026-06-12 14:18:47.529946 | controller |     msg: ''
2026-06-12 14:18:47.529950 | controller |     rc: 0
2026-06-12 14:18:47.529954 | controller |     start: '2026-06-12 14:18:47.270472'
2026-06-12 14:18:47.529958 | controller |     stderr: ''
2026-06-12 14:18:47.529962 | controller |     stderr_lines: []
2026-06-12 14:18:47.529966 | controller |     stdout: ''
2026-06-12 14:18:47.529970 | controller |     stdout_lines: []
2026-06-12 14:18:47.529974 | controller |   msg: All assertions passed
2026-06-12 14:18:47.529978 | controller |
2026-06-12 14:18:47.529982 | controller | TASK [Check rule content] ******************************************************
2026-06-12 14:18:47.529986 | controller | Friday 12 June 2026  14:18:47 +0000 (0:00:00.066)       0:00:13.123 ***********
2026-06-12 14:18:49.296350 | controller | changed: [instance] => (item=tcp dport { 179 } ct state new counter accept)
2026-06-12 14:18:49.296395 | controller | changed: [instance] => (item=udp dport { 3784, 3785, 4784, 49152, 49153 } ct state new counter accept)
2026-06-12 14:18:49.296402 | controller |
2026-06-12 14:18:49.296580 | controller | TASK [Assert rule content exists] **********************************************
2026-06-12 14:18:49.296611 | controller | Friday 12 June 2026  14:18:49 +0000 (0:00:01.772)       0:00:14.895 ***********
2026-06-12 14:18:49.357321 | controller | ok: [instance] => (item=tcp dport { 179 } ct state new counter accept) => changed=false
2026-06-12 14:18:49.357359 | controller |   ansible_loop_var: item
2026-06-12 14:18:49.357368 | controller |   item:
2026-06-12 14:18:49.357375 | controller |     ansible_loop_var: item
2026-06-12 14:18:49.357382 | controller |     changed: true
2026-06-12 14:18:49.360866 | controller |     cmd: grep -q "tcp dport { 179 } ct state new counter accept" /etc/nftables/edpm-rules.nft
2026-06-12 14:18:49.360909 | controller |     delta: '0:00:00.004498'
2026-06-12 14:18:49.360920 | controller |     end: '2026-06-12 14:18:48.195531'
2026-06-12 14:18:49.360929 | controller |     failed: false
2026-06-12 14:18:49.360938 | controller |     invocation:
2026-06-12 14:18:49.360949 | controller |       module_args:
2026-06-12 14:18:49.360957 | controller |         _raw_params: grep -q "tcp dport { 179 } ct state new counter accept" /etc/nftables/edpm-rules.nft
2026-06-12 14:18:49.360966 | controller |         _uses_shell: true
2026-06-12 14:18:49.360973 | controller |         argv: null
2026-06-12 14:18:49.360981 | controller |         chdir: null
2026-06-12 14:18:49.360989 | controller |         creates: null
2026-06-12 14:18:49.360996 | controller |         executable: null
2026-06-12 14:18:49.361004 | controller |         removes: null
2026-06-12 14:18:49.361011 | controller |         stdin: null
2026-06-12 14:18:49.361019 | controller |         stdin_add_newline: true
2026-06-12 14:18:49.361027 | controller |         strip_empty_ends: true
2026-06-12 14:18:49.361034 | controller |     item: tcp dport { 179 } ct state new counter accept
2026-06-12 14:18:49.361049 | controller |     msg: ''
2026-06-12 14:18:49.361057 | controller |     rc: 0
2026-06-12 14:18:49.361065 | controller |     start: '2026-06-12 14:18:48.191033'
2026-06-12 14:18:49.361073 | controller |     stderr: ''
2026-06-12 14:18:49.361080 | controller |     stderr_lines: []
2026-06-12 14:18:49.361088 | controller |     stdout: ''
2026-06-12 14:18:49.361095 | controller |     stdout_lines: []
2026-06-12 14:18:49.361103 | controller |   msg: All assertions passed
2026-06-12 14:18:49.361111 | controller | ok: [instance] => (item=udp dport { 3784, 3785, 4784, 49152, 49153 } ct state new counter accept) => changed=false
2026-06-12 14:18:49.361118 | controller |   ansible_loop_var: item
2026-06-12 14:18:49.361126 | controller |   item:
2026-06-12 14:18:49.361134 | controller |     ansible_loop_var: item
2026-06-12 14:18:49.361141 | controller |     changed: true
2026-06-12 14:18:49.361149 | controller |     cmd: grep -q "udp dport { 3784, 3785, 4784, 49152, 49153 } ct state new counter accept" /etc/nftables/edpm-rules.nft
2026-06-12 14:18:49.361186 | controller |     delta: '0:00:00.004747'
2026-06-12 14:18:49.361198 | controller |     end: '2026-06-12 14:18:49.107394'
2026-06-12 14:18:49.361205 | controller |     failed: false
2026-06-12 14:18:49.361213 | controller |     invocation:
2026-06-12 14:18:49.361220 | controller |       module_args:
2026-06-12 14:18:49.361234 | controller |         _raw_params: grep -q "udp dport { 3784, 3785, 4784, 49152, 49153 } ct state new counter accept" /etc/nftables/edpm-rules.nft
2026-06-12 14:18:49.361242 | controller |         _uses_shell: true
2026-06-12 14:18:49.361250 | controller |         argv: null
2026-06-12 14:18:49.361307 | controller |         chdir: null
2026-06-12 14:18:49.361454 | controller |         creates: null
2026-06-12 14:18:49.361571 | controller |         executable: null
2026-06-12 14:18:49.361958 | controller |         removes: null
2026-06-12 14:18:49.361984 | controller |         stdin: null
2026-06-12 14:18:49.361992 | controller |         stdin_add_newline: true
2026-06-12 14:18:49.362064 | controller |         strip_empty_ends: true
2026-06-12 14:18:49.362210 | controller |     item: udp dport { 3784, 3785, 4784, 49152, 49153 } ct state new counter accept
2026-06-12 14:18:49.362350 | controller |     msg: ''
2026-06-12 14:18:49.362468 | controller |     rc: 0
2026-06-12 14:18:49.362590 | controller |     start: '2026-06-12 14:18:49.102647'
2026-06-12 14:18:49.362704 | controller |     stderr: ''
2026-06-12 14:18:49.362834 | controller |     stderr_lines: []
2026-06-12 14:18:49.362953 | controller |     stdout: ''
2026-06-12 14:18:49.363067 | controller |     stdout_lines: []
2026-06-12 14:18:49.363204 | controller |   msg: All assertions passed
2026-06-12 14:18:49.363332 | controller |
2026-06-12 14:18:49.363458 | controller | TASK [Check input chain rules are loaded] **************************************
2026-06-12 14:18:49.363575 | controller | Friday 12 June 2026  14:18:49 +0000 (0:00:00.060)       0:00:14.956 ***********
2026-06-12 14:18:51.599232 | controller | changed: [instance] => (item=tcp dport 179 ct state new counter)
2026-06-12 14:18:51.599264 | controller | changed: [instance] => (item=udp dport { 3784, 3785, 4784, 49152, 49153 } ct state new counter)
2026-06-12 14:18:51.599269 | controller |
2026-06-12 14:18:51.599274 | controller | TASK [Assert input chain rules are loaded] *************************************
2026-06-12 14:18:51.599279 | controller | Friday 12 June 2026  14:18:51 +0000 (0:00:02.241)       0:00:17.198 ***********
2026-06-12 14:18:51.703256 | controller | ok: [instance] => (item=tcp dport 179 ct state new counter) => changed=false
2026-06-12 14:18:51.703287 | controller |   ansible_loop_var: item
2026-06-12 14:18:51.703294 | controller |   item:
2026-06-12 14:18:51.703300 | controller |     ansible_loop_var: item
2026-06-12 14:18:51.703305 | controller |     changed: true
2026-06-12 14:18:51.703310 | controller |     cmd: nft list table inet filter | awk '/chain EDPM_INPUT {/,/}/' | grep -q "tcp dport 179 ct state new counter.*accept"
2026-06-12 14:18:51.703316 | controller |     delta: '0:00:00.009480'
2026-06-12 14:18:51.703321 | controller |     end: '2026-06-12 14:18:50.143495'
2026-06-12 14:18:51.703326 | controller |     failed: false
2026-06-12 14:18:51.703331 | controller |     invocation:
2026-06-12 14:18:51.703335 | controller |       module_args:
2026-06-12 14:18:51.703341 | controller |         _raw_params: nft list table inet filter | awk '/chain EDPM_INPUT {/,/}/' | grep -q "tcp dport 179 ct state new counter.*accept"
2026-06-12 14:18:51.703410 | controller |         _uses_shell: true
2026-06-12 14:18:51.703691 | controller |         argv: null
2026-06-12 14:18:51.703979 | controller |         chdir: null
2026-06-12 14:18:51.704323 | controller |         creates: null
2026-06-12 14:18:51.704598 | controller |         executable: null
2026-06-12 14:18:51.704860 | controller |         removes: null
2026-06-12 14:18:51.705143 | controller |         stdin: null
2026-06-12 14:18:51.705452 | controller |         stdin_add_newline: true
2026-06-12 14:18:51.705843 | controller |         strip_empty_ends: true
2026-06-12 14:18:51.706261 | controller |     item: tcp dport 179 ct state new counter
2026-06-12 14:18:51.706659 | controller |     msg: ''
2026-06-12 14:18:51.707015 | controller |     rc: 0
2026-06-12 14:18:51.707445 | controller |     start: '2026-06-12 14:18:50.134015'
2026-06-12 14:18:51.707837 | controller |     stderr: ''
2026-06-12 14:18:51.708246 | controller |     stderr_lines: []
2026-06-12 14:18:51.708619 | controller |     stdout: ''
2026-06-12 14:18:51.708999 | controller |     stdout_lines: []
2026-06-12 14:18:51.709435 | controller |   msg: All assertions passed
2026-06-12 14:18:51.709865 | controller | ok: [instance] => (item=udp dport { 3784, 3785, 4784, 49152, 49153 } ct state new counter) => changed=false
2026-06-12 14:18:51.710285 | controller |   ansible_loop_var: item
2026-06-12 14:18:51.710637 | controller |   item:
2026-06-12 14:18:51.710980 | controller |     ansible_loop_var: item
2026-06-12 14:18:51.711378 | controller |     changed: true
2026-06-12 14:18:51.711776 | controller |     cmd: nft list table inet filter | awk '/chain EDPM_INPUT {/,/}/' | grep -q "udp dport { 3784, 3785, 4784, 49152, 49153 } ct state new counter.*accept"
2026-06-12 14:18:51.712150 | controller |     delta: '0:00:00.010338'
2026-06-12 14:18:51.712575 | controller |     end: '2026-06-12 14:18:51.281245'
2026-06-12 14:18:51.712970 | controller |     failed: false
2026-06-12 14:18:51.713370 | controller |     invocation:
2026-06-12 14:18:51.713761 | controller |       module_args:
2026-06-12 14:18:51.714056 | controller |         _raw_params: nft list table inet filter | awk '/chain EDPM_INPUT {/,/}/' | grep -q "udp dport { 3784, 3785, 4784, 49152, 49153 } ct state new counter.*accept"
2026-06-12 14:18:51.714375 | controller |         _uses_shell: true
2026-06-12 14:18:51.714639 | controller |         argv: null
2026-06-12 14:18:51.714900 | controller |         chdir: null
2026-06-12 14:18:51.715236 | controller |         creates: null
2026-06-12 14:18:51.715511 | controller |         executable: null
2026-06-12 14:18:51.715766 | controller |         removes: null
2026-06-12 14:18:51.716021 | controller |         stdin: null
2026-06-12 14:18:51.716345 | controller |         stdin_add_newline: true
2026-06-12 14:18:51.716611 | controller |         strip_empty_ends: true
2026-06-12 14:18:51.716872 | controller |     item: udp dport { 3784, 3785, 4784, 49152, 49153 } ct state new counter
2026-06-12 14:18:51.717157 | controller |     msg: ''
2026-06-12 14:18:51.717482 | controller |     rc: 0
2026-06-12 14:18:51.717798 | controller |     start: '2026-06-12 14:18:51.270907'
2026-06-12 14:18:51.718077 | controller |     stderr: ''
2026-06-12 14:18:51.718390 | controller |     stderr_lines: []
2026-06-12 14:18:51.718654 | controller |     stdout: ''
2026-06-12 14:18:51.718915 | controller |     stdout_lines: []
2026-06-12 14:18:51.719248 | controller |   msg: All assertions passed
2026-06-12 14:18:51.719510 | controller |
2026-06-12 14:18:51.719774 | controller | TASK [Ensure podman container exists and is running] ***************************
2026-06-12 14:18:51.720051 | controller | Friday 12 June 2026  14:18:51 +0000 (0:00:00.101)       0:00:17.300 ***********
2026-06-12 14:18:51.777800 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/edpm-ansible/molecule/test-helpers/verify_podman.yaml for instance => (item=frr)
2026-06-12 14:18:51.777967 | controller |
2026-06-12 14:18:51.778115 | controller | TASK [Check if podman container exists frr] ************************************
2026-06-12 14:18:51.778292 | controller | Friday 12 June 2026  14:18:51 +0000 (0:00:00.077)       0:00:17.377 ***********
2026-06-12 14:18:52.879478 | controller | changed: [instance]
2026-06-12 14:18:52.879529 | controller |
2026-06-12 14:18:52.879539 | controller | TASK [Assert podman container exists frr] **************************************
2026-06-12 14:18:52.879555 | controller | Friday 12 June 2026  14:18:52 +0000 (0:00:01.100)       0:00:18.478 ***********
2026-06-12 14:18:52.965152 | controller | ok: [instance] => changed=false
2026-06-12 14:18:52.965224 | controller |   msg: All assertions passed
2026-06-12 14:18:52.965231 | controller |
2026-06-12 14:18:52.965236 | controller | TASK [Check if podman container is running frr] ********************************
2026-06-12 14:18:52.965241 | controller | Friday 12 June 2026  14:18:52 +0000 (0:00:00.085)       0:00:18.564 ***********
2026-06-12 14:18:54.113702 | controller | changed: [instance]
2026-06-12 14:18:54.113731 | controller |
2026-06-12 14:18:54.113736 | controller | TASK [Assert podman container is running frr] **********************************
2026-06-12 14:18:54.113740 | controller | Friday 12 June 2026  14:18:54 +0000 (0:00:01.149)       0:00:19.713 ***********
2026-06-12 14:18:54.183805 | controller | ok: [instance] => changed=false
2026-06-12 14:18:54.183836 | controller |   msg: All assertions passed
2026-06-12 14:18:54.183841 | controller |
2026-06-12 14:18:54.183846 | controller | TASK [Ensure systemd services are defined and functional] **********************
2026-06-12 14:18:54.183850 | controller | Friday 12 June 2026  14:18:54 +0000 (0:00:00.070)       0:00:19.783 ***********
2026-06-12 14:18:54.246367 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/edpm-ansible/molecule/test-helpers/verify_systemd_unit.yaml for instance => (item={'name': 'edpm_frr.service'})
2026-06-12 14:18:54.246410 | controller |
2026-06-12 14:18:54.246451 | controller | TASK [Query systemd for unit fragment path edpm_frr.service] *******************
2026-06-12 14:18:54.246480 | controller | Friday 12 June 2026  14:18:54 +0000 (0:00:00.063)       0:00:19.846 ***********
2026-06-12 14:18:55.382548 | controller | ok: [instance]
2026-06-12 14:18:55.382636 | controller |
2026-06-12 14:18:55.382750 | controller | TASK [Assert systemd unit exists edpm_frr.service] *****************************
2026-06-12 14:18:55.382885 | controller | Friday 12 June 2026  14:18:55 +0000 (0:00:01.136)       0:00:20.982 ***********
2026-06-12 14:18:55.434219 | controller | ok: [instance] => changed=false
2026-06-12 14:18:55.434675 | controller |   msg: All assertions passed
2026-06-12 14:18:55.434702 | controller |
2026-06-12 14:18:55.434708 | controller | TASK [Check if systemd unit is enabled edpm_frr.service] ***********************
2026-06-12 14:18:55.434714 | controller | Friday 12 June 2026  14:18:55 +0000 (0:00:00.051)       0:00:21.034 ***********
2026-06-12 14:18:56.402079 | controller | ok: [instance]
2026-06-12 14:18:56.402116 | controller |
2026-06-12 14:18:56.402138 | controller | TASK [Assert systemd unit is enabled edpm_frr.service] *************************
2026-06-12 14:18:56.402147 | controller | Friday 12 June 2026  14:18:56 +0000 (0:00:00.966)       0:00:22.001 ***********
2026-06-12 14:18:56.473420 | controller | ok: [instance] => changed=false
2026-06-12 14:18:56.473466 | controller |   msg: All assertions passed
2026-06-12 14:18:56.473476 | controller |
2026-06-12 14:18:56.473485 | controller | TASK [Check if systemd unit is running edpm_frr.service] ***********************
2026-06-12 14:18:56.473494 | controller | Friday 12 June 2026  14:18:56 +0000 (0:00:00.071)       0:00:22.072 ***********
2026-06-12 14:18:57.607714 | controller | ok: [instance]
2026-06-12 14:18:57.607794 | controller |
2026-06-12 14:18:57.607807 | controller | TASK [Assert systemd unit is running edpm_frr.service] *************************
2026-06-12 14:18:57.607829 | controller | Friday 12 June 2026  14:18:57 +0000 (0:00:01.134)       0:00:23.206 ***********
2026-06-12 14:18:57.720406 | controller | ok: [instance] => changed=false
2026-06-12 14:18:57.720450 | controller |   msg: All assertions passed
2026-06-12 14:18:57.720456 | controller |
2026-06-12 14:18:57.720460 | controller | PLAY RECAP *********************************************************************
2026-06-12 14:18:57.720466 | controller | instance                   : ok=39   changed=5    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-06-12 14:18:57.722408 | controller |
2026-06-12 14:18:57.722464 | controller | Friday 12 June 2026  14:18:57 +0000 (0:00:00.112)       0:00:23.319 ***********
2026-06-12 14:18:57.722480 | controller | ===============================================================================
2026-06-12 14:18:57.722493 | controller | Check input chain rules are loaded -------------------------------------- 2.24s
2026-06-12 14:18:57.722505 | controller | Check if nftables rule exists in /etc/nftables/edpm-rules.nft ----------- 2.00s
2026-06-12 14:18:57.722517 | controller | Check rule content ------------------------------------------------------ 1.77s
2026-06-12 14:18:57.722529 | controller | Read Quadlet file content ----------------------------------------------- 1.29s
2026-06-12 14:18:57.722541 | controller | Check if podman container is running frr -------------------------------- 1.15s
2026-06-12 14:18:57.722553 | controller | Ensure staged Quadlet file exists --------------------------------------- 1.14s
2026-06-12 14:18:57.722565 | controller | Query systemd for unit fragment path edpm_frr.service ------------------- 1.14s
2026-06-12 14:18:57.722592 | controller | Check if systemd unit is running edpm_frr.service ----------------------- 1.13s
2026-06-12 14:18:57.722825 | controller | Check if podman container exists frr ------------------------------------ 1.10s
2026-06-12 14:18:57.723113 | controller | Check if directory exists /etc/tmpfiles.d/ ------------------------------ 1.08s
2026-06-12 14:18:57.723434 | controller | Check if directory exists /var/lib/openstack/frr ------------------------ 1.07s
2026-06-12 14:18:57.723762 | controller | Ensure Quadlet .container file exists ----------------------------------- 1.04s
2026-06-12 14:18:57.724043 | controller | Check if directory exists /var/lib/openstack/frr/etc/frr ---------------- 1.03s
2026-06-12 14:18:57.724376 | controller | Check if /etc/tmpfiles.d/run-frr.conf exists ---------------------------- 1.02s
2026-06-12 14:18:57.724674 | controller | Check if daemons file exists -------------------------------------------- 0.98s
2026-06-12 14:18:57.724963 | controller | Check if systemd unit is enabled edpm_frr.service ----------------------- 0.97s
2026-06-12 14:18:57.725279 | controller | Check if frr.conf exists ------------------------------------------------ 0.91s
2026-06-12 14:18:57.725570 | controller | Rule source file exists ------------------------------------------------- 0.86s
2026-06-12 14:18:57.725854 | controller | Assert systemd unit is running edpm_frr.service ------------------------- 0.11s
2026-06-12 14:18:57.726142 | controller | Assert input chain rules are loaded ------------------------------------- 0.10s
2026-06-12 14:18:57.792209 | controller | INFO     Verifier completed successfully.
2026-06-12 14:18:57.812425 | controller | INFO     Running default > destroy
2026-06-12 14:18:58.229245 | controller |
2026-06-12 14:18:58.229343 | controller | PLAY [Destroy] *****************************************************************
2026-06-12 14:18:58.229474 | controller |
2026-06-12 14:18:58.229591 | controller | TASK [Get passwd entries for USER env] *****************************************
2026-06-12 14:18:58.229728 | controller | Friday 12 June 2026  14:18:58 +0000 (0:00:00.013)       0:00:00.013 ***********
2026-06-12 14:18:58.589248 | controller | ok: [localhost]
2026-06-12 14:18:58.589295 | controller |
2026-06-12 14:18:58.589300 | controller | TASK [Get shell async_dir] *****************************************************
2026-06-12 14:18:58.589374 | controller | Friday 12 June 2026  14:18:58 +0000 (0:00:00.359)       0:00:00.372 ***********
2026-06-12 14:18:58.662929 | controller | ok: [localhost]
2026-06-12 14:18:58.662961 | controller |
2026-06-12 14:18:58.662968 | controller | TASK [Set async_dir for HOME env] **********************************************
2026-06-12 14:18:58.662974 | controller | Friday 12 June 2026  14:18:58 +0000 (0:00:00.074)       0:00:00.446 ***********
2026-06-12 14:18:58.704366 | controller | skipping: [localhost]
2026-06-12 14:18:58.704407 | controller |
2026-06-12 14:18:58.704418 | controller | TASK [Destroy molecule instance(s)] ********************************************
2026-06-12 14:18:58.704682 | controller | Friday 12 June 2026  14:18:58 +0000 (0:00:00.040)       0:00:00.487 ***********
2026-06-12 14:18:59.186125 | controller | changed: [localhost] => (item={'command': '/sbin/init', 'dockerfile': '../../../../molecule/common/Containerfile.j2', 'image': 'ubi9/ubi-init', 'name': 'instance', 'privileged': True, 'registry': {'url': 'registry.access.redhat.com'}, 'ulimits': ['host']})
2026-06-12 14:18:59.186153 | controller |
2026-06-12 14:18:59.186172 | controller | TASK [Wait for instance(s) deletion to complete] *******************************
2026-06-12 14:18:59.186178 | controller | Friday 12 June 2026  14:18:59 +0000 (0:00:00.482)       0:00:00.969 ***********
2026-06-12 14:19:04.748356 | controller | FAILED - RETRYING: [localhost]: Wait for instance(s) deletion to complete (300 retries left).
2026-06-12 14:19:04.748390 | controller | changed: [localhost] => (item={'failed': 0, 'started': 1, 'finished': 0, 'ansible_job_id': 'j755162333225.54062', 'results_file': '/home/zuul/.ansible_async/j755162333225.54062', 'changed': True, 'item': {'command': '/sbin/init', 'dockerfile': '../../../../molecule/common/Containerfile.j2', 'image': 'ubi9/ubi-init', 'name': 'instance', 'privileged': True, 'registry': {'url': 'registry.access.redhat.com'}, 'ulimits': ['host']}, 'ansible_loop_var': 'item'})
2026-06-12 14:19:04.748981 | controller |
2026-06-12 14:19:04.749057 | controller | TASK [Delete podman network dedicated to this scenario] ************************
2026-06-12 14:19:04.749083 | controller | Friday 12 June 2026  14:19:04 +0000 (0:00:05.561)       0:00:06.531 ***********
2026-06-12 14:19:04.826339 | controller | skipping: [localhost] => (item=instance: None specified)
2026-06-12 14:19:04.826370 | controller | skipping: [localhost]
2026-06-12 14:19:04.826379 | controller |
2026-06-12 14:19:04.826387 | controller | PLAY RECAP *********************************************************************
2026-06-12 14:19:04.826396 | controller | localhost                  : ok=4    changed=2    unreachable=0    failed=0    skipped=2    rescued=0    ignored=0
2026-06-12 14:19:04.827985 | controller |
2026-06-12 14:19:04.828028 | controller | Friday 12 June 2026  14:19:04 +0000 (0:00:00.077)       0:00:06.609 ***********
2026-06-12 14:19:04.828039 | controller | ===============================================================================
2026-06-12 14:19:04.828047 | controller | Wait for instance(s) deletion to complete ------------------------------- 5.56s
2026-06-12 14:19:04.828055 | controller | Destroy molecule instance(s) -------------------------------------------- 0.48s
2026-06-12 14:19:04.828063 | controller | Get passwd entries for USER env ----------------------------------------- 0.36s
2026-06-12 14:19:04.828091 | controller | Delete podman network dedicated to this scenario ------------------------ 0.08s
2026-06-12 14:19:04.828100 | controller | Get shell async_dir ----------------------------------------------------- 0.07s
2026-06-12 14:19:04.828107 | controller | Set async_dir for HOME env ---------------------------------------------- 0.04s
2026-06-12 14:19:04.911956 | controller | INFO     Pruning extra files from scenario ephemeral directory
2026-06-12 14:19:05.130850 | controller | INFO     Writing /tmp/report.html report.
2026-06-12 14:19:05.342627 | controller | ok: Runtime: 0:05:41.495528
2026-06-12 14:19:05.363691 | 
2026-06-12 14:19:05.363787 | PLAY RECAP
2026-06-12 14:19:05.363864 | controller | ok: 4 changed: 2 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-06-12 14:19:05.363906 | 
2026-06-12 14:19:05.484044 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2026-06-12 14:19:05.485865 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2026-06-12 14:19:06.105678 | 
2026-06-12 14:19:06.105797 | PLAY [Ensure ci-framework-data base directories exist on all nodes]
2026-06-12 14:19:06.116772 | 
2026-06-12 14:19:06.116845 | TASK [Create ci-framework-data/logs directory if missing]
2026-06-12 14:19:06.559149 | controller | ok
2026-06-12 14:19:06.603935 | 
2026-06-12 14:19:06.604069 | PLAY [Run ci/playbooks/collect-logs.yml]
2026-06-12 14:19:06.610407 | 
2026-06-12 14:19:06.610521 | TASK [Filter out host if needed]
2026-06-12 14:19:06.620394 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2026-06-12 14:19:06.624645 | 
2026-06-12 14:19:06.624718 | TASK [Ensure file is present]
2026-06-12 14:19:07.044570 | controller | ok
2026-06-12 14:19:07.056886 | 
2026-06-12 14:19:07.057012 | TASK [Manage molecule report file]
2026-06-12 14:19:07.602679 | controller | ok: Runtime: 0:00:00.006746
2026-06-12 14:19:07.611589 | 
2026-06-12 14:19:07.611900 | TASK [Check if we get ci-framework-data basedir]
2026-06-12 14:19:07.858855 | controller | ok
2026-06-12 14:19:07.868859 | 
2026-06-12 14:19:07.868983 | TASK [Create ci-framework-data log directory for zuul]
2026-06-12 14:19:08.100639 | controller | changed
2026-06-12 14:19:08.104770 | 
2026-06-12 14:19:08.104834 | TASK [Copy ci-framework interesting files]
2026-06-12 14:19:08.392923 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2026-06-12 14:19:08.655186 | controller | ok: Runtime: 0:00:00.012249
2026-06-12 14:19:08.663063 | 
2026-06-12 14:19:08.663203 | TASK [Get SELinux listing]
2026-06-12 14:19:09.206537 | controller | ok: Runtime: 0:00:00.012874
2026-06-12 14:19:09.215622 | 
2026-06-12 14:19:09.215756 | TASK [Generate log index]
2026-06-12 14:19:10.120877 | controller | changed
2026-06-12 14:19:10.131260 | 
2026-06-12 14:19:10.131472 | TASK [Get some env related data]
2026-06-12 14:19:10.857228 | controller | /home/zuul/.local/bin/ansible
2026-06-12 14:19:11.683998 | controller | ok: Runtime: 0:00:01.046964
2026-06-12 14:19:11.731550 | 
2026-06-12 14:19:11.731721 | TASK [Generate list of logs to collect in home directory]
2026-06-12 14:19:12.121133 | controller | ok: All paths examined
2026-06-12 14:19:12.130091 | 
2026-06-12 14:19:12.130222 | LOOP [Copy logs from home directory]
2026-06-12 14:19:12.608271 | controller | changed:
2026-06-12 14:19:12.608548 | controller | {
2026-06-12 14:19:12.608643 | controller |   "atime": 1781273574.416169,
2026-06-12 14:19:12.608685 | controller |   "ctime": 1781273600.7423346,
2026-06-12 14:19:12.608721 | controller |   "dev": 64513,
2026-06-12 14:19:12.608755 | controller |   "gid": 1000,
2026-06-12 14:19:12.608789 | controller |   "gr_name": "zuul",
2026-06-12 14:19:12.608823 | controller |   "inode": 306983,
2026-06-12 14:19:12.608859 | controller |   "isblk": false,
2026-06-12 14:19:12.608894 | controller |   "ischr": false,
2026-06-12 14:19:12.608926 | controller |   "isdir": false,
2026-06-12 14:19:12.608957 | controller |   "isfifo": false,
2026-06-12 14:19:12.608989 | controller |   "isgid": false,
2026-06-12 14:19:12.609021 | controller |   "islnk": false,
2026-06-12 14:19:12.609052 | controller |   "isreg": true,
2026-06-12 14:19:12.609084 | controller |   "issock": false,
2026-06-12 14:19:12.609123 | controller |   "isuid": false,
2026-06-12 14:19:12.609157 | controller |   "mode": "0644",
2026-06-12 14:19:12.609256 | controller |   "mtime": 1781273600.7423346,
2026-06-12 14:19:12.609301 | controller |   "nlink": 1,
2026-06-12 14:19:12.609336 | controller |   "path": "/home/zuul/ansible.log",
2026-06-12 14:19:12.609370 | controller |   "pw_name": "zuul",
2026-06-12 14:19:12.609403 | controller |   "rgrp": true,
2026-06-12 14:19:12.609435 | controller |   "roth": true,
2026-06-12 14:19:12.609472 | controller |   "rusr": true,
2026-06-12 14:19:12.609507 | controller |   "size": 6749,
2026-06-12 14:19:12.609540 | controller |   "uid": 1000,
2026-06-12 14:19:12.609606 | controller |   "wgrp": false,
2026-06-12 14:19:12.609703 | controller |   "woth": false,
2026-06-12 14:19:12.609746 | controller |   "wusr": true,
2026-06-12 14:19:12.609779 | controller |   "xgrp": false,
2026-06-12 14:19:12.609811 | controller |   "xoth": false,
2026-06-12 14:19:12.609844 | controller |   "xusr": false
2026-06-12 14:19:12.609876 | controller | }
2026-06-12 14:19:12.624849 | 
2026-06-12 14:19:12.625055 | TASK [Copy crio stats log file]
2026-06-12 14:19:12.663049 | controller | skipping: Conditional result was False
2026-06-12 14:19:12.672715 | 
2026-06-12 14:19:12.672849 | TASK [Get SELinux related data]
2026-06-12 14:19:12.957731 | controller | <no matches>
2026-06-12 14:19:13.220273 | controller | ERROR
2026-06-12 14:19:13.220719 | controller | {
2026-06-12 14:19:13.220789 | controller |   "delta": "0:00:00.011020",
2026-06-12 14:19:13.220831 | controller |   "end": "2026-06-12 14:19:12.958614",
2026-06-12 14:19:13.220867 | controller |   "msg": "non-zero return code",
2026-06-12 14:19:13.220901 | controller |   "rc": 1,
2026-06-12 14:19:13.220934 | controller |   "start": "2026-06-12 14:19:12.947594"
2026-06-12 14:19:13.221038 | controller | }
2026-06-12 14:19:13.221091 | controller | ERROR: Ignoring Errors
2026-06-12 14:19:13.228962 | 
2026-06-12 14:19:13.229092 | TASK [Create system configuration directory]
2026-06-12 14:19:13.480480 | controller | changed
2026-06-12 14:19:13.489540 | 
2026-06-12 14:19:13.489723 | TASK [Get some of the system configurations]
2026-06-12 14:19:14.040483 | controller | ok: Runtime: 0:00:00.022574
2026-06-12 14:19:14.048673 | 
2026-06-12 14:19:14.048804 | TASK [Copy generated documentation if available]
2026-06-12 14:19:14.086976 | controller | skipping: Conditional result was False
2026-06-12 14:19:14.095748 | 
2026-06-12 14:19:14.095879 | TASK [Copy generated AsciiDoc documentation if available]
2026-06-12 14:19:14.132911 | controller | skipping: Conditional result was False
2026-06-12 14:19:14.141620 | 
2026-06-12 14:19:14.141747 | TASK [Compress logs bigger than 2MB]
2026-06-12 14:19:14.688703 | controller | ok: Runtime: 0:00:00.004673
2026-06-12 14:19:14.696680 | 
2026-06-12 14:19:14.696837 | TASK [Copy files from workspace on node]
2026-06-12 14:19:14.731319 | controller | ok
2026-06-12 14:19:14.771436 | 
2026-06-12 14:19:14.771526 | TASK [fetch-output : Set log path for multiple nodes]
2026-06-12 14:19:14.806960 | controller | skipping: Conditional result was False
2026-06-12 14:19:14.813639 | 
2026-06-12 14:19:14.813730 | TASK [fetch-output : Set log path for single node]
2026-06-12 14:19:14.870568 | controller | ok
2026-06-12 14:19:14.878720 | 
2026-06-12 14:19:14.878886 | LOOP [fetch-output : Ensure local output dirs]
2026-06-12 14:19:15.186434 | controller -> localhost | changed: "/var/lib/zuul/builds/51f4563f9d0a44f49d26838494b1b441/work/logs"
2026-06-12 14:19:15.186997 | controller -> localhost | changed: All items complete
2026-06-12 14:19:15.187057 | 
2026-06-12 14:19:15.405370 | controller -> localhost | changed: "/var/lib/zuul/builds/51f4563f9d0a44f49d26838494b1b441/work/artifacts"
2026-06-12 14:19:15.666030 | controller -> localhost | changed: "/var/lib/zuul/builds/51f4563f9d0a44f49d26838494b1b441/work/docs"
2026-06-12 14:19:15.683393 | 
2026-06-12 14:19:15.683645 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-06-12 14:19:16.665669 | controller | changed:
2026-06-12 14:19:16.666175 | controller | .d..t...... ./
2026-06-12 14:19:16.666257 | controller | >f+++++++++ README.html
2026-06-12 14:19:16.666302 | controller | >f+++++++++ ansible-execution.log
2026-06-12 14:19:16.666366 | controller | >f+++++++++ ansible.log
2026-06-12 14:19:16.666411 | controller | >f+++++++++ dmesg.log
2026-06-12 14:19:16.666450 | controller | >f+++++++++ installed-pkgs.log
2026-06-12 14:19:16.666488 | controller | >f+++++++++ python.log
2026-06-12 14:19:16.666525 | controller | >f+++++++++ registries.conf
2026-06-12 14:19:16.666562 | controller | >f+++++++++ report.html
2026-06-12 14:19:16.666639 | controller | >f+++++++++ selinux-denials.log
2026-06-12 14:19:16.666678 | controller | >f+++++++++ selinux-listing.log
2026-06-12 14:19:16.666714 | controller | cd+++++++++ ci-framework-data/
2026-06-12 14:19:16.666750 | controller | cd+++++++++ ci-framework-data/artifacts/
2026-06-12 14:19:16.666785 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2026-06-12 14:19:16.666822 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2026-06-12 14:19:16.666857 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2026-06-12 14:19:16.666894 | controller | cd+++++++++ ci-framework-data/logs/
2026-06-12 14:19:16.666929 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2026-06-12 14:19:16.666965 | controller | cd+++++++++ registries.conf.d/
2026-06-12 14:19:16.667000 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2026-06-12 14:19:16.667036 | controller | cd+++++++++ system-config/
2026-06-12 14:19:16.667137 | controller | cd+++++++++ system-config/libvirt/
2026-06-12 14:19:16.667178 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2026-06-12 14:19:16.667214 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2026-06-12 14:19:17.222381 | controller | changed: .d..t...... ./
2026-06-12 14:19:17.798152 | controller | changed: .d..t...... ./
2026-06-12 14:19:17.840705 | 
2026-06-12 14:19:17.841375 | TASK [Return artifact to Zuul]
2026-06-12 14:19:17.888487 | controller | ok
2026-06-12 14:19:17.892449 | 
2026-06-12 14:19:17.892556 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2026-06-12 14:19:17.892819 | 
2026-06-12 14:19:17.892873 | PLAY RECAP
2026-06-12 14:19:17.892953 | controller | ok: 19 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2026-06-12 14:19:17.893012 | 
2026-06-12 14:19:18.103427 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2026-06-12 14:19:18.105801 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2026-06-12 14:19:18.744676 | 
2026-06-12 14:19:18.744801 | PLAY [all]
2026-06-12 14:19:18.754321 | 
2026-06-12 14:19:18.754397 | TASK [include_role : fetch-output]
2026-06-12 14:19:18.803968 | controller | ok
2026-06-12 14:19:18.824317 | 
2026-06-12 14:19:18.824435 | TASK [fetch-output : Set log path for multiple nodes]
2026-06-12 14:19:18.902932 | controller | skipping: Conditional result was False
2026-06-12 14:19:18.912687 | 
2026-06-12 14:19:18.912830 | TASK [fetch-output : Set log path for single node]
2026-06-12 14:19:18.980471 | controller | ok
2026-06-12 14:19:18.988990 | 
2026-06-12 14:19:18.989133 | LOOP [fetch-output : Ensure local output dirs]
2026-06-12 14:19:19.578098 | controller -> localhost | ok: "/var/lib/zuul/builds/51f4563f9d0a44f49d26838494b1b441/work/logs"
2026-06-12 14:19:19.846949 | controller -> localhost | ok: "/var/lib/zuul/builds/51f4563f9d0a44f49d26838494b1b441/work/artifacts"
2026-06-12 14:19:20.086055 | controller -> localhost | ok: "/var/lib/zuul/builds/51f4563f9d0a44f49d26838494b1b441/work/docs"
2026-06-12 14:19:20.096433 | 
2026-06-12 14:19:20.096596 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-06-12 14:19:21.007405 | controller | ok
2026-06-12 14:19:21.007842 | controller | ok: All items complete
2026-06-12 14:19:21.007914 | 
2026-06-12 14:19:21.583014 | controller | ok
2026-06-12 14:19:22.153031 | controller | ok
2026-06-12 14:19:22.179103 | 
2026-06-12 14:19:22.179532 | TASK [include_role : fetch-output-openshift]
2026-06-12 14:19:22.207656 | controller | skipping: Conditional result was False
2026-06-12 14:19:22.217884 | 
2026-06-12 14:19:22.218022 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-06-12 14:19:22.706040 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.011625
2026-06-12 14:19:22.974779 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.014417
2026-06-12 14:19:22.994822 | 
2026-06-12 14:19:22.994982 | PLAY [all]
2026-06-12 14:19:22.999982 | 
2026-06-12 14:19:23.000050 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-06-12 14:19:23.528837 | controller | changed
2026-06-12 14:19:23.538015 | 
2026-06-12 14:19:23.538117 | PLAY RECAP
2026-06-12 14:19:23.538201 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2026-06-12 14:19:23.538248 | 
2026-06-12 14:19:23.696283 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2026-06-12 14:19:23.698518 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2026-06-12 14:19:24.395933 | 
2026-06-12 14:19:24.396051 | PLAY [localhost]
2026-06-12 14:19:24.406524 | 
2026-06-12 14:19:24.406613 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-06-12 14:19:24.837635 | localhost | changed
2026-06-12 14:19:24.859071 | 
2026-06-12 14:19:24.859383 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-06-12 14:19:24.912247 | localhost | ok
2026-06-12 14:19:24.925250 | 
2026-06-12 14:19:24.925397 | TASK [add-fileserver : Create SSH private key tempfile]
2026-06-12 14:19:25.405787 | localhost | changed
2026-06-12 14:19:25.418190 | 
2026-06-12 14:19:25.418345 | TASK [add-fileserver : Create SSH private key from secret]
2026-06-12 14:19:26.306931 | localhost | changed
2026-06-12 14:19:26.316733 | 
2026-06-12 14:19:26.316876 | TASK [add-fileserver : Add fileserver ssh key]
2026-06-12 14:19:26.937748 | localhost | Identity added: /var/lib/zuul/builds/51f4563f9d0a44f49d26838494b1b441/work/tmp/ansible.64mj2b8u (/var/lib/zuul/builds/51f4563f9d0a44f49d26838494b1b441/work/tmp/ansible.64mj2b8u)
2026-06-12 14:19:26.938068 | localhost | ok: Runtime: 0:00:00.020946
2026-06-12 14:19:26.947225 | 
2026-06-12 14:19:26.947361 | TASK [add-fileserver : Remove SSH private key from disk]
2026-06-12 14:19:27.269206 | localhost | ok: Runtime: 0:00:00.006806
2026-06-12 14:19:27.277973 | 
2026-06-12 14:19:27.278110 | TASK [add-fileserver : Add fileserver to inventory]
2026-06-12 14:19:27.340023 | localhost | changed
2026-06-12 14:19:27.350963 | 
2026-06-12 14:19:27.351135 | TASK [add-fileserver : Add fileserver server to known hosts]
2026-06-12 14:19:27.816421 | localhost | changed
2026-06-12 14:19:27.822512 | 
2026-06-12 14:19:27.822566 | PLAY [localhost]
2026-06-12 14:19:27.828215 | 
2026-06-12 14:19:27.828294 | TASK [Generate bulk log download script]
2026-06-12 14:19:27.858041 | localhost | ok
2026-06-12 14:19:27.872255 | 
2026-06-12 14:19:27.872340 | TASK [local-log-download : Check API endpoint is defined]
2026-06-12 14:19:27.964456 | localhost | ok: All assertions passed
2026-06-12 14:19:27.973337 | 
2026-06-12 14:19:27.973476 | TASK [local-log-download : Create download script]
2026-06-12 14:19:28.508686 | localhost -> localhost | changed
2026-06-12 14:19:28.573231 | 
2026-06-12 14:19:28.573395 | TASK [Register quick-download link]
2026-06-12 14:19:28.602992 | localhost | ok
2026-06-12 14:19:28.605272 | 
2026-06-12 14:19:28.605329 | PLAY [a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com]
2026-06-12 14:19:28.611364 | 
2026-06-12 14:19:28.611434 | TASK [Set zuul-log-path fact]
2026-06-12 14:19:28.636952 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | ok
2026-06-12 14:19:28.643391 | 
2026-06-12 14:19:28.643452 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-06-12 14:19:28.679774 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | ok
2026-06-12 14:19:28.682102 | 
2026-06-12 14:19:28.682162 | TASK [upload-logs : Create log directories]
2026-06-12 14:19:29.573166 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | changed
2026-06-12 14:19:29.576048 | 
2026-06-12 14:19:29.576145 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-06-12 14:19:29.952074 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com -> localhost | ok: Runtime: 0:00:00.010786
2026-06-12 14:19:29.958765 | 
2026-06-12 14:19:29.958894 | TASK [upload-logs : Upload logs to log server]
2026-06-12 14:19:32.095175 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | Output suppressed because no_log was given
2026-06-12 14:19:32.098550 | 
2026-06-12 14:19:32.098752 | LOOP [upload-logs : Compress console log and json output]