2025-10-08 16:01:45.085737 | Job console starting...
2025-10-08 16:01:45.325874 | Updating repositories
2025-10-08 16:01:46.063733 | Preparing job workspace
2025-10-08 16:01:56.302267 | Running Ansible setup...
2025-10-08 16:02:00.387455 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-10-08 16:02:01.386528 | 
2025-10-08 16:02:01.386653 | PLAY [localhost]
2025-10-08 16:02:01.417649 | 
2025-10-08 16:02:01.417868 | TASK [Gathering Facts]
2025-10-08 16:02:02.969322 | localhost | ok
2025-10-08 16:02:02.988075 | 
2025-10-08 16:02:02.988180 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-10-08 16:02:03.660290 | localhost -> localhost | changed
2025-10-08 16:02:03.666415 | 
2025-10-08 16:02:03.666500 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-10-08 16:02:04.827846 | localhost -> localhost | changed
2025-10-08 16:02:04.836374 | 
2025-10-08 16:02:04.836472 | TASK [Setup log path fact]
2025-10-08 16:02:04.854360 | localhost | ok
2025-10-08 16:02:04.876272 | 
2025-10-08 16:02:04.876396 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-08 16:02:04.924789 | localhost | ok
2025-10-08 16:02:04.936570 | 
2025-10-08 16:02:04.936691 | TASK [emit-job-header : Print job information]
2025-10-08 16:02:04.997353 | # Job Information
2025-10-08 16:02:04.997497 | Ansible Version: 2.15.12
2025-10-08 16:02:04.997527 | Job: cifmw-molecule-compliance
2025-10-08 16:02:04.997551 | Pipeline: github-check
2025-10-08 16:02:04.997572 | Executor: ibm-bm4-ze.softwarefactory-project.io
2025-10-08 16:02:04.997592 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3368
2025-10-08 16:02:04.997612 | Log URL (when completed): https://logserver.rdoproject.org/662/rdoproject.org/662eb396bebf496dba5101083bca8a20/
2025-10-08 16:02:04.997631 | Event ID: b612d780-a45c-11f0-87cb-9ea07ef2b05c
2025-10-08 16:02:05.001213 | 
2025-10-08 16:02:05.001283 | LOOP [emit-job-header : Print node information]
2025-10-08 16:02:05.238686 | localhost | ok:
2025-10-08 16:02:05.238887 | localhost | # Node Information
2025-10-08 16:02:05.238945 | localhost | Inventory Hostname: controller
2025-10-08 16:02:05.238980 | localhost | Hostname: np0005477366
2025-10-08 16:02:05.239004 | localhost | Username: zuul
2025-10-08 16:02:05.239026 | localhost | Distro: CentOS 9
2025-10-08 16:02:05.239046 | localhost | Provider: ibm-bm4-nodepool
2025-10-08 16:02:05.239064 | localhost | Region: regionOne
2025-10-08 16:02:05.239083 | localhost | Label: cloud-centos-9-stream-tripleo-ibm
2025-10-08 16:02:05.239101 | localhost | Product Name: OpenStack Compute
2025-10-08 16:02:05.239119 | localhost | Interface IP: 192.168.26.87
2025-10-08 16:02:05.301977 | 
2025-10-08 16:02:05.302711 | PLAY [all]
2025-10-08 16:02:05.330004 | 
2025-10-08 16:02:05.330287 | TASK [Gather network facts]
2025-10-08 16:02:05.712619 | controller | ok
2025-10-08 16:02:05.729409 | 
2025-10-08 16:02:05.729494 | TASK [include_role : start-zuul-console]
2025-10-08 16:02:05.748785 | controller | ok
2025-10-08 16:02:05.760642 | 
2025-10-08 16:02:05.760719 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-10-08 16:02:06.077389 | controller | ok
2025-10-08 16:02:06.092026 | 
2025-10-08 16:02:06.092112 | TASK [include_role : add-build-sshkey]
2025-10-08 16:02:06.110701 | controller | ok
2025-10-08 16:02:06.124557 | 
2025-10-08 16:02:06.124649 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-10-08 16:02:06.371684 | controller -> localhost | ok
2025-10-08 16:02:06.377160 | 
2025-10-08 16:02:06.377250 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-10-08 16:02:06.395132 | controller | ok
2025-10-08 16:02:06.408878 | controller | included: /var/lib/zuul/builds/662eb396bebf496dba5101083bca8a20/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-10-08 16:02:06.415584 | 
2025-10-08 16:02:06.415661 | TASK [add-build-sshkey : Create Temp SSH key]
2025-10-08 16:02:07.160674 | controller -> localhost | Generating public/private rsa key pair.
2025-10-08 16:02:07.160967 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/662eb396bebf496dba5101083bca8a20/work/662eb396bebf496dba5101083bca8a20_id_rsa.
2025-10-08 16:02:07.161007 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/662eb396bebf496dba5101083bca8a20/work/662eb396bebf496dba5101083bca8a20_id_rsa.pub.
2025-10-08 16:02:07.161031 | controller -> localhost | The key fingerprint is:
2025-10-08 16:02:07.161050 | controller -> localhost | SHA256:c4WpoEDoUsiQ36erFX5jQzOHEuuSv+mdBvh4DuoZQTo zuul-build-sshkey
2025-10-08 16:02:07.161070 | controller -> localhost | The key's randomart image is:
2025-10-08 16:02:07.161087 | controller -> localhost | +---[RSA 3072]----+
2025-10-08 16:02:07.161104 | controller -> localhost | |++.              |
2025-10-08 16:02:07.161123 | controller -> localhost | |+o.        o     |
2025-10-08 16:02:07.161141 | controller -> localhost | |.+... .   o .    |
2025-10-08 16:02:07.161160 | controller -> localhost | |+....+.o . .     |
2025-10-08 16:02:07.161177 | controller -> localhost | |E. .=o= S .      |
2025-10-08 16:02:07.161194 | controller -> localhost | | .o+o+ + o       |
2025-10-08 16:02:07.161210 | controller -> localhost | | .+o+o=          |
2025-10-08 16:02:07.161228 | controller -> localhost | | .+=+=.+         |
2025-10-08 16:02:07.161245 | controller -> localhost | |oo.=*oo          |
2025-10-08 16:02:07.161262 | controller -> localhost | +----[SHA256]-----+
2025-10-08 16:02:07.161321 | controller -> localhost | ok: Runtime: 0:00:00.376320
2025-10-08 16:02:07.168185 | 
2025-10-08 16:02:07.168253 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-10-08 16:02:07.200250 | controller | ok
2025-10-08 16:02:07.220839 | controller | included: /var/lib/zuul/builds/662eb396bebf496dba5101083bca8a20/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-10-08 16:02:07.230108 | 
2025-10-08 16:02:07.230201 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-10-08 16:02:07.265258 | controller | skipping: Conditional result was False
2025-10-08 16:02:07.271587 | 
2025-10-08 16:02:07.271665 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-10-08 16:02:07.733238 | controller | changed
2025-10-08 16:02:07.740221 | 
2025-10-08 16:02:07.740603 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-10-08 16:02:07.941866 | controller | ok
2025-10-08 16:02:07.946850 | 
2025-10-08 16:02:07.946936 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-10-08 16:02:08.608115 | controller | changed
2025-10-08 16:02:08.613447 | 
2025-10-08 16:02:08.613642 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-10-08 16:02:09.267212 | controller | changed
2025-10-08 16:02:09.279145 | 
2025-10-08 16:02:09.279233 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-10-08 16:02:09.303031 | controller | skipping: Conditional result was False
2025-10-08 16:02:09.308469 | 
2025-10-08 16:02:09.308539 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-10-08 16:02:09.843554 | controller -> localhost | changed
2025-10-08 16:02:09.854883 | 
2025-10-08 16:02:09.854994 | TASK [add-build-sshkey : Add back temp key]
2025-10-08 16:02:10.257040 | controller -> localhost | Identity added: /var/lib/zuul/builds/662eb396bebf496dba5101083bca8a20/work/662eb396bebf496dba5101083bca8a20_id_rsa (zuul-build-sshkey)
2025-10-08 16:02:10.257239 | controller -> localhost | ok: Runtime: 0:00:00.008079
2025-10-08 16:02:10.262826 | 
2025-10-08 16:02:10.262893 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-10-08 16:02:10.616106 | controller | ok
2025-10-08 16:02:10.621247 | 
2025-10-08 16:02:10.621343 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-10-08 16:02:10.645030 | controller | skipping: Conditional result was False
2025-10-08 16:02:10.654346 | 
2025-10-08 16:02:10.654415 | TASK [include_role : validate-host]
2025-10-08 16:02:10.681709 | controller | ok
2025-10-08 16:02:10.700773 | 
2025-10-08 16:02:10.700863 | TASK [validate-host : Define zuul_info_dir fact]
2025-10-08 16:02:10.759003 | controller | ok
2025-10-08 16:02:10.767420 | 
2025-10-08 16:02:10.767499 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-10-08 16:02:11.063411 | controller -> localhost | ok
2025-10-08 16:02:11.068502 | 
2025-10-08 16:02:11.068569 | TASK [validate-host : Collect information about the host]
2025-10-08 16:02:11.705864 | controller | ok
2025-10-08 16:02:11.723568 | 
2025-10-08 16:02:11.723700 | TASK [validate-host : Sanitize hostname]
2025-10-08 16:02:11.781172 | controller | ok
2025-10-08 16:02:11.787245 | 
2025-10-08 16:02:11.787345 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-10-08 16:02:12.307095 | controller -> localhost | changed
2025-10-08 16:02:12.313094 | 
2025-10-08 16:02:12.313172 | TASK [validate-host : Collect information about zuul worker]
2025-10-08 16:02:12.687935 | controller | ok
2025-10-08 16:02:12.692864 | 
2025-10-08 16:02:12.692950 | TASK [validate-host : Write out all zuul information for each host]
2025-10-08 16:02:13.116294 | controller -> localhost | changed
2025-10-08 16:02:13.125858 | 
2025-10-08 16:02:13.125947 | TASK [include_role : prepare-workspace-openshift]
2025-10-08 16:02:13.139547 | controller | skipping: Conditional result was False
2025-10-08 16:02:13.146038 | 
2025-10-08 16:02:13.146129 | TASK [include_role : remove-zuul-sshkey]
2025-10-08 16:02:13.161775 | controller | skipping: Conditional result was False
2025-10-08 16:02:13.167957 | 
2025-10-08 16:02:13.168046 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-10-08 16:02:13.367397 | controller | ok: "logs"
2025-10-08 16:02:13.367720 | controller | ok: All items complete
2025-10-08 16:02:13.367759 | 
2025-10-08 16:02:13.533680 | controller | ok: "artifacts"
2025-10-08 16:02:13.705489 | controller | ok: "docs"
2025-10-08 16:02:13.723827 | 
2025-10-08 16:02:13.723946 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-10-08 16:02:13.962982 | controller | changed: "logs"
2025-10-08 16:02:14.157443 | controller | changed: "artifacts"
2025-10-08 16:02:14.334395 | controller | changed: "docs"
2025-10-08 16:02:14.377074 | 
2025-10-08 16:02:14.377170 | PLAY RECAP
2025-10-08 16:02:14.377215 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-10-08 16:02:14.377244 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-08 16:02:14.377264 | 
2025-10-08 16:02:14.485246 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-10-08 16:02:14.486053 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-10-08 16:02:15.263093 | 
2025-10-08 16:02:15.263216 | PLAY [all]
2025-10-08 16:02:15.284276 | 
2025-10-08 16:02:15.284391 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-10-08 16:02:15.334651 | controller | ok
2025-10-08 16:02:15.340249 | 
2025-10-08 16:02:15.340362 | TASK [mirror-info-fork : Create /etc/ci]
2025-10-08 16:02:15.717327 | controller | changed
2025-10-08 16:02:15.723194 | 
2025-10-08 16:02:15.723266 | TASK [mirror-info-fork : Install ci_mirror script]
2025-10-08 16:02:16.587045 | controller | changed
2025-10-08 16:02:16.597077 | 
2025-10-08 16:02:16.597177 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-10-08 16:02:16.991606 | controller | changed:
2025-10-08 16:02:16.991840 | controller | {
2025-10-08 16:02:16.991873 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-10-08 16:02:16.991904 | controller | }
2025-10-08 16:02:17.205257 | controller | changed:
2025-10-08 16:02:17.205363 | controller | {
2025-10-08 16:02:17.205397 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-10-08 16:02:17.205422 | controller | }
2025-10-08 16:02:17.420032 | controller | changed:
2025-10-08 16:02:17.420138 | controller | {
2025-10-08 16:02:17.420167 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com"
2025-10-08 16:02:17.420189 | controller | }
2025-10-08 16:02:17.638629 | controller | changed:
2025-10-08 16:02:17.638787 | controller | {
2025-10-08 16:02:17.638828 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-10-08 16:02:17.638851 | controller | }
2025-10-08 16:02:17.866277 | controller | changed:
2025-10-08 16:02:17.866377 | controller | {
2025-10-08 16:02:17.866409 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-10-08 16:02:17.866431 | controller | }
2025-10-08 16:02:18.083010 | controller | changed:
2025-10-08 16:02:18.083165 | controller | {
2025-10-08 16:02:18.083198 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-10-08 16:02:18.083221 | controller | }
2025-10-08 16:02:18.293776 | controller | changed:
2025-10-08 16:02:18.293869 | controller | {
2025-10-08 16:02:18.293896 | 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-10-08 16:02:18.293941 | controller | }
2025-10-08 16:02:18.524393 | controller | changed:
2025-10-08 16:02:18.524488 | controller | {
2025-10-08 16:02:18.524517 | 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-10-08 16:02:18.524539 | controller | }
2025-10-08 16:02:18.766169 | controller | changed:
2025-10-08 16:02:18.766272 | controller | {
2025-10-08 16:02:18.766299 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-10-08 16:02:18.766338 | controller | }
2025-10-08 16:02:18.982080 | controller | changed:
2025-10-08 16:02:18.982168 | controller | {
2025-10-08 16:02:18.982196 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-10-08 16:02:18.982218 | controller | }
2025-10-08 16:02:19.192898 | controller | changed:
2025-10-08 16:02:19.192999 | controller | {
2025-10-08 16:02:19.193027 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-10-08 16:02:19.193049 | controller | }
2025-10-08 16:02:19.408151 | controller | changed:
2025-10-08 16:02:19.408251 | controller | {
2025-10-08 16:02:19.408281 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-10-08 16:02:19.408302 | controller | }
2025-10-08 16:02:19.639009 | controller | changed:
2025-10-08 16:02:19.639092 | controller | {
2025-10-08 16:02:19.639120 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-10-08 16:02:19.639141 | controller | }
2025-10-08 16:02:19.866448 | controller | changed:
2025-10-08 16:02:19.866544 | controller | {
2025-10-08 16:02:19.866572 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-10-08 16:02:19.866594 | controller | }
2025-10-08 16:02:20.081125 | controller | changed:
2025-10-08 16:02:20.081213 | controller | {
2025-10-08 16:02:20.081241 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-10-08 16:02:20.081433 | controller | }
2025-10-08 16:02:20.293342 | controller | changed:
2025-10-08 16:02:20.293439 | controller | {
2025-10-08 16:02:20.293468 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-10-08 16:02:20.293488 | controller | }
2025-10-08 16:02:20.504055 | controller | changed:
2025-10-08 16:02:20.504144 | controller | {
2025-10-08 16:02:20.504172 | 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-10-08 16:02:20.504195 | controller | }
2025-10-08 16:02:20.712503 | controller | changed:
2025-10-08 16:02:20.712591 | controller | {
2025-10-08 16:02:20.712623 | 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-10-08 16:02:20.712653 | controller | }
2025-10-08 16:02:20.930272 | controller | changed:
2025-10-08 16:02:20.930371 | controller | {
2025-10-08 16:02:20.930402 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-10-08 16:02:20.930424 | controller | }
2025-10-08 16:02:21.145967 | controller | changed:
2025-10-08 16:02:21.146051 | controller | {
2025-10-08 16:02:21.146079 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-10-08 16:02:21.146100 | controller | }
2025-10-08 16:02:21.362636 | controller | changed:
2025-10-08 16:02:21.362721 | controller | {
2025-10-08 16:02:21.362750 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-10-08 16:02:21.362771 | controller | }
2025-10-08 16:02:21.577663 | controller | changed:
2025-10-08 16:02:21.577749 | controller | {
2025-10-08 16:02:21.577776 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-10-08 16:02:21.577796 | controller | }
2025-10-08 16:02:21.791876 | controller | changed:
2025-10-08 16:02:21.791976 | controller | {
2025-10-08 16:02:21.792003 | 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-10-08 16:02:21.792024 | controller | }
2025-10-08 16:02:22.017206 | controller | changed:
2025-10-08 16:02:22.017290 | controller | {
2025-10-08 16:02:22.017342 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-10-08 16:02:22.017364 | controller | }
2025-10-08 16:02:22.243411 | controller | changed:
2025-10-08 16:02:22.243499 | controller | {
2025-10-08 16:02:22.243533 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-10-08 16:02:22.243560 | controller | }
2025-10-08 16:02:22.458390 | controller | changed:
2025-10-08 16:02:22.458482 | controller | {
2025-10-08 16:02:22.458511 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-10-08 16:02:22.458532 | controller | }
2025-10-08 16:02:22.482481 | 
2025-10-08 16:02:22.482589 | TASK [Set timezone to UTC]
2025-10-08 16:02:22.907083 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-10-08 16:02:22.912838 | 
2025-10-08 16:02:22.912936 | TASK [Create nodepool directory]
2025-10-08 16:02:23.155186 | controller | changed
2025-10-08 16:02:23.160862 | 
2025-10-08 16:02:23.160968 | TASK [Create nodepool sub_nodes file]
2025-10-08 16:02:23.709055 | controller | changed
2025-10-08 16:02:23.714443 | 
2025-10-08 16:02:23.714520 | TASK [Create nodepool sub_nodes_private file]
2025-10-08 16:02:24.252179 | controller | changed
2025-10-08 16:02:24.257364 | 
2025-10-08 16:02:24.257454 | LOOP [Populate nodepool sub_nodes file]
2025-10-08 16:02:24.287778 | 
2025-10-08 16:02:24.287946 | LOOP [Populate nodepool sub_nodes_private file]
2025-10-08 16:02:24.317513 | 
2025-10-08 16:02:24.317657 | TASK [Create nodepool primary file]
2025-10-08 16:02:24.341017 | controller | skipping: Conditional result was False
2025-10-08 16:02:24.347425 | 
2025-10-08 16:02:24.347521 | TASK [Create nodepool node_private for this node]
2025-10-08 16:02:24.966760 | controller | changed
2025-10-08 16:02:24.972097 | 
2025-10-08 16:02:24.972178 | LOOP [Copy ssh keys to nodepool directory]
2025-10-08 16:02:25.311566 | controller | ok: Item: id_rsa Runtime: 0:00:00.006153
2025-10-08 16:02:25.311742 | 
2025-10-08 16:02:25.486722 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.005182
2025-10-08 16:02:25.502454 | 
2025-10-08 16:02:25.502544 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-10-08 16:02:26.080684 | controller | changed
2025-10-08 16:02:26.090166 | 
2025-10-08 16:02:26.090249 | TASK [Validate sudoers config after edits]
2025-10-08 16:02:26.337489 | controller | /etc/sudoers: parsed OK
2025-10-08 16:02:26.337550 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-10-08 16:02:26.337558 | controller | /etc/sudoers.d/zuul: parsed OK
2025-10-08 16:02:26.337564 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-10-08 16:02:26.620199 | controller | ok: Runtime: 0:00:00.005972
2025-10-08 16:02:26.626538 | 
2025-10-08 16:02:26.626762 | TASK [Show the environment passed in to job shell scripts]
2025-10-08 16:02:26.844503 | controller | SHELL=/bin/bash
2025-10-08 16:02:26.844560 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-10-08 16:02:26.844570 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-10-08 16:02:26.844577 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/85/3385/f8a3c957fc51bd892aee4b1f66ce262682944823^openstack-k8s-operators/ci-framework:main:refs/changes/68/3368/127d45eecba72e2daf3d33e73e380ccf7b6e7649
2025-10-08 16:02:26.844586 | controller | PWD=/home/zuul
2025-10-08 16:02:26.844591 | controller | ZUUL_PIPELINE=github-check
2025-10-08 16:02:26.844650 | controller | LOGNAME=zuul
2025-10-08 16:02:26.844659 | controller | XDG_SESSION_TYPE=tty
2025-10-08 16:02:26.844665 | controller | _=/usr/bin/env
2025-10-08 16:02:26.844670 | controller | MOTD_SHOWN=pam
2025-10-08 16:02:26.844676 | controller | HOME=/home/zuul
2025-10-08 16:02:26.844681 | controller | LANG=en_US.UTF-8
2025-10-08 16:02:26.844686 | controller | SSH_CONNECTION=192.168.26.12 42078 192.168.26.87 22
2025-10-08 16:02:26.844692 | 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-10-08 16:02:26.844698 | controller | ZUUL_CHANGE_IDS=3385,f8a3c957fc51bd892aee4b1f66ce262682944823 3368,127d45eecba72e2daf3d33e73e380ccf7b6e7649
2025-10-08 16:02:26.844703 | controller | WORKSPACE=/home/zuul/workspace
2025-10-08 16:02:26.844709 | controller | XDG_SESSION_CLASS=user
2025-10-08 16:02:26.844715 | controller | SELINUX_ROLE_REQUESTED=
2025-10-08 16:02:26.844720 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-10-08 16:02:26.844725 | controller | USER=zuul
2025-10-08 16:02:26.844731 | controller | ZUUL_VOTING=True
2025-10-08 16:02:26.844736 | controller | BUILD_TIMEOUT=1800000
2025-10-08 16:02:26.844741 | controller | SELINUX_USE_CURRENT_RANGE=
2025-10-08 16:02:26.844746 | controller | SHLVL=1
2025-10-08 16:02:26.844752 | controller | ZUUL_PATCHSET=127d45eecba72e2daf3d33e73e380ccf7b6e7649
2025-10-08 16:02:26.844757 | controller | XDG_SESSION_ID=1
2025-10-08 16:02:26.844763 | controller | ZUUL_BRANCH=main
2025-10-08 16:02:26.844768 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-10-08 16:02:26.844773 | controller | SSH_CLIENT=192.168.26.12 42078 22
2025-10-08 16:02:26.844792 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-10-08 16:02:26.844811 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-10-08 16:02:26.844817 | controller | which_declare=declare -f
2025-10-08 16:02:26.844824 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2025-10-08 16:02:26.844829 | controller | SELINUX_LEVEL_REQUESTED=
2025-10-08 16:02:26.844835 | controller | ZUUL_CHANGE=3368
2025-10-08 16:02:26.844840 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-10-08 16:02:26.844846 | controller | ZUUL_UUID=662eb396bebf496dba5101083bca8a20
2025-10-08 16:02:26.844851 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-10-08 16:02:26.844857 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-10-08 16:02:26.844862 | controller | }
2025-10-08 16:02:27.155239 | controller | ok: Runtime: 0:00:00.006382
2025-10-08 16:02:27.161206 | 
2025-10-08 16:02:27.161286 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-10-08 16:02:27.175241 | controller | skipping: Conditional result was False
2025-10-08 16:02:27.180927 | 
2025-10-08 16:02:27.181001 | TASK [Symlink /home/zuul-worker/workspace]
2025-10-08 16:02:27.703665 | controller | skipping: Conditional result was False
2025-10-08 16:02:27.709718 | 
2025-10-08 16:02:27.709789 | TASK [Ensure legacy workspace directory]
2025-10-08 16:02:27.912577 | controller | changed
2025-10-08 16:02:27.931207 | 
2025-10-08 16:02:27.931347 | PLAY RECAP
2025-10-08 16:02:27.931401 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-10-08 16:02:27.931427 | 
2025-10-08 16:02:28.025227 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-10-08 16:02:28.026069 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-10-08 16:02:28.573380 | 
2025-10-08 16:02:28.573493 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-10-08 16:02:28.606272 | 
2025-10-08 16:02:28.606401 | TASK [Create zuul-output directory]
2025-10-08 16:02:28.962150 | controller | changed
2025-10-08 16:02:28.967446 | 
2025-10-08 16:02:28.967548 | TASK [Slurp Zuul inventory test]
2025-10-08 16:02:29.266306 | controller -> localhost | ok
2025-10-08 16:02:29.273092 | 
2025-10-08 16:02:29.273187 | TASK [Save zuul inventory]
2025-10-08 16:02:30.052417 | controller | changed
2025-10-08 16:02:30.057480 | 
2025-10-08 16:02:30.057564 | TASK [Save zuul vars without the change_message]
2025-10-08 16:02:30.688553 | controller | changed
2025-10-08 16:02:30.707847 | 
2025-10-08 16:02:30.707953 | PLAY RECAP
2025-10-08 16:02:30.708003 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-08 16:02:30.708027 | 
2025-10-08 16:02:30.785773 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-10-08 16:02:30.786597 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-10-08 16:02:31.357330 | 
2025-10-08 16:02:31.357446 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2025-10-08 16:02:31.378819 | 
2025-10-08 16:02:31.378956 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2025-10-08 16:02:31.410404 | controller | ok
2025-10-08 16:02:31.427387 | 
2025-10-08 16:02:31.427689 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-10-08 16:02:31.451936 | controller | skipping: Conditional result was False
2025-10-08 16:02:31.457681 | 
2025-10-08 16:02:31.457764 | TASK [mirror-info-fork : Create /etc/ci]
2025-10-08 16:02:31.780516 | controller | ok
2025-10-08 16:02:31.794923 | 
2025-10-08 16:02:31.795038 | TASK [mirror-info-fork : Install ci_mirror script]
2025-10-08 16:02:32.433273 | controller | ok
2025-10-08 16:02:32.444634 | 
2025-10-08 16:02:32.444732 | TASK [Prepare workspace]
2025-10-08 16:02:32.463609 | controller | ok
2025-10-08 16:02:32.481486 | 
2025-10-08 16:02:32.481574 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-10-08 16:02:32.785070 | controller | ok
2025-10-08 16:02:32.792001 | 
2025-10-08 16:02:32.792075 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-10-08 16:02:35.273495 | controller | Output suppressed because no_log was given
2025-10-08 16:02:35.282794 | 
2025-10-08 16:02:35.282862 | LOOP [Create zuul-output directory]
2025-10-08 16:02:35.469416 | controller | changed: "/home/zuul/ci-framework-data/logs"
2025-10-08 16:02:35.634459 | controller | ok: "/home/zuul/zuul-output/logs"
2025-10-08 16:02:35.643420 | 
2025-10-08 16:02:35.643563 | TASK [Install required packages]
2025-10-08 16:03:30.708149 | controller | changed
2025-10-08 16:03:30.717440 | 
2025-10-08 16:03:30.717558 | TASK [Install venv]
2025-10-08 16:04:33.498360 | controller | changed
2025-10-08 16:04:33.530183 | 
2025-10-08 16:04:33.530284 | PLAY RECAP
2025-10-08 16:04:33.530332 | controller | ok: 7 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-10-08 16:04:33.530357 | 
2025-10-08 16:04:33.643182 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-10-08 16:04:33.644051 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-10-08 16:04:34.215812 | 
2025-10-08 16:04:34.215947 | PLAY [Run ci/playbooks/molecule-test.yml]
2025-10-08 16:04:34.237084 | 
2025-10-08 16:04:34.237166 | TASK [Gather required facts]
2025-10-08 16:04:34.733757 | controller | ok
2025-10-08 16:04:34.739178 | 
2025-10-08 16:04:34.739275 | TASK [Load environment var if instructed to]
2025-10-08 16:04:34.763536 | controller | skipping: Conditional result was False
2025-10-08 16:04:34.768829 | 
2025-10-08 16:04:34.768901 | TASK [Ensure group_vars dir exists]
2025-10-08 16:04:35.091758 | controller | ok
2025-10-08 16:04:35.098016 | 
2025-10-08 16:04:35.098114 | TASK [Run molecule]
2025-10-08 16:04:36.057007 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2025-10-08 16:04:36.129577 | controller | INFO     Performing prerun with role_name_check=0...
2025-10-08 16:04:52.761890 | controller | WARNING  Another version of '@NAMESPACE@.@NAME@' 3.1.4 was found installed in /home/zuul/.cache/ansible-compat/29a3e1/collections/ansible_collections, only the first one will be used, 3.1.4 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 16:04:52.762361 | controller | WARNING  Another version of 'ansible.netcommon' 7.1.0 was found installed in /home/zuul/.cache/ansible-compat/29a3e1/collections/ansible_collections, only the first one will be used, 7.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 16:04:52.762847 | controller | WARNING  Another version of 'ansible.posix' 1.6.2 was found installed in /home/zuul/.cache/ansible-compat/29a3e1/collections/ansible_collections, only the first one will be used, 1.6.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 16:04:52.763305 | controller | WARNING  Another version of 'ansible.utils' 5.1.2 was found installed in /home/zuul/.cache/ansible-compat/29a3e1/collections/ansible_collections, only the first one will be used, 5.1.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 16:04:52.763803 | controller | WARNING  Another version of 'cifmw.general' 1.0.0+4772ac9e was found installed in /home/zuul/.cache/ansible-compat/29a3e1/collections/ansible_collections, only the first one will be used, 1.0.0+4772ac9e (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 16:04:52.764327 | controller | WARNING  Another version of 'cisco.ios' 9.0.3 was found installed in /home/zuul/.cache/ansible-compat/29a3e1/collections/ansible_collections, only the first one will be used, 9.0.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 16:04:52.764811 | controller | WARNING  Another version of 'community.crypto' 2.22.3 was found installed in /home/zuul/.cache/ansible-compat/29a3e1/collections/ansible_collections, only the first one will be used, 2.22.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 16:04:52.765265 | controller | WARNING  Another version of 'community.general' 10.0.1 was found installed in /home/zuul/.cache/ansible-compat/29a3e1/collections/ansible_collections, only the first one will be used, 10.0.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 16:04:52.765721 | controller | WARNING  Another version of 'community.libvirt' 1.3.0 was found installed in /home/zuul/.cache/ansible-compat/29a3e1/collections/ansible_collections, only the first one will be used, 1.3.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 16:04:52.766169 | controller | WARNING  Another version of 'community.okd' 4.0.0 was found installed in /home/zuul/.cache/ansible-compat/29a3e1/collections/ansible_collections, only the first one will be used, 4.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 16:04:52.766605 | controller | WARNING  Another version of 'containers.podman' 1.16.2 was found installed in /home/zuul/.cache/ansible-compat/29a3e1/collections/ansible_collections, only the first one will be used, 1.16.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 16:04:52.767093 | controller | WARNING  Another version of 'junipernetworks.junos' 9.1.0 was found installed in /home/zuul/.cache/ansible-compat/29a3e1/collections/ansible_collections, only the first one will be used, 9.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 16:04:52.767534 | controller | WARNING  Another version of 'kubernetes.core' 5.0.0 was found installed in /home/zuul/.cache/ansible-compat/29a3e1/collections/ansible_collections, only the first one will be used, 5.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 16:04:52.768015 | controller | WARNING  Another version of 'mellanox.onyx' 1.0.0 was found installed in /home/zuul/.cache/ansible-compat/29a3e1/collections/ansible_collections, only the first one will be used, 1.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 16:04:52.768452 | controller | WARNING  Another version of 'openstack.config_template' 2.1.1 was found installed in /home/zuul/.cache/ansible-compat/29a3e1/collections/ansible_collections, only the first one will be used, 2.1.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 16:04:52.778207 | controller | INFO     Running default > prepare
2025-10-08 16:04:52.778690 | controller | WARNING  Skipping, prepare playbook not configured.
2025-10-08 16:04:52.786527 | controller | INFO     Running default > converge
2025-10-08 16:04:53.450130 | controller |
2025-10-08 16:04:53.450206 | controller | PLAY [Converge test of controller tasks] ***************************************
2025-10-08 16:04:53.450308 | controller |
2025-10-08 16:04:53.450415 | controller | TASK [Gathering Facts] *********************************************************
2025-10-08 16:04:53.450518 | controller | Wednesday 08 October 2025  16:04:53 +0000 (0:00:00.010)       0:00:00.010 *****
2025-10-08 16:04:54.309534 | controller | ok: [instance]
2025-10-08 16:04:54.309602 | controller |
2025-10-08 16:04:54.309769 | controller | TASK [compliance : Ensure compliance folder exists] ****************************
2025-10-08 16:04:54.309942 | controller | Wednesday 08 October 2025  16:04:54 +0000 (0:00:00.859)       0:00:00.870 *****
2025-10-08 16:04:54.614810 | controller | changed: [instance]
2025-10-08 16:04:54.614867 | controller |
2025-10-08 16:04:54.614954 | controller | TASK [compliance : Install the compliance operator and plugin] *****************
2025-10-08 16:04:54.615069 | controller | Wednesday 08 October 2025  16:04:54 +0000 (0:00:00.305)       0:00:01.175 *****
2025-10-08 16:04:54.648473 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/install.yml for instance
2025-10-08 16:04:54.648521 | controller |
2025-10-08 16:04:54.648643 | controller | TASK [compliance : Create the compliance namespace] ****************************
2025-10-08 16:04:54.648813 | controller | Wednesday 08 October 2025  16:04:54 +0000 (0:00:00.033)       0:00:01.209 *****
2025-10-08 16:04:54.675485 | controller | skipping: [instance]
2025-10-08 16:04:54.675556 | controller |
2025-10-08 16:04:54.675709 | controller | TASK [compliance : Ensure OperatorGroup for the compliance-operator is present] ***
2025-10-08 16:04:54.675828 | controller | Wednesday 08 October 2025  16:04:54 +0000 (0:00:00.027)       0:00:01.236 *****
2025-10-08 16:04:54.702213 | controller | skipping: [instance]
2025-10-08 16:04:54.702310 | controller |
2025-10-08 16:04:54.702447 | controller | TASK [compliance : Ensure Subscription for the compliance-operator is present] ***
2025-10-08 16:04:54.702574 | controller | Wednesday 08 October 2025  16:04:54 +0000 (0:00:00.026)       0:00:01.263 *****
2025-10-08 16:04:54.731589 | controller | skipping: [instance]
2025-10-08 16:04:54.731644 | controller |
2025-10-08 16:04:54.731804 | controller | TASK [compliance : Wait until the compliance-operator csv is present] **********
2025-10-08 16:04:54.731939 | controller | Wednesday 08 October 2025  16:04:54 +0000 (0:00:00.029)       0:00:01.292 *****
2025-10-08 16:04:54.758914 | controller | skipping: [instance]
2025-10-08 16:04:54.758969 | controller |
2025-10-08 16:04:54.759095 | controller | TASK [compliance : Get full name of the compliance-operator csv] ***************
2025-10-08 16:04:54.759204 | controller | Wednesday 08 October 2025  16:04:54 +0000 (0:00:00.027)       0:00:01.319 *****
2025-10-08 16:04:54.786828 | controller | skipping: [instance]
2025-10-08 16:04:54.786934 | controller |
2025-10-08 16:04:54.787051 | controller | TASK [compliance : Wait for the compliance-operator csv to Succeed] ************
2025-10-08 16:04:54.787166 | controller | Wednesday 08 October 2025  16:04:54 +0000 (0:00:00.028)       0:00:01.347 *****
2025-10-08 16:04:54.814009 | controller | skipping: [instance]
2025-10-08 16:04:54.814089 | controller |
2025-10-08 16:04:54.814205 | controller | TASK [podman : Ensure podman is installed] *************************************
2025-10-08 16:04:54.814322 | controller | Wednesday 08 October 2025  16:04:54 +0000 (0:00:00.027)       0:00:01.374 *****
2025-10-08 16:04:55.619141 | controller | ok: [instance]
2025-10-08 16:04:55.619591 | controller |
2025-10-08 16:04:55.619743 | controller | TASK [podman : Enable loginctl linger for ansible_user_id] *********************
2025-10-08 16:04:55.619883 | controller | Wednesday 08 October 2025  16:04:55 +0000 (0:00:00.805)       0:00:02.179 *****
2025-10-08 16:04:55.966280 | controller | changed: [instance]
2025-10-08 16:04:55.966372 | controller |
2025-10-08 16:04:55.966493 | controller | TASK [podman : Ensure subordinate UID entry exists for zuul] *******************
2025-10-08 16:04:55.966605 | controller | Wednesday 08 October 2025  16:04:55 +0000 (0:00:00.347)       0:00:02.527 *****
2025-10-08 16:04:55.988185 | controller | skipping: [instance]
2025-10-08 16:04:55.988266 | controller |
2025-10-08 16:04:55.988374 | controller | TASK [podman : Ensure subordinate GID entry exists for zuul] *******************
2025-10-08 16:04:55.988474 | controller | Wednesday 08 October 2025  16:04:55 +0000 (0:00:00.022)       0:00:02.549 *****
2025-10-08 16:04:56.007644 | controller | skipping: [instance]
2025-10-08 16:04:56.007739 | controller |
2025-10-08 16:04:56.007890 | controller | TASK [podman : Run podman system migrate if subuid/subgid files were changed] ***
2025-10-08 16:04:56.007993 | controller | Wednesday 08 October 2025  16:04:56 +0000 (0:00:00.019)       0:00:02.568 *****
2025-10-08 16:04:56.031151 | controller | skipping: [instance]
2025-10-08 16:04:56.031234 | controller |
2025-10-08 16:04:56.031344 | controller | TASK [compliance : Log into registry] ******************************************
2025-10-08 16:04:56.031451 | controller | Wednesday 08 October 2025  16:04:56 +0000 (0:00:00.023)       0:00:02.592 *****
2025-10-08 16:04:56.047669 | controller | skipping: [instance]
2025-10-08 16:04:56.047755 | controller |
2025-10-08 16:04:56.047898 | controller | TASK [compliance : Copy out the compliance plugin from the oc-compliance image] ***
2025-10-08 16:04:56.048007 | controller | Wednesday 08 October 2025  16:04:56 +0000 (0:00:00.016)       0:00:02.608 *****
2025-10-08 16:04:56.064330 | controller | skipping: [instance]
2025-10-08 16:04:56.064425 | controller |
2025-10-08 16:04:56.064534 | controller | TASK [compliance : Install packages to process results] ************************
2025-10-08 16:04:56.064639 | controller | Wednesday 08 October 2025  16:04:56 +0000 (0:00:00.016)       0:00:02.625 *****
2025-10-08 16:05:00.205896 | controller | changed: [instance]
2025-10-08 16:05:00.206225 | controller |
2025-10-08 16:05:00.249882 | controller | TASK [compliance : Run compliance scans and extract results] *******************
2025-10-08 16:05:00.249920 | controller | Wednesday 08 October 2025  16:05:00 +0000 (0:00:04.141)       0:00:06.766 *****
2025-10-08 16:05:00.249935 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_suite_scan.yml for instance => (item=cis)
2025-10-08 16:05:00.249963 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_suite_scan.yml for instance => (item=e8)
2025-10-08 16:05:00.249974 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_suite_scan.yml for instance => (item=high)
2025-10-08 16:05:00.250664 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_suite_scan.yml for instance => (item=moderate)
2025-10-08 16:05:00.267822 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_suite_scan.yml for instance => (item=nerc-cip)
2025-10-08 16:05:00.267843 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_suite_scan.yml for instance => (item=pci-dss)
2025-10-08 16:05:00.267851 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_suite_scan.yml for instance => (item=stig)
2025-10-08 16:05:00.267857 | controller |
2025-10-08 16:05:00.267863 | controller | TASK [compliance : Log start of scans] *****************************************
2025-10-08 16:05:00.267868 | controller | Wednesday 08 October 2025  16:05:00 +0000 (0:00:00.043)       0:00:06.810 *****
2025-10-08 16:05:00.267877 | controller | ok: [instance] =>
2025-10-08 16:05:00.267899 | controller |   msg: Starting scans for cis
2025-10-08 16:05:00.267905 | controller |
2025-10-08 16:05:00.267912 | controller | TASK [compliance : Run scans and extract results] ******************************
2025-10-08 16:05:00.268005 | controller | Wednesday 08 October 2025  16:05:00 +0000 (0:00:00.018)       0:00:06.828 *****
2025-10-08 16:05:00.292967 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_operator_scan.yml for instance => (item=ocp4-cis)
2025-10-08 16:05:00.315331 | controller |
2025-10-08 16:05:00.315361 | controller | TASK [compliance : Set output directory] ***************************************
2025-10-08 16:05:00.315369 | controller | Wednesday 08 October 2025  16:05:00 +0000 (0:00:00.024)       0:00:06.853 *****
2025-10-08 16:05:00.315388 | controller | ok: [instance]
2025-10-08 16:05:00.330672 | controller |
2025-10-08 16:05:00.330695 | controller | TASK [compliance : Create scan binding] ****************************************
2025-10-08 16:05:00.330703 | controller | Wednesday 08 October 2025  16:05:00 +0000 (0:00:00.022)       0:00:06.875 *****
2025-10-08 16:05:00.330716 | controller | skipping: [instance]
2025-10-08 16:05:00.344847 | controller |
2025-10-08 16:05:00.344871 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-10-08 16:05:00.344879 | controller | Wednesday 08 October 2025  16:05:00 +0000 (0:00:00.015)       0:00:06.891 *****
2025-10-08 16:05:00.344888 | controller | skipping: [instance]
2025-10-08 16:05:00.344913 | controller |
2025-10-08 16:05:00.345135 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-10-08 16:05:00.525243 | controller | Wednesday 08 October 2025  16:05:00 +0000 (0:00:00.014)       0:00:06.905 *****
2025-10-08 16:05:00.525277 | controller | ok: [instance]
2025-10-08 16:05:00.703856 | controller |
2025-10-08 16:05:00.703885 | controller | TASK [compliance : Create directory for scan results] **************************
2025-10-08 16:05:00.703894 | controller | Wednesday 08 October 2025  16:05:00 +0000 (0:00:00.179)       0:00:07.085 *****
2025-10-08 16:05:00.703904 | controller | changed: [instance]
2025-10-08 16:05:00.703926 | controller |
2025-10-08 16:05:00.703933 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-10-08 16:05:00.703940 | controller | Wednesday 08 October 2025  16:05:00 +0000 (0:00:00.178)       0:00:07.264 *****
2025-10-08 16:05:00.718849 | controller | skipping: [instance]
2025-10-08 16:05:00.982718 | controller |
2025-10-08 16:05:00.982747 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-10-08 16:05:00.982756 | controller | Wednesday 08 October 2025  16:05:00 +0000 (0:00:00.015)       0:00:07.279 *****
2025-10-08 16:05:00.982766 | controller | ok: [instance]
2025-10-08 16:05:00.995516 | controller |
2025-10-08 16:05:00.995542 | controller | TASK [compliance : Process scan result files] **********************************
2025-10-08 16:05:00.995550 | controller | Wednesday 08 October 2025  16:05:00 +0000 (0:00:00.263)       0:00:07.543 *****
2025-10-08 16:05:00.995560 | controller | skipping: [instance]
2025-10-08 16:05:01.011878 | controller |
2025-10-08 16:05:01.011904 | controller | TASK [compliance : Log start of scans] *****************************************
2025-10-08 16:05:01.011914 | controller | Wednesday 08 October 2025  16:05:00 +0000 (0:00:00.012)       0:00:07.556 *****
2025-10-08 16:05:01.011933 | controller | ok: [instance] =>
2025-10-08 16:05:01.011962 | controller |   msg: Starting scans for e8
2025-10-08 16:05:01.011971 | controller |
2025-10-08 16:05:01.012194 | controller | TASK [compliance : Run scans and extract results] ******************************
2025-10-08 16:05:01.041831 | controller | Wednesday 08 October 2025  16:05:01 +0000 (0:00:00.016)       0:00:07.572 *****
2025-10-08 16:05:01.041858 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_operator_scan.yml for instance => (item=ocp4-e8)
2025-10-08 16:05:01.042072 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_operator_scan.yml for instance => (item=rhcos4-e8)
2025-10-08 16:05:01.042090 | controller |
2025-10-08 16:05:01.062270 | controller | TASK [compliance : Set output directory] ***************************************
2025-10-08 16:05:01.062289 | controller | Wednesday 08 October 2025  16:05:01 +0000 (0:00:00.029)       0:00:07.602 *****
2025-10-08 16:05:01.062299 | controller | ok: [instance]
2025-10-08 16:05:01.076853 | controller |
2025-10-08 16:05:01.076880 | controller | TASK [compliance : Create scan binding] ****************************************
2025-10-08 16:05:01.076887 | controller | Wednesday 08 October 2025  16:05:01 +0000 (0:00:00.020)       0:00:07.623 *****
2025-10-08 16:05:01.076895 | controller | skipping: [instance]
2025-10-08 16:05:01.091624 | controller |
2025-10-08 16:05:01.091640 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-10-08 16:05:01.091664 | controller | Wednesday 08 October 2025  16:05:01 +0000 (0:00:00.014)       0:00:07.637 *****
2025-10-08 16:05:01.091674 | controller | skipping: [instance]
2025-10-08 16:05:01.262558 | controller |
2025-10-08 16:05:01.262588 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-10-08 16:05:01.262602 | controller | Wednesday 08 October 2025  16:05:01 +0000 (0:00:00.014)       0:00:07.652 *****
2025-10-08 16:05:01.262612 | controller | ok: [instance]
2025-10-08 16:05:01.446742 | controller |
2025-10-08 16:05:01.446771 | controller | TASK [compliance : Create directory for scan results] **************************
2025-10-08 16:05:01.446795 | controller | Wednesday 08 October 2025  16:05:01 +0000 (0:00:00.170)       0:00:07.823 *****
2025-10-08 16:05:01.446806 | controller | changed: [instance]
2025-10-08 16:05:01.462559 | controller |
2025-10-08 16:05:01.462581 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-10-08 16:05:01.462589 | controller | Wednesday 08 October 2025  16:05:01 +0000 (0:00:00.184)       0:00:08.007 *****
2025-10-08 16:05:01.462603 | controller | skipping: [instance]
2025-10-08 16:05:01.639469 | controller |
2025-10-08 16:05:01.639499 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-10-08 16:05:01.639507 | controller | Wednesday 08 October 2025  16:05:01 +0000 (0:00:00.015)       0:00:08.023 *****
2025-10-08 16:05:01.639516 | controller | ok: [instance]
2025-10-08 16:05:01.652852 | controller |
2025-10-08 16:05:01.652878 | controller | TASK [compliance : Process scan result files] **********************************
2025-10-08 16:05:01.652886 | controller | Wednesday 08 October 2025  16:05:01 +0000 (0:00:00.176)       0:00:08.200 *****
2025-10-08 16:05:01.652896 | controller | skipping: [instance]
2025-10-08 16:05:01.652959 | controller |
2025-10-08 16:05:01.652981 | controller | TASK [compliance : Set output directory] ***************************************
2025-10-08 16:05:01.652993 | controller | Wednesday 08 October 2025  16:05:01 +0000 (0:00:00.013)       0:00:08.213 *****
2025-10-08 16:05:01.674444 | controller | ok: [instance]
2025-10-08 16:05:01.689267 | controller |
2025-10-08 16:05:01.689287 | controller | TASK [compliance : Create scan binding] ****************************************
2025-10-08 16:05:01.689295 | controller | Wednesday 08 October 2025  16:05:01 +0000 (0:00:00.021)       0:00:08.235 *****
2025-10-08 16:05:01.689304 | controller | skipping: [instance]
2025-10-08 16:05:01.703834 | controller |
2025-10-08 16:05:01.703855 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-10-08 16:05:01.703863 | controller | Wednesday 08 October 2025  16:05:01 +0000 (0:00:00.014)       0:00:08.249 *****
2025-10-08 16:05:01.703871 | controller | skipping: [instance]
2025-10-08 16:05:01.703954 | controller |
2025-10-08 16:05:01.703965 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-10-08 16:05:01.704112 | controller | Wednesday 08 October 2025  16:05:01 +0000 (0:00:00.014)       0:00:08.264 *****
2025-10-08 16:05:01.882431 | controller | ok: [instance]
2025-10-08 16:05:02.074587 | controller |
2025-10-08 16:05:02.074617 | controller | TASK [compliance : Create directory for scan results] **************************
2025-10-08 16:05:02.074626 | controller | Wednesday 08 October 2025  16:05:01 +0000 (0:00:00.178)       0:00:08.442 *****
2025-10-08 16:05:02.074636 | controller | changed: [instance]
2025-10-08 16:05:02.091876 | controller |
2025-10-08 16:05:02.091914 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-10-08 16:05:02.091922 | controller | Wednesday 08 October 2025  16:05:02 +0000 (0:00:00.192)       0:00:08.635 *****
2025-10-08 16:05:02.091933 | controller | skipping: [instance]
2025-10-08 16:05:02.267682 | controller |
2025-10-08 16:05:02.267713 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-10-08 16:05:02.267721 | controller | Wednesday 08 October 2025  16:05:02 +0000 (0:00:00.017)       0:00:08.652 *****
2025-10-08 16:05:02.267731 | controller | ok: [instance]
2025-10-08 16:05:02.282853 | controller |
2025-10-08 16:05:02.282898 | controller | TASK [compliance : Process scan result files] **********************************
2025-10-08 16:05:02.282906 | controller | Wednesday 08 October 2025  16:05:02 +0000 (0:00:00.175)       0:00:08.828 *****
2025-10-08 16:05:02.282919 | controller | skipping: [instance]
2025-10-08 16:05:02.301907 | controller |
2025-10-08 16:05:02.301941 | controller | TASK [compliance : Log start of scans] *****************************************
2025-10-08 16:05:02.301949 | controller | Wednesday 08 October 2025  16:05:02 +0000 (0:00:00.015)       0:00:08.843 *****
2025-10-08 16:05:02.301960 | controller | ok: [instance] =>
2025-10-08 16:05:02.340520 | controller |   msg: Starting scans for high
2025-10-08 16:05:02.340565 | controller |
2025-10-08 16:05:02.340574 | controller | TASK [compliance : Run scans and extract results] ******************************
2025-10-08 16:05:02.340581 | controller | Wednesday 08 October 2025  16:05:02 +0000 (0:00:00.018)       0:00:08.862 *****
2025-10-08 16:05:02.340591 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_operator_scan.yml for instance => (item=ocp4-high)
2025-10-08 16:05:02.364278 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_operator_scan.yml for instance => (item=ocp4-high-node)
2025-10-08 16:05:02.364320 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_operator_scan.yml for instance => (item=rhcos4-high)
2025-10-08 16:05:02.364328 | controller |
2025-10-08 16:05:02.364335 | controller | TASK [compliance : Set output directory] ***************************************
2025-10-08 16:05:02.364341 | controller | Wednesday 08 October 2025  16:05:02 +0000 (0:00:00.038)       0:00:08.900 *****
2025-10-08 16:05:02.364352 | controller | ok: [instance]
2025-10-08 16:05:02.364720 | controller |
2025-10-08 16:05:02.365003 | controller | TASK [compliance : Create scan binding] ****************************************
2025-10-08 16:05:02.365168 | controller | Wednesday 08 October 2025  16:05:02 +0000 (0:00:00.024)       0:00:08.925 *****
2025-10-08 16:05:02.383510 | controller | skipping: [instance]
2025-10-08 16:05:02.383715 | controller |
2025-10-08 16:05:02.383901 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-10-08 16:05:02.384056 | controller | Wednesday 08 October 2025  16:05:02 +0000 (0:00:00.019)       0:00:08.944 *****
2025-10-08 16:05:02.401948 | controller | skipping: [instance]
2025-10-08 16:05:02.402146 | controller |
2025-10-08 16:05:02.402307 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-10-08 16:05:02.402474 | controller | Wednesday 08 October 2025  16:05:02 +0000 (0:00:00.018)       0:00:08.962 *****
2025-10-08 16:05:02.589405 | controller | ok: [instance]
2025-10-08 16:05:02.781462 | controller |
2025-10-08 16:05:02.781493 | controller | TASK [compliance : Create directory for scan results] **************************
2025-10-08 16:05:02.781501 | controller | Wednesday 08 October 2025  16:05:02 +0000 (0:00:00.187)       0:00:09.149 *****
2025-10-08 16:05:02.781512 | controller | changed: [instance]
2025-10-08 16:05:02.799862 | controller |
2025-10-08 16:05:02.799912 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-10-08 16:05:02.799921 | controller | Wednesday 08 October 2025  16:05:02 +0000 (0:00:00.192)       0:00:09.341 *****
2025-10-08 16:05:02.799935 | controller | skipping: [instance]
2025-10-08 16:05:02.976610 | controller |
2025-10-08 16:05:02.976641 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-10-08 16:05:02.976670 | controller | Wednesday 08 October 2025  16:05:02 +0000 (0:00:00.018)       0:00:09.360 *****
2025-10-08 16:05:02.976680 | controller | ok: [instance]
2025-10-08 16:05:02.991525 | controller |
2025-10-08 16:05:02.991558 | controller | TASK [compliance : Process scan result files] **********************************
2025-10-08 16:05:02.991566 | controller | Wednesday 08 October 2025  16:05:02 +0000 (0:00:00.176)       0:00:09.537 *****
2025-10-08 16:05:02.991576 | controller | skipping: [instance]
2025-10-08 16:05:03.015500 | controller |
2025-10-08 16:05:03.015538 | controller | TASK [compliance : Set output directory] ***************************************
2025-10-08 16:05:03.015547 | controller | Wednesday 08 October 2025  16:05:02 +0000 (0:00:00.014)       0:00:09.551 *****
2025-10-08 16:05:03.015560 | controller | ok: [instance]
2025-10-08 16:05:03.032401 | controller |
2025-10-08 16:05:03.032433 | controller | TASK [compliance : Create scan binding] ****************************************
2025-10-08 16:05:03.032441 | controller | Wednesday 08 October 2025  16:05:03 +0000 (0:00:00.023)       0:00:09.575 *****
2025-10-08 16:05:03.032451 | controller | skipping: [instance]
2025-10-08 16:05:03.049497 | controller |
2025-10-08 16:05:03.049534 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-10-08 16:05:03.049542 | controller | Wednesday 08 October 2025  16:05:03 +0000 (0:00:00.017)       0:00:09.592 *****
2025-10-08 16:05:03.049552 | controller | skipping: [instance]
2025-10-08 16:05:03.050022 | controller |
2025-10-08 16:05:03.050208 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-10-08 16:05:03.050354 | controller | Wednesday 08 October 2025  16:05:03 +0000 (0:00:00.017)       0:00:09.610 *****
2025-10-08 16:05:03.230599 | controller | ok: [instance]
2025-10-08 16:05:03.421320 | controller |
2025-10-08 16:05:03.421352 | controller | TASK [compliance : Create directory for scan results] **************************
2025-10-08 16:05:03.421360 | controller | Wednesday 08 October 2025  16:05:03 +0000 (0:00:00.180)       0:00:09.791 *****
2025-10-08 16:05:03.421370 | controller | changed: [instance]
2025-10-08 16:05:03.438182 | controller |
2025-10-08 16:05:03.438209 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-10-08 16:05:03.438216 | controller | Wednesday 08 October 2025  16:05:03 +0000 (0:00:00.190)       0:00:09.981 *****
2025-10-08 16:05:03.438226 | controller | skipping: [instance]
2025-10-08 16:05:03.612986 | controller |
2025-10-08 16:05:03.613017 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-10-08 16:05:03.613025 | controller | Wednesday 08 October 2025  16:05:03 +0000 (0:00:00.016)       0:00:09.998 *****
2025-10-08 16:05:03.613035 | controller | ok: [instance]
2025-10-08 16:05:03.627468 | controller |
2025-10-08 16:05:03.627495 | controller | TASK [compliance : Process scan result files] **********************************
2025-10-08 16:05:03.627503 | controller | Wednesday 08 October 2025  16:05:03 +0000 (0:00:00.174)       0:00:10.173 *****
2025-10-08 16:05:03.627513 | controller | skipping: [instance]
2025-10-08 16:05:03.650641 | controller |
2025-10-08 16:05:03.650685 | controller | TASK [compliance : Set output directory] ***************************************
2025-10-08 16:05:03.650693 | controller | Wednesday 08 October 2025  16:05:03 +0000 (0:00:00.014)       0:00:10.188 *****
2025-10-08 16:05:03.650703 | controller | ok: [instance]
2025-10-08 16:05:03.667278 | controller |
2025-10-08 16:05:03.667303 | controller | TASK [compliance : Create scan binding] ****************************************
2025-10-08 16:05:03.667311 | controller | Wednesday 08 October 2025  16:05:03 +0000 (0:00:00.023)       0:00:10.211 *****
2025-10-08 16:05:03.667320 | controller | skipping: [instance]
2025-10-08 16:05:03.684628 | controller |
2025-10-08 16:05:03.684674 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-10-08 16:05:03.684684 | controller | Wednesday 08 October 2025  16:05:03 +0000 (0:00:00.016)       0:00:10.227 *****
2025-10-08 16:05:03.684702 | controller | skipping: [instance]
2025-10-08 16:05:03.863385 | controller |
2025-10-08 16:05:03.863416 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-10-08 16:05:03.863424 | controller | Wednesday 08 October 2025  16:05:03 +0000 (0:00:00.017)       0:00:10.245 *****
2025-10-08 16:05:03.863434 | controller | ok: [instance]
2025-10-08 16:05:04.051876 | controller |
2025-10-08 16:05:04.051907 | controller | TASK [compliance : Create directory for scan results] **************************
2025-10-08 16:05:04.051916 | controller | Wednesday 08 October 2025  16:05:03 +0000 (0:00:00.178)       0:00:10.423 *****
2025-10-08 16:05:04.051926 | controller | changed: [instance]
2025-10-08 16:05:04.068405 | controller |
2025-10-08 16:05:04.068458 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-10-08 16:05:04.068468 | controller | Wednesday 08 October 2025  16:05:04 +0000 (0:00:00.188)       0:00:10.612 *****
2025-10-08 16:05:04.068479 | controller | skipping: [instance]
2025-10-08 16:05:04.242448 | controller |
2025-10-08 16:05:04.242479 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-10-08 16:05:04.242488 | controller | Wednesday 08 October 2025  16:05:04 +0000 (0:00:00.016)       0:00:10.629 *****
2025-10-08 16:05:04.242498 | controller | ok: [instance]
2025-10-08 16:05:04.256868 | controller |
2025-10-08 16:05:04.256903 | controller | TASK [compliance : Process scan result files] **********************************
2025-10-08 16:05:04.256911 | controller | Wednesday 08 October 2025  16:05:04 +0000 (0:00:00.173)       0:00:10.802 *****
2025-10-08 16:05:04.256923 | controller | skipping: [instance]
2025-10-08 16:05:04.256948 | controller |
2025-10-08 16:05:04.256956 | controller | TASK [compliance : Log start of scans] *****************************************
2025-10-08 16:05:04.257049 | controller | Wednesday 08 October 2025  16:05:04 +0000 (0:00:00.014)       0:00:10.817 *****
2025-10-08 16:05:04.276866 | controller | ok: [instance] =>
2025-10-08 16:05:04.276913 | controller |   msg: Starting scans for moderate
2025-10-08 16:05:04.276922 | controller |
2025-10-08 16:05:04.276930 | controller | TASK [compliance : Run scans and extract results] ******************************
2025-10-08 16:05:04.277021 | controller | Wednesday 08 October 2025  16:05:04 +0000 (0:00:00.019)       0:00:10.837 *****
2025-10-08 16:05:04.315514 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_operator_scan.yml for instance => (item=ocp4-moderate)
2025-10-08 16:05:04.338436 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_operator_scan.yml for instance => (item=ocp4-moderate-node)
2025-10-08 16:05:04.338462 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_operator_scan.yml for instance => (item=rhcos4-moderate)
2025-10-08 16:05:04.338469 | controller |
2025-10-08 16:05:04.338476 | controller | TASK [compliance : Set output directory] ***************************************
2025-10-08 16:05:04.338482 | controller | Wednesday 08 October 2025  16:05:04 +0000 (0:00:00.038)       0:00:10.875 *****
2025-10-08 16:05:04.338491 | controller | ok: [instance]
2025-10-08 16:05:04.355136 | controller |
2025-10-08 16:05:04.355167 | controller | TASK [compliance : Create scan binding] ****************************************
2025-10-08 16:05:04.355175 | controller | Wednesday 08 October 2025  16:05:04 +0000 (0:00:00.023)       0:00:10.899 *****
2025-10-08 16:05:04.355187 | controller | skipping: [instance]
2025-10-08 16:05:04.355345 | controller |
2025-10-08 16:05:04.355516 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-10-08 16:05:04.355684 | controller | Wednesday 08 October 2025  16:05:04 +0000 (0:00:00.017)       0:00:10.916 *****
2025-10-08 16:05:04.372427 | controller | skipping: [instance]
2025-10-08 16:05:04.372615 | controller |
2025-10-08 16:05:04.372827 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-10-08 16:05:04.372994 | controller | Wednesday 08 October 2025  16:05:04 +0000 (0:00:00.017)       0:00:10.933 *****
2025-10-08 16:05:04.556864 | controller | ok: [instance]
2025-10-08 16:05:04.557135 | controller |
2025-10-08 16:05:04.557152 | controller | TASK [compliance : Create directory for scan results] **************************
2025-10-08 16:05:04.775860 | controller | Wednesday 08 October 2025  16:05:04 +0000 (0:00:00.184)       0:00:11.117 *****
2025-10-08 16:05:04.775896 | controller | changed: [instance]
2025-10-08 16:05:04.775996 | controller |
2025-10-08 16:05:04.776006 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-10-08 16:05:04.776015 | controller | Wednesday 08 October 2025  16:05:04 +0000 (0:00:00.218)       0:00:11.336 *****
2025-10-08 16:05:04.791695 | controller | skipping: [instance]
2025-10-08 16:05:04.967536 | controller |
2025-10-08 16:05:04.967568 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-10-08 16:05:04.967576 | controller | Wednesday 08 October 2025  16:05:04 +0000 (0:00:00.015)       0:00:11.352 *****
2025-10-08 16:05:04.967586 | controller | ok: [instance]
2025-10-08 16:05:04.981882 | controller |
2025-10-08 16:05:04.981919 | controller | TASK [compliance : Process scan result files] **********************************
2025-10-08 16:05:04.981927 | controller | Wednesday 08 October 2025  16:05:04 +0000 (0:00:00.175)       0:00:11.528 *****
2025-10-08 16:05:04.981938 | controller | skipping: [instance]
2025-10-08 16:05:05.004635 | controller |
2025-10-08 16:05:05.004693 | controller | TASK [compliance : Set output directory] ***************************************
2025-10-08 16:05:05.004706 | controller | Wednesday 08 October 2025  16:05:04 +0000 (0:00:00.014)       0:00:11.542 *****
2025-10-08 16:05:05.004717 | controller | ok: [instance]
2025-10-08 16:05:05.021481 | controller |
2025-10-08 16:05:05.021499 | controller | TASK [compliance : Create scan binding] ****************************************
2025-10-08 16:05:05.021507 | controller | Wednesday 08 October 2025  16:05:05 +0000 (0:00:00.022)       0:00:11.565 *****
2025-10-08 16:05:05.021516 | controller | skipping: [instance]
2025-10-08 16:05:05.039222 | controller |
2025-10-08 16:05:05.039242 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-10-08 16:05:05.039250 | controller | Wednesday 08 October 2025  16:05:05 +0000 (0:00:00.016)       0:00:11.582 *****
2025-10-08 16:05:05.039266 | controller | skipping: [instance]
2025-10-08 16:05:05.224286 | controller |
2025-10-08 16:05:05.224318 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-10-08 16:05:05.224326 | controller | Wednesday 08 October 2025  16:05:05 +0000 (0:00:00.017)       0:00:11.599 *****
2025-10-08 16:05:05.224337 | controller | ok: [instance]
2025-10-08 16:05:05.419153 | controller |
2025-10-08 16:05:05.419184 | controller | TASK [compliance : Create directory for scan results] **************************
2025-10-08 16:05:05.419192 | controller | Wednesday 08 October 2025  16:05:05 +0000 (0:00:00.184)       0:00:11.784 *****
2025-10-08 16:05:05.419202 | controller | changed: [instance]
2025-10-08 16:05:05.435743 | controller |
2025-10-08 16:05:05.435794 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-10-08 16:05:05.435806 | controller | Wednesday 08 October 2025  16:05:05 +0000 (0:00:00.194)       0:00:11.979 *****
2025-10-08 16:05:05.435816 | controller | skipping: [instance]
2025-10-08 16:05:05.611403 | controller |
2025-10-08 16:05:05.611434 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-10-08 16:05:05.611442 | controller | Wednesday 08 October 2025  16:05:05 +0000 (0:00:00.016)       0:00:11.996 *****
2025-10-08 16:05:05.611452 | controller | ok: [instance]
2025-10-08 16:05:05.625845 | controller |
2025-10-08 16:05:05.625872 | controller | TASK [compliance : Process scan result files] **********************************
2025-10-08 16:05:05.625880 | controller | Wednesday 08 October 2025  16:05:05 +0000 (0:00:00.175)       0:00:12.171 *****
2025-10-08 16:05:05.625890 | controller | skipping: [instance]
2025-10-08 16:05:05.649851 | controller |
2025-10-08 16:05:05.649884 | controller | TASK [compliance : Set output directory] ***************************************
2025-10-08 16:05:05.649892 | controller | Wednesday 08 October 2025  16:05:05 +0000 (0:00:00.014)       0:00:12.186 *****
2025-10-08 16:05:05.649903 | controller | ok: [instance]
2025-10-08 16:05:05.649925 | controller |
2025-10-08 16:05:05.650182 | controller | TASK [compliance : Create scan binding] ****************************************
2025-10-08 16:05:05.667247 | controller | Wednesday 08 October 2025  16:05:05 +0000 (0:00:00.024)       0:00:12.210 *****
2025-10-08 16:05:05.667275 | controller | skipping: [instance]
2025-10-08 16:05:05.685762 | controller |
2025-10-08 16:05:05.685818 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-10-08 16:05:05.685828 | controller | Wednesday 08 October 2025  16:05:05 +0000 (0:00:00.017)       0:00:12.227 *****
2025-10-08 16:05:05.685840 | controller | skipping: [instance]
2025-10-08 16:05:05.686033 | controller |
2025-10-08 16:05:05.686211 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-10-08 16:05:05.686377 | controller | Wednesday 08 October 2025  16:05:05 +0000 (0:00:00.018)       0:00:12.246 *****
2025-10-08 16:05:05.872440 | controller | ok: [instance]
2025-10-08 16:05:06.062859 | controller |
2025-10-08 16:05:06.062892 | controller | TASK [compliance : Create directory for scan results] **************************
2025-10-08 16:05:06.062900 | controller | Wednesday 08 October 2025  16:05:05 +0000 (0:00:00.186)       0:00:12.432 *****
2025-10-08 16:05:06.062910 | controller | changed: [instance]
2025-10-08 16:05:06.062927 | controller |
2025-10-08 16:05:06.062934 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-10-08 16:05:06.062941 | controller | Wednesday 08 October 2025  16:05:06 +0000 (0:00:00.190)       0:00:12.623 *****
2025-10-08 16:05:06.079867 | controller | skipping: [instance]
2025-10-08 16:05:06.080247 | controller |
2025-10-08 16:05:06.253868 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-10-08 16:05:06.253901 | controller | Wednesday 08 October 2025  16:05:06 +0000 (0:00:00.017)       0:00:12.640 *****
2025-10-08 16:05:06.253913 | controller | ok: [instance]
2025-10-08 16:05:06.253936 | controller |
2025-10-08 16:05:06.253942 | controller | TASK [compliance : Process scan result files] **********************************
2025-10-08 16:05:06.253950 | controller | Wednesday 08 October 2025  16:05:06 +0000 (0:00:00.173)       0:00:12.814 *****
2025-10-08 16:05:06.268637 | controller | skipping: [instance]
2025-10-08 16:05:06.287917 | controller |
2025-10-08 16:05:06.287942 | controller | TASK [compliance : Log start of scans] *****************************************
2025-10-08 16:05:06.287961 | controller | Wednesday 08 October 2025  16:05:06 +0000 (0:00:00.015)       0:00:12.829 *****
2025-10-08 16:05:06.287971 | controller | ok: [instance] =>
2025-10-08 16:05:06.320887 | controller |   msg: Starting scans for nerc-cip
2025-10-08 16:05:06.320929 | controller |
2025-10-08 16:05:06.320938 | controller | TASK [compliance : Run scans and extract results] ******************************
2025-10-08 16:05:06.320944 | controller | Wednesday 08 October 2025  16:05:06 +0000 (0:00:00.019)       0:00:12.848 *****
2025-10-08 16:05:06.320954 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_operator_scan.yml for instance => (item=ocp4-nerc-cip)
2025-10-08 16:05:06.320978 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_operator_scan.yml for instance => (item=ocp4-nerc-cip-node)
2025-10-08 16:05:06.321304 | controller |
2025-10-08 16:05:06.343547 | controller | TASK [compliance : Set output directory] ***************************************
2025-10-08 16:05:06.343571 | controller | Wednesday 08 October 2025  16:05:06 +0000 (0:00:00.033)       0:00:12.881 *****
2025-10-08 16:05:06.343584 | controller | ok: [instance]
2025-10-08 16:05:06.343773 | controller |
2025-10-08 16:05:06.343990 | controller | TASK [compliance : Create scan binding] ****************************************
2025-10-08 16:05:06.344137 | controller | Wednesday 08 October 2025  16:05:06 +0000 (0:00:00.022)       0:00:12.904 *****
2025-10-08 16:05:06.360367 | controller | skipping: [instance]
2025-10-08 16:05:06.360546 | controller |
2025-10-08 16:05:06.360739 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-10-08 16:05:06.360939 | controller | Wednesday 08 October 2025  16:05:06 +0000 (0:00:00.016)       0:00:12.921 *****
2025-10-08 16:05:06.377636 | controller | skipping: [instance]
2025-10-08 16:05:06.377869 | controller |
2025-10-08 16:05:06.378049 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-10-08 16:05:06.378190 | controller | Wednesday 08 October 2025  16:05:06 +0000 (0:00:00.017)       0:00:12.938 *****
2025-10-08 16:05:06.558849 | controller | ok: [instance]
2025-10-08 16:05:06.751696 | controller |
2025-10-08 16:05:06.751729 | controller | TASK [compliance : Create directory for scan results] **************************
2025-10-08 16:05:06.751737 | controller | Wednesday 08 October 2025  16:05:06 +0000 (0:00:00.180)       0:00:13.119 *****
2025-10-08 16:05:06.751747 | controller | changed: [instance]
2025-10-08 16:05:06.768752 | controller |
2025-10-08 16:05:06.768798 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-10-08 16:05:06.768808 | controller | Wednesday 08 October 2025  16:05:06 +0000 (0:00:00.192)       0:00:13.312 *****
2025-10-08 16:05:06.768818 | controller | skipping: [instance]
2025-10-08 16:05:06.946305 | controller |
2025-10-08 16:05:06.946337 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-10-08 16:05:06.946345 | controller | Wednesday 08 October 2025  16:05:06 +0000 (0:00:00.017)       0:00:13.329 *****
2025-10-08 16:05:06.946355 | controller | ok: [instance]
2025-10-08 16:05:06.960575 | controller |
2025-10-08 16:05:06.960603 | controller | TASK [compliance : Process scan result files] **********************************
2025-10-08 16:05:06.960612 | controller | Wednesday 08 October 2025  16:05:06 +0000 (0:00:00.177)       0:00:13.506 *****
2025-10-08 16:05:06.960623 | controller | skipping: [instance]
2025-10-08 16:05:06.983552 | controller |
2025-10-08 16:05:06.983579 | controller | TASK [compliance : Set output directory] ***************************************
2025-10-08 16:05:06.983588 | controller | Wednesday 08 October 2025  16:05:06 +0000 (0:00:00.014)       0:00:13.521 *****
2025-10-08 16:05:06.983599 | controller | ok: [instance]
2025-10-08 16:05:07.000490 | controller |
2025-10-08 16:05:07.000514 | controller | TASK [compliance : Create scan binding] ****************************************
2025-10-08 16:05:07.000522 | controller | Wednesday 08 October 2025  16:05:06 +0000 (0:00:00.022)       0:00:13.544 *****
2025-10-08 16:05:07.000531 | controller | skipping: [instance]
2025-10-08 16:05:07.016699 | controller |
2025-10-08 16:05:07.016726 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-10-08 16:05:07.016736 | controller | Wednesday 08 October 2025  16:05:06 +0000 (0:00:00.017)       0:00:13.561 *****
2025-10-08 16:05:07.016746 | controller | skipping: [instance]
2025-10-08 16:05:07.196826 | controller |
2025-10-08 16:05:07.196893 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-10-08 16:05:07.196902 | controller | Wednesday 08 October 2025  16:05:07 +0000 (0:00:00.016)       0:00:13.577 *****
2025-10-08 16:05:07.196913 | controller | ok: [instance]
2025-10-08 16:05:07.395571 | controller |
2025-10-08 16:05:07.395611 | controller | TASK [compliance : Create directory for scan results] **************************
2025-10-08 16:05:07.395620 | controller | Wednesday 08 October 2025  16:05:07 +0000 (0:00:00.179)       0:00:13.757 *****
2025-10-08 16:05:07.395631 | controller | changed: [instance]
2025-10-08 16:05:07.412638 | controller |
2025-10-08 16:05:07.412670 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-10-08 16:05:07.412678 | controller | Wednesday 08 October 2025  16:05:07 +0000 (0:00:00.198)       0:00:13.956 *****
2025-10-08 16:05:07.412694 | controller | skipping: [instance]
2025-10-08 16:05:07.587869 | controller |
2025-10-08 16:05:07.587905 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-10-08 16:05:07.587914 | controller | Wednesday 08 October 2025  16:05:07 +0000 (0:00:00.017)       0:00:13.973 *****
2025-10-08 16:05:07.587925 | controller | ok: [instance]
2025-10-08 16:05:07.601655 | controller |
2025-10-08 16:05:07.601690 | controller | TASK [compliance : Process scan result files] **********************************
2025-10-08 16:05:07.601699 | controller | Wednesday 08 October 2025  16:05:07 +0000 (0:00:00.175)       0:00:14.148 *****
2025-10-08 16:05:07.601716 | controller | skipping: [instance]
2025-10-08 16:05:07.601735 | controller |
2025-10-08 16:05:07.601858 | controller | TASK [compliance : Log start of scans] *****************************************
2025-10-08 16:05:07.601962 | controller | Wednesday 08 October 2025  16:05:07 +0000 (0:00:00.014)       0:00:14.162 *****
2025-10-08 16:05:07.620239 | controller | ok: [instance] =>
2025-10-08 16:05:07.620343 | controller |   msg: Starting scans for pci-dss
2025-10-08 16:05:07.620442 | controller |
2025-10-08 16:05:07.620543 | controller | TASK [compliance : Run scans and extract results] ******************************
2025-10-08 16:05:07.620653 | controller | Wednesday 08 October 2025  16:05:07 +0000 (0:00:00.018)       0:00:14.181 *****
2025-10-08 16:05:07.652635 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_operator_scan.yml for instance => (item=ocp4-pci-dss)
2025-10-08 16:05:07.652758 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_operator_scan.yml for instance => (item=ocp4-pci-dss-node)
2025-10-08 16:05:07.652874 | controller |
2025-10-08 16:05:07.652976 | controller | TASK [compliance : Set output directory] ***************************************
2025-10-08 16:05:07.653079 | controller | Wednesday 08 October 2025  16:05:07 +0000 (0:00:00.032)       0:00:14.213 *****
2025-10-08 16:05:07.675612 | controller | ok: [instance]
2025-10-08 16:05:07.691586 | controller |
2025-10-08 16:05:07.691618 | controller | TASK [compliance : Create scan binding] ****************************************
2025-10-08 16:05:07.691626 | controller | Wednesday 08 October 2025  16:05:07 +0000 (0:00:00.022)       0:00:14.236 *****
2025-10-08 16:05:07.691658 | controller | skipping: [instance]
2025-10-08 16:05:07.691683 | controller |
2025-10-08 16:05:07.691816 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-10-08 16:05:07.691929 | controller | Wednesday 08 October 2025  16:05:07 +0000 (0:00:00.016)       0:00:14.252 *****
2025-10-08 16:05:07.708716 | controller | skipping: [instance]
2025-10-08 16:05:07.708827 | controller |
2025-10-08 16:05:07.708945 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-10-08 16:05:07.709053 | controller | Wednesday 08 October 2025  16:05:07 +0000 (0:00:00.017)       0:00:14.269 *****
2025-10-08 16:05:07.889814 | controller | ok: [instance]
2025-10-08 16:05:07.890111 | controller |
2025-10-08 16:05:08.086158 | controller | TASK [compliance : Create directory for scan results] **************************
2025-10-08 16:05:08.086188 | controller | Wednesday 08 October 2025  16:05:07 +0000 (0:00:00.180)       0:00:14.450 *****
2025-10-08 16:05:08.086200 | controller | changed: [instance]
2025-10-08 16:05:08.103563 | controller |
2025-10-08 16:05:08.103584 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-10-08 16:05:08.103598 | controller | Wednesday 08 October 2025  16:05:08 +0000 (0:00:00.196)       0:00:14.646 *****
2025-10-08 16:05:08.103608 | controller | skipping: [instance]
2025-10-08 16:05:08.280744 | controller |
2025-10-08 16:05:08.280776 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-10-08 16:05:08.280801 | controller | Wednesday 08 October 2025  16:05:08 +0000 (0:00:00.017)       0:00:14.664 *****
2025-10-08 16:05:08.280812 | controller | ok: [instance]
2025-10-08 16:05:08.294841 | controller |
2025-10-08 16:05:08.294873 | controller | TASK [compliance : Process scan result files] **********************************
2025-10-08 16:05:08.294881 | controller | Wednesday 08 October 2025  16:05:08 +0000 (0:00:00.176)       0:00:14.841 *****
2025-10-08 16:05:08.294891 | controller | skipping: [instance]
2025-10-08 16:05:08.316371 | controller |
2025-10-08 16:05:08.316398 | controller | TASK [compliance : Set output directory] ***************************************
2025-10-08 16:05:08.316405 | controller | Wednesday 08 October 2025  16:05:08 +0000 (0:00:00.014)       0:00:14.855 *****
2025-10-08 16:05:08.316415 | controller | ok: [instance]
2025-10-08 16:05:08.332618 | controller |
2025-10-08 16:05:08.332653 | controller | TASK [compliance : Create scan binding] ****************************************
2025-10-08 16:05:08.332661 | controller | Wednesday 08 October 2025  16:05:08 +0000 (0:00:00.021)       0:00:14.877 *****
2025-10-08 16:05:08.332670 | controller | skipping: [instance]
2025-10-08 16:05:08.348883 | controller |
2025-10-08 16:05:08.348909 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-10-08 16:05:08.348916 | controller | Wednesday 08 October 2025  16:05:08 +0000 (0:00:00.016)       0:00:14.893 *****
2025-10-08 16:05:08.348926 | controller | skipping: [instance]
2025-10-08 16:05:08.528906 | controller |
2025-10-08 16:05:08.528946 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-10-08 16:05:08.528955 | controller | Wednesday 08 October 2025  16:05:08 +0000 (0:00:00.015)       0:00:14.909 *****
2025-10-08 16:05:08.528966 | controller | ok: [instance]
2025-10-08 16:05:08.716399 | controller |
2025-10-08 16:05:08.716430 | controller | TASK [compliance : Create directory for scan results] **************************
2025-10-08 16:05:08.716439 | controller | Wednesday 08 October 2025  16:05:08 +0000 (0:00:00.179)       0:00:15.089 *****
2025-10-08 16:05:08.716449 | controller | changed: [instance]
2025-10-08 16:05:08.733200 | controller |
2025-10-08 16:05:08.733231 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-10-08 16:05:08.733241 | controller | Wednesday 08 October 2025  16:05:08 +0000 (0:00:00.187)       0:00:15.276 *****
2025-10-08 16:05:08.733253 | controller | skipping: [instance]
2025-10-08 16:05:08.907950 | controller |
2025-10-08 16:05:08.907983 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-10-08 16:05:08.907991 | controller | Wednesday 08 October 2025  16:05:08 +0000 (0:00:00.016)       0:00:15.293 *****
2025-10-08 16:05:08.908001 | controller | ok: [instance]
2025-10-08 16:05:08.922561 | controller |
2025-10-08 16:05:08.922596 | controller | TASK [compliance : Process scan result files] **********************************
2025-10-08 16:05:08.922604 | controller | Wednesday 08 October 2025  16:05:08 +0000 (0:00:00.174)       0:00:15.468 *****
2025-10-08 16:05:08.922614 | controller | skipping: [instance]
2025-10-08 16:05:08.941520 | controller |
2025-10-08 16:05:08.941549 | controller | TASK [compliance : Log start of scans] *****************************************
2025-10-08 16:05:08.941557 | controller | Wednesday 08 October 2025  16:05:08 +0000 (0:00:00.014)       0:00:15.483 *****
2025-10-08 16:05:08.941566 | controller | ok: [instance] =>
2025-10-08 16:05:08.979491 | controller |   msg: Starting scans for stig
2025-10-08 16:05:08.979522 | controller |
2025-10-08 16:05:08.979530 | controller | TASK [compliance : Run scans and extract results] ******************************
2025-10-08 16:05:08.979537 | controller | Wednesday 08 October 2025  16:05:08 +0000 (0:00:00.018)       0:00:15.502 *****
2025-10-08 16:05:08.979547 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_operator_scan.yml for instance => (item=ocp4-stig)
2025-10-08 16:05:09.002070 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_operator_scan.yml for instance => (item=ocp4-stig-node)
2025-10-08 16:05:09.002109 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_operator_scan.yml for instance => (item=rhcos4-stig)
2025-10-08 16:05:09.002117 | controller |
2025-10-08 16:05:09.002124 | controller | TASK [compliance : Set output directory] ***************************************
2025-10-08 16:05:09.002130 | controller | Wednesday 08 October 2025  16:05:08 +0000 (0:00:00.037)       0:00:15.539 *****
2025-10-08 16:05:09.002140 | controller | ok: [instance]
2025-10-08 16:05:09.002287 | controller |
2025-10-08 16:05:09.002435 | controller | TASK [compliance : Create scan binding] ****************************************
2025-10-08 16:05:09.002586 | controller | Wednesday 08 October 2025  16:05:09 +0000 (0:00:00.023)       0:00:15.563 *****
2025-10-08 16:05:09.019417 | controller | skipping: [instance]
2025-10-08 16:05:09.019581 | controller |
2025-10-08 16:05:09.019751 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-10-08 16:05:09.019924 | controller | Wednesday 08 October 2025  16:05:09 +0000 (0:00:00.017)       0:00:15.580 *****
2025-10-08 16:05:09.037524 | controller | skipping: [instance]
2025-10-08 16:05:09.037728 | controller |
2025-10-08 16:05:09.037900 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-10-08 16:05:09.038057 | controller | Wednesday 08 October 2025  16:05:09 +0000 (0:00:00.018)       0:00:15.598 *****
2025-10-08 16:05:09.217250 | controller | ok: [instance]
2025-10-08 16:05:09.403316 | controller |
2025-10-08 16:05:09.403347 | controller | TASK [compliance : Create directory for scan results] **************************
2025-10-08 16:05:09.403355 | controller | Wednesday 08 October 2025  16:05:09 +0000 (0:00:00.179)       0:00:15.777 *****
2025-10-08 16:05:09.403365 | controller | changed: [instance]
2025-10-08 16:05:09.421279 | controller |
2025-10-08 16:05:09.421308 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-10-08 16:05:09.421316 | controller | Wednesday 08 October 2025  16:05:09 +0000 (0:00:00.186)       0:00:15.963 *****
2025-10-08 16:05:09.421326 | controller | skipping: [instance]
2025-10-08 16:05:09.600465 | controller |
2025-10-08 16:05:09.600494 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-10-08 16:05:09.600502 | controller | Wednesday 08 October 2025  16:05:09 +0000 (0:00:00.017)       0:00:15.981 *****
2025-10-08 16:05:09.600512 | controller | ok: [instance]
2025-10-08 16:05:09.616224 | controller |
2025-10-08 16:05:09.616249 | controller | TASK [compliance : Process scan result files] **********************************
2025-10-08 16:05:09.616257 | controller | Wednesday 08 October 2025  16:05:09 +0000 (0:00:00.179)       0:00:16.161 *****
2025-10-08 16:05:09.616267 | controller | skipping: [instance]
2025-10-08 16:05:09.639323 | controller |
2025-10-08 16:05:09.639349 | controller | TASK [compliance : Set output directory] ***************************************
2025-10-08 16:05:09.639362 | controller | Wednesday 08 October 2025  16:05:09 +0000 (0:00:00.015)       0:00:16.176 *****
2025-10-08 16:05:09.639372 | controller | ok: [instance]
2025-10-08 16:05:09.655860 | controller |
2025-10-08 16:05:09.655888 | controller | TASK [compliance : Create scan binding] ****************************************
2025-10-08 16:05:09.655896 | controller | Wednesday 08 October 2025  16:05:09 +0000 (0:00:00.023)       0:00:16.199 *****
2025-10-08 16:05:09.655907 | controller | skipping: [instance]
2025-10-08 16:05:09.655931 | controller |
2025-10-08 16:05:09.656156 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-10-08 16:05:09.691884 | controller | Wednesday 08 October 2025  16:05:09 +0000 (0:00:00.016)       0:00:16.216 *****
2025-10-08 16:05:09.691921 | controller | skipping: [instance]
2025-10-08 16:05:09.872736 | controller |
2025-10-08 16:05:09.872766 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-10-08 16:05:09.872775 | controller | Wednesday 08 October 2025  16:05:09 +0000 (0:00:00.035)       0:00:16.252 *****
2025-10-08 16:05:09.872841 | controller | ok: [instance]
2025-10-08 16:05:10.068708 | controller |
2025-10-08 16:05:10.068739 | controller | TASK [compliance : Create directory for scan results] **************************
2025-10-08 16:05:10.068747 | controller | Wednesday 08 October 2025  16:05:09 +0000 (0:00:00.180)       0:00:16.433 *****
2025-10-08 16:05:10.068757 | controller | changed: [instance]
2025-10-08 16:05:10.084873 | controller |
2025-10-08 16:05:10.084904 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-10-08 16:05:10.084920 | controller | Wednesday 08 October 2025  16:05:10 +0000 (0:00:00.195)       0:00:16.629 *****
2025-10-08 16:05:10.084931 | controller | skipping: [instance]
2025-10-08 16:05:10.084956 | controller |
2025-10-08 16:05:10.084965 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-10-08 16:05:10.085045 | controller | Wednesday 08 October 2025  16:05:10 +0000 (0:00:00.016)       0:00:16.645 *****
2025-10-08 16:05:10.259159 | controller | ok: [instance]
2025-10-08 16:05:10.273576 | controller |
2025-10-08 16:05:10.273602 | controller | TASK [compliance : Process scan result files] **********************************
2025-10-08 16:05:10.273610 | controller | Wednesday 08 October 2025  16:05:10 +0000 (0:00:00.173)       0:00:16.819 *****
2025-10-08 16:05:10.273620 | controller | skipping: [instance]
2025-10-08 16:05:10.296315 | controller |
2025-10-08 16:05:10.296343 | controller | TASK [compliance : Set output directory] ***************************************
2025-10-08 16:05:10.296352 | controller | Wednesday 08 October 2025  16:05:10 +0000 (0:00:00.014)       0:00:16.834 *****
2025-10-08 16:05:10.296364 | controller | ok: [instance]
2025-10-08 16:05:10.312196 | controller |
2025-10-08 16:05:10.312219 | controller | TASK [compliance : Create scan binding] ****************************************
2025-10-08 16:05:10.312228 | controller | Wednesday 08 October 2025  16:05:10 +0000 (0:00:00.022)       0:00:16.856 *****
2025-10-08 16:05:10.312239 | controller | skipping: [instance]
2025-10-08 16:05:10.327540 | controller |
2025-10-08 16:05:10.327559 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-10-08 16:05:10.327566 | controller | Wednesday 08 October 2025  16:05:10 +0000 (0:00:00.015)       0:00:16.872 *****
2025-10-08 16:05:10.327575 | controller | skipping: [instance]
2025-10-08 16:05:10.504580 | controller |
2025-10-08 16:05:10.504611 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-10-08 16:05:10.504619 | controller | Wednesday 08 October 2025  16:05:10 +0000 (0:00:00.015)       0:00:16.888 *****
2025-10-08 16:05:10.504629 | controller | ok: [instance]
2025-10-08 16:05:10.692168 | controller |
2025-10-08 16:05:10.692201 | controller | TASK [compliance : Create directory for scan results] **************************
2025-10-08 16:05:10.692209 | controller | Wednesday 08 October 2025  16:05:10 +0000 (0:00:00.176)       0:00:17.065 *****
2025-10-08 16:05:10.692219 | controller | changed: [instance]
2025-10-08 16:05:10.709412 | controller |
2025-10-08 16:05:10.709441 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-10-08 16:05:10.709449 | controller | Wednesday 08 October 2025  16:05:10 +0000 (0:00:00.187)       0:00:17.252 *****
2025-10-08 16:05:10.709459 | controller | skipping: [instance]
2025-10-08 16:05:10.887411 | controller |
2025-10-08 16:05:10.887445 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-10-08 16:05:10.887453 | controller | Wednesday 08 October 2025  16:05:10 +0000 (0:00:00.017)       0:00:17.270 *****
2025-10-08 16:05:10.887463 | controller | ok: [instance]
2025-10-08 16:05:10.902200 | controller |
2025-10-08 16:05:10.902231 | controller | TASK [compliance : Process scan result files] **********************************
2025-10-08 16:05:10.902241 | controller | Wednesday 08 October 2025  16:05:10 +0000 (0:00:00.177)       0:00:17.447 *****
2025-10-08 16:05:10.902252 | controller | skipping: [instance]
2025-10-08 16:05:10.942347 | controller |
2025-10-08 16:05:10.942390 | controller | TASK [compliance : Delete all resources created by the role] *******************
2025-10-08 16:05:10.942399 | controller | Wednesday 08 October 2025  16:05:10 +0000 (0:00:00.014)       0:00:17.462 *****
2025-10-08 16:05:10.942410 | controller | skipping: [instance]
2025-10-08 16:05:10.942436 | controller |
2025-10-08 16:05:10.942442 | controller | PLAY [Converge test of compute tasks] ******************************************
2025-10-08 16:05:10.942448 | controller |
2025-10-08 16:05:10.942454 | controller | TASK [Gathering Facts] *********************************************************
2025-10-08 16:05:10.942560 | controller | Wednesday 08 October 2025  16:05:10 +0000 (0:00:00.039)       0:00:17.502 *****
2025-10-08 16:05:11.555219 | controller | ok: [instance]
2025-10-08 16:05:11.770508 | controller |
2025-10-08 16:05:11.770540 | controller | TASK [compliance : Ensure compliance folder exists] ****************************
2025-10-08 16:05:11.770548 | controller | Wednesday 08 October 2025  16:05:11 +0000 (0:00:00.613)       0:00:18.115 *****
2025-10-08 16:05:11.770566 | controller | ok: [instance]
2025-10-08 16:05:11.799599 | controller |
2025-10-08 16:05:11.799624 | controller | TASK [compliance : Install the compliance operator and plugin] *****************
2025-10-08 16:05:11.799631 | controller | Wednesday 08 October 2025  16:05:11 +0000 (0:00:00.215)       0:00:18.331 *****
2025-10-08 16:05:11.799661 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/install.yml for instance
2025-10-08 16:05:11.816514 | controller |
2025-10-08 16:05:11.816532 | controller | TASK [compliance : Create the compliance namespace] ****************************
2025-10-08 16:05:11.816539 | controller | Wednesday 08 October 2025  16:05:11 +0000 (0:00:00.029)       0:00:18.360 *****
2025-10-08 16:05:11.816548 | controller | skipping: [instance]
2025-10-08 16:05:11.832679 | controller |
2025-10-08 16:05:11.832698 | controller | TASK [compliance : Ensure OperatorGroup for the compliance-operator is present] ***
2025-10-08 16:05:11.832706 | controller | Wednesday 08 October 2025  16:05:11 +0000 (0:00:00.016)       0:00:18.377 *****
2025-10-08 16:05:11.832714 | controller | skipping: [instance]
2025-10-08 16:05:11.848552 | controller |
2025-10-08 16:05:11.848569 | controller | TASK [compliance : Ensure Subscription for the compliance-operator is present] ***
2025-10-08 16:05:11.848576 | controller | Wednesday 08 October 2025  16:05:11 +0000 (0:00:00.016)       0:00:18.393 *****
2025-10-08 16:05:11.848585 | controller | skipping: [instance]
2025-10-08 16:05:11.864289 | controller |
2025-10-08 16:05:11.864306 | controller | TASK [compliance : Wait until the compliance-operator csv is present] **********
2025-10-08 16:05:11.864313 | controller | Wednesday 08 October 2025  16:05:11 +0000 (0:00:00.015)       0:00:18.409 *****
2025-10-08 16:05:11.864321 | controller | skipping: [instance]
2025-10-08 16:05:11.879693 | controller |
2025-10-08 16:05:11.879709 | controller | TASK [compliance : Get full name of the compliance-operator csv] ***************
2025-10-08 16:05:11.879716 | controller | Wednesday 08 October 2025  16:05:11 +0000 (0:00:00.015)       0:00:18.425 *****
2025-10-08 16:05:11.879725 | controller | skipping: [instance]
2025-10-08 16:05:11.896261 | controller |
2025-10-08 16:05:11.896278 | controller | TASK [compliance : Wait for the compliance-operator csv to Succeed] ************
2025-10-08 16:05:11.896285 | controller | Wednesday 08 October 2025  16:05:11 +0000 (0:00:00.015)       0:00:18.440 *****
2025-10-08 16:05:11.896293 | controller | skipping: [instance]
2025-10-08 16:05:12.581901 | controller |
2025-10-08 16:05:12.581934 | controller | TASK [podman : Ensure podman is installed] *************************************
2025-10-08 16:05:12.581942 | controller | Wednesday 08 October 2025  16:05:11 +0000 (0:00:00.016)       0:00:18.456 *****
2025-10-08 16:05:12.581952 | controller | ok: [instance]
2025-10-08 16:05:12.581975 | controller |
2025-10-08 16:05:12.581983 | controller | TASK [podman : Enable loginctl linger for ansible_user_id] *********************
2025-10-08 16:05:12.582112 | controller | Wednesday 08 October 2025  16:05:12 +0000 (0:00:00.685)       0:00:19.142 *****
2025-10-08 16:05:12.791386 | controller | changed: [instance]
2025-10-08 16:05:12.814866 | controller |
2025-10-08 16:05:12.814896 | controller | TASK [podman : Ensure subordinate UID entry exists for zuul] *******************
2025-10-08 16:05:12.814904 | controller | Wednesday 08 October 2025  16:05:12 +0000 (0:00:00.209)       0:00:19.351 *****
2025-10-08 16:05:12.814915 | controller | skipping: [instance]
2025-10-08 16:05:12.814937 | controller |
2025-10-08 16:05:12.814945 | controller | TASK [podman : Ensure subordinate GID entry exists for zuul] *******************
2025-10-08 16:05:12.815003 | controller | Wednesday 08 October 2025  16:05:12 +0000 (0:00:00.023)       0:00:19.375 *****
2025-10-08 16:05:12.836348 | controller | skipping: [instance]
2025-10-08 16:05:12.860746 | controller |
2025-10-08 16:05:12.860763 | controller | TASK [podman : Run podman system migrate if subuid/subgid files were changed] ***
2025-10-08 16:05:12.860771 | controller | Wednesday 08 October 2025  16:05:12 +0000 (0:00:00.021)       0:00:19.397 *****
2025-10-08 16:05:12.860793 | controller | skipping: [instance]
2025-10-08 16:05:12.878591 | controller |
2025-10-08 16:05:12.878612 | controller | TASK [compliance : Log into registry] ******************************************
2025-10-08 16:05:12.878619 | controller | Wednesday 08 October 2025  16:05:12 +0000 (0:00:00.024)       0:00:19.421 *****
2025-10-08 16:05:12.878628 | controller | skipping: [instance]
2025-10-08 16:05:12.895803 | controller |
2025-10-08 16:05:12.895826 | controller | TASK [compliance : Copy out the compliance plugin from the oc-compliance image] ***
2025-10-08 16:05:12.895835 | controller | Wednesday 08 October 2025  16:05:12 +0000 (0:00:00.017)       0:00:19.439 *****
2025-10-08 16:05:12.895846 | controller | skipping: [instance]
2025-10-08 16:05:13.568203 | controller |
2025-10-08 16:05:13.568232 | controller | TASK [compliance : Install packages to process results] ************************
2025-10-08 16:05:13.568240 | controller | Wednesday 08 October 2025  16:05:12 +0000 (0:00:00.017)       0:00:19.456 *****
2025-10-08 16:05:13.568250 | controller | ok: [instance]
2025-10-08 16:05:13.610889 | controller |
2025-10-08 16:05:13.610922 | controller | TASK [compliance : Run compliance scans and extract results] *******************
2025-10-08 16:05:13.610935 | controller | Wednesday 08 October 2025  16:05:13 +0000 (0:00:00.672)       0:00:20.128 *****
2025-10-08 16:05:13.610948 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_suite_scan.yml for instance => (item=cis)
2025-10-08 16:05:13.610976 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_suite_scan.yml for instance => (item=e8)
2025-10-08 16:05:13.610984 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_suite_scan.yml for instance => (item=high)
2025-10-08 16:05:13.610991 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_suite_scan.yml for instance => (item=moderate)
2025-10-08 16:05:13.610998 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_suite_scan.yml for instance => (item=nerc-cip)
2025-10-08 16:05:13.611005 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_suite_scan.yml for instance => (item=pci-dss)
2025-10-08 16:05:13.611012 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_suite_scan.yml for instance => (item=stig)
2025-10-08 16:05:13.611019 | controller |
2025-10-08 16:05:13.611028 | controller | TASK [compliance : Log start of scans] *****************************************
2025-10-08 16:05:13.627732 | controller | Wednesday 08 October 2025  16:05:13 +0000 (0:00:00.042)       0:00:20.170 *****
2025-10-08 16:05:13.627758 | controller | ok: [instance] =>
2025-10-08 16:05:13.652885 | controller |   msg: Starting scans for cis
2025-10-08 16:05:13.652911 | controller |
2025-10-08 16:05:13.652919 | controller | TASK [compliance : Run scans and extract results] ******************************
2025-10-08 16:05:13.652926 | controller | Wednesday 08 October 2025  16:05:13 +0000 (0:00:00.017)       0:00:20.188 *****
2025-10-08 16:05:13.652935 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_operator_scan.yml for instance => (item=ocp4-cis)
2025-10-08 16:05:13.652951 | controller |
2025-10-08 16:05:13.652957 | controller | TASK [compliance : Set output directory] ***************************************
2025-10-08 16:05:13.652964 | controller | Wednesday 08 October 2025  16:05:13 +0000 (0:00:00.025)       0:00:20.213 *****
2025-10-08 16:05:13.673838 | controller | ok: [instance]
2025-10-08 16:05:13.674047 | controller |
2025-10-08 16:05:13.674065 | controller | TASK [compliance : Create scan binding] ****************************************
2025-10-08 16:05:13.689406 | controller | Wednesday 08 October 2025  16:05:13 +0000 (0:00:00.021)       0:00:20.234 *****
2025-10-08 16:05:13.689430 | controller | skipping: [instance]
2025-10-08 16:05:13.704713 | controller |
2025-10-08 16:05:13.704737 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-10-08 16:05:13.704744 | controller | Wednesday 08 October 2025  16:05:13 +0000 (0:00:00.015)       0:00:20.250 *****
2025-10-08 16:05:13.704753 | controller | skipping: [instance]
2025-10-08 16:05:13.910465 | controller |
2025-10-08 16:05:13.910494 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-10-08 16:05:13.910502 | controller | Wednesday 08 October 2025  16:05:13 +0000 (0:00:00.015)       0:00:20.265 *****
2025-10-08 16:05:13.910512 | controller | changed: [instance]
2025-10-08 16:05:14.099116 | controller |
2025-10-08 16:05:14.099148 | controller | TASK [compliance : Create directory for scan results] **************************
2025-10-08 16:05:14.099165 | controller | Wednesday 08 October 2025  16:05:13 +0000 (0:00:00.205)       0:00:20.471 *****
2025-10-08 16:05:14.099175 | controller | changed: [instance]
2025-10-08 16:05:14.116532 | controller |
2025-10-08 16:05:14.116554 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-10-08 16:05:14.116562 | controller | Wednesday 08 October 2025  16:05:14 +0000 (0:00:00.188)       0:00:20.659 *****
2025-10-08 16:05:14.116571 | controller | skipping: [instance]
2025-10-08 16:05:14.296054 | controller |
2025-10-08 16:05:14.296085 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-10-08 16:05:14.296093 | controller | Wednesday 08 October 2025  16:05:14 +0000 (0:00:00.017)       0:00:20.677 *****
2025-10-08 16:05:14.296103 | controller | ok: [instance]
2025-10-08 16:05:14.309888 | controller |
2025-10-08 16:05:14.309925 | controller | TASK [compliance : Process scan result files] **********************************
2025-10-08 16:05:14.309935 | controller | Wednesday 08 October 2025  16:05:14 +0000 (0:00:00.179)       0:00:20.856 *****
2025-10-08 16:05:14.309948 | controller | skipping: [instance]
2025-10-08 16:05:14.309978 | controller |
2025-10-08 16:05:14.309987 | controller | TASK [compliance : Log start of scans] *****************************************
2025-10-08 16:05:14.310064 | controller | Wednesday 08 October 2025  16:05:14 +0000 (0:00:00.014)       0:00:20.870 *****
2025-10-08 16:05:14.327355 | controller | ok: [instance] =>
2025-10-08 16:05:14.357895 | controller |   msg: Starting scans for e8
2025-10-08 16:05:14.357930 | controller |
2025-10-08 16:05:14.357938 | controller | TASK [compliance : Run scans and extract results] ******************************
2025-10-08 16:05:14.357952 | controller | Wednesday 08 October 2025  16:05:14 +0000 (0:00:00.017)       0:00:20.888 *****
2025-10-08 16:05:14.357963 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_operator_scan.yml for instance => (item=ocp4-e8)
2025-10-08 16:05:14.357994 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_operator_scan.yml for instance => (item=rhcos4-e8)
2025-10-08 16:05:14.358275 | controller |
2025-10-08 16:05:14.379879 | controller | TASK [compliance : Set output directory] ***************************************
2025-10-08 16:05:14.379906 | controller | Wednesday 08 October 2025  16:05:14 +0000 (0:00:00.030)       0:00:20.918 *****
2025-10-08 16:05:14.379916 | controller | ok: [instance]
2025-10-08 16:05:14.379944 | controller |
2025-10-08 16:05:14.380133 | controller | TASK [compliance : Create scan binding] ****************************************
2025-10-08 16:05:14.395952 | controller | Wednesday 08 October 2025  16:05:14 +0000 (0:00:00.022)       0:00:20.940 *****
2025-10-08 16:05:14.395973 | controller | skipping: [instance]
2025-10-08 16:05:14.412555 | controller |
2025-10-08 16:05:14.412575 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-10-08 16:05:14.412582 | controller | Wednesday 08 October 2025  16:05:14 +0000 (0:00:00.015)       0:00:20.956 *****
2025-10-08 16:05:14.412591 | controller | skipping: [instance]
2025-10-08 16:05:14.595150 | controller |
2025-10-08 16:05:14.595181 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-10-08 16:05:14.595189 | controller | Wednesday 08 October 2025  16:05:14 +0000 (0:00:00.016)       0:00:20.973 *****
2025-10-08 16:05:14.595200 | controller | changed: [instance]
2025-10-08 16:05:14.784386 | controller |
2025-10-08 16:05:14.784417 | controller | TASK [compliance : Create directory for scan results] **************************
2025-10-08 16:05:14.784425 | controller | Wednesday 08 October 2025  16:05:14 +0000 (0:00:00.182)       0:00:21.155 *****
2025-10-08 16:05:14.784436 | controller | changed: [instance]
2025-10-08 16:05:14.801347 | controller |
2025-10-08 16:05:14.801370 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-10-08 16:05:14.801377 | controller | Wednesday 08 October 2025  16:05:14 +0000 (0:00:00.189)       0:00:21.344 *****
2025-10-08 16:05:14.801387 | controller | skipping: [instance]
2025-10-08 16:05:14.977279 | controller |
2025-10-08 16:05:14.977312 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-10-08 16:05:14.977320 | controller | Wednesday 08 October 2025  16:05:14 +0000 (0:00:00.017)       0:00:21.362 *****
2025-10-08 16:05:14.977335 | controller | ok: [instance]
2025-10-08 16:05:14.992306 | controller |
2025-10-08 16:05:14.992332 | controller | TASK [compliance : Process scan result files] **********************************
2025-10-08 16:05:14.992339 | controller | Wednesday 08 October 2025  16:05:14 +0000 (0:00:00.175)       0:00:21.537 *****
2025-10-08 16:05:14.992349 | controller | skipping: [instance]
2025-10-08 16:05:15.016960 | controller |
2025-10-08 16:05:15.017041 | controller | TASK [compliance : Set output directory] ***************************************
2025-10-08 16:05:15.017050 | controller | Wednesday 08 October 2025  16:05:14 +0000 (0:00:00.015)       0:00:21.552 *****
2025-10-08 16:05:15.017066 | controller | ok: [instance]
2025-10-08 16:05:15.017104 | controller |
2025-10-08 16:05:15.017110 | controller | TASK [compliance : Create scan binding] ****************************************
2025-10-08 16:05:15.017118 | controller | Wednesday 08 October 2025  16:05:15 +0000 (0:00:00.024)       0:00:21.577 *****
2025-10-08 16:05:15.034709 | controller | skipping: [instance]
2025-10-08 16:05:15.052882 | controller |
2025-10-08 16:05:15.052915 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-10-08 16:05:15.052923 | controller | Wednesday 08 October 2025  16:05:15 +0000 (0:00:00.017)       0:00:21.595 *****
2025-10-08 16:05:15.052934 | controller | skipping: [instance]
2025-10-08 16:05:15.235165 | controller |
2025-10-08 16:05:15.235196 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-10-08 16:05:15.235204 | controller | Wednesday 08 October 2025  16:05:15 +0000 (0:00:00.018)       0:00:21.613 *****
2025-10-08 16:05:15.235214 | controller | changed: [instance]
2025-10-08 16:05:15.422873 | controller |
2025-10-08 16:05:15.422903 | controller | TASK [compliance : Create directory for scan results] **************************
2025-10-08 16:05:15.422911 | controller | Wednesday 08 October 2025  16:05:15 +0000 (0:00:00.182)       0:00:21.795 *****
2025-10-08 16:05:15.422921 | controller | changed: [instance]
2025-10-08 16:05:15.422943 | controller |
2025-10-08 16:05:15.422951 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-10-08 16:05:15.423083 | controller | Wednesday 08 October 2025  16:05:15 +0000 (0:00:00.187)       0:00:21.983 *****
2025-10-08 16:05:15.442850 | controller | skipping: [instance]
2025-10-08 16:05:15.442908 | controller |
2025-10-08 16:05:15.442920 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-10-08 16:05:15.443033 | controller | Wednesday 08 October 2025  16:05:15 +0000 (0:00:00.019)       0:00:22.002 *****
2025-10-08 16:05:15.619500 | controller | ok: [instance]
2025-10-08 16:05:15.634308 | controller |
2025-10-08 16:05:15.634333 | controller | TASK [compliance : Process scan result files] **********************************
2025-10-08 16:05:15.634346 | controller | Wednesday 08 October 2025  16:05:15 +0000 (0:00:00.177)       0:00:22.180 *****
2025-10-08 16:05:15.634356 | controller | skipping: [instance]
2025-10-08 16:05:15.653753 | controller |
2025-10-08 16:05:15.653796 | controller | TASK [compliance : Log start of scans] *****************************************
2025-10-08 16:05:15.653808 | controller | Wednesday 08 October 2025  16:05:15 +0000 (0:00:00.015)       0:00:22.195 *****
2025-10-08 16:05:15.653818 | controller | ok: [instance] =>
2025-10-08 16:05:15.693899 | controller |   msg: Starting scans for high
2025-10-08 16:05:15.693935 | controller |
2025-10-08 16:05:15.693943 | controller | TASK [compliance : Run scans and extract results] ******************************
2025-10-08 16:05:15.693949 | controller | Wednesday 08 October 2025  16:05:15 +0000 (0:00:00.019)       0:00:22.214 *****
2025-10-08 16:05:15.693960 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_operator_scan.yml for instance => (item=ocp4-high)
2025-10-08 16:05:15.718283 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_operator_scan.yml for instance => (item=ocp4-high-node)
2025-10-08 16:05:15.718304 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_operator_scan.yml for instance => (item=rhcos4-high)
2025-10-08 16:05:15.718312 | controller |
2025-10-08 16:05:15.718318 | controller | TASK [compliance : Set output directory] ***************************************
2025-10-08 16:05:15.718324 | controller | Wednesday 08 October 2025  16:05:15 +0000 (0:00:00.039)       0:00:22.254 *****
2025-10-08 16:05:15.718338 | controller | ok: [instance]
2025-10-08 16:05:15.738855 | controller |
2025-10-08 16:05:15.738878 | controller | TASK [compliance : Create scan binding] ****************************************
2025-10-08 16:05:15.738888 | controller | Wednesday 08 October 2025  16:05:15 +0000 (0:00:00.024)       0:00:22.278 *****
2025-10-08 16:05:15.738899 | controller | skipping: [instance]
2025-10-08 16:05:15.782658 | controller |
2025-10-08 16:05:15.782689 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-10-08 16:05:15.782699 | controller | Wednesday 08 October 2025  16:05:15 +0000 (0:00:00.020)       0:00:22.299 *****
2025-10-08 16:05:15.782710 | controller | skipping: [instance]
2025-10-08 16:05:15.961271 | controller |
2025-10-08 16:05:15.961301 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-10-08 16:05:15.961309 | controller | Wednesday 08 October 2025  16:05:15 +0000 (0:00:00.043)       0:00:22.343 *****
2025-10-08 16:05:15.961319 | controller | changed: [instance]
2025-10-08 16:05:16.145340 | controller |
2025-10-08 16:05:16.145371 | controller | TASK [compliance : Create directory for scan results] **************************
2025-10-08 16:05:16.145379 | controller | Wednesday 08 October 2025  16:05:15 +0000 (0:00:00.178)       0:00:22.521 *****
2025-10-08 16:05:16.145388 | controller | changed: [instance]
2025-10-08 16:05:16.161907 | controller |
2025-10-08 16:05:16.161932 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-10-08 16:05:16.161940 | controller | Wednesday 08 October 2025  16:05:16 +0000 (0:00:00.184)       0:00:22.705 *****
2025-10-08 16:05:16.161949 | controller | skipping: [instance]
2025-10-08 16:05:16.332732 | controller |
2025-10-08 16:05:16.332762 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-10-08 16:05:16.332770 | controller | Wednesday 08 October 2025  16:05:16 +0000 (0:00:00.016)       0:00:22.722 *****
2025-10-08 16:05:16.332844 | controller | ok: [instance]
2025-10-08 16:05:16.346838 | controller |
2025-10-08 16:05:16.346857 | controller | TASK [compliance : Process scan result files] **********************************
2025-10-08 16:05:16.346865 | controller | Wednesday 08 October 2025  16:05:16 +0000 (0:00:00.170)       0:00:22.893 *****
2025-10-08 16:05:16.346874 | controller | skipping: [instance]
2025-10-08 16:05:16.347208 | controller |
2025-10-08 16:05:16.369846 | controller | TASK [compliance : Set output directory] ***************************************
2025-10-08 16:05:16.369869 | controller | Wednesday 08 October 2025  16:05:16 +0000 (0:00:00.014)       0:00:22.907 *****
2025-10-08 16:05:16.369879 | controller | ok: [instance]
2025-10-08 16:05:16.370084 | controller |
2025-10-08 16:05:16.370098 | controller | TASK [compliance : Create scan binding] ****************************************
2025-10-08 16:05:16.387238 | controller | Wednesday 08 October 2025  16:05:16 +0000 (0:00:00.022)       0:00:22.930 *****
2025-10-08 16:05:16.387260 | controller | skipping: [instance]
2025-10-08 16:05:16.404959 | controller |
2025-10-08 16:05:16.404985 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-10-08 16:05:16.404994 | controller | Wednesday 08 October 2025  16:05:16 +0000 (0:00:00.017)       0:00:22.947 *****
2025-10-08 16:05:16.405005 | controller | skipping: [instance]
2025-10-08 16:05:16.581509 | controller |
2025-10-08 16:05:16.581539 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-10-08 16:05:16.581547 | controller | Wednesday 08 October 2025  16:05:16 +0000 (0:00:00.017)       0:00:22.965 *****
2025-10-08 16:05:16.581557 | controller | changed: [instance]
2025-10-08 16:05:16.770564 | controller |
2025-10-08 16:05:16.770596 | controller | TASK [compliance : Create directory for scan results] **************************
2025-10-08 16:05:16.770610 | controller | Wednesday 08 October 2025  16:05:16 +0000 (0:00:00.176)       0:00:23.142 *****
2025-10-08 16:05:16.770621 | controller | changed: [instance]
2025-10-08 16:05:16.787286 | controller |
2025-10-08 16:05:16.787314 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-10-08 16:05:16.787323 | controller | Wednesday 08 October 2025  16:05:16 +0000 (0:00:00.189)       0:00:23.331 *****
2025-10-08 16:05:16.787334 | controller | skipping: [instance]
2025-10-08 16:05:16.961181 | controller |
2025-10-08 16:05:16.961212 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-10-08 16:05:16.961225 | controller | Wednesday 08 October 2025  16:05:16 +0000 (0:00:00.016)       0:00:23.347 *****
2025-10-08 16:05:16.961235 | controller | ok: [instance]
2025-10-08 16:05:16.975575 | controller |
2025-10-08 16:05:16.975601 | controller | TASK [compliance : Process scan result files] **********************************
2025-10-08 16:05:16.975609 | controller | Wednesday 08 October 2025  16:05:16 +0000 (0:00:00.173)       0:00:23.521 *****
2025-10-08 16:05:16.975619 | controller | skipping: [instance]
2025-10-08 16:05:16.997906 | controller |
2025-10-08 16:05:16.997938 | controller | TASK [compliance : Set output directory] ***************************************
2025-10-08 16:05:16.997946 | controller | Wednesday 08 October 2025  16:05:16 +0000 (0:00:00.014)       0:00:23.536 *****
2025-10-08 16:05:16.997957 | controller | ok: [instance]
2025-10-08 16:05:16.997982 | controller |
2025-10-08 16:05:16.998172 | controller | TASK [compliance : Create scan binding] ****************************************
2025-10-08 16:05:17.013758 | controller | Wednesday 08 October 2025  16:05:16 +0000 (0:00:00.022)       0:00:23.558 *****
2025-10-08 16:05:17.013806 | controller | skipping: [instance]
2025-10-08 16:05:17.030757 | controller |
2025-10-08 16:05:17.030802 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-10-08 16:05:17.030814 | controller | Wednesday 08 October 2025  16:05:17 +0000 (0:00:00.015)       0:00:23.574 *****
2025-10-08 16:05:17.030824 | controller | skipping: [instance]
2025-10-08 16:05:17.208032 | controller |
2025-10-08 16:05:17.208061 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-10-08 16:05:17.208075 | controller | Wednesday 08 October 2025  16:05:17 +0000 (0:00:00.016)       0:00:23.591 *****
2025-10-08 16:05:17.208085 | controller | changed: [instance]
2025-10-08 16:05:17.394872 | controller |
2025-10-08 16:05:17.394908 | controller | TASK [compliance : Create directory for scan results] **************************
2025-10-08 16:05:17.394917 | controller | Wednesday 08 October 2025  16:05:17 +0000 (0:00:00.177)       0:00:23.768 *****
2025-10-08 16:05:17.394930 | controller | changed: [instance]
2025-10-08 16:05:17.412554 | controller |
2025-10-08 16:05:17.412585 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-10-08 16:05:17.412593 | controller | Wednesday 08 October 2025  16:05:17 +0000 (0:00:00.186)       0:00:23.955 *****
2025-10-08 16:05:17.412603 | controller | skipping: [instance]
2025-10-08 16:05:17.588079 | controller |
2025-10-08 16:05:17.588111 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-10-08 16:05:17.588120 | controller | Wednesday 08 October 2025  16:05:17 +0000 (0:00:00.017)       0:00:23.973 *****
2025-10-08 16:05:17.588130 | controller | ok: [instance]
2025-10-08 16:05:17.602576 | controller |
2025-10-08 16:05:17.602603 | controller | TASK [compliance : Process scan result files] **********************************
2025-10-08 16:05:17.602611 | controller | Wednesday 08 October 2025  16:05:17 +0000 (0:00:00.175)       0:00:24.148 *****
2025-10-08 16:05:17.602621 | controller | skipping: [instance]
2025-10-08 16:05:17.620803 | controller |
2025-10-08 16:05:17.620827 | controller | TASK [compliance : Log start of scans] *****************************************
2025-10-08 16:05:17.620835 | controller | Wednesday 08 October 2025  16:05:17 +0000 (0:00:00.014)       0:00:24.163 *****
2025-10-08 16:05:17.620845 | controller | ok: [instance] =>
2025-10-08 16:05:17.659495 | controller |   msg: Starting scans for moderate
2025-10-08 16:05:17.659525 | controller |
2025-10-08 16:05:17.659533 | controller | TASK [compliance : Run scans and extract results] ******************************
2025-10-08 16:05:17.659539 | controller | Wednesday 08 October 2025  16:05:17 +0000 (0:00:00.018)       0:00:24.181 *****
2025-10-08 16:05:17.659549 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_operator_scan.yml for instance => (item=ocp4-moderate)
2025-10-08 16:05:17.683606 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_operator_scan.yml for instance => (item=ocp4-moderate-node)
2025-10-08 16:05:17.683646 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_operator_scan.yml for instance => (item=rhcos4-moderate)
2025-10-08 16:05:17.683655 | controller |
2025-10-08 16:05:17.683661 | controller | TASK [compliance : Set output directory] ***************************************
2025-10-08 16:05:17.683669 | controller | Wednesday 08 October 2025  16:05:17 +0000 (0:00:00.038)       0:00:24.219 *****
2025-10-08 16:05:17.683679 | controller | ok: [instance]
2025-10-08 16:05:17.701602 | controller |
2025-10-08 16:05:17.701625 | controller | TASK [compliance : Create scan binding] ****************************************
2025-10-08 16:05:17.701648 | controller | Wednesday 08 October 2025  16:05:17 +0000 (0:00:00.024)       0:00:24.244 *****
2025-10-08 16:05:17.701662 | controller | skipping: [instance]
2025-10-08 16:05:17.718905 | controller |
2025-10-08 16:05:17.718942 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-10-08 16:05:17.718953 | controller | Wednesday 08 October 2025  16:05:17 +0000 (0:00:00.017)       0:00:24.262 *****
2025-10-08 16:05:17.718965 | controller | skipping: [instance]
2025-10-08 16:05:17.718986 | controller |
2025-10-08 16:05:17.718996 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-10-08 16:05:17.719037 | controller | Wednesday 08 October 2025  16:05:17 +0000 (0:00:00.017)       0:00:24.279 *****
2025-10-08 16:05:17.901755 | controller | changed: [instance]
2025-10-08 16:05:18.090353 | controller |
2025-10-08 16:05:18.090384 | controller | TASK [compliance : Create directory for scan results] **************************
2025-10-08 16:05:18.090392 | controller | Wednesday 08 October 2025  16:05:17 +0000 (0:00:00.182)       0:00:24.462 *****
2025-10-08 16:05:18.090402 | controller | changed: [instance]
2025-10-08 16:05:18.107100 | controller |
2025-10-08 16:05:18.107127 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-10-08 16:05:18.107134 | controller | Wednesday 08 October 2025  16:05:18 +0000 (0:00:00.188)       0:00:24.650 *****
2025-10-08 16:05:18.107144 | controller | skipping: [instance]
2025-10-08 16:05:18.283510 | controller |
2025-10-08 16:05:18.283539 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-10-08 16:05:18.283548 | controller | Wednesday 08 October 2025  16:05:18 +0000 (0:00:00.016)       0:00:24.667 *****
2025-10-08 16:05:18.283558 | controller | ok: [instance]
2025-10-08 16:05:18.298531 | controller |
2025-10-08 16:05:18.298554 | controller | TASK [compliance : Process scan result files] **********************************
2025-10-08 16:05:18.298562 | controller | Wednesday 08 October 2025  16:05:18 +0000 (0:00:00.176)       0:00:24.844 *****
2025-10-08 16:05:18.298572 | controller | skipping: [instance]
2025-10-08 16:05:18.322290 | controller |
2025-10-08 16:05:18.322319 | controller | TASK [compliance : Set output directory] ***************************************
2025-10-08 16:05:18.322327 | controller | Wednesday 08 October 2025  16:05:18 +0000 (0:00:00.015)       0:00:24.859 *****
2025-10-08 16:05:18.322337 | controller | ok: [instance]
2025-10-08 16:05:18.339821 | controller |
2025-10-08 16:05:18.339848 | controller | TASK [compliance : Create scan binding] ****************************************
2025-10-08 16:05:18.339856 | controller | Wednesday 08 October 2025  16:05:18 +0000 (0:00:00.023)       0:00:24.882 *****
2025-10-08 16:05:18.339866 | controller | skipping: [instance]
2025-10-08 16:05:18.356520 | controller |
2025-10-08 16:05:18.356558 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-10-08 16:05:18.356568 | controller | Wednesday 08 October 2025  16:05:18 +0000 (0:00:00.017)       0:00:24.900 *****
2025-10-08 16:05:18.356580 | controller | skipping: [instance]
2025-10-08 16:05:18.537551 | controller |
2025-10-08 16:05:18.537580 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-10-08 16:05:18.537588 | controller | Wednesday 08 October 2025  16:05:18 +0000 (0:00:00.016)       0:00:24.916 *****
2025-10-08 16:05:18.537598 | controller | changed: [instance]
2025-10-08 16:05:18.727257 | controller |
2025-10-08 16:05:18.727286 | controller | TASK [compliance : Create directory for scan results] **************************
2025-10-08 16:05:18.727294 | controller | Wednesday 08 October 2025  16:05:18 +0000 (0:00:00.181)       0:00:25.098 *****
2025-10-08 16:05:18.727303 | controller | changed: [instance]
2025-10-08 16:05:18.746013 | controller |
2025-10-08 16:05:18.746043 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-10-08 16:05:18.746051 | controller | Wednesday 08 October 2025  16:05:18 +0000 (0:00:00.189)       0:00:25.287 *****
2025-10-08 16:05:18.746061 | controller | skipping: [instance]
2025-10-08 16:05:18.920865 | controller |
2025-10-08 16:05:18.920896 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-10-08 16:05:18.920904 | controller | Wednesday 08 October 2025  16:05:18 +0000 (0:00:00.018)       0:00:25.306 *****
2025-10-08 16:05:18.920914 | controller | ok: [instance]
2025-10-08 16:05:18.934823 | controller |
2025-10-08 16:05:18.934848 | controller | TASK [compliance : Process scan result files] **********************************
2025-10-08 16:05:18.934855 | controller | Wednesday 08 October 2025  16:05:18 +0000 (0:00:00.174)       0:00:25.481 *****
2025-10-08 16:05:18.934865 | controller | skipping: [instance]
2025-10-08 16:05:18.956900 | controller |
2025-10-08 16:05:18.956933 | controller | TASK [compliance : Set output directory] ***************************************
2025-10-08 16:05:18.956942 | controller | Wednesday 08 October 2025  16:05:18 +0000 (0:00:00.014)       0:00:25.495 *****
2025-10-08 16:05:18.956954 | controller | ok: [instance]
2025-10-08 16:05:18.956981 | controller |
2025-10-08 16:05:18.956991 | controller | TASK [compliance : Create scan binding] ****************************************
2025-10-08 16:05:18.957110 | controller | Wednesday 08 October 2025  16:05:18 +0000 (0:00:00.022)       0:00:25.517 *****
2025-10-08 16:05:18.972488 | controller | skipping: [instance]
2025-10-08 16:05:18.972566 | controller |
2025-10-08 16:05:18.972701 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-10-08 16:05:18.972813 | controller | Wednesday 08 October 2025  16:05:18 +0000 (0:00:00.015)       0:00:25.533 *****
2025-10-08 16:05:18.988394 | controller | skipping: [instance]
2025-10-08 16:05:18.988468 | controller |
2025-10-08 16:05:18.988562 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-10-08 16:05:18.988681 | controller | Wednesday 08 October 2025  16:05:18 +0000 (0:00:00.015)       0:00:25.549 *****
2025-10-08 16:05:19.174592 | controller | changed: [instance]
2025-10-08 16:05:19.174694 | controller |
2025-10-08 16:05:19.174822 | controller | TASK [compliance : Create directory for scan results] **************************
2025-10-08 16:05:19.174934 | controller | Wednesday 08 October 2025  16:05:19 +0000 (0:00:00.186)       0:00:25.735 *****
2025-10-08 16:05:19.356931 | controller | changed: [instance]
2025-10-08 16:05:19.357004 | controller |
2025-10-08 16:05:19.357114 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-10-08 16:05:19.357209 | controller | Wednesday 08 October 2025  16:05:19 +0000 (0:00:00.182)       0:00:25.917 *****
2025-10-08 16:05:19.373304 | controller | skipping: [instance]
2025-10-08 16:05:19.373381 | controller |
2025-10-08 16:05:19.373499 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-10-08 16:05:19.373602 | controller | Wednesday 08 October 2025  16:05:19 +0000 (0:00:00.016)       0:00:25.934 *****
2025-10-08 16:05:19.541687 | controller | ok: [instance]
2025-10-08 16:05:19.541744 | controller |
2025-10-08 16:05:19.541955 | controller | TASK [compliance : Process scan result files] **********************************
2025-10-08 16:05:19.542059 | controller | Wednesday 08 October 2025  16:05:19 +0000 (0:00:00.168)       0:00:26.102 *****
2025-10-08 16:05:19.555984 | controller | skipping: [instance]
2025-10-08 16:05:19.556071 | controller |
2025-10-08 16:05:19.556183 | controller | TASK [compliance : Log start of scans] *****************************************
2025-10-08 16:05:19.556280 | controller | Wednesday 08 October 2025  16:05:19 +0000 (0:00:00.014)       0:00:26.117 *****
2025-10-08 16:05:19.574420 | controller | ok: [instance] =>
2025-10-08 16:05:19.574508 | controller |   msg: Starting scans for nerc-cip
2025-10-08 16:05:19.574603 | controller |
2025-10-08 16:05:19.574728 | controller | TASK [compliance : Run scans and extract results] ******************************
2025-10-08 16:05:19.574861 | controller | Wednesday 08 October 2025  16:05:19 +0000 (0:00:00.018)       0:00:26.135 *****
2025-10-08 16:05:19.605687 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_operator_scan.yml for instance => (item=ocp4-nerc-cip)
2025-10-08 16:05:19.605817 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_operator_scan.yml for instance => (item=ocp4-nerc-cip-node)
2025-10-08 16:05:19.605954 | controller |
2025-10-08 16:05:19.606080 | controller | TASK [compliance : Set output directory] ***************************************
2025-10-08 16:05:19.606190 | controller | Wednesday 08 October 2025  16:05:19 +0000 (0:00:00.031)       0:00:26.166 *****
2025-10-08 16:05:19.627550 | controller | ok: [instance]
2025-10-08 16:05:19.627593 | controller |
2025-10-08 16:05:19.627767 | controller | TASK [compliance : Create scan binding] ****************************************
2025-10-08 16:05:19.627887 | controller | Wednesday 08 October 2025  16:05:19 +0000 (0:00:00.021)       0:00:26.188 *****
2025-10-08 16:05:19.643673 | controller | skipping: [instance]
2025-10-08 16:05:19.643717 | controller |
2025-10-08 16:05:19.643860 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-10-08 16:05:19.643960 | controller | Wednesday 08 October 2025  16:05:19 +0000 (0:00:00.016)       0:00:26.204 *****
2025-10-08 16:05:19.659909 | controller | skipping: [instance]
2025-10-08 16:05:19.659974 | controller |
2025-10-08 16:05:19.660094 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-10-08 16:05:19.660203 | controller | Wednesday 08 October 2025  16:05:19 +0000 (0:00:00.016)       0:00:26.220 *****
2025-10-08 16:05:19.831603 | controller | changed: [instance]
2025-10-08 16:05:19.831695 | controller |
2025-10-08 16:05:19.831823 | controller | TASK [compliance : Create directory for scan results] **************************
2025-10-08 16:05:19.831930 | controller | Wednesday 08 October 2025  16:05:19 +0000 (0:00:00.171)       0:00:26.392 *****
2025-10-08 16:05:20.018283 | controller | changed: [instance]
2025-10-08 16:05:20.018354 | controller |
2025-10-08 16:05:20.018457 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-10-08 16:05:20.018560 | controller | Wednesday 08 October 2025  16:05:20 +0000 (0:00:00.186)       0:00:26.579 *****
2025-10-08 16:05:20.034760 | controller | skipping: [instance]
2025-10-08 16:05:20.034859 | controller |
2025-10-08 16:05:20.034964 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-10-08 16:05:20.035067 | controller | Wednesday 08 October 2025  16:05:20 +0000 (0:00:00.016)       0:00:26.595 *****
2025-10-08 16:05:20.208080 | controller | ok: [instance]
2025-10-08 16:05:20.208166 | controller |
2025-10-08 16:05:20.208400 | controller | TASK [compliance : Process scan result files] **********************************
2025-10-08 16:05:20.223704 | controller | Wednesday 08 October 2025  16:05:20 +0000 (0:00:00.173)       0:00:26.768 *****
2025-10-08 16:05:20.223726 | controller | skipping: [instance]
2025-10-08 16:05:20.247339 | controller |
2025-10-08 16:05:20.247356 | controller | TASK [compliance : Set output directory] ***************************************
2025-10-08 16:05:20.247364 | controller | Wednesday 08 October 2025  16:05:20 +0000 (0:00:00.015)       0:00:26.784 *****
2025-10-08 16:05:20.247372 | controller | ok: [instance]
2025-10-08 16:05:20.263841 | controller |
2025-10-08 16:05:20.263863 | controller | TASK [compliance : Create scan binding] ****************************************
2025-10-08 16:05:20.263871 | controller | Wednesday 08 October 2025  16:05:20 +0000 (0:00:00.023)       0:00:26.808 *****
2025-10-08 16:05:20.263880 | controller | skipping: [instance]
2025-10-08 16:05:20.264080 | controller |
2025-10-08 16:05:20.264102 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-10-08 16:05:20.280816 | controller | Wednesday 08 October 2025  16:05:20 +0000 (0:00:00.016)       0:00:26.824 *****
2025-10-08 16:05:20.280844 | controller | skipping: [instance]
2025-10-08 16:05:20.459357 | controller |
2025-10-08 16:05:20.459385 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-10-08 16:05:20.459393 | controller | Wednesday 08 October 2025  16:05:20 +0000 (0:00:00.016)       0:00:26.841 *****
2025-10-08 16:05:20.459402 | controller | changed: [instance]
2025-10-08 16:05:20.649927 | controller |
2025-10-08 16:05:20.649957 | controller | TASK [compliance : Create directory for scan results] **************************
2025-10-08 16:05:20.649964 | controller | Wednesday 08 October 2025  16:05:20 +0000 (0:00:00.178)       0:00:27.019 *****
2025-10-08 16:05:20.649974 | controller | changed: [instance]
2025-10-08 16:05:20.667729 | controller |
2025-10-08 16:05:20.667762 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-10-08 16:05:20.667770 | controller | Wednesday 08 October 2025  16:05:20 +0000 (0:00:00.190)       0:00:27.210 *****
2025-10-08 16:05:20.667793 | controller | skipping: [instance]
2025-10-08 16:05:20.847584 | controller |
2025-10-08 16:05:20.847614 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-10-08 16:05:20.847641 | controller | Wednesday 08 October 2025  16:05:20 +0000 (0:00:00.017)       0:00:27.228 *****
2025-10-08 16:05:20.847652 | controller | ok: [instance]
2025-10-08 16:05:20.862185 | controller |
2025-10-08 16:05:20.862211 | controller | TASK [compliance : Process scan result files] **********************************
2025-10-08 16:05:20.862219 | controller | Wednesday 08 October 2025  16:05:20 +0000 (0:00:00.179)       0:00:27.408 *****
2025-10-08 16:05:20.862229 | controller | skipping: [instance]
2025-10-08 16:05:20.880870 | controller |
2025-10-08 16:05:20.880906 | controller | TASK [compliance : Log start of scans] *****************************************
2025-10-08 16:05:20.880914 | controller | Wednesday 08 October 2025  16:05:20 +0000 (0:00:00.014)       0:00:27.422 *****
2025-10-08 16:05:20.880924 | controller | ok: [instance] =>
2025-10-08 16:05:20.912898 | controller |   msg: Starting scans for pci-dss
2025-10-08 16:05:20.912931 | controller |
2025-10-08 16:05:20.912941 | controller | TASK [compliance : Run scans and extract results] ******************************
2025-10-08 16:05:20.912949 | controller | Wednesday 08 October 2025  16:05:20 +0000 (0:00:00.018)       0:00:27.441 *****
2025-10-08 16:05:20.912961 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_operator_scan.yml for instance => (item=ocp4-pci-dss)
2025-10-08 16:05:20.912981 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_operator_scan.yml for instance => (item=ocp4-pci-dss-node)
2025-10-08 16:05:20.912989 | controller |
2025-10-08 16:05:20.912996 | controller | TASK [compliance : Set output directory] ***************************************
2025-10-08 16:05:20.913005 | controller | Wednesday 08 October 2025  16:05:20 +0000 (0:00:00.032)       0:00:27.473 *****
2025-10-08 16:05:20.935282 | controller | ok: [instance]
2025-10-08 16:05:20.951476 | controller |
2025-10-08 16:05:20.951501 | controller | TASK [compliance : Create scan binding] ****************************************
2025-10-08 16:05:20.951509 | controller | Wednesday 08 October 2025  16:05:20 +0000 (0:00:00.022)       0:00:27.495 *****
2025-10-08 16:05:20.951519 | controller | skipping: [instance]
2025-10-08 16:05:20.967961 | controller |
2025-10-08 16:05:20.967985 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-10-08 16:05:20.967992 | controller | Wednesday 08 October 2025  16:05:20 +0000 (0:00:00.016)       0:00:27.512 *****
2025-10-08 16:05:20.968001 | controller | skipping: [instance]
2025-10-08 16:05:21.146946 | controller |
2025-10-08 16:05:21.146975 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-10-08 16:05:21.146983 | controller | Wednesday 08 October 2025  16:05:20 +0000 (0:00:00.016)       0:00:27.528 *****
2025-10-08 16:05:21.146993 | controller | changed: [instance]
2025-10-08 16:05:21.340740 | controller |
2025-10-08 16:05:21.340768 | controller | TASK [compliance : Create directory for scan results] **************************
2025-10-08 16:05:21.340798 | controller | Wednesday 08 October 2025  16:05:21 +0000 (0:00:00.178)       0:00:27.707 *****
2025-10-08 16:05:21.340813 | controller | changed: [instance]
2025-10-08 16:05:21.358421 | controller |
2025-10-08 16:05:21.358447 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-10-08 16:05:21.358455 | controller | Wednesday 08 October 2025  16:05:21 +0000 (0:00:00.193)       0:00:27.901 *****
2025-10-08 16:05:21.358464 | controller | skipping: [instance]
2025-10-08 16:05:21.529440 | controller |
2025-10-08 16:05:21.529468 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-10-08 16:05:21.529476 | controller | Wednesday 08 October 2025  16:05:21 +0000 (0:00:00.017)       0:00:27.919 *****
2025-10-08 16:05:21.529486 | controller | ok: [instance]
2025-10-08 16:05:21.543232 | controller |
2025-10-08 16:05:21.543258 | controller | TASK [compliance : Process scan result files] **********************************
2025-10-08 16:05:21.543266 | controller | Wednesday 08 October 2025  16:05:21 +0000 (0:00:00.170)       0:00:28.090 *****
2025-10-08 16:05:21.543276 | controller | skipping: [instance]
2025-10-08 16:05:21.565174 | controller |
2025-10-08 16:05:21.565197 | controller | TASK [compliance : Set output directory] ***************************************
2025-10-08 16:05:21.565204 | controller | Wednesday 08 October 2025  16:05:21 +0000 (0:00:00.013)       0:00:28.103 *****
2025-10-08 16:05:21.565219 | controller | ok: [instance]
2025-10-08 16:05:21.580767 | controller |
2025-10-08 16:05:21.580815 | controller | TASK [compliance : Create scan binding] ****************************************
2025-10-08 16:05:21.580823 | controller | Wednesday 08 October 2025  16:05:21 +0000 (0:00:00.021)       0:00:28.125 *****
2025-10-08 16:05:21.580833 | controller | skipping: [instance]
2025-10-08 16:05:21.596288 | controller |
2025-10-08 16:05:21.596309 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-10-08 16:05:21.596316 | controller | Wednesday 08 October 2025  16:05:21 +0000 (0:00:00.015)       0:00:28.141 *****
2025-10-08 16:05:21.596326 | controller | skipping: [instance]
2025-10-08 16:05:21.777353 | controller |
2025-10-08 16:05:21.777387 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-10-08 16:05:21.777395 | controller | Wednesday 08 October 2025  16:05:21 +0000 (0:00:00.015)       0:00:28.157 *****
2025-10-08 16:05:21.777405 | controller | changed: [instance]
2025-10-08 16:05:21.974378 | controller |
2025-10-08 16:05:21.974407 | controller | TASK [compliance : Create directory for scan results] **************************
2025-10-08 16:05:21.974415 | controller | Wednesday 08 October 2025  16:05:21 +0000 (0:00:00.180)       0:00:28.337 *****
2025-10-08 16:05:21.974425 | controller | changed: [instance]
2025-10-08 16:05:21.991766 | controller |
2025-10-08 16:05:21.991809 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-10-08 16:05:21.991817 | controller | Wednesday 08 October 2025  16:05:21 +0000 (0:00:00.197)       0:00:28.534 *****
2025-10-08 16:05:21.991827 | controller | skipping: [instance]
2025-10-08 16:05:22.170682 | controller |
2025-10-08 16:05:22.170712 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-10-08 16:05:22.170719 | controller | Wednesday 08 October 2025  16:05:21 +0000 (0:00:00.017)       0:00:28.552 *****
2025-10-08 16:05:22.170730 | controller | ok: [instance]
2025-10-08 16:05:22.184852 | controller |
2025-10-08 16:05:22.184879 | controller | TASK [compliance : Process scan result files] **********************************
2025-10-08 16:05:22.184887 | controller | Wednesday 08 October 2025  16:05:22 +0000 (0:00:00.178)       0:00:28.731 *****
2025-10-08 16:05:22.184898 | controller | skipping: [instance]
2025-10-08 16:05:22.184920 | controller |
2025-10-08 16:05:22.184928 | controller | TASK [compliance : Log start of scans] *****************************************
2025-10-08 16:05:22.185034 | controller | Wednesday 08 October 2025  16:05:22 +0000 (0:00:00.014)       0:00:28.745 *****
2025-10-08 16:05:22.202863 | controller | ok: [instance] =>
2025-10-08 16:05:22.203069 | controller |   msg: Starting scans for stig
2025-10-08 16:05:22.203084 | controller |
2025-10-08 16:05:22.203094 | controller | TASK [compliance : Run scans and extract results] ******************************
2025-10-08 16:05:22.240895 | controller | Wednesday 08 October 2025  16:05:22 +0000 (0:00:00.017)       0:00:28.763 *****
2025-10-08 16:05:22.240932 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_operator_scan.yml for instance => (item=ocp4-stig)
2025-10-08 16:05:22.241324 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_operator_scan.yml for instance => (item=ocp4-stig-node)
2025-10-08 16:05:22.241346 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_operator_scan.yml for instance => (item=rhcos4-stig)
2025-10-08 16:05:22.263990 | controller |
2025-10-08 16:05:22.264018 | controller | TASK [compliance : Set output directory] ***************************************
2025-10-08 16:05:22.264026 | controller | Wednesday 08 October 2025  16:05:22 +0000 (0:00:00.037)       0:00:28.801 *****
2025-10-08 16:05:22.264036 | controller | ok: [instance]
2025-10-08 16:05:22.280662 | controller |
2025-10-08 16:05:22.280687 | controller | TASK [compliance : Create scan binding] ****************************************
2025-10-08 16:05:22.280694 | controller | Wednesday 08 October 2025  16:05:22 +0000 (0:00:00.023)       0:00:28.824 *****
2025-10-08 16:05:22.280710 | controller | skipping: [instance]
2025-10-08 16:05:22.296670 | controller |
2025-10-08 16:05:22.296693 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-10-08 16:05:22.296700 | controller | Wednesday 08 October 2025  16:05:22 +0000 (0:00:00.016)       0:00:28.841 *****
2025-10-08 16:05:22.296714 | controller | skipping: [instance]
2025-10-08 16:05:22.475741 | controller |
2025-10-08 16:05:22.475769 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-10-08 16:05:22.475795 | controller | Wednesday 08 October 2025  16:05:22 +0000 (0:00:00.016)       0:00:28.857 *****
2025-10-08 16:05:22.475809 | controller | changed: [instance]
2025-10-08 16:05:22.659427 | controller |
2025-10-08 16:05:22.659462 | controller | TASK [compliance : Create directory for scan results] **************************
2025-10-08 16:05:22.659470 | controller | Wednesday 08 October 2025  16:05:22 +0000 (0:00:00.178)       0:00:29.036 *****
2025-10-08 16:05:22.659480 | controller | changed: [instance]
2025-10-08 16:05:22.676243 | controller |
2025-10-08 16:05:22.676269 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-10-08 16:05:22.676276 | controller | Wednesday 08 October 2025  16:05:22 +0000 (0:00:00.183)       0:00:29.220 *****
2025-10-08 16:05:22.676286 | controller | skipping: [instance]
2025-10-08 16:05:22.862640 | controller |
2025-10-08 16:05:22.862675 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-10-08 16:05:22.862683 | controller | Wednesday 08 October 2025  16:05:22 +0000 (0:00:00.016)       0:00:29.236 *****
2025-10-08 16:05:22.862693 | controller | ok: [instance]
2025-10-08 16:05:22.878471 | controller |
2025-10-08 16:05:22.878498 | controller | TASK [compliance : Process scan result files] **********************************
2025-10-08 16:05:22.878506 | controller | Wednesday 08 October 2025  16:05:22 +0000 (0:00:00.186)       0:00:29.423 *****
2025-10-08 16:05:22.878516 | controller | skipping: [instance]
2025-10-08 16:05:22.902865 | controller |
2025-10-08 16:05:22.902894 | controller | TASK [compliance : Set output directory] ***************************************
2025-10-08 16:05:22.902903 | controller | Wednesday 08 October 2025  16:05:22 +0000 (0:00:00.015)       0:00:29.439 *****
2025-10-08 16:05:22.902912 | controller | ok: [instance]
2025-10-08 16:05:22.920831 | controller |
2025-10-08 16:05:22.920857 | controller | TASK [compliance : Create scan binding] ****************************************
2025-10-08 16:05:22.920865 | controller | Wednesday 08 October 2025  16:05:22 +0000 (0:00:00.024)       0:00:29.463 *****
2025-10-08 16:05:22.920876 | controller | skipping: [instance]
2025-10-08 16:05:22.938644 | controller |
2025-10-08 16:05:22.938669 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-10-08 16:05:22.938677 | controller | Wednesday 08 October 2025  16:05:22 +0000 (0:00:00.017)       0:00:29.481 *****
2025-10-08 16:05:22.938687 | controller | skipping: [instance]
2025-10-08 16:05:23.123405 | controller |
2025-10-08 16:05:23.123437 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-10-08 16:05:23.123446 | controller | Wednesday 08 October 2025  16:05:22 +0000 (0:00:00.017)       0:00:29.499 *****
2025-10-08 16:05:23.123456 | controller | changed: [instance]
2025-10-08 16:05:23.318574 | controller |
2025-10-08 16:05:23.318604 | controller | TASK [compliance : Create directory for scan results] **************************
2025-10-08 16:05:23.318612 | controller | Wednesday 08 October 2025  16:05:23 +0000 (0:00:00.184)       0:00:29.683 *****
2025-10-08 16:05:23.318621 | controller | changed: [instance]
2025-10-08 16:05:23.335588 | controller |
2025-10-08 16:05:23.335617 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-10-08 16:05:23.335639 | controller | Wednesday 08 October 2025  16:05:23 +0000 (0:00:00.195)       0:00:29.879 *****
2025-10-08 16:05:23.335650 | controller | skipping: [instance]
2025-10-08 16:05:23.512432 | controller |
2025-10-08 16:05:23.512464 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-10-08 16:05:23.512471 | controller | Wednesday 08 October 2025  16:05:23 +0000 (0:00:00.017)       0:00:29.896 *****
2025-10-08 16:05:23.512481 | controller | ok: [instance]
2025-10-08 16:05:23.526858 | controller |
2025-10-08 16:05:23.526888 | controller | TASK [compliance : Process scan result files] **********************************
2025-10-08 16:05:23.526896 | controller | Wednesday 08 October 2025  16:05:23 +0000 (0:00:00.176)       0:00:30.072 *****
2025-10-08 16:05:23.526905 | controller | skipping: [instance]
2025-10-08 16:05:23.526929 | controller |
2025-10-08 16:05:23.527120 | controller | TASK [compliance : Set output directory] ***************************************
2025-10-08 16:05:23.551499 | controller | Wednesday 08 October 2025  16:05:23 +0000 (0:00:00.014)       0:00:30.087 *****
2025-10-08 16:05:23.551534 | controller | ok: [instance]
2025-10-08 16:05:23.568754 | controller |
2025-10-08 16:05:23.568797 | controller | TASK [compliance : Create scan binding] ****************************************
2025-10-08 16:05:23.568811 | controller | Wednesday 08 October 2025  16:05:23 +0000 (0:00:00.024)       0:00:30.112 *****
2025-10-08 16:05:23.568821 | controller | skipping: [instance]
2025-10-08 16:05:23.585796 | controller |
2025-10-08 16:05:23.585819 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-10-08 16:05:23.585826 | controller | Wednesday 08 October 2025  16:05:23 +0000 (0:00:00.017)       0:00:30.129 *****
2025-10-08 16:05:23.585835 | controller | skipping: [instance]
2025-10-08 16:05:23.776457 | controller |
2025-10-08 16:05:23.776488 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-10-08 16:05:23.776496 | controller | Wednesday 08 October 2025  16:05:23 +0000 (0:00:00.017)       0:00:30.146 *****
2025-10-08 16:05:23.776506 | controller | changed: [instance]
2025-10-08 16:05:23.973213 | controller |
2025-10-08 16:05:23.973244 | controller | TASK [compliance : Create directory for scan results] **************************
2025-10-08 16:05:23.973252 | controller | Wednesday 08 October 2025  16:05:23 +0000 (0:00:00.190)       0:00:30.336 *****
2025-10-08 16:05:23.973262 | controller | changed: [instance]
2025-10-08 16:05:23.989745 | controller |
2025-10-08 16:05:23.989767 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-10-08 16:05:23.989775 | controller | Wednesday 08 October 2025  16:05:23 +0000 (0:00:00.196)       0:00:30.533 *****
2025-10-08 16:05:23.989805 | controller | skipping: [instance]
2025-10-08 16:05:24.174849 | controller |
2025-10-08 16:05:24.174879 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-10-08 16:05:24.174887 | controller | Wednesday 08 October 2025  16:05:23 +0000 (0:00:00.016)       0:00:30.550 *****
2025-10-08 16:05:24.174904 | controller | ok: [instance]
2025-10-08 16:05:24.189301 | controller |
2025-10-08 16:05:24.189329 | controller | TASK [compliance : Process scan result files] **********************************
2025-10-08 16:05:24.189337 | controller | Wednesday 08 October 2025  16:05:24 +0000 (0:00:00.184)       0:00:30.735 *****
2025-10-08 16:05:24.189347 | controller | skipping: [instance]
2025-10-08 16:05:24.223431 | controller |
2025-10-08 16:05:24.223469 | controller | TASK [compliance : Delete all resources created by the role] *******************
2025-10-08 16:05:24.223479 | controller | Wednesday 08 October 2025  16:05:24 +0000 (0:00:00.014)       0:00:30.749 *****
2025-10-08 16:05:24.223491 | controller | skipping: [instance]
2025-10-08 16:05:24.223538 | controller |
2025-10-08 16:05:24.223549 | controller | PLAY RECAP *********************************************************************
2025-10-08 16:05:24.223677 | controller | instance                   : ok=200  changed=52   unreachable=0    failed=0    skipped=152  rescued=0    ignored=0
2025-10-08 16:05:24.223718 | controller |
2025-10-08 16:05:24.225041 | controller | Wednesday 08 October 2025  16:05:24 +0000 (0:00:00.033)       0:00:30.783 *****
2025-10-08 16:05:24.225116 | controller | ===============================================================================
2025-10-08 16:05:24.225125 | controller | compliance : Install packages to process results ------------------------ 4.14s
2025-10-08 16:05:24.225131 | controller | Gathering Facts --------------------------------------------------------- 0.86s
2025-10-08 16:05:24.225137 | controller | podman : Ensure podman is installed ------------------------------------- 0.81s
2025-10-08 16:05:24.225143 | controller | podman : Ensure podman is installed ------------------------------------- 0.69s
2025-10-08 16:05:24.225148 | controller | compliance : Install packages to process results ------------------------ 0.67s
2025-10-08 16:05:24.225154 | controller | Gathering Facts --------------------------------------------------------- 0.61s
2025-10-08 16:05:24.225159 | controller | podman : Enable loginctl linger for ansible_user_id --------------------- 0.35s
2025-10-08 16:05:24.225165 | controller | compliance : Ensure compliance folder exists ---------------------------- 0.31s
2025-10-08 16:05:24.225170 | controller | compliance : Find all the compressed scan results ----------------------- 0.26s
2025-10-08 16:05:24.225176 | controller | compliance : Create directory for scan results -------------------------- 0.22s
2025-10-08 16:05:24.225190 | controller | compliance : Ensure compliance folder exists ---------------------------- 0.22s
2025-10-08 16:05:24.225198 | controller | podman : Enable loginctl linger for ansible_user_id --------------------- 0.21s
2025-10-08 16:05:24.225223 | controller | compliance : Delete directory for scan results if it exists ------------- 0.21s
2025-10-08 16:05:24.225255 | controller | compliance : Create directory for scan results -------------------------- 0.20s
2025-10-08 16:05:24.225874 | controller | compliance : Create directory for scan results -------------------------- 0.20s
2025-10-08 16:05:24.284740 | controller | compliance : Create directory for scan results -------------------------- 0.20s
2025-10-08 16:05:24.284795 | controller | compliance : Create directory for scan results -------------------------- 0.20s
2025-10-08 16:05:24.284807 | controller | compliance : Create directory for scan results -------------------------- 0.20s
2025-10-08 16:05:24.284814 | controller | compliance : Create directory for scan results -------------------------- 0.20s
2025-10-08 16:05:24.284819 | controller | compliance : Create directory for scan results -------------------------- 0.19s
2025-10-08 16:05:24.284830 | controller | INFO     Running default > cleanup
2025-10-08 16:05:24.285315 | controller | WARNING  Skipping, cleanup playbook not configured.
2025-10-08 16:05:24.285929 | controller | INFO     Writing /tmp/report.html report.
2025-10-08 16:05:24.687692 | controller | changed
2025-10-08 16:05:24.715881 | 
2025-10-08 16:05:24.715984 | PLAY RECAP
2025-10-08 16:05:24.716027 | controller | ok: 3 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-10-08 16:05:24.716051 | 
2025-10-08 16:05:24.825588 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-10-08 16:05:24.826420 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-10-08 16:05:25.539955 | 
2025-10-08 16:05:25.540061 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-10-08 16:05:25.561719 | 
2025-10-08 16:05:25.561802 | TASK [Filter out host if needed]
2025-10-08 16:05:25.571050 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-10-08 16:05:25.575953 | 
2025-10-08 16:05:25.576023 | TASK [Ensure file is present]
2025-10-08 16:05:25.915998 | controller | ok
2025-10-08 16:05:25.922902 | 
2025-10-08 16:05:25.923024 | TASK [Manage molecule report file]
2025-10-08 16:05:26.467220 | controller | changed
2025-10-08 16:05:26.473241 | 
2025-10-08 16:05:26.473329 | TASK [Check if we get ci-framework-data basedir]
2025-10-08 16:05:26.655238 | controller | ok
2025-10-08 16:05:26.660419 | 
2025-10-08 16:05:26.660510 | TASK [Create ci-framework-data log directory for zuul]
2025-10-08 16:05:26.963705 | controller | changed
2025-10-08 16:05:26.970252 | 
2025-10-08 16:05:26.970344 | TASK [Copy ci-framework interesting files]
2025-10-08 16:05:27.497527 | controller | changed
2025-10-08 16:05:27.503679 | 
2025-10-08 16:05:27.503782 | TASK [Get SELinux listing]
2025-10-08 16:05:28.032674 | controller | changed
2025-10-08 16:05:28.038806 | 
2025-10-08 16:05:28.038876 | TASK [Generate log index]
2025-10-08 16:05:28.686317 | controller | changed
2025-10-08 16:05:28.691881 | 
2025-10-08 16:05:28.691982 | TASK [Get some env related data]
2025-10-08 16:05:29.228437 | controller | /home/zuul/.local/bin/ansible
2025-10-08 16:05:30.219523 | controller | changed
2025-10-08 16:05:30.224794 | 
2025-10-08 16:05:30.224866 | TASK [Generate list of logs to collect in home directory]
2025-10-08 16:05:30.532116 | controller | ok: All paths examined
2025-10-08 16:05:30.537757 | 
2025-10-08 16:05:30.537846 | LOOP [Copy logs from home directory]
2025-10-08 16:05:30.918287 | controller | changed:
2025-10-08 16:05:30.918449 | controller | {
2025-10-08 16:05:30.918479 | controller |   "atime": 1759939456.8279195,
2025-10-08 16:05:30.918502 | controller |   "ctime": 1759939473.0671232,
2025-10-08 16:05:30.918520 | controller |   "dev": 64513,
2025-10-08 16:05:30.918536 | controller |   "gid": 1000,
2025-10-08 16:05:30.918552 | controller |   "gr_name": "zuul",
2025-10-08 16:05:30.918568 | controller |   "inode": 4450031,
2025-10-08 16:05:30.918584 | controller |   "isblk": false,
2025-10-08 16:05:30.918599 | controller |   "ischr": false,
2025-10-08 16:05:30.918614 | controller |   "isdir": false,
2025-10-08 16:05:30.918628 | controller |   "isfifo": false,
2025-10-08 16:05:30.918644 | controller |   "isgid": false,
2025-10-08 16:05:30.918660 | controller |   "islnk": false,
2025-10-08 16:05:30.918675 | controller |   "isreg": true,
2025-10-08 16:05:30.918689 | controller |   "issock": false,
2025-10-08 16:05:30.918703 | controller |   "isuid": false,
2025-10-08 16:05:30.918718 | controller |   "mode": "0644",
2025-10-08 16:05:30.918732 | controller |   "mtime": 1759939473.0671232,
2025-10-08 16:05:30.918745 | controller |   "nlink": 1,
2025-10-08 16:05:30.918759 | controller |   "path": "/home/zuul/ansible.log",
2025-10-08 16:05:30.918773 | controller |   "pw_name": "zuul",
2025-10-08 16:05:30.918790 | controller |   "rgrp": true,
2025-10-08 16:05:30.918805 | controller |   "roth": true,
2025-10-08 16:05:30.918821 | controller |   "rusr": true,
2025-10-08 16:05:30.918844 | controller |   "size": 6749,
2025-10-08 16:05:30.918861 | controller |   "uid": 1000,
2025-10-08 16:05:30.918877 | controller |   "wgrp": false,
2025-10-08 16:05:30.918891 | controller |   "woth": false,
2025-10-08 16:05:30.918922 | controller |   "wusr": true,
2025-10-08 16:05:30.918950 | controller |   "xgrp": false,
2025-10-08 16:05:30.918968 | controller |   "xoth": false,
2025-10-08 16:05:30.918984 | controller |   "xusr": false
2025-10-08 16:05:30.918999 | controller | }
2025-10-08 16:05:30.932016 | 
2025-10-08 16:05:30.932097 | TASK [Copy crio stats log file]
2025-10-08 16:05:30.957039 | controller | skipping: Conditional result was False
2025-10-08 16:05:30.963626 | 
2025-10-08 16:05:30.963727 | TASK [Get SELinux related data]
2025-10-08 16:05:31.195410 | controller | <no matches>
2025-10-08 16:05:31.504235 | controller | ERROR
2025-10-08 16:05:31.504457 | controller | {
2025-10-08 16:05:31.504505 | controller |   "delta": "0:00:00.009295",
2025-10-08 16:05:31.504530 | controller |   "end": "2025-10-08 16:05:31.196635",
2025-10-08 16:05:31.504550 | controller |   "msg": "non-zero return code",
2025-10-08 16:05:31.504566 | controller |   "rc": 1,
2025-10-08 16:05:31.504583 | controller |   "start": "2025-10-08 16:05:31.187340"
2025-10-08 16:05:31.504600 | controller | }
2025-10-08 16:05:31.504628 | controller | ERROR: Ignoring Errors
2025-10-08 16:05:31.510632 | 
2025-10-08 16:05:31.510727 | TASK [Create system configuration directory]
2025-10-08 16:05:31.704101 | controller | changed
2025-10-08 16:05:31.709533 | 
2025-10-08 16:05:31.709622 | TASK [Get some of the system configurations]
2025-10-08 16:05:32.241087 | controller | changed
2025-10-08 16:05:32.246322 | 
2025-10-08 16:05:32.246421 | TASK [Copy generated documentation if available]
2025-10-08 16:05:32.259771 | controller | skipping: Conditional result was False
2025-10-08 16:05:32.265457 | 
2025-10-08 16:05:32.265536 | TASK [Copy generated AsciiDoc documentation if available]
2025-10-08 16:05:32.278625 | controller | skipping: Conditional result was False
2025-10-08 16:05:32.284713 | 
2025-10-08 16:05:32.284790 | TASK [Compress logs bigger than 2MB]
2025-10-08 16:05:32.810806 | controller | changed
2025-10-08 16:05:32.824937 | 
2025-10-08 16:05:32.825036 | TASK [Copy files from workspace on node]
2025-10-08 16:05:32.854603 | controller | ok
2025-10-08 16:05:32.879839 | 
2025-10-08 16:05:32.879962 | TASK [fetch-output : Set log path for multiple nodes]
2025-10-08 16:05:32.894414 | controller | skipping: Conditional result was False
2025-10-08 16:05:32.901415 | 
2025-10-08 16:05:32.901510 | TASK [fetch-output : Set log path for single node]
2025-10-08 16:05:32.934641 | controller | ok
2025-10-08 16:05:32.939410 | 
2025-10-08 16:05:32.939486 | LOOP [fetch-output : Ensure local output dirs]
2025-10-08 16:05:33.194024 | controller -> localhost | ok: "/var/lib/zuul/builds/662eb396bebf496dba5101083bca8a20/work/logs"
2025-10-08 16:05:33.194321 | controller -> localhost | changed: All items complete
2025-10-08 16:05:33.194352 | 
2025-10-08 16:05:33.443022 | controller -> localhost | changed: "/var/lib/zuul/builds/662eb396bebf496dba5101083bca8a20/work/artifacts"
2025-10-08 16:05:33.654702 | controller -> localhost | changed: "/var/lib/zuul/builds/662eb396bebf496dba5101083bca8a20/work/docs"
2025-10-08 16:05:33.668511 | 
2025-10-08 16:05:33.668642 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-10-08 16:05:34.267174 | controller | changed:
2025-10-08 16:05:34.267807 | controller | .d..t...... ./
2025-10-08 16:05:34.267842 | controller | >f+++++++++ README.html
2025-10-08 16:05:34.267864 | controller | >f+++++++++ ansible-execution.log
2025-10-08 16:05:34.267886 | controller | >f+++++++++ ansible.log
2025-10-08 16:05:34.267904 | controller | >f+++++++++ dmesg.log
2025-10-08 16:05:34.267973 | controller | >f+++++++++ installed-pkgs.log
2025-10-08 16:05:34.267992 | controller | >f+++++++++ python.log
2025-10-08 16:05:34.268013 | controller | >f+++++++++ registries.conf
2025-10-08 16:05:34.268034 | controller | >f+++++++++ report.html
2025-10-08 16:05:34.268058 | controller | >f+++++++++ selinux-denials.log
2025-10-08 16:05:34.268078 | controller | >f+++++++++ selinux-listing.log
2025-10-08 16:05:34.268095 | controller | cd+++++++++ ci-framework-data/
2025-10-08 16:05:34.268112 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-10-08 16:05:34.268127 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-10-08 16:05:34.268142 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-10-08 16:05:34.268157 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-10-08 16:05:34.268171 | controller | cd+++++++++ ci-framework-data/logs/
2025-10-08 16:05:34.268185 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2025-10-08 16:05:34.268199 | controller | cd+++++++++ ci-framework-data/tests/
2025-10-08 16:05:34.268213 | controller | cd+++++++++ ci-framework-data/tests/compliance/
2025-10-08 16:05:34.268228 | controller | cd+++++++++ ci-framework-data/tests/compliance/cis/
2025-10-08 16:05:34.268242 | controller | cd+++++++++ ci-framework-data/tests/compliance/cis/ocp4-cis/
2025-10-08 16:05:34.268255 | controller | cd+++++++++ ci-framework-data/tests/compliance/e8/
2025-10-08 16:05:34.268270 | controller | cd+++++++++ ci-framework-data/tests/compliance/e8/ocp4-e8/
2025-10-08 16:05:34.268285 | controller | cd+++++++++ ci-framework-data/tests/compliance/e8/rhcos4-e8/
2025-10-08 16:05:34.268299 | controller | cd+++++++++ ci-framework-data/tests/compliance/high/
2025-10-08 16:05:34.268312 | controller | cd+++++++++ ci-framework-data/tests/compliance/high/ocp4-high-node/
2025-10-08 16:05:34.268326 | controller | cd+++++++++ ci-framework-data/tests/compliance/high/ocp4-high/
2025-10-08 16:05:34.268340 | controller | cd+++++++++ ci-framework-data/tests/compliance/high/rhcos4-high/
2025-10-08 16:05:34.268354 | controller | cd+++++++++ ci-framework-data/tests/compliance/moderate/
2025-10-08 16:05:34.268367 | controller | cd+++++++++ ci-framework-data/tests/compliance/moderate/ocp4-moderate-node/
2025-10-08 16:05:34.268381 | controller | cd+++++++++ ci-framework-data/tests/compliance/moderate/ocp4-moderate/
2025-10-08 16:05:34.268410 | controller | cd+++++++++ ci-framework-data/tests/compliance/moderate/rhcos4-moderate/
2025-10-08 16:05:34.268427 | controller | cd+++++++++ ci-framework-data/tests/compliance/nerc-cip/
2025-10-08 16:05:34.268440 | controller | cd+++++++++ ci-framework-data/tests/compliance/nerc-cip/ocp4-nerc-cip-node/
2025-10-08 16:05:34.268464 | controller | cd+++++++++ ci-framework-data/tests/compliance/nerc-cip/ocp4-nerc-cip/
2025-10-08 16:05:34.268480 | controller | cd+++++++++ ci-framework-data/tests/compliance/pci-dss/
2025-10-08 16:05:34.269542 | controller | cd+++++++++ ci-framework-data/tests/compliance/pci-dss/ocp4-pci-dss-node/
2025-10-08 16:05:34.269597 | controller | cd+++++++++ ci-framework-data/tests/compliance/pci-dss/ocp4-pci-dss/
2025-10-08 16:05:34.269624 | controller | cd+++++++++ ci-framework-data/tests/compliance/stig/
2025-10-08 16:05:34.269644 | controller | cd+++++++++ ci-framework-data/tests/compliance/stig/ocp4-stig-node/
2025-10-08 16:05:34.269661 | controller | cd+++++++++ ci-framework-data/tests/compliance/stig/ocp4-stig/
2025-10-08 16:05:34.269677 | controller | cd+++++++++ ci-framework-data/tests/compliance/stig/rhcos4-stig/
2025-10-08 16:05:34.269695 | controller | cd+++++++++ registries.conf.d/
2025-10-08 16:05:34.269712 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-10-08 16:05:34.269729 | controller | cd+++++++++ system-config/
2025-10-08 16:05:34.269744 | controller | cd+++++++++ system-config/libvirt/
2025-10-08 16:05:34.269759 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-10-08 16:05:34.269774 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-10-08 16:05:34.670454 | controller | changed: .d..t...... ./
2025-10-08 16:05:35.077057 | controller | changed: .d..t...... ./
2025-10-08 16:05:35.092997 | 
2025-10-08 16:05:35.093112 | TASK [Return artifact to Zuul]
2025-10-08 16:05:35.122677 | controller | ok
2025-10-08 16:05:35.144290 | 
2025-10-08 16:05:35.144383 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-10-08 16:05:35.144500 | 
2025-10-08 16:05:35.144529 | PLAY RECAP
2025-10-08 16:05:35.144566 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2025-10-08 16:05:35.144587 | 
2025-10-08 16:05:35.247251 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-10-08 16:05:35.248023 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-10-08 16:05:35.838740 | 
2025-10-08 16:05:35.838847 | PLAY [all]
2025-10-08 16:05:35.858502 | 
2025-10-08 16:05:35.858592 | TASK [include_role : fetch-output]
2025-10-08 16:05:35.889835 | controller | ok
2025-10-08 16:05:35.908813 | 
2025-10-08 16:05:35.908937 | TASK [fetch-output : Set log path for multiple nodes]
2025-10-08 16:05:35.953153 | controller | skipping: Conditional result was False
2025-10-08 16:05:35.960188 | 
2025-10-08 16:05:35.960290 | TASK [fetch-output : Set log path for single node]
2025-10-08 16:05:36.001004 | controller | ok
2025-10-08 16:05:36.006438 | 
2025-10-08 16:05:36.006541 | LOOP [fetch-output : Ensure local output dirs]
2025-10-08 16:05:36.427644 | controller -> localhost | ok: "/var/lib/zuul/builds/662eb396bebf496dba5101083bca8a20/work/logs"
2025-10-08 16:05:36.637128 | controller -> localhost | ok: "/var/lib/zuul/builds/662eb396bebf496dba5101083bca8a20/work/artifacts"
2025-10-08 16:05:36.853005 | controller -> localhost | ok: "/var/lib/zuul/builds/662eb396bebf496dba5101083bca8a20/work/docs"
2025-10-08 16:05:36.863433 | 
2025-10-08 16:05:36.863522 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-10-08 16:05:37.464072 | controller | ok
2025-10-08 16:05:37.464266 | controller | ok: All items complete
2025-10-08 16:05:37.464297 | 
2025-10-08 16:05:37.883830 | controller | ok
2025-10-08 16:05:38.268432 | controller | ok
2025-10-08 16:05:38.281608 | 
2025-10-08 16:05:38.281716 | TASK [include_role : fetch-output-openshift]
2025-10-08 16:05:38.296081 | controller | skipping: Conditional result was False
2025-10-08 16:05:38.302307 | 
2025-10-08 16:05:38.302389 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-10-08 16:05:38.719094 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.007695
2025-10-08 16:05:38.923794 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.006819
2025-10-08 16:05:38.950585 | 
2025-10-08 16:05:38.950724 | PLAY [all]
2025-10-08 16:05:38.967676 | 
2025-10-08 16:05:38.967844 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-10-08 16:05:39.395772 | controller | changed
2025-10-08 16:05:39.417576 | 
2025-10-08 16:05:39.417666 | PLAY RECAP
2025-10-08 16:05:39.417710 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-10-08 16:05:39.417732 | 
2025-10-08 16:05:39.503766 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-10-08 16:05:39.504561 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-10-08 16:05:40.172324 | 
2025-10-08 16:05:40.172458 | PLAY [localhost]
2025-10-08 16:05:40.191733 | 
2025-10-08 16:05:40.191826 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-10-08 16:05:40.525182 | localhost | changed
2025-10-08 16:05:40.530465 | 
2025-10-08 16:05:40.530565 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-10-08 16:05:40.557938 | localhost | ok
2025-10-08 16:05:40.566201 | 
2025-10-08 16:05:40.566289 | TASK [add-fileserver : Create SSH private key tempfile]
2025-10-08 16:05:40.890008 | localhost | changed
2025-10-08 16:05:40.895973 | 
2025-10-08 16:05:40.896077 | TASK [add-fileserver : Create SSH private key from secret]
2025-10-08 16:05:41.482165 | localhost | changed
2025-10-08 16:05:41.487162 | 
2025-10-08 16:05:41.487235 | TASK [add-fileserver : Add fileserver ssh key]
2025-10-08 16:05:41.871324 | localhost | Identity added: /var/lib/zuul/builds/662eb396bebf496dba5101083bca8a20/work/tmp/ansible.25jhf8ky (/var/lib/zuul/builds/662eb396bebf496dba5101083bca8a20/work/tmp/ansible.25jhf8ky)
2025-10-08 16:05:41.871531 | localhost | ok: Runtime: 0:00:00.013435
2025-10-08 16:05:41.877089 | 
2025-10-08 16:05:41.877171 | TASK [add-fileserver : Remove SSH private key from disk]
2025-10-08 16:05:42.189510 | localhost | ok: Runtime: 0:00:00.005536
2025-10-08 16:05:42.195145 | 
2025-10-08 16:05:42.195240 | TASK [add-fileserver : Add fileserver to inventory]
2025-10-08 16:05:42.245475 | localhost | changed
2025-10-08 16:05:42.249764 | 
2025-10-08 16:05:42.249857 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-10-08 16:05:42.668652 | localhost | changed
2025-10-08 16:05:42.697519 | 
2025-10-08 16:05:42.697654 | PLAY [localhost]
2025-10-08 16:05:42.710870 | 
2025-10-08 16:05:42.711018 | TASK [Generate bulk log download script]
2025-10-08 16:05:42.740505 | localhost | ok
2025-10-08 16:05:42.753216 | 
2025-10-08 16:05:42.753345 | TASK [local-log-download : Check API endpoint is defined]
2025-10-08 16:05:42.782696 | localhost | ok: All assertions passed
2025-10-08 16:05:42.788503 | 
2025-10-08 16:05:42.788622 | TASK [local-log-download : Create download script]
2025-10-08 16:05:43.210828 | localhost -> localhost | changed
2025-10-08 16:05:43.224748 | 
2025-10-08 16:05:43.224832 | TASK [Register quick-download link]
2025-10-08 16:05:43.253217 | localhost | ok
2025-10-08 16:05:43.305166 | 
2025-10-08 16:05:43.305276 | PLAY [logserver.rdoproject.org]
2025-10-08 16:05:43.316600 | 
2025-10-08 16:05:43.316668 | TASK [Set zuul-log-path fact]
2025-10-08 16:05:43.345269 | logserver.rdoproject.org | ok
2025-10-08 16:05:43.357083 | 
2025-10-08 16:05:43.357219 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-08 16:05:43.410191 | logserver.rdoproject.org | ok
2025-10-08 16:05:43.416157 | 
2025-10-08 16:05:43.416252 | TASK [upload-logs : Create log directories]
2025-10-08 16:05:45.453062 | logserver.rdoproject.org | changed
2025-10-08 16:05:45.457596 | 
2025-10-08 16:05:45.457681 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-10-08 16:05:45.745874 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.012579
2025-10-08 16:05:45.750599 | 
2025-10-08 16:05:45.750687 | TASK [upload-logs : Upload logs to log server]
2025-10-08 16:05:47.323084 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-10-08 16:05:47.326047 | 
2025-10-08 16:05:47.326156 | LOOP [upload-logs : Compress console log and json output]
2025-10-08 16:05:47.379770 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-08 16:05:47.380642 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-08 16:05:47.394123 | 
2025-10-08 16:05:47.394217 | LOOP [upload-logs : Upload compressed console log and json output]
2025-10-08 16:05:47.449279 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-08 16:05:47.449520 | 
2025-10-08 16:05:47.450148 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-08 16:05:47.462099 | 
2025-10-08 16:05:47.462222 | LOOP [upload-logs : Upload console log and json output]