2025-12-03 06:11:54.096604 | Job console starting...
2025-12-03 06:11:54.317315 | Updating repositories
2025-12-03 06:11:55.115290 | Preparing job workspace
2025-12-03 06:12:02.479368 | Running Ansible setup...
2025-12-03 06:12:05.441011 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-12-03 06:12:05.914464 | 
2025-12-03 06:12:05.914558 | PLAY [localhost]
2025-12-03 06:12:05.922177 | 
2025-12-03 06:12:05.922240 | TASK [Gathering Facts]
2025-12-03 06:12:06.718909 | localhost | ok
2025-12-03 06:12:06.731935 | 
2025-12-03 06:12:06.732022 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-12-03 06:12:07.048424 | localhost -> localhost | changed
2025-12-03 06:12:07.053516 | 
2025-12-03 06:12:07.053586 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-12-03 06:12:07.724476 | localhost -> localhost | changed
2025-12-03 06:12:07.731972 | 
2025-12-03 06:12:07.732046 | TASK [Setup log path fact]
2025-12-03 06:12:07.748432 | localhost | ok
2025-12-03 06:12:07.758513 | 
2025-12-03 06:12:07.758579 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-12-03 06:12:07.774821 | localhost | ok
2025-12-03 06:12:07.781558 | 
2025-12-03 06:12:07.781620 | TASK [emit-job-header : Print job information]
2025-12-03 06:12:07.807940 | # Job Information
2025-12-03 06:12:07.808049 | Ansible Version: 2.15.12
2025-12-03 06:12:07.808076 | Job: edpm-ansible-molecule-edpm_frr
2025-12-03 06:12:07.808096 | Pipeline: github-check
2025-12-03 06:12:07.808114 | Executor: ibm-bm3-ze.softwarefactory-project.io
2025-12-03 06:12:07.808131 | Triggered by: https://github.com/openstack-k8s-operators/edpm-ansible/pull/1054
2025-12-03 06:12:07.808149 | Log URL (when completed): https://logserver.rdoproject.org/627/rdoproject.org/6276732edb4c4c96aa50de44e817f9d3/
2025-12-03 06:12:07.808166 | Event ID: d450ccb0-d00e-11f0-9d31-edd4772f120a
2025-12-03 06:12:07.811582 | 
2025-12-03 06:12:07.811645 | LOOP [emit-job-header : Print node information]
2025-12-03 06:12:07.890809 | localhost | ok:
2025-12-03 06:12:07.891081 | localhost | # Node Information
2025-12-03 06:12:07.891118 | localhost | Inventory Hostname: controller
2025-12-03 06:12:07.891143 | localhost | Hostname: np0005543177
2025-12-03 06:12:07.891163 | localhost | Username: zuul
2025-12-03 06:12:07.891184 | localhost | Distro: CentOS 9
2025-12-03 06:12:07.891202 | localhost | Provider: ibm-bm3-nodepool
2025-12-03 06:12:07.891219 | localhost | Region: regionOne
2025-12-03 06:12:07.891235 | localhost | Label: cloud-centos-9-stream-tripleo-ibm
2025-12-03 06:12:07.891255 | localhost | Product Name: OpenStack Compute
2025-12-03 06:12:07.891274 | localhost | Interface IP: 192.168.25.172
2025-12-03 06:12:07.912145 | 
2025-12-03 06:12:07.912219 | PLAY [all]
2025-12-03 06:12:07.918031 | 
2025-12-03 06:12:07.918092 | TASK [Gather network facts]
2025-12-03 06:12:08.302574 | controller | ok
2025-12-03 06:12:08.315272 | 
2025-12-03 06:12:08.315332 | TASK [include_role : start-zuul-console]
2025-12-03 06:12:08.332128 | controller | ok
2025-12-03 06:12:08.342931 | 
2025-12-03 06:12:08.342995 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-12-03 06:12:08.647001 | controller | ok
2025-12-03 06:12:08.662230 | 
2025-12-03 06:12:08.662292 | TASK [include_role : add-build-sshkey]
2025-12-03 06:12:08.678633 | controller | ok
2025-12-03 06:12:08.692926 | 
2025-12-03 06:12:08.692990 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-12-03 06:12:08.867906 | controller -> localhost | ok
2025-12-03 06:12:08.873059 | 
2025-12-03 06:12:08.873136 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-12-03 06:12:08.890645 | controller | ok
2025-12-03 06:12:08.902946 | controller | included: /var/lib/zuul/builds/6276732edb4c4c96aa50de44e817f9d3/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-12-03 06:12:08.908051 | 
2025-12-03 06:12:08.908111 | TASK [add-build-sshkey : Create Temp SSH key]
2025-12-03 06:12:09.306675 | controller -> localhost | Generating public/private rsa key pair.
2025-12-03 06:12:09.306882 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/6276732edb4c4c96aa50de44e817f9d3/work/6276732edb4c4c96aa50de44e817f9d3_id_rsa.
2025-12-03 06:12:09.306915 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/6276732edb4c4c96aa50de44e817f9d3/work/6276732edb4c4c96aa50de44e817f9d3_id_rsa.pub.
2025-12-03 06:12:09.306939 | controller -> localhost | The key fingerprint is:
2025-12-03 06:12:09.306959 | controller -> localhost | SHA256:PcqXUCwryVjMAgbOt4F/oqG1+zxgKzW5MBTECr8lrzY zuul-build-sshkey
2025-12-03 06:12:09.306979 | controller -> localhost | The key's randomart image is:
2025-12-03 06:12:09.306997 | controller -> localhost | +---[RSA 3072]----+
2025-12-03 06:12:09.307015 | controller -> localhost | |+oo              |
2025-12-03 06:12:09.307033 | controller -> localhost | |++.. o   .       |
2025-12-03 06:12:09.307050 | controller -> localhost | |o=.o. + . o      |
2025-12-03 06:12:09.307068 | controller -> localhost | |..= += . =       |
2025-12-03 06:12:09.307084 | controller -> localhost | |...X..+ S o      |
2025-12-03 06:12:09.307102 | controller -> localhost | |+.X.+  o o o     |
2025-12-03 06:12:09.307118 | controller -> localhost | |.*.*    o o      |
2025-12-03 06:12:09.307137 | controller -> localhost | |. Eo.    .       |
2025-12-03 06:12:09.307153 | controller -> localhost | | o.oo.           |
2025-12-03 06:12:09.307168 | controller -> localhost | +----[SHA256]-----+
2025-12-03 06:12:09.307222 | controller -> localhost | ok: Runtime: 0:00:00.089349
2025-12-03 06:12:09.312485 | 
2025-12-03 06:12:09.312549 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-12-03 06:12:09.329970 | controller | ok
2025-12-03 06:12:09.338358 | controller | included: /var/lib/zuul/builds/6276732edb4c4c96aa50de44e817f9d3/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-12-03 06:12:09.345269 | 
2025-12-03 06:12:09.345328 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-12-03 06:12:09.358682 | controller | skipping: Conditional result was False
2025-12-03 06:12:09.363351 | 
2025-12-03 06:12:09.363414 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-12-03 06:12:09.714282 | controller | changed
2025-12-03 06:12:09.719926 | 
2025-12-03 06:12:09.719990 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-12-03 06:12:09.929633 | controller | ok
2025-12-03 06:12:09.934353 | 
2025-12-03 06:12:09.934419 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-12-03 06:12:10.539650 | controller | changed
2025-12-03 06:12:10.544170 | 
2025-12-03 06:12:10.544232 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-12-03 06:12:11.104671 | controller | changed
2025-12-03 06:12:11.109464 | 
2025-12-03 06:12:11.109530 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-12-03 06:12:11.122867 | controller | skipping: Conditional result was False
2025-12-03 06:12:11.128074 | 
2025-12-03 06:12:11.128142 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-12-03 06:12:11.416177 | controller -> localhost | changed
2025-12-03 06:12:11.425031 | 
2025-12-03 06:12:11.425103 | TASK [add-build-sshkey : Add back temp key]
2025-12-03 06:12:11.641648 | controller -> localhost | Identity added: /var/lib/zuul/builds/6276732edb4c4c96aa50de44e817f9d3/work/6276732edb4c4c96aa50de44e817f9d3_id_rsa (zuul-build-sshkey)
2025-12-03 06:12:11.641810 | controller -> localhost | ok: Runtime: 0:00:00.006882
2025-12-03 06:12:11.646927 | 
2025-12-03 06:12:11.646987 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-12-03 06:12:11.937729 | controller | ok
2025-12-03 06:12:11.941902 | 
2025-12-03 06:12:11.941961 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-12-03 06:12:11.965828 | controller | skipping: Conditional result was False
2025-12-03 06:12:11.973658 | 
2025-12-03 06:12:11.973733 | TASK [include_role : validate-host]
2025-12-03 06:12:11.990610 | controller | ok
2025-12-03 06:12:12.008169 | 
2025-12-03 06:12:12.008234 | TASK [validate-host : Define zuul_info_dir fact]
2025-12-03 06:12:12.035292 | controller | ok
2025-12-03 06:12:12.039351 | 
2025-12-03 06:12:12.039411 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-12-03 06:12:12.224336 | controller -> localhost | ok
2025-12-03 06:12:12.230268 | 
2025-12-03 06:12:12.230340 | TASK [validate-host : Collect information about the host]
2025-12-03 06:12:12.837076 | controller | ok
2025-12-03 06:12:12.844520 | 
2025-12-03 06:12:12.844592 | TASK [validate-host : Sanitize hostname]
2025-12-03 06:12:12.912261 | controller | ok
2025-12-03 06:12:12.916599 | 
2025-12-03 06:12:12.916668 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-12-03 06:12:13.305820 | controller -> localhost | changed
2025-12-03 06:12:13.310451 | 
2025-12-03 06:12:13.310515 | TASK [validate-host : Collect information about zuul worker]
2025-12-03 06:12:13.621388 | controller | ok
2025-12-03 06:12:13.625898 | 
2025-12-03 06:12:13.625981 | TASK [validate-host : Write out all zuul information for each host]
2025-12-03 06:12:14.005160 | controller -> localhost | changed
2025-12-03 06:12:14.013556 | 
2025-12-03 06:12:14.013643 | TASK [include_role : prepare-workspace-openshift]
2025-12-03 06:12:14.026482 | controller | skipping: Conditional result was False
2025-12-03 06:12:14.031037 | 
2025-12-03 06:12:14.031102 | TASK [include_role : remove-zuul-sshkey]
2025-12-03 06:12:14.043611 | controller | skipping: Conditional result was False
2025-12-03 06:12:14.048158 | 
2025-12-03 06:12:14.048224 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-12-03 06:12:14.232363 | controller | ok: "logs"
2025-12-03 06:12:14.232656 | controller | ok: All items complete
2025-12-03 06:12:14.232688 | 
2025-12-03 06:12:14.382937 | controller | ok: "artifacts"
2025-12-03 06:12:14.536596 | controller | ok: "docs"
2025-12-03 06:12:14.543265 | 
2025-12-03 06:12:14.543341 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-12-03 06:12:14.729348 | controller | changed: "logs"
2025-12-03 06:12:14.896671 | controller | changed: "artifacts"
2025-12-03 06:12:15.074830 | controller | changed: "docs"
2025-12-03 06:12:15.109659 | 
2025-12-03 06:12:15.109753 | PLAY RECAP
2025-12-03 06:12:15.109803 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-12-03 06:12:15.109830 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-12-03 06:12:15.109848 | 
2025-12-03 06:12:15.180427 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-12-03 06:12:15.181223 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-12-03 06:12:15.713185 | 
2025-12-03 06:12:15.713286 | PLAY [all]
2025-12-03 06:12:15.731810 | 
2025-12-03 06:12:15.731885 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-12-03 06:12:15.791799 | controller | ok
2025-12-03 06:12:15.796267 | 
2025-12-03 06:12:15.796339 | TASK [mirror-info-fork : Create /etc/ci]
2025-12-03 06:12:16.109196 | controller | changed
2025-12-03 06:12:16.124842 | 
2025-12-03 06:12:16.124929 | TASK [mirror-info-fork : Install ci_mirror script]
2025-12-03 06:12:16.894296 | controller | changed
2025-12-03 06:12:16.904261 | 
2025-12-03 06:12:16.904328 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-12-03 06:12:17.233413 | controller | changed:
2025-12-03 06:12:17.233586 | controller | {
2025-12-03 06:12:17.233621 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-12-03 06:12:17.235081 | controller | }
2025-12-03 06:12:17.436028 | controller | changed:
2025-12-03 06:12:17.436082 | controller | {
2025-12-03 06:12:17.436110 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-12-03 06:12:17.436131 | controller | }
2025-12-03 06:12:17.644643 | controller | changed:
2025-12-03 06:12:17.644763 | controller | {
2025-12-03 06:12:17.644792 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com"
2025-12-03 06:12:17.644814 | controller | }
2025-12-03 06:12:17.849099 | controller | changed:
2025-12-03 06:12:17.849191 | controller | {
2025-12-03 06:12:17.849217 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-12-03 06:12:17.849237 | controller | }
2025-12-03 06:12:18.051589 | controller | changed:
2025-12-03 06:12:18.051678 | controller | {
2025-12-03 06:12:18.051725 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-12-03 06:12:18.051748 | controller | }
2025-12-03 06:12:18.264385 | controller | changed:
2025-12-03 06:12:18.264582 | controller | {
2025-12-03 06:12:18.264614 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-12-03 06:12:18.264635 | controller | }
2025-12-03 06:12:18.464794 | controller | changed:
2025-12-03 06:12:18.464890 | controller | {
2025-12-03 06:12:18.464920 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDLOQd4ZLtkZXQGY6UwAr/06ppWQK4fDO3HaqxPk98csyOCBXsliSKK39Bso828+5srIXiW7aI6aC9P5mwi4mUZlGPfJlQbfrcGvY+b/SocuvaGK+1RrHLoJCT52LBhwgrzlXio2jeksZeein8iaTrhsPrOAs7KggIL/rB9hEiB3NaOPWhhoCP4vlW6MEMExGcqB/1FVxXFBPnLkEyW0Lk7ycVflZl2ocRxbfjZi0+tI1Wlinp8PvSQSc/WVrAcDgKjc/mB4ODPOyYy3G8FHgfMsrXSDEyjBKgLKMsdCrAUcqJQWjkqXleXSYOV4q3pzL+9umK+q/e3P/bIoSFQzmJKTU1eDfuvPXmow9F5H54fii/Da7ezlMJ+wPGHJrRAkmzvMbALy7xwswLhZMkOGNtRcPqaKYRmIBKpw3o6bCTtcNUHOtOQnzwY8JzrM2eBWJBXAANYw+9/ho80JIiwhg29CFNpVBuHbql2YxJQNrnl90guN65rYNpDxdIluweyUf8= anbanerj@kaermorhen"
2025-12-03 06:12:18.464942 | controller | }
2025-12-03 06:12:18.674892 | controller | changed:
2025-12-03 06:12:18.674994 | controller | {
2025-12-03 06:12:18.675022 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQC3VwV8Im9kRm49lt3tM36hj4Zv27FxGo4C1Q/0jqhzFmHY7RHbmeRr8ObhwWoHjXSozKWg8FL5ER0z3hTwL0W6lez3sL7hUaCmSuZmG5Hnl3x4vTSxDI9JZ/Y65rtYiiWQo2fC5xJhU/4+0e5e/pseCm8cKRSu+SaxhO+sd6FDojA2x1BzOzKiQRDy/1zWGp/cZkxcEuB1wHI5LMzN03c67vmbu+fhZRAUO4dQkvcnj2LrhQtpa+ytvnSjr8icMDosf1OsbSffwZFyHB/hfWGAfe0eIeSA2XPraxiPknXxiPKx2MJsaUTYbsZcm3EjFdHBBMumw5rBI74zLrMRvCO9GwBEmGT4rFng1nP+yw5DB8sn2zqpOsPg1LYRwCPOUveC13P6pgsZZPh812e8v5EKnETct+5XI3dVpdw6CnNiLwAyVAF15DJvBGT/u1k0Myg/bQn+Gv9k2MSj6LvQmf6WbZu2Wgjm30z3FyCneBqTL7mLF19YXzeC0ufHz5pnO1E= dasm@fedora"
2025-12-03 06:12:18.675042 | controller | }
2025-12-03 06:12:18.901390 | controller | changed:
2025-12-03 06:12:18.901536 | controller | {
2025-12-03 06:12:18.901568 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-12-03 06:12:18.901589 | controller | }
2025-12-03 06:12:19.116889 | controller | changed:
2025-12-03 06:12:19.116970 | controller | {
2025-12-03 06:12:19.116996 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-12-03 06:12:19.117016 | controller | }
2025-12-03 06:12:19.322484 | controller | changed:
2025-12-03 06:12:19.322568 | controller | {
2025-12-03 06:12:19.322591 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-12-03 06:12:19.322609 | controller | }
2025-12-03 06:12:19.537688 | controller | changed:
2025-12-03 06:12:19.537790 | controller | {
2025-12-03 06:12:19.537817 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-12-03 06:12:19.537837 | controller | }
2025-12-03 06:12:19.709067 | controller | changed:
2025-12-03 06:12:19.709130 | controller | {
2025-12-03 06:12:19.709164 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-12-03 06:12:19.709192 | controller | }
2025-12-03 06:12:19.906845 | controller | changed:
2025-12-03 06:12:19.906896 | controller | {
2025-12-03 06:12:19.906921 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-12-03 06:12:19.906940 | controller | }
2025-12-03 06:12:20.115629 | controller | changed:
2025-12-03 06:12:20.116290 | controller | {
2025-12-03 06:12:20.116336 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-12-03 06:12:20.116384 | controller | }
2025-12-03 06:12:20.319478 | controller | changed:
2025-12-03 06:12:20.319528 | controller | {
2025-12-03 06:12:20.319551 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-12-03 06:12:20.319569 | controller | }
2025-12-03 06:12:20.510161 | controller | changed:
2025-12-03 06:12:20.510207 | controller | {
2025-12-03 06:12:20.510230 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQCfNtF7NvKl915TGsGGoseUb06Hj8L/S4toWf0hExeY+F00woL6NvBlJD0nDct+P5a22I4EhvoQCRQ8reaPCm1lybR3uiRIJsj+8zkVvLwby9LXzfZorlNG9ofjd00FEmB09uW/YvTl6Q9XwwwX6tInzIOv3TMqTHHGOL74ibbj8J/FJR0cFEyj0z4WQRvtkh32xAHl83gbuINryMt0sqRI+clj2381NKL55DRLQrVw0gsfqqxiHAnXg21qWmc4J+b9e9kiuAFQjcjwTVkwJCcg3xbPwC/qokYRby/Y5S40UUd7/jEARGXT7RZgpzTuDd1oZiCVrnrqJNPaMNdVv5MLeFdf1B7iIe5aa/fGouX7AO4SdKhZUdnJmCFAGvjC6S3JMZ2wAcUl+OHnssfmdj7XL50cLo27vjuzMtLAgSqi6N99m92WCF2s8J9aVzszX7Xz9OKZCeGsiVJp3/NdABKzSEAyM9xBD/5Vho894Sav+otpySHe3p6RUTgbB5Zu8VyZRZ/UtB3ueXxyo764yrc6qWIDqrehm84Xm9g+/jpIBzGPl07NUNJpdt/6Sgf9RIKXw/7XypO5yZfUcuFNGTxLfqjTNrtgLZNcjfav6sSdVXVcMPL//XNuRdKmVFaO76eV/oGMQGr1fGcCD+N+CpI7+Q+fCNB6VFWG4nZFuI/Iuw== averdagu@redhat.com"
2025-12-03 06:12:20.510375 | controller | }
2025-12-03 06:12:20.700610 | controller | changed:
2025-12-03 06:12:20.700657 | controller | {
2025-12-03 06:12:20.700681 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDq8l27xI+QlQVdS4djp9ogSoyrNE2+Ox6vKPdhSNL1J3PE5w+WCSvMz9A5gnNuH810zwbekEApbxTze/gLQJwBHA52CChfURpXrFaxY7ePXRElwKAL3mJfzBWY/c5jnNL9TCVmFJTGZkFZP3Nh+BMgZvL6xBkt3WKm6Uq18qzd9XeKcZusrA+O+uLv1fVeQnadY9RIqOCyeFYCzLWrUfTyE8x/XG0hAWIM7qpnF2cALQS2h9n4hW5ybiUN790H08wf9hFwEf5nxY9Z9dVkPFQiTSGKNBzmnCXU9skxS/xhpFjJ5duGSZdtAHe9O+nGZm9c67hxgtf8e5PDuqAdXEv2cf6e3VBAt+Bz8EKI3yosTj0oZHfwr42Yzb1l/SKy14Rggsrc9KAQlrGXan6+u2jcQqqx7l+SWmnpFiWTV9u5cWj2IgOhApOitmRBPYqk9rE2usfO0hLn/Pj/R/Nau4803e1/EikdLE7Ps95s9mX5jRDjAoUa2JwFF5RsVFyL910= ashigupt@ashigupt.remote.csb"
2025-12-03 06:12:20.700732 | controller | }
2025-12-03 06:12:20.893021 | controller | changed:
2025-12-03 06:12:20.893077 | controller | {
2025-12-03 06:12:20.893100 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-12-03 06:12:20.893117 | controller | }
2025-12-03 06:12:21.085464 | controller | changed:
2025-12-03 06:12:21.085558 | controller | {
2025-12-03 06:12:21.085585 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-12-03 06:12:21.085606 | controller | }
2025-12-03 06:12:21.289117 | controller | changed:
2025-12-03 06:12:21.289221 | controller | {
2025-12-03 06:12:21.289248 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-12-03 06:12:21.289268 | controller | }
2025-12-03 06:12:21.493009 | controller | changed:
2025-12-03 06:12:21.493177 | controller | {
2025-12-03 06:12:21.493203 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-12-03 06:12:21.493222 | controller | }
2025-12-03 06:12:21.698385 | controller | changed:
2025-12-03 06:12:21.698528 | controller | {
2025-12-03 06:12:21.698555 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQDZdI7t1cxYx65heVI24HTV4F7oQLW1zyfxHreL2TIJKxjyrUUKIFEUmTutcBlJRLNT2Eoix6x1sOw9YrchloCLcn//SGfTElr9mSc5jbjb7QXEU+zJMhtxyEJ1Po3CUGnj7ckiIXw7wcawZtrEOAQ9pH3ExYCJcEMiyNjRQZCxT3tPK+S4B95EWh5Fsrz9CkwpjNRPPH7LigCeQTM3Wc7r97utAslBUUvYceDSLA7rMgkitJE38b7rZBeYzsGQ8YYUBjTCtehqQXxCRjizbHWaaZkBU+N3zkKB6n/iCNGIO690NK7A/qb6msTijiz1PeuM8ThOsi9qXnbX5v0PoTpcFSojV7NHAQ71f0XXuS43FhZctT+Dcx44dT8Fb5vJu2cJGrk+qF8ZgJYNpRS7gPg0EG2EqjK7JMf9ULdjSu0r+KlqIAyLvtzT4eOnQipoKlb/WG5D/0ohKv7OMQ352ggfkBFIQsRXyyTCT98Ft9juqPuahi3CAQmP4H9dyE+7+Kz437PEtsxLmfm6naNmWi7Ee1DqWPwS8rEajsm4sNM4wW9gdBboJQtc0uZw0DfLj1I9r3Mc8Ol0jYtz0yNQDSzVLrGCaJlC311trU70tZ+ZkAVV6Mn8lOhSbj1cK0lvSr6ZK4dgqGl3I1eTZJJhbLNdg7UOVaiRx9543+C/p/As7w== brjackma@redhat.com"
2025-12-03 06:12:21.698577 | controller | }
2025-12-03 06:12:21.901915 | controller | changed:
2025-12-03 06:12:21.901998 | controller | {
2025-12-03 06:12:21.902026 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-12-03 06:12:21.902046 | controller | }
2025-12-03 06:12:22.094548 | controller | changed:
2025-12-03 06:12:22.094681 | controller | {
2025-12-03 06:12:22.094730 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-12-03 06:12:22.094753 | controller | }
2025-12-03 06:12:22.293197 | controller | changed:
2025-12-03 06:12:22.293254 | controller | {
2025-12-03 06:12:22.293280 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-12-03 06:12:22.293300 | controller | }
2025-12-03 06:12:22.312437 | 
2025-12-03 06:12:22.312545 | TASK [Set timezone to UTC]
2025-12-03 06:12:22.676338 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-12-03 06:12:22.680896 | 
2025-12-03 06:12:22.680957 | TASK [Create nodepool directory]
2025-12-03 06:12:22.872793 | controller | changed
2025-12-03 06:12:22.877995 | 
2025-12-03 06:12:22.878061 | TASK [Create nodepool sub_nodes file]
2025-12-03 06:12:23.356265 | controller | changed
2025-12-03 06:12:23.360980 | 
2025-12-03 06:12:23.361042 | TASK [Create nodepool sub_nodes_private file]
2025-12-03 06:12:23.835135 | controller | changed
2025-12-03 06:12:23.840847 | 
2025-12-03 06:12:23.840910 | LOOP [Populate nodepool sub_nodes file]
2025-12-03 06:12:23.870063 | 
2025-12-03 06:12:23.870200 | LOOP [Populate nodepool sub_nodes_private file]
2025-12-03 06:12:23.898615 | 
2025-12-03 06:12:23.898757 | TASK [Create nodepool primary file]
2025-12-03 06:12:23.921996 | controller | skipping: Conditional result was False
2025-12-03 06:12:23.927062 | 
2025-12-03 06:12:23.927153 | TASK [Create nodepool node_private for this node]
2025-12-03 06:12:24.440949 | controller | changed
2025-12-03 06:12:24.446521 | 
2025-12-03 06:12:24.446613 | LOOP [Copy ssh keys to nodepool directory]
2025-12-03 06:12:24.750477 | controller | ok: Item: id_rsa Runtime: 0:00:00.004231
2025-12-03 06:12:24.750866 | 
2025-12-03 06:12:24.900269 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.004217
2025-12-03 06:12:24.908707 | 
2025-12-03 06:12:24.908815 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-12-03 06:12:25.403491 | controller | changed
2025-12-03 06:12:25.408436 | 
2025-12-03 06:12:25.408511 | TASK [Validate sudoers config after edits]
2025-12-03 06:12:25.612937 | controller | /etc/sudoers: parsed OK
2025-12-03 06:12:25.612985 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-12-03 06:12:25.612994 | controller | /etc/sudoers.d/zuul: parsed OK
2025-12-03 06:12:25.613000 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-12-03 06:12:25.934960 | controller | ok: Runtime: 0:00:00.005048
2025-12-03 06:12:25.940225 | 
2025-12-03 06:12:25.940285 | TASK [Show the environment passed in to job shell scripts]
2025-12-03 06:12:26.146650 | controller | SHELL=/bin/bash
2025-12-03 06:12:26.146701 | controller | ZUUL_PROJECT=openstack-k8s-operators/edpm-ansible
2025-12-03 06:12:26.146711 | controller | ZUUL_SHORT_PROJECT_NAME=edpm-ansible
2025-12-03 06:12:26.146760 | controller | ZUUL_CHANGES=openstack-k8s-operators/edpm-ansible:main:refs/changes/54/1054/665b4c3ea80c1806be3227eb8ad3568c9583cf23
2025-12-03 06:12:26.146770 | controller | PWD=/home/zuul
2025-12-03 06:12:26.146776 | controller | ZUUL_PIPELINE=github-check
2025-12-03 06:12:26.146781 | controller | LOGNAME=zuul
2025-12-03 06:12:26.146787 | controller | XDG_SESSION_TYPE=tty
2025-12-03 06:12:26.146792 | controller | _=/usr/bin/env
2025-12-03 06:12:26.146797 | controller | MOTD_SHOWN=pam
2025-12-03 06:12:26.146802 | controller | HOME=/home/zuul
2025-12-03 06:12:26.146808 | controller | LANG=en_US.UTF-8
2025-12-03 06:12:26.146813 | controller | SSH_CONNECTION=192.168.25.12 33070 192.168.25.172 22
2025-12-03 06:12:26.146819 | controller | TOX_TESTENV_PASSENV=ZUUL_UUID ZUUL_PROJECT ZUUL_SHORT_PROJECT_NAME ZUUL_PIPELINE ZUUL_VOTING WORKSPACE BUILD_TIMEOUT ZUUL_BRANCH ZUUL_CHANGES ZUUL_CHANGE_IDS ZUUL_CHANGE ZUUL_PATCHSET
2025-12-03 06:12:26.146827 | controller | ZUUL_CHANGE_IDS=1054,665b4c3ea80c1806be3227eb8ad3568c9583cf23
2025-12-03 06:12:26.146833 | controller | WORKSPACE=/home/zuul/workspace
2025-12-03 06:12:26.146838 | controller | XDG_SESSION_CLASS=user
2025-12-03 06:12:26.146844 | controller | SELINUX_ROLE_REQUESTED=
2025-12-03 06:12:26.146849 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-12-03 06:12:26.146855 | controller | USER=zuul
2025-12-03 06:12:26.146860 | controller | ZUUL_VOTING=True
2025-12-03 06:12:26.146865 | controller | BUILD_TIMEOUT=1800000
2025-12-03 06:12:26.146871 | controller | SELINUX_USE_CURRENT_RANGE=
2025-12-03 06:12:26.146876 | controller | SHLVL=1
2025-12-03 06:12:26.146881 | controller | ZUUL_PATCHSET=665b4c3ea80c1806be3227eb8ad3568c9583cf23
2025-12-03 06:12:26.146887 | controller | XDG_SESSION_ID=1
2025-12-03 06:12:26.146892 | controller | ZUUL_BRANCH=main
2025-12-03 06:12:26.146897 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-12-03 06:12:26.146903 | controller | SSH_CLIENT=192.168.25.12 33070 22
2025-12-03 06:12:26.146909 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-12-03 06:12:26.146914 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-12-03 06:12:26.146920 | controller | which_declare=declare -f
2025-12-03 06:12:26.146925 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2025-12-03 06:12:26.146931 | controller | SELINUX_LEVEL_REQUESTED=
2025-12-03 06:12:26.146936 | controller | ZUUL_CHANGE=1054
2025-12-03 06:12:26.146941 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-12-03 06:12:26.146947 | controller | ZUUL_UUID=6276732edb4c4c96aa50de44e817f9d3
2025-12-03 06:12:26.146952 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-12-03 06:12:26.146958 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-12-03 06:12:26.146963 | controller | }
2025-12-03 06:12:26.461759 | controller | ok: Runtime: 0:00:00.005659
2025-12-03 06:12:26.467245 | 
2025-12-03 06:12:26.467310 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-12-03 06:12:26.481790 | controller | skipping: Conditional result was False
2025-12-03 06:12:26.487630 | 
2025-12-03 06:12:26.487728 | TASK [Symlink /home/zuul-worker/workspace]
2025-12-03 06:12:27.012809 | controller | skipping: Conditional result was False
2025-12-03 06:12:27.018553 | 
2025-12-03 06:12:27.018620 | TASK [Ensure legacy workspace directory]
2025-12-03 06:12:27.194810 | controller | changed
2025-12-03 06:12:27.213400 | 
2025-12-03 06:12:27.213476 | PLAY RECAP
2025-12-03 06:12:27.213525 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-12-03 06:12:27.213549 | 
2025-12-03 06:12:27.285506 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-12-03 06:12:27.286277 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-12-03 06:12:27.823377 | 
2025-12-03 06:12:27.823476 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-12-03 06:12:27.852045 | 
2025-12-03 06:12:27.852119 | TASK [Create zuul-output directory]
2025-12-03 06:12:28.154875 | controller | changed
2025-12-03 06:12:28.159939 | 
2025-12-03 06:12:28.160016 | TASK [Slurp Zuul inventory test]
2025-12-03 06:12:28.456947 | controller -> localhost | ok
2025-12-03 06:12:28.463370 | 
2025-12-03 06:12:28.463464 | TASK [Save zuul inventory]
2025-12-03 06:12:29.175070 | controller | changed
2025-12-03 06:12:29.180065 | 
2025-12-03 06:12:29.180142 | TASK [Save zuul vars without the change_message]
2025-12-03 06:12:29.731936 | controller | changed
2025-12-03 06:12:29.752084 | 
2025-12-03 06:12:29.752161 | PLAY RECAP
2025-12-03 06:12:29.752235 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-12-03 06:12:29.752264 | 
2025-12-03 06:12:29.817327 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-12-03 06:12:29.818121 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-12-03 06:12:30.356796 | 
2025-12-03 06:12:30.356892 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2025-12-03 06:12:30.376549 | 
2025-12-03 06:12:30.376618 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2025-12-03 06:12:30.394737 | controller | ok
2025-12-03 06:12:30.410822 | 
2025-12-03 06:12:30.410899 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-12-03 06:12:30.434262 | controller | skipping: Conditional result was False
2025-12-03 06:12:30.439878 | 
2025-12-03 06:12:30.439947 | TASK [mirror-info-fork : Create /etc/ci]
2025-12-03 06:12:30.751890 | controller | ok
2025-12-03 06:12:30.757741 | 
2025-12-03 06:12:30.757818 | TASK [mirror-info-fork : Install ci_mirror script]
2025-12-03 06:12:31.394119 | controller | ok
2025-12-03 06:12:31.407016 | 
2025-12-03 06:12:31.407210 | TASK [Prepare workspace]
2025-12-03 06:12:31.429198 | controller | ok
2025-12-03 06:12:31.447761 | 
2025-12-03 06:12:31.447836 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-12-03 06:12:31.794333 | controller | ok
2025-12-03 06:12:31.803147 | 
2025-12-03 06:12:31.803218 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-12-03 06:12:34.193561 | controller | Output suppressed because no_log was given
2025-12-03 06:12:34.202917 | 
2025-12-03 06:12:34.202981 | LOOP [Create zuul-output directory]
2025-12-03 06:12:34.383452 | controller | changed: "/home/zuul/ci-framework-data/logs"
2025-12-03 06:12:34.533753 | controller | ok: "/home/zuul/zuul-output/logs"
2025-12-03 06:12:34.547359 | 
2025-12-03 06:12:34.547453 | TASK [Install required packages]
2025-12-03 06:13:25.424455 | controller | changed
2025-12-03 06:13:25.429391 | 
2025-12-03 06:13:25.429458 | TASK [Install venv]
2025-12-03 06:14:24.994390 | controller | changed
2025-12-03 06:14:25.018085 | 
2025-12-03 06:14:25.018142 | PLAY RECAP
2025-12-03 06:14:25.018196 | controller | ok: 7 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-12-03 06:14:25.018219 | 
2025-12-03 06:14:25.086843 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-12-03 06:14:25.087657 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-12-03 06:14:25.556947 | 
2025-12-03 06:14:25.557036 | PLAY [Run ci/playbooks/molecule-test.yml]
2025-12-03 06:14:25.575264 | 
2025-12-03 06:14:25.575331 | TASK [Gather required facts]
2025-12-03 06:14:26.019487 | controller | ok
2025-12-03 06:14:26.024584 | 
2025-12-03 06:14:26.024657 | TASK [Load environment var if instructed to]
2025-12-03 06:14:26.037926 | controller | skipping: Conditional result was False
2025-12-03 06:14:26.042992 | 
2025-12-03 06:14:26.043051 | TASK [Ensure group_vars dir exists]
2025-12-03 06:14:26.324082 | controller | changed
2025-12-03 06:14:26.329397 | 
2025-12-03 06:14:26.329469 | TASK [Print related variables]
2025-12-03 06:14:26.347081 | controller | ok:
2025-12-03 06:14:26.347188 | controller | mol_config_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config_edpm_ansible.yml
2025-12-03 06:14:26.347217 | controller | roles_dir: /home/zuul/src/github.com/openstack-k8s-operators/edpm-ansible/roles/edpm_frr
2025-12-03 06:14:26.351390 | 
2025-12-03 06:14:26.351457 | TASK [Run molecule]
2025-12-03 06:14:27.285587 | controller | INFO     Performing prerun with role_name_check=0...
2025-12-03 06:14:28.061904 | controller | INFO     Running default > prepare
2025-12-03 06:14:28.704801 | controller |
2025-12-03 06:14:28.704868 | controller | PLAY [Prepare test_deps] *******************************************************
2025-12-03 06:14:28.705239 | controller |
2025-12-03 06:14:28.705265 | controller | TASK [set basic user fact] *****************************************************
2025-12-03 06:14:28.705284 | controller | Wednesday 03 December 2025  06:14:28 +0000 (0:00:00.012)       0:00:00.012 ****
2025-12-03 06:14:28.730377 | controller | ok: [compute-1]
2025-12-03 06:14:28.730478 | controller |
2025-12-03 06:14:28.730590 | controller | TASK [../../../../molecule/common/test_deps : Validating arguments against arg spec 'main' - The main entry point for the test_deps role.] ***
2025-12-03 06:14:28.730691 | controller | Wednesday 03 December 2025  06:14:28 +0000 (0:00:00.026)       0:00:00.038 ****
2025-12-03 06:14:28.744563 | controller | ok: [compute-1]
2025-12-03 06:14:28.744681 | controller |
2025-12-03 06:14:28.744813 | controller | TASK [../../../../molecule/common/test_deps : Gather facts if they don't exist] ***
2025-12-03 06:14:28.744936 | controller | Wednesday 03 December 2025  06:14:28 +0000 (0:00:00.014)       0:00:00.052 ****
2025-12-03 06:14:29.222250 | controller | ok: [compute-1]
2025-12-03 06:14:29.222311 | controller |
2025-12-03 06:14:29.222439 | controller | TASK [../../../../molecule/common/test_deps : Gather variables for each operating system] ***
2025-12-03 06:14:29.222539 | controller | Wednesday 03 December 2025  06:14:29 +0000 (0:00:00.477)       0:00:00.530 ****
2025-12-03 06:14:29.258375 | controller | ok: [compute-1] => (item=/home/zuul/src/github.com/openstack-k8s-operators/edpm-ansible/molecule/common/test_deps/vars/centos-9.yml)
2025-12-03 06:14:29.258499 | controller |
2025-12-03 06:14:29.258638 | controller | TASK [../../../../molecule/common/test_deps : Detect mirrors file] *************
2025-12-03 06:14:29.258772 | controller | Wednesday 03 December 2025  06:14:29 +0000 (0:00:00.036)       0:00:00.566 ****
2025-12-03 06:14:29.525877 | controller | ok: [compute-1]
2025-12-03 06:14:29.525955 | controller |
2025-12-03 06:14:29.526096 | controller | TASK [../../../../molecule/common/test_deps : Disable ubi host subscription-manager integration] ***
2025-12-03 06:14:29.526213 | controller | Wednesday 03 December 2025  06:14:29 +0000 (0:00:00.266)       0:00:00.833 ****
2025-12-03 06:14:29.815609 | controller | ok: [compute-1]
2025-12-03 06:14:29.815676 | controller |
2025-12-03 06:14:29.815792 | controller | TASK [../../../../molecule/common/test_deps : Remove ubi 9 repos] **************
2025-12-03 06:14:29.815901 | controller | Wednesday 03 December 2025  06:14:29 +0000 (0:00:00.286)       0:00:01.120 ****
2025-12-03 06:14:30.018498 | controller | ok: [compute-1]
2025-12-03 06:14:30.018594 | controller |
2025-12-03 06:14:30.018700 | controller | TASK [../../../../molecule/common/test_deps : Fetch latest repo version] *******
2025-12-03 06:14:30.018814 | controller | Wednesday 03 December 2025  06:14:30 +0000 (0:00:00.206)       0:00:01.326 ****
2025-12-03 06:14:30.540318 | controller | ok: [compute-1]
2025-12-03 06:14:30.540408 | controller |
2025-12-03 06:14:30.540515 | controller | TASK [../../../../molecule/common/test_deps : Create default repo file] ********
2025-12-03 06:14:30.540616 | controller | Wednesday 03 December 2025  06:14:30 +0000 (0:00:00.521)       0:00:01.848 ****
2025-12-03 06:14:30.993994 | controller | changed: [compute-1]
2025-12-03 06:14:30.994125 | controller |
2025-12-03 06:14:30.994242 | controller | TASK [../../../../molecule/common/test_deps : Install repo-setup pre-requirements] ***
2025-12-03 06:14:30.994365 | controller | Wednesday 03 December 2025  06:14:30 +0000 (0:00:00.453)       0:00:02.301 ****
2025-12-03 06:14:40.298101 | controller | ok: [compute-1]
2025-12-03 06:14:40.298154 | controller |
2025-12-03 06:14:40.298162 | controller | TASK [../../../../molecule/common/test_deps : Get repo-setup repository] *******
2025-12-03 06:14:40.298171 | controller | Wednesday 03 December 2025  06:14:40 +0000 (0:00:09.303)       0:00:11.605 ****
2025-12-03 06:14:41.308588 | controller | changed: [compute-1]
2025-12-03 06:14:44.540534 | controller |
2025-12-03 06:14:44.540564 | controller | TASK [../../../../molecule/common/test_deps : Install repo-setup] **************
2025-12-03 06:14:44.540572 | controller | Wednesday 03 December 2025  06:14:41 +0000 (0:00:01.010)       0:00:12.616 ****
2025-12-03 06:14:44.540582 | controller | changed: [compute-1]
2025-12-03 06:14:45.287932 | controller |
2025-12-03 06:14:45.287961 | controller | TASK [../../../../molecule/common/test_deps : Create repos] ********************
2025-12-03 06:14:45.287975 | controller | Wednesday 03 December 2025  06:14:44 +0000 (0:00:03.231)       0:00:15.848 ****
2025-12-03 06:14:45.287985 | controller | changed: [compute-1]
2025-12-03 06:14:45.488069 | controller |
2025-12-03 06:14:45.488094 | controller | TASK [../../../../molecule/common/test_deps : Look for redhat-release rpm] *****
2025-12-03 06:14:45.488102 | controller | Wednesday 03 December 2025  06:14:45 +0000 (0:00:00.747)       0:00:16.595 ****
2025-12-03 06:14:45.488113 | controller | fatal: [compute-1]: FAILED! => changed=true
2025-12-03 06:14:45.488745 | controller |   cmd: |-
2025-12-03 06:14:45.488758 | controller |     rpm -qe redhat-release
2025-12-03 06:14:45.488765 | controller |   delta: '0:00:00.011155'
2025-12-03 06:14:45.488770 | controller |   end: '2025-12-03 06:14:45.465045'
2025-12-03 06:14:45.488784 | controller |   msg: non-zero return code
2025-12-03 06:14:45.488793 | controller |   rc: 1
2025-12-03 06:14:45.492049 | controller |   start: '2025-12-03 06:14:45.453890'
2025-12-03 06:14:45.492065 | controller |   stderr: ''
2025-12-03 06:14:45.492072 | controller |   stderr_lines: <omitted>
2025-12-03 06:14:45.492078 | controller |   stdout: package redhat-release is not installed
2025-12-03 06:14:45.492083 | controller |   stdout_lines: <omitted>
2025-12-03 06:14:45.492094 | controller | ...ignoring
2025-12-03 06:14:45.492441 | controller |
2025-12-03 06:14:45.519971 | controller | TASK [../../../../molecule/common/test_deps : Workaround of redhat-release binary on ubi9] ***
2025-12-03 06:14:45.519986 | controller | Wednesday 03 December 2025  06:14:45 +0000 (0:00:00.204)       0:00:16.800 ****
2025-12-03 06:14:45.519995 | controller | skipping: [compute-1]
2025-12-03 06:16:04.740146 | controller |
2025-12-03 06:16:04.740238 | controller | TASK [../../../../molecule/common/test_deps : Install edpm packages] ***********
2025-12-03 06:16:04.740249 | controller | Wednesday 03 December 2025  06:14:45 +0000 (0:00:00.027)       0:00:16.827 ****
2025-12-03 06:16:04.740260 | controller | changed: [compute-1]
2025-12-03 06:16:04.740478 | controller |
2025-12-03 06:16:04.740708 | controller | TASK [../../../../molecule/common/test_deps : Install selinux python libs] *****
2025-12-03 06:16:04.741781 | controller | Wednesday 03 December 2025  06:16:04 +0000 (0:01:19.218)       0:01:36.046 ****
2025-12-03 06:16:06.089885 | controller | ok: [compute-1]
2025-12-03 06:16:07.454082 | controller |
2025-12-03 06:16:07.454113 | controller | TASK [../../../../molecule/common/test_deps : Install python yaml libs] ********
2025-12-03 06:16:07.454122 | controller | Wednesday 03 December 2025  06:16:06 +0000 (0:00:01.351)       0:01:37.397 ****
2025-12-03 06:16:07.454133 | controller | ok: [compute-1]
2025-12-03 06:16:07.454331 | controller |
2025-12-03 06:16:07.454346 | controller | TASK [../../../../molecule/common/test_deps : Install extra packages] **********
2025-12-03 06:16:08.837151 | controller | Wednesday 03 December 2025  06:16:07 +0000 (0:00:01.364)       0:01:38.761 ****
2025-12-03 06:16:08.837199 | controller | ok: [compute-1]
2025-12-03 06:16:09.017489 | controller |
2025-12-03 06:16:09.017514 | controller | TASK [../../../../molecule/common/test_deps : Check for /etc/ansible/facts.d/bootc.fact] ***
2025-12-03 06:16:09.017523 | controller | Wednesday 03 December 2025  06:16:08 +0000 (0:00:01.382)       0:01:40.144 ****
2025-12-03 06:16:09.017533 | controller | ok: [compute-1]
2025-12-03 06:16:09.205702 | controller |
2025-12-03 06:16:09.205725 | controller | TASK [../../../../molecule/common/test_deps : Create /etc/ansible/facts.d] *****
2025-12-03 06:16:09.205733 | controller | Wednesday 03 December 2025  06:16:09 +0000 (0:00:00.180)       0:01:40.325 ****
2025-12-03 06:16:09.205742 | controller | changed: [compute-1]
2025-12-03 06:16:09.561600 | controller |
2025-12-03 06:16:09.561634 | controller | TASK [../../../../molecule/common/test_deps : Create /etc/ansible/facts.d/bootc.fact] ***
2025-12-03 06:16:09.561642 | controller | Wednesday 03 December 2025  06:16:09 +0000 (0:00:00.188)       0:01:40.513 ****
2025-12-03 06:16:09.561652 | controller | changed: [compute-1]
2025-12-03 06:16:10.901628 | controller |
2025-12-03 06:16:10.901658 | controller | TASK [Install os-net-config] ***************************************************
2025-12-03 06:16:10.901666 | controller | Wednesday 03 December 2025  06:16:09 +0000 (0:00:00.355)       0:01:40.869 ****
2025-12-03 06:16:10.901678 | controller | ok: [compute-1]
2025-12-03 06:16:11.096551 | controller |
2025-12-03 06:16:11.096581 | controller | TASK [Create persistent directories] *******************************************
2025-12-03 06:16:11.096589 | controller | Wednesday 03 December 2025  06:16:10 +0000 (0:00:01.340)       0:01:42.209 ****
2025-12-03 06:16:11.096606 | controller | changed: [compute-1] => (item={'path': '/etc/tmpfiles.d/', 'owner': 'root', 'group': 'root', 'mode': '0755'})
2025-12-03 06:16:11.280696 | controller |
2025-12-03 06:16:11.280731 | controller | TASK [Create firewall directory] ***********************************************
2025-12-03 06:16:11.280741 | controller | Wednesday 03 December 2025  06:16:11 +0000 (0:00:00.194)       0:01:42.404 ****
2025-12-03 06:16:11.280752 | controller | changed: [compute-1]
2025-12-03 06:16:11.610487 | controller |
2025-12-03 06:16:11.610519 | controller | TASK [open port 22 (edpm_nftables will active this later)] *********************
2025-12-03 06:16:11.610527 | controller | Wednesday 03 December 2025  06:16:11 +0000 (0:00:00.183)       0:01:42.588 ****
2025-12-03 06:16:11.610537 | controller | changed: [compute-1]
2025-12-03 06:16:12.214986 | controller |
2025-12-03 06:16:12.215067 | controller | TASK [Force systemd to reread configs] *****************************************
2025-12-03 06:16:12.215079 | controller | Wednesday 03 December 2025  06:16:11 +0000 (0:00:00.329)       0:01:42.918 ****
2025-12-03 06:16:12.215089 | controller | ok: [compute-1]
2025-12-03 06:16:12.229865 | controller |
2025-12-03 06:16:12.229884 | controller | TASK [osp.edpm.edpm_bootstrap : Gather facts if they don't exist] **************
2025-12-03 06:16:12.229891 | controller | Wednesday 03 December 2025  06:16:12 +0000 (0:00:00.604)       0:01:43.522 ****
2025-12-03 06:16:12.229900 | controller | skipping: [compute-1]
2025-12-03 06:16:18.041881 | controller |
2025-12-03 06:16:18.041910 | controller | TASK [osp.edpm.edpm_bootstrap : Download needed packages] **********************
2025-12-03 06:16:18.041918 | controller | Wednesday 03 December 2025  06:16:12 +0000 (0:00:00.014)       0:01:43.537 ****
2025-12-03 06:16:18.041928 | controller | changed: [compute-1]
2025-12-03 06:16:19.166469 | controller |
2025-12-03 06:16:19.166498 | controller | TASK [osp.edpm.edpm_bootstrap : Download legacy network service package] *******
2025-12-03 06:16:19.166506 | controller | Wednesday 03 December 2025  06:16:18 +0000 (0:00:05.811)       0:01:49.349 ****
2025-12-03 06:16:19.166516 | controller | ok: [compute-1]
2025-12-03 06:16:19.195131 | controller |
2025-12-03 06:16:19.195186 | controller | TASK [osp.edpm.edpm_bootstrap : Download release version package] **************
2025-12-03 06:16:19.195196 | controller | Wednesday 03 December 2025  06:16:19 +0000 (0:00:01.124)       0:01:50.474 ****
2025-12-03 06:16:19.195209 | controller | skipping: [compute-1]
2025-12-03 06:16:19.195230 | controller |
2025-12-03 06:16:19.195238 | controller | TASK [osp.edpm.edpm_bootstrap : Include bootc tasks] ***************************
2025-12-03 06:16:19.195248 | controller | Wednesday 03 December 2025  06:16:19 +0000 (0:00:00.028)       0:01:50.502 ****
2025-12-03 06:16:19.217747 | controller | included: /home/zuul/.cache/ansible-compat/8075a4/collections/ansible_collections/osp/edpm/roles/edpm_bootstrap/tasks/bootc.yml for compute-1
2025-12-03 06:16:19.217845 | controller |
2025-12-03 06:16:19.217956 | controller | TASK [osp.edpm.edpm_bootstrap : Check if /etc/ansible/facts.d/bootc.fact exists] ***
2025-12-03 06:16:19.218102 | controller | Wednesday 03 December 2025  06:16:19 +0000 (0:00:00.023)       0:01:50.525 ****
2025-12-03 06:16:19.412693 | controller | ok: [compute-1]
2025-12-03 06:16:19.427078 | controller |
2025-12-03 06:16:19.427099 | controller | TASK [osp.edpm.edpm_bootstrap : Ensure /etc/ansible/facts.d exists] ************
2025-12-03 06:16:19.427108 | controller | Wednesday 03 December 2025  06:16:19 +0000 (0:00:00.194)       0:01:50.720 ****
2025-12-03 06:16:19.427119 | controller | skipping: [compute-1]
2025-12-03 06:16:19.427145 | controller |
2025-12-03 06:16:19.427347 | controller | TASK [osp.edpm.edpm_bootstrap : Ensure /etc/ansible/facts.d/bootc.fact exists] ***
2025-12-03 06:16:19.442001 | controller | Wednesday 03 December 2025  06:16:19 +0000 (0:00:00.014)       0:01:50.735 ****
2025-12-03 06:16:19.442035 | controller | skipping: [compute-1]
2025-12-03 06:16:19.456987 | controller |
2025-12-03 06:16:19.457005 | controller | TASK [osp.edpm.edpm_bootstrap : Gather ansible_local facts] ********************
2025-12-03 06:16:19.457045 | controller | Wednesday 03 December 2025  06:16:19 +0000 (0:00:00.014)       0:01:50.749 ****
2025-12-03 06:16:19.457055 | controller | skipping: [compute-1]
2025-12-03 06:16:19.662076 | controller |
2025-12-03 06:16:19.662106 | controller | TASK [osp.edpm.edpm_bootstrap : Ensure /var/log/journal exists] ****************
2025-12-03 06:16:19.662115 | controller | Wednesday 03 December 2025  06:16:19 +0000 (0:00:00.014)       0:01:50.764 ****
2025-12-03 06:16:19.662131 | controller | changed: [compute-1]
2025-12-03 06:16:19.662188 | controller |
2025-12-03 06:16:19.662204 | controller | TASK [osp.edpm.edpm_bootstrap : Gather user fact] ******************************
2025-12-03 06:16:19.662215 | controller | Wednesday 03 December 2025  06:16:19 +0000 (0:00:00.204)       0:01:50.969 ****
2025-12-03 06:16:19.681525 | controller | skipping: [compute-1]
2025-12-03 06:16:19.895105 | controller |
2025-12-03 06:16:19.895135 | controller | TASK [osp.edpm.edpm_bootstrap : Ensure /var/lib/config-data/ansible-generated exists and fix subdirs owner and mode if it existed] ***
2025-12-03 06:16:19.895144 | controller | Wednesday 03 December 2025  06:16:19 +0000 (0:00:00.019)       0:01:50.989 ****
2025-12-03 06:16:19.895166 | controller | changed: [compute-1]
2025-12-03 06:16:21.745620 | controller |
2025-12-03 06:16:21.745653 | controller | TASK [osp.edpm.edpm_bootstrap : Gather services facts] *************************
2025-12-03 06:16:21.745661 | controller | Wednesday 03 December 2025  06:16:19 +0000 (0:00:00.213)       0:01:51.202 ****
2025-12-03 06:16:21.745670 | controller | ok: [compute-1]
2025-12-03 06:16:21.796122 | controller |
2025-12-03 06:16:21.796164 | controller | TASK [osp.edpm.edpm_bootstrap : Print cloud-init service status] ***************
2025-12-03 06:16:21.796175 | controller | Wednesday 03 December 2025  06:16:21 +0000 (0:00:01.850)       0:01:53.053 ****
2025-12-03 06:16:21.796186 | controller | ok: [compute-1] =>
2025-12-03 06:16:21.796216 | controller |   ansible_facts.services["cloud-init.service"]:
2025-12-03 06:16:21.796224 | controller |     name: cloud-init.service
2025-12-03 06:16:21.796230 | controller |     source: systemd
2025-12-03 06:16:21.796239 | controller |     state: stopped
2025-12-03 06:16:21.796628 | controller |     status: enabled
2025-12-03 06:16:22.061534 | controller |
2025-12-03 06:16:22.061561 | controller | TASK [osp.edpm.edpm_bootstrap : Check if cloud-init is disabled via kernel args] ***
2025-12-03 06:16:22.061569 | controller | Wednesday 03 December 2025  06:16:21 +0000 (0:00:00.050)       0:01:53.103 ****
2025-12-03 06:16:22.061580 | controller | changed: [compute-1]
2025-12-03 06:16:22.090591 | controller |
2025-12-03 06:16:22.090610 | controller | TASK [osp.edpm.edpm_bootstrap : Wait for cloud-init to finish, if enabled] *****
2025-12-03 06:16:22.090618 | controller | Wednesday 03 December 2025  06:16:22 +0000 (0:00:00.265)       0:01:53.369 ****
2025-12-03 06:16:22.090627 | controller | skipping: [compute-1]
2025-12-03 06:16:22.371725 | controller |
2025-12-03 06:16:22.371755 | controller | TASK [osp.edpm.edpm_bootstrap : Re-read facts] *********************************
2025-12-03 06:16:22.371764 | controller | Wednesday 03 December 2025  06:16:22 +0000 (0:00:00.029)       0:01:53.398 ****
2025-12-03 06:16:22.371774 | controller | ok: [compute-1]
2025-12-03 06:16:22.388781 | controller |
2025-12-03 06:16:22.388801 | controller | TASK [osp.edpm.edpm_bootstrap : Bootstrap command] *****************************
2025-12-03 06:16:22.388810 | controller | Wednesday 03 December 2025  06:16:22 +0000 (0:00:00.280)       0:01:53.679 ****
2025-12-03 06:16:22.388821 | controller | skipping: [compute-1]
2025-12-03 06:16:22.405873 | controller |
2025-12-03 06:16:22.405892 | controller | TASK [osp.edpm.edpm_bootstrap : Bootstrap command output] **********************
2025-12-03 06:16:22.405900 | controller | Wednesday 03 December 2025  06:16:22 +0000 (0:00:00.017)       0:01:53.696 ****
2025-12-03 06:16:22.405908 | controller | skipping: [compute-1]
2025-12-03 06:16:22.423058 | controller |
2025-12-03 06:16:22.423078 | controller | TASK [osp.edpm.edpm_bootstrap : Create directory for ansible custom facts] *****
2025-12-03 06:16:22.423085 | controller | Wednesday 03 December 2025  06:16:22 +0000 (0:00:00.017)       0:01:53.713 ****
2025-12-03 06:16:22.423095 | controller | skipping: [compute-1]
2025-12-03 06:16:22.440541 | controller |
2025-12-03 06:16:22.440561 | controller | TASK [osp.edpm.edpm_bootstrap : Mark bootstrap command as executed on node] ****
2025-12-03 06:16:22.440569 | controller | Wednesday 03 December 2025  06:16:22 +0000 (0:00:00.017)       0:01:53.730 ****
2025-12-03 06:16:22.440580 | controller | skipping: [compute-1]
2025-12-03 06:16:22.476899 | controller |
2025-12-03 06:16:22.476919 | controller | TASK [osp.edpm.edpm_bootstrap : Include packages tasks] ************************
2025-12-03 06:16:22.476927 | controller | Wednesday 03 December 2025  06:16:22 +0000 (0:00:00.017)       0:01:53.748 ****
2025-12-03 06:16:22.476938 | controller | included: /home/zuul/.cache/ansible-compat/8075a4/collections/ansible_collections/osp/edpm/roles/edpm_bootstrap/tasks/packages.yml for compute-1
2025-12-03 06:16:22.495599 | controller |
2025-12-03 06:16:22.495618 | controller | TASK [osp.edpm.edpm_bootstrap : Gather facts if they don't exist] **************
2025-12-03 06:16:22.495627 | controller | Wednesday 03 December 2025  06:16:22 +0000 (0:00:00.036)       0:01:53.784 ****
2025-12-03 06:16:22.495637 | controller | skipping: [compute-1]
2025-12-03 06:16:22.527602 | controller |
2025-12-03 06:16:22.527621 | controller | TASK [osp.edpm.edpm_bootstrap : Install release version package] ***************
2025-12-03 06:16:22.527629 | controller | Wednesday 03 December 2025  06:16:22 +0000 (0:00:00.018)       0:01:53.803 ****
2025-12-03 06:16:22.527640 | controller | skipping: [compute-1]
2025-12-03 06:17:07.139396 | controller |
2025-12-03 06:17:07.139428 | controller | TASK [osp.edpm.edpm_bootstrap : Install required packages to bootstrap EDPM] ***
2025-12-03 06:17:07.139437 | controller | Wednesday 03 December 2025  06:16:22 +0000 (0:00:00.031)       0:01:53.835 ****
2025-12-03 06:17:07.139446 | controller | changed: [compute-1]
2025-12-03 06:17:07.948180 | controller |
2025-12-03 06:17:07.948212 | controller | TASK [osp.edpm.edpm_bootstrap : Ensure packages are actually well installed] ***
2025-12-03 06:17:07.948226 | controller | Wednesday 03 December 2025  06:17:07 +0000 (0:00:44.611)       0:02:38.446 ****
2025-12-03 06:17:07.948237 | controller | ok: [compute-1]
2025-12-03 06:17:08.430404 | controller |
2025-12-03 06:17:08.430433 | controller | TASK [osp.edpm.edpm_bootstrap : Set selinux state] *****************************
2025-12-03 06:17:08.430442 | controller | Wednesday 03 December 2025  06:17:07 +0000 (0:00:00.808)       0:02:39.255 ****
2025-12-03 06:17:08.430452 | controller | ok: [compute-1]
2025-12-03 06:17:08.464199 | controller |
2025-12-03 06:17:08.464246 | controller | TASK [osp.edpm.edpm_bootstrap : Include swap tasks] ****************************
2025-12-03 06:17:08.464261 | controller | Wednesday 03 December 2025  06:17:08 +0000 (0:00:00.482)       0:02:39.738 ****
2025-12-03 06:17:08.464278 | controller | included: /home/zuul/.cache/ansible-compat/8075a4/collections/ansible_collections/osp/edpm/roles/edpm_bootstrap/tasks/swap.yml for compute-1
2025-12-03 06:17:08.485808 | controller |
2025-12-03 06:17:08.485842 | controller | TASK [osp.edpm.edpm_bootstrap : Gather ansible_local facts] ********************
2025-12-03 06:17:08.485856 | controller | Wednesday 03 December 2025  06:17:08 +0000 (0:00:00.034)       0:02:39.772 ****
2025-12-03 06:17:08.485872 | controller | skipping: [compute-1]
2025-12-03 06:17:08.485911 | controller |
2025-12-03 06:17:08.486048 | controller | TASK [osp.edpm.edpm_bootstrap : Set swap path] *********************************
2025-12-03 06:17:08.486064 | controller | Wednesday 03 December 2025  06:17:08 +0000 (0:00:00.021)       0:02:39.793 ****
2025-12-03 06:17:08.518484 | controller | ok: [compute-1]
2025-12-03 06:17:08.518539 | controller |
2025-12-03 06:17:08.518595 | controller | TASK [osp.edpm.edpm_bootstrap : Create swapfile if needed] *********************
2025-12-03 06:17:08.518716 | controller | Wednesday 03 December 2025  06:17:08 +0000 (0:00:00.032)       0:02:39.826 ****
2025-12-03 06:17:09.271414 | controller | changed: [compute-1]
2025-12-03 06:17:09.271494 | controller |
2025-12-03 06:17:09.271739 | controller | TASK [osp.edpm.edpm_bootstrap : Set permission on swapfile] ********************
2025-12-03 06:17:09.474328 | controller | Wednesday 03 December 2025  06:17:09 +0000 (0:00:00.752)       0:02:40.579 ****
2025-12-03 06:17:09.474355 | controller | ok: [compute-1]
2025-12-03 06:17:09.749080 | controller |
2025-12-03 06:17:09.749116 | controller | TASK [osp.edpm.edpm_bootstrap : Enable swapfile on fstab] **********************
2025-12-03 06:17:09.749124 | controller | Wednesday 03 December 2025  06:17:09 +0000 (0:00:00.202)       0:02:40.782 ****
2025-12-03 06:17:09.749133 | controller | changed: [compute-1]
2025-12-03 06:17:09.776479 | controller |
2025-12-03 06:17:09.776496 | controller | TASK [osp.edpm.edpm_bootstrap : Check if partition exists by its label] ********
2025-12-03 06:17:09.776503 | controller | Wednesday 03 December 2025  06:17:09 +0000 (0:00:00.274)       0:02:41.057 ****
2025-12-03 06:17:09.776511 | controller | skipping: [compute-1]
2025-12-03 06:17:09.803914 | controller |
2025-12-03 06:17:09.803931 | controller | TASK [osp.edpm.edpm_bootstrap : Enable swap partition on fstab] ****************
2025-12-03 06:17:09.803938 | controller | Wednesday 03 December 2025  06:17:09 +0000 (0:00:00.027)       0:02:41.084 ****
2025-12-03 06:17:09.803947 | controller | skipping: [compute-1]
2025-12-03 06:17:09.830941 | controller |
2025-12-03 06:17:09.830958 | controller | TASK [osp.edpm.edpm_bootstrap : Gather swap partition by its type] *************
2025-12-03 06:17:09.830970 | controller | Wednesday 03 December 2025  06:17:09 +0000 (0:00:00.027)       0:02:41.111 ****
2025-12-03 06:17:09.830979 | controller | skipping: [compute-1]
2025-12-03 06:17:09.860137 | controller |
2025-12-03 06:17:09.860172 | controller | TASK [osp.edpm.edpm_bootstrap : Enable swap partitions in fstab] ***************
2025-12-03 06:17:09.860193 | controller | Wednesday 03 December 2025  06:17:09 +0000 (0:00:00.027)       0:02:41.138 ****
2025-12-03 06:17:09.860207 | controller | skipping: [compute-1]
2025-12-03 06:17:09.860236 | controller |
2025-12-03 06:17:09.860244 | controller | TASK [osp.edpm.edpm_bootstrap : Activate swap partitions] **********************
2025-12-03 06:17:09.860254 | controller | Wednesday 03 December 2025  06:17:09 +0000 (0:00:00.029)       0:02:41.168 ****
2025-12-03 06:17:09.887943 | controller | skipping: [compute-1]
2025-12-03 06:17:10.066003 | controller |
2025-12-03 06:17:10.066035 | controller | TASK [osp.edpm.edpm_bootstrap : Check if CA cert file exists] ******************
2025-12-03 06:17:10.066043 | controller | Wednesday 03 December 2025  06:17:09 +0000 (0:00:00.027)       0:02:41.195 ****
2025-12-03 06:17:10.066051 | controller | ok: [compute-1 -> localhost]
2025-12-03 06:17:10.084939 | controller |
2025-12-03 06:17:10.084968 | controller | TASK [osp.edpm.edpm_bootstrap : Ensure that the CA destination directory exists] ***
2025-12-03 06:17:10.084980 | controller | Wednesday 03 December 2025  06:17:10 +0000 (0:00:00.178)       0:02:41.373 ****
2025-12-03 06:17:10.084990 | controller | skipping: [compute-1]
2025-12-03 06:17:10.102116 | controller |
2025-12-03 06:17:10.102144 | controller | TASK [osp.edpm.edpm_bootstrap : Copy CA certs to the standard location on the compute node] ***
2025-12-03 06:17:10.102154 | controller | Wednesday 03 December 2025  06:17:10 +0000 (0:00:00.018)       0:02:41.392 ****
2025-12-03 06:17:10.102166 | controller | skipping: [compute-1]
2025-12-03 06:17:10.102623 | controller |
2025-12-03 06:17:10.131112 | controller | TASK [osp.edpm.edpm_bootstrap : Gather ansible_local facts] ********************
2025-12-03 06:17:10.131142 | controller | Wednesday 03 December 2025  06:17:10 +0000 (0:00:00.017)       0:02:41.410 ****
2025-12-03 06:17:10.131156 | controller | skipping: [compute-1]
2025-12-03 06:17:10.131184 | controller |
2025-12-03 06:17:10.131195 | controller | TASK [osp.edpm.edpm_bootstrap : Check FIPS status] *****************************
2025-12-03 06:17:10.131254 | controller | Wednesday 03 December 2025  06:17:10 +0000 (0:00:00.028)       0:02:41.438 ****
2025-12-03 06:17:10.158879 | controller | skipping: [compute-1]
2025-12-03 06:17:10.159038 | controller |
2025-12-03 06:17:10.159196 | controller | TASK [osp.edpm.edpm_bootstrap : Warn for changing FIPS status for bootc] *******
2025-12-03 06:17:10.159325 | controller | Wednesday 03 December 2025  06:17:10 +0000 (0:00:00.028)       0:02:41.467 ****
2025-12-03 06:17:10.189329 | controller | skipping: [compute-1]
2025-12-03 06:17:10.189599 | controller |
2025-12-03 06:17:10.189870 | controller | TASK [osp.edpm.edpm_bootstrap : Enable FIPS] ***********************************
2025-12-03 06:17:10.190174 | controller | Wednesday 03 December 2025  06:17:10 +0000 (0:00:00.030)       0:02:41.497 ****
2025-12-03 06:17:10.217603 | controller | skipping: [compute-1]
2025-12-03 06:17:10.217872 | controller |
2025-12-03 06:17:10.218190 | controller | TASK [osp.edpm.edpm_bootstrap : Disable FIPS] **********************************
2025-12-03 06:17:10.218364 | controller | Wednesday 03 December 2025  06:17:10 +0000 (0:00:00.028)       0:02:41.525 ****
2025-12-03 06:17:10.245682 | controller | skipping: [compute-1]
2025-12-03 06:17:10.245820 | controller |
2025-12-03 06:17:10.245956 | controller | TASK [osp.edpm.edpm_bootstrap : Create directory required by edpm-reboot role] ***
2025-12-03 06:17:10.246116 | controller | Wednesday 03 December 2025  06:17:10 +0000 (0:00:00.028)       0:02:41.553 ****
2025-12-03 06:17:10.274042 | controller | skipping: [compute-1]
2025-12-03 06:17:10.274199 | controller |
2025-12-03 06:17:10.274339 | controller | TASK [osp.edpm.edpm_bootstrap : Create required file to enforce a reboot] ******
2025-12-03 06:17:10.274467 | controller | Wednesday 03 December 2025  06:17:10 +0000 (0:00:00.028)       0:02:41.582 ****
2025-12-03 06:17:10.302644 | controller | skipping: [compute-1]
2025-12-03 06:17:10.302913 | controller |
2025-12-03 06:17:10.303240 | controller | TASK [Call edpm_reboot role] ***************************************************
2025-12-03 06:17:10.303468 | controller | Wednesday 03 December 2025  06:17:10 +0000 (0:00:00.028)       0:02:41.610 ****
2025-12-03 06:17:10.330772 | controller | skipping: [compute-1]
2025-12-03 06:17:10.330972 | controller |
2025-12-03 06:17:10.331151 | controller | TASK [osp.edpm.edpm_bootstrap : Ensure that the proper FIPS status is enabled] ***
2025-12-03 06:17:10.331283 | controller | Wednesday 03 December 2025  06:17:10 +0000 (0:00:00.028)       0:02:41.639 ****
2025-12-03 06:17:10.361659 | controller | skipping: [compute-1]
2025-12-03 06:17:10.361927 | controller |
2025-12-03 06:17:10.362248 | controller | TASK [osp.edpm.edpm_bootstrap : Check if the LVM devices file exists] **********
2025-12-03 06:17:10.362503 | controller | Wednesday 03 December 2025  06:17:10 +0000 (0:00:00.030)       0:02:41.669 ****
2025-12-03 06:17:10.552674 | controller | ok: [compute-1]
2025-12-03 06:17:10.796475 | controller |
2025-12-03 06:17:10.796496 | controller | TASK [osp.edpm.edpm_bootstrap : Import any existing LVM devices] ***************
2025-12-03 06:17:10.796503 | controller | Wednesday 03 December 2025  06:17:10 +0000 (0:00:00.190)       0:02:41.860 ****
2025-12-03 06:17:10.796512 | controller | changed: [compute-1]
2025-12-03 06:17:11.013511 | controller |
2025-12-03 06:17:11.013537 | controller | TASK [osp.edpm.edpm_bootstrap : Create an empty LVM devices file] **************
2025-12-03 06:17:11.013547 | controller | Wednesday 03 December 2025  06:17:10 +0000 (0:00:00.243)       0:02:42.104 ****
2025-12-03 06:17:11.013559 | controller | changed: [compute-1]
2025-12-03 06:17:11.464944 | controller |
2025-12-03 06:17:11.464972 | controller | RUNNING HANDLER [osp.edpm.edpm_bootstrap : Setup linux swap area on file if needed] ***
2025-12-03 06:17:11.464980 | controller | Wednesday 03 December 2025  06:17:11 +0000 (0:00:00.216)       0:02:42.321 ****
2025-12-03 06:17:11.464991 | controller | changed: [compute-1]
2025-12-03 06:17:11.669085 | controller |
2025-12-03 06:17:11.669125 | controller | RUNNING HANDLER [osp.edpm.edpm_bootstrap : Activate swap] **********************
2025-12-03 06:17:11.669134 | controller | Wednesday 03 December 2025  06:17:11 +0000 (0:00:00.451)       0:02:42.772 ****
2025-12-03 06:17:11.669147 | controller | changed: [compute-1]
2025-12-03 06:17:11.669172 | controller |
2025-12-03 06:17:11.669179 | controller | PLAY RECAP *********************************************************************
2025-12-03 06:17:11.669185 | controller | compute-1                  : ok=51   changed=22   unreachable=0    failed=0    skipped=31   rescued=0    ignored=1
2025-12-03 06:17:11.669191 | controller |
2025-12-03 06:17:11.669197 | controller | Wednesday 03 December 2025  06:17:11 +0000 (0:00:00.202)       0:02:42.974 ****
2025-12-03 06:17:11.669202 | controller | ===============================================================================
2025-12-03 06:17:11.669208 | controller | ../../../../molecule/common/test_deps : Install edpm packages ---------- 79.22s
2025-12-03 06:17:11.669217 | controller | osp.edpm.edpm_bootstrap : Install required packages to bootstrap EDPM -- 44.61s
2025-12-03 06:17:11.669223 | controller | ../../../../molecule/common/test_deps : Install repo-setup pre-requirements --- 9.30s
2025-12-03 06:17:11.669229 | controller | osp.edpm.edpm_bootstrap : Download needed packages ---------------------- 5.81s
2025-12-03 06:17:11.669234 | controller | ../../../../molecule/common/test_deps : Install repo-setup -------------- 3.23s
2025-12-03 06:17:11.669239 | controller | osp.edpm.edpm_bootstrap : Gather services facts ------------------------- 1.85s
2025-12-03 06:17:11.669245 | controller | ../../../../molecule/common/test_deps : Install extra packages ---------- 1.38s
2025-12-03 06:17:11.669250 | controller | ../../../../molecule/common/test_deps : Install python yaml libs -------- 1.36s
2025-12-03 06:17:11.669256 | controller | ../../../../molecule/common/test_deps : Install selinux python libs ----- 1.35s
2025-12-03 06:17:11.669261 | controller | Install os-net-config --------------------------------------------------- 1.34s
2025-12-03 06:17:11.669266 | controller | osp.edpm.edpm_bootstrap : Download legacy network service package ------- 1.12s
2025-12-03 06:17:11.669272 | controller | ../../../../molecule/common/test_deps : Get repo-setup repository ------- 1.01s
2025-12-03 06:17:11.669277 | controller | osp.edpm.edpm_bootstrap : Ensure packages are actually well installed --- 0.81s
2025-12-03 06:17:11.669282 | controller | osp.edpm.edpm_bootstrap : Create swapfile if needed --------------------- 0.75s
2025-12-03 06:17:11.669288 | controller | ../../../../molecule/common/test_deps : Create repos -------------------- 0.75s
2025-12-03 06:17:11.669293 | controller | Force systemd to reread configs ----------------------------------------- 0.60s
2025-12-03 06:17:11.669302 | controller | ../../../../molecule/common/test_deps : Fetch latest repo version ------- 0.52s
2025-12-03 06:17:11.669308 | controller | osp.edpm.edpm_bootstrap : Set selinux state ----------------------------- 0.48s
2025-12-03 06:17:11.669316 | controller | ../../../../molecule/common/test_deps : Gather facts if they don't exist --- 0.48s
2025-12-03 06:17:11.728653 | controller | ../../../../molecule/common/test_deps : Create default repo file -------- 0.45s
2025-12-03 06:17:11.728685 | controller | INFO     Running default > converge
2025-12-03 06:17:12.116067 | controller |
2025-12-03 06:17:12.157490 | controller | PLAY [Converge] ****************************************************************
2025-12-03 06:17:12.157507 | controller |
2025-12-03 06:17:12.157514 | controller | TASK [set basic user fact] *****************************************************
2025-12-03 06:17:12.157519 | controller | Wednesday 03 December 2025  06:17:12 +0000 (0:00:00.013)       0:00:00.013 ****
2025-12-03 06:17:12.157527 | controller | ok: [compute-1]
2025-12-03 06:17:12.189476 | controller |
2025-12-03 06:17:12.189498 | controller | TASK [Download required role packages] *****************************************
2025-12-03 06:17:12.189508 | controller | Wednesday 03 December 2025  06:17:12 +0000 (0:00:00.041)       0:00:00.055 ****
2025-12-03 06:17:12.189518 | controller |
2025-12-03 06:17:17.904929 | controller | TASK [osp.edpm.edpm_frr : Download needed container images] ********************
2025-12-03 06:17:17.904965 | controller | Wednesday 03 December 2025  06:17:12 +0000 (0:00:00.032)       0:00:00.087 ****
2025-12-03 06:17:17.904977 | controller | changed: [compute-1] => (item=quay.io/podified-antelope-centos9/openstack-frr:current-podified)
2025-12-03 06:17:17.966881 | controller |
2025-12-03 06:17:17.966899 | controller | TASK [ansible.builtin.include_role : osp.edpm.edpm_frr] ************************
2025-12-03 06:17:17.966906 | controller | Wednesday 03 December 2025  06:17:17 +0000 (0:00:05.715)       0:00:05.803 ****
2025-12-03 06:17:17.966914 | controller |
2025-12-03 06:17:18.004147 | controller | TASK [osp.edpm.edpm_frr : Validating arguments against arg spec 'main' - The main entry point for the edpm_frr role.] ***
2025-12-03 06:17:18.004173 | controller | Wednesday 03 December 2025  06:17:17 +0000 (0:00:00.062)       0:00:05.865 ****
2025-12-03 06:17:18.004186 | controller | ok: [compute-1]
2025-12-03 06:17:18.027690 | controller |
2025-12-03 06:17:18.027711 | controller | TASK [osp.edpm.edpm_frr : Gather user fact] ************************************
2025-12-03 06:17:18.027720 | controller | Wednesday 03 December 2025  06:17:18 +0000 (0:00:00.037)       0:00:05.902 ****
2025-12-03 06:17:18.027737 | controller | skipping: [compute-1]
2025-12-03 06:17:18.714384 | controller |
2025-12-03 06:17:18.714416 | controller | TASK [osp.edpm.edpm_frr : Create persistent directories] ***********************
2025-12-03 06:17:18.714425 | controller | Wednesday 03 December 2025  06:17:18 +0000 (0:00:00.023)       0:00:05.926 ****
2025-12-03 06:17:18.714435 | controller | changed: [compute-1] => (item={'path': '/var/lib/openstack/frr', 'setype': 'container_file_t', 'mode': '0750'})
2025-12-03 06:17:19.244669 | controller | changed: [compute-1] => (item={'path': '/run/frr', 'setype': 'container_file_t', 'mode': '0755'})
2025-12-03 06:17:19.244698 | controller | changed: [compute-1] => (item={'path': '/var/lib/edpm-config/firewall', 'setype': 'container_file_t', 'mode': '0750'})
2025-12-03 06:17:19.244706 | controller |
2025-12-03 06:17:19.244712 | controller | TASK [osp.edpm.edpm_frr : Ensure /run/frr is present upon reboot] **************
2025-12-03 06:17:19.244717 | controller | Wednesday 03 December 2025  06:17:18 +0000 (0:00:00.686)       0:00:06.612 ****
2025-12-03 06:17:19.244726 | controller | changed: [compute-1]
2025-12-03 06:17:19.694456 | controller |
2025-12-03 06:17:19.694479 | controller | TASK [osp.edpm.edpm_frr : Gather SELinux fact if needed] ***********************
2025-12-03 06:17:19.694487 | controller | Wednesday 03 December 2025  06:17:19 +0000 (0:00:00.530)       0:00:07.143 ****
2025-12-03 06:17:19.694496 | controller | ok: [compute-1]
2025-12-03 06:17:19.907758 | controller |
2025-12-03 06:17:19.907786 | controller | TASK [osp.edpm.edpm_frr : Create directory /var/lib/openstack/frr] *************
2025-12-03 06:17:19.907794 | controller | Wednesday 03 December 2025  06:17:19 +0000 (0:00:00.449)       0:00:07.592 ****
2025-12-03 06:17:19.907804 | controller | changed: [compute-1]
2025-12-03 06:17:20.207903 | controller |
2025-12-03 06:17:20.207941 | controller | TASK [osp.edpm.edpm_frr : Inject firewall rules for frr BGP and BFD] ***********
2025-12-03 06:17:20.207949 | controller | Wednesday 03 December 2025  06:17:19 +0000 (0:00:00.213)       0:00:07.806 ****
2025-12-03 06:17:20.207960 | controller | changed: [compute-1]
2025-12-03 06:17:20.248169 | controller |
2025-12-03 06:17:20.248205 | controller | TASK [Configure firewall for frr BGP and BFD] **********************************
2025-12-03 06:17:20.248215 | controller | Wednesday 03 December 2025  06:17:20 +0000 (0:00:00.300)       0:00:08.106 ****
2025-12-03 06:17:20.248228 | controller |
2025-12-03 06:17:20.248260 | controller | TASK [osp.edpm.edpm_nftables : Create snippets directory] **********************
2025-12-03 06:17:20.248335 | controller | Wednesday 03 December 2025  06:17:20 +0000 (0:00:00.040)       0:00:08.146 ****
2025-12-03 06:17:20.447742 | controller | changed: [compute-1]
2025-12-03 06:17:20.810587 | controller |
2025-12-03 06:17:20.810618 | controller | TASK [osp.edpm.edpm_nftables : Push default ruleset snipet] ********************
2025-12-03 06:17:20.810626 | controller | Wednesday 03 December 2025  06:17:20 +0000 (0:00:00.199)       0:00:08.345 ****
2025-12-03 06:17:20.810636 | controller | changed: [compute-1]
2025-12-03 06:17:21.173862 | controller |
2025-12-03 06:17:21.173894 | controller | TASK [osp.edpm.edpm_nftables : Write user rules snippet] ***********************
2025-12-03 06:17:21.173902 | controller | Wednesday 03 December 2025  06:17:20 +0000 (0:00:00.362)       0:00:08.708 ****
2025-12-03 06:17:21.173912 | controller | changed: [compute-1]
2025-12-03 06:17:21.531088 | controller |
2025-12-03 06:17:21.531120 | controller | TASK [osp.edpm.edpm_nftables : Push empty ruleset] *****************************
2025-12-03 06:17:21.531128 | controller | Wednesday 03 December 2025  06:17:21 +0000 (0:00:00.363)       0:00:09.072 ****
2025-12-03 06:17:21.531138 | controller | changed: [compute-1]
2025-12-03 06:17:21.834651 | controller |
2025-12-03 06:17:21.834689 | controller | TASK [osp.edpm.edpm_nftables : Load empty ruleset] *****************************
2025-12-03 06:17:21.834701 | controller | Wednesday 03 December 2025  06:17:21 +0000 (0:00:00.357)       0:00:09.429 ****
2025-12-03 06:17:21.834714 | controller | changed: [compute-1]
2025-12-03 06:17:22.038726 | controller |
2025-12-03 06:17:22.038755 | controller | TASK [osp.edpm.edpm_nftables : Get current nftables content] *******************
2025-12-03 06:17:22.038764 | controller | Wednesday 03 December 2025  06:17:21 +0000 (0:00:00.303)       0:00:09.732 ****
2025-12-03 06:17:22.038774 | controller | changed: [compute-1]
2025-12-03 06:17:22.315453 | controller |
2025-12-03 06:17:22.315477 | controller | TASK [osp.edpm.edpm_nftables : Load firewall snippets] *************************
2025-12-03 06:17:22.315485 | controller | Wednesday 03 December 2025  06:17:22 +0000 (0:00:00.204)       0:00:09.937 ****
2025-12-03 06:17:22.315495 | controller | ok: [compute-1]
2025-12-03 06:17:22.717113 | controller |
2025-12-03 06:17:22.717148 | controller | TASK [osp.edpm.edpm_nftables : Generate chain jumps] ***************************
2025-12-03 06:17:22.717156 | controller | Wednesday 03 December 2025  06:17:22 +0000 (0:00:00.276)       0:00:10.213 ****
2025-12-03 06:17:22.717168 | controller | changed: [compute-1]
2025-12-03 06:17:22.717424 | controller |
2025-12-03 06:17:23.092226 | controller | TASK [osp.edpm.edpm_nftables : Generate chain jumps] ***************************
2025-12-03 06:17:23.092261 | controller | Wednesday 03 December 2025  06:17:22 +0000 (0:00:00.401)       0:00:10.615 ****
2025-12-03 06:17:23.092274 | controller | changed: [compute-1]
2025-12-03 06:17:23.442565 | controller |
2025-12-03 06:17:23.442591 | controller | TASK [osp.edpm.edpm_nftables : Generate nft flushes] ***************************
2025-12-03 06:17:23.442608 | controller | Wednesday 03 December 2025  06:17:23 +0000 (0:00:00.374)       0:00:10.990 ****
2025-12-03 06:17:23.442619 | controller | changed: [compute-1]
2025-12-03 06:17:23.800837 | controller |
2025-12-03 06:17:23.800865 | controller | TASK [osp.edpm.edpm_nftables : Generate nft edpm chains] ***********************
2025-12-03 06:17:23.800874 | controller | Wednesday 03 December 2025  06:17:23 +0000 (0:00:00.350)       0:00:11.341 ****
2025-12-03 06:17:23.800884 | controller | changed: [compute-1]
2025-12-03 06:17:24.197426 | controller |
2025-12-03 06:17:24.197452 | controller | TASK [osp.edpm.edpm_nftables : Generate nft ruleset in static file] ************
2025-12-03 06:17:24.197460 | controller | Wednesday 03 December 2025  06:17:23 +0000 (0:00:00.358)       0:00:11.699 ****
2025-12-03 06:17:24.197469 | controller | changed: [compute-1]
2025-12-03 06:17:24.404794 | controller |
2025-12-03 06:17:24.404823 | controller | TASK [osp.edpm.edpm_nftables : Create a sentinel file when nft rules are changed] ***
2025-12-03 06:17:24.404831 | controller | Wednesday 03 December 2025  06:17:24 +0000 (0:00:00.396)       0:00:12.095 ****
2025-12-03 06:17:24.404841 | controller | changed: [compute-1]
2025-12-03 06:17:24.656568 | controller |
2025-12-03 06:17:24.656597 | controller | TASK [osp.edpm.edpm_nftables : Validate all of the generated content before loading] ***
2025-12-03 06:17:24.656605 | controller | Wednesday 03 December 2025  06:17:24 +0000 (0:00:00.207)       0:00:12.303 ****
2025-12-03 06:17:24.656616 | controller | changed: [compute-1]
2025-12-03 06:17:24.945661 | controller |
2025-12-03 06:17:24.945691 | controller | TASK [osp.edpm.edpm_nftables : Ensure we load our different nft rulesets on boot] ***
2025-12-03 06:17:24.945701 | controller | Wednesday 03 December 2025  06:17:24 +0000 (0:00:00.251)       0:00:12.554 ****
2025-12-03 06:17:24.945714 | controller | changed: [compute-1]
2025-12-03 06:17:24.993147 | controller |
2025-12-03 06:17:24.993185 | controller | TASK [Apply firewall for frr BGP and BFD] **************************************
2025-12-03 06:17:24.993197 | controller | Wednesday 03 December 2025  06:17:24 +0000 (0:00:00.289)       0:00:12.844 ****
2025-12-03 06:17:24.993210 | controller |
2025-12-03 06:17:25.187717 | controller | TASK [osp.edpm.edpm_nftables : Inject our custom chains in nftables] ***********
2025-12-03 06:17:25.187747 | controller | Wednesday 03 December 2025  06:17:24 +0000 (0:00:00.047)       0:00:12.891 ****
2025-12-03 06:17:25.187761 | controller | changed: [compute-1]
2025-12-03 06:17:25.379979 | controller |
2025-12-03 06:17:25.380022 | controller | TASK [osp.edpm.edpm_nftables : Check if rules are changed] *********************
2025-12-03 06:17:25.380034 | controller | Wednesday 03 December 2025  06:17:25 +0000 (0:00:00.194)       0:00:13.086 ****
2025-12-03 06:17:25.380046 | controller | ok: [compute-1]
2025-12-03 06:17:25.597623 | controller |
2025-12-03 06:17:25.597657 | controller | TASK [osp.edpm.edpm_nftables : Reload ruleset] *********************************
2025-12-03 06:17:25.597666 | controller | Wednesday 03 December 2025  06:17:25 +0000 (0:00:00.192)       0:00:13.278 ****
2025-12-03 06:17:25.597676 | controller | changed: [compute-1]
2025-12-03 06:17:25.803268 | controller |
2025-12-03 06:17:25.803309 | controller | TASK [osp.edpm.edpm_nftables : Delete nft_ruleset_changed file] ****************
2025-12-03 06:17:25.803321 | controller | Wednesday 03 December 2025  06:17:25 +0000 (0:00:00.217)       0:00:13.496 ****
2025-12-03 06:17:25.803337 | controller | changed: [compute-1]
2025-12-03 06:17:26.270106 | controller |
2025-12-03 06:17:26.270146 | controller | TASK [osp.edpm.edpm_frr : Identify the real interfaces via os-net-config] ******
2025-12-03 06:17:26.270157 | controller | Wednesday 03 December 2025  06:17:25 +0000 (0:00:00.205)       0:00:13.701 ****
2025-12-03 06:17:26.270169 | controller | changed: [compute-1]
2025-12-03 06:17:26.270190 | controller |
2025-12-03 06:17:26.270200 | controller | TASK [osp.edpm.edpm_frr : Os-net-config from json] *****************************
2025-12-03 06:17:26.270284 | controller | Wednesday 03 December 2025  06:17:26 +0000 (0:00:00.466)       0:00:14.168 ****
2025-12-03 06:17:26.310153 | controller | ok: [compute-1]
2025-12-03 06:17:26.310456 | controller |
2025-12-03 06:17:26.310487 | controller | TASK [osp.edpm.edpm_frr : Assert either edpm_frr_bgp_peers or edpm_frr_bgp_uplinks configured] ***
2025-12-03 06:17:26.344043 | controller | Wednesday 03 December 2025  06:17:26 +0000 (0:00:00.040)       0:00:14.208 ****
2025-12-03 06:17:26.344074 | controller | ok: [compute-1] => changed=false
2025-12-03 06:17:26.373838 | controller |   msg: All assertions passed
2025-12-03 06:17:26.373853 | controller |
2025-12-03 06:17:26.373861 | controller | TASK [osp.edpm.edpm_frr : Construct FRR uplink interfaces from os-net-config mappings] ***
2025-12-03 06:17:26.373867 | controller | Wednesday 03 December 2025  06:17:26 +0000 (0:00:00.033)       0:00:14.242 ****
2025-12-03 06:17:26.373882 | controller | skipping: [compute-1]
2025-12-03 06:17:26.405571 | controller |
2025-12-03 06:17:26.405594 | controller | TASK [osp.edpm.edpm_frr : Failed to construct FRR uplink interfaces] ***********
2025-12-03 06:17:26.405603 | controller | Wednesday 03 December 2025  06:17:26 +0000 (0:00:00.029)       0:00:14.272 ****
2025-12-03 06:17:26.405614 | controller | skipping: [compute-1]
2025-12-03 06:17:26.695807 | controller |
2025-12-03 06:17:26.695840 | controller | TASK [osp.edpm.edpm_frr : Gather facts if they don't exist] ********************
2025-12-03 06:17:26.695858 | controller | Wednesday 03 December 2025  06:17:26 +0000 (0:00:00.031)       0:00:14.304 ****
2025-12-03 06:17:26.695869 | controller | ok: [compute-1]
2025-12-03 06:17:27.104261 | controller |
2025-12-03 06:17:27.104293 | controller | TASK [osp.edpm.edpm_frr : Configure FRR] ***************************************
2025-12-03 06:17:27.104301 | controller | Wednesday 03 December 2025  06:17:26 +0000 (0:00:00.290)       0:00:14.594 ****
2025-12-03 06:17:27.104310 | controller | changed: [compute-1]
2025-12-03 06:17:27.478752 | controller |
2025-12-03 06:17:27.478782 | controller | TASK [osp.edpm.edpm_frr : Configure FRR daemons] *******************************
2025-12-03 06:17:27.478790 | controller | Wednesday 03 December 2025  06:17:27 +0000 (0:00:00.408)       0:00:15.002 ****
2025-12-03 06:17:27.478800 | controller | changed: [compute-1]
2025-12-03 06:17:27.672843 | controller |
2025-12-03 06:17:27.672872 | controller | TASK [osp.edpm.edpm_container_manage : Create /var/local/libexec dir] **********
2025-12-03 06:17:27.672880 | controller | Wednesday 03 December 2025  06:17:27 +0000 (0:00:00.374)       0:00:15.377 ****
2025-12-03 06:17:27.672889 | controller | changed: [compute-1]
2025-12-03 06:17:28.367154 | controller |
2025-12-03 06:17:28.367184 | controller | TASK [osp.edpm.edpm_container_manage : Deploy edpm-container-shutdown and edpm-start-podman-container] ***
2025-12-03 06:17:28.367192 | controller | Wednesday 03 December 2025  06:17:27 +0000 (0:00:00.194)       0:00:15.571 ****
2025-12-03 06:17:28.367203 | controller | changed: [compute-1] => (item=edpm-container-shutdown)
2025-12-03 06:17:28.367225 | controller | changed: [compute-1] => (item=edpm-start-podman-container)
2025-12-03 06:17:28.367233 | controller |
2025-12-03 06:17:28.367420 | controller | TASK [osp.edpm.edpm_container_manage : Create systemd preset dir] **************
2025-12-03 06:17:28.576820 | controller | Wednesday 03 December 2025  06:17:28 +0000 (0:00:00.694)       0:00:16.265 ****
2025-12-03 06:17:28.576848 | controller | changed: [compute-1]
2025-12-03 06:17:28.941347 | controller |
2025-12-03 06:17:28.941376 | controller | TASK [osp.edpm.edpm_container_manage : Create /etc/systemd/system/edpm-container-shutdown.service] ***
2025-12-03 06:17:28.941384 | controller | Wednesday 03 December 2025  06:17:28 +0000 (0:00:00.209)       0:00:16.475 ****
2025-12-03 06:17:28.941394 | controller | changed: [compute-1]
2025-12-03 06:17:29.292038 | controller |
2025-12-03 06:17:29.292077 | controller | TASK [osp.edpm.edpm_container_manage : Create /etc/systemd/system-preset/91-edpm-container-shutdown.preset] ***
2025-12-03 06:17:29.292085 | controller | Wednesday 03 December 2025  06:17:28 +0000 (0:00:00.364)       0:00:16.839 ****
2025-12-03 06:17:29.292095 | controller | changed: [compute-1]
2025-12-03 06:17:30.131528 | controller |
2025-12-03 06:17:30.131557 | controller | TASK [osp.edpm.edpm_container_manage : Enable and start edpm-container-shutdown] ***
2025-12-03 06:17:30.131565 | controller | Wednesday 03 December 2025  06:17:29 +0000 (0:00:00.350)       0:00:17.190 ****
2025-12-03 06:17:30.131575 | controller | changed: [compute-1]
2025-12-03 06:17:30.498601 | controller |
2025-12-03 06:17:30.498639 | controller | TASK [osp.edpm.edpm_container_manage : Create /etc/systemd/system/netns-placeholder.service] ***
2025-12-03 06:17:30.498648 | controller | Wednesday 03 December 2025  06:17:30 +0000 (0:00:00.839)       0:00:18.029 ****
2025-12-03 06:17:30.498659 | controller | changed: [compute-1]
2025-12-03 06:17:30.846104 | controller |
2025-12-03 06:17:30.846140 | controller | TASK [osp.edpm.edpm_container_manage : Create /etc/systemd/system-preset/91-netns-placeholder.preset] ***
2025-12-03 06:17:30.846149 | controller | Wednesday 03 December 2025  06:17:30 +0000 (0:00:00.366)       0:00:18.396 ****
2025-12-03 06:17:30.846159 | controller | changed: [compute-1]
2025-12-03 06:17:30.846181 | controller |
2025-12-03 06:17:30.846190 | controller | TASK [osp.edpm.edpm_container_manage : Enable and start netns-placeholder] *****
2025-12-03 06:17:30.846271 | controller | Wednesday 03 December 2025  06:17:30 +0000 (0:00:00.347)       0:00:18.744 ****
2025-12-03 06:17:31.461192 | controller | changed: [compute-1]
2025-12-03 06:17:31.502737 | controller |
2025-12-03 06:17:31.502766 | controller | TASK [Run frr container] *******************************************************
2025-12-03 06:17:31.502777 | controller | Wednesday 03 December 2025  06:17:31 +0000 (0:00:00.614)       0:00:19.359 ****
2025-12-03 06:17:31.502789 | controller |
2025-12-03 06:17:31.554108 | controller | TASK [osp.edpm.edpm_container_standalone : Validating arguments against arg spec 'main' - The main entry point for the edpm_container_standalone role.] ***
2025-12-03 06:17:31.554139 | controller | Wednesday 03 December 2025  06:17:31 +0000 (0:00:00.042)       0:00:19.401 ****
2025-12-03 06:17:31.554151 | controller | ok: [compute-1]
2025-12-03 06:17:31.776101 | controller |
2025-12-03 06:17:31.776133 | controller | TASK [osp.edpm.edpm_container_standalone : Ensure /var/lib/edpm-config exists with correct ownership] ***
2025-12-03 06:17:31.776141 | controller | Wednesday 03 December 2025  06:17:31 +0000 (0:00:00.051)       0:00:19.452 ****
2025-12-03 06:17:31.776151 | controller | changed: [compute-1]
2025-12-03 06:17:31.971818 | controller |
2025-12-03 06:17:31.971849 | controller | TASK [osp.edpm.edpm_container_standalone : Ensure directory exists: /var/lib/kolla/config_files] ***
2025-12-03 06:17:31.971858 | controller | Wednesday 03 December 2025  06:17:31 +0000 (0:00:00.221)       0:00:19.674 ****
2025-12-03 06:17:31.971868 | controller | changed: [compute-1]
2025-12-03 06:17:32.334830 | controller |
2025-12-03 06:17:32.334863 | controller | TASK [osp.edpm.edpm_container_standalone : Create kolla config files] **********
2025-12-03 06:17:32.334872 | controller | Wednesday 03 December 2025  06:17:31 +0000 (0:00:00.195)       0:00:19.869 ****
2025-12-03 06:17:32.334888 | 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}]}})
2025-12-03 06:17:32.540404 | controller |
2025-12-03 06:17:32.540433 | controller | TASK [osp.edpm.edpm_container_standalone : Create config file /var/lib/edpm-config/container-startup-config/frr] ***
2025-12-03 06:17:32.540441 | controller | Wednesday 03 December 2025  06:17:32 +0000 (0:00:00.363)       0:00:20.233 ****
2025-12-03 06:17:32.540451 | controller | changed: [compute-1]
2025-12-03 06:17:32.922739 | controller |
2025-12-03 06:17:32.922770 | controller | TASK [osp.edpm.edpm_container_standalone : Render container definitions: [frr ]] ***
2025-12-03 06:17:32.922778 | controller | Wednesday 03 December 2025  06:17:32 +0000 (0:00:00.205)       0:00:20.438 ****
2025-12-03 06:17:32.922788 | controller | changed: [compute-1] => (item=None)
2025-12-03 06:17:32.963123 | controller | changed: [compute-1]
2025-12-03 06:17:32.963148 | controller |
2025-12-03 06:17:32.963158 | controller | TASK [Run containers: [ frr ]] *************************************************
2025-12-03 06:17:32.963164 | controller | Wednesday 03 December 2025  06:17:32 +0000 (0:00:00.382)       0:00:20.821 ****
2025-12-03 06:17:32.963175 | controller |
2025-12-03 06:17:33.014430 | controller | TASK [osp.edpm.edpm_container_manage : Validating arguments against arg spec 'main' - The main entry point for the edpm_container_manage role.] ***
2025-12-03 06:17:33.014454 | controller | Wednesday 03 December 2025  06:17:32 +0000 (0:00:00.040)       0:00:20.861 ****
2025-12-03 06:17:33.014466 | controller | ok: [compute-1]
2025-12-03 06:17:33.014505 | controller |
2025-12-03 06:17:33.014626 | controller | TASK [osp.edpm.edpm_container_manage : Find all matching configs configs for in /var/lib/edpm-config/container-startup-config/frr] ***
2025-12-03 06:17:33.014751 | controller | Wednesday 03 December 2025  06:17:33 +0000 (0:00:00.051)       0:00:20.913 ****
2025-12-03 06:17:33.308383 | controller | ok: [compute-1]
2025-12-03 06:17:33.358115 | controller |
2025-12-03 06:17:33.358146 | controller | TASK [osp.edpm.edpm_container_manage : Initialize all_containers_hash for this invocation] ***
2025-12-03 06:17:33.358155 | controller | Wednesday 03 December 2025  06:17:33 +0000 (0:00:00.293)       0:00:21.206 ****
2025-12-03 06:17:33.358167 | controller | ok: [compute-1]
2025-12-03 06:17:33.358191 | controller |
2025-12-03 06:17:33.358201 | controller | TASK [osp.edpm.edpm_container_manage : Filter configs if container list provided] ***
2025-12-03 06:17:33.358303 | controller | Wednesday 03 December 2025  06:17:33 +0000 (0:00:00.050)       0:00:21.256 ****
2025-12-03 06:17:33.425805 | controller | ok: [compute-1] => (item=frr)
2025-12-03 06:17:33.473422 | controller |
2025-12-03 06:17:33.473452 | controller | TASK [osp.edpm.edpm_container_manage : Use all configs if no filter] ***********
2025-12-03 06:17:33.473461 | controller | Wednesday 03 December 2025  06:17:33 +0000 (0:00:00.067)       0:00:21.324 ****
2025-12-03 06:17:33.473478 | controller | skipping: [compute-1]
2025-12-03 06:17:33.766617 | controller |
2025-12-03 06:17:33.766650 | controller | TASK [osp.edpm.edpm_container_manage : Update container configs with new config hashes] ***
2025-12-03 06:17:33.766658 | controller | Wednesday 03 December 2025  06:17:33 +0000 (0:00:00.047)       0:00:21.371 ****
2025-12-03 06:17:33.766668 | controller | changed: [compute-1]
2025-12-03 06:17:33.808118 | controller |
2025-12-03 06:17:33.808144 | controller | TASK [osp.edpm.edpm_container_manage : Delete orphan containers (DEPRECATED)] ***
2025-12-03 06:17:33.808153 | controller | Wednesday 03 December 2025  06:17:33 +0000 (0:00:00.293)       0:00:21.664 ****
2025-12-03 06:17:33.808164 | controller | included: /home/zuul/.cache/ansible-compat/8075a4/collections/ansible_collections/osp/edpm/roles/edpm_container_manage/tasks/delete_orphan.yml for compute-1
2025-12-03 06:17:33.808184 | controller |
2025-12-03 06:17:33.808193 | controller | TASK [osp.edpm.edpm_container_manage : Gather podman infos] ********************
2025-12-03 06:17:33.808222 | controller | Wednesday 03 December 2025  06:17:33 +0000 (0:00:00.041)       0:00:21.706 ****
2025-12-03 06:17:34.127232 | controller | ok: [compute-1]
2025-12-03 06:17:34.127539 | controller |
2025-12-03 06:17:34.167102 | controller | TASK [osp.edpm.edpm_container_manage : Exclude Ceph containers from podman container list] ***
2025-12-03 06:17:34.167125 | controller | Wednesday 03 December 2025  06:17:34 +0000 (0:00:00.319)       0:00:22.025 ****
2025-12-03 06:17:34.167139 | controller | ok: [compute-1]
2025-12-03 06:17:34.217000 | controller |
2025-12-03 06:17:34.217880 | controller | TASK [Delete orphan containers managed by Podman for /var/lib/edpm-config/container-startup-config/frr] ***
2025-12-03 06:17:34.217890 | controller | Wednesday 03 December 2025  06:17:34 +0000 (0:00:00.039)       0:00:22.065 ****
2025-12-03 06:17:34.217901 | controller |
2025-12-03 06:17:34.257380 | controller | TASK [osp.edpm.edpm_container_rm : Validating arguments against arg spec 'main' - The main entry point for the edpm_container_rm role.] ***
2025-12-03 06:17:34.257402 | controller | Wednesday 03 December 2025  06:17:34 +0000 (0:00:00.049)       0:00:22.115 ****
2025-12-03 06:17:34.257413 | controller | ok: [compute-1]
2025-12-03 06:17:34.295642 | controller |
2025-12-03 06:17:34.295664 | controller | TASK [osp.edpm.edpm_container_rm : Include edpm container rm task file] ********
2025-12-03 06:17:34.295672 | controller | Wednesday 03 December 2025  06:17:34 +0000 (0:00:00.040)       0:00:22.155 ****
2025-12-03 06:17:34.295681 | controller | skipping: [compute-1]
2025-12-03 06:17:34.341124 | controller |
2025-12-03 06:17:34.341150 | controller | TASK [osp.edpm.edpm_container_manage : Create containers from /var/lib/edpm-config/container-startup-config/frr] ***
2025-12-03 06:17:34.341160 | controller | Wednesday 03 December 2025  06:17:34 +0000 (0:00:00.038)       0:00:22.193 ****
2025-12-03 06:17:34.341173 | controller | included: /home/zuul/.cache/ansible-compat/8075a4/collections/ansible_collections/osp/edpm/roles/edpm_container_manage/tasks/create.yml for compute-1
2025-12-03 06:17:34.341202 | controller |
2025-12-03 06:17:34.341440 | controller | TASK [osp.edpm.edpm_container_manage : Create containers managed by Podman for /var/lib/edpm-config/container-startup-config/frr] ***
2025-12-03 06:17:34.908089 | controller | Wednesday 03 December 2025  06:17:34 +0000 (0:00:00.045)       0:00:22.239 ****
2025-12-03 06:17:34.908126 | controller | changed: [compute-1]
2025-12-03 06:17:34.908464 | controller |
2025-12-03 06:17:35.104682 | controller | TASK [osp.edpm.edpm_container_manage : Check if /etc/sysconfig/podman_drop_in exists] ***
2025-12-03 06:17:35.104713 | controller | Wednesday 03 December 2025  06:17:34 +0000 (0:00:00.566)       0:00:22.806 ****
2025-12-03 06:17:35.104725 | controller | ok: [compute-1]
2025-12-03 06:17:35.141164 | controller |
2025-12-03 06:17:35.141191 | controller | TASK [osp.edpm.edpm_container_manage : Set podman_drop_in fact] ****************
2025-12-03 06:17:35.141200 | controller | Wednesday 03 December 2025  06:17:35 +0000 (0:00:00.196)       0:00:23.003 ****
2025-12-03 06:17:35.141212 | controller | skipping: [compute-1]
2025-12-03 06:17:35.141231 | controller |
2025-12-03 06:17:35.141240 | 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] ***
2025-12-03 06:17:35.141249 | controller | Wednesday 03 December 2025  06:17:35 +0000 (0:00:00.036)       0:00:23.039 ****
2025-12-03 06:17:36.616604 | controller | changed: [compute-1]
2025-12-03 06:17:36.616668 | controller |
2025-12-03 06:17:36.616773 | controller | TASK [osp.edpm.edpm_container_standalone : Update service state file] **********
2025-12-03 06:17:36.616870 | controller | Wednesday 03 December 2025  06:17:36 +0000 (0:00:01.475)       0:00:24.515 ****
2025-12-03 06:17:36.673640 | 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
2025-12-03 06:17:36.673704 | controller |
2025-12-03 06:17:36.673913 | controller | TASK [osp.edpm.edpm_container_standalone : Read current state file] ************
2025-12-03 06:17:36.938131 | controller | Wednesday 03 December 2025  06:17:36 +0000 (0:00:00.056)       0:00:24.572 ****
2025-12-03 06:17:36.938182 | controller | ok: [compute-1]
2025-12-03 06:17:36.960959 | controller |
2025-12-03 06:17:36.960995 | controller | TASK [osp.edpm.edpm_container_standalone : Parse state file data] **************
2025-12-03 06:17:36.961024 | controller | Wednesday 03 December 2025  06:17:36 +0000 (0:00:00.264)       0:00:24.836 ****
2025-12-03 06:17:36.961048 | controller | skipping: [compute-1]
2025-12-03 06:17:36.986202 | controller |
2025-12-03 06:17:36.986226 | controller | TASK [osp.edpm.edpm_container_standalone : Initialize empty state data if file doesn't exist] ***
2025-12-03 06:17:36.986235 | controller | Wednesday 03 December 2025  06:17:36 +0000 (0:00:00.023)       0:00:24.859 ****
2025-12-03 06:17:36.986244 | controller | ok: [compute-1]
2025-12-03 06:17:37.047697 | controller |
2025-12-03 06:17:37.047720 | controller | TASK [osp.edpm.edpm_container_standalone : Update service in state data] *******
2025-12-03 06:17:37.047728 | controller | Wednesday 03 December 2025  06:17:36 +0000 (0:00:00.025)       0:00:24.884 ****
2025-12-03 06:17:37.047738 | controller | ok: [compute-1]
2025-12-03 06:17:37.435687 | controller |
2025-12-03 06:17:37.435718 | controller | TASK [osp.edpm.edpm_container_standalone : Write updated state file] ***********
2025-12-03 06:17:37.435727 | controller | Wednesday 03 December 2025  06:17:37 +0000 (0:00:00.061)       0:00:24.946 ****
2025-12-03 06:17:37.435737 | controller | changed: [compute-1]
2025-12-03 06:17:37.497687 | controller |
2025-12-03 06:17:37.497729 | controller | PLAY RECAP *********************************************************************
2025-12-03 06:17:37.497738 | controller | compute-1                  : ok=66   changed=43   unreachable=0    failed=0    skipped=7    rescued=0    ignored=0
2025-12-03 06:17:37.497744 | controller |
2025-12-03 06:17:37.497749 | controller | Wednesday 03 December 2025  06:17:37 +0000 (0:00:00.385)       0:00:25.331 ****
2025-12-03 06:17:37.497755 | controller | ===============================================================================
2025-12-03 06:17:37.497760 | controller | osp.edpm.edpm_frr : Download needed container images -------------------- 5.72s
2025-12-03 06:17:37.497766 | controller | osp.edpm.edpm_container_manage : Manage container systemd services and cleanup old systemd healthchecks for /var/lib/edpm-config/container-startup-config/frr --- 1.48s
2025-12-03 06:17:37.497772 | controller | osp.edpm.edpm_container_manage : Enable and start edpm-container-shutdown --- 0.84s
2025-12-03 06:17:37.497777 | controller | osp.edpm.edpm_container_manage : Deploy edpm-container-shutdown and edpm-start-podman-container --- 0.69s
2025-12-03 06:17:37.497783 | controller | osp.edpm.edpm_frr : Create persistent directories ----------------------- 0.69s
2025-12-03 06:17:37.497789 | controller | osp.edpm.edpm_container_manage : Enable and start netns-placeholder ----- 0.61s
2025-12-03 06:17:37.497794 | controller | osp.edpm.edpm_container_manage : Create containers managed by Podman for /var/lib/edpm-config/container-startup-config/frr --- 0.57s
2025-12-03 06:17:37.497799 | controller | osp.edpm.edpm_frr : Ensure /run/frr is present upon reboot -------------- 0.53s
2025-12-03 06:17:37.497805 | controller | osp.edpm.edpm_frr : Identify the real interfaces via os-net-config ------ 0.47s
2025-12-03 06:17:37.497810 | controller | osp.edpm.edpm_frr : Gather SELinux fact if needed ----------------------- 0.45s
2025-12-03 06:17:37.497815 | controller | osp.edpm.edpm_frr : Configure FRR --------------------------------------- 0.41s
2025-12-03 06:17:37.497821 | controller | osp.edpm.edpm_nftables : Generate chain jumps --------------------------- 0.40s
2025-12-03 06:17:37.497826 | controller | osp.edpm.edpm_nftables : Generate nft ruleset in static file ------------ 0.40s
2025-12-03 06:17:37.497836 | controller | osp.edpm.edpm_container_standalone : Write updated state file ----------- 0.39s
2025-12-03 06:17:37.497842 | controller | osp.edpm.edpm_container_standalone : Render container definitions: [frr ] --- 0.38s
2025-12-03 06:17:37.497847 | controller | osp.edpm.edpm_nftables : Generate chain jumps --------------------------- 0.37s
2025-12-03 06:17:37.497853 | controller | osp.edpm.edpm_frr : Configure FRR daemons ------------------------------- 0.37s
2025-12-03 06:17:37.497858 | controller | osp.edpm.edpm_container_manage : Create /etc/systemd/system/netns-placeholder.service --- 0.37s
2025-12-03 06:17:37.497863 | controller | osp.edpm.edpm_container_manage : Create /etc/systemd/system/edpm-container-shutdown.service --- 0.36s
2025-12-03 06:17:37.497869 | controller | osp.edpm.edpm_nftables : Write user rules snippet ----------------------- 0.36s
2025-12-03 06:17:37.497879 | controller | INFO     Running default > verify
2025-12-03 06:17:37.498249 | controller | INFO     Running Ansible Verifier
2025-12-03 06:17:37.906032 | controller |
2025-12-03 06:17:37.940933 | controller | PLAY [Verify frr] **************************************************************
2025-12-03 06:17:37.940951 | controller |
2025-12-03 06:17:37.940959 | controller | TASK [ensure expected directories exist] ***************************************
2025-12-03 06:17:37.940965 | controller | Wednesday 03 December 2025  06:17:37 +0000 (0:00:00.025)       0:00:00.025 ****
2025-12-03 06:17:37.940974 | 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)
2025-12-03 06:17:38.214117 | 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)
2025-12-03 06:17:38.214150 | 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/)
2025-12-03 06:17:38.214159 | 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)
2025-12-03 06:17:38.214167 | controller |
2025-12-03 06:17:38.214174 | controller | TASK [Check if directory exists /var/lib/edpm-config/container-startup-config/frr] ***
2025-12-03 06:17:38.214180 | controller | Wednesday 03 December 2025  06:17:37 +0000 (0:00:00.034)       0:00:00.060 ****
2025-12-03 06:17:38.214192 | controller | ok: [compute-1]
2025-12-03 06:17:38.214217 | controller |
2025-12-03 06:17:38.214227 | controller | TASK [Assert directory exists /var/lib/edpm-config/container-startup-config/frr] ***
2025-12-03 06:17:38.214249 | controller | Wednesday 03 December 2025  06:17:38 +0000 (0:00:00.273)       0:00:00.334 ****
2025-12-03 06:17:38.236106 | controller | ok: [compute-1] => changed=false
2025-12-03 06:17:38.236311 | controller |   msg: All assertions passed
2025-12-03 06:17:38.236327 | controller |
2025-12-03 06:17:38.236338 | controller | TASK [Check if directory exists /var/lib/openstack/frr] ************************
2025-12-03 06:17:38.425630 | controller | Wednesday 03 December 2025  06:17:38 +0000 (0:00:00.022)       0:00:00.356 ****
2025-12-03 06:17:38.425663 | controller | ok: [compute-1]
2025-12-03 06:17:38.447087 | controller |
2025-12-03 06:17:38.447108 | controller | TASK [Assert directory exists /var/lib/openstack/frr] **************************
2025-12-03 06:17:38.447116 | controller | Wednesday 03 December 2025  06:17:38 +0000 (0:00:00.189)       0:00:00.545 ****
2025-12-03 06:17:38.447125 | controller | ok: [compute-1] => changed=false
2025-12-03 06:17:38.447457 | controller |   msg: All assertions passed
2025-12-03 06:17:38.447475 | controller |
2025-12-03 06:17:38.635123 | controller | TASK [Check if directory exists /etc/tmpfiles.d/] ******************************
2025-12-03 06:17:38.635153 | controller | Wednesday 03 December 2025  06:17:38 +0000 (0:00:00.021)       0:00:00.567 ****
2025-12-03 06:17:38.635166 | controller | ok: [compute-1]
2025-12-03 06:17:38.635237 | controller |
2025-12-03 06:17:38.635254 | controller | TASK [Assert directory exists /etc/tmpfiles.d/] ********************************
2025-12-03 06:17:38.635264 | controller | Wednesday 03 December 2025  06:17:38 +0000 (0:00:00.187)       0:00:00.755 ****
2025-12-03 06:17:38.671089 | controller | ok: [compute-1] => changed=false
2025-12-03 06:17:38.671166 | controller |   msg: All assertions passed
2025-12-03 06:17:38.671188 | controller |
2025-12-03 06:17:38.671196 | controller | TASK [Check if directory exists /var/lib/openstack/frr/etc/frr] ****************
2025-12-03 06:17:38.671205 | controller | Wednesday 03 December 2025  06:17:38 +0000 (0:00:00.035)       0:00:00.790 ****
2025-12-03 06:17:38.850548 | controller | ok: [compute-1]
2025-12-03 06:17:38.871830 | controller |
2025-12-03 06:17:38.871856 | controller | TASK [Assert directory exists /var/lib/openstack/frr/etc/frr] ******************
2025-12-03 06:17:38.871866 | controller | Wednesday 03 December 2025  06:17:38 +0000 (0:00:00.179)       0:00:00.970 ****
2025-12-03 06:17:38.871877 | controller | ok: [compute-1] => changed=false
2025-12-03 06:17:39.058984 | controller |   msg: All assertions passed
2025-12-03 06:17:39.059030 | controller |
2025-12-03 06:17:39.059041 | controller | TASK [Check if file /etc/tmpfiles.d/run-frr.conf exist] ************************
2025-12-03 06:17:39.059048 | controller | Wednesday 03 December 2025  06:17:38 +0000 (0:00:00.021)       0:00:00.991 ****
2025-12-03 06:17:39.059057 | controller | ok: [compute-1]
2025-12-03 06:17:39.078711 | controller |
2025-12-03 06:17:39.078727 | controller | TASK [Assert file /etc/tmpfiles.d/run-frr.conf exist] **************************
2025-12-03 06:17:39.078734 | controller | Wednesday 03 December 2025  06:17:39 +0000 (0:00:00.187)       0:00:01.178 ****
2025-12-03 06:17:39.078742 | controller | ok: [compute-1] => changed=false
2025-12-03 06:17:39.271629 | controller |   msg: All assertions passed
2025-12-03 06:17:39.271658 | controller |
2025-12-03 06:17:39.271666 | controller | TASK [Check if file /var/lib/openstack/frr/etc/frr/frr.conf exist] *************
2025-12-03 06:17:39.271672 | controller | Wednesday 03 December 2025  06:17:39 +0000 (0:00:00.019)       0:00:01.198 ****
2025-12-03 06:17:39.271682 | controller | ok: [compute-1]
2025-12-03 06:17:39.290482 | controller |
2025-12-03 06:17:39.290506 | controller | TASK [Assert file /var/lib/openstack/frr/etc/frr/frr.conf exist] ***************
2025-12-03 06:17:39.290514 | controller | Wednesday 03 December 2025  06:17:39 +0000 (0:00:00.192)       0:00:01.391 ****
2025-12-03 06:17:39.290524 | controller | ok: [compute-1] => changed=false
2025-12-03 06:17:39.479725 | controller |   msg: All assertions passed
2025-12-03 06:17:39.479756 | controller |
2025-12-03 06:17:39.479765 | controller | TASK [Check if file /var/lib/openstack/frr/etc/frr/daemons exist] **************
2025-12-03 06:17:39.479772 | controller | Wednesday 03 December 2025  06:17:39 +0000 (0:00:00.018)       0:00:01.410 ****
2025-12-03 06:17:39.479781 | controller | ok: [compute-1]
2025-12-03 06:17:39.498097 | controller |
2025-12-03 06:17:39.498130 | controller | TASK [Assert file /var/lib/openstack/frr/etc/frr/daemons exist] ****************
2025-12-03 06:17:39.498139 | controller | Wednesday 03 December 2025  06:17:39 +0000 (0:00:00.189)       0:00:01.599 ****
2025-12-03 06:17:39.498149 | controller | ok: [compute-1] => changed=false
2025-12-03 06:17:39.498565 | controller |   msg: All assertions passed
2025-12-03 06:17:39.687919 | controller |
2025-12-03 06:17:39.687952 | controller | TASK [rule source file exists] *************************************************
2025-12-03 06:17:39.687961 | controller | Wednesday 03 December 2025  06:17:39 +0000 (0:00:00.018)       0:00:01.618 ****
2025-12-03 06:17:39.687970 | controller | ok: [compute-1]
2025-12-03 06:17:39.707593 | controller |
2025-12-03 06:17:39.707617 | controller | TASK [Assert frr BGP and BFD rule source file exists] **************************
2025-12-03 06:17:39.707627 | controller | Wednesday 03 December 2025  06:17:39 +0000 (0:00:00.189)       0:00:01.807 ****
2025-12-03 06:17:39.707639 | controller | ok: [compute-1] => changed=false
2025-12-03 06:17:40.164671 | controller |   msg: All assertions passed
2025-12-03 06:17:40.164697 | controller |
2025-12-03 06:17:40.164705 | controller | TASK [Check if nftables rule exists in /etc/nftables/edpm-rules.nft] ***********
2025-12-03 06:17:40.164711 | controller | Wednesday 03 December 2025  06:17:39 +0000 (0:00:00.019)       0:00:01.827 ****
2025-12-03 06:17:40.164720 | controller | changed: [compute-1] => (item=121 frr bgp port)
2025-12-03 06:17:40.198155 | controller | changed: [compute-1] => (item=122 frr bfd ports)
2025-12-03 06:17:40.198198 | controller |
2025-12-03 06:17:40.198212 | controller | TASK [Assert nftables rule exists in /etc/nftables/edpm-rules.nft] *************
2025-12-03 06:17:40.198230 | controller | Wednesday 03 December 2025  06:17:40 +0000 (0:00:00.457)       0:00:02.284 ****
2025-12-03 06:17:40.198249 | controller | ok: [compute-1] => (item={'changed': True, 'stdout': '', 'stderr': '', 'rc': 0, 'cmd': 'grep -q "121 frr bgp port" /etc/nftables/edpm-rules.nft', 'start': '2025-12-03 06:17:39.952050', 'end': '2025-12-03 06:17:39.956634', 'delta': '0:00:00.004584', '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
2025-12-03 06:17:40.198312 | controller |   ansible_loop_var: item
2025-12-03 06:17:40.198325 | controller |   item:
2025-12-03 06:17:40.198335 | controller |     ansible_loop_var: item
2025-12-03 06:17:40.198344 | controller |     changed: true
2025-12-03 06:17:40.198353 | controller |     cmd: grep -q "121 frr bgp port" /etc/nftables/edpm-rules.nft
2025-12-03 06:17:40.198363 | controller |     delta: '0:00:00.004584'
2025-12-03 06:17:40.198372 | controller |     end: '2025-12-03 06:17:39.956634'
2025-12-03 06:17:40.198381 | controller |     failed: false
2025-12-03 06:17:40.198390 | controller |     invocation:
2025-12-03 06:17:40.198399 | controller |       module_args:
2025-12-03 06:17:40.198412 | controller |         _raw_params: grep -q "121 frr bgp port" /etc/nftables/edpm-rules.nft
2025-12-03 06:17:40.198439 | controller |         _uses_shell: true
2025-12-03 06:17:40.199209 | controller |         argv: null
2025-12-03 06:17:40.199243 | controller |         chdir: null
2025-12-03 06:17:40.199269 | controller |         creates: null
2025-12-03 06:17:40.199276 | controller |         executable: null
2025-12-03 06:17:40.199282 | controller |         removes: null
2025-12-03 06:17:40.199287 | controller |         stdin: null
2025-12-03 06:17:40.199292 | controller |         stdin_add_newline: true
2025-12-03 06:17:40.199298 | controller |         strip_empty_ends: true
2025-12-03 06:17:40.199304 | controller |     item: 121 frr bgp port
2025-12-03 06:17:40.199311 | controller |     msg: ''
2025-12-03 06:17:40.199332 | controller |     rc: 0
2025-12-03 06:17:40.200058 | controller |     start: '2025-12-03 06:17:39.952050'
2025-12-03 06:17:40.200072 | controller |     stderr: ''
2025-12-03 06:17:40.200078 | controller |     stderr_lines: []
2025-12-03 06:17:40.200084 | controller |     stdout: ''
2025-12-03 06:17:40.200089 | controller |     stdout_lines: []
2025-12-03 06:17:40.200095 | controller |   msg: All assertions passed
2025-12-03 06:17:40.200106 | controller | ok: [compute-1] => (item={'changed': True, 'stdout': '', 'stderr': '', 'rc': 0, 'cmd': 'grep -q "122 frr bfd ports" /etc/nftables/edpm-rules.nft', 'start': '2025-12-03 06:17:40.126041', 'end': '2025-12-03 06:17:40.130040', 'delta': '0:00:00.003999', '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
2025-12-03 06:17:40.200115 | controller |   ansible_loop_var: item
2025-12-03 06:17:40.200221 | controller |   item:
2025-12-03 06:17:40.200327 | controller |     ansible_loop_var: item
2025-12-03 06:17:40.200435 | controller |     changed: true
2025-12-03 06:17:40.200545 | controller |     cmd: grep -q "122 frr bfd ports" /etc/nftables/edpm-rules.nft
2025-12-03 06:17:40.200655 | controller |     delta: '0:00:00.003999'
2025-12-03 06:17:40.200759 | controller |     end: '2025-12-03 06:17:40.130040'
2025-12-03 06:17:40.200875 | controller |     failed: false
2025-12-03 06:17:40.200978 | controller |     invocation:
2025-12-03 06:17:40.201131 | controller |       module_args:
2025-12-03 06:17:40.201256 | controller |         _raw_params: grep -q "122 frr bfd ports" /etc/nftables/edpm-rules.nft
2025-12-03 06:17:40.201380 | controller |         _uses_shell: true
2025-12-03 06:17:40.201520 | controller |         argv: null
2025-12-03 06:17:40.201658 | controller |         chdir: null
2025-12-03 06:17:40.201791 | controller |         creates: null
2025-12-03 06:17:40.201921 | controller |         executable: null
2025-12-03 06:17:40.202097 | controller |         removes: null
2025-12-03 06:17:40.202240 | controller |         stdin: null
2025-12-03 06:17:40.202376 | controller |         stdin_add_newline: true
2025-12-03 06:17:40.202505 | controller |         strip_empty_ends: true
2025-12-03 06:17:40.202633 | controller |     item: 122 frr bfd ports
2025-12-03 06:17:40.202760 | controller |     msg: ''
2025-12-03 06:17:40.202895 | controller |     rc: 0
2025-12-03 06:17:40.203052 | controller |     start: '2025-12-03 06:17:40.126041'
2025-12-03 06:17:40.203191 | controller |     stderr: ''
2025-12-03 06:17:40.203319 | controller |     stderr_lines: []
2025-12-03 06:17:40.203444 | controller |     stdout: ''
2025-12-03 06:17:40.203570 | controller |     stdout_lines: []
2025-12-03 06:17:40.203704 | controller |   msg: All assertions passed
2025-12-03 06:17:40.203829 | controller |
2025-12-03 06:17:40.203959 | controller | TASK [Check rule content] ******************************************************
2025-12-03 06:17:40.204119 | controller | Wednesday 03 December 2025  06:17:40 +0000 (0:00:00.032)       0:00:02.317 ****
2025-12-03 06:17:40.555202 | controller | changed: [compute-1] => (item=tcp dport { 179 } ct state new counter accept)
2025-12-03 06:17:40.588138 | controller | changed: [compute-1] => (item=udp dport { 3784, 3785, 4784, 49152, 49153 } ct state new counter accept)
2025-12-03 06:17:40.588180 | controller |
2025-12-03 06:17:40.588191 | controller | TASK [Assert rule content exists] **********************************************
2025-12-03 06:17:40.588199 | controller | Wednesday 03 December 2025  06:17:40 +0000 (0:00:00.357)       0:00:02.674 ****
2025-12-03 06:17:40.588213 | 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': '2025-12-03 06:17:40.346619', 'end': '2025-12-03 06:17:40.350643', 'delta': '0:00:00.004024', '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
2025-12-03 06:17:40.588244 | controller |   ansible_loop_var: item
2025-12-03 06:17:40.588253 | controller |   item:
2025-12-03 06:17:40.588260 | controller |     ansible_loop_var: item
2025-12-03 06:17:40.588267 | controller |     changed: true
2025-12-03 06:17:40.588277 | controller |     cmd: grep -q "tcp dport { 179 } ct state new counter accept" /etc/nftables/edpm-rules.nft
2025-12-03 06:17:40.590105 | controller |     delta: '0:00:00.004024'
2025-12-03 06:17:40.591256 | controller |     end: '2025-12-03 06:17:40.350643'
2025-12-03 06:17:40.591275 | controller |     failed: false
2025-12-03 06:17:40.591283 | controller |     invocation:
2025-12-03 06:17:40.591289 | controller |       module_args:
2025-12-03 06:17:40.591295 | controller |         _raw_params: grep -q "tcp dport { 179 } ct state new counter accept" /etc/nftables/edpm-rules.nft
2025-12-03 06:17:40.591301 | controller |         _uses_shell: true
2025-12-03 06:17:40.591306 | controller |         argv: null
2025-12-03 06:17:40.591311 | controller |         chdir: null
2025-12-03 06:17:40.591317 | controller |         creates: null
2025-12-03 06:17:40.591322 | controller |         executable: null
2025-12-03 06:17:40.591331 | controller |         removes: null
2025-12-03 06:17:40.591348 | controller |         stdin: null
2025-12-03 06:17:40.591355 | controller |         stdin_add_newline: true
2025-12-03 06:17:40.591360 | controller |         strip_empty_ends: true
2025-12-03 06:17:40.591366 | controller |     item: tcp dport { 179 } ct state new counter accept
2025-12-03 06:17:40.591372 | controller |     msg: ''
2025-12-03 06:17:40.591377 | controller |     rc: 0
2025-12-03 06:17:40.591383 | controller |     start: '2025-12-03 06:17:40.346619'
2025-12-03 06:17:40.591388 | controller |     stderr: ''
2025-12-03 06:17:40.591394 | controller |     stderr_lines: []
2025-12-03 06:17:40.591399 | controller |     stdout: ''
2025-12-03 06:17:40.591407 | controller |     stdout_lines: []
2025-12-03 06:17:40.591479 | controller |   msg: All assertions passed
2025-12-03 06:17:40.591654 | 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': '2025-12-03 06:17:40.518641', 'end': '2025-12-03 06:17:40.523135', 'delta': '0:00:00.004494', '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
2025-12-03 06:17:40.591813 | controller |   ansible_loop_var: item
2025-12-03 06:17:40.591950 | controller |   item:
2025-12-03 06:17:40.592120 | controller |     ansible_loop_var: item
2025-12-03 06:17:40.592262 | controller |     changed: true
2025-12-03 06:17:40.592408 | controller |     cmd: grep -q "udp dport { 3784, 3785, 4784, 49152, 49153 } ct state new counter accept" /etc/nftables/edpm-rules.nft
2025-12-03 06:17:40.592540 | controller |     delta: '0:00:00.004494'
2025-12-03 06:17:40.592673 | controller |     end: '2025-12-03 06:17:40.523135'
2025-12-03 06:17:40.592803 | controller |     failed: false
2025-12-03 06:17:40.592932 | controller |     invocation:
2025-12-03 06:17:40.593090 | controller |       module_args:
2025-12-03 06:17:40.593237 | controller |         _raw_params: grep -q "udp dport { 3784, 3785, 4784, 49152, 49153 } ct state new counter accept" /etc/nftables/edpm-rules.nft
2025-12-03 06:17:40.593369 | controller |         _uses_shell: true
2025-12-03 06:17:40.593499 | controller |         argv: null
2025-12-03 06:17:40.593625 | controller |         chdir: null
2025-12-03 06:17:40.593753 | controller |         creates: null
2025-12-03 06:17:40.593882 | controller |         executable: null
2025-12-03 06:17:40.594037 | controller |         removes: null
2025-12-03 06:17:40.594188 | controller |         stdin: null
2025-12-03 06:17:40.594320 | controller |         stdin_add_newline: true
2025-12-03 06:17:40.594450 | controller |         strip_empty_ends: true
2025-12-03 06:17:40.594583 | controller |     item: udp dport { 3784, 3785, 4784, 49152, 49153 } ct state new counter accept
2025-12-03 06:17:40.594710 | controller |     msg: ''
2025-12-03 06:17:40.594837 | controller |     rc: 0
2025-12-03 06:17:40.594967 | controller |     start: '2025-12-03 06:17:40.518641'
2025-12-03 06:17:40.595128 | controller |     stderr: ''
2025-12-03 06:17:40.595264 | controller |     stderr_lines: []
2025-12-03 06:17:40.595392 | controller |     stdout: ''
2025-12-03 06:17:40.595520 | controller |     stdout_lines: []
2025-12-03 06:17:40.595647 | controller |   msg: All assertions passed
2025-12-03 06:17:40.595769 | controller |
2025-12-03 06:17:40.595900 | controller | TASK [Check input chain rules are loaded] **************************************
2025-12-03 06:17:40.596067 | controller | Wednesday 03 December 2025  06:17:40 +0000 (0:00:00.032)       0:00:02.707 ****
2025-12-03 06:17:40.976062 | controller | changed: [compute-1] => (item=tcp dport 179 ct state new counter)
2025-12-03 06:17:41.005096 | controller | changed: [compute-1] => (item=udp dport { 3784, 3785, 4784, 49152, 49153 } ct state new counter)
2025-12-03 06:17:41.005128 | controller |
2025-12-03 06:17:41.005136 | controller | TASK [Assert rule content exists] **********************************************
2025-12-03 06:17:41.005142 | controller | Wednesday 03 December 2025  06:17:40 +0000 (0:00:00.388)       0:00:03.095 ****
2025-12-03 06:17:41.005154 | 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': '2025-12-03 06:17:40.748304', 'end': '2025-12-03 06:17:40.756237', 'delta': '0:00:00.007933', '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
2025-12-03 06:17:41.005178 | controller |   ansible_loop_var: item
2025-12-03 06:17:41.005187 | controller |   item:
2025-12-03 06:17:41.005209 | controller |     ansible_loop_var: item
2025-12-03 06:17:41.006096 | controller |     changed: true
2025-12-03 06:17:41.006877 | controller |     cmd: nft list table inet filter | awk '/chain EDPM_INPUT {/,/}/' | grep -q "tcp dport 179 ct state new counter.*accept"
2025-12-03 06:17:41.006905 | controller |     delta: '0:00:00.007933'
2025-12-03 06:17:41.006913 | controller |     end: '2025-12-03 06:17:40.756237'
2025-12-03 06:17:41.006923 | controller |     failed: false
2025-12-03 06:17:41.006930 | controller |     invocation:
2025-12-03 06:17:41.006935 | controller |       module_args:
2025-12-03 06:17:41.006941 | controller |         _raw_params: nft list table inet filter | awk '/chain EDPM_INPUT {/,/}/' | grep -q "tcp dport 179 ct state new counter.*accept"
2025-12-03 06:17:41.006947 | controller |         _uses_shell: true
2025-12-03 06:17:41.006957 | controller |         argv: null
2025-12-03 06:17:41.007102 | controller |         chdir: null
2025-12-03 06:17:41.007118 | controller |         creates: null
2025-12-03 06:17:41.007124 | controller |         executable: null
2025-12-03 06:17:41.007130 | controller |         removes: null
2025-12-03 06:17:41.007135 | controller |         stdin: null
2025-12-03 06:17:41.007141 | controller |         stdin_add_newline: true
2025-12-03 06:17:41.007146 | controller |         strip_empty_ends: true
2025-12-03 06:17:41.007152 | controller |     item: tcp dport 179 ct state new counter
2025-12-03 06:17:41.007158 | controller |     msg: ''
2025-12-03 06:17:41.007172 | controller |     rc: 0
2025-12-03 06:17:41.007231 | controller |     start: '2025-12-03 06:17:40.748304'
2025-12-03 06:17:41.007348 | controller |     stderr: ''
2025-12-03 06:17:41.007461 | controller |     stderr_lines: []
2025-12-03 06:17:41.007563 | controller |     stdout: ''
2025-12-03 06:17:41.007672 | controller |     stdout_lines: []
2025-12-03 06:17:41.007782 | controller |   msg: All assertions passed
2025-12-03 06:17:41.007938 | 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': '2025-12-03 06:17:40.919270', 'end': '2025-12-03 06:17:40.926731', 'delta': '0:00:00.007461', '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
2025-12-03 06:17:41.008074 | controller |   ansible_loop_var: item
2025-12-03 06:17:41.008193 | controller |   item:
2025-12-03 06:17:41.008304 | controller |     ansible_loop_var: item
2025-12-03 06:17:41.008415 | controller |     changed: true
2025-12-03 06:17:41.008535 | 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"
2025-12-03 06:17:41.008656 | controller |     delta: '0:00:00.007461'
2025-12-03 06:17:41.008766 | controller |     end: '2025-12-03 06:17:40.926731'
2025-12-03 06:17:41.008882 | controller |     failed: false
2025-12-03 06:17:41.009033 | controller |     invocation:
2025-12-03 06:17:41.009198 | controller |       module_args:
2025-12-03 06:17:41.009348 | 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"
2025-12-03 06:17:41.009482 | controller |         _uses_shell: true
2025-12-03 06:17:41.009611 | controller |         argv: null
2025-12-03 06:17:41.009740 | controller |         chdir: null
2025-12-03 06:17:41.009866 | controller |         creates: null
2025-12-03 06:17:41.009993 | controller |         executable: null
2025-12-03 06:17:41.010160 | controller |         removes: null
2025-12-03 06:17:41.010297 | controller |         stdin: null
2025-12-03 06:17:41.010428 | controller |         stdin_add_newline: true
2025-12-03 06:17:41.010555 | controller |         strip_empty_ends: true
2025-12-03 06:17:41.010687 | controller |     item: udp dport { 3784, 3785, 4784, 49152, 49153 } ct state new counter
2025-12-03 06:17:41.010816 | controller |     msg: ''
2025-12-03 06:17:41.010951 | controller |     rc: 0
2025-12-03 06:17:41.011117 | controller |     start: '2025-12-03 06:17:40.919270'
2025-12-03 06:17:41.011254 | controller |     stderr: ''
2025-12-03 06:17:41.011384 | controller |     stderr_lines: []
2025-12-03 06:17:41.011513 | controller |     stdout: ''
2025-12-03 06:17:41.011641 | controller |     stdout_lines: []
2025-12-03 06:17:41.011770 | controller |   msg: All assertions passed
2025-12-03 06:17:41.011901 | controller |
2025-12-03 06:17:41.012073 | controller | TASK [ensure podman container exists and are running] **************************
2025-12-03 06:17:41.012234 | controller | Wednesday 03 December 2025  06:17:41 +0000 (0:00:00.029)       0:00:03.125 ****
2025-12-03 06:17:41.044082 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/edpm-ansible/molecule/test-helpers/verify_podman.yaml for compute-1 => (item=frr)
2025-12-03 06:17:41.044253 | controller |
2025-12-03 06:17:41.044407 | controller | TASK [Check if podman container exists frr] ************************************
2025-12-03 06:17:41.044536 | controller | Wednesday 03 December 2025  06:17:41 +0000 (0:00:00.039)       0:00:03.164 ****
2025-12-03 06:17:41.273846 | controller | changed: [compute-1]
2025-12-03 06:17:41.306133 | controller |
2025-12-03 06:17:41.306170 | controller | TASK [Assert podman container exists frr] **************************************
2025-12-03 06:17:41.306179 | controller | Wednesday 03 December 2025  06:17:41 +0000 (0:00:00.229)       0:00:03.393 ****
2025-12-03 06:17:41.306190 | controller | ok: [compute-1] => changed=false
2025-12-03 06:17:41.306216 | controller |   msg: All assertions passed
2025-12-03 06:17:41.306624 | controller |
2025-12-03 06:17:41.531476 | controller | TASK [Check if podman container is running frr] ********************************
2025-12-03 06:17:41.531498 | controller | Wednesday 03 December 2025  06:17:41 +0000 (0:00:00.032)       0:00:03.426 ****
2025-12-03 06:17:41.531509 | controller | changed: [compute-1]
2025-12-03 06:17:41.561537 | controller |
2025-12-03 06:17:41.561555 | controller | TASK [Assert podman container is running frr] **********************************
2025-12-03 06:17:41.561562 | controller | Wednesday 03 December 2025  06:17:41 +0000 (0:00:00.225)       0:00:03.651 ****
2025-12-03 06:17:41.561570 | controller | ok: [compute-1] => changed=false
2025-12-03 06:17:41.590841 | controller |   msg: All assertions passed
2025-12-03 06:17:41.590860 | controller |
2025-12-03 06:17:41.590867 | controller | TASK [ensure systemd services are defined and functional] **********************
2025-12-03 06:17:41.590873 | controller | Wednesday 03 December 2025  06:17:41 +0000 (0:00:00.030)       0:00:03.681 ****
2025-12-03 06:17:41.590882 | 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'})
2025-12-03 06:17:41.808088 | controller |
2025-12-03 06:17:41.808124 | controller | TASK [Check if systemd file exists edpm_frr.service] ***************************
2025-12-03 06:17:41.808132 | controller | Wednesday 03 December 2025  06:17:41 +0000 (0:00:00.029)       0:00:03.710 ****
2025-12-03 06:17:41.808144 | controller | ok: [compute-1]
2025-12-03 06:17:41.833102 | controller |
2025-12-03 06:17:41.833130 | controller | TASK [Assert systemd unit exists edpm_frr.service] *****************************
2025-12-03 06:17:41.833140 | controller | Wednesday 03 December 2025  06:17:41 +0000 (0:00:00.216)       0:00:03.927 ****
2025-12-03 06:17:41.833152 | controller | ok: [compute-1] => changed=false
2025-12-03 06:17:41.833592 | controller |   msg: All assertions passed
2025-12-03 06:17:41.833635 | controller |
2025-12-03 06:17:42.022397 | controller | TASK [Check if systemd unit is enabled edpm_frr.service] ***********************
2025-12-03 06:17:42.022424 | controller | Wednesday 03 December 2025  06:17:41 +0000 (0:00:00.025)       0:00:03.953 ****
2025-12-03 06:17:42.022435 | controller | ok: [compute-1]
2025-12-03 06:17:42.046210 | controller |
2025-12-03 06:17:42.046242 | controller | TASK [Assert systemd unit is enabled edpm_frr.service] *************************
2025-12-03 06:17:42.046250 | controller | Wednesday 03 December 2025  06:17:42 +0000 (0:00:00.189)       0:00:04.142 ****
2025-12-03 06:17:42.046265 | controller | ok: [compute-1] => changed=false
2025-12-03 06:17:42.046290 | controller |   msg: All assertions passed
2025-12-03 06:17:42.046297 | controller |
2025-12-03 06:17:42.046304 | controller | TASK [Check if systemd unit is running edpm_frr.service] ***********************
2025-12-03 06:17:42.046387 | controller | Wednesday 03 December 2025  06:17:42 +0000 (0:00:00.023)       0:00:04.166 ****
2025-12-03 06:17:42.229567 | controller | ok: [compute-1]
2025-12-03 06:17:42.274086 | controller |
2025-12-03 06:17:42.274118 | controller | TASK [Assert systemd unit is running edpm_frr.service] *************************
2025-12-03 06:17:42.274126 | controller | Wednesday 03 December 2025  06:17:42 +0000 (0:00:00.183)       0:00:04.349 ****
2025-12-03 06:17:42.274137 | controller | ok: [compute-1] => changed=false
2025-12-03 06:17:42.274161 | controller |   msg: All assertions passed
2025-12-03 06:17:42.274174 | controller |
2025-12-03 06:17:42.276064 | controller | PLAY RECAP *********************************************************************
2025-12-03 06:17:42.276098 | controller | compute-1                  : ok=38   changed=5    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2025-12-03 06:17:42.276105 | controller |
2025-12-03 06:17:42.276111 | controller | Wednesday 03 December 2025  06:17:42 +0000 (0:00:00.044)       0:00:04.394 ****
2025-12-03 06:17:42.276116 | controller | ===============================================================================
2025-12-03 06:17:42.276122 | controller | Check if nftables rule exists in /etc/nftables/edpm-rules.nft ----------- 0.46s
2025-12-03 06:17:42.276127 | controller | Check input chain rules are loaded -------------------------------------- 0.39s
2025-12-03 06:17:42.276132 | controller | Check rule content ------------------------------------------------------ 0.36s
2025-12-03 06:17:42.276138 | controller | Check if directory exists /var/lib/edpm-config/container-startup-config/frr --- 0.27s
2025-12-03 06:17:42.276143 | controller | Check if podman container exists frr ------------------------------------ 0.23s
2025-12-03 06:17:42.276148 | controller | Check if podman container is running frr -------------------------------- 0.23s
2025-12-03 06:17:42.276154 | controller | Check if systemd file exists edpm_frr.service --------------------------- 0.22s
2025-12-03 06:17:42.276159 | controller | Check if file /var/lib/openstack/frr/etc/frr/frr.conf exist ------------- 0.19s
2025-12-03 06:17:42.276164 | controller | Check if directory exists /var/lib/openstack/frr ------------------------ 0.19s
2025-12-03 06:17:42.276169 | controller | Check if systemd unit is enabled edpm_frr.service ----------------------- 0.19s
2025-12-03 06:17:42.276175 | controller | rule source file exists ------------------------------------------------- 0.19s
2025-12-03 06:17:42.276180 | controller | Check if file /var/lib/openstack/frr/etc/frr/daemons exist -------------- 0.19s
2025-12-03 06:17:42.276185 | controller | Check if directory exists /etc/tmpfiles.d/ ------------------------------ 0.19s
2025-12-03 06:17:42.276190 | controller | Check if file /etc/tmpfiles.d/run-frr.conf exist ------------------------ 0.19s
2025-12-03 06:17:42.276196 | controller | Check if systemd unit is running edpm_frr.service ----------------------- 0.18s
2025-12-03 06:17:42.276203 | controller | Check if directory exists /var/lib/openstack/frr/etc/frr ---------------- 0.18s
2025-12-03 06:17:42.276226 | controller | Assert systemd unit is running edpm_frr.service ------------------------- 0.04s
2025-12-03 06:17:42.276345 | controller | ensure podman container exists and are running -------------------------- 0.04s
2025-12-03 06:17:42.276451 | controller | Assert directory exists /etc/tmpfiles.d/ -------------------------------- 0.04s
2025-12-03 06:17:42.276557 | controller | ensure expected directories exist --------------------------------------- 0.04s
2025-12-03 06:17:42.321296 | controller | INFO     Verifier completed successfully.
2025-12-03 06:17:42.322030 | controller | INFO     Writing /tmp/report.html report.
2025-12-03 06:17:47.595322 | controller | changed
2025-12-03 06:17:47.616777 | 
2025-12-03 06:17:47.616833 | PLAY RECAP
2025-12-03 06:17:47.616877 | controller | ok: 4 changed: 2 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-12-03 06:17:47.616897 | 
2025-12-03 06:17:47.671455 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-12-03 06:17:47.672221 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-12-03 06:17:48.157513 | 
2025-12-03 06:17:48.157622 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-12-03 06:17:48.178788 | 
2025-12-03 06:17:48.178867 | TASK [Filter out host if needed]
2025-12-03 06:17:48.187286 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-12-03 06:17:48.192076 | 
2025-12-03 06:17:48.192148 | TASK [Ensure file is present]
2025-12-03 06:17:48.474251 | controller | ok
2025-12-03 06:17:48.480953 | 
2025-12-03 06:17:48.481040 | TASK [Manage molecule report file]
2025-12-03 06:17:53.507910 | controller | changed
2025-12-03 06:17:53.513289 | 
2025-12-03 06:17:53.513355 | TASK [Check if we get ci-framework-data basedir]
2025-12-03 06:17:53.694218 | controller | ok
2025-12-03 06:17:53.699279 | 
2025-12-03 06:17:53.699341 | TASK [Create ci-framework-data log directory for zuul]
2025-12-03 06:17:53.990759 | controller | changed
2025-12-03 06:17:53.995738 | 
2025-12-03 06:17:53.995804 | TASK [Copy ci-framework interesting files]
2025-12-03 06:17:59.021672 | controller | changed
2025-12-03 06:17:59.026533 | 
2025-12-03 06:17:59.026593 | TASK [Get SELinux listing]
2025-12-03 06:18:04.048050 | controller | changed
2025-12-03 06:18:04.053356 | 
2025-12-03 06:18:04.053419 | TASK [Generate log index]
2025-12-03 06:18:04.639043 | controller | changed
2025-12-03 06:18:04.645834 | 
2025-12-03 06:18:04.645899 | TASK [Get some env related data]
2025-12-03 06:18:09.668688 | controller | changed
2025-12-03 06:18:09.673470 | 
2025-12-03 06:18:09.673532 | TASK [Generate list of logs to collect in home directory]
2025-12-03 06:18:09.937690 | controller | ok: All paths examined
2025-12-03 06:18:09.942443 | 
2025-12-03 06:18:09.942505 | LOOP [Copy logs from home directory]
2025-12-03 06:18:10.239247 | controller | changed:
2025-12-03 06:18:10.239358 | controller | {
2025-12-03 06:18:10.239386 | controller |   "atime": 1764742449.1260693,
2025-12-03 06:18:10.239406 | controller |   "ctime": 1764742464.5700734,
2025-12-03 06:18:10.239424 | controller |   "dev": 64513,
2025-12-03 06:18:10.239456 | controller |   "gid": 1000,
2025-12-03 06:18:10.239473 | controller |   "gr_name": "zuul",
2025-12-03 06:18:10.239490 | controller |   "inode": 4330377,
2025-12-03 06:18:10.239505 | controller |   "isblk": false,
2025-12-03 06:18:10.239521 | controller |   "ischr": false,
2025-12-03 06:18:10.239536 | controller |   "isdir": false,
2025-12-03 06:18:10.239550 | controller |   "isfifo": false,
2025-12-03 06:18:10.239565 | controller |   "isgid": false,
2025-12-03 06:18:10.239579 | controller |   "islnk": false,
2025-12-03 06:18:10.239593 | controller |   "isreg": true,
2025-12-03 06:18:10.239607 | controller |   "issock": false,
2025-12-03 06:18:10.239622 | controller |   "isuid": false,
2025-12-03 06:18:10.239637 | controller |   "mode": "0644",
2025-12-03 06:18:10.239651 | controller |   "mtime": 1764742464.5700734,
2025-12-03 06:18:10.239665 | controller |   "nlink": 1,
2025-12-03 06:18:10.239680 | controller |   "path": "/home/zuul/ansible.log",
2025-12-03 06:18:10.239715 | controller |   "pw_name": "zuul",
2025-12-03 06:18:10.239736 | controller |   "rgrp": true,
2025-12-03 06:18:10.239753 | controller |   "roth": true,
2025-12-03 06:18:10.239769 | controller |   "rusr": true,
2025-12-03 06:18:10.239784 | controller |   "size": 6749,
2025-12-03 06:18:10.239800 | controller |   "uid": 1000,
2025-12-03 06:18:10.239815 | controller |   "wgrp": false,
2025-12-03 06:18:10.239829 | controller |   "woth": false,
2025-12-03 06:18:10.239843 | controller |   "wusr": true,
2025-12-03 06:18:10.239857 | controller |   "xgrp": false,
2025-12-03 06:18:10.239873 | controller |   "xoth": false,
2025-12-03 06:18:10.239888 | controller |   "xusr": false
2025-12-03 06:18:10.239901 | controller | }
2025-12-03 06:18:10.253195 | 
2025-12-03 06:18:10.253305 | TASK [Copy crio stats log file]
2025-12-03 06:18:10.267120 | controller | skipping: Conditional result was False
2025-12-03 06:18:10.272206 | 
2025-12-03 06:18:10.272276 | TASK [Get SELinux related data]
2025-12-03 06:18:15.298641 | controller | ERROR
2025-12-03 06:18:15.298849 | controller | {
2025-12-03 06:18:15.298878 | controller |   "delta": "0:00:00.008072",
2025-12-03 06:18:15.298898 | controller |   "end": "2025-12-03 06:18:10.470600",
2025-12-03 06:18:15.298913 | controller |   "msg": "non-zero return code",
2025-12-03 06:18:15.298928 | controller |   "rc": 1,
2025-12-03 06:18:15.298943 | controller |   "start": "2025-12-03 06:18:10.462528"
2025-12-03 06:18:15.298957 | controller | }
2025-12-03 06:18:15.298977 | controller | ERROR: Ignoring Errors
2025-12-03 06:18:15.303659 | 
2025-12-03 06:18:15.303736 | TASK [Create system configuration directory]
2025-12-03 06:18:15.483823 | controller | changed
2025-12-03 06:18:15.488575 | 
2025-12-03 06:18:15.488637 | TASK [Get some of the system configurations]
2025-12-03 06:18:20.515290 | controller | changed
2025-12-03 06:18:20.520285 | 
2025-12-03 06:18:20.520347 | TASK [Copy generated documentation if available]
2025-12-03 06:18:20.533350 | controller | skipping: Conditional result was False
2025-12-03 06:18:20.538088 | 
2025-12-03 06:18:20.538150 | TASK [Copy generated AsciiDoc documentation if available]
2025-12-03 06:18:20.551246 | controller | skipping: Conditional result was False
2025-12-03 06:18:20.556121 | 
2025-12-03 06:18:20.556179 | TASK [Compress logs bigger than 2MB]
2025-12-03 06:18:25.580895 | controller | changed
2025-12-03 06:18:25.585925 | 
2025-12-03 06:18:25.585995 | TASK [Copy files from workspace on node]
2025-12-03 06:18:25.603383 | controller | ok
2025-12-03 06:18:25.623310 | 
2025-12-03 06:18:25.623375 | TASK [fetch-output : Set log path for multiple nodes]
2025-12-03 06:18:25.637153 | controller | skipping: Conditional result was False
2025-12-03 06:18:25.642671 | 
2025-12-03 06:18:25.642754 | TASK [fetch-output : Set log path for single node]
2025-12-03 06:18:25.670406 | controller | ok
2025-12-03 06:18:25.675158 | 
2025-12-03 06:18:25.675223 | LOOP [fetch-output : Ensure local output dirs]
2025-12-03 06:18:25.856493 | controller -> localhost | ok: "/var/lib/zuul/builds/6276732edb4c4c96aa50de44e817f9d3/work/logs"
2025-12-03 06:18:25.856762 | controller -> localhost | changed: All items complete
2025-12-03 06:18:25.856791 | 
2025-12-03 06:18:26.021798 | controller -> localhost | changed: "/var/lib/zuul/builds/6276732edb4c4c96aa50de44e817f9d3/work/artifacts"
2025-12-03 06:18:26.185894 | controller -> localhost | changed: "/var/lib/zuul/builds/6276732edb4c4c96aa50de44e817f9d3/work/docs"
2025-12-03 06:18:26.202334 | 
2025-12-03 06:18:26.202449 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-12-03 06:18:26.697081 | controller | changed:
2025-12-03 06:18:26.697263 | controller | .d..t...... ./
2025-12-03 06:18:26.697294 | controller | >f+++++++++ README.html
2025-12-03 06:18:26.697317 | controller | >f+++++++++ ansible-execution.log
2025-12-03 06:18:26.697338 | controller | >f+++++++++ ansible.log
2025-12-03 06:18:26.697357 | controller | >f+++++++++ dmesg.log
2025-12-03 06:18:26.697376 | controller | >f+++++++++ installed-pkgs.log
2025-12-03 06:18:26.697394 | controller | >f+++++++++ python.log
2025-12-03 06:18:26.697413 | controller | >f+++++++++ registries.conf
2025-12-03 06:18:26.697445 | controller | >f+++++++++ report.html
2025-12-03 06:18:26.697469 | controller | >f+++++++++ selinux-denials.log
2025-12-03 06:18:26.697490 | controller | >f+++++++++ selinux-listing.log
2025-12-03 06:18:26.697509 | controller | cd+++++++++ ci-framework-data/
2025-12-03 06:18:26.697529 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-12-03 06:18:26.697549 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-12-03 06:18:26.697567 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-12-03 06:18:26.697584 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-12-03 06:18:26.697603 | controller | cd+++++++++ ci-framework-data/logs/
2025-12-03 06:18:26.697620 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2025-12-03 06:18:26.697638 | controller | cd+++++++++ registries.conf.d/
2025-12-03 06:18:26.697655 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-12-03 06:18:26.697671 | controller | cd+++++++++ system-config/
2025-12-03 06:18:26.697688 | controller | cd+++++++++ system-config/libvirt/
2025-12-03 06:18:26.697737 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-12-03 06:18:26.697759 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-12-03 06:18:27.050277 | controller | changed: .d..t...... ./
2025-12-03 06:18:27.385484 | controller | changed: .d..t...... ./
2025-12-03 06:18:27.405181 | 
2025-12-03 06:18:27.405264 | TASK [Return artifact to Zuul]
2025-12-03 06:18:27.433270 | controller | ok
2025-12-03 06:18:27.451348 | 
2025-12-03 06:18:27.451404 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-12-03 06:18:27.451508 | 
2025-12-03 06:18:27.451535 | PLAY RECAP
2025-12-03 06:18:27.451572 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2025-12-03 06:18:27.451593 | 
2025-12-03 06:18:27.526087 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-12-03 06:18:27.526827 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-12-03 06:18:27.994060 | 
2025-12-03 06:18:27.994149 | PLAY [all]
2025-12-03 06:18:28.010862 | 
2025-12-03 06:18:28.010936 | TASK [include_role : fetch-output]
2025-12-03 06:18:28.039346 | controller | ok
2025-12-03 06:18:28.054336 | 
2025-12-03 06:18:28.054417 | TASK [fetch-output : Set log path for multiple nodes]
2025-12-03 06:18:28.088578 | controller | skipping: Conditional result was False
2025-12-03 06:18:28.094018 | 
2025-12-03 06:18:28.094093 | TASK [fetch-output : Set log path for single node]
2025-12-03 06:18:28.122404 | controller | ok
2025-12-03 06:18:28.127064 | 
2025-12-03 06:18:28.127135 | LOOP [fetch-output : Ensure local output dirs]
2025-12-03 06:18:28.426847 | controller -> localhost | ok: "/var/lib/zuul/builds/6276732edb4c4c96aa50de44e817f9d3/work/logs"
2025-12-03 06:18:28.592196 | controller -> localhost | ok: "/var/lib/zuul/builds/6276732edb4c4c96aa50de44e817f9d3/work/artifacts"
2025-12-03 06:18:28.756168 | controller -> localhost | ok: "/var/lib/zuul/builds/6276732edb4c4c96aa50de44e817f9d3/work/docs"
2025-12-03 06:18:28.763848 | 
2025-12-03 06:18:28.763910 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-12-03 06:18:29.247118 | controller | ok
2025-12-03 06:18:29.247304 | controller | ok: All items complete
2025-12-03 06:18:29.247335 | 
2025-12-03 06:18:29.619985 | controller | ok
2025-12-03 06:18:29.967855 | controller | ok
2025-12-03 06:18:29.984352 | 
2025-12-03 06:18:29.984475 | TASK [include_role : fetch-output-openshift]
2025-12-03 06:18:29.998176 | controller | skipping: Conditional result was False
2025-12-03 06:18:30.003752 | 
2025-12-03 06:18:30.003821 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-12-03 06:18:30.313197 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.006054
2025-12-03 06:18:30.487906 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.006187
2025-12-03 06:18:30.515794 | 
2025-12-03 06:18:30.515884 | PLAY [all]
2025-12-03 06:18:30.529689 | 
2025-12-03 06:18:30.529784 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-12-03 06:18:30.893542 | controller | changed
2025-12-03 06:18:30.915294 | 
2025-12-03 06:18:30.915342 | PLAY RECAP
2025-12-03 06:18:30.915382 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-12-03 06:18:30.915402 | 
2025-12-03 06:18:30.982291 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-12-03 06:18:30.983058 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-12-03 06:18:31.510956 | 
2025-12-03 06:18:31.511054 | PLAY [localhost]
2025-12-03 06:18:31.528150 | 
2025-12-03 06:18:31.528228 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-12-03 06:18:31.818921 | localhost | changed
2025-12-03 06:18:31.823644 | 
2025-12-03 06:18:31.823762 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-12-03 06:18:31.841503 | localhost | ok
2025-12-03 06:18:31.849262 | 
2025-12-03 06:18:31.849331 | TASK [add-fileserver : Create SSH private key tempfile]
2025-12-03 06:18:32.129444 | localhost | changed
2025-12-03 06:18:32.134778 | 
2025-12-03 06:18:32.134866 | TASK [add-fileserver : Create SSH private key from secret]
2025-12-03 06:18:32.638381 | localhost | changed
2025-12-03 06:18:32.643162 | 
2025-12-03 06:18:32.643229 | TASK [add-fileserver : Add fileserver ssh key]
2025-12-03 06:18:32.944966 | localhost | Identity added: /var/lib/zuul/builds/6276732edb4c4c96aa50de44e817f9d3/work/tmp/ansible.f2u4mg6w (/var/lib/zuul/builds/6276732edb4c4c96aa50de44e817f9d3/work/tmp/ansible.f2u4mg6w)
2025-12-03 06:18:32.945136 | localhost | ok: Runtime: 0:00:00.005397
2025-12-03 06:18:32.948962 | 
2025-12-03 06:18:32.949028 | TASK [add-fileserver : Remove SSH private key from disk]
2025-12-03 06:18:33.159080 | localhost | ok: Runtime: 0:00:00.004545
2025-12-03 06:18:33.163294 | 
2025-12-03 06:18:33.163363 | TASK [add-fileserver : Add fileserver to inventory]
2025-12-03 06:18:33.211539 | localhost | changed
2025-12-03 06:18:33.216324 | 
2025-12-03 06:18:33.216385 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-12-03 06:18:33.506747 | localhost | changed
2025-12-03 06:18:33.525152 | 
2025-12-03 06:18:33.525227 | PLAY [localhost]
2025-12-03 06:18:33.536533 | 
2025-12-03 06:18:33.536597 | TASK [Generate bulk log download script]
2025-12-03 06:18:33.555101 | localhost | ok
2025-12-03 06:18:33.565459 | 
2025-12-03 06:18:33.565523 | TASK [local-log-download : Check API endpoint is defined]
2025-12-03 06:18:33.592336 | localhost | ok: All assertions passed
2025-12-03 06:18:33.596281 | 
2025-12-03 06:18:33.596340 | TASK [local-log-download : Create download script]
2025-12-03 06:18:33.904271 | localhost -> localhost | changed
2025-12-03 06:18:33.912858 | 
2025-12-03 06:18:33.912937 | TASK [Register quick-download link]
2025-12-03 06:18:33.929124 | localhost | ok
2025-12-03 06:18:33.960608 | 
2025-12-03 06:18:33.960666 | PLAY [logserver.rdoproject.org]
2025-12-03 06:18:33.969349 | 
2025-12-03 06:18:33.969404 | TASK [Set zuul-log-path fact]
2025-12-03 06:18:33.984299 | logserver.rdoproject.org | ok
2025-12-03 06:18:33.992252 | 
2025-12-03 06:18:33.992314 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-12-03 06:18:34.007659 | logserver.rdoproject.org | ok
2025-12-03 06:18:34.012295 | 
2025-12-03 06:18:34.012354 | TASK [upload-logs : Create log directories]
2025-12-03 06:18:35.523336 | logserver.rdoproject.org | changed
2025-12-03 06:18:35.526469 | 
2025-12-03 06:18:35.526534 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-12-03 06:18:35.732072 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.004235
2025-12-03 06:18:35.736355 | 
2025-12-03 06:18:35.736418 | TASK [upload-logs : Upload logs to log server]
2025-12-03 06:18:37.122469 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-12-03 06:18:37.125204 | 
2025-12-03 06:18:37.125266 | LOOP [upload-logs : Compress console log and json output]
2025-12-03 06:18:37.161397 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-03 06:18:37.169590 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-03 06:18:37.181997 | 
2025-12-03 06:18:37.182065 | LOOP [upload-logs : Upload compressed console log and json output]
2025-12-03 06:18:37.215250 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-03 06:18:37.215416 | 
2025-12-03 06:18:37.218240 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-03 06:18:37.229025 | 
2025-12-03 06:18:37.229094 | LOOP [upload-logs : Upload console log and json output]