2026-06-08 21:41:02.405957 | Job console starting
2026-06-08 21:41:02.516870 | Updating git repos
2026-06-08 21:41:02.557994 | Cloning repos into workspace
2026-06-08 21:41:04.842960 | Restoring repo states
2026-06-08 21:41:04.861684 | Merging changes
2026-06-08 21:41:05.811821 | Checking out repos
2026-06-08 21:41:06.660224 | Preparing playbooks
2026-06-08 21:41:11.509908 | Running Ansible setup
2026-06-08 21:41:15.514394 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2026-06-08 21:41:16.127042 | 
2026-06-08 21:41:16.127171 | PLAY [localhost]
2026-06-08 21:41:16.134597 | 
2026-06-08 21:41:16.134662 | TASK [Gathering Facts]
2026-06-08 21:41:17.105042 | localhost | ok
2026-06-08 21:41:17.113513 | 
2026-06-08 21:41:17.113623 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-06-08 21:41:17.554508 | localhost -> localhost | changed
2026-06-08 21:41:17.564966 | 
2026-06-08 21:41:17.565114 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-06-08 21:41:18.631628 | localhost -> localhost | changed
2026-06-08 21:41:18.644709 | 
2026-06-08 21:41:18.644847 | TASK [Setup log path fact]
2026-06-08 21:41:18.667224 | localhost | ok
2026-06-08 21:41:18.688413 | 
2026-06-08 21:41:18.688550 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-06-08 21:41:18.728154 | localhost | ok
2026-06-08 21:41:18.735484 | 
2026-06-08 21:41:18.735568 | TASK [emit-job-header : Print job information]
2026-06-08 21:41:18.784893 | # Job Information
2026-06-08 21:41:18.785048 | Ansible Version: 2.16.18
2026-06-08 21:41:18.785082 | Job: edpm-ansible-molecule-edpm_frr
2026-06-08 21:41:18.785107 | Pipeline: github-check
2026-06-08 21:41:18.785131 | Executor: 38.102.83.51
2026-06-08 21:41:18.785153 | Triggered by: https://github.com/openstack-k8s-operators/edpm-ansible/pull/1185
2026-06-08 21:41:18.785181 | Log URL (when completed): https://gateway-cloud-softwarefactory.apps.ocp.cloud.ci.centos.org/logs/df9/rdoproject.org/df980c2dd7be40afadf31c474d1d76c4/
2026-06-08 21:41:18.785209 | Event ID: 7950c1f0-6382-11f1-95d4-d54f1801dd86
2026-06-08 21:41:18.791082 | 
2026-06-08 21:41:18.791176 | LOOP [emit-job-header : Print node information]
2026-06-08 21:41:18.896431 | localhost | ok:
2026-06-08 21:41:18.896725 | localhost | # Node Information
2026-06-08 21:41:18.896779 | localhost | Inventory Hostname: controller
2026-06-08 21:41:18.896824 | localhost | Hostname: np0000067726
2026-06-08 21:41:18.896863 | localhost | Username: zuul
2026-06-08 21:41:18.896904 | localhost | Distro: CentOS 9
2026-06-08 21:41:18.896941 | localhost | Provider: vexxhost-nodepool-tripleo
2026-06-08 21:41:18.896977 | localhost | Region: RegionOne
2026-06-08 21:41:18.897011 | localhost | Label: cloud-centos-9-stream-tripleo
2026-06-08 21:41:18.897044 | localhost | Product Name: OpenStack Nova
2026-06-08 21:41:18.897078 | localhost | Interface IP: 38.102.83.19
2026-06-08 21:41:18.908226 | 
2026-06-08 21:41:18.908443 | PLAY [all]
2026-06-08 21:41:18.920081 | 
2026-06-08 21:41:18.920205 | TASK [Gather network facts]
2026-06-08 21:41:19.351700 | controller | ok
2026-06-08 21:41:19.365986 | 
2026-06-08 21:41:19.366116 | TASK [include_role : start-zuul-console]
2026-06-08 21:41:19.393023 | controller | ok
2026-06-08 21:41:19.412181 | 
2026-06-08 21:41:19.412329 | TASK [start-zuul-console : Start zuul_console daemon.]
2026-06-08 21:41:19.819906 | controller | ok
2026-06-08 21:41:19.832383 | 
2026-06-08 21:41:19.832508 | TASK [include_role : add-build-sshkey]
2026-06-08 21:41:19.867866 | controller | ok
2026-06-08 21:41:19.890953 | 
2026-06-08 21:41:19.891081 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-06-08 21:41:20.179803 | controller -> localhost | ok
2026-06-08 21:41:20.186205 | 
2026-06-08 21:41:20.186358 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-06-08 21:41:20.225801 | controller | ok
2026-06-08 21:41:20.244289 | controller | included: /var/lib/zuul/builds/df980c2dd7be40afadf31c474d1d76c4/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-06-08 21:41:20.250870 | 
2026-06-08 21:41:20.250947 | TASK [add-build-sshkey : Create Temp SSH key]
2026-06-08 21:41:20.940683 | controller -> localhost | Generating public/private rsa key pair.
2026-06-08 21:41:20.941031 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/df980c2dd7be40afadf31c474d1d76c4/work/df980c2dd7be40afadf31c474d1d76c4_id_rsa
2026-06-08 21:41:20.941087 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/df980c2dd7be40afadf31c474d1d76c4/work/df980c2dd7be40afadf31c474d1d76c4_id_rsa.pub
2026-06-08 21:41:20.941126 | controller -> localhost | The key fingerprint is:
2026-06-08 21:41:20.941162 | controller -> localhost | SHA256:zDqMNqylctErvVcQ7I5iB+sS6lX2tPaifB5lBClC6oQ zuul-build-sshkey
2026-06-08 21:41:20.941198 | controller -> localhost | The key's randomart image is:
2026-06-08 21:41:20.941239 | controller -> localhost | +---[RSA 3072]----+
2026-06-08 21:41:20.941271 | controller -> localhost | |  .. . ..        |
2026-06-08 21:41:20.941301 | controller -> localhost | |. .. .o..        |
2026-06-08 21:41:20.941328 | controller -> localhost | |Eo  .... .       |
2026-06-08 21:41:20.941378 | controller -> localhost | |o .   oo.        |
2026-06-08 21:41:20.941413 | controller -> localhost | | . + = oSo       |
2026-06-08 21:41:20.941440 | controller -> localhost | |. =.*o+.=        |
2026-06-08 21:41:20.941465 | controller -> localhost | |.+ **.+*         |
2026-06-08 21:41:20.941493 | controller -> localhost | |+ ==+.o+o        |
2026-06-08 21:41:20.941521 | controller -> localhost | |.=o..=+...       |
2026-06-08 21:41:20.941556 | controller -> localhost | +----[SHA256]-----+
2026-06-08 21:41:20.941623 | controller -> localhost | ok: Runtime: 0:00:00.140634
2026-06-08 21:41:20.947098 | 
2026-06-08 21:41:20.947237 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-06-08 21:41:20.978557 | controller | ok
2026-06-08 21:41:20.990840 | controller | included: /var/lib/zuul/builds/df980c2dd7be40afadf31c474d1d76c4/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-06-08 21:41:21.000143 | 
2026-06-08 21:41:21.000224 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-06-08 21:41:21.026083 | controller | skipping: Conditional result was False
2026-06-08 21:41:21.032385 | 
2026-06-08 21:41:21.032481 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-06-08 21:41:21.488985 | controller | changed
2026-06-08 21:41:21.496789 | 
2026-06-08 21:41:21.496922 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-06-08 21:41:21.784071 | controller | ok
2026-06-08 21:41:21.791613 | 
2026-06-08 21:41:21.791770 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-06-08 21:41:22.677688 | controller | changed
2026-06-08 21:41:22.685475 | 
2026-06-08 21:41:22.685647 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-06-08 21:41:23.513033 | controller | changed
2026-06-08 21:41:23.520990 | 
2026-06-08 21:41:23.521147 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-06-08 21:41:23.549266 | controller | skipping: Conditional result was False
2026-06-08 21:41:23.558820 | 
2026-06-08 21:41:23.558964 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-06-08 21:41:23.991930 | controller -> localhost | changed
2026-06-08 21:41:24.007116 | 
2026-06-08 21:41:24.007255 | TASK [add-build-sshkey : Add back temp key]
2026-06-08 21:41:24.451432 | controller -> localhost | Identity added: /var/lib/zuul/builds/df980c2dd7be40afadf31c474d1d76c4/work/df980c2dd7be40afadf31c474d1d76c4_id_rsa (zuul-build-sshkey)
2026-06-08 21:41:24.451785 | controller -> localhost | ok: Runtime: 0:00:00.017913
2026-06-08 21:41:24.459553 | 
2026-06-08 21:41:24.459683 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-06-08 21:41:24.869994 | controller | ok
2026-06-08 21:41:24.877156 | 
2026-06-08 21:41:24.877402 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-06-08 21:41:24.917152 | controller | skipping: Conditional result was False
2026-06-08 21:41:24.932505 | 
2026-06-08 21:41:24.932659 | TASK [include_role : validate-host]
2026-06-08 21:41:24.970885 | controller | ok
2026-06-08 21:41:25.003691 | 
2026-06-08 21:41:25.003830 | TASK [validate-host : Define zuul_info_dir fact]
2026-06-08 21:41:25.044584 | controller | ok
2026-06-08 21:41:25.049380 | 
2026-06-08 21:41:25.049478 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2026-06-08 21:41:25.360038 | controller -> localhost | ok
2026-06-08 21:41:25.369652 | 
2026-06-08 21:41:25.369785 | TASK [validate-host : Collect information about the host]
2026-06-08 21:41:26.107814 | controller | ok
2026-06-08 21:41:26.125916 | 
2026-06-08 21:41:26.126109 | TASK [validate-host : Sanitize hostname]
2026-06-08 21:41:26.190248 | controller | ok
2026-06-08 21:41:26.198566 | 
2026-06-08 21:41:26.198703 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2026-06-08 21:41:26.716822 | controller -> localhost | changed
2026-06-08 21:41:26.723818 | 
2026-06-08 21:41:26.723892 | TASK [validate-host : Collect information about zuul worker]
2026-06-08 21:41:27.164718 | controller | ok
2026-06-08 21:41:27.168660 | 
2026-06-08 21:41:27.168731 | TASK [validate-host : Write out all zuul information for each host]
2026-06-08 21:41:27.770301 | controller -> localhost | changed
2026-06-08 21:41:27.785121 | 
2026-06-08 21:41:27.785258 | TASK [include_role : prepare-workspace-openshift]
2026-06-08 21:41:27.812063 | controller | skipping: Conditional result was False
2026-06-08 21:41:27.819600 | 
2026-06-08 21:41:27.819729 | TASK [include_role : remove-zuul-sshkey]
2026-06-08 21:41:27.846780 | controller | skipping: Conditional result was False
2026-06-08 21:41:27.853133 | 
2026-06-08 21:41:27.853236 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-06-08 21:41:28.118616 | controller | ok: "logs"
2026-06-08 21:41:28.118985 | controller | ok: All items complete
2026-06-08 21:41:28.119021 | 
2026-06-08 21:41:28.368275 | controller | ok: "artifacts"
2026-06-08 21:41:28.618239 | controller | ok: "docs"
2026-06-08 21:41:28.628967 | 
2026-06-08 21:41:28.629185 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-06-08 21:41:28.898833 | controller | changed: "logs"
2026-06-08 21:41:29.156341 | controller | changed: "artifacts"
2026-06-08 21:41:29.369260 | controller | changed: "docs"
2026-06-08 21:41:29.388402 | 
2026-06-08 21:41:29.388543 | PLAY RECAP
2026-06-08 21:41:29.388632 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2026-06-08 21:41:29.388687 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-06-08 21:41:29.388726 | 
2026-06-08 21:41:29.542083 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2026-06-08 21:41:29.543328 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2026-06-08 21:41:30.273766 | 
2026-06-08 21:41:30.273922 | PLAY [all]
2026-06-08 21:41:30.284862 | 
2026-06-08 21:41:30.284939 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2026-06-08 21:41:30.379648 | controller | ok
2026-06-08 21:41:30.387214 | 
2026-06-08 21:41:30.387422 | TASK [mirror-info-fork : Create /etc/ci]
2026-06-08 21:41:30.858193 | controller | changed
2026-06-08 21:41:30.868097 | 
2026-06-08 21:41:30.868253 | TASK [mirror-info-fork : Install ci_mirror script]
2026-06-08 21:41:32.110499 | controller | changed
2026-06-08 21:41:32.129019 | 
2026-06-08 21:41:32.129178 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2026-06-08 21:41:32.622966 | controller | changed:
2026-06-08 21:41:32.623288 | controller | {
2026-06-08 21:41:32.623342 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2026-06-08 21:41:32.623465 | controller | }
2026-06-08 21:41:32.899895 | controller | changed:
2026-06-08 21:41:32.900035 | controller | {
2026-06-08 21:41:32.900088 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2026-06-08 21:41:32.900130 | controller | }
2026-06-08 21:41:33.146670 | controller | changed:
2026-06-08 21:41:33.146819 | controller | {
2026-06-08 21:41:33.146866 | 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-08 21:41:33.146905 | controller | }
2026-06-08 21:41:33.386577 | controller | changed:
2026-06-08 21:41:33.386792 | controller | {
2026-06-08 21:41:33.386843 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2026-06-08 21:41:33.386883 | controller | }
2026-06-08 21:41:33.672845 | controller | changed:
2026-06-08 21:41:33.673085 | controller | {
2026-06-08 21:41:33.673144 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2026-06-08 21:41:33.673187 | controller | }
2026-06-08 21:41:33.970173 | controller | changed:
2026-06-08 21:41:33.970440 | controller | {
2026-06-08 21:41:33.970516 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2026-06-08 21:41:33.970564 | controller | }
2026-06-08 21:41:34.222105 | controller | changed:
2026-06-08 21:41:34.223030 | controller | {
2026-06-08 21:41:34.223075 | 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-08 21:41:34.223101 | controller | }
2026-06-08 21:41:34.497843 | controller | changed:
2026-06-08 21:41:34.498004 | controller | {
2026-06-08 21:41:34.498054 | 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-08 21:41:34.498109 | controller | }
2026-06-08 21:41:34.776460 | controller | changed:
2026-06-08 21:41:34.776615 | controller | {
2026-06-08 21:41:34.776663 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2026-06-08 21:41:34.776703 | controller | }
2026-06-08 21:41:35.026668 | controller | changed:
2026-06-08 21:41:35.026868 | controller | {
2026-06-08 21:41:35.026902 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2026-06-08 21:41:35.026929 | controller | }
2026-06-08 21:41:35.297551 | controller | changed:
2026-06-08 21:41:35.297696 | controller | {
2026-06-08 21:41:35.297728 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2026-06-08 21:41:35.297754 | controller | }
2026-06-08 21:41:35.540333 | controller | changed:
2026-06-08 21:41:35.540533 | controller | {
2026-06-08 21:41:35.540585 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2026-06-08 21:41:35.540626 | controller | }
2026-06-08 21:41:35.833816 | controller | changed:
2026-06-08 21:41:35.833964 | controller | {
2026-06-08 21:41:35.834011 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICWBreHW95Wz2Toz5YwCGQwFcUG8oFYkienDh9tntmDc ralfieri@redhat.com"
2026-06-08 21:41:35.834050 | controller | }
2026-06-08 21:41:36.166153 | controller | changed:
2026-06-08 21:41:36.166329 | controller | {
2026-06-08 21:41:36.166461 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2026-06-08 21:41:36.166504 | controller | }
2026-06-08 21:41:36.412880 | controller | changed:
2026-06-08 21:41:36.413008 | controller | {
2026-06-08 21:41:36.413031 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2026-06-08 21:41:36.413050 | controller | }
2026-06-08 21:41:36.668127 | controller | changed:
2026-06-08 21:41:36.668263 | controller | {
2026-06-08 21:41:36.668312 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2026-06-08 21:41:36.668385 | controller | }
2026-06-08 21:41:36.909294 | controller | changed:
2026-06-08 21:41:36.909460 | controller | {
2026-06-08 21:41:36.909511 | 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-08 21:41:36.909553 | controller | }
2026-06-08 21:41:37.170875 | controller | changed:
2026-06-08 21:41:37.171046 | controller | {
2026-06-08 21:41:37.171098 | 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-08 21:41:37.171158 | controller | }
2026-06-08 21:41:37.406410 | controller | changed:
2026-06-08 21:41:37.406530 | controller | {
2026-06-08 21:41:37.406568 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2026-06-08 21:41:37.406601 | controller | }
2026-06-08 21:41:37.689576 | controller | changed:
2026-06-08 21:41:37.689754 | controller | {
2026-06-08 21:41:37.689807 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2026-06-08 21:41:37.689848 | controller | }
2026-06-08 21:41:37.921424 | controller | changed:
2026-06-08 21:41:37.921561 | controller | {
2026-06-08 21:41:37.921585 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2026-06-08 21:41:37.921603 | controller | }
2026-06-08 21:41:38.189154 | controller | changed:
2026-06-08 21:41:38.189284 | controller | {
2026-06-08 21:41:38.189305 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2026-06-08 21:41:38.189322 | controller | }
2026-06-08 21:41:38.483134 | controller | changed:
2026-06-08 21:41:38.483253 | controller | {
2026-06-08 21:41:38.483275 | 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-08 21:41:38.483294 | controller | }
2026-06-08 21:41:38.741495 | controller | changed:
2026-06-08 21:41:38.741738 | controller | {
2026-06-08 21:41:38.741797 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2026-06-08 21:41:38.741837 | controller | }
2026-06-08 21:41:39.033803 | controller | changed:
2026-06-08 21:41:39.033998 | controller | {
2026-06-08 21:41:39.034064 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2026-06-08 21:41:39.034113 | controller | }
2026-06-08 21:41:39.329099 | controller | changed:
2026-06-08 21:41:39.329253 | controller | {
2026-06-08 21:41:39.329278 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2026-06-08 21:41:39.329296 | controller | }
2026-06-08 21:41:39.369314 | 
2026-06-08 21:41:39.369582 | TASK [Set timezone to UTC]
2026-06-08 21:41:39.910445 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2026-06-08 21:41:39.914390 | 
2026-06-08 21:41:39.914463 | TASK [Create nodepool directory]
2026-06-08 21:41:40.222736 | controller | changed
2026-06-08 21:41:40.235993 | 
2026-06-08 21:41:40.236317 | TASK [Create nodepool sub_nodes file]
2026-06-08 21:41:41.051297 | controller | changed
2026-06-08 21:41:41.058920 | 
2026-06-08 21:41:41.059048 | TASK [Create nodepool sub_nodes_private file]
2026-06-08 21:41:41.875008 | controller | changed
2026-06-08 21:41:41.883079 | 
2026-06-08 21:41:41.883228 | LOOP [Populate nodepool sub_nodes file]
2026-06-08 21:41:41.939301 | 
2026-06-08 21:41:41.939601 | LOOP [Populate nodepool sub_nodes_private file]
2026-06-08 21:41:42.004214 | 
2026-06-08 21:41:42.004501 | TASK [Create nodepool primary file]
2026-06-08 21:41:42.040749 | controller | skipping: Conditional result was False
2026-06-08 21:41:42.050293 | 
2026-06-08 21:41:42.050497 | TASK [Create nodepool node_private for this node]
2026-06-08 21:41:42.914593 | controller | changed
2026-06-08 21:41:42.923665 | 
2026-06-08 21:41:42.923791 | LOOP [Copy ssh keys to nodepool directory]
2026-06-08 21:41:43.388745 | controller | ok: Item: id_rsa Runtime: 0:00:00.007654
2026-06-08 21:41:43.389105 | 
2026-06-08 21:41:43.635707 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.007473
2026-06-08 21:41:43.650026 | 
2026-06-08 21:41:43.650247 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2026-06-08 21:41:44.487480 | controller | changed
2026-06-08 21:41:44.495058 | 
2026-06-08 21:41:44.495188 | TASK [Validate sudoers config after edits]
2026-06-08 21:41:44.827519 | controller | /etc/sudoers: parsed OK
2026-06-08 21:41:44.827655 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2026-06-08 21:41:44.827672 | controller | /etc/sudoers.d/zuul: parsed OK
2026-06-08 21:41:44.827682 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2026-06-08 21:41:45.039263 | controller | ok: Runtime: 0:00:00.008153
2026-06-08 21:41:45.044838 | 
2026-06-08 21:41:45.044918 | TASK [Show the environment passed in to job shell scripts]
2026-06-08 21:41:45.341227 | controller | SHELL=/bin/bash
2026-06-08 21:41:45.341361 | controller | ZUUL_PROJECT=openstack-k8s-operators/edpm-ansible
2026-06-08 21:41:45.341377 | controller | ZUUL_SHORT_PROJECT_NAME=edpm-ansible
2026-06-08 21:41:45.341392 | controller | ZUUL_CHANGES=openstack-k8s-operators/edpm-ansible:main:refs/changes/85/1185/624bb060d87d8f3a9a309baad2c21391c1335baf
2026-06-08 21:41:45.341403 | controller | PWD=/home/zuul
2026-06-08 21:41:45.341413 | controller | ZUUL_PIPELINE=github-check
2026-06-08 21:41:45.341481 | controller | LOGNAME=zuul
2026-06-08 21:41:45.341489 | controller | XDG_SESSION_TYPE=tty
2026-06-08 21:41:45.341493 | controller | _=/usr/bin/env
2026-06-08 21:41:45.341498 | controller | MOTD_SHOWN=pam
2026-06-08 21:41:45.341502 | controller | HOME=/home/zuul
2026-06-08 21:41:45.341505 | controller | LANG=en_US.UTF-8
2026-06-08 21:41:45.341510 | controller | SSH_CONNECTION=38.102.83.51 41406 38.102.83.19 22
2026-06-08 21:41:45.341516 | 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-08 21:41:45.341521 | controller | ZUUL_CHANGE_IDS=1185,624bb060d87d8f3a9a309baad2c21391c1335baf
2026-06-08 21:41:45.341525 | controller | WORKSPACE=/home/zuul/workspace
2026-06-08 21:41:45.341529 | controller | XDG_SESSION_CLASS=user
2026-06-08 21:41:45.341532 | controller | SELINUX_ROLE_REQUESTED=
2026-06-08 21:41:45.341536 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2026-06-08 21:41:45.341540 | controller | USER=zuul
2026-06-08 21:41:45.341543 | controller | ZUUL_VOTING=True
2026-06-08 21:41:45.341547 | controller | BUILD_TIMEOUT=1800000
2026-06-08 21:41:45.341550 | controller | SELINUX_USE_CURRENT_RANGE=
2026-06-08 21:41:45.341554 | controller | SHLVL=1
2026-06-08 21:41:45.341557 | controller | ZUUL_PATCHSET=624bb060d87d8f3a9a309baad2c21391c1335baf
2026-06-08 21:41:45.341561 | controller | XDG_SESSION_ID=1
2026-06-08 21:41:45.341565 | controller | ZUUL_BRANCH=main
2026-06-08 21:41:45.341568 | controller | XDG_RUNTIME_DIR=/run/user/1000
2026-06-08 21:41:45.341571 | controller | SSH_CLIENT=38.102.83.51 41406 22
2026-06-08 21:41:45.341579 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2026-06-08 21:41:45.341583 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2026-06-08 21:41:45.341586 | controller | which_declare=declare -f
2026-06-08 21:41:45.341590 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2026-06-08 21:41:45.341594 | controller | SELINUX_LEVEL_REQUESTED=
2026-06-08 21:41:45.341597 | controller | ZUUL_CHANGE=1185
2026-06-08 21:41:45.341601 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2026-06-08 21:41:45.341605 | controller | ZUUL_UUID=df980c2dd7be40afadf31c474d1d76c4
2026-06-08 21:41:45.341608 | controller | BASH_FUNC_which%%=() {  ( alias;
2026-06-08 21:41:45.341612 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2026-06-08 21:41:45.341617 | controller | }
2026-06-08 21:41:45.579551 | controller | ok: Runtime: 0:00:00.009351
2026-06-08 21:41:45.588228 | 
2026-06-08 21:41:45.588385 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2026-06-08 21:41:45.615165 | controller | skipping: Conditional result was False
2026-06-08 21:41:45.626486 | 
2026-06-08 21:41:45.626626 | TASK [Symlink /home/zuul-worker/workspace]
2026-06-08 21:41:46.166232 | controller | skipping: Conditional result was False
2026-06-08 21:41:46.175442 | 
2026-06-08 21:41:46.175573 | TASK [Ensure legacy workspace directory]
2026-06-08 21:41:46.474240 | controller | changed
2026-06-08 21:41:46.476807 | 
2026-06-08 21:41:46.476902 | PLAY RECAP
2026-06-08 21:41:46.476986 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2026-06-08 21:41:46.477029 | 
2026-06-08 21:41:46.627121 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2026-06-08 21:41:46.629544 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2026-06-08 21:41:47.271470 | 
2026-06-08 21:41:47.271815 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2026-06-08 21:41:47.283096 | 
2026-06-08 21:41:47.283170 | TASK [Create zuul-output directory]
2026-06-08 21:41:47.671136 | controller | changed
2026-06-08 21:41:47.679567 | 
2026-06-08 21:41:47.679715 | TASK [Slurp Zuul inventory test]
2026-06-08 21:41:48.122645 | controller -> localhost | ok
2026-06-08 21:41:48.130573 | 
2026-06-08 21:41:48.130668 | TASK [Save zuul inventory]
2026-06-08 21:41:49.258268 | controller | changed
2026-06-08 21:41:49.261790 | 
2026-06-08 21:41:49.261855 | TASK [Save zuul vars without the change_message]
2026-06-08 21:41:50.135649 | controller | changed
2026-06-08 21:41:50.137984 | 
2026-06-08 21:41:50.138078 | PLAY RECAP
2026-06-08 21:41:50.138160 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-06-08 21:41:50.138203 | 
2026-06-08 21:41:50.280171 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2026-06-08 21:41:50.282672 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2026-06-08 21:41:50.937526 | 
2026-06-08 21:41:50.937894 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2026-06-08 21:41:50.950542 | 
2026-06-08 21:41:50.950627 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2026-06-08 21:41:50.969828 | controller | ok
2026-06-08 21:41:50.984039 | 
2026-06-08 21:41:50.984113 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2026-06-08 21:41:51.029064 | controller | skipping: Conditional result was False
2026-06-08 21:41:51.034987 | 
2026-06-08 21:41:51.035076 | TASK [mirror-info-fork : Create /etc/ci]
2026-06-08 21:41:51.445434 | controller | ok
2026-06-08 21:41:51.456659 | 
2026-06-08 21:41:51.456805 | TASK [mirror-info-fork : Install ci_mirror script]
2026-06-08 21:41:52.376307 | controller | ok
2026-06-08 21:41:52.392260 | 
2026-06-08 21:41:52.392432 | TASK [Prepare workspace]
2026-06-08 21:41:52.419466 | controller | ok
2026-06-08 21:41:52.444616 | 
2026-06-08 21:41:52.444684 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-06-08 21:41:52.863334 | controller | ok
2026-06-08 21:41:52.873786 | 
2026-06-08 21:41:52.873944 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-06-08 21:41:55.038029 | controller | Output suppressed because no_log was given
2026-06-08 21:41:55.051759 | 
2026-06-08 21:41:55.051912 | LOOP [Create zuul-output directory]
2026-06-08 21:41:55.333051 | controller | changed: "/home/zuul/ci-framework-data/logs"
2026-06-08 21:41:55.549493 | controller | ok: "/home/zuul/zuul-output/logs"
2026-06-08 21:41:55.567824 | 
2026-06-08 21:41:55.568013 | TASK [Install required packages]
2026-06-08 21:42:39.818943 | controller | changed
2026-06-08 21:42:39.827649 | 
2026-06-08 21:42:39.827788 | TASK [Install venv]
2026-06-08 21:43:43.166786 | controller | changed
2026-06-08 21:43:43.190456 | 
2026-06-08 21:43:43.190752 | PLAY RECAP
2026-06-08 21:43:43.190835 | controller | ok: 7 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-06-08 21:43:43.190876 | 
2026-06-08 21:43:43.339543 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2026-06-08 21:43:43.342550 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2026-06-08 21:43:43.992157 | 
2026-06-08 21:43:43.992290 | PLAY [Run ci/playbooks/molecule-test.yml]
2026-06-08 21:43:44.003318 | 
2026-06-08 21:43:44.003412 | TASK [Gather required facts]
2026-06-08 21:43:44.647504 | controller | ok
2026-06-08 21:43:44.656398 | 
2026-06-08 21:43:44.656547 | TASK [Load environment var if instructed to]
2026-06-08 21:43:44.692991 | controller | skipping: Conditional result was False
2026-06-08 21:43:44.702266 | 
2026-06-08 21:43:44.702441 | TASK [Ensure group_vars dir exists]
2026-06-08 21:43:45.100745 | controller | changed
2026-06-08 21:43:45.110876 | 
2026-06-08 21:43:45.111007 | TASK [Print related variables]
2026-06-08 21:43:45.154836 | controller | ok:
2026-06-08 21:43:45.155112 | controller | mol_config_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config_edpm_ansible.yml
2026-06-08 21:43:45.155169 | controller | roles_dir: /home/zuul/src/github.com/openstack-k8s-operators/edpm-ansible/roles/edpm_frr
2026-06-08 21:43:45.162994 | 
2026-06-08 21:43:45.163122 | TASK [Run molecule]
2026-06-08 21:43:46.456269 | controller | INFO     Performing prerun with role_name_check=0...
2026-06-08 21:43:47.396799 | controller | INFO     Running default > prepare
2026-06-08 21:43:48.238058 | controller |
2026-06-08 21:43:48.238182 | controller | PLAY [Prepare test_deps] *******************************************************
2026-06-08 21:43:48.238425 | controller |
2026-06-08 21:43:48.238626 | controller | TASK [set basic user fact] *****************************************************
2026-06-08 21:43:48.238760 | controller | Monday 08 June 2026  21:43:48 +0000 (0:00:00.014)       0:00:00.015 ***********
2026-06-08 21:43:48.284894 | controller | ok: [compute-1]
2026-06-08 21:43:48.285019 | controller |
2026-06-08 21:43:48.285225 | controller | TASK [../../../../molecule/common/test_deps : Validating arguments against arg spec 'main' - The main entry point for the test_deps role.] ***
2026-06-08 21:43:48.285461 | controller | Monday 08 June 2026  21:43:48 +0000 (0:00:00.047)       0:00:00.062 ***********
2026-06-08 21:43:48.309202 | controller | ok: [compute-1]
2026-06-08 21:43:48.309278 | controller |
2026-06-08 21:43:48.309438 | controller | TASK [../../../../molecule/common/test_deps : Gather facts if they don't exist] ***
2026-06-08 21:43:48.309565 | controller | Monday 08 June 2026  21:43:48 +0000 (0:00:00.024)       0:00:00.087 ***********
2026-06-08 21:43:48.944824 | controller | ok: [compute-1]
2026-06-08 21:43:48.944940 | controller |
2026-06-08 21:43:48.945158 | controller | TASK [../../../../molecule/common/test_deps : Gather variables for each operating system] ***
2026-06-08 21:43:48.945417 | controller | Monday 08 June 2026  21:43:48 +0000 (0:00:00.635)       0:00:00.722 ***********
2026-06-08 21:43:49.011013 | controller | ok: [compute-1] => (item=/home/zuul/src/github.com/openstack-k8s-operators/edpm-ansible/molecule/common/test_deps/vars/centos-9.yml)
2026-06-08 21:43:49.011169 | controller |
2026-06-08 21:43:49.011428 | controller | TASK [../../../../molecule/common/test_deps : Detect mirrors file] *************
2026-06-08 21:43:49.011649 | controller | Monday 08 June 2026  21:43:49 +0000 (0:00:00.066)       0:00:00.788 ***********
2026-06-08 21:43:49.403828 | controller | ok: [compute-1]
2026-06-08 21:43:49.403979 | controller |
2026-06-08 21:43:49.404234 | controller | TASK [../../../../molecule/common/test_deps : Disable ubi host subscription-manager integration] ***
2026-06-08 21:43:49.404453 | controller | Monday 08 June 2026  21:43:49 +0000 (0:00:00.392)       0:00:01.180 ***********
2026-06-08 21:43:49.809149 | controller | ok: [compute-1]
2026-06-08 21:43:49.809195 | controller |
2026-06-08 21:43:49.809286 | controller | TASK [../../../../molecule/common/test_deps : Remove ubi 9 repos] **************
2026-06-08 21:43:49.809380 | controller | Monday 08 June 2026  21:43:49 +0000 (0:00:00.406)       0:00:01.587 ***********
2026-06-08 21:43:50.094653 | controller | ok: [compute-1]
2026-06-08 21:43:50.094895 | controller |
2026-06-08 21:43:50.095124 | controller | TASK [../../../../molecule/common/test_deps : Fetch latest repo version] *******
2026-06-08 21:43:50.095486 | controller | Monday 08 June 2026  21:43:50 +0000 (0:00:00.284)       0:00:01.871 ***********
2026-06-08 21:43:50.646030 | controller | ok: [compute-1]
2026-06-08 21:43:50.646135 | controller |
2026-06-08 21:43:50.646437 | controller | TASK [../../../../molecule/common/test_deps : Create default repo file] ********
2026-06-08 21:43:50.646632 | controller | Monday 08 June 2026  21:43:50 +0000 (0:00:00.551)       0:00:02.422 ***********
2026-06-08 21:43:51.239149 | controller | changed: [compute-1]
2026-06-08 21:43:51.239227 | controller |
2026-06-08 21:43:51.239420 | controller | TASK [../../../../molecule/common/test_deps : Install repo-setup pre-requirements] ***
2026-06-08 21:43:51.239566 | controller | Monday 08 June 2026  21:43:51 +0000 (0:00:00.594)       0:00:03.016 ***********
2026-06-08 21:43:56.489490 | controller | ok: [compute-1]
2026-06-08 21:43:56.489872 | controller |
2026-06-08 21:43:56.489913 | controller | TASK [../../../../molecule/common/test_deps : Get repo-setup repository] *******
2026-06-08 21:43:56.489919 | controller | Monday 08 June 2026  21:43:56 +0000 (0:00:05.249)       0:00:08.266 ***********
2026-06-08 21:43:57.608389 | controller | changed: [compute-1]
2026-06-08 21:43:57.608444 | controller |
2026-06-08 21:43:57.608456 | controller | TASK [../../../../molecule/common/test_deps : Install repo-setup] **************
2026-06-08 21:43:57.608465 | controller | Monday 08 June 2026  21:43:57 +0000 (0:00:01.118)       0:00:09.385 ***********
2026-06-08 21:43:59.239037 | controller | changed: [compute-1]
2026-06-08 21:43:59.239084 | controller |
2026-06-08 21:43:59.239095 | controller | TASK [../../../../molecule/common/test_deps : Create repos] ********************
2026-06-08 21:43:59.239118 | controller | Monday 08 June 2026  21:43:59 +0000 (0:00:01.630)       0:00:11.016 ***********
2026-06-08 21:43:59.993456 | controller | changed: [compute-1]
2026-06-08 21:43:59.993498 | controller |
2026-06-08 21:43:59.993509 | controller | TASK [../../../../molecule/common/test_deps : Look for redhat-release rpm] *****
2026-06-08 21:43:59.993519 | controller | Monday 08 June 2026  21:43:59 +0000 (0:00:00.754)       0:00:11.770 ***********
2026-06-08 21:44:00.323370 | controller | fatal: [compute-1]: FAILED! => changed=true
2026-06-08 21:44:00.323418 | controller |   cmd: |-
2026-06-08 21:44:00.323433 | controller |     rpm -qe redhat-release
2026-06-08 21:44:00.323446 | controller |   delta: '0:00:00.023006'
2026-06-08 21:44:00.325195 | controller |   end: '2026-06-08 21:44:00.283163'
2026-06-08 21:44:00.325224 | controller |   msg: non-zero return code
2026-06-08 21:44:00.325237 | controller |   rc: 1
2026-06-08 21:44:00.325281 | controller |   start: '2026-06-08 21:44:00.260157'
2026-06-08 21:44:00.325293 | controller |   stderr: ''
2026-06-08 21:44:00.325304 | controller |   stderr_lines: <omitted>
2026-06-08 21:44:00.325317 | controller |   stdout: package redhat-release is not installed
2026-06-08 21:44:00.325328 | controller |   stdout_lines: <omitted>
2026-06-08 21:44:00.331339 | controller | ...ignoring
2026-06-08 21:44:00.331368 | controller |
2026-06-08 21:44:00.331378 | controller | TASK [../../../../molecule/common/test_deps : Workaround of redhat-release binary on ubi9] ***
2026-06-08 21:44:00.331566 | controller | Monday 08 June 2026  21:44:00 +0000 (0:00:00.337)       0:00:12.108 ***********
2026-06-08 21:44:00.374967 | controller | skipping: [compute-1]
2026-06-08 21:44:00.375352 | controller |
2026-06-08 21:44:00.375658 | controller | TASK [../../../../molecule/common/test_deps : Install edpm packages] ***********
2026-06-08 21:44:00.375953 | controller | Monday 08 June 2026  21:44:00 +0000 (0:00:00.043)       0:00:12.152 ***********
2026-06-08 21:44:59.296200 | controller | changed: [compute-1]
2026-06-08 21:44:59.296236 | controller |
2026-06-08 21:44:59.296255 | controller | TASK [../../../../molecule/common/test_deps : Install selinux python libs] *****
2026-06-08 21:44:59.296262 | controller | Monday 08 June 2026  21:44:59 +0000 (0:00:58.920)       0:01:11.073 ***********
2026-06-08 21:45:01.161444 | controller | ok: [compute-1]
2026-06-08 21:45:01.161489 | controller |
2026-06-08 21:45:01.161500 | controller | TASK [../../../../molecule/common/test_deps : Install python yaml libs] ********
2026-06-08 21:45:01.161510 | controller | Monday 08 June 2026  21:45:01 +0000 (0:00:01.864)       0:01:12.938 ***********
2026-06-08 21:45:02.971333 | controller | ok: [compute-1]
2026-06-08 21:45:02.971366 | controller |
2026-06-08 21:45:02.971372 | controller | TASK [../../../../molecule/common/test_deps : Install extra packages] **********
2026-06-08 21:45:02.971392 | controller | Monday 08 June 2026  21:45:02 +0000 (0:00:01.809)       0:01:14.748 ***********
2026-06-08 21:45:04.851381 | controller | ok: [compute-1]
2026-06-08 21:45:04.851425 | controller |
2026-06-08 21:45:04.851435 | controller | TASK [../../../../molecule/common/test_deps : Check for /etc/ansible/facts.d/bootc.fact] ***
2026-06-08 21:45:04.851445 | controller | Monday 08 June 2026  21:45:04 +0000 (0:00:01.880)       0:01:16.628 ***********
2026-06-08 21:45:05.089373 | controller | ok: [compute-1]
2026-06-08 21:45:05.089435 | controller |
2026-06-08 21:45:05.089446 | controller | TASK [../../../../molecule/common/test_deps : Create /etc/ansible/facts.d] *****
2026-06-08 21:45:05.089457 | controller | Monday 08 June 2026  21:45:05 +0000 (0:00:00.238)       0:01:16.866 ***********
2026-06-08 21:45:05.362660 | controller | changed: [compute-1]
2026-06-08 21:45:05.362698 | controller |
2026-06-08 21:45:05.362704 | controller | TASK [../../../../molecule/common/test_deps : Create /etc/ansible/facts.d/bootc.fact] ***
2026-06-08 21:45:05.362718 | controller | Monday 08 June 2026  21:45:05 +0000 (0:00:00.273)       0:01:17.140 ***********
2026-06-08 21:45:05.802441 | controller | changed: [compute-1]
2026-06-08 21:45:05.802488 | controller |
2026-06-08 21:45:05.802498 | controller | TASK [Install os-net-config] ***************************************************
2026-06-08 21:45:05.802508 | controller | Monday 08 June 2026  21:45:05 +0000 (0:00:00.439)       0:01:17.579 ***********
2026-06-08 21:45:07.623344 | controller | ok: [compute-1]
2026-06-08 21:45:07.623377 | controller |
2026-06-08 21:45:07.623555 | controller | TASK [Create persistent directories] *******************************************
2026-06-08 21:45:07.623586 | controller | Monday 08 June 2026  21:45:07 +0000 (0:00:01.821)       0:01:19.401 ***********
2026-06-08 21:45:07.948536 | controller | changed: [compute-1] => (item={'path': '/etc/tmpfiles.d/', 'owner': 'root', 'group': 'root', 'mode': '0755'})
2026-06-08 21:45:07.948581 | controller |
2026-06-08 21:45:07.948592 | controller | TASK [Create firewall directory] ***********************************************
2026-06-08 21:45:07.948609 | controller | Monday 08 June 2026  21:45:07 +0000 (0:00:00.325)       0:01:19.726 ***********
2026-06-08 21:45:08.244389 | controller | changed: [compute-1]
2026-06-08 21:45:08.244426 | controller |
2026-06-08 21:45:08.244433 | controller | TASK [open port 22 (edpm_nftables will active this later)] *********************
2026-06-08 21:45:08.244440 | controller | Monday 08 June 2026  21:45:08 +0000 (0:00:00.294)       0:01:20.020 ***********
2026-06-08 21:45:08.740457 | controller | changed: [compute-1]
2026-06-08 21:45:08.740499 | controller |
2026-06-08 21:45:08.740959 | controller | TASK [Force systemd to reread configs] *****************************************
2026-06-08 21:45:08.740990 | controller | Monday 08 June 2026  21:45:08 +0000 (0:00:00.496)       0:01:20.517 ***********
2026-06-08 21:45:09.636510 | controller | ok: [compute-1]
2026-06-08 21:45:09.636569 | controller |
2026-06-08 21:45:09.636586 | controller | TASK [osp.edpm.edpm_bootstrap : Gather facts if they don't exist] **************
2026-06-08 21:45:09.636672 | controller | Monday 08 June 2026  21:45:09 +0000 (0:00:00.895)       0:01:21.413 ***********
2026-06-08 21:45:09.665043 | controller | skipping: [compute-1]
2026-06-08 21:45:09.665070 | controller |
2026-06-08 21:45:09.665079 | controller | TASK [osp.edpm.edpm_bootstrap : Download needed packages] **********************
2026-06-08 21:45:09.665089 | controller | Monday 08 June 2026  21:45:09 +0000 (0:00:00.028)       0:01:21.442 ***********
2026-06-08 21:45:12.974490 | controller | changed: [compute-1]
2026-06-08 21:45:12.974536 | controller |
2026-06-08 21:45:12.974547 | controller | TASK [osp.edpm.edpm_bootstrap : Download legacy network service package] *******
2026-06-08 21:45:12.974557 | controller | Monday 08 June 2026  21:45:12 +0000 (0:00:03.309)       0:01:24.751 ***********
2026-06-08 21:45:14.487220 | controller | ok: [compute-1]
2026-06-08 21:45:14.487336 | controller |
2026-06-08 21:45:14.487352 | controller | TASK [osp.edpm.edpm_bootstrap : Download release version package] **************
2026-06-08 21:45:14.487374 | controller | Monday 08 June 2026  21:45:14 +0000 (0:00:01.510)       0:01:26.262 ***********
2026-06-08 21:45:14.529461 | controller | skipping: [compute-1]
2026-06-08 21:45:14.530180 | controller |
2026-06-08 21:45:14.530233 | controller | TASK [osp.edpm.edpm_bootstrap : Include bootc tasks] ***************************
2026-06-08 21:45:14.530246 | controller | Monday 08 June 2026  21:45:14 +0000 (0:00:00.044)       0:01:26.306 ***********
2026-06-08 21:45:14.575480 | controller | included: /home/zuul/.cache/ansible-compat/8075a4/collections/ansible_collections/osp/edpm/roles/edpm_bootstrap/tasks/bootc.yml for compute-1
2026-06-08 21:45:14.575521 | controller |
2026-06-08 21:45:14.575529 | controller | TASK [osp.edpm.edpm_bootstrap : Check if /etc/ansible/facts.d/bootc.fact exists] ***
2026-06-08 21:45:14.575668 | controller | Monday 08 June 2026  21:45:14 +0000 (0:00:00.046)       0:01:26.353 ***********
2026-06-08 21:45:14.854426 | controller | ok: [compute-1]
2026-06-08 21:45:14.854471 | controller |
2026-06-08 21:45:14.854482 | controller | TASK [osp.edpm.edpm_bootstrap : Ensure /etc/ansible/facts.d exists] ************
2026-06-08 21:45:14.854497 | controller | Monday 08 June 2026  21:45:14 +0000 (0:00:00.278)       0:01:26.631 ***********
2026-06-08 21:45:14.887144 | controller | skipping: [compute-1]
2026-06-08 21:45:14.887194 | controller |
2026-06-08 21:45:14.887204 | controller | TASK [osp.edpm.edpm_bootstrap : Ensure /etc/ansible/facts.d/bootc.fact exists] ***
2026-06-08 21:45:14.887213 | controller | Monday 08 June 2026  21:45:14 +0000 (0:00:00.032)       0:01:26.663 ***********
2026-06-08 21:45:14.919624 | controller | skipping: [compute-1]
2026-06-08 21:45:14.919696 | controller |
2026-06-08 21:45:14.919708 | controller | TASK [osp.edpm.edpm_bootstrap : Gather ansible_local facts] ********************
2026-06-08 21:45:14.919722 | controller | Monday 08 June 2026  21:45:14 +0000 (0:00:00.032)       0:01:26.696 ***********
2026-06-08 21:45:14.949151 | controller | skipping: [compute-1]
2026-06-08 21:45:14.949529 | controller |
2026-06-08 21:45:14.949893 | controller | TASK [osp.edpm.edpm_bootstrap : Ensure /var/log/journal exists] ****************
2026-06-08 21:45:14.950284 | controller | Monday 08 June 2026  21:45:14 +0000 (0:00:00.030)       0:01:26.726 ***********
2026-06-08 21:45:15.238353 | controller | changed: [compute-1]
2026-06-08 21:45:15.238440 | controller |
2026-06-08 21:45:15.238452 | controller | TASK [osp.edpm.edpm_bootstrap : Gather user fact] ******************************
2026-06-08 21:45:15.238461 | controller | Monday 08 June 2026  21:45:15 +0000 (0:00:00.288)       0:01:27.014 ***********
2026-06-08 21:45:15.267578 | controller | skipping: [compute-1]
2026-06-08 21:45:15.267641 | controller |
2026-06-08 21:45:15.267652 | controller | TASK [osp.edpm.edpm_bootstrap : Ensure /var/lib/config-data/ansible-generated exists and fix subdirs owner and mode if it existed] ***
2026-06-08 21:45:15.267663 | controller | Monday 08 June 2026  21:45:15 +0000 (0:00:00.029)       0:01:27.044 ***********
2026-06-08 21:45:15.588726 | controller | changed: [compute-1]
2026-06-08 21:45:15.588756 | controller |
2026-06-08 21:45:15.588761 | controller | TASK [osp.edpm.edpm_bootstrap : Gather services facts] *************************
2026-06-08 21:45:15.588766 | controller | Monday 08 June 2026  21:45:15 +0000 (0:00:00.321)       0:01:27.366 ***********
2026-06-08 21:45:18.695827 | controller | ok: [compute-1]
2026-06-08 21:45:18.695920 | controller |
2026-06-08 21:45:18.696231 | controller | TASK [osp.edpm.edpm_bootstrap : Print cloud-init service status] ***************
2026-06-08 21:45:18.696344 | controller | Monday 08 June 2026  21:45:18 +0000 (0:00:03.106)       0:01:30.472 ***********
2026-06-08 21:45:18.745471 | controller | ok: [compute-1] =>
2026-06-08 21:45:18.745513 | controller |   ansible_facts.services["cloud-init.service"]:
2026-06-08 21:45:18.745519 | controller |     name: cloud-init.service
2026-06-08 21:45:18.745523 | controller |     source: systemd
2026-06-08 21:45:18.746571 | controller |     state: stopped
2026-06-08 21:45:18.746616 | controller |     status: enabled
2026-06-08 21:45:18.746627 | controller |
2026-06-08 21:45:18.746636 | controller | TASK [osp.edpm.edpm_bootstrap : Check if cloud-init is disabled via kernel args] ***
2026-06-08 21:45:18.746656 | controller | Monday 08 June 2026  21:45:18 +0000 (0:00:00.049)       0:01:30.522 ***********
2026-06-08 21:45:19.093419 | controller | changed: [compute-1]
2026-06-08 21:45:19.093461 | controller |
2026-06-08 21:45:19.093472 | controller | TASK [osp.edpm.edpm_bootstrap : Wait for cloud-init to finish, if enabled] *****
2026-06-08 21:45:19.093598 | controller | Monday 08 June 2026  21:45:19 +0000 (0:00:00.348)       0:01:30.870 ***********
2026-06-08 21:45:19.137993 | controller | skipping: [compute-1]
2026-06-08 21:45:19.138022 | controller |
2026-06-08 21:45:19.138032 | controller | TASK [osp.edpm.edpm_bootstrap : Re-read facts] *********************************
2026-06-08 21:45:19.138041 | controller | Monday 08 June 2026  21:45:19 +0000 (0:00:00.044)       0:01:30.915 ***********
2026-06-08 21:45:19.543771 | controller | ok: [compute-1]
2026-06-08 21:45:19.543801 | controller |
2026-06-08 21:45:19.543806 | controller | TASK [osp.edpm.edpm_bootstrap : Bootstrap command] *****************************
2026-06-08 21:45:19.543811 | controller | Monday 08 June 2026  21:45:19 +0000 (0:00:00.405)       0:01:31.321 ***********
2026-06-08 21:45:19.577041 | controller | skipping: [compute-1]
2026-06-08 21:45:19.577079 | controller |
2026-06-08 21:45:19.577089 | controller | TASK [osp.edpm.edpm_bootstrap : Bootstrap command output] **********************
2026-06-08 21:45:19.577099 | controller | Monday 08 June 2026  21:45:19 +0000 (0:00:00.033)       0:01:31.354 ***********
2026-06-08 21:45:19.603535 | controller | skipping: [compute-1]
2026-06-08 21:45:19.603592 | controller |
2026-06-08 21:45:19.603603 | controller | TASK [osp.edpm.edpm_bootstrap : Create directory for ansible custom facts] *****
2026-06-08 21:45:19.603620 | controller | Monday 08 June 2026  21:45:19 +0000 (0:00:00.026)       0:01:31.380 ***********
2026-06-08 21:45:19.631454 | controller | skipping: [compute-1]
2026-06-08 21:45:19.631565 | controller |
2026-06-08 21:45:19.631577 | controller | TASK [osp.edpm.edpm_bootstrap : Mark bootstrap command as executed on node] ****
2026-06-08 21:45:19.631597 | controller | Monday 08 June 2026  21:45:19 +0000 (0:00:00.028)       0:01:31.408 ***********
2026-06-08 21:45:19.660394 | controller | skipping: [compute-1]
2026-06-08 21:45:19.660597 | controller |
2026-06-08 21:45:19.660843 | controller | TASK [osp.edpm.edpm_bootstrap : Include packages tasks] ************************
2026-06-08 21:45:19.661087 | controller | Monday 08 June 2026  21:45:19 +0000 (0:00:00.029)       0:01:31.438 ***********
2026-06-08 21:45:19.721786 | controller | included: /home/zuul/.cache/ansible-compat/8075a4/collections/ansible_collections/osp/edpm/roles/edpm_bootstrap/tasks/packages.yml for compute-1
2026-06-08 21:45:19.721989 | controller |
2026-06-08 21:45:19.722267 | controller | TASK [osp.edpm.edpm_bootstrap : Gather facts if they don't exist] **************
2026-06-08 21:45:19.722530 | controller | Monday 08 June 2026  21:45:19 +0000 (0:00:00.061)       0:01:31.499 ***********
2026-06-08 21:45:19.757232 | controller | skipping: [compute-1]
2026-06-08 21:45:19.757511 | controller |
2026-06-08 21:45:19.757771 | controller | TASK [osp.edpm.edpm_bootstrap : Install release version package] ***************
2026-06-08 21:45:19.758018 | controller | Monday 08 June 2026  21:45:19 +0000 (0:00:00.035)       0:01:31.535 ***********
2026-06-08 21:45:19.813967 | controller | skipping: [compute-1]
2026-06-08 21:45:19.814155 | controller |
2026-06-08 21:45:19.814449 | controller | TASK [osp.edpm.edpm_bootstrap : Install required packages to bootstrap EDPM] ***
2026-06-08 21:45:19.814707 | controller | Monday 08 June 2026  21:45:19 +0000 (0:00:00.056)       0:01:31.591 ***********
2026-06-08 21:46:16.988659 | controller | changed: [compute-1]
2026-06-08 21:46:16.988706 | controller |
2026-06-08 21:46:16.988716 | controller | TASK [osp.edpm.edpm_bootstrap : Ensure packages are actually well installed] ***
2026-06-08 21:46:16.988726 | controller | Monday 08 June 2026  21:46:16 +0000 (0:00:57.173)       0:02:28.764 ***********
2026-06-08 21:46:18.280459 | controller | ok: [compute-1]
2026-06-08 21:46:18.280530 | controller |
2026-06-08 21:46:18.280831 | controller | TASK [osp.edpm.edpm_bootstrap : Set selinux state] *****************************
2026-06-08 21:46:18.280881 | controller | Monday 08 June 2026  21:46:18 +0000 (0:00:01.292)       0:02:30.057 ***********
2026-06-08 21:46:18.855505 | controller | ok: [compute-1]
2026-06-08 21:46:18.855577 | controller |
2026-06-08 21:46:18.855591 | controller | TASK [osp.edpm.edpm_bootstrap : Include swap tasks] ****************************
2026-06-08 21:46:18.855601 | controller | Monday 08 June 2026  21:46:18 +0000 (0:00:00.574)       0:02:30.632 ***********
2026-06-08 21:46:18.923004 | controller | included: /home/zuul/.cache/ansible-compat/8075a4/collections/ansible_collections/osp/edpm/roles/edpm_bootstrap/tasks/swap.yml for compute-1
2026-06-08 21:46:18.923047 | controller |
2026-06-08 21:46:18.923057 | controller | TASK [osp.edpm.edpm_bootstrap : Gather ansible_local facts] ********************
2026-06-08 21:46:18.923066 | controller | Monday 08 June 2026  21:46:18 +0000 (0:00:00.068)       0:02:30.700 ***********
2026-06-08 21:46:18.959101 | controller | skipping: [compute-1]
2026-06-08 21:46:18.959127 | controller |
2026-06-08 21:46:18.959136 | controller | TASK [osp.edpm.edpm_bootstrap : Set swap path] *********************************
2026-06-08 21:46:18.959144 | controller | Monday 08 June 2026  21:46:18 +0000 (0:00:00.036)       0:02:30.736 ***********
2026-06-08 21:46:19.043575 | controller | ok: [compute-1]
2026-06-08 21:46:19.043661 | controller |
2026-06-08 21:46:19.043676 | controller | TASK [osp.edpm.edpm_bootstrap : Create swapfile if needed] *********************
2026-06-08 21:46:19.043691 | controller | Monday 08 June 2026  21:46:19 +0000 (0:00:00.083)       0:02:30.820 ***********
2026-06-08 21:46:19.864506 | controller | changed: [compute-1]
2026-06-08 21:46:19.864558 | controller |
2026-06-08 21:46:19.864783 | controller | TASK [osp.edpm.edpm_bootstrap : Set permission on swapfile] ********************
2026-06-08 21:46:19.864823 | controller | Monday 08 June 2026  21:46:19 +0000 (0:00:00.821)       0:02:31.641 ***********
2026-06-08 21:46:20.319543 | controller | ok: [compute-1]
2026-06-08 21:46:20.319583 | controller |
2026-06-08 21:46:20.319594 | controller | TASK [osp.edpm.edpm_bootstrap : Enable swapfile on fstab] **********************
2026-06-08 21:46:20.319603 | controller | Monday 08 June 2026  21:46:20 +0000 (0:00:00.454)       0:02:32.096 ***********
2026-06-08 21:46:20.847449 | controller | changed: [compute-1]
2026-06-08 21:46:20.847500 | controller |
2026-06-08 21:46:20.847751 | controller | TASK [osp.edpm.edpm_bootstrap : Check if partition exists by its label] ********
2026-06-08 21:46:20.847779 | controller | Monday 08 June 2026  21:46:20 +0000 (0:00:00.528)       0:02:32.625 ***********
2026-06-08 21:46:20.899403 | controller | skipping: [compute-1]
2026-06-08 21:46:20.899461 | controller |
2026-06-08 21:46:20.899875 | controller | TASK [osp.edpm.edpm_bootstrap : Enable swap partition on fstab] ****************
2026-06-08 21:46:20.899940 | controller | Monday 08 June 2026  21:46:20 +0000 (0:00:00.051)       0:02:32.676 ***********
2026-06-08 21:46:20.952441 | controller | skipping: [compute-1]
2026-06-08 21:46:20.952465 | controller |
2026-06-08 21:46:20.952474 | controller | TASK [osp.edpm.edpm_bootstrap : Gather swap partition by its type] *************
2026-06-08 21:46:20.952496 | controller | Monday 08 June 2026  21:46:20 +0000 (0:00:00.052)       0:02:32.729 ***********
2026-06-08 21:46:21.002730 | controller | skipping: [compute-1]
2026-06-08 21:46:21.002963 | controller |
2026-06-08 21:46:21.003200 | controller | TASK [osp.edpm.edpm_bootstrap : Enable swap partitions in fstab] ***************
2026-06-08 21:46:21.003475 | controller | Monday 08 June 2026  21:46:20 +0000 (0:00:00.051)       0:02:32.780 ***********
2026-06-08 21:46:21.062525 | controller | skipping: [compute-1]
2026-06-08 21:46:21.062783 | controller |
2026-06-08 21:46:21.063036 | controller | TASK [osp.edpm.edpm_bootstrap : Activate swap partitions] **********************
2026-06-08 21:46:21.063412 | controller | Monday 08 June 2026  21:46:21 +0000 (0:00:00.059)       0:02:32.840 ***********
2026-06-08 21:46:21.115676 | controller | skipping: [compute-1]
2026-06-08 21:46:21.115852 | controller |
2026-06-08 21:46:21.116146 | controller | TASK [osp.edpm.edpm_bootstrap : Check if CA cert file exists] ******************
2026-06-08 21:46:21.116461 | controller | Monday 08 June 2026  21:46:21 +0000 (0:00:00.053)       0:02:32.893 ***********
2026-06-08 21:46:21.388191 | controller | ok: [compute-1 -> localhost]
2026-06-08 21:46:21.388231 | controller |
2026-06-08 21:46:21.388267 | controller | TASK [osp.edpm.edpm_bootstrap : Ensure that the CA destination directory exists] ***
2026-06-08 21:46:21.388281 | controller | Monday 08 June 2026  21:46:21 +0000 (0:00:00.272)       0:02:33.165 ***********
2026-06-08 21:46:21.414218 | controller | skipping: [compute-1]
2026-06-08 21:46:21.414286 | controller |
2026-06-08 21:46:21.414299 | controller | TASK [osp.edpm.edpm_bootstrap : Copy CA certs to the standard location on the compute node] ***
2026-06-08 21:46:21.414308 | controller | Monday 08 June 2026  21:46:21 +0000 (0:00:00.026)       0:02:33.191 ***********
2026-06-08 21:46:21.440446 | controller | skipping: [compute-1]
2026-06-08 21:46:21.440483 | controller |
2026-06-08 21:46:21.440493 | controller | TASK [osp.edpm.edpm_bootstrap : Gather ansible_local facts] ********************
2026-06-08 21:46:21.440501 | controller | Monday 08 June 2026  21:46:21 +0000 (0:00:00.025)       0:02:33.217 ***********
2026-06-08 21:46:21.487146 | controller | skipping: [compute-1]
2026-06-08 21:46:21.487421 | controller |
2026-06-08 21:46:21.487679 | controller | TASK [osp.edpm.edpm_bootstrap : Check FIPS status] *****************************
2026-06-08 21:46:21.487929 | controller | Monday 08 June 2026  21:46:21 +0000 (0:00:00.047)       0:02:33.265 ***********
2026-06-08 21:46:21.524115 | controller | skipping: [compute-1]
2026-06-08 21:46:21.524404 | controller |
2026-06-08 21:46:21.524706 | controller | TASK [osp.edpm.edpm_bootstrap : Warn for changing FIPS status for bootc] *******
2026-06-08 21:46:21.524979 | controller | Monday 08 June 2026  21:46:21 +0000 (0:00:00.037)       0:02:33.302 ***********
2026-06-08 21:46:21.581623 | controller | skipping: [compute-1]
2026-06-08 21:46:21.581906 | controller |
2026-06-08 21:46:21.582228 | controller | TASK [osp.edpm.edpm_bootstrap : Enable FIPS] ***********************************
2026-06-08 21:46:21.582560 | controller | Monday 08 June 2026  21:46:21 +0000 (0:00:00.057)       0:02:33.359 ***********
2026-06-08 21:46:21.633383 | controller | skipping: [compute-1]
2026-06-08 21:46:21.633726 | controller |
2026-06-08 21:46:21.634093 | controller | TASK [osp.edpm.edpm_bootstrap : Disable FIPS] **********************************
2026-06-08 21:46:21.634501 | controller | Monday 08 June 2026  21:46:21 +0000 (0:00:00.051)       0:02:33.411 ***********
2026-06-08 21:46:21.691587 | controller | skipping: [compute-1]
2026-06-08 21:46:21.691748 | controller |
2026-06-08 21:46:21.692012 | controller | TASK [osp.edpm.edpm_bootstrap : Create directory required by edpm-reboot role] ***
2026-06-08 21:46:21.692193 | controller | Monday 08 June 2026  21:46:21 +0000 (0:00:00.058)       0:02:33.469 ***********
2026-06-08 21:46:21.750809 | controller | skipping: [compute-1]
2026-06-08 21:46:21.750872 | controller |
2026-06-08 21:46:21.751002 | controller | TASK [osp.edpm.edpm_bootstrap : Create required file to enforce a reboot] ******
2026-06-08 21:46:21.751133 | controller | Monday 08 June 2026  21:46:21 +0000 (0:00:00.059)       0:02:33.528 ***********
2026-06-08 21:46:21.799184 | controller | skipping: [compute-1]
2026-06-08 21:46:21.799288 | controller |
2026-06-08 21:46:21.799402 | controller | TASK [Call edpm_reboot role] ***************************************************
2026-06-08 21:46:21.799511 | controller | Monday 08 June 2026  21:46:21 +0000 (0:00:00.048)       0:02:33.577 ***********
2026-06-08 21:46:21.836697 | controller | skipping: [compute-1]
2026-06-08 21:46:21.836793 | controller |
2026-06-08 21:46:21.836917 | controller | TASK [osp.edpm.edpm_bootstrap : Ensure that the proper FIPS status is enabled] ***
2026-06-08 21:46:21.837025 | controller | Monday 08 June 2026  21:46:21 +0000 (0:00:00.037)       0:02:33.615 ***********
2026-06-08 21:46:21.899300 | controller | skipping: [compute-1]
2026-06-08 21:46:21.899458 | controller |
2026-06-08 21:46:21.899600 | controller | TASK [osp.edpm.edpm_bootstrap : Check if the LVM devices file exists] **********
2026-06-08 21:46:21.899707 | controller | Monday 08 June 2026  21:46:21 +0000 (0:00:00.062)       0:02:33.677 ***********
2026-06-08 21:46:22.177208 | controller | ok: [compute-1]
2026-06-08 21:46:22.177283 | controller |
2026-06-08 21:46:22.177301 | controller | TASK [osp.edpm.edpm_bootstrap : Import any existing LVM devices] ***************
2026-06-08 21:46:22.177314 | controller | Monday 08 June 2026  21:46:22 +0000 (0:00:00.275)       0:02:33.953 ***********
2026-06-08 21:46:22.535136 | controller | changed: [compute-1]
2026-06-08 21:46:22.535176 | controller |
2026-06-08 21:46:22.535187 | controller | TASK [osp.edpm.edpm_bootstrap : Create an empty LVM devices file] **************
2026-06-08 21:46:22.535196 | controller | Monday 08 June 2026  21:46:22 +0000 (0:00:00.359)       0:02:34.312 ***********
2026-06-08 21:46:22.857725 | controller | changed: [compute-1]
2026-06-08 21:46:22.857769 | controller |
2026-06-08 21:46:22.857797 | controller | RUNNING HANDLER [osp.edpm.edpm_bootstrap : Setup linux swap area on file if needed] ***
2026-06-08 21:46:22.857808 | controller | Monday 08 June 2026  21:46:22 +0000 (0:00:00.322)       0:02:34.634 ***********
2026-06-08 21:46:24.683282 | controller | changed: [compute-1]
2026-06-08 21:46:24.683322 | controller |
2026-06-08 21:46:24.683332 | controller | RUNNING HANDLER [osp.edpm.edpm_bootstrap : Activate swap] **********************
2026-06-08 21:46:24.683341 | controller | Monday 08 June 2026  21:46:24 +0000 (0:00:01.825)       0:02:36.460 ***********
2026-06-08 21:46:25.003358 | controller | changed: [compute-1]
2026-06-08 21:46:25.003398 | controller |
2026-06-08 21:46:25.003409 | controller | PLAY RECAP *********************************************************************
2026-06-08 21:46:25.003418 | controller | compute-1                  : ok=51   changed=22   unreachable=0    failed=0    skipped=31   rescued=0    ignored=1
2026-06-08 21:46:25.003426 | controller |
2026-06-08 21:46:25.003434 | controller | Monday 08 June 2026  21:46:24 +0000 (0:00:00.317)       0:02:36.777 ***********
2026-06-08 21:46:25.003443 | controller | ===============================================================================
2026-06-08 21:46:25.003450 | controller | ../../../../molecule/common/test_deps : Install edpm packages ---------- 58.92s
2026-06-08 21:46:25.003458 | controller | osp.edpm.edpm_bootstrap : Install required packages to bootstrap EDPM -- 57.17s
2026-06-08 21:46:25.003466 | controller | ../../../../molecule/common/test_deps : Install repo-setup pre-requirements --- 5.25s
2026-06-08 21:46:25.003474 | controller | osp.edpm.edpm_bootstrap : Download needed packages ---------------------- 3.31s
2026-06-08 21:46:25.003481 | controller | osp.edpm.edpm_bootstrap : Gather services facts ------------------------- 3.11s
2026-06-08 21:46:25.003489 | controller | ../../../../molecule/common/test_deps : Install extra packages ---------- 1.88s
2026-06-08 21:46:25.003497 | controller | ../../../../molecule/common/test_deps : Install selinux python libs ----- 1.86s
2026-06-08 21:46:25.003505 | controller | osp.edpm.edpm_bootstrap : Setup linux swap area on file if needed ------- 1.83s
2026-06-08 21:46:25.003513 | controller | Install os-net-config --------------------------------------------------- 1.82s
2026-06-08 21:46:25.005462 | controller | ../../../../molecule/common/test_deps : Install python yaml libs -------- 1.81s
2026-06-08 21:46:25.005510 | controller | ../../../../molecule/common/test_deps : Install repo-setup -------------- 1.63s
2026-06-08 21:46:25.005520 | controller | osp.edpm.edpm_bootstrap : Download legacy network service package ------- 1.51s
2026-06-08 21:46:25.005528 | controller | osp.edpm.edpm_bootstrap : Ensure packages are actually well installed --- 1.29s
2026-06-08 21:46:25.005536 | controller | ../../../../molecule/common/test_deps : Get repo-setup repository ------- 1.12s
2026-06-08 21:46:25.005544 | controller | Force systemd to reread configs ----------------------------------------- 0.90s
2026-06-08 21:46:25.005552 | controller | osp.edpm.edpm_bootstrap : Create swapfile if needed --------------------- 0.82s
2026-06-08 21:46:25.005574 | controller | ../../../../molecule/common/test_deps : Create repos -------------------- 0.75s
2026-06-08 21:46:25.005583 | controller | ../../../../molecule/common/test_deps : Gather facts if they don't exist --- 0.64s
2026-06-08 21:46:25.005599 | controller | ../../../../molecule/common/test_deps : Create default repo file -------- 0.59s
2026-06-08 21:46:25.005778 | controller | osp.edpm.edpm_bootstrap : Set selinux state ----------------------------- 0.57s
2026-06-08 21:46:25.116652 | controller | INFO     Running default > converge
2026-06-08 21:46:25.608455 | controller |
2026-06-08 21:46:25.608646 | controller | PLAY [Converge] ****************************************************************
2026-06-08 21:46:25.608920 | controller |
2026-06-08 21:46:25.609169 | controller | TASK [set basic user fact] *****************************************************
2026-06-08 21:46:25.609435 | controller | Monday 08 June 2026  21:46:25 +0000 (0:00:00.016)       0:00:00.016 ***********
2026-06-08 21:46:25.673150 | controller | ok: [compute-1]
2026-06-08 21:46:25.673424 | controller |
2026-06-08 21:46:25.673676 | controller | TASK [Download required role packages] *****************************************
2026-06-08 21:46:25.673921 | controller | Monday 08 June 2026  21:46:25 +0000 (0:00:00.065)       0:00:00.081 ***********
2026-06-08 21:46:25.715584 | controller |
2026-06-08 21:46:25.715883 | controller | TASK [osp.edpm.edpm_frr : Download needed container images] ********************
2026-06-08 21:46:25.716164 | controller | Monday 08 June 2026  21:46:25 +0000 (0:00:00.042)       0:00:00.123 ***********
2026-06-08 21:46:32.062453 | controller | changed: [compute-1] => (item=quay.io/podified-antelope-centos9/openstack-frr:current-podified)
2026-06-08 21:46:32.062503 | controller |
2026-06-08 21:46:32.062514 | controller | TASK [ansible.builtin.include_role : osp.edpm.edpm_frr] ************************
2026-06-08 21:46:32.062522 | controller | Monday 08 June 2026  21:46:32 +0000 (0:00:06.346)       0:00:06.469 ***********
2026-06-08 21:46:32.149973 | controller |
2026-06-08 21:46:32.150010 | controller | TASK [osp.edpm.edpm_frr : Validating arguments against arg spec 'main' - The main entry point for the edpm_frr role.] ***
2026-06-08 21:46:32.150017 | controller | Monday 08 June 2026  21:46:32 +0000 (0:00:00.088)       0:00:06.557 ***********
2026-06-08 21:46:32.217477 | controller | ok: [compute-1]
2026-06-08 21:46:32.217637 | controller |
2026-06-08 21:46:32.217659 | controller | TASK [osp.edpm.edpm_frr : Gather user fact] ************************************
2026-06-08 21:46:32.217669 | controller | Monday 08 June 2026  21:46:32 +0000 (0:00:00.067)       0:00:06.625 ***********
2026-06-08 21:46:32.253576 | controller | skipping: [compute-1]
2026-06-08 21:46:32.253628 | controller |
2026-06-08 21:46:32.254036 | controller | TASK [osp.edpm.edpm_frr : Create persistent directories] ***********************
2026-06-08 21:46:32.254076 | controller | Monday 08 June 2026  21:46:32 +0000 (0:00:00.036)       0:00:06.661 ***********
2026-06-08 21:46:33.141519 | controller | changed: [compute-1] => (item={'path': '/var/lib/openstack/frr', 'setype': 'container_file_t', 'mode': '0750'})
2026-06-08 21:46:33.141571 | controller | changed: [compute-1] => (item={'path': '/run/frr', 'setype': 'container_file_t', 'mode': '0755'})
2026-06-08 21:46:33.141586 | controller | changed: [compute-1] => (item={'path': '/var/lib/edpm-config/firewall', 'setype': 'container_file_t', 'mode': '0750'})
2026-06-08 21:46:33.141599 | controller |
2026-06-08 21:46:33.141612 | controller | TASK [osp.edpm.edpm_frr : Ensure /run/frr is present upon reboot] **************
2026-06-08 21:46:33.141632 | controller | Monday 08 June 2026  21:46:33 +0000 (0:00:00.886)       0:00:07.547 ***********
2026-06-08 21:46:33.887501 | controller | changed: [compute-1]
2026-06-08 21:46:33.887536 | controller |
2026-06-08 21:46:33.887543 | controller | TASK [osp.edpm.edpm_frr : Gather SELinux fact if needed] ***********************
2026-06-08 21:46:33.887557 | controller | Monday 08 June 2026  21:46:33 +0000 (0:00:00.746)       0:00:08.294 ***********
2026-06-08 21:46:34.454458 | controller | ok: [compute-1]
2026-06-08 21:46:34.454503 | controller |
2026-06-08 21:46:34.454805 | controller | TASK [osp.edpm.edpm_frr : Create directory /var/lib/openstack/frr] *************
2026-06-08 21:46:34.454843 | controller | Monday 08 June 2026  21:46:34 +0000 (0:00:00.567)       0:00:08.861 ***********
2026-06-08 21:46:34.769513 | controller | changed: [compute-1]
2026-06-08 21:46:34.769558 | controller |
2026-06-08 21:46:34.769584 | controller | TASK [osp.edpm.edpm_frr : Inject firewall rules for frr BGP and BFD] ***********
2026-06-08 21:46:34.769733 | controller | Monday 08 June 2026  21:46:34 +0000 (0:00:00.314)       0:00:09.176 ***********
2026-06-08 21:46:35.167333 | controller | changed: [compute-1]
2026-06-08 21:46:35.167375 | controller |
2026-06-08 21:46:35.167385 | controller | TASK [Configure firewall for frr BGP and BFD] **********************************
2026-06-08 21:46:35.167394 | controller | Monday 08 June 2026  21:46:35 +0000 (0:00:00.397)       0:00:09.574 ***********
2026-06-08 21:46:35.244401 | controller |
2026-06-08 21:46:35.244441 | controller | TASK [osp.edpm.edpm_nftables : Create snippets directory] **********************
2026-06-08 21:46:35.244569 | controller | Monday 08 June 2026  21:46:35 +0000 (0:00:00.077)       0:00:09.651 ***********
2026-06-08 21:46:35.537127 | controller | changed: [compute-1]
2026-06-08 21:46:35.537160 | controller |
2026-06-08 21:46:35.537167 | controller | TASK [osp.edpm.edpm_nftables : Push default ruleset snipet] ********************
2026-06-08 21:46:35.537172 | controller | Monday 08 June 2026  21:46:35 +0000 (0:00:00.292)       0:00:09.944 ***********
2026-06-08 21:46:36.044449 | controller | changed: [compute-1]
2026-06-08 21:46:36.044490 | controller |
2026-06-08 21:46:36.044500 | controller | TASK [osp.edpm.edpm_nftables : Write user rules snippet] ***********************
2026-06-08 21:46:36.044729 | controller | Monday 08 June 2026  21:46:36 +0000 (0:00:00.506)       0:00:10.451 ***********
2026-06-08 21:46:36.541218 | controller | changed: [compute-1]
2026-06-08 21:46:36.541287 | controller |
2026-06-08 21:46:36.541299 | controller | TASK [osp.edpm.edpm_nftables : Push empty ruleset] *****************************
2026-06-08 21:46:36.541308 | controller | Monday 08 June 2026  21:46:36 +0000 (0:00:00.496)       0:00:10.947 ***********
2026-06-08 21:46:37.010480 | controller | changed: [compute-1]
2026-06-08 21:46:37.010530 | controller |
2026-06-08 21:46:37.010541 | controller | TASK [osp.edpm.edpm_nftables : Load empty ruleset] *****************************
2026-06-08 21:46:37.010681 | controller | Monday 08 June 2026  21:46:37 +0000 (0:00:00.469)       0:00:11.417 ***********
2026-06-08 21:46:37.473435 | controller | changed: [compute-1]
2026-06-08 21:46:37.473486 | controller |
2026-06-08 21:46:37.473498 | controller | TASK [osp.edpm.edpm_nftables : Delete iptables-compat tables if load failed due to bare chains] ***
2026-06-08 21:46:37.473694 | controller | Monday 08 June 2026  21:46:37 +0000 (0:00:00.463)       0:00:11.880 ***********
2026-06-08 21:46:37.576460 | controller | skipping: [compute-1] => (item=inet filter)
2026-06-08 21:46:37.576499 | controller | skipping: [compute-1] => (item=inet raw)
2026-06-08 21:46:37.576506 | controller | skipping: [compute-1] => (item=inet nat)
2026-06-08 21:46:37.576511 | controller | skipping: [compute-1] => (item=ip filter)
2026-06-08 21:46:37.576543 | controller | skipping: [compute-1] => (item=ip raw)
2026-06-08 21:46:37.577152 | controller | skipping: [compute-1] => (item=ip nat)
2026-06-08 21:46:37.577184 | controller | skipping: [compute-1] => (item=ip6 filter)
2026-06-08 21:46:37.577188 | controller | skipping: [compute-1] => (item=ip6 raw)
2026-06-08 21:46:37.577193 | controller | skipping: [compute-1]
2026-06-08 21:46:37.577198 | controller |
2026-06-08 21:46:37.577203 | controller | TASK [osp.edpm.edpm_nftables : Retry loading empty ruleset after cleanup] ******
2026-06-08 21:46:37.577207 | controller | Monday 08 June 2026  21:46:37 +0000 (0:00:00.103)       0:00:11.983 ***********
2026-06-08 21:46:37.617497 | controller | skipping: [compute-1]
2026-06-08 21:46:37.617526 | controller |
2026-06-08 21:46:37.617532 | controller | TASK [osp.edpm.edpm_nftables : Get current nftables content] *******************
2026-06-08 21:46:37.617537 | controller | Monday 08 June 2026  21:46:37 +0000 (0:00:00.040)       0:00:12.024 ***********
2026-06-08 21:46:37.950586 | controller | changed: [compute-1]
2026-06-08 21:46:37.950641 | controller |
2026-06-08 21:46:37.950651 | controller | TASK [osp.edpm.edpm_nftables : Load firewall snippets] *************************
2026-06-08 21:46:37.950660 | controller | Monday 08 June 2026  21:46:37 +0000 (0:00:00.333)       0:00:12.357 ***********
2026-06-08 21:46:38.342422 | controller | ok: [compute-1]
2026-06-08 21:46:38.342965 | controller |
2026-06-08 21:46:38.343017 | controller | TASK [osp.edpm.edpm_nftables : Generate chain jumps] ***************************
2026-06-08 21:46:38.343032 | controller | Monday 08 June 2026  21:46:38 +0000 (0:00:00.392)       0:00:12.750 ***********
2026-06-08 21:46:38.856465 | controller | changed: [compute-1]
2026-06-08 21:46:38.856520 | controller |
2026-06-08 21:46:38.856527 | controller | TASK [osp.edpm.edpm_nftables : Generate chain jumps] ***************************
2026-06-08 21:46:38.856653 | controller | Monday 08 June 2026  21:46:38 +0000 (0:00:00.513)       0:00:13.263 ***********
2026-06-08 21:46:39.383416 | controller | changed: [compute-1]
2026-06-08 21:46:39.383480 | controller |
2026-06-08 21:46:39.383676 | controller | TASK [osp.edpm.edpm_nftables : Generate nft flushes] ***************************
2026-06-08 21:46:39.383715 | controller | Monday 08 June 2026  21:46:39 +0000 (0:00:00.526)       0:00:13.790 ***********
2026-06-08 21:46:39.897694 | controller | changed: [compute-1]
2026-06-08 21:46:39.897722 | controller |
2026-06-08 21:46:39.897726 | controller | TASK [osp.edpm.edpm_nftables : Generate nft edpm chains] ***********************
2026-06-08 21:46:39.897731 | controller | Monday 08 June 2026  21:46:39 +0000 (0:00:00.514)       0:00:14.304 ***********
2026-06-08 21:46:40.442413 | controller | changed: [compute-1]
2026-06-08 21:46:40.442450 | controller |
2026-06-08 21:46:40.442460 | controller | TASK [osp.edpm.edpm_nftables : Generate nft ruleset in static file] ************
2026-06-08 21:46:40.442475 | controller | Monday 08 June 2026  21:46:40 +0000 (0:00:00.544)       0:00:14.849 ***********
2026-06-08 21:46:41.056262 | controller | changed: [compute-1]
2026-06-08 21:46:41.056316 | controller |
2026-06-08 21:46:41.056327 | controller | TASK [osp.edpm.edpm_nftables : Create a sentinel file when nft rules are changed] ***
2026-06-08 21:46:41.056337 | controller | Monday 08 June 2026  21:46:41 +0000 (0:00:00.613)       0:00:15.462 ***********
2026-06-08 21:46:41.354592 | controller | changed: [compute-1]
2026-06-08 21:46:41.354638 | controller |
2026-06-08 21:46:41.354648 | controller | TASK [osp.edpm.edpm_nftables : Validate all of the generated content before loading] ***
2026-06-08 21:46:41.354657 | controller | Monday 08 June 2026  21:46:41 +0000 (0:00:00.299)       0:00:15.762 ***********
2026-06-08 21:46:41.748224 | controller | changed: [compute-1]
2026-06-08 21:46:41.748292 | controller |
2026-06-08 21:46:41.748303 | controller | TASK [osp.edpm.edpm_nftables : Ensure we load our different nft rulesets on boot] ***
2026-06-08 21:46:41.748313 | controller | Monday 08 June 2026  21:46:41 +0000 (0:00:00.392)       0:00:16.154 ***********
2026-06-08 21:46:42.146501 | controller | changed: [compute-1]
2026-06-08 21:46:42.146541 | controller |
2026-06-08 21:46:42.147037 | controller | TASK [Apply firewall for frr BGP and BFD] **************************************
2026-06-08 21:46:42.147081 | controller | Monday 08 June 2026  21:46:42 +0000 (0:00:00.398)       0:00:16.553 ***********
2026-06-08 21:46:42.224434 | controller |
2026-06-08 21:46:42.224479 | controller | TASK [osp.edpm.edpm_nftables : Inject our custom chains in nftables] ***********
2026-06-08 21:46:42.224636 | controller | Monday 08 June 2026  21:46:42 +0000 (0:00:00.078)       0:00:16.632 ***********
2026-06-08 21:46:42.508416 | controller | changed: [compute-1]
2026-06-08 21:46:42.508454 | controller |
2026-06-08 21:46:42.508465 | controller | TASK [osp.edpm.edpm_nftables : Check if rules are changed] *********************
2026-06-08 21:46:42.508616 | controller | Monday 08 June 2026  21:46:42 +0000 (0:00:00.283)       0:00:16.915 ***********
2026-06-08 21:46:42.789168 | controller | ok: [compute-1]
2026-06-08 21:46:42.789208 | controller |
2026-06-08 21:46:42.789218 | controller | TASK [osp.edpm.edpm_nftables : Reload ruleset] *********************************
2026-06-08 21:46:42.789227 | controller | Monday 08 June 2026  21:46:42 +0000 (0:00:00.280)       0:00:17.196 ***********
2026-06-08 21:46:43.098701 | controller | changed: [compute-1]
2026-06-08 21:46:43.098743 | controller |
2026-06-08 21:46:43.098753 | controller | TASK [osp.edpm.edpm_nftables : Delete nft_ruleset_changed file] ****************
2026-06-08 21:46:43.098762 | controller | Monday 08 June 2026  21:46:43 +0000 (0:00:00.309)       0:00:17.505 ***********
2026-06-08 21:46:43.380963 | controller | changed: [compute-1]
2026-06-08 21:46:43.380990 | controller |
2026-06-08 21:46:43.380995 | controller | TASK [osp.edpm.edpm_frr : Gather facts if they don't exist] ********************
2026-06-08 21:46:43.381000 | controller | Monday 08 June 2026  21:46:43 +0000 (0:00:00.282)       0:00:17.788 ***********
2026-06-08 21:46:43.788521 | controller | ok: [compute-1]
2026-06-08 21:46:43.789236 | controller |
2026-06-08 21:46:43.789322 | controller | TASK [osp.edpm.edpm_frr : Configure FRR for BGP underlay] **********************
2026-06-08 21:46:43.789333 | controller | Monday 08 June 2026  21:46:43 +0000 (0:00:00.407)       0:00:18.195 ***********
2026-06-08 21:46:43.854468 | controller | included: /home/zuul/.cache/ansible-compat/8075a4/collections/ansible_collections/osp/edpm/roles/edpm_frr/tasks/configure_bgp.yml for compute-1
2026-06-08 21:46:43.855225 | controller |
2026-06-08 21:46:43.855319 | controller | TASK [osp.edpm.edpm_frr : Identify the real interfaces via os-net-config] ******
2026-06-08 21:46:43.855336 | controller | Monday 08 June 2026  21:46:43 +0000 (0:00:00.066)       0:00:18.262 ***********
2026-06-08 21:46:44.490861 | controller | changed: [compute-1]
2026-06-08 21:46:44.490891 | controller |
2026-06-08 21:46:44.490897 | controller | TASK [osp.edpm.edpm_frr : Os-net-config from json] *****************************
2026-06-08 21:46:44.490903 | controller | Monday 08 June 2026  21:46:44 +0000 (0:00:00.636)       0:00:18.898 ***********
2026-06-08 21:46:44.519318 | controller | ok: [compute-1]
2026-06-08 21:46:44.519346 | controller |
2026-06-08 21:46:44.519358 | controller | TASK [osp.edpm.edpm_frr : Assert either edpm_frr_bgp_peers or edpm_frr_bgp_uplinks configured] ***
2026-06-08 21:46:44.519367 | controller | Monday 08 June 2026  21:46:44 +0000 (0:00:00.028)       0:00:18.926 ***********
2026-06-08 21:46:44.583391 | controller | ok: [compute-1] => changed=false
2026-06-08 21:46:44.583445 | controller |   msg: All assertions passed
2026-06-08 21:46:44.583456 | controller |
2026-06-08 21:46:44.583465 | controller | TASK [osp.edpm.edpm_frr : Construct FRR uplink interfaces from os-net-config mappings] ***
2026-06-08 21:46:44.583474 | controller | Monday 08 June 2026  21:46:44 +0000 (0:00:00.063)       0:00:18.990 ***********
2026-06-08 21:46:44.634468 | controller | skipping: [compute-1]
2026-06-08 21:46:44.634506 | controller |
2026-06-08 21:46:44.634513 | controller | TASK [osp.edpm.edpm_frr : Failed to construct FRR uplink interfaces] ***********
2026-06-08 21:46:44.634681 | controller | Monday 08 June 2026  21:46:44 +0000 (0:00:00.051)       0:00:19.042 ***********
2026-06-08 21:46:44.688907 | controller | skipping: [compute-1]
2026-06-08 21:46:44.689146 | controller |
2026-06-08 21:46:44.689424 | controller | TASK [osp.edpm.edpm_frr : Configure FRR] ***************************************
2026-06-08 21:46:44.689685 | controller | Monday 08 June 2026  21:46:44 +0000 (0:00:00.055)       0:00:19.097 ***********
2026-06-08 21:46:45.258338 | controller | changed: [compute-1]
2026-06-08 21:46:45.258385 | controller |
2026-06-08 21:46:45.258392 | controller | TASK [osp.edpm.edpm_frr : Configure FRR for EVPN driver mode] ******************
2026-06-08 21:46:45.258539 | controller | Monday 08 June 2026  21:46:45 +0000 (0:00:00.568)       0:00:19.665 ***********
2026-06-08 21:46:45.291466 | controller | skipping: [compute-1]
2026-06-08 21:46:45.291511 | controller |
2026-06-08 21:46:45.291522 | controller | TASK [osp.edpm.edpm_frr : Configure FRR daemons] *******************************
2026-06-08 21:46:45.291530 | controller | Monday 08 June 2026  21:46:45 +0000 (0:00:00.033)       0:00:19.698 ***********
2026-06-08 21:46:45.797235 | controller | changed: [compute-1]
2026-06-08 21:46:45.797304 | controller |
2026-06-08 21:46:45.797316 | controller | TASK [osp.edpm.edpm_container_manage : Create /var/local/libexec dir] **********
2026-06-08 21:46:45.797326 | controller | Monday 08 June 2026  21:46:45 +0000 (0:00:00.505)       0:00:20.204 ***********
2026-06-08 21:46:46.071863 | controller | changed: [compute-1]
2026-06-08 21:46:46.071899 | controller |
2026-06-08 21:46:46.071906 | controller | TASK [osp.edpm.edpm_container_manage : Deploy edpm-container-shutdown and edpm-start-podman-container] ***
2026-06-08 21:46:46.071912 | controller | Monday 08 June 2026  21:46:46 +0000 (0:00:00.275)       0:00:20.479 ***********
2026-06-08 21:46:47.047156 | controller | changed: [compute-1] => (item=edpm-container-shutdown)
2026-06-08 21:46:47.047197 | controller | changed: [compute-1] => (item=edpm-start-podman-container)
2026-06-08 21:46:47.047207 | controller |
2026-06-08 21:46:47.047216 | controller | TASK [osp.edpm.edpm_container_manage : Create systemd preset dir] **************
2026-06-08 21:46:47.047225 | controller | Monday 08 June 2026  21:46:47 +0000 (0:00:00.974)       0:00:21.453 ***********
2026-06-08 21:46:47.291359 | controller | changed: [compute-1]
2026-06-08 21:46:47.291402 | controller |
2026-06-08 21:46:47.291413 | controller | TASK [osp.edpm.edpm_container_manage : Create /etc/systemd/system/edpm-container-shutdown.service] ***
2026-06-08 21:46:47.291423 | controller | Monday 08 June 2026  21:46:47 +0000 (0:00:00.244)       0:00:21.697 ***********
2026-06-08 21:46:47.727128 | controller | changed: [compute-1]
2026-06-08 21:46:47.727155 | controller |
2026-06-08 21:46:47.727161 | controller | TASK [osp.edpm.edpm_container_manage : Create /etc/systemd/system-preset/91-edpm-container-shutdown.preset] ***
2026-06-08 21:46:47.727172 | controller | Monday 08 June 2026  21:46:47 +0000 (0:00:00.436)       0:00:22.134 ***********
2026-06-08 21:46:48.186435 | controller | changed: [compute-1]
2026-06-08 21:46:48.187073 | controller |
2026-06-08 21:46:48.187112 | controller | TASK [osp.edpm.edpm_container_manage : Enable and start edpm-container-shutdown] ***
2026-06-08 21:46:48.187123 | controller | Monday 08 June 2026  21:46:48 +0000 (0:00:00.458)       0:00:22.593 ***********
2026-06-08 21:46:49.198476 | controller | changed: [compute-1]
2026-06-08 21:46:49.199182 | controller |
2026-06-08 21:46:49.199231 | controller | TASK [osp.edpm.edpm_container_manage : Create /etc/systemd/system/netns-placeholder.service] ***
2026-06-08 21:46:49.199285 | controller | Monday 08 June 2026  21:46:49 +0000 (0:00:01.011)       0:00:23.605 ***********
2026-06-08 21:46:49.687530 | controller | changed: [compute-1]
2026-06-08 21:46:49.687581 | controller |
2026-06-08 21:46:49.687937 | controller | TASK [osp.edpm.edpm_container_manage : Create /etc/systemd/system-preset/91-netns-placeholder.preset] ***
2026-06-08 21:46:49.687973 | controller | Monday 08 June 2026  21:46:49 +0000 (0:00:00.488)       0:00:24.094 ***********
2026-06-08 21:46:50.192363 | controller | changed: [compute-1]
2026-06-08 21:46:50.192994 | controller |
2026-06-08 21:46:50.193011 | controller | TASK [osp.edpm.edpm_container_manage : Enable and start netns-placeholder] *****
2026-06-08 21:46:50.193019 | controller | Monday 08 June 2026  21:46:50 +0000 (0:00:00.505)       0:00:24.600 ***********
2026-06-08 21:46:51.050291 | controller | changed: [compute-1]
2026-06-08 21:46:51.050345 | controller |
2026-06-08 21:46:51.050370 | controller | TASK [Run frr container] *******************************************************
2026-06-08 21:46:51.050385 | controller | Monday 08 June 2026  21:46:51 +0000 (0:00:00.856)       0:00:25.457 ***********
2026-06-08 21:46:51.113489 | controller |
2026-06-08 21:46:51.113528 | 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-08 21:46:51.113538 | controller | Monday 08 June 2026  21:46:51 +0000 (0:00:00.063)       0:00:25.520 ***********
2026-06-08 21:46:51.204053 | controller | ok: [compute-1]
2026-06-08 21:46:51.204087 | controller |
2026-06-08 21:46:51.204095 | controller | TASK [osp.edpm.edpm_container_standalone : Ensure /var/lib/edpm-config exists with correct ownership] ***
2026-06-08 21:46:51.204101 | controller | Monday 08 June 2026  21:46:51 +0000 (0:00:00.090)       0:00:25.611 ***********
2026-06-08 21:46:51.507469 | controller | changed: [compute-1]
2026-06-08 21:46:51.508090 | controller |
2026-06-08 21:46:51.508132 | controller | TASK [osp.edpm.edpm_container_standalone : Ensure directory exists: /var/lib/kolla/config_files] ***
2026-06-08 21:46:51.508143 | controller | Monday 08 June 2026  21:46:51 +0000 (0:00:00.302)       0:00:25.914 ***********
2026-06-08 21:46:51.785365 | controller | changed: [compute-1]
2026-06-08 21:46:51.785703 | controller |
2026-06-08 21:46:51.785729 | controller | TASK [osp.edpm.edpm_container_standalone : Create kolla config files] **********
2026-06-08 21:46:51.785735 | controller | Monday 08 June 2026  21:46:51 +0000 (0:00:00.278)       0:00:26.193 ***********
2026-06-08 21:46:52.289439 | controller | changed: [compute-1] => (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-08 21:46:52.289482 | controller |
2026-06-08 21:46:52.289709 | controller | TASK [osp.edpm.edpm_container_standalone : Create config file /var/lib/edpm-config/container-startup-config/frr] ***
2026-06-08 21:46:52.289741 | controller | Monday 08 June 2026  21:46:52 +0000 (0:00:00.503)       0:00:26.696 ***********
2026-06-08 21:46:52.579479 | controller | changed: [compute-1]
2026-06-08 21:46:52.579519 | controller |
2026-06-08 21:46:52.579526 | controller | TASK [osp.edpm.edpm_container_standalone : Render container definitions: [frr ]] ***
2026-06-08 21:46:52.579637 | controller | Monday 08 June 2026  21:46:52 +0000 (0:00:00.289)       0:00:26.986 ***********
2026-06-08 21:46:53.098502 | controller | changed: [compute-1] => (item=None)
2026-06-08 21:46:53.098563 | controller | changed: [compute-1]
2026-06-08 21:46:53.098575 | controller |
2026-06-08 21:46:53.098584 | controller | TASK [Run containers: [ frr ]] *************************************************
2026-06-08 21:46:53.098594 | controller | Monday 08 June 2026  21:46:53 +0000 (0:00:00.518)       0:00:27.505 ***********
2026-06-08 21:46:53.153060 | controller |
2026-06-08 21:46:53.153103 | controller | TASK [osp.edpm.edpm_container_manage : Validating arguments against arg spec 'main' - The main entry point for the edpm_container_manage role.] ***
2026-06-08 21:46:53.153115 | controller | Monday 08 June 2026  21:46:53 +0000 (0:00:00.054)       0:00:27.560 ***********
2026-06-08 21:46:53.238921 | controller | ok: [compute-1]
2026-06-08 21:46:53.238959 | controller |
2026-06-08 21:46:53.238967 | controller | TASK [osp.edpm.edpm_container_manage : Find all matching configs configs for in /var/lib/edpm-config/container-startup-config/frr] ***
2026-06-08 21:46:53.238975 | controller | Monday 08 June 2026  21:46:53 +0000 (0:00:00.086)       0:00:27.646 ***********
2026-06-08 21:46:53.599571 | controller | ok: [compute-1]
2026-06-08 21:46:53.599648 | controller |
2026-06-08 21:46:53.600098 | controller | TASK [osp.edpm.edpm_container_manage : Initialize all_containers_hash for this invocation] ***
2026-06-08 21:46:53.600124 | controller | Monday 08 June 2026  21:46:53 +0000 (0:00:00.360)       0:00:28.006 ***********
2026-06-08 21:46:53.706555 | controller | ok: [compute-1]
2026-06-08 21:46:53.706609 | controller |
2026-06-08 21:46:53.706620 | controller | TASK [osp.edpm.edpm_container_manage : Filter configs if container list provided] ***
2026-06-08 21:46:53.706629 | controller | Monday 08 June 2026  21:46:53 +0000 (0:00:00.106)       0:00:28.113 ***********
2026-06-08 21:46:53.820457 | controller | ok: [compute-1] => (item=frr)
2026-06-08 21:46:53.820495 | controller |
2026-06-08 21:46:53.820505 | controller | TASK [osp.edpm.edpm_container_manage : Use all configs if no filter] ***********
2026-06-08 21:46:53.820623 | controller | Monday 08 June 2026  21:46:53 +0000 (0:00:00.114)       0:00:28.227 ***********
2026-06-08 21:46:53.896609 | controller | skipping: [compute-1]
2026-06-08 21:46:53.896968 | controller |
2026-06-08 21:46:53.897345 | controller | TASK [osp.edpm.edpm_container_manage : Update container configs with new config hashes] ***
2026-06-08 21:46:53.897656 | controller | Monday 08 June 2026  21:46:53 +0000 (0:00:00.076)       0:00:28.303 ***********
2026-06-08 21:46:54.269381 | controller | changed: [compute-1]
2026-06-08 21:46:54.270308 | controller |
2026-06-08 21:46:54.270382 | controller | TASK [osp.edpm.edpm_container_manage : Delete orphan containers (DEPRECATED)] ***
2026-06-08 21:46:54.270397 | controller | Monday 08 June 2026  21:46:54 +0000 (0:00:00.373)       0:00:28.677 ***********
2026-06-08 21:46:54.328425 | controller | skipping: [compute-1]
2026-06-08 21:46:54.328459 | controller |
2026-06-08 21:46:54.328467 | controller | TASK [osp.edpm.edpm_container_manage : Create containers from /var/lib/edpm-config/container-startup-config/frr] ***
2026-06-08 21:46:54.328588 | controller | Monday 08 June 2026  21:46:54 +0000 (0:00:00.058)       0:00:28.735 ***********
2026-06-08 21:46:54.374006 | controller | included: /home/zuul/.cache/ansible-compat/8075a4/collections/ansible_collections/osp/edpm/roles/edpm_container_manage/tasks/create.yml for compute-1
2026-06-08 21:46:54.374416 | controller |
2026-06-08 21:46:54.374776 | controller | TASK [osp.edpm.edpm_container_manage : Create containers managed by Podman for /var/lib/edpm-config/container-startup-config/frr] ***
2026-06-08 21:46:54.375196 | controller | Monday 08 June 2026  21:46:54 +0000 (0:00:00.046)       0:00:28.781 ***********
2026-06-08 21:46:55.231125 | controller | changed: [compute-1]
2026-06-08 21:46:55.231164 | controller |
2026-06-08 21:46:55.231174 | controller | TASK [osp.edpm.edpm_container_manage : Check if /etc/sysconfig/podman_drop_in exists] ***
2026-06-08 21:46:55.231183 | controller | Monday 08 June 2026  21:46:55 +0000 (0:00:00.856)       0:00:29.637 ***********
2026-06-08 21:46:55.518459 | controller | ok: [compute-1]
2026-06-08 21:46:55.518503 | controller |
2026-06-08 21:46:55.518513 | controller | TASK [osp.edpm.edpm_container_manage : Set podman_drop_in fact] ****************
2026-06-08 21:46:55.518522 | controller | Monday 08 June 2026  21:46:55 +0000 (0:00:00.286)       0:00:29.924 ***********
2026-06-08 21:46:55.604239 | controller | skipping: [compute-1]
2026-06-08 21:46:55.604306 | controller |
2026-06-08 21:46:55.604317 | controller | TASK [osp.edpm.edpm_container_manage : Manage container systemd services and cleanup old systemd healthchecks for /var/lib/edpm-config/container-startup-config/frr] ***
2026-06-08 21:46:55.604337 | controller | Monday 08 June 2026  21:46:55 +0000 (0:00:00.086)       0:00:30.011 ***********
2026-06-08 21:46:57.686397 | controller | changed: [compute-1]
2026-06-08 21:46:57.687120 | controller |
2026-06-08 21:46:57.687160 | controller | TASK [osp.edpm.edpm_container_standalone : Update service state file] **********
2026-06-08 21:46:57.687173 | controller | Monday 08 June 2026  21:46:57 +0000 (0:00:02.082)       0:00:32.094 ***********
2026-06-08 21:46:57.776455 | controller | included: /home/zuul/.cache/ansible-compat/8075a4/collections/ansible_collections/osp/edpm/roles/edpm_container_standalone/tasks/state_file_update.yml for compute-1
2026-06-08 21:46:57.776897 | controller |
2026-06-08 21:46:57.776923 | controller | TASK [osp.edpm.edpm_container_standalone : Read current state file] ************
2026-06-08 21:46:57.776934 | controller | Monday 08 June 2026  21:46:57 +0000 (0:00:00.090)       0:00:32.184 ***********
2026-06-08 21:46:58.154555 | controller | ok: [compute-1]
2026-06-08 21:46:58.154612 | controller |
2026-06-08 21:46:58.154625 | controller | TASK [osp.edpm.edpm_container_standalone : Parse state file data] **************
2026-06-08 21:46:58.154866 | controller | Monday 08 June 2026  21:46:58 +0000 (0:00:00.377)       0:00:32.562 ***********
2026-06-08 21:46:58.215228 | controller | ok: [compute-1]
2026-06-08 21:46:58.215487 | controller |
2026-06-08 21:46:58.215500 | controller | TASK [osp.edpm.edpm_container_standalone : Determine service name for state file] ***
2026-06-08 21:46:58.215510 | controller | Monday 08 June 2026  21:46:58 +0000 (0:00:00.060)       0:00:32.622 ***********
2026-06-08 21:46:58.249413 | controller | ok: [compute-1]
2026-06-08 21:46:58.249449 | controller |
2026-06-08 21:46:58.249788 | controller | TASK [osp.edpm.edpm_container_standalone : Remove container from service containers list] ***
2026-06-08 21:46:58.249824 | controller | Monday 08 June 2026  21:46:58 +0000 (0:00:00.034)       0:00:32.657 ***********
2026-06-08 21:46:58.294632 | controller | skipping: [compute-1]
2026-06-08 21:46:58.294933 | controller |
2026-06-08 21:46:58.295228 | controller | TASK [osp.edpm.edpm_container_standalone : Update service with remaining containers] ***
2026-06-08 21:46:58.295556 | controller | Monday 08 June 2026  21:46:58 +0000 (0:00:00.045)       0:00:32.702 ***********
2026-06-08 21:46:58.347383 | controller | skipping: [compute-1]
2026-06-08 21:46:58.347678 | controller |
2026-06-08 21:46:58.347966 | controller | TASK [osp.edpm.edpm_container_standalone : Build filtered services dict (exclude empty service)] ***
2026-06-08 21:46:58.348279 | controller | Monday 08 June 2026  21:46:58 +0000 (0:00:00.052)       0:00:32.755 ***********
2026-06-08 21:46:58.400537 | controller | skipping: [compute-1]
2026-06-08 21:46:58.400817 | controller |
2026-06-08 21:46:58.401090 | controller | TASK [osp.edpm.edpm_container_standalone : Update state data without empty service] ***
2026-06-08 21:46:58.401398 | controller | Monday 08 June 2026  21:46:58 +0000 (0:00:00.053)       0:00:32.808 ***********
2026-06-08 21:46:58.481948 | controller | skipping: [compute-1]
2026-06-08 21:46:58.482364 | controller |
2026-06-08 21:46:58.482668 | controller | TASK [osp.edpm.edpm_container_standalone : Update service in state data] *******
2026-06-08 21:46:58.482978 | controller | Monday 08 June 2026  21:46:58 +0000 (0:00:00.081)       0:00:32.889 ***********
2026-06-08 21:46:58.586530 | controller | ok: [compute-1]
2026-06-08 21:46:58.586836 | controller |
2026-06-08 21:46:58.587127 | controller | TASK [osp.edpm.edpm_container_standalone : Write updated state file] ***********
2026-06-08 21:46:58.587454 | controller | Monday 08 June 2026  21:46:58 +0000 (0:00:00.104)       0:00:32.994 ***********
2026-06-08 21:46:59.136526 | controller | changed: [compute-1]
2026-06-08 21:46:59.136581 | controller |
2026-06-08 21:46:59.136591 | controller | PLAY RECAP *********************************************************************
2026-06-08 21:46:59.136599 | controller | compute-1                  : ok=64   changed=43   unreachable=0    failed=0    skipped=13   rescued=0    ignored=0
2026-06-08 21:46:59.136606 | controller |
2026-06-08 21:46:59.136613 | controller | Monday 08 June 2026  21:46:59 +0000 (0:00:00.548)       0:00:33.543 ***********
2026-06-08 21:46:59.136620 | controller | ===============================================================================
2026-06-08 21:46:59.136627 | controller | osp.edpm.edpm_frr : Download needed container images -------------------- 6.35s
2026-06-08 21:46:59.136654 | controller | osp.edpm.edpm_container_manage : Manage container systemd services and cleanup old systemd healthchecks for /var/lib/edpm-config/container-startup-config/frr --- 2.08s
2026-06-08 21:46:59.138900 | controller | osp.edpm.edpm_container_manage : Enable and start edpm-container-shutdown --- 1.01s
2026-06-08 21:46:59.138948 | controller | osp.edpm.edpm_container_manage : Deploy edpm-container-shutdown and edpm-start-podman-container --- 0.97s
2026-06-08 21:46:59.138957 | controller | osp.edpm.edpm_frr : Create persistent directories ----------------------- 0.89s
2026-06-08 21:46:59.138964 | controller | osp.edpm.edpm_container_manage : Enable and start netns-placeholder ----- 0.86s
2026-06-08 21:46:59.138971 | controller | osp.edpm.edpm_container_manage : Create containers managed by Podman for /var/lib/edpm-config/container-startup-config/frr --- 0.86s
2026-06-08 21:46:59.138979 | controller | osp.edpm.edpm_frr : Ensure /run/frr is present upon reboot -------------- 0.75s
2026-06-08 21:46:59.138994 | controller | osp.edpm.edpm_frr : Identify the real interfaces via os-net-config ------ 0.64s
2026-06-08 21:46:59.139001 | controller | osp.edpm.edpm_nftables : Generate nft ruleset in static file ------------ 0.61s
2026-06-08 21:46:59.139007 | controller | osp.edpm.edpm_frr : Configure FRR --------------------------------------- 0.57s
2026-06-08 21:46:59.139014 | controller | osp.edpm.edpm_frr : Gather SELinux fact if needed ----------------------- 0.57s
2026-06-08 21:46:59.139021 | controller | osp.edpm.edpm_container_standalone : Write updated state file ----------- 0.55s
2026-06-08 21:46:59.139027 | controller | osp.edpm.edpm_nftables : Generate nft edpm chains ----------------------- 0.54s
2026-06-08 21:46:59.139033 | controller | osp.edpm.edpm_nftables : Generate chain jumps --------------------------- 0.53s
2026-06-08 21:46:59.139040 | controller | osp.edpm.edpm_container_standalone : Render container definitions: [frr ] --- 0.52s
2026-06-08 21:46:59.139047 | controller | osp.edpm.edpm_nftables : Generate nft flushes --------------------------- 0.51s
2026-06-08 21:46:59.139053 | controller | osp.edpm.edpm_nftables : Generate chain jumps --------------------------- 0.51s
2026-06-08 21:46:59.139059 | controller | osp.edpm.edpm_nftables : Push default ruleset snipet -------------------- 0.51s
2026-06-08 21:46:59.139066 | controller | osp.edpm.edpm_container_manage : Create /etc/systemd/system-preset/91-netns-placeholder.preset --- 0.51s
2026-06-08 21:46:59.215200 | controller | INFO     Running default > verify
2026-06-08 21:46:59.215862 | controller | INFO     Running Ansible Verifier
2026-06-08 21:46:59.702557 | controller |
2026-06-08 21:46:59.702920 | controller | PLAY [Verify frr] **************************************************************
2026-06-08 21:46:59.703192 | controller |
2026-06-08 21:46:59.703531 | controller | TASK [ensure expected directories exist] ***************************************
2026-06-08 21:46:59.703813 | controller | Monday 08 June 2026  21:46:59 +0000 (0:00:00.029)       0:00:00.029 ***********
2026-06-08 21:46:59.750842 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/edpm-ansible/molecule/test-helpers/verify_dir.yaml for compute-1 => (item=/var/lib/edpm-config/container-startup-config/frr)
2026-06-08 21:46:59.751140 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/edpm-ansible/molecule/test-helpers/verify_dir.yaml for compute-1 => (item=/var/lib/openstack/frr)
2026-06-08 21:46:59.751601 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/edpm-ansible/molecule/test-helpers/verify_dir.yaml for compute-1 => (item=/etc/tmpfiles.d/)
2026-06-08 21:46:59.751879 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/edpm-ansible/molecule/test-helpers/verify_dir.yaml for compute-1 => (item=/var/lib/openstack/frr/etc/frr)
2026-06-08 21:46:59.752136 | controller |
2026-06-08 21:46:59.752464 | controller | TASK [Check if directory exists /var/lib/edpm-config/container-startup-config/frr] ***
2026-06-08 21:46:59.752747 | controller | Monday 08 June 2026  21:46:59 +0000 (0:00:00.049)       0:00:00.078 ***********
2026-06-08 21:47:00.169293 | controller | ok: [compute-1]
2026-06-08 21:47:00.169338 | controller |
2026-06-08 21:47:00.169348 | controller | TASK [Assert directory exists /var/lib/edpm-config/container-startup-config/frr] ***
2026-06-08 21:47:00.169358 | controller | Monday 08 June 2026  21:47:00 +0000 (0:00:00.416)       0:00:00.495 ***********
2026-06-08 21:47:00.216467 | controller | ok: [compute-1] => changed=false
2026-06-08 21:47:00.216551 | controller |   msg: All assertions passed
2026-06-08 21:47:00.216562 | controller |
2026-06-08 21:47:00.216571 | controller | TASK [Check if directory exists /var/lib/openstack/frr] ************************
2026-06-08 21:47:00.216590 | controller | Monday 08 June 2026  21:47:00 +0000 (0:00:00.047)       0:00:00.542 ***********
2026-06-08 21:47:00.512409 | controller | ok: [compute-1]
2026-06-08 21:47:00.512460 | controller |
2026-06-08 21:47:00.512498 | controller | TASK [Assert directory exists /var/lib/openstack/frr] **************************
2026-06-08 21:47:00.512645 | controller | Monday 08 June 2026  21:47:00 +0000 (0:00:00.296)       0:00:00.839 ***********
2026-06-08 21:47:00.559467 | controller | ok: [compute-1] => changed=false
2026-06-08 21:47:00.560095 | controller |   msg: All assertions passed
2026-06-08 21:47:00.560137 | controller |
2026-06-08 21:47:00.560146 | controller | TASK [Check if directory exists /etc/tmpfiles.d/] ******************************
2026-06-08 21:47:00.560154 | controller | Monday 08 June 2026  21:47:00 +0000 (0:00:00.047)       0:00:00.886 ***********
2026-06-08 21:47:00.860261 | controller | ok: [compute-1]
2026-06-08 21:47:00.860286 | controller |
2026-06-08 21:47:00.860291 | controller | TASK [Assert directory exists /etc/tmpfiles.d/] ********************************
2026-06-08 21:47:00.860296 | controller | Monday 08 June 2026  21:47:00 +0000 (0:00:00.300)       0:00:01.187 ***********
2026-06-08 21:47:00.905389 | controller | ok: [compute-1] => changed=false
2026-06-08 21:47:00.905439 | controller |   msg: All assertions passed
2026-06-08 21:47:00.905450 | controller |
2026-06-08 21:47:00.905706 | controller | TASK [Check if directory exists /var/lib/openstack/frr/etc/frr] ****************
2026-06-08 21:47:00.905742 | controller | Monday 08 June 2026  21:47:00 +0000 (0:00:00.044)       0:00:01.232 ***********
2026-06-08 21:47:01.191288 | controller | ok: [compute-1]
2026-06-08 21:47:01.191331 | controller |
2026-06-08 21:47:01.191341 | controller | TASK [Assert directory exists /var/lib/openstack/frr/etc/frr] ******************
2026-06-08 21:47:01.191350 | controller | Monday 08 June 2026  21:47:01 +0000 (0:00:00.285)       0:00:01.518 ***********
2026-06-08 21:47:01.223417 | controller | ok: [compute-1] => changed=false
2026-06-08 21:47:01.223457 | controller |   msg: All assertions passed
2026-06-08 21:47:01.223466 | controller |
2026-06-08 21:47:01.223474 | controller | TASK [Check if file /etc/tmpfiles.d/run-frr.conf exist] ************************
2026-06-08 21:47:01.223693 | controller | Monday 08 June 2026  21:47:01 +0000 (0:00:00.032)       0:00:01.550 ***********
2026-06-08 21:47:01.513152 | controller | ok: [compute-1]
2026-06-08 21:47:01.513181 | controller |
2026-06-08 21:47:01.513186 | controller | TASK [Assert file /etc/tmpfiles.d/run-frr.conf exist] **************************
2026-06-08 21:47:01.513391 | controller | Monday 08 June 2026  21:47:01 +0000 (0:00:00.289)       0:00:01.840 ***********
2026-06-08 21:47:01.546435 | controller | ok: [compute-1] => changed=false
2026-06-08 21:47:01.546476 | controller |   msg: All assertions passed
2026-06-08 21:47:01.546975 | controller |
2026-06-08 21:47:01.547013 | controller | TASK [Check if file /var/lib/openstack/frr/etc/frr/frr.conf exist] *************
2026-06-08 21:47:01.547023 | controller | Monday 08 June 2026  21:47:01 +0000 (0:00:00.033)       0:00:01.873 ***********
2026-06-08 21:47:01.857396 | controller | ok: [compute-1]
2026-06-08 21:47:01.858033 | controller |
2026-06-08 21:47:01.858062 | controller | TASK [Assert file /var/lib/openstack/frr/etc/frr/frr.conf exist] ***************
2026-06-08 21:47:01.858075 | controller | Monday 08 June 2026  21:47:01 +0000 (0:00:00.311)       0:00:02.185 ***********
2026-06-08 21:47:01.887491 | controller | ok: [compute-1] => changed=false
2026-06-08 21:47:01.887552 | controller |   msg: All assertions passed
2026-06-08 21:47:01.887558 | controller |
2026-06-08 21:47:01.887743 | controller | TASK [Check if file /var/lib/openstack/frr/etc/frr/daemons exist] **************
2026-06-08 21:47:01.887777 | controller | Monday 08 June 2026  21:47:01 +0000 (0:00:00.029)       0:00:02.214 ***********
2026-06-08 21:47:02.177377 | controller | ok: [compute-1]
2026-06-08 21:47:02.177418 | controller |
2026-06-08 21:47:02.177425 | controller | TASK [Assert file /var/lib/openstack/frr/etc/frr/daemons exist] ****************
2026-06-08 21:47:02.177431 | controller | Monday 08 June 2026  21:47:02 +0000 (0:00:00.288)       0:00:02.503 ***********
2026-06-08 21:47:02.226474 | controller | ok: [compute-1] => changed=false
2026-06-08 21:47:02.226518 | controller |   msg: All assertions passed
2026-06-08 21:47:02.227387 | controller |
2026-06-08 21:47:02.227443 | controller | TASK [rule source file exists] *************************************************
2026-06-08 21:47:02.227456 | controller | Monday 08 June 2026  21:47:02 +0000 (0:00:00.049)       0:00:02.553 ***********
2026-06-08 21:47:02.505237 | controller | ok: [compute-1]
2026-06-08 21:47:02.505413 | controller |
2026-06-08 21:47:02.505424 | controller | TASK [Assert frr BGP and BFD rule source file exists] **************************
2026-06-08 21:47:02.505433 | controller | Monday 08 June 2026  21:47:02 +0000 (0:00:00.278)       0:00:02.831 ***********
2026-06-08 21:47:02.546444 | controller | ok: [compute-1] => changed=false
2026-06-08 21:47:02.546473 | controller |   msg: All assertions passed
2026-06-08 21:47:02.546483 | controller |
2026-06-08 21:47:02.546491 | controller | TASK [Check if nftables rule exists in /etc/nftables/edpm-rules.nft] ***********
2026-06-08 21:47:02.546668 | controller | Monday 08 June 2026  21:47:02 +0000 (0:00:00.041)       0:00:02.872 ***********
2026-06-08 21:47:03.187547 | controller | changed: [compute-1] => (item=121 frr bgp port)
2026-06-08 21:47:03.187601 | controller | changed: [compute-1] => (item=122 frr bfd ports)
2026-06-08 21:47:03.187611 | controller |
2026-06-08 21:47:03.188013 | controller | TASK [Assert nftables rule exists in /etc/nftables/edpm-rules.nft] *************
2026-06-08 21:47:03.188058 | controller | Monday 08 June 2026  21:47:03 +0000 (0:00:00.641)       0:00:03.514 ***********
2026-06-08 21:47:03.266583 | controller | ok: [compute-1] => (item={'changed': True, 'stdout': '', 'stderr': '', 'rc': 0, 'cmd': 'grep -q "121 frr bgp port" /etc/nftables/edpm-rules.nft', 'start': '2026-06-08 21:47:02.891359', 'end': '2026-06-08 21:47:02.896807', 'delta': '0:00:00.005448', 'msg': '', 'invocation': {'module_args': {'_raw_params': 'grep -q "121 frr bgp port" /etc/nftables/edpm-rules.nft', '_uses_shell': True, 'stdin_add_newline': True, 'strip_empty_ends': True, 'argv': None, 'chdir': None, 'executable': None, 'creates': None, 'removes': None, 'stdin': None}}, 'stdout_lines': [], 'stderr_lines': [], 'failed': False, 'item': '121 frr bgp port', 'ansible_loop_var': 'item'}) => changed=false
2026-06-08 21:47:03.266629 | controller |   ansible_loop_var: item
2026-06-08 21:47:03.266641 | controller |   item:
2026-06-08 21:47:03.266650 | controller |     ansible_loop_var: item
2026-06-08 21:47:03.266659 | controller |     changed: true
2026-06-08 21:47:03.266668 | controller |     cmd: grep -q "121 frr bgp port" /etc/nftables/edpm-rules.nft
2026-06-08 21:47:03.268377 | controller |     delta: '0:00:00.005448'
2026-06-08 21:47:03.268420 | controller |     end: '2026-06-08 21:47:02.896807'
2026-06-08 21:47:03.268428 | controller |     failed: false
2026-06-08 21:47:03.268436 | controller |     invocation:
2026-06-08 21:47:03.268442 | controller |       module_args:
2026-06-08 21:47:03.268449 | controller |         _raw_params: grep -q "121 frr bgp port" /etc/nftables/edpm-rules.nft
2026-06-08 21:47:03.268457 | controller |         _uses_shell: true
2026-06-08 21:47:03.268464 | controller |         argv: null
2026-06-08 21:47:03.268473 | controller |         chdir: null
2026-06-08 21:47:03.268697 | controller |         creates: null
2026-06-08 21:47:03.268931 | controller |         executable: null
2026-06-08 21:47:03.269182 | controller |         removes: null
2026-06-08 21:47:03.269479 | controller |         stdin: null
2026-06-08 21:47:03.269740 | controller |         stdin_add_newline: true
2026-06-08 21:47:03.269981 | controller |         strip_empty_ends: true
2026-06-08 21:47:03.270233 | controller |     item: 121 frr bgp port
2026-06-08 21:47:03.270545 | controller |     msg: ''
2026-06-08 21:47:03.270848 | controller |     rc: 0
2026-06-08 21:47:03.271128 | controller |     start: '2026-06-08 21:47:02.891359'
2026-06-08 21:47:03.271467 | controller |     stderr: ''
2026-06-08 21:47:03.271754 | controller |     stderr_lines: []
2026-06-08 21:47:03.272026 | controller |     stdout: ''
2026-06-08 21:47:03.272400 | controller |     stdout_lines: []
2026-06-08 21:47:03.272694 | controller |   msg: All assertions passed
2026-06-08 21:47:03.273003 | controller | ok: [compute-1] => (item={'changed': True, 'stdout': '', 'stderr': '', 'rc': 0, 'cmd': 'grep -q "122 frr bfd ports" /etc/nftables/edpm-rules.nft', 'start': '2026-06-08 21:47:03.125195', 'end': '2026-06-08 21:47:03.131703', 'delta': '0:00:00.006508', 'msg': '', 'invocation': {'module_args': {'_raw_params': 'grep -q "122 frr bfd ports" /etc/nftables/edpm-rules.nft', '_uses_shell': True, 'stdin_add_newline': True, 'strip_empty_ends': True, 'argv': None, 'chdir': None, 'executable': None, 'creates': None, 'removes': None, 'stdin': None}}, 'stdout_lines': [], 'stderr_lines': [], 'failed': False, 'item': '122 frr bfd ports', 'ansible_loop_var': 'item'}) => changed=false
2026-06-08 21:47:03.273350 | controller |   ansible_loop_var: item
2026-06-08 21:47:03.273641 | controller |   item:
2026-06-08 21:47:03.273918 | controller |     ansible_loop_var: item
2026-06-08 21:47:03.274191 | controller |     changed: true
2026-06-08 21:47:03.274548 | controller |     cmd: grep -q "122 frr bfd ports" /etc/nftables/edpm-rules.nft
2026-06-08 21:47:03.274833 | controller |     delta: '0:00:00.006508'
2026-06-08 21:47:03.275107 | controller |     end: '2026-06-08 21:47:03.131703'
2026-06-08 21:47:03.275439 | controller |     failed: false
2026-06-08 21:47:03.275740 | controller |     invocation:
2026-06-08 21:47:03.276017 | controller |       module_args:
2026-06-08 21:47:03.276364 | controller |         _raw_params: grep -q "122 frr bfd ports" /etc/nftables/edpm-rules.nft
2026-06-08 21:47:03.276754 | controller |         _uses_shell: true
2026-06-08 21:47:03.277029 | controller |         argv: null
2026-06-08 21:47:03.277353 | controller |         chdir: null
2026-06-08 21:47:03.277661 | controller |         creates: null
2026-06-08 21:47:03.277947 | controller |         executable: null
2026-06-08 21:47:03.278289 | controller |         removes: null
2026-06-08 21:47:03.278621 | controller |         stdin: null
2026-06-08 21:47:03.278914 | controller |         stdin_add_newline: true
2026-06-08 21:47:03.279203 | controller |         strip_empty_ends: true
2026-06-08 21:47:03.279525 | controller |     item: 122 frr bfd ports
2026-06-08 21:47:03.279820 | controller |     msg: ''
2026-06-08 21:47:03.280093 | controller |     rc: 0
2026-06-08 21:47:03.280439 | controller |     start: '2026-06-08 21:47:03.125195'
2026-06-08 21:47:03.280748 | controller |     stderr: ''
2026-06-08 21:47:03.281023 | controller |     stderr_lines: []
2026-06-08 21:47:03.281336 | controller |     stdout: ''
2026-06-08 21:47:03.281636 | controller |     stdout_lines: []
2026-06-08 21:47:03.281933 | controller |   msg: All assertions passed
2026-06-08 21:47:03.282607 | controller |
2026-06-08 21:47:03.282945 | controller | TASK [Check rule content] ******************************************************
2026-06-08 21:47:03.283232 | controller | Monday 08 June 2026  21:47:03 +0000 (0:00:00.078)       0:00:03.592 ***********
2026-06-08 21:47:03.802448 | controller | changed: [compute-1] => (item=tcp dport { 179 } ct state new counter accept)
2026-06-08 21:47:03.802492 | controller | changed: [compute-1] => (item=udp dport { 3784, 3785, 4784, 49152, 49153 } ct state new counter accept)
2026-06-08 21:47:03.802502 | controller |
2026-06-08 21:47:03.802510 | controller | TASK [Assert rule content exists] **********************************************
2026-06-08 21:47:03.802524 | controller | Monday 08 June 2026  21:47:03 +0000 (0:00:00.535)       0:00:04.128 ***********
2026-06-08 21:47:03.846395 | controller | ok: [compute-1] => (item={'changed': True, 'stdout': '', 'stderr': '', 'rc': 0, 'cmd': 'grep -q "tcp dport { 179 } ct state new counter accept" /etc/nftables/edpm-rules.nft', 'start': '2026-06-08 21:47:03.497968', 'end': '2026-06-08 21:47:03.504470', 'delta': '0:00:00.006502', 'msg': '', 'invocation': {'module_args': {'_raw_params': 'grep -q "tcp dport { 179 } ct state new counter accept" /etc/nftables/edpm-rules.nft', '_uses_shell': True, 'stdin_add_newline': True, 'strip_empty_ends': True, 'argv': None, 'chdir': None, 'executable': None, 'creates': None, 'removes': None, 'stdin': None}}, 'stdout_lines': [], 'stderr_lines': [], 'failed': False, 'item': 'tcp dport { 179 } ct state new counter accept', 'ansible_loop_var': 'item'}) => changed=false
2026-06-08 21:47:03.846421 | controller |   ansible_loop_var: item
2026-06-08 21:47:03.846431 | controller |   item:
2026-06-08 21:47:03.846440 | controller |     ansible_loop_var: item
2026-06-08 21:47:03.848335 | controller |     changed: true
2026-06-08 21:47:03.848367 | controller |     cmd: grep -q "tcp dport { 179 } ct state new counter accept" /etc/nftables/edpm-rules.nft
2026-06-08 21:47:03.848372 | controller |     delta: '0:00:00.006502'
2026-06-08 21:47:03.848377 | controller |     end: '2026-06-08 21:47:03.504470'
2026-06-08 21:47:03.848382 | controller |     failed: false
2026-06-08 21:47:03.848386 | controller |     invocation:
2026-06-08 21:47:03.848411 | controller |       module_args:
2026-06-08 21:47:03.848416 | controller |         _raw_params: grep -q "tcp dport { 179 } ct state new counter accept" /etc/nftables/edpm-rules.nft
2026-06-08 21:47:03.848420 | controller |         _uses_shell: true
2026-06-08 21:47:03.848429 | controller |         argv: null
2026-06-08 21:47:03.848433 | controller |         chdir: null
2026-06-08 21:47:03.848437 | controller |         creates: null
2026-06-08 21:47:03.848440 | controller |         executable: null
2026-06-08 21:47:03.848444 | controller |         removes: null
2026-06-08 21:47:03.848447 | controller |         stdin: null
2026-06-08 21:47:03.848451 | controller |         stdin_add_newline: true
2026-06-08 21:47:03.848459 | controller |         strip_empty_ends: true
2026-06-08 21:47:03.848744 | controller |     item: tcp dport { 179 } ct state new counter accept
2026-06-08 21:47:03.848928 | controller |     msg: ''
2026-06-08 21:47:03.849105 | controller |     rc: 0
2026-06-08 21:47:03.849305 | controller |     start: '2026-06-08 21:47:03.497968'
2026-06-08 21:47:03.849490 | controller |     stderr: ''
2026-06-08 21:47:03.849839 | controller |     stderr_lines: []
2026-06-08 21:47:03.850019 | controller |     stdout: ''
2026-06-08 21:47:03.850206 | controller |     stdout_lines: []
2026-06-08 21:47:03.850415 | controller |   msg: All assertions passed
2026-06-08 21:47:03.850624 | controller | ok: [compute-1] => (item={'changed': True, 'stdout': '', 'stderr': '', 'rc': 0, 'cmd': 'grep -q "udp dport { 3784, 3785, 4784, 49152, 49153 } ct state new counter accept" /etc/nftables/edpm-rules.nft', 'start': '2026-06-08 21:47:03.741806', 'end': '2026-06-08 21:47:03.747540', 'delta': '0:00:00.005734', 'msg': '', 'invocation': {'module_args': {'_raw_params': 'grep -q "udp dport { 3784, 3785, 4784, 49152, 49153 } ct state new counter accept" /etc/nftables/edpm-rules.nft', '_uses_shell': True, 'stdin_add_newline': True, 'strip_empty_ends': True, 'argv': None, 'chdir': None, 'executable': None, 'creates': None, 'removes': None, 'stdin': None}}, 'stdout_lines': [], 'stderr_lines': [], 'failed': False, 'item': 'udp dport { 3784, 3785, 4784, 49152, 49153 } ct state new counter accept', 'ansible_loop_var': 'item'}) => changed=false
2026-06-08 21:47:03.850813 | controller |   ansible_loop_var: item
2026-06-08 21:47:03.850999 | controller |   item:
2026-06-08 21:47:03.851186 | controller |     ansible_loop_var: item
2026-06-08 21:47:03.851394 | controller |     changed: true
2026-06-08 21:47:03.851588 | controller |     cmd: grep -q "udp dport { 3784, 3785, 4784, 49152, 49153 } ct state new counter accept" /etc/nftables/edpm-rules.nft
2026-06-08 21:47:03.851771 | controller |     delta: '0:00:00.005734'
2026-06-08 21:47:03.851957 | controller |     end: '2026-06-08 21:47:03.747540'
2026-06-08 21:47:03.852167 | controller |     failed: false
2026-06-08 21:47:03.852379 | controller |     invocation:
2026-06-08 21:47:03.852568 | controller |       module_args:
2026-06-08 21:47:03.852752 | controller |         _raw_params: grep -q "udp dport { 3784, 3785, 4784, 49152, 49153 } ct state new counter accept" /etc/nftables/edpm-rules.nft
2026-06-08 21:47:03.852935 | controller |         _uses_shell: true
2026-06-08 21:47:03.853117 | controller |         argv: null
2026-06-08 21:47:03.853330 | controller |         chdir: null
2026-06-08 21:47:03.853516 | controller |         creates: null
2026-06-08 21:47:03.853701 | controller |         executable: null
2026-06-08 21:47:03.853883 | controller |         removes: null
2026-06-08 21:47:03.854066 | controller |         stdin: null
2026-06-08 21:47:03.854262 | controller |         stdin_add_newline: true
2026-06-08 21:47:03.854457 | controller |         strip_empty_ends: true
2026-06-08 21:47:03.854642 | controller |     item: udp dport { 3784, 3785, 4784, 49152, 49153 } ct state new counter accept
2026-06-08 21:47:03.854822 | controller |     msg: ''
2026-06-08 21:47:03.855004 | controller |     rc: 0
2026-06-08 21:47:03.855192 | controller |     start: '2026-06-08 21:47:03.741806'
2026-06-08 21:47:03.855404 | controller |     stderr: ''
2026-06-08 21:47:03.855596 | controller |     stderr_lines: []
2026-06-08 21:47:03.855788 | controller |     stdout: ''
2026-06-08 21:47:03.855989 | controller |     stdout_lines: []
2026-06-08 21:47:03.856185 | controller |   msg: All assertions passed
2026-06-08 21:47:03.856389 | controller |
2026-06-08 21:47:03.856581 | controller | TASK [Check input chain rules are loaded] **************************************
2026-06-08 21:47:03.856762 | controller | Monday 08 June 2026  21:47:03 +0000 (0:00:00.045)       0:00:04.174 ***********
2026-06-08 21:47:04.354127 | controller | changed: [compute-1] => (item=tcp dport 179 ct state new counter)
2026-06-08 21:47:04.354168 | controller | changed: [compute-1] => (item=udp dport { 3784, 3785, 4784, 49152, 49153 } ct state new counter)
2026-06-08 21:47:04.354787 | controller |
2026-06-08 21:47:04.354811 | controller | TASK [Assert rule content exists] **********************************************
2026-06-08 21:47:04.354830 | controller | Monday 08 June 2026  21:47:04 +0000 (0:00:00.506)       0:00:04.681 ***********
2026-06-08 21:47:04.399446 | controller | ok: [compute-1] => (item={'changed': True, 'stdout': '', 'stderr': '', 'rc': 0, 'cmd': 'nft list table inet filter | awk \'/chain EDPM_INPUT {/,/}/\' | grep -q "tcp dport 179 ct state new counter.*accept"', 'start': '2026-06-08 21:47:04.063139', 'end': '2026-06-08 21:47:04.072793', 'delta': '0:00:00.009654', 'msg': '', 'invocation': {'module_args': {'_raw_params': 'nft list table inet filter | awk \'/chain EDPM_INPUT {/,/}/\' | grep -q "tcp dport 179 ct state new counter.*accept"', '_uses_shell': True, 'stdin_add_newline': True, 'strip_empty_ends': True, 'argv': None, 'chdir': None, 'executable': None, 'creates': None, 'removes': None, 'stdin': None}}, 'stdout_lines': [], 'stderr_lines': [], 'failed': False, 'item': 'tcp dport 179 ct state new counter', 'ansible_loop_var': 'item'}) => changed=false
2026-06-08 21:47:04.399490 | controller |   ansible_loop_var: item
2026-06-08 21:47:04.399501 | controller |   item:
2026-06-08 21:47:04.402373 | controller |     ansible_loop_var: item
2026-06-08 21:47:04.402413 | controller |     changed: true
2026-06-08 21:47:04.402424 | controller |     cmd: nft list table inet filter | awk '/chain EDPM_INPUT {/,/}/' | grep -q "tcp dport 179 ct state new counter.*accept"
2026-06-08 21:47:04.402434 | controller |     delta: '0:00:00.009654'
2026-06-08 21:47:04.402443 | controller |     end: '2026-06-08 21:47:04.072793'
2026-06-08 21:47:04.402453 | controller |     failed: false
2026-06-08 21:47:04.402461 | controller |     invocation:
2026-06-08 21:47:04.402470 | controller |       module_args:
2026-06-08 21:47:04.402479 | controller |         _raw_params: nft list table inet filter | awk '/chain EDPM_INPUT {/,/}/' | grep -q "tcp dport 179 ct state new counter.*accept"
2026-06-08 21:47:04.402488 | controller |         _uses_shell: true
2026-06-08 21:47:04.402497 | controller |         argv: null
2026-06-08 21:47:04.402505 | controller |         chdir: null
2026-06-08 21:47:04.402514 | controller |         creates: null
2026-06-08 21:47:04.402522 | controller |         executable: null
2026-06-08 21:47:04.402531 | controller |         removes: null
2026-06-08 21:47:04.402565 | controller |         stdin: null
2026-06-08 21:47:04.402779 | controller |         stdin_add_newline: true
2026-06-08 21:47:04.403010 | controller |         strip_empty_ends: true
2026-06-08 21:47:04.403280 | controller |     item: tcp dport 179 ct state new counter
2026-06-08 21:47:04.403531 | controller |     msg: ''
2026-06-08 21:47:04.403808 | controller |     rc: 0
2026-06-08 21:47:04.404108 | controller |     start: '2026-06-08 21:47:04.063139'
2026-06-08 21:47:04.404425 | controller |     stderr: ''
2026-06-08 21:47:04.404727 | controller |     stderr_lines: []
2026-06-08 21:47:04.405008 | controller |     stdout: ''
2026-06-08 21:47:04.405320 | controller |     stdout_lines: []
2026-06-08 21:47:04.405605 | controller |   msg: All assertions passed
2026-06-08 21:47:04.405929 | controller | ok: [compute-1] => (item={'changed': True, 'stdout': '', 'stderr': '', 'rc': 0, 'cmd': 'nft list table inet filter | awk \'/chain EDPM_INPUT {/,/}/\' | grep -q "udp dport { 3784, 3785, 4784, 49152, 49153 } ct state new counter.*accept"', 'start': '2026-06-08 21:47:04.294121', 'end': '2026-06-08 21:47:04.304915', 'delta': '0:00:00.010794', 'msg': '', 'invocation': {'module_args': {'_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"', '_uses_shell': True, 'stdin_add_newline': True, 'strip_empty_ends': True, 'argv': None, 'chdir': None, 'executable': None, 'creates': None, 'removes': None, 'stdin': None}}, 'stdout_lines': [], 'stderr_lines': [], 'failed': False, 'item': 'udp dport { 3784, 3785, 4784, 49152, 49153 } ct state new counter', 'ansible_loop_var': 'item'}) => changed=false
2026-06-08 21:47:04.406211 | controller |   ansible_loop_var: item
2026-06-08 21:47:04.406525 | controller |   item:
2026-06-08 21:47:04.406808 | controller |     ansible_loop_var: item
2026-06-08 21:47:04.407081 | controller |     changed: true
2026-06-08 21:47:04.407408 | 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-08 21:47:04.407692 | controller |     delta: '0:00:00.010794'
2026-06-08 21:47:04.408050 | controller |     end: '2026-06-08 21:47:04.304915'
2026-06-08 21:47:04.408364 | controller |     failed: false
2026-06-08 21:47:04.408705 | controller |     invocation:
2026-06-08 21:47:04.409061 | controller |       module_args:
2026-06-08 21:47:04.409453 | 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-08 21:47:04.409751 | controller |         _uses_shell: true
2026-06-08 21:47:04.410030 | controller |         argv: null
2026-06-08 21:47:04.410344 | controller |         chdir: null
2026-06-08 21:47:04.410627 | controller |         creates: null
2026-06-08 21:47:04.410901 | controller |         executable: null
2026-06-08 21:47:04.411173 | controller |         removes: null
2026-06-08 21:47:04.411484 | controller |         stdin: null
2026-06-08 21:47:04.411897 | controller |         stdin_add_newline: true
2026-06-08 21:47:04.412179 | controller |         strip_empty_ends: true
2026-06-08 21:47:04.412513 | controller |     item: udp dport { 3784, 3785, 4784, 49152, 49153 } ct state new counter
2026-06-08 21:47:04.412900 | controller |     msg: ''
2026-06-08 21:47:04.413199 | controller |     rc: 0
2026-06-08 21:47:04.413597 | controller |     start: '2026-06-08 21:47:04.294121'
2026-06-08 21:47:04.413883 | controller |     stderr: ''
2026-06-08 21:47:04.414156 | controller |     stderr_lines: []
2026-06-08 21:47:04.414470 | controller |     stdout: ''
2026-06-08 21:47:04.414765 | controller |     stdout_lines: []
2026-06-08 21:47:04.415052 | controller |   msg: All assertions passed
2026-06-08 21:47:04.415352 | controller |
2026-06-08 21:47:04.415645 | controller | TASK [ensure podman container exists and are running] **************************
2026-06-08 21:47:04.415955 | controller | Monday 08 June 2026  21:47:04 +0000 (0:00:00.045)       0:00:04.726 ***********
2026-06-08 21:47:04.472354 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/edpm-ansible/molecule/test-helpers/verify_podman.yaml for compute-1 => (item=frr)
2026-06-08 21:47:04.472654 | controller |
2026-06-08 21:47:04.472936 | controller | TASK [Check if podman container exists frr] ************************************
2026-06-08 21:47:04.473211 | controller | Monday 08 June 2026  21:47:04 +0000 (0:00:00.073)       0:00:04.800 ***********
2026-06-08 21:47:04.816199 | controller | changed: [compute-1]
2026-06-08 21:47:04.816271 | controller |
2026-06-08 21:47:04.816285 | controller | TASK [Assert podman container exists frr] **************************************
2026-06-08 21:47:04.816294 | controller | Monday 08 June 2026  21:47:04 +0000 (0:00:00.342)       0:00:05.142 ***********
2026-06-08 21:47:04.883435 | controller | ok: [compute-1] => changed=false
2026-06-08 21:47:04.883493 | controller |   msg: All assertions passed
2026-06-08 21:47:04.884079 | controller |
2026-06-08 21:47:04.884120 | controller | TASK [Check if podman container is running frr] ********************************
2026-06-08 21:47:04.884129 | controller | Monday 08 June 2026  21:47:04 +0000 (0:00:00.067)       0:00:05.210 ***********
2026-06-08 21:47:05.241459 | controller | changed: [compute-1]
2026-06-08 21:47:05.241509 | controller |
2026-06-08 21:47:05.241895 | controller | TASK [Assert podman container is running frr] **********************************
2026-06-08 21:47:05.241934 | controller | Monday 08 June 2026  21:47:05 +0000 (0:00:00.357)       0:00:05.568 ***********
2026-06-08 21:47:05.308292 | controller | ok: [compute-1] => changed=false
2026-06-08 21:47:05.308341 | controller |   msg: All assertions passed
2026-06-08 21:47:05.309073 | controller |
2026-06-08 21:47:05.309095 | controller | TASK [ensure systemd services are defined and functional] **********************
2026-06-08 21:47:05.309104 | controller | Monday 08 June 2026  21:47:05 +0000 (0:00:00.066)       0:00:05.635 ***********
2026-06-08 21:47:05.371575 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/edpm-ansible/molecule/test-helpers/verify_systemd_unit.yaml for compute-1 => (item={'name': 'edpm_frr.service'})
2026-06-08 21:47:05.371626 | controller |
2026-06-08 21:47:05.371637 | controller | TASK [Check if systemd file exists edpm_frr.service] ***************************
2026-06-08 21:47:05.371753 | controller | Monday 08 June 2026  21:47:05 +0000 (0:00:00.063)       0:00:05.698 ***********
2026-06-08 21:47:05.692509 | controller | ok: [compute-1]
2026-06-08 21:47:05.692557 | controller |
2026-06-08 21:47:05.692568 | controller | TASK [Assert systemd unit exists edpm_frr.service] *****************************
2026-06-08 21:47:05.692577 | controller | Monday 08 June 2026  21:47:05 +0000 (0:00:00.320)       0:00:06.019 ***********
2026-06-08 21:47:05.733272 | controller | ok: [compute-1] => changed=false
2026-06-08 21:47:05.733317 | controller |   msg: All assertions passed
2026-06-08 21:47:05.733327 | controller |
2026-06-08 21:47:05.733336 | controller | TASK [Check if systemd unit is enabled edpm_frr.service] ***********************
2026-06-08 21:47:05.733345 | controller | Monday 08 June 2026  21:47:05 +0000 (0:00:00.041)       0:00:06.060 ***********
2026-06-08 21:47:06.032415 | controller | ok: [compute-1]
2026-06-08 21:47:06.032459 | controller |
2026-06-08 21:47:06.032830 | controller | TASK [Assert systemd unit is enabled edpm_frr.service] *************************
2026-06-08 21:47:06.032868 | controller | Monday 08 June 2026  21:47:06 +0000 (0:00:00.299)       0:00:06.359 ***********
2026-06-08 21:47:06.083497 | controller | ok: [compute-1] => changed=false
2026-06-08 21:47:06.083553 | controller |   msg: All assertions passed
2026-06-08 21:47:06.083567 | controller |
2026-06-08 21:47:06.083586 | controller | TASK [Check if systemd unit is running edpm_frr.service] ***********************
2026-06-08 21:47:06.083772 | controller | Monday 08 June 2026  21:47:06 +0000 (0:00:00.050)       0:00:06.410 ***********
2026-06-08 21:47:06.352539 | controller | ok: [compute-1]
2026-06-08 21:47:06.352613 | controller |
2026-06-08 21:47:06.352621 | controller | TASK [Assert systemd unit is running edpm_frr.service] *************************
2026-06-08 21:47:06.352636 | controller | Monday 08 June 2026  21:47:06 +0000 (0:00:00.268)       0:00:06.679 ***********
2026-06-08 21:47:06.452481 | controller | ok: [compute-1] => changed=false
2026-06-08 21:47:06.452528 | controller |   msg: All assertions passed
2026-06-08 21:47:06.453328 | controller |
2026-06-08 21:47:06.453357 | controller | PLAY RECAP *********************************************************************
2026-06-08 21:47:06.453368 | controller | compute-1                  : ok=38   changed=5    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-06-08 21:47:06.453377 | controller |
2026-06-08 21:47:06.457403 | controller | Monday 08 June 2026  21:47:06 +0000 (0:00:00.100)       0:00:06.779 ***********
2026-06-08 21:47:06.457430 | controller | ===============================================================================
2026-06-08 21:47:06.457439 | controller | Check if nftables rule exists in /etc/nftables/edpm-rules.nft ----------- 0.64s
2026-06-08 21:47:06.457447 | controller | Check rule content ------------------------------------------------------ 0.54s
2026-06-08 21:47:06.457455 | controller | Check input chain rules are loaded -------------------------------------- 0.51s
2026-06-08 21:47:06.457463 | controller | Check if directory exists /var/lib/edpm-config/container-startup-config/frr --- 0.42s
2026-06-08 21:47:06.457471 | controller | Check if podman container is running frr -------------------------------- 0.36s
2026-06-08 21:47:06.457479 | controller | Check if podman container exists frr ------------------------------------ 0.34s
2026-06-08 21:47:06.457487 | controller | Check if systemd file exists edpm_frr.service --------------------------- 0.32s
2026-06-08 21:47:06.457494 | controller | Check if file /var/lib/openstack/frr/etc/frr/frr.conf exist ------------- 0.31s
2026-06-08 21:47:06.457502 | controller | Check if directory exists /etc/tmpfiles.d/ ------------------------------ 0.30s
2026-06-08 21:47:06.457510 | controller | Check if systemd unit is enabled edpm_frr.service ----------------------- 0.30s
2026-06-08 21:47:06.457517 | controller | Check if directory exists /var/lib/openstack/frr ------------------------ 0.30s
2026-06-08 21:47:06.457525 | controller | Check if file /etc/tmpfiles.d/run-frr.conf exist ------------------------ 0.29s
2026-06-08 21:47:06.457533 | controller | Check if file /var/lib/openstack/frr/etc/frr/daemons exist -------------- 0.29s
2026-06-08 21:47:06.457541 | controller | Check if directory exists /var/lib/openstack/frr/etc/frr ---------------- 0.29s
2026-06-08 21:47:06.457549 | controller | rule source file exists ------------------------------------------------- 0.28s
2026-06-08 21:47:06.457556 | controller | Check if systemd unit is running edpm_frr.service ----------------------- 0.27s
2026-06-08 21:47:06.457564 | controller | Assert systemd unit is running edpm_frr.service ------------------------- 0.10s
2026-06-08 21:47:06.457571 | controller | Assert nftables rule exists in /etc/nftables/edpm-rules.nft ------------- 0.08s
2026-06-08 21:47:06.457579 | controller | ensure podman container exists and are running -------------------------- 0.07s
2026-06-08 21:47:06.457591 | controller | Assert podman container exists frr -------------------------------------- 0.07s
2026-06-08 21:47:06.521435 | controller | INFO     Verifier completed successfully.
2026-06-08 21:47:06.523368 | controller | INFO     Writing /tmp/report.html report.
2026-06-08 21:47:12.104275 | controller | ok: Runtime: 0:03:21.040718
2026-06-08 21:47:12.123890 | 
2026-06-08 21:47:12.123992 | PLAY RECAP
2026-06-08 21:47:12.124133 | controller | ok: 4 changed: 2 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-06-08 21:47:12.124185 | 
2026-06-08 21:47:12.334677 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2026-06-08 21:47:12.337283 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2026-06-08 21:47:13.026114 | 
2026-06-08 21:47:13.026242 | PLAY [Ensure ci-framework-data base directories exist on all nodes]
2026-06-08 21:47:13.037555 | 
2026-06-08 21:47:13.037629 | TASK [Create ci-framework-data/logs directory if missing]
2026-06-08 21:47:13.484465 | controller | ok
2026-06-08 21:47:13.531243 | 
2026-06-08 21:47:13.531416 | PLAY [Run ci/playbooks/collect-logs.yml]
2026-06-08 21:47:13.538410 | 
2026-06-08 21:47:13.538558 | TASK [Filter out host if needed]
2026-06-08 21:47:13.549311 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2026-06-08 21:47:13.553464 | 
2026-06-08 21:47:13.553571 | TASK [Ensure file is present]
2026-06-08 21:47:13.992339 | controller | ok
2026-06-08 21:47:14.005166 | 
2026-06-08 21:47:14.005303 | TASK [Manage molecule report file]
2026-06-08 21:47:19.044930 | controller | ok: Runtime: 0:00:00.006041
2026-06-08 21:47:19.053796 | 
2026-06-08 21:47:19.053925 | TASK [Check if we get ci-framework-data basedir]
2026-06-08 21:47:19.334110 | controller | ok
2026-06-08 21:47:19.343077 | 
2026-06-08 21:47:19.343208 | TASK [Create ci-framework-data log directory for zuul]
2026-06-08 21:47:19.621372 | controller | changed
2026-06-08 21:47:19.630409 | 
2026-06-08 21:47:19.630541 | TASK [Copy ci-framework interesting files]
2026-06-08 21:47:24.673438 | controller | ok: Runtime: 0:00:00.015862
2026-06-08 21:47:24.681013 | 
2026-06-08 21:47:24.681147 | TASK [Get SELinux listing]
2026-06-08 21:47:29.723673 | controller | ok: Runtime: 0:00:00.013801
2026-06-08 21:47:29.733081 | 
2026-06-08 21:47:29.733206 | TASK [Generate log index]
2026-06-08 21:47:30.740570 | controller | changed
2026-06-08 21:47:30.749255 | 
2026-06-08 21:47:30.749408 | TASK [Get some env related data]
2026-06-08 21:47:35.786860 | controller | ok: Runtime: 0:00:01.011710
2026-06-08 21:47:35.841743 | 
2026-06-08 21:47:35.841902 | TASK [Generate list of logs to collect in home directory]
2026-06-08 21:47:36.232593 | controller | ok: All paths examined
2026-06-08 21:47:36.241045 | 
2026-06-08 21:47:36.241174 | LOOP [Copy logs from home directory]
2026-06-08 21:47:36.696290 | controller | changed:
2026-06-08 21:47:36.696632 | controller | {
2026-06-08 21:47:36.696686 | controller |   "atime": 1780955004.6343694,
2026-06-08 21:47:36.696725 | controller |   "ctime": 1780955022.6338308,
2026-06-08 21:47:36.696763 | controller |   "dev": 64513,
2026-06-08 21:47:36.696798 | controller |   "gid": 1000,
2026-06-08 21:47:36.696832 | controller |   "gr_name": "zuul",
2026-06-08 21:47:36.696867 | controller |   "inode": 299247,
2026-06-08 21:47:36.696902 | controller |   "isblk": false,
2026-06-08 21:47:36.696935 | controller |   "ischr": false,
2026-06-08 21:47:36.696970 | controller |   "isdir": false,
2026-06-08 21:47:36.697003 | controller |   "isfifo": false,
2026-06-08 21:47:36.697035 | controller |   "isgid": false,
2026-06-08 21:47:36.697067 | controller |   "islnk": false,
2026-06-08 21:47:36.697099 | controller |   "isreg": true,
2026-06-08 21:47:36.697131 | controller |   "issock": false,
2026-06-08 21:47:36.697164 | controller |   "isuid": false,
2026-06-08 21:47:36.697196 | controller |   "mode": "0644",
2026-06-08 21:47:36.697235 | controller |   "mtime": 1780955022.6338308,
2026-06-08 21:47:36.697270 | controller |   "nlink": 1,
2026-06-08 21:47:36.697315 | controller |   "path": "/home/zuul/ansible.log",
2026-06-08 21:47:36.697386 | controller |   "pw_name": "zuul",
2026-06-08 21:47:36.697430 | controller |   "rgrp": true,
2026-06-08 21:47:36.697465 | controller |   "roth": true,
2026-06-08 21:47:36.697498 | controller |   "rusr": true,
2026-06-08 21:47:36.697532 | controller |   "size": 6749,
2026-06-08 21:47:36.697569 | controller |   "uid": 1000,
2026-06-08 21:47:36.697603 | controller |   "wgrp": false,
2026-06-08 21:47:36.697636 | controller |   "woth": false,
2026-06-08 21:47:36.697668 | controller |   "wusr": true,
2026-06-08 21:47:36.697701 | controller |   "xgrp": false,
2026-06-08 21:47:36.697734 | controller |   "xoth": false,
2026-06-08 21:47:36.697766 | controller |   "xusr": false
2026-06-08 21:47:36.697799 | controller | }
2026-06-08 21:47:36.712630 | 
2026-06-08 21:47:36.712897 | TASK [Copy crio stats log file]
2026-06-08 21:47:36.741038 | controller | skipping: Conditional result was False
2026-06-08 21:47:36.750526 | 
2026-06-08 21:47:36.750649 | TASK [Get SELinux related data]
2026-06-08 21:47:41.793216 | controller | ERROR
2026-06-08 21:47:41.793664 | controller | {
2026-06-08 21:47:41.793727 | controller |   "delta": "0:00:00.010994",
2026-06-08 21:47:41.793769 | controller |   "end": "2026-06-08 21:47:37.061353",
2026-06-08 21:47:41.793805 | controller |   "msg": "non-zero return code",
2026-06-08 21:47:41.793841 | controller |   "rc": 1,
2026-06-08 21:47:41.793877 | controller |   "start": "2026-06-08 21:47:37.050359"
2026-06-08 21:47:41.793912 | controller | }
2026-06-08 21:47:41.793961 | controller | ERROR: Ignoring Errors
2026-06-08 21:47:41.801270 | 
2026-06-08 21:47:41.801436 | TASK [Create system configuration directory]
2026-06-08 21:47:42.079505 | controller | changed
2026-06-08 21:47:42.087057 | 
2026-06-08 21:47:42.087181 | TASK [Get some of the system configurations]
2026-06-08 21:47:47.123092 | controller | ok: Runtime: 0:00:00.018570
2026-06-08 21:47:47.130677 | 
2026-06-08 21:47:47.130803 | TASK [Copy generated documentation if available]
2026-06-08 21:47:47.167522 | controller | skipping: Conditional result was False
2026-06-08 21:47:47.175867 | 
2026-06-08 21:47:47.175990 | TASK [Copy generated AsciiDoc documentation if available]
2026-06-08 21:47:47.212342 | controller | skipping: Conditional result was False
2026-06-08 21:47:47.220714 | 
2026-06-08 21:47:47.220842 | TASK [Compress logs bigger than 2MB]
2026-06-08 21:47:52.264528 | controller | ok: Runtime: 0:00:00.007079
2026-06-08 21:47:52.272300 | 
2026-06-08 21:47:52.272465 | TASK [Copy files from workspace on node]
2026-06-08 21:47:52.306098 | controller | ok
2026-06-08 21:47:52.343329 | 
2026-06-08 21:47:52.343437 | TASK [fetch-output : Set log path for multiple nodes]
2026-06-08 21:47:52.378743 | controller | skipping: Conditional result was False
2026-06-08 21:47:52.387492 | 
2026-06-08 21:47:52.387616 | TASK [fetch-output : Set log path for single node]
2026-06-08 21:47:52.431767 | controller | ok
2026-06-08 21:47:52.439250 | 
2026-06-08 21:47:52.439420 | LOOP [fetch-output : Ensure local output dirs]
2026-06-08 21:47:52.744224 | controller -> localhost | changed: "/var/lib/zuul/builds/df980c2dd7be40afadf31c474d1d76c4/work/logs"
2026-06-08 21:47:52.744783 | controller -> localhost | changed: All items complete
2026-06-08 21:47:52.744842 | 
2026-06-08 21:47:53.001134 | controller -> localhost | changed: "/var/lib/zuul/builds/df980c2dd7be40afadf31c474d1d76c4/work/artifacts"
2026-06-08 21:47:53.273975 | controller -> localhost | changed: "/var/lib/zuul/builds/df980c2dd7be40afadf31c474d1d76c4/work/docs"
2026-06-08 21:47:53.291496 | 
2026-06-08 21:47:53.291713 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-06-08 21:47:54.094450 | controller | changed:
2026-06-08 21:47:54.094802 | controller | .d..t...... ./
2026-06-08 21:47:54.094858 | controller | >f+++++++++ README.html
2026-06-08 21:47:54.094898 | controller | >f+++++++++ ansible-execution.log
2026-06-08 21:47:54.094936 | controller | >f+++++++++ ansible.log
2026-06-08 21:47:54.094989 | controller | >f+++++++++ dmesg.log
2026-06-08 21:47:54.095030 | controller | >f+++++++++ installed-pkgs.log
2026-06-08 21:47:54.095067 | controller | >f+++++++++ python.log
2026-06-08 21:47:54.095108 | controller | >f+++++++++ registries.conf
2026-06-08 21:47:54.095145 | controller | >f+++++++++ report.html
2026-06-08 21:47:54.095181 | controller | >f+++++++++ selinux-denials.log
2026-06-08 21:47:54.095217 | controller | >f+++++++++ selinux-listing.log
2026-06-08 21:47:54.095252 | controller | cd+++++++++ ci-framework-data/
2026-06-08 21:47:54.095287 | controller | cd+++++++++ ci-framework-data/artifacts/
2026-06-08 21:47:54.095323 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2026-06-08 21:47:54.095393 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2026-06-08 21:47:54.095441 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2026-06-08 21:47:54.095478 | controller | cd+++++++++ ci-framework-data/logs/
2026-06-08 21:47:54.095513 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2026-06-08 21:47:54.095548 | controller | cd+++++++++ registries.conf.d/
2026-06-08 21:47:54.095583 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2026-06-08 21:47:54.095618 | controller | cd+++++++++ system-config/
2026-06-08 21:47:54.095677 | controller | cd+++++++++ system-config/libvirt/
2026-06-08 21:47:54.095716 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2026-06-08 21:47:54.095753 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2026-06-08 21:47:54.619708 | controller | changed: .d..t...... ./
2026-06-08 21:47:55.151485 | controller | changed: .d..t...... ./
2026-06-08 21:47:55.189064 | 
2026-06-08 21:47:55.189278 | TASK [Return artifact to Zuul]
2026-06-08 21:47:55.244922 | controller | ok
2026-06-08 21:47:55.248646 | 
2026-06-08 21:47:55.248770 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2026-06-08 21:47:55.248974 | 
2026-06-08 21:47:55.249033 | PLAY RECAP
2026-06-08 21:47:55.249106 | controller | ok: 19 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2026-06-08 21:47:55.249147 | 
2026-06-08 21:47:55.412988 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2026-06-08 21:47:55.415641 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2026-06-08 21:47:56.010319 | 
2026-06-08 21:47:56.010475 | PLAY [all]
2026-06-08 21:47:56.019748 | 
2026-06-08 21:47:56.019818 | TASK [include_role : fetch-output]
2026-06-08 21:47:56.068077 | controller | ok
2026-06-08 21:47:56.083101 | 
2026-06-08 21:47:56.083175 | TASK [fetch-output : Set log path for multiple nodes]
2026-06-08 21:47:56.158576 | controller | skipping: Conditional result was False
2026-06-08 21:47:56.167272 | 
2026-06-08 21:47:56.167474 | TASK [fetch-output : Set log path for single node]
2026-06-08 21:47:56.221748 | controller | ok
2026-06-08 21:47:56.230052 | 
2026-06-08 21:47:56.230188 | LOOP [fetch-output : Ensure local output dirs]
2026-06-08 21:47:56.686731 | controller -> localhost | ok: "/var/lib/zuul/builds/df980c2dd7be40afadf31c474d1d76c4/work/logs"
2026-06-08 21:47:56.974645 | controller -> localhost | ok: "/var/lib/zuul/builds/df980c2dd7be40afadf31c474d1d76c4/work/artifacts"
2026-06-08 21:47:57.256433 | controller -> localhost | ok: "/var/lib/zuul/builds/df980c2dd7be40afadf31c474d1d76c4/work/docs"
2026-06-08 21:47:57.276844 | 
2026-06-08 21:47:57.277048 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-06-08 21:47:58.057995 | controller | ok
2026-06-08 21:47:58.058398 | controller | ok: All items complete
2026-06-08 21:47:58.058464 | 
2026-06-08 21:47:58.623126 | controller | ok
2026-06-08 21:47:59.184956 | controller | ok
2026-06-08 21:47:59.208591 | 
2026-06-08 21:47:59.208723 | TASK [include_role : fetch-output-openshift]
2026-06-08 21:47:59.236584 | controller | skipping: Conditional result was False
2026-06-08 21:47:59.246845 | 
2026-06-08 21:47:59.246992 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-06-08 21:47:59.771261 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.013035
2026-06-08 21:48:00.067048 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.012455
2026-06-08 21:48:00.082934 | 
2026-06-08 21:48:00.083012 | PLAY [all]
2026-06-08 21:48:00.089339 | 
2026-06-08 21:48:00.089455 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-06-08 21:48:00.659180 | controller | changed
2026-06-08 21:48:00.668846 | 
2026-06-08 21:48:00.668948 | PLAY RECAP
2026-06-08 21:48:00.669029 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2026-06-08 21:48:00.669072 | 
2026-06-08 21:48:00.814432 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2026-06-08 21:48:00.816830 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2026-06-08 21:48:01.454231 | 
2026-06-08 21:48:01.454400 | PLAY [localhost]
2026-06-08 21:48:01.464742 | 
2026-06-08 21:48:01.464820 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-06-08 21:48:02.002507 | localhost | changed
2026-06-08 21:48:02.022079 | 
2026-06-08 21:48:02.022444 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-06-08 21:48:02.065253 | localhost | ok
2026-06-08 21:48:02.078151 | 
2026-06-08 21:48:02.078291 | TASK [add-fileserver : Create SSH private key tempfile]
2026-06-08 21:48:02.537307 | localhost | changed
2026-06-08 21:48:02.547160 | 
2026-06-08 21:48:02.547307 | TASK [add-fileserver : Create SSH private key from secret]
2026-06-08 21:48:03.428993 | localhost | changed
2026-06-08 21:48:03.433507 | 
2026-06-08 21:48:03.433575 | TASK [add-fileserver : Add fileserver ssh key]
2026-06-08 21:48:03.893579 | localhost | Identity added: /var/lib/zuul/builds/df980c2dd7be40afadf31c474d1d76c4/work/tmp/ansible.m0pztg89 (/var/lib/zuul/builds/df980c2dd7be40afadf31c474d1d76c4/work/tmp/ansible.m0pztg89)
2026-06-08 21:48:03.893803 | localhost | ok: Runtime: 0:00:00.014519
2026-06-08 21:48:03.897175 | 
2026-06-08 21:48:03.897239 | TASK [add-fileserver : Remove SSH private key from disk]
2026-06-08 21:48:04.199632 | localhost | ok: Runtime: 0:00:00.010246
2026-06-08 21:48:04.203526 | 
2026-06-08 21:48:04.203595 | TASK [add-fileserver : Add fileserver to inventory]
2026-06-08 21:48:04.252714 | localhost | changed
2026-06-08 21:48:04.257425 | 
2026-06-08 21:48:04.257490 | TASK [add-fileserver : Add fileserver server to known hosts]
2026-06-08 21:48:04.705817 | localhost | changed
2026-06-08 21:48:04.716437 | 
2026-06-08 21:48:04.716547 | PLAY [localhost]
2026-06-08 21:48:04.724801 | 
2026-06-08 21:48:04.724919 | TASK [Generate bulk log download script]
2026-06-08 21:48:04.750616 | localhost | ok
2026-06-08 21:48:04.771795 | 
2026-06-08 21:48:04.771942 | TASK [local-log-download : Check API endpoint is defined]
2026-06-08 21:48:04.816592 | localhost | ok: All assertions passed
2026-06-08 21:48:04.824678 | 
2026-06-08 21:48:04.824819 | TASK [local-log-download : Create download script]
2026-06-08 21:48:05.401143 | localhost -> localhost | changed
2026-06-08 21:48:05.458311 | 
2026-06-08 21:48:05.458505 | TASK [Register quick-download link]
2026-06-08 21:48:05.492501 | localhost | ok
2026-06-08 21:48:05.495026 | 
2026-06-08 21:48:05.495093 | PLAY [a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com]
2026-06-08 21:48:05.501092 | 
2026-06-08 21:48:05.501168 | TASK [Set zuul-log-path fact]
2026-06-08 21:48:05.518677 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | ok
2026-06-08 21:48:05.527631 | 
2026-06-08 21:48:05.527711 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-06-08 21:48:05.565470 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | ok
2026-06-08 21:48:05.570082 | 
2026-06-08 21:48:05.570208 | TASK [upload-logs : Create log directories]
2026-06-08 21:48:06.482287 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | changed
2026-06-08 21:48:06.485883 | 
2026-06-08 21:48:06.486022 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-06-08 21:48:06.862383 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com -> localhost | ok: Runtime: 0:00:00.008156
2026-06-08 21:48:06.868684 | 
2026-06-08 21:48:06.868815 | TASK [upload-logs : Upload logs to log server]
2026-06-08 21:48:09.463795 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | Output suppressed because no_log was given
2026-06-08 21:48:09.467913 | 
2026-06-08 21:48:09.468071 | LOOP [upload-logs : Compress console log and json output]