2025-09-30 06:39:29.706849 | Job console starting...
2025-09-30 06:39:30.031487 | Updating repositories
2025-09-30 06:39:30.823301 | Preparing job workspace
2025-09-30 06:39:42.667081 | Running Ansible setup...
2025-09-30 06:39:49.650209 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-09-30 06:39:51.264181 | 
2025-09-30 06:39:51.264300 | PLAY [localhost]
2025-09-30 06:39:51.285615 | 
2025-09-30 06:39:51.285695 | TASK [Gathering Facts]
2025-09-30 06:39:53.546685 | localhost | ok
2025-09-30 06:39:53.576117 | 
2025-09-30 06:39:53.576229 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-09-30 06:39:55.041920 | localhost -> localhost | changed
2025-09-30 06:39:55.048250 | 
2025-09-30 06:39:55.048359 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-09-30 06:39:57.592085 | localhost -> localhost | changed
2025-09-30 06:39:57.604248 | 
2025-09-30 06:39:57.620289 | TASK [Setup log path fact]
2025-09-30 06:39:57.678603 | localhost | ok
2025-09-30 06:39:57.711316 | 
2025-09-30 06:39:57.711414 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-09-30 06:39:57.809789 | localhost | ok
2025-09-30 06:39:57.836322 | 
2025-09-30 06:39:57.836432 | TASK [emit-job-header : Print job information]
2025-09-30 06:39:57.977259 | # Job Information
2025-09-30 06:39:57.977472 | Ansible Version: 2.15.12
2025-09-30 06:39:57.977504 | Job: cifmw-molecule-compliance
2025-09-30 06:39:57.977528 | Pipeline: github-check
2025-09-30 06:39:57.977548 | Executor: ibm-bm4-ze.softwarefactory-project.io
2025-09-30 06:39:57.977568 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3333
2025-09-30 06:39:57.977587 | Log URL (when completed): https://logserver.rdoproject.org/0f9/rdoproject.org/0f9667c155b24790be8b9199741cc743/
2025-09-30 06:39:57.977606 | Event ID: 04e0d970-9dc8-11f0-9216-1f816847bc0d
2025-09-30 06:39:57.981935 | 
2025-09-30 06:39:57.982017 | LOOP [emit-job-header : Print node information]
2025-09-30 06:39:58.278656 | localhost | ok:
2025-09-30 06:39:58.278819 | localhost | # Node Information
2025-09-30 06:39:58.278862 | localhost | Inventory Hostname: controller
2025-09-30 06:39:58.278934 | localhost | Hostname: np0005461840
2025-09-30 06:39:58.278972 | localhost | Username: zuul
2025-09-30 06:39:58.278999 | localhost | Distro: CentOS 9
2025-09-30 06:39:58.279021 | localhost | Provider: ibm-bm4-nodepool
2025-09-30 06:39:58.279041 | localhost | Region: regionOne
2025-09-30 06:39:58.279060 | localhost | Label: cloud-centos-9-stream-tripleo-ibm
2025-09-30 06:39:58.279080 | localhost | Product Name: OpenStack Compute
2025-09-30 06:39:58.279097 | localhost | Interface IP: 192.168.26.111
2025-09-30 06:39:58.334522 | 
2025-09-30 06:39:58.334624 | PLAY [all]
2025-09-30 06:39:58.352297 | 
2025-09-30 06:39:58.352376 | TASK [Gather network facts]
2025-09-30 06:39:58.818565 | controller | ok
2025-09-30 06:39:58.873388 | 
2025-09-30 06:39:58.873524 | TASK [include_role : start-zuul-console]
2025-09-30 06:39:58.954779 | controller | ok
2025-09-30 06:39:58.980798 | 
2025-09-30 06:39:58.980926 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-09-30 06:39:59.745156 | controller | ok
2025-09-30 06:39:59.763970 | 
2025-09-30 06:39:59.764046 | TASK [include_role : add-build-sshkey]
2025-09-30 06:39:59.831545 | controller | ok
2025-09-30 06:39:59.876693 | 
2025-09-30 06:39:59.876802 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-09-30 06:40:00.583542 | controller -> localhost | ok
2025-09-30 06:40:00.590330 | 
2025-09-30 06:40:00.590417 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-09-30 06:40:00.664182 | controller | ok
2025-09-30 06:40:00.685777 | controller | included: /var/lib/zuul/builds/0f9667c155b24790be8b9199741cc743/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-09-30 06:40:00.712965 | 
2025-09-30 06:40:00.713048 | TASK [add-build-sshkey : Create Temp SSH key]
2025-09-30 06:40:03.054740 | controller -> localhost | Generating public/private rsa key pair.
2025-09-30 06:40:03.055008 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/0f9667c155b24790be8b9199741cc743/work/0f9667c155b24790be8b9199741cc743_id_rsa.
2025-09-30 06:40:03.055046 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/0f9667c155b24790be8b9199741cc743/work/0f9667c155b24790be8b9199741cc743_id_rsa.pub.
2025-09-30 06:40:03.055072 | controller -> localhost | The key fingerprint is:
2025-09-30 06:40:03.055094 | controller -> localhost | SHA256:bPmIZLkrFiMzUfBSMqVQtZoJRhO/Q4x4OtFU8ZQWwq4 zuul-build-sshkey
2025-09-30 06:40:03.055115 | controller -> localhost | The key's randomart image is:
2025-09-30 06:40:03.055135 | controller -> localhost | +---[RSA 3072]----+
2025-09-30 06:40:03.055154 | controller -> localhost | |.=B*B.oo         |
2025-09-30 06:40:03.055174 | controller -> localhost | |o+=*o=o          |
2025-09-30 06:40:03.055193 | controller -> localhost | |+o=*o..          |
2025-09-30 06:40:03.055212 | controller -> localhost | |.=o=o  o .       |
2025-09-30 06:40:03.055230 | controller -> localhost | |o +=  + S        |
2025-09-30 06:40:03.055247 | controller -> localhost | | .E +o + o       |
2025-09-30 06:40:03.055264 | controller -> localhost | |   + oo . .      |
2025-09-30 06:40:03.055284 | controller -> localhost | |    o  .         |
2025-09-30 06:40:03.055302 | controller -> localhost | |   . ..          |
2025-09-30 06:40:03.055319 | controller -> localhost | +----[SHA256]-----+
2025-09-30 06:40:03.055364 | controller -> localhost | ok: Runtime: 0:00:01.146219
2025-09-30 06:40:03.061250 | 
2025-09-30 06:40:03.061321 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-09-30 06:40:03.163665 | controller | ok
2025-09-30 06:40:03.183101 | controller | included: /var/lib/zuul/builds/0f9667c155b24790be8b9199741cc743/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-09-30 06:40:03.263766 | 
2025-09-30 06:40:03.263878 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-09-30 06:40:03.352853 | controller | skipping: Conditional result was False
2025-09-30 06:40:03.360093 | 
2025-09-30 06:40:03.360196 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-09-30 06:40:04.384254 | controller | changed
2025-09-30 06:40:04.389893 | 
2025-09-30 06:40:04.389978 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-09-30 06:40:04.682740 | controller | ok
2025-09-30 06:40:04.704743 | 
2025-09-30 06:40:04.704819 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-09-30 06:40:05.722210 | controller | changed
2025-09-30 06:40:05.728273 | 
2025-09-30 06:40:05.728345 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-09-30 06:40:06.611387 | controller | changed
2025-09-30 06:40:06.620007 | 
2025-09-30 06:40:06.620086 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-09-30 06:40:06.687032 | controller | skipping: Conditional result was False
2025-09-30 06:40:06.694411 | 
2025-09-30 06:40:06.694493 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-09-30 06:40:07.993472 | controller -> localhost | changed
2025-09-30 06:40:08.007856 | 
2025-09-30 06:40:08.007962 | TASK [add-build-sshkey : Add back temp key]
2025-09-30 06:40:09.430116 | controller -> localhost | Identity added: /var/lib/zuul/builds/0f9667c155b24790be8b9199741cc743/work/0f9667c155b24790be8b9199741cc743_id_rsa (zuul-build-sshkey)
2025-09-30 06:40:09.430322 | controller -> localhost | ok: Runtime: 0:00:00.037005
2025-09-30 06:40:09.435929 | 
2025-09-30 06:40:09.435998 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-09-30 06:40:10.086247 | controller | ok
2025-09-30 06:40:10.095608 | 
2025-09-30 06:40:10.095683 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-09-30 06:40:10.235855 | controller | skipping: Conditional result was False
2025-09-30 06:40:10.247198 | 
2025-09-30 06:40:10.247302 | TASK [include_role : validate-host]
2025-09-30 06:40:10.339825 | controller | ok
2025-09-30 06:40:10.447003 | 
2025-09-30 06:40:10.447119 | TASK [validate-host : Define zuul_info_dir fact]
2025-09-30 06:40:10.527071 | controller | ok
2025-09-30 06:40:10.531860 | 
2025-09-30 06:40:10.534472 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-09-30 06:40:11.859097 | controller -> localhost | ok
2025-09-30 06:40:11.869356 | 
2025-09-30 06:40:11.869456 | TASK [validate-host : Collect information about the host]
2025-09-30 06:40:12.617015 | controller | ok
2025-09-30 06:40:12.652156 | 
2025-09-30 06:40:12.652319 | TASK [validate-host : Sanitize hostname]
2025-09-30 06:40:12.907238 | controller | ok
2025-09-30 06:40:12.949155 | 
2025-09-30 06:40:12.949285 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-09-30 06:40:14.686300 | controller -> localhost | changed
2025-09-30 06:40:14.701480 | 
2025-09-30 06:40:14.701550 | TASK [validate-host : Collect information about zuul worker]
2025-09-30 06:40:15.736375 | controller | ok
2025-09-30 06:40:15.745366 | 
2025-09-30 06:40:15.745439 | TASK [validate-host : Write out all zuul information for each host]
2025-09-30 06:40:17.454282 | controller -> localhost | changed
2025-09-30 06:40:17.467001 | 
2025-09-30 06:40:17.472009 | TASK [include_role : prepare-workspace-openshift]
2025-09-30 06:40:17.538516 | controller | skipping: Conditional result was False
2025-09-30 06:40:17.545246 | 
2025-09-30 06:40:17.545338 | TASK [include_role : remove-zuul-sshkey]
2025-09-30 06:40:17.614377 | controller | skipping: Conditional result was False
2025-09-30 06:40:17.620412 | 
2025-09-30 06:40:17.620493 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-09-30 06:40:18.033437 | controller | ok: "logs"
2025-09-30 06:40:18.033739 | controller | ok: All items complete
2025-09-30 06:40:18.033769 | 
2025-09-30 06:40:18.221516 | controller | ok: "artifacts"
2025-09-30 06:40:18.410854 | controller | ok: "docs"
2025-09-30 06:40:18.423387 | 
2025-09-30 06:40:18.423734 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-09-30 06:40:18.829989 | controller | changed: "logs"
2025-09-30 06:40:19.033173 | controller | changed: "artifacts"
2025-09-30 06:40:19.235182 | controller | changed: "docs"
2025-09-30 06:40:19.325104 | 
2025-09-30 06:40:19.325231 | PLAY RECAP
2025-09-30 06:40:19.325279 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-09-30 06:40:19.325308 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-09-30 06:40:19.325328 | 
2025-09-30 06:40:19.772534 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-09-30 06:40:19.773419 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-09-30 06:40:22.758663 | 
2025-09-30 06:40:22.758789 | PLAY [all]
2025-09-30 06:40:22.838541 | 
2025-09-30 06:40:22.838675 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-09-30 06:40:23.081507 | controller | ok
2025-09-30 06:40:23.095113 | 
2025-09-30 06:40:23.095200 | TASK [mirror-info-fork : Create /etc/ci]
2025-09-30 06:40:23.913673 | controller | changed
2025-09-30 06:40:23.929078 | 
2025-09-30 06:40:23.929170 | TASK [mirror-info-fork : Install ci_mirror script]
2025-09-30 06:40:26.036588 | controller | changed
2025-09-30 06:40:26.092886 | 
2025-09-30 06:40:26.093005 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-09-30 06:40:27.107494 | controller | changed:
2025-09-30 06:40:27.107680 | controller | {
2025-09-30 06:40:27.107715 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-09-30 06:40:27.107745 | controller | }
2025-09-30 06:40:27.358196 | controller | changed:
2025-09-30 06:40:27.358322 | controller | {
2025-09-30 06:40:27.358351 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-09-30 06:40:27.358374 | controller | }
2025-09-30 06:40:27.641931 | controller | changed:
2025-09-30 06:40:27.642063 | controller | {
2025-09-30 06:40:27.642094 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com"
2025-09-30 06:40:27.642117 | controller | }
2025-09-30 06:40:27.887471 | controller | changed:
2025-09-30 06:40:27.887559 | controller | {
2025-09-30 06:40:27.887586 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-09-30 06:40:27.887608 | controller | }
2025-09-30 06:40:28.129750 | controller | changed:
2025-09-30 06:40:28.129871 | controller | {
2025-09-30 06:40:28.129901 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-09-30 06:40:28.129943 | controller | }
2025-09-30 06:40:28.388857 | controller | changed:
2025-09-30 06:40:28.388979 | controller | {
2025-09-30 06:40:28.389011 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-09-30 06:40:28.389033 | controller | }
2025-09-30 06:40:28.648416 | controller | changed:
2025-09-30 06:40:28.648559 | controller | {
2025-09-30 06:40:28.648590 | 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-09-30 06:40:28.648612 | controller | }
2025-09-30 06:40:29.034442 | controller | changed:
2025-09-30 06:40:29.034529 | controller | {
2025-09-30 06:40:29.034557 | 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-09-30 06:40:29.034580 | controller | }
2025-09-30 06:40:29.146050 | controller | changed:
2025-09-30 06:40:29.146159 | controller | {
2025-09-30 06:40:29.146187 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-09-30 06:40:29.146207 | controller | }
2025-09-30 06:40:29.399996 | controller | changed:
2025-09-30 06:40:29.400082 | controller | {
2025-09-30 06:40:29.400109 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-09-30 06:40:29.400131 | controller | }
2025-09-30 06:40:29.631680 | controller | changed:
2025-09-30 06:40:29.631765 | controller | {
2025-09-30 06:40:29.631791 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-09-30 06:40:29.631811 | controller | }
2025-09-30 06:40:29.897615 | controller | changed:
2025-09-30 06:40:29.897708 | controller | {
2025-09-30 06:40:29.897735 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-09-30 06:40:29.897757 | controller | }
2025-09-30 06:40:30.160851 | controller | changed:
2025-09-30 06:40:30.161043 | controller | {
2025-09-30 06:40:30.161079 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-09-30 06:40:30.161102 | controller | }
2025-09-30 06:40:30.380167 | controller | changed:
2025-09-30 06:40:30.380253 | controller | {
2025-09-30 06:40:30.380281 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-09-30 06:40:30.380303 | controller | }
2025-09-30 06:40:30.636698 | controller | changed:
2025-09-30 06:40:30.636784 | controller | {
2025-09-30 06:40:30.636813 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-09-30 06:40:30.636835 | controller | }
2025-09-30 06:40:30.907720 | controller | changed:
2025-09-30 06:40:30.907809 | controller | {
2025-09-30 06:40:30.907860 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-09-30 06:40:30.907883 | controller | }
2025-09-30 06:40:31.168405 | controller | changed:
2025-09-30 06:40:31.168497 | controller | {
2025-09-30 06:40:31.168525 | 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-09-30 06:40:31.168547 | controller | }
2025-09-30 06:40:31.434402 | controller | changed:
2025-09-30 06:40:31.434486 | controller | {
2025-09-30 06:40:31.434513 | 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-09-30 06:40:31.434542 | controller | }
2025-09-30 06:40:31.675701 | controller | changed:
2025-09-30 06:40:31.675787 | controller | {
2025-09-30 06:40:31.675814 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-09-30 06:40:31.675833 | controller | }
2025-09-30 06:40:31.921681 | controller | changed:
2025-09-30 06:40:31.921770 | controller | {
2025-09-30 06:40:31.924808 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-09-30 06:40:31.924867 | controller | }
2025-09-30 06:40:32.147646 | controller | changed:
2025-09-30 06:40:32.147724 | controller | {
2025-09-30 06:40:32.147751 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-09-30 06:40:32.147772 | controller | }
2025-09-30 06:40:32.405424 | controller | changed:
2025-09-30 06:40:32.405511 | controller | {
2025-09-30 06:40:32.405539 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-09-30 06:40:32.405560 | controller | }
2025-09-30 06:40:32.650750 | controller | changed:
2025-09-30 06:40:32.650862 | controller | {
2025-09-30 06:40:32.650891 | 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-09-30 06:40:32.650934 | controller | }
2025-09-30 06:40:32.915655 | controller | changed:
2025-09-30 06:40:32.915749 | controller | {
2025-09-30 06:40:32.915776 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-09-30 06:40:32.915796 | controller | }
2025-09-30 06:40:33.204335 | controller | changed:
2025-09-30 06:40:33.204432 | controller | {
2025-09-30 06:40:33.204459 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-09-30 06:40:33.204480 | controller | }
2025-09-30 06:40:33.464817 | controller | changed:
2025-09-30 06:40:33.464997 | controller | {
2025-09-30 06:40:33.465030 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-09-30 06:40:33.465053 | controller | }
2025-09-30 06:40:33.521191 | 
2025-09-30 06:40:33.521292 | TASK [Set timezone to UTC]
2025-09-30 06:40:34.914626 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-09-30 06:40:34.932735 | 
2025-09-30 06:40:34.932808 | TASK [Create nodepool directory]
2025-09-30 06:40:35.260890 | controller | changed
2025-09-30 06:40:35.292298 | 
2025-09-30 06:40:35.292401 | TASK [Create nodepool sub_nodes file]
2025-09-30 06:40:36.168675 | controller | changed
2025-09-30 06:40:36.174968 | 
2025-09-30 06:40:36.175041 | TASK [Create nodepool sub_nodes_private file]
2025-09-30 06:40:37.101106 | controller | changed
2025-09-30 06:40:37.110452 | 
2025-09-30 06:40:37.110532 | LOOP [Populate nodepool sub_nodes file]
2025-09-30 06:40:37.283161 | 
2025-09-30 06:40:37.283325 | LOOP [Populate nodepool sub_nodes_private file]
2025-09-30 06:40:37.454422 | 
2025-09-30 06:40:37.454655 | TASK [Create nodepool primary file]
2025-09-30 06:40:37.583648 | controller | skipping: Conditional result was False
2025-09-30 06:40:37.590656 | 
2025-09-30 06:40:37.590761 | TASK [Create nodepool node_private for this node]
2025-09-30 06:40:38.548953 | controller | changed
2025-09-30 06:40:38.558461 | 
2025-09-30 06:40:38.558529 | LOOP [Copy ssh keys to nodepool directory]
2025-09-30 06:40:40.100498 | controller | ok: Item: id_rsa Runtime: 0:00:00.007633
2025-09-30 06:40:40.100686 | 
2025-09-30 06:40:40.320532 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.005218
2025-09-30 06:40:40.334605 | 
2025-09-30 06:40:40.334709 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-09-30 06:40:41.209619 | controller | changed
2025-09-30 06:40:41.215806 | 
2025-09-30 06:40:41.215889 | TASK [Validate sudoers config after edits]
2025-09-30 06:40:41.768688 | controller | /etc/sudoers: parsed OK
2025-09-30 06:40:41.768846 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-09-30 06:40:41.768859 | controller | /etc/sudoers.d/zuul: parsed OK
2025-09-30 06:40:41.768870 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-09-30 06:40:41.888195 | controller | ok: Runtime: 0:00:00.006377
2025-09-30 06:40:41.894145 | 
2025-09-30 06:40:41.894211 | TASK [Show the environment passed in to job shell scripts]
2025-09-30 06:40:42.365896 | controller | SHELL=/bin/bash
2025-09-30 06:40:42.365951 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-09-30 06:40:42.365961 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-09-30 06:40:42.366010 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/46/3346/eb5b53fb17db92a72d33d052a56c8edd0d10ea78^openstack-k8s-operators/ci-framework:main:refs/changes/33/3333/b461fc761ffaeed72eedc04b84a1fb92d251fe5d
2025-09-30 06:40:42.366042 | controller | PWD=/home/zuul
2025-09-30 06:40:42.366052 | controller | ZUUL_PIPELINE=github-check
2025-09-30 06:40:42.366058 | controller | LOGNAME=zuul
2025-09-30 06:40:42.366064 | controller | XDG_SESSION_TYPE=tty
2025-09-30 06:40:42.366069 | controller | _=/usr/bin/env
2025-09-30 06:40:42.366075 | controller | MOTD_SHOWN=pam
2025-09-30 06:40:42.366080 | controller | HOME=/home/zuul
2025-09-30 06:40:42.366085 | controller | LANG=en_US.UTF-8
2025-09-30 06:40:42.366091 | controller | SSH_CONNECTION=192.168.26.12 60014 192.168.26.111 22
2025-09-30 06:40:42.366096 | 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-09-30 06:40:42.366102 | controller | ZUUL_CHANGE_IDS=3346,eb5b53fb17db92a72d33d052a56c8edd0d10ea78 3333,b461fc761ffaeed72eedc04b84a1fb92d251fe5d
2025-09-30 06:40:42.366107 | controller | WORKSPACE=/home/zuul/workspace
2025-09-30 06:40:42.366113 | controller | XDG_SESSION_CLASS=user
2025-09-30 06:40:42.366120 | controller | SELINUX_ROLE_REQUESTED=
2025-09-30 06:40:42.366126 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-09-30 06:40:42.366131 | controller | USER=zuul
2025-09-30 06:40:42.366136 | controller | ZUUL_VOTING=True
2025-09-30 06:40:42.366142 | controller | BUILD_TIMEOUT=1800000
2025-09-30 06:40:42.366147 | controller | SELINUX_USE_CURRENT_RANGE=
2025-09-30 06:40:42.366152 | controller | SHLVL=1
2025-09-30 06:40:42.366157 | controller | ZUUL_PATCHSET=b461fc761ffaeed72eedc04b84a1fb92d251fe5d
2025-09-30 06:40:42.366163 | controller | XDG_SESSION_ID=1
2025-09-30 06:40:42.366168 | controller | ZUUL_BRANCH=main
2025-09-30 06:40:42.366173 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-09-30 06:40:42.366179 | controller | SSH_CLIENT=192.168.26.12 60014 22
2025-09-30 06:40:42.366184 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-09-30 06:40:42.366189 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-09-30 06:40:42.366195 | controller | which_declare=declare -f
2025-09-30 06:40:42.366200 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2025-09-30 06:40:42.366206 | controller | SELINUX_LEVEL_REQUESTED=
2025-09-30 06:40:42.366212 | controller | ZUUL_CHANGE=3333
2025-09-30 06:40:42.366217 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-09-30 06:40:42.366222 | controller | ZUUL_UUID=0f9667c155b24790be8b9199741cc743
2025-09-30 06:40:42.366227 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-09-30 06:40:42.366232 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-09-30 06:40:42.366238 | controller | }
2025-09-30 06:40:42.526741 | controller | ok: Runtime: 0:00:00.006114
2025-09-30 06:40:42.532566 | 
2025-09-30 06:40:42.532637 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-09-30 06:40:42.607684 | controller | skipping: Conditional result was False
2025-09-30 06:40:42.895141 | 
2025-09-30 06:40:42.895269 | TASK [Symlink /home/zuul-worker/workspace]
2025-09-30 06:40:43.467720 | controller | skipping: Conditional result was False
2025-09-30 06:40:43.473656 | 
2025-09-30 06:40:43.473729 | TASK [Ensure legacy workspace directory]
2025-09-30 06:40:43.841703 | controller | changed
2025-09-30 06:40:43.950406 | 
2025-09-30 06:40:43.950512 | PLAY RECAP
2025-09-30 06:40:43.950565 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-09-30 06:40:43.950590 | 
2025-09-30 06:40:44.737226 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-09-30 06:40:44.738067 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/read_global_vars.yml@main]
2025-09-30 06:40:48.187592 | 
2025-09-30 06:40:48.187726 | PLAY [Load global variables]
2025-09-30 06:40:48.327026 | 
2025-09-30 06:40:48.327274 | TASK [Read group_vars all file]
2025-09-30 06:40:48.448274 | controller | ok
2025-09-30 06:40:48.543664 | 
2025-09-30 06:40:48.543823 | TASK [cifmw_helpers : Check if file is available]
2025-09-30 06:40:49.339243 | controller | ok
2025-09-30 06:40:49.357158 | 
2025-09-30 06:40:49.357241 | TASK [cifmw_helpers : Read vars]
2025-09-30 06:40:49.479281 | 
2025-09-30 06:40:49.479559 | LOOP [cifmw_helpers : Set vars as fact]
2025-09-30 06:40:49.755107 | 
2025-09-30 06:40:49.755299 | PLAY RECAP
2025-09-30 06:40:49.755358 | controller | ok: 1 changed: 0 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-09-30 06:40:49.755382 | 
2025-09-30 06:40:50.690637 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/read_global_vars.yml@main]
2025-09-30 06:40:50.697337 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-09-30 06:40:54.591197 | 
2025-09-30 06:40:54.591388 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-09-30 06:40:54.669415 | 
2025-09-30 06:40:54.669531 | TASK [Create zuul-output directory]
2025-09-30 06:40:56.014539 | controller | changed
2025-09-30 06:40:56.020066 | 
2025-09-30 06:40:56.020147 | TASK [Slurp Zuul inventory test]
2025-09-30 06:40:58.292596 | controller -> localhost | ok
2025-09-30 06:40:58.300186 | 
2025-09-30 06:40:58.300262 | TASK [Save zuul inventory]
2025-09-30 06:41:00.673593 | controller | changed
2025-09-30 06:41:00.723089 | 
2025-09-30 06:41:00.723228 | TASK [Save zuul vars without the change_message]
2025-09-30 06:41:02.259093 | controller | changed
2025-09-30 06:41:02.545180 | 
2025-09-30 06:41:02.545429 | PLAY RECAP
2025-09-30 06:41:02.545494 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-09-30 06:41:02.545521 | 
2025-09-30 06:41:03.542678 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-09-30 06:41:03.543601 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-09-30 06:41:06.909326 | 
2025-09-30 06:41:06.909520 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2025-09-30 06:41:07.064222 | 
2025-09-30 06:41:07.064439 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2025-09-30 06:41:07.183522 | controller | ok
2025-09-30 06:41:07.350567 | 
2025-09-30 06:41:07.350803 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-09-30 06:41:07.491341 | controller | skipping: Conditional result was False
2025-09-30 06:41:07.498074 | 
2025-09-30 06:41:07.498150 | TASK [mirror-info-fork : Create /etc/ci]
2025-09-30 06:41:08.543872 | controller | ok
2025-09-30 06:41:08.557017 | 
2025-09-30 06:41:08.557120 | TASK [mirror-info-fork : Install ci_mirror script]
2025-09-30 06:41:10.122649 | controller | ok
2025-09-30 06:41:10.175278 | 
2025-09-30 06:41:10.175398 | TASK [Prepare workspace]
2025-09-30 06:41:10.260516 | controller | ok
2025-09-30 06:41:10.365097 | 
2025-09-30 06:41:10.365208 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-09-30 06:41:11.132295 | controller | ok
2025-09-30 06:41:11.141983 | 
2025-09-30 06:41:11.142056 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-09-30 06:41:19.058496 | controller | Output suppressed because no_log was given
2025-09-30 06:41:19.081245 | 
2025-09-30 06:41:19.081337 | LOOP [Create zuul-output directory]
2025-09-30 06:41:19.398163 | controller | changed: "/home/zuul/ci-framework-data/logs"
2025-09-30 06:41:19.605571 | controller | ok: "/home/zuul/zuul-output/logs"
2025-09-30 06:41:19.623253 | 
2025-09-30 06:41:19.623389 | TASK [Install required packages]
2025-09-30 06:42:12.303363 | controller | changed
2025-09-30 06:42:12.308952 | 
2025-09-30 06:42:12.309018 | TASK [Install venv]
2025-09-30 06:43:18.187887 | controller | changed
2025-09-30 06:43:18.213180 | 
2025-09-30 06:43:18.213262 | PLAY RECAP
2025-09-30 06:43:18.213311 | controller | ok: 7 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-09-30 06:43:18.213334 | 
2025-09-30 06:43:18.308681 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-09-30 06:43:18.309517 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-09-30 06:43:18.874414 | 
2025-09-30 06:43:18.874534 | PLAY [Run ci/playbooks/molecule-test.yml]
2025-09-30 06:43:18.895482 | 
2025-09-30 06:43:18.895555 | TASK [Gather required facts]
2025-09-30 06:43:19.394148 | controller | ok
2025-09-30 06:43:19.401346 | 
2025-09-30 06:43:19.401427 | TASK [Load environment var if instructed to]
2025-09-30 06:43:19.436391 | controller | skipping: Conditional result was False
2025-09-30 06:43:19.442151 | 
2025-09-30 06:43:19.442243 | TASK [Run molecule]
2025-09-30 06:43:20.420499 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2025-09-30 06:43:20.489700 | controller | INFO     Performing prerun with role_name_check=0...
2025-09-30 06:43:37.505734 | 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-09-30 06:43:37.506194 | 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-09-30 06:43:37.506696 | 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-09-30 06:43:37.507165 | 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-09-30 06:43:37.507671 | controller | WARNING  Another version of 'cifmw.general' 1.0.0+7e3734e2 was found installed in /home/zuul/.cache/ansible-compat/29a3e1/collections/ansible_collections, only the first one will be used, 1.0.0+7e3734e2 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-30 06:43:37.508133 | 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-09-30 06:43:37.508594 | 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-09-30 06:43:37.509045 | 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-09-30 06:43:37.509507 | 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-09-30 06:43:37.509915 | 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-09-30 06:43:37.510388 | 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-09-30 06:43:37.510834 | 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-09-30 06:43:37.511320 | 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-09-30 06:43:37.511764 | 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-09-30 06:43:37.512238 | 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-09-30 06:43:37.521832 | controller | INFO     Running default > prepare
2025-09-30 06:43:37.522315 | controller | WARNING  Skipping, prepare playbook not configured.
2025-09-30 06:43:37.529778 | controller | INFO     Running default > converge
2025-09-30 06:43:38.195140 | controller |
2025-09-30 06:43:38.195207 | controller | PLAY [Converge test of controller tasks] ***************************************
2025-09-30 06:43:38.195331 | controller |
2025-09-30 06:43:38.195440 | controller | TASK [Gathering Facts] *********************************************************
2025-09-30 06:43:38.195544 | controller | Tuesday 30 September 2025  06:43:38 +0000 (0:00:00.010)       0:00:00.010 *****
2025-09-30 06:43:39.010942 | controller | ok: [instance]
2025-09-30 06:43:39.011053 | controller |
2025-09-30 06:43:39.011180 | controller | TASK [compliance : Ensure compliance folder exists] ****************************
2025-09-30 06:43:39.011330 | controller | Tuesday 30 September 2025  06:43:39 +0000 (0:00:00.816)       0:00:00.827 *****
2025-09-30 06:43:39.320225 | controller | changed: [instance]
2025-09-30 06:43:39.320288 | controller |
2025-09-30 06:43:39.320394 | controller | TASK [compliance : Install the compliance operator and plugin] *****************
2025-09-30 06:43:39.320502 | controller | Tuesday 30 September 2025  06:43:39 +0000 (0:00:00.309)       0:00:01.136 *****
2025-09-30 06:43:39.355553 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/install.yml for instance
2025-09-30 06:43:39.355650 | controller |
2025-09-30 06:43:39.355784 | controller | TASK [compliance : Create the compliance namespace] ****************************
2025-09-30 06:43:39.355919 | controller | Tuesday 30 September 2025  06:43:39 +0000 (0:00:00.035)       0:00:01.172 *****
2025-09-30 06:43:39.381774 | controller | skipping: [instance]
2025-09-30 06:43:39.381881 | controller |
2025-09-30 06:43:39.382037 | controller | TASK [compliance : Ensure OperatorGroup for the compliance-operator is present] ***
2025-09-30 06:43:39.382179 | controller | Tuesday 30 September 2025  06:43:39 +0000 (0:00:00.026)       0:00:01.198 *****
2025-09-30 06:43:39.407318 | controller | skipping: [instance]
2025-09-30 06:43:39.407447 | controller |
2025-09-30 06:43:39.407561 | controller | TASK [compliance : Ensure Subscription for the compliance-operator is present] ***
2025-09-30 06:43:39.407664 | controller | Tuesday 30 September 2025  06:43:39 +0000 (0:00:00.025)       0:00:01.223 *****
2025-09-30 06:43:39.435812 | controller | skipping: [instance]
2025-09-30 06:43:39.435925 | controller |
2025-09-30 06:43:39.436073 | controller | TASK [compliance : Wait until the compliance-operator csv is present] **********
2025-09-30 06:43:39.436205 | controller | Tuesday 30 September 2025  06:43:39 +0000 (0:00:00.028)       0:00:01.252 *****
2025-09-30 06:43:39.461957 | controller | skipping: [instance]
2025-09-30 06:43:39.462091 | controller |
2025-09-30 06:43:39.462223 | controller | TASK [compliance : Get full name of the compliance-operator csv] ***************
2025-09-30 06:43:39.462369 | controller | Tuesday 30 September 2025  06:43:39 +0000 (0:00:00.026)       0:00:01.278 *****
2025-09-30 06:43:39.487837 | controller | skipping: [instance]
2025-09-30 06:43:39.487943 | controller |
2025-09-30 06:43:39.488102 | controller | TASK [compliance : Wait for the compliance-operator csv to Succeed] ************
2025-09-30 06:43:39.488238 | controller | Tuesday 30 September 2025  06:43:39 +0000 (0:00:00.025)       0:00:01.304 *****
2025-09-30 06:43:39.515941 | controller | skipping: [instance]
2025-09-30 06:43:39.516062 | controller |
2025-09-30 06:43:39.516197 | controller | TASK [podman : Ensure podman is installed] *************************************
2025-09-30 06:43:39.516339 | controller | Tuesday 30 September 2025  06:43:39 +0000 (0:00:00.027)       0:00:01.332 *****
2025-09-30 06:43:40.307941 | controller | ok: [instance]
2025-09-30 06:43:40.308397 | controller |
2025-09-30 06:43:40.308524 | controller | TASK [podman : Enable loginctl linger for ansible_user_id] *********************
2025-09-30 06:43:40.308646 | controller | Tuesday 30 September 2025  06:43:40 +0000 (0:00:00.791)       0:00:02.124 *****
2025-09-30 06:43:40.643192 | controller | changed: [instance]
2025-09-30 06:43:40.643262 | controller |
2025-09-30 06:43:40.643377 | controller | TASK [podman : Ensure subordinate UID entry exists for zuul] *******************
2025-09-30 06:43:40.643489 | controller | Tuesday 30 September 2025  06:43:40 +0000 (0:00:00.335)       0:00:02.459 *****
2025-09-30 06:43:40.665391 | controller | skipping: [instance]
2025-09-30 06:43:40.665495 | controller |
2025-09-30 06:43:40.665607 | controller | TASK [podman : Ensure subordinate GID entry exists for zuul] *******************
2025-09-30 06:43:40.665713 | controller | Tuesday 30 September 2025  06:43:40 +0000 (0:00:00.022)       0:00:02.482 *****
2025-09-30 06:43:40.684147 | controller | skipping: [instance]
2025-09-30 06:43:40.684249 | controller |
2025-09-30 06:43:40.684382 | controller | TASK [podman : Run podman system migrate if subuid/subgid files were changed] ***
2025-09-30 06:43:40.684493 | controller | Tuesday 30 September 2025  06:43:40 +0000 (0:00:00.018)       0:00:02.500 *****
2025-09-30 06:43:40.707679 | controller | skipping: [instance]
2025-09-30 06:43:40.707747 | controller |
2025-09-30 06:43:40.707782 | controller | TASK [compliance : Log into registry] ******************************************
2025-09-30 06:43:40.707889 | controller | Tuesday 30 September 2025  06:43:40 +0000 (0:00:00.023)       0:00:02.524 *****
2025-09-30 06:43:40.724473 | controller | skipping: [instance]
2025-09-30 06:43:40.724549 | controller |
2025-09-30 06:43:40.724657 | controller | TASK [compliance : Copy out the compliance plugin from the oc-compliance image] ***
2025-09-30 06:43:40.724767 | controller | Tuesday 30 September 2025  06:43:40 +0000 (0:00:00.016)       0:00:02.541 *****
2025-09-30 06:43:40.740656 | controller | skipping: [instance]
2025-09-30 06:43:40.740745 | controller |
2025-09-30 06:43:40.740855 | controller | TASK [compliance : Install packages to process results] ************************
2025-09-30 06:43:40.740966 | controller | Tuesday 30 September 2025  06:43:40 +0000 (0:00:00.016)       0:00:02.557 *****
2025-09-30 06:43:44.744323 | controller | changed: [instance]
2025-09-30 06:43:44.744623 | controller |
2025-09-30 06:43:44.787144 | controller | TASK [compliance : Run compliance scans and extract results] *******************
2025-09-30 06:43:44.787168 | controller | Tuesday 30 September 2025  06:43:44 +0000 (0:00:04.003)       0:00:06.560 *****
2025-09-30 06:43:44.787182 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_suite_scan.yml for instance => (item=cis)
2025-09-30 06:43:44.787532 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_suite_scan.yml for instance => (item=e8)
2025-09-30 06:43:44.787548 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_suite_scan.yml for instance => (item=high)
2025-09-30 06:43:44.787555 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_suite_scan.yml for instance => (item=moderate)
2025-09-30 06:43:44.787561 | 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-09-30 06:43:44.787567 | 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-09-30 06:43:44.787575 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_suite_scan.yml for instance => (item=stig)
2025-09-30 06:43:44.804918 | controller |
2025-09-30 06:43:44.804935 | controller | TASK [compliance : Log start of scans] *****************************************
2025-09-30 06:43:44.804943 | controller | Tuesday 30 September 2025  06:43:44 +0000 (0:00:00.042)       0:00:06.603 *****
2025-09-30 06:43:44.804952 | controller | ok: [instance] =>
2025-09-30 06:43:44.831852 | controller |   msg: Starting scans for cis
2025-09-30 06:43:44.831870 | controller |
2025-09-30 06:43:44.831878 | controller | TASK [compliance : Run scans and extract results] ******************************
2025-09-30 06:43:44.831884 | controller | Tuesday 30 September 2025  06:43:44 +0000 (0:00:00.018)       0:00:06.621 *****
2025-09-30 06:43:44.831893 | 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-09-30 06:43:44.854444 | controller |
2025-09-30 06:43:44.854463 | controller | TASK [compliance : Set output directory] ***************************************
2025-09-30 06:43:44.854470 | controller | Tuesday 30 September 2025  06:43:44 +0000 (0:00:00.026)       0:00:06.648 *****
2025-09-30 06:43:44.854487 | controller | ok: [instance]
2025-09-30 06:43:44.870309 | controller |
2025-09-30 06:43:44.870327 | controller | TASK [compliance : Create scan binding] ****************************************
2025-09-30 06:43:44.870335 | controller | Tuesday 30 September 2025  06:43:44 +0000 (0:00:00.022)       0:00:06.670 *****
2025-09-30 06:43:44.870347 | controller | skipping: [instance]
2025-09-30 06:43:44.885858 | controller |
2025-09-30 06:43:44.885876 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-09-30 06:43:44.885883 | controller | Tuesday 30 September 2025  06:43:44 +0000 (0:00:00.016)       0:00:06.686 *****
2025-09-30 06:43:44.885891 | controller | skipping: [instance]
2025-09-30 06:43:45.060514 | controller |
2025-09-30 06:43:45.060542 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-09-30 06:43:45.060550 | controller | Tuesday 30 September 2025  06:43:44 +0000 (0:00:00.015)       0:00:06.702 *****
2025-09-30 06:43:45.060560 | controller | ok: [instance]
2025-09-30 06:43:45.241113 | controller |
2025-09-30 06:43:45.241144 | controller | TASK [compliance : Create directory for scan results] **************************
2025-09-30 06:43:45.241153 | controller | Tuesday 30 September 2025  06:43:45 +0000 (0:00:00.174)       0:00:06.876 *****
2025-09-30 06:43:45.241164 | controller | changed: [instance]
2025-09-30 06:43:45.257427 | controller |
2025-09-30 06:43:45.257449 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-09-30 06:43:45.257458 | controller | Tuesday 30 September 2025  06:43:45 +0000 (0:00:00.180)       0:00:07.057 *****
2025-09-30 06:43:45.257467 | controller | skipping: [instance]
2025-09-30 06:43:45.518151 | controller |
2025-09-30 06:43:45.518179 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-09-30 06:43:45.518187 | controller | Tuesday 30 September 2025  06:43:45 +0000 (0:00:00.016)       0:00:07.073 *****
2025-09-30 06:43:45.518197 | controller | ok: [instance]
2025-09-30 06:43:45.532558 | controller |
2025-09-30 06:43:45.532578 | controller | TASK [compliance : Process scan result files] **********************************
2025-09-30 06:43:45.532585 | controller | Tuesday 30 September 2025  06:43:45 +0000 (0:00:00.260)       0:00:07.334 *****
2025-09-30 06:43:45.532594 | controller | skipping: [instance]
2025-09-30 06:43:45.551176 | controller |
2025-09-30 06:43:45.551194 | controller | TASK [compliance : Log start of scans] *****************************************
2025-09-30 06:43:45.551201 | controller | Tuesday 30 September 2025  06:43:45 +0000 (0:00:00.014)       0:00:07.349 *****
2025-09-30 06:43:45.551210 | controller | ok: [instance] =>
2025-09-30 06:43:45.582120 | controller |   msg: Starting scans for e8
2025-09-30 06:43:45.582144 | controller |
2025-09-30 06:43:45.582154 | controller | TASK [compliance : Run scans and extract results] ******************************
2025-09-30 06:43:45.582162 | controller | Tuesday 30 September 2025  06:43:45 +0000 (0:00:00.018)       0:00:07.367 *****
2025-09-30 06:43:45.582173 | 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-09-30 06:43:45.582202 | 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-09-30 06:43:45.582531 | controller |
2025-09-30 06:43:45.604971 | controller | TASK [compliance : Set output directory] ***************************************
2025-09-30 06:43:45.604993 | controller | Tuesday 30 September 2025  06:43:45 +0000 (0:00:00.031)       0:00:07.398 *****
2025-09-30 06:43:45.605005 | controller | ok: [instance]
2025-09-30 06:43:45.621845 | controller |
2025-09-30 06:43:45.621866 | controller | TASK [compliance : Create scan binding] ****************************************
2025-09-30 06:43:45.621873 | controller | Tuesday 30 September 2025  06:43:45 +0000 (0:00:00.022)       0:00:07.421 *****
2025-09-30 06:43:45.621883 | controller | skipping: [instance]
2025-09-30 06:43:45.638101 | controller |
2025-09-30 06:43:45.638126 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-09-30 06:43:45.638135 | controller | Tuesday 30 September 2025  06:43:45 +0000 (0:00:00.016)       0:00:07.438 *****
2025-09-30 06:43:45.638147 | controller | skipping: [instance]
2025-09-30 06:43:45.638174 | controller |
2025-09-30 06:43:45.638405 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-09-30 06:43:45.812766 | controller | Tuesday 30 September 2025  06:43:45 +0000 (0:00:00.016)       0:00:07.454 *****
2025-09-30 06:43:45.812798 | controller | ok: [instance]
2025-09-30 06:43:45.993191 | controller |
2025-09-30 06:43:45.993221 | controller | TASK [compliance : Create directory for scan results] **************************
2025-09-30 06:43:45.993229 | controller | Tuesday 30 September 2025  06:43:45 +0000 (0:00:00.174)       0:00:07.629 *****
2025-09-30 06:43:45.993238 | controller | changed: [instance]
2025-09-30 06:43:46.009138 | controller |
2025-09-30 06:43:46.009163 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-09-30 06:43:46.009172 | controller | Tuesday 30 September 2025  06:43:45 +0000 (0:00:00.180)       0:00:07.809 *****
2025-09-30 06:43:46.009187 | controller | skipping: [instance]
2025-09-30 06:43:46.179532 | controller |
2025-09-30 06:43:46.179561 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-09-30 06:43:46.179570 | controller | Tuesday 30 September 2025  06:43:46 +0000 (0:00:00.016)       0:00:07.825 *****
2025-09-30 06:43:46.179580 | controller | ok: [instance]
2025-09-30 06:43:46.193741 | controller |
2025-09-30 06:43:46.193763 | controller | TASK [compliance : Process scan result files] **********************************
2025-09-30 06:43:46.193772 | controller | Tuesday 30 September 2025  06:43:46 +0000 (0:00:00.170)       0:00:07.995 *****
2025-09-30 06:43:46.193783 | controller | skipping: [instance]
2025-09-30 06:43:46.216101 | controller |
2025-09-30 06:43:46.216143 | controller | TASK [compliance : Set output directory] ***************************************
2025-09-30 06:43:46.216152 | controller | Tuesday 30 September 2025  06:43:46 +0000 (0:00:00.014)       0:00:08.010 *****
2025-09-30 06:43:46.216165 | controller | ok: [instance]
2025-09-30 06:43:46.232987 | controller |
2025-09-30 06:43:46.233019 | controller | TASK [compliance : Create scan binding] ****************************************
2025-09-30 06:43:46.233062 | controller | Tuesday 30 September 2025  06:43:46 +0000 (0:00:00.022)       0:00:08.032 *****
2025-09-30 06:43:46.233075 | controller | skipping: [instance]
2025-09-30 06:43:46.249144 | controller |
2025-09-30 06:43:46.249184 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-09-30 06:43:46.249194 | controller | Tuesday 30 September 2025  06:43:46 +0000 (0:00:00.016)       0:00:08.049 *****
2025-09-30 06:43:46.249207 | controller | skipping: [instance]
2025-09-30 06:43:46.249236 | controller |
2025-09-30 06:43:46.249246 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-09-30 06:43:46.249375 | controller | Tuesday 30 September 2025  06:43:46 +0000 (0:00:00.016)       0:00:08.065 *****
2025-09-30 06:43:46.426900 | controller | ok: [instance]
2025-09-30 06:43:46.615303 | controller |
2025-09-30 06:43:46.615334 | controller | TASK [compliance : Create directory for scan results] **************************
2025-09-30 06:43:46.615343 | controller | Tuesday 30 September 2025  06:43:46 +0000 (0:00:00.177)       0:00:08.243 *****
2025-09-30 06:43:46.615353 | controller | changed: [instance]
2025-09-30 06:43:46.631922 | controller |
2025-09-30 06:43:46.631948 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-09-30 06:43:46.631957 | controller | Tuesday 30 September 2025  06:43:46 +0000 (0:00:00.188)       0:00:08.431 *****
2025-09-30 06:43:46.631968 | controller | skipping: [instance]
2025-09-30 06:43:46.807167 | controller |
2025-09-30 06:43:46.807197 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-09-30 06:43:46.807205 | controller | Tuesday 30 September 2025  06:43:46 +0000 (0:00:00.016)       0:00:08.448 *****
2025-09-30 06:43:46.807215 | controller | ok: [instance]
2025-09-30 06:43:46.821078 | controller |
2025-09-30 06:43:46.821096 | controller | TASK [compliance : Process scan result files] **********************************
2025-09-30 06:43:46.821104 | controller | Tuesday 30 September 2025  06:43:46 +0000 (0:00:00.174)       0:00:08.623 *****
2025-09-30 06:43:46.821113 | controller | skipping: [instance]
2025-09-30 06:43:46.821471 | controller |
2025-09-30 06:43:46.840134 | controller | TASK [compliance : Log start of scans] *****************************************
2025-09-30 06:43:46.840167 | controller | Tuesday 30 September 2025  06:43:46 +0000 (0:00:00.014)       0:00:08.637 *****
2025-09-30 06:43:46.840179 | controller | ok: [instance] =>
2025-09-30 06:43:46.840205 | controller |   msg: Starting scans for high
2025-09-30 06:43:46.840235 | controller |
2025-09-30 06:43:46.840498 | controller | TASK [compliance : Run scans and extract results] ******************************
2025-09-30 06:43:46.878210 | controller | Tuesday 30 September 2025  06:43:46 +0000 (0:00:00.018)       0:00:08.656 *****
2025-09-30 06:43:46.878255 | 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-09-30 06:43:46.878300 | 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-09-30 06:43:46.878314 | 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-09-30 06:43:46.878324 | controller |
2025-09-30 06:43:46.878518 | controller | TASK [compliance : Set output directory] ***************************************
2025-09-30 06:43:46.901583 | controller | Tuesday 30 September 2025  06:43:46 +0000 (0:00:00.037)       0:00:08.694 *****
2025-09-30 06:43:46.901613 | controller | ok: [instance]
2025-09-30 06:43:46.918458 | controller |
2025-09-30 06:43:46.918480 | controller | TASK [compliance : Create scan binding] ****************************************
2025-09-30 06:43:46.918489 | controller | Tuesday 30 September 2025  06:43:46 +0000 (0:00:00.023)       0:00:08.717 *****
2025-09-30 06:43:46.918500 | controller | skipping: [instance]
2025-09-30 06:43:46.934772 | controller |
2025-09-30 06:43:46.934790 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-09-30 06:43:46.934797 | controller | Tuesday 30 September 2025  06:43:46 +0000 (0:00:00.016)       0:00:08.734 *****
2025-09-30 06:43:46.934806 | controller | skipping: [instance]
2025-09-30 06:43:47.113661 | controller |
2025-09-30 06:43:47.113693 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-09-30 06:43:47.113706 | controller | Tuesday 30 September 2025  06:43:46 +0000 (0:00:00.016)       0:00:08.751 *****
2025-09-30 06:43:47.113717 | controller | ok: [instance]
2025-09-30 06:43:47.303836 | controller |
2025-09-30 06:43:47.303874 | controller | TASK [compliance : Create directory for scan results] **************************
2025-09-30 06:43:47.303882 | controller | Tuesday 30 September 2025  06:43:47 +0000 (0:00:00.178)       0:00:08.929 *****
2025-09-30 06:43:47.303893 | controller | changed: [instance]
2025-09-30 06:43:47.321187 | controller |
2025-09-30 06:43:47.321221 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-09-30 06:43:47.321229 | controller | Tuesday 30 September 2025  06:43:47 +0000 (0:00:00.190)       0:00:09.120 *****
2025-09-30 06:43:47.321239 | controller | skipping: [instance]
2025-09-30 06:43:47.321298 | controller |
2025-09-30 06:43:47.321309 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-09-30 06:43:47.321446 | controller | Tuesday 30 September 2025  06:43:47 +0000 (0:00:00.017)       0:00:09.137 *****
2025-09-30 06:43:47.496100 | controller | ok: [instance]
2025-09-30 06:43:47.496460 | controller |
2025-09-30 06:43:47.510778 | controller | TASK [compliance : Process scan result files] **********************************
2025-09-30 06:43:47.510798 | controller | Tuesday 30 September 2025  06:43:47 +0000 (0:00:00.174)       0:00:09.312 *****
2025-09-30 06:43:47.510808 | controller | skipping: [instance]
2025-09-30 06:43:47.534493 | controller |
2025-09-30 06:43:47.534512 | controller | TASK [compliance : Set output directory] ***************************************
2025-09-30 06:43:47.534519 | controller | Tuesday 30 September 2025  06:43:47 +0000 (0:00:00.014)       0:00:09.327 *****
2025-09-30 06:43:47.534528 | controller | ok: [instance]
2025-09-30 06:43:47.551443 | controller |
2025-09-30 06:43:47.551460 | controller | TASK [compliance : Create scan binding] ****************************************
2025-09-30 06:43:47.551467 | controller | Tuesday 30 September 2025  06:43:47 +0000 (0:00:00.023)       0:00:09.350 *****
2025-09-30 06:43:47.551476 | controller | skipping: [instance]
2025-09-30 06:43:47.568128 | controller |
2025-09-30 06:43:47.568164 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-09-30 06:43:47.568173 | controller | Tuesday 30 September 2025  06:43:47 +0000 (0:00:00.016)       0:00:09.367 *****
2025-09-30 06:43:47.568184 | controller | skipping: [instance]
2025-09-30 06:43:47.568217 | controller |
2025-09-30 06:43:47.568225 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-09-30 06:43:47.568306 | controller | Tuesday 30 September 2025  06:43:47 +0000 (0:00:00.016)       0:00:09.384 *****
2025-09-30 06:43:47.748067 | controller | ok: [instance]
2025-09-30 06:43:47.930969 | controller |
2025-09-30 06:43:47.931001 | controller | TASK [compliance : Create directory for scan results] **************************
2025-09-30 06:43:47.931010 | controller | Tuesday 30 September 2025  06:43:47 +0000 (0:00:00.179)       0:00:09.564 *****
2025-09-30 06:43:47.931035 | controller | changed: [instance]
2025-09-30 06:43:47.947599 | controller |
2025-09-30 06:43:47.947625 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-09-30 06:43:47.947632 | controller | Tuesday 30 September 2025  06:43:47 +0000 (0:00:00.182)       0:00:09.747 *****
2025-09-30 06:43:47.947642 | controller | skipping: [instance]
2025-09-30 06:43:48.115812 | controller |
2025-09-30 06:43:48.115842 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-09-30 06:43:48.115850 | controller | Tuesday 30 September 2025  06:43:47 +0000 (0:00:00.016)       0:00:09.763 *****
2025-09-30 06:43:48.115860 | controller | ok: [instance]
2025-09-30 06:43:48.129949 | controller |
2025-09-30 06:43:48.129973 | controller | TASK [compliance : Process scan result files] **********************************
2025-09-30 06:43:48.129981 | controller | Tuesday 30 September 2025  06:43:48 +0000 (0:00:00.168)       0:00:09.932 *****
2025-09-30 06:43:48.129991 | controller | skipping: [instance]
2025-09-30 06:43:48.152509 | controller |
2025-09-30 06:43:48.152534 | controller | TASK [compliance : Set output directory] ***************************************
2025-09-30 06:43:48.152542 | controller | Tuesday 30 September 2025  06:43:48 +0000 (0:00:00.014)       0:00:09.946 *****
2025-09-30 06:43:48.152552 | controller | ok: [instance]
2025-09-30 06:43:48.168510 | controller |
2025-09-30 06:43:48.168533 | controller | TASK [compliance : Create scan binding] ****************************************
2025-09-30 06:43:48.168541 | controller | Tuesday 30 September 2025  06:43:48 +0000 (0:00:00.022)       0:00:09.968 *****
2025-09-30 06:43:48.168551 | controller | skipping: [instance]
2025-09-30 06:43:48.184702 | controller |
2025-09-30 06:43:48.184727 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-09-30 06:43:48.184736 | controller | Tuesday 30 September 2025  06:43:48 +0000 (0:00:00.015)       0:00:09.984 *****
2025-09-30 06:43:48.184754 | controller | skipping: [instance]
2025-09-30 06:43:48.358323 | controller |
2025-09-30 06:43:48.358353 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-09-30 06:43:48.358361 | controller | Tuesday 30 September 2025  06:43:48 +0000 (0:00:00.016)       0:00:10.001 *****
2025-09-30 06:43:48.358371 | controller | ok: [instance]
2025-09-30 06:43:48.537641 | controller |
2025-09-30 06:43:48.537671 | controller | TASK [compliance : Create directory for scan results] **************************
2025-09-30 06:43:48.537679 | controller | Tuesday 30 September 2025  06:43:48 +0000 (0:00:00.173)       0:00:10.174 *****
2025-09-30 06:43:48.537690 | controller | changed: [instance]
2025-09-30 06:43:48.553447 | controller |
2025-09-30 06:43:48.553474 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-09-30 06:43:48.553483 | controller | Tuesday 30 September 2025  06:43:48 +0000 (0:00:00.179)       0:00:10.353 *****
2025-09-30 06:43:48.553495 | controller | skipping: [instance]
2025-09-30 06:43:48.720597 | controller |
2025-09-30 06:43:48.720627 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-09-30 06:43:48.720635 | controller | Tuesday 30 September 2025  06:43:48 +0000 (0:00:00.015)       0:00:10.369 *****
2025-09-30 06:43:48.720645 | controller | ok: [instance]
2025-09-30 06:43:48.734550 | controller |
2025-09-30 06:43:48.734574 | controller | TASK [compliance : Process scan result files] **********************************
2025-09-30 06:43:48.734584 | controller | Tuesday 30 September 2025  06:43:48 +0000 (0:00:00.166)       0:00:10.536 *****
2025-09-30 06:43:48.734596 | controller | skipping: [instance]
2025-09-30 06:43:48.752100 | controller |
2025-09-30 06:43:48.752128 | controller | TASK [compliance : Log start of scans] *****************************************
2025-09-30 06:43:48.752138 | controller | Tuesday 30 September 2025  06:43:48 +0000 (0:00:00.014)       0:00:10.550 *****
2025-09-30 06:43:48.752158 | controller | ok: [instance] =>
2025-09-30 06:43:48.752186 | controller |   msg: Starting scans for moderate
2025-09-30 06:43:48.752508 | controller |
2025-09-30 06:43:48.789126 | controller | TASK [compliance : Run scans and extract results] ******************************
2025-09-30 06:43:48.789146 | controller | Tuesday 30 September 2025  06:43:48 +0000 (0:00:00.017)       0:00:10.568 *****
2025-09-30 06:43:48.789157 | 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-09-30 06:43:48.789353 | 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-09-30 06:43:48.789368 | 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-09-30 06:43:48.789376 | controller |
2025-09-30 06:43:48.789384 | controller | TASK [compliance : Set output directory] ***************************************
2025-09-30 06:43:48.811118 | controller | Tuesday 30 September 2025  06:43:48 +0000 (0:00:00.036)       0:00:10.605 *****
2025-09-30 06:43:48.811144 | controller | ok: [instance]
2025-09-30 06:43:48.826956 | controller |
2025-09-30 06:43:48.826975 | controller | TASK [compliance : Create scan binding] ****************************************
2025-09-30 06:43:48.826982 | controller | Tuesday 30 September 2025  06:43:48 +0000 (0:00:00.022)       0:00:10.627 *****
2025-09-30 06:43:48.826991 | controller | skipping: [instance]
2025-09-30 06:43:48.842999 | controller |
2025-09-30 06:43:48.843017 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-09-30 06:43:48.843042 | controller | Tuesday 30 September 2025  06:43:48 +0000 (0:00:00.015)       0:00:10.643 *****
2025-09-30 06:43:48.843051 | controller | skipping: [instance]
2025-09-30 06:43:49.037766 | controller |
2025-09-30 06:43:49.037837 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-09-30 06:43:49.037847 | controller | Tuesday 30 September 2025  06:43:48 +0000 (0:00:00.016)       0:00:10.659 *****
2025-09-30 06:43:49.037862 | controller | ok: [instance]
2025-09-30 06:43:49.237676 | controller |
2025-09-30 06:43:49.237720 | controller | TASK [compliance : Create directory for scan results] **************************
2025-09-30 06:43:49.237730 | controller | Tuesday 30 September 2025  06:43:49 +0000 (0:00:00.193)       0:00:10.853 *****
2025-09-30 06:43:49.237742 | controller | changed: [instance]
2025-09-30 06:43:49.254071 | controller |
2025-09-30 06:43:49.254089 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-09-30 06:43:49.254096 | controller | Tuesday 30 September 2025  06:43:49 +0000 (0:00:00.200)       0:00:11.053 *****
2025-09-30 06:43:49.254106 | controller | skipping: [instance]
2025-09-30 06:43:49.254156 | controller |
2025-09-30 06:43:49.254166 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-09-30 06:43:49.254174 | controller | Tuesday 30 September 2025  06:43:49 +0000 (0:00:00.016)       0:00:11.070 *****
2025-09-30 06:43:49.450178 | controller | ok: [instance]
2025-09-30 06:43:49.450231 | controller |
2025-09-30 06:43:49.450240 | controller | TASK [compliance : Process scan result files] **********************************
2025-09-30 06:43:49.450249 | controller | Tuesday 30 September 2025  06:43:49 +0000 (0:00:00.195)       0:00:11.265 *****
2025-09-30 06:43:49.464658 | controller | skipping: [instance]
2025-09-30 06:43:49.487794 | controller |
2025-09-30 06:43:49.487842 | controller | TASK [compliance : Set output directory] ***************************************
2025-09-30 06:43:49.487861 | controller | Tuesday 30 September 2025  06:43:49 +0000 (0:00:00.015)       0:00:11.280 *****
2025-09-30 06:43:49.487875 | controller | ok: [instance]
2025-09-30 06:43:49.504140 | controller |
2025-09-30 06:43:49.504186 | controller | TASK [compliance : Create scan binding] ****************************************
2025-09-30 06:43:49.504195 | controller | Tuesday 30 September 2025  06:43:49 +0000 (0:00:00.023)       0:00:11.304 *****
2025-09-30 06:43:49.504206 | controller | skipping: [instance]
2025-09-30 06:43:49.521854 | controller |
2025-09-30 06:43:49.521900 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-09-30 06:43:49.521910 | controller | Tuesday 30 September 2025  06:43:49 +0000 (0:00:00.016)       0:00:11.320 *****
2025-09-30 06:43:49.521939 | controller | skipping: [instance]
2025-09-30 06:43:49.720817 | controller |
2025-09-30 06:43:49.720879 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-09-30 06:43:49.720888 | controller | Tuesday 30 September 2025  06:43:49 +0000 (0:00:00.017)       0:00:11.338 *****
2025-09-30 06:43:49.720902 | controller | ok: [instance]
2025-09-30 06:43:49.920233 | controller |
2025-09-30 06:43:49.920262 | controller | TASK [compliance : Create directory for scan results] **************************
2025-09-30 06:43:49.920270 | controller | Tuesday 30 September 2025  06:43:49 +0000 (0:00:00.197)       0:00:11.536 *****
2025-09-30 06:43:49.920297 | controller | changed: [instance]
2025-09-30 06:43:49.936852 | controller |
2025-09-30 06:43:49.936878 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-09-30 06:43:49.936886 | controller | Tuesday 30 September 2025  06:43:49 +0000 (0:00:00.200)       0:00:11.736 *****
2025-09-30 06:43:49.936896 | controller | skipping: [instance]
2025-09-30 06:43:50.110543 | controller |
2025-09-30 06:43:50.110574 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-09-30 06:43:50.110582 | controller | Tuesday 30 September 2025  06:43:49 +0000 (0:00:00.016)       0:00:11.753 *****
2025-09-30 06:43:50.110592 | controller | ok: [instance]
2025-09-30 06:43:50.124942 | controller |
2025-09-30 06:43:50.124966 | controller | TASK [compliance : Process scan result files] **********************************
2025-09-30 06:43:50.124974 | controller | Tuesday 30 September 2025  06:43:50 +0000 (0:00:00.173)       0:00:11.926 *****
2025-09-30 06:43:50.124984 | controller | skipping: [instance]
2025-09-30 06:43:50.146897 | controller |
2025-09-30 06:43:50.146926 | controller | TASK [compliance : Set output directory] ***************************************
2025-09-30 06:43:50.146934 | controller | Tuesday 30 September 2025  06:43:50 +0000 (0:00:00.014)       0:00:11.941 *****
2025-09-30 06:43:50.146944 | controller | ok: [instance]
2025-09-30 06:43:50.163057 | controller |
2025-09-30 06:43:50.163086 | controller | TASK [compliance : Create scan binding] ****************************************
2025-09-30 06:43:50.163095 | controller | Tuesday 30 September 2025  06:43:50 +0000 (0:00:00.021)       0:00:11.963 *****
2025-09-30 06:43:50.163105 | controller | skipping: [instance]
2025-09-30 06:43:50.178863 | controller |
2025-09-30 06:43:50.178883 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-09-30 06:43:50.178891 | controller | Tuesday 30 September 2025  06:43:50 +0000 (0:00:00.016)       0:00:11.979 *****
2025-09-30 06:43:50.178900 | controller | skipping: [instance]
2025-09-30 06:43:50.353802 | controller |
2025-09-30 06:43:50.353832 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-09-30 06:43:50.353840 | controller | Tuesday 30 September 2025  06:43:50 +0000 (0:00:00.015)       0:00:11.995 *****
2025-09-30 06:43:50.353850 | controller | ok: [instance]
2025-09-30 06:43:50.539757 | controller |
2025-09-30 06:43:50.539789 | controller | TASK [compliance : Create directory for scan results] **************************
2025-09-30 06:43:50.539797 | controller | Tuesday 30 September 2025  06:43:50 +0000 (0:00:00.174)       0:00:12.170 *****
2025-09-30 06:43:50.539807 | controller | changed: [instance]
2025-09-30 06:43:50.556119 | controller |
2025-09-30 06:43:50.556144 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-09-30 06:43:50.556154 | controller | Tuesday 30 September 2025  06:43:50 +0000 (0:00:00.185)       0:00:12.355 *****
2025-09-30 06:43:50.556165 | controller | skipping: [instance]
2025-09-30 06:43:50.727563 | controller |
2025-09-30 06:43:50.727593 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-09-30 06:43:50.727601 | controller | Tuesday 30 September 2025  06:43:50 +0000 (0:00:00.016)       0:00:12.372 *****
2025-09-30 06:43:50.727611 | controller | ok: [instance]
2025-09-30 06:43:50.742110 | controller |
2025-09-30 06:43:50.742139 | controller | TASK [compliance : Process scan result files] **********************************
2025-09-30 06:43:50.742149 | controller | Tuesday 30 September 2025  06:43:50 +0000 (0:00:00.171)       0:00:12.543 *****
2025-09-30 06:43:50.742168 | controller | skipping: [instance]
2025-09-30 06:43:50.760763 | controller |
2025-09-30 06:43:50.760784 | controller | TASK [compliance : Log start of scans] *****************************************
2025-09-30 06:43:50.760797 | controller | Tuesday 30 September 2025  06:43:50 +0000 (0:00:00.014)       0:00:12.558 *****
2025-09-30 06:43:50.760807 | controller | ok: [instance] =>
2025-09-30 06:43:50.792948 | controller |   msg: Starting scans for nerc-cip
2025-09-30 06:43:50.792978 | controller |
2025-09-30 06:43:50.792988 | controller | TASK [compliance : Run scans and extract results] ******************************
2025-09-30 06:43:50.792996 | controller | Tuesday 30 September 2025  06:43:50 +0000 (0:00:00.018)       0:00:12.577 *****
2025-09-30 06:43:50.793008 | 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-09-30 06:43:50.816081 | 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-09-30 06:43:50.816099 | controller |
2025-09-30 06:43:50.816107 | controller | TASK [compliance : Set output directory] ***************************************
2025-09-30 06:43:50.816113 | controller | Tuesday 30 September 2025  06:43:50 +0000 (0:00:00.031)       0:00:12.609 *****
2025-09-30 06:43:50.816122 | controller | ok: [instance]
2025-09-30 06:43:50.816268 | controller |
2025-09-30 06:43:50.816313 | controller | TASK [compliance : Create scan binding] ****************************************
2025-09-30 06:43:50.833762 | controller | Tuesday 30 September 2025  06:43:50 +0000 (0:00:00.023)       0:00:12.632 *****
2025-09-30 06:43:50.833783 | controller | skipping: [instance]
2025-09-30 06:43:50.851107 | controller |
2025-09-30 06:43:50.851131 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-09-30 06:43:50.851141 | controller | Tuesday 30 September 2025  06:43:50 +0000 (0:00:00.017)       0:00:12.650 *****
2025-09-30 06:43:50.851152 | controller | skipping: [instance]
2025-09-30 06:43:51.024864 | controller |
2025-09-30 06:43:51.024894 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-09-30 06:43:51.024902 | controller | Tuesday 30 September 2025  06:43:50 +0000 (0:00:00.017)       0:00:12.667 *****
2025-09-30 06:43:51.024912 | controller | ok: [instance]
2025-09-30 06:43:51.210104 | controller |
2025-09-30 06:43:51.210138 | controller | TASK [compliance : Create directory for scan results] **************************
2025-09-30 06:43:51.210147 | controller | Tuesday 30 September 2025  06:43:51 +0000 (0:00:00.173)       0:00:12.841 *****
2025-09-30 06:43:51.210157 | controller | changed: [instance]
2025-09-30 06:43:51.227719 | controller |
2025-09-30 06:43:51.227739 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-09-30 06:43:51.227746 | controller | Tuesday 30 September 2025  06:43:51 +0000 (0:00:00.185)       0:00:13.026 *****
2025-09-30 06:43:51.227756 | controller | skipping: [instance]
2025-09-30 06:43:51.402002 | controller |
2025-09-30 06:43:51.402047 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-09-30 06:43:51.402058 | controller | Tuesday 30 September 2025  06:43:51 +0000 (0:00:00.017)       0:00:13.044 *****
2025-09-30 06:43:51.402068 | controller | ok: [instance]
2025-09-30 06:43:51.417040 | controller |
2025-09-30 06:43:51.417062 | controller | TASK [compliance : Process scan result files] **********************************
2025-09-30 06:43:51.417070 | controller | Tuesday 30 September 2025  06:43:51 +0000 (0:00:00.174)       0:00:13.218 *****
2025-09-30 06:43:51.417080 | controller | skipping: [instance]
2025-09-30 06:43:51.439885 | controller |
2025-09-30 06:43:51.439908 | controller | TASK [compliance : Set output directory] ***************************************
2025-09-30 06:43:51.439917 | controller | Tuesday 30 September 2025  06:43:51 +0000 (0:00:00.015)       0:00:13.233 *****
2025-09-30 06:43:51.439928 | controller | ok: [instance]
2025-09-30 06:43:51.456260 | controller |
2025-09-30 06:43:51.456280 | controller | TASK [compliance : Create scan binding] ****************************************
2025-09-30 06:43:51.456300 | controller | Tuesday 30 September 2025  06:43:51 +0000 (0:00:00.022)       0:00:13.256 *****
2025-09-30 06:43:51.456309 | controller | skipping: [instance]
2025-09-30 06:43:51.472633 | controller |
2025-09-30 06:43:51.472652 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-09-30 06:43:51.472661 | controller | Tuesday 30 September 2025  06:43:51 +0000 (0:00:00.016)       0:00:13.272 *****
2025-09-30 06:43:51.472671 | controller | skipping: [instance]
2025-09-30 06:43:51.647920 | controller |
2025-09-30 06:43:51.647949 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-09-30 06:43:51.647958 | controller | Tuesday 30 September 2025  06:43:51 +0000 (0:00:00.016)       0:00:13.289 *****
2025-09-30 06:43:51.647968 | controller | ok: [instance]
2025-09-30 06:43:51.844326 | controller |
2025-09-30 06:43:51.844357 | controller | TASK [compliance : Create directory for scan results] **************************
2025-09-30 06:43:51.844366 | controller | Tuesday 30 September 2025  06:43:51 +0000 (0:00:00.175)       0:00:13.464 *****
2025-09-30 06:43:51.844376 | controller | changed: [instance]
2025-09-30 06:43:51.861100 | controller |
2025-09-30 06:43:51.861128 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-09-30 06:43:51.861142 | controller | Tuesday 30 September 2025  06:43:51 +0000 (0:00:00.196)       0:00:13.660 *****
2025-09-30 06:43:51.861154 | controller | skipping: [instance]
2025-09-30 06:43:51.861181 | controller |
2025-09-30 06:43:51.861191 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-09-30 06:43:51.861309 | controller | Tuesday 30 September 2025  06:43:51 +0000 (0:00:00.017)       0:00:13.677 *****
2025-09-30 06:43:52.035166 | controller | ok: [instance]
2025-09-30 06:43:52.035330 | controller |
2025-09-30 06:43:52.035343 | controller | TASK [compliance : Process scan result files] **********************************
2025-09-30 06:43:52.035352 | controller | Tuesday 30 September 2025  06:43:52 +0000 (0:00:00.173)       0:00:13.851 *****
2025-09-30 06:43:52.049764 | controller | skipping: [instance]
2025-09-30 06:43:52.068581 | controller |
2025-09-30 06:43:52.068599 | controller | TASK [compliance : Log start of scans] *****************************************
2025-09-30 06:43:52.068606 | controller | Tuesday 30 September 2025  06:43:52 +0000 (0:00:00.014)       0:00:13.866 *****
2025-09-30 06:43:52.068615 | controller | ok: [instance] =>
2025-09-30 06:43:52.100145 | controller |   msg: Starting scans for pci-dss
2025-09-30 06:43:52.100181 | controller |
2025-09-30 06:43:52.100192 | controller | TASK [compliance : Run scans and extract results] ******************************
2025-09-30 06:43:52.100199 | controller | Tuesday 30 September 2025  06:43:52 +0000 (0:00:00.018)       0:00:13.884 *****
2025-09-30 06:43:52.100211 | 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-09-30 06:43:52.100241 | 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-09-30 06:43:52.100251 | controller |
2025-09-30 06:43:52.100488 | controller | TASK [compliance : Set output directory] ***************************************
2025-09-30 06:43:52.124751 | controller | Tuesday 30 September 2025  06:43:52 +0000 (0:00:00.031)       0:00:13.916 *****
2025-09-30 06:43:52.124771 | controller | ok: [instance]
2025-09-30 06:43:52.141747 | controller |
2025-09-30 06:43:52.141763 | controller | TASK [compliance : Create scan binding] ****************************************
2025-09-30 06:43:52.141771 | controller | Tuesday 30 September 2025  06:43:52 +0000 (0:00:00.024)       0:00:13.941 *****
2025-09-30 06:43:52.141786 | controller | skipping: [instance]
2025-09-30 06:43:52.158454 | controller |
2025-09-30 06:43:52.158471 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-09-30 06:43:52.158478 | controller | Tuesday 30 September 2025  06:43:52 +0000 (0:00:00.016)       0:00:13.958 *****
2025-09-30 06:43:52.158494 | controller | skipping: [instance]
2025-09-30 06:43:52.337471 | controller |
2025-09-30 06:43:52.337504 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-09-30 06:43:52.337512 | controller | Tuesday 30 September 2025  06:43:52 +0000 (0:00:00.016)       0:00:13.974 *****
2025-09-30 06:43:52.337523 | controller | ok: [instance]
2025-09-30 06:43:52.534971 | controller |
2025-09-30 06:43:52.535001 | controller | TASK [compliance : Create directory for scan results] **************************
2025-09-30 06:43:52.535010 | controller | Tuesday 30 September 2025  06:43:52 +0000 (0:00:00.178)       0:00:14.153 *****
2025-09-30 06:43:52.535036 | controller | changed: [instance]
2025-09-30 06:43:52.550093 | controller |
2025-09-30 06:43:52.550126 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-09-30 06:43:52.550140 | controller | Tuesday 30 September 2025  06:43:52 +0000 (0:00:00.197)       0:00:14.351 *****
2025-09-30 06:43:52.550151 | controller | skipping: [instance]
2025-09-30 06:43:52.550175 | controller |
2025-09-30 06:43:52.550402 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-09-30 06:43:52.720680 | controller | Tuesday 30 September 2025  06:43:52 +0000 (0:00:00.015)       0:00:14.366 *****
2025-09-30 06:43:52.720714 | controller | ok: [instance]
2025-09-30 06:43:52.734126 | controller |
2025-09-30 06:43:52.734162 | controller | TASK [compliance : Process scan result files] **********************************
2025-09-30 06:43:52.734173 | controller | Tuesday 30 September 2025  06:43:52 +0000 (0:00:00.170)       0:00:14.536 *****
2025-09-30 06:43:52.734186 | controller | skipping: [instance]
2025-09-30 06:43:52.734222 | controller |
2025-09-30 06:43:52.734231 | controller | TASK [compliance : Set output directory] ***************************************
2025-09-30 06:43:52.734241 | controller | Tuesday 30 September 2025  06:43:52 +0000 (0:00:00.013)       0:00:14.550 *****
2025-09-30 06:43:52.755948 | controller | ok: [instance]
2025-09-30 06:43:52.771109 | controller |
2025-09-30 06:43:52.771133 | controller | TASK [compliance : Create scan binding] ****************************************
2025-09-30 06:43:52.771142 | controller | Tuesday 30 September 2025  06:43:52 +0000 (0:00:00.021)       0:00:14.572 *****
2025-09-30 06:43:52.771151 | controller | skipping: [instance]
2025-09-30 06:43:52.771241 | controller |
2025-09-30 06:43:52.771270 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-09-30 06:43:52.771282 | controller | Tuesday 30 September 2025  06:43:52 +0000 (0:00:00.015)       0:00:14.587 *****
2025-09-30 06:43:52.786095 | controller | skipping: [instance]
2025-09-30 06:43:52.963114 | controller |
2025-09-30 06:43:52.963152 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-09-30 06:43:52.963161 | controller | Tuesday 30 September 2025  06:43:52 +0000 (0:00:00.015)       0:00:14.602 *****
2025-09-30 06:43:52.963172 | controller | ok: [instance]
2025-09-30 06:43:52.963308 | controller |
2025-09-30 06:43:52.963339 | controller | TASK [compliance : Create directory for scan results] **************************
2025-09-30 06:43:52.963351 | controller | Tuesday 30 September 2025  06:43:52 +0000 (0:00:00.176)       0:00:14.779 *****
2025-09-30 06:43:53.153812 | controller | changed: [instance]
2025-09-30 06:43:53.171088 | controller |
2025-09-30 06:43:53.171113 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-09-30 06:43:53.171123 | controller | Tuesday 30 September 2025  06:43:53 +0000 (0:00:00.190)       0:00:14.969 *****
2025-09-30 06:43:53.171134 | controller | skipping: [instance]
2025-09-30 06:43:53.171223 | controller |
2025-09-30 06:43:53.171235 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-09-30 06:43:53.171245 | controller | Tuesday 30 September 2025  06:43:53 +0000 (0:00:00.017)       0:00:14.987 *****
2025-09-30 06:43:53.344011 | controller | ok: [instance]
2025-09-30 06:43:53.359133 | controller |
2025-09-30 06:43:53.359170 | controller | TASK [compliance : Process scan result files] **********************************
2025-09-30 06:43:53.359181 | controller | Tuesday 30 September 2025  06:43:53 +0000 (0:00:00.172)       0:00:15.160 *****
2025-09-30 06:43:53.359193 | controller | skipping: [instance]
2025-09-30 06:43:53.377944 | controller |
2025-09-30 06:43:53.377972 | controller | TASK [compliance : Log start of scans] *****************************************
2025-09-30 06:43:53.377980 | controller | Tuesday 30 September 2025  06:43:53 +0000 (0:00:00.015)       0:00:15.175 *****
2025-09-30 06:43:53.377990 | controller | ok: [instance] =>
2025-09-30 06:43:53.415126 | controller |   msg: Starting scans for stig
2025-09-30 06:43:53.415160 | controller |
2025-09-30 06:43:53.415170 | controller | TASK [compliance : Run scans and extract results] ******************************
2025-09-30 06:43:53.415178 | controller | Tuesday 30 September 2025  06:43:53 +0000 (0:00:00.018)       0:00:15.194 *****
2025-09-30 06:43:53.415189 | 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-09-30 06:43:53.436964 | 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-09-30 06:43:53.436999 | 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-09-30 06:43:53.437008 | controller |
2025-09-30 06:43:53.437014 | controller | TASK [compliance : Set output directory] ***************************************
2025-09-30 06:43:53.437033 | controller | Tuesday 30 September 2025  06:43:53 +0000 (0:00:00.036)       0:00:15.230 *****
2025-09-30 06:43:53.437047 | controller | ok: [instance]
2025-09-30 06:43:53.437121 | controller |
2025-09-30 06:43:53.437250 | controller | TASK [compliance : Create scan binding] ****************************************
2025-09-30 06:43:53.437389 | controller | Tuesday 30 September 2025  06:43:53 +0000 (0:00:00.022)       0:00:15.253 *****
2025-09-30 06:43:53.453145 | controller | skipping: [instance]
2025-09-30 06:43:53.453317 | controller |
2025-09-30 06:43:53.453468 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-09-30 06:43:53.453606 | controller | Tuesday 30 September 2025  06:43:53 +0000 (0:00:00.016)       0:00:15.269 *****
2025-09-30 06:43:53.469176 | controller | skipping: [instance]
2025-09-30 06:43:53.469333 | controller |
2025-09-30 06:43:53.469457 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-09-30 06:43:53.469587 | controller | Tuesday 30 September 2025  06:43:53 +0000 (0:00:00.015)       0:00:15.285 *****
2025-09-30 06:43:53.642972 | controller | ok: [instance]
2025-09-30 06:43:53.834658 | controller |
2025-09-30 06:43:53.834686 | controller | TASK [compliance : Create directory for scan results] **************************
2025-09-30 06:43:53.834695 | controller | Tuesday 30 September 2025  06:43:53 +0000 (0:00:00.173)       0:00:15.459 *****
2025-09-30 06:43:53.834705 | controller | changed: [instance]
2025-09-30 06:43:53.851759 | controller |
2025-09-30 06:43:53.851779 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-09-30 06:43:53.851787 | controller | Tuesday 30 September 2025  06:43:53 +0000 (0:00:00.191)       0:00:15.650 *****
2025-09-30 06:43:53.851796 | controller | skipping: [instance]
2025-09-30 06:43:54.029009 | controller |
2025-09-30 06:43:54.029064 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-09-30 06:43:54.029076 | controller | Tuesday 30 September 2025  06:43:53 +0000 (0:00:00.017)       0:00:15.668 *****
2025-09-30 06:43:54.029088 | controller | ok: [instance]
2025-09-30 06:43:54.043208 | controller |
2025-09-30 06:43:54.043236 | controller | TASK [compliance : Process scan result files] **********************************
2025-09-30 06:43:54.043244 | controller | Tuesday 30 September 2025  06:43:54 +0000 (0:00:00.176)       0:00:15.845 *****
2025-09-30 06:43:54.043254 | controller | skipping: [instance]
2025-09-30 06:43:54.065831 | controller |
2025-09-30 06:43:54.065861 | controller | TASK [compliance : Set output directory] ***************************************
2025-09-30 06:43:54.065877 | controller | Tuesday 30 September 2025  06:43:54 +0000 (0:00:00.014)       0:00:15.859 *****
2025-09-30 06:43:54.065890 | controller | ok: [instance]
2025-09-30 06:43:54.082759 | controller |
2025-09-30 06:43:54.082784 | controller | TASK [compliance : Create scan binding] ****************************************
2025-09-30 06:43:54.082793 | controller | Tuesday 30 September 2025  06:43:54 +0000 (0:00:00.022)       0:00:15.882 *****
2025-09-30 06:43:54.082804 | controller | skipping: [instance]
2025-09-30 06:43:54.099426 | controller |
2025-09-30 06:43:54.099444 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-09-30 06:43:54.099452 | controller | Tuesday 30 September 2025  06:43:54 +0000 (0:00:00.017)       0:00:15.899 *****
2025-09-30 06:43:54.099460 | controller | skipping: [instance]
2025-09-30 06:43:54.277156 | controller |
2025-09-30 06:43:54.277188 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-09-30 06:43:54.277196 | controller | Tuesday 30 September 2025  06:43:54 +0000 (0:00:00.016)       0:00:15.915 *****
2025-09-30 06:43:54.277207 | controller | ok: [instance]
2025-09-30 06:43:54.464535 | controller |
2025-09-30 06:43:54.464565 | controller | TASK [compliance : Create directory for scan results] **************************
2025-09-30 06:43:54.464574 | controller | Tuesday 30 September 2025  06:43:54 +0000 (0:00:00.177)       0:00:16.093 *****
2025-09-30 06:43:54.464584 | controller | changed: [instance]
2025-09-30 06:43:54.480751 | controller |
2025-09-30 06:43:54.480774 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-09-30 06:43:54.480791 | controller | Tuesday 30 September 2025  06:43:54 +0000 (0:00:00.187)       0:00:16.280 *****
2025-09-30 06:43:54.480802 | controller | skipping: [instance]
2025-09-30 06:43:54.679361 | controller |
2025-09-30 06:43:54.679392 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-09-30 06:43:54.679400 | controller | Tuesday 30 September 2025  06:43:54 +0000 (0:00:00.016)       0:00:16.297 *****
2025-09-30 06:43:54.679410 | controller | ok: [instance]
2025-09-30 06:43:54.695074 | controller |
2025-09-30 06:43:54.695103 | controller | TASK [compliance : Process scan result files] **********************************
2025-09-30 06:43:54.695111 | controller | Tuesday 30 September 2025  06:43:54 +0000 (0:00:00.198)       0:00:16.495 *****
2025-09-30 06:43:54.695122 | controller | skipping: [instance]
2025-09-30 06:43:54.717606 | controller |
2025-09-30 06:43:54.717625 | controller | TASK [compliance : Set output directory] ***************************************
2025-09-30 06:43:54.717633 | controller | Tuesday 30 September 2025  06:43:54 +0000 (0:00:00.015)       0:00:16.511 *****
2025-09-30 06:43:54.717642 | controller | ok: [instance]
2025-09-30 06:43:54.734206 | controller |
2025-09-30 06:43:54.734228 | controller | TASK [compliance : Create scan binding] ****************************************
2025-09-30 06:43:54.734237 | controller | Tuesday 30 September 2025  06:43:54 +0000 (0:00:00.022)       0:00:16.534 *****
2025-09-30 06:43:54.734248 | controller | skipping: [instance]
2025-09-30 06:43:54.750538 | controller |
2025-09-30 06:43:54.750557 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-09-30 06:43:54.750565 | controller | Tuesday 30 September 2025  06:43:54 +0000 (0:00:00.016)       0:00:16.550 *****
2025-09-30 06:43:54.750574 | controller | skipping: [instance]
2025-09-30 06:43:54.926133 | controller |
2025-09-30 06:43:54.926165 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-09-30 06:43:54.926173 | controller | Tuesday 30 September 2025  06:43:54 +0000 (0:00:00.016)       0:00:16.566 *****
2025-09-30 06:43:54.926183 | controller | ok: [instance]
2025-09-30 06:43:54.926209 | controller |
2025-09-30 06:43:54.926450 | controller | TASK [compliance : Create directory for scan results] **************************
2025-09-30 06:43:55.113238 | controller | Tuesday 30 September 2025  06:43:54 +0000 (0:00:00.175)       0:00:16.742 *****
2025-09-30 06:43:55.113272 | controller | changed: [instance]
2025-09-30 06:43:55.129280 | controller |
2025-09-30 06:43:55.129317 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-09-30 06:43:55.129325 | controller | Tuesday 30 September 2025  06:43:55 +0000 (0:00:00.186)       0:00:16.929 *****
2025-09-30 06:43:55.129334 | controller | skipping: [instance]
2025-09-30 06:43:55.301397 | controller |
2025-09-30 06:43:55.301427 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-09-30 06:43:55.301435 | controller | Tuesday 30 September 2025  06:43:55 +0000 (0:00:00.016)       0:00:16.945 *****
2025-09-30 06:43:55.301445 | controller | ok: [instance]
2025-09-30 06:43:55.315561 | controller |
2025-09-30 06:43:55.315587 | controller | TASK [compliance : Process scan result files] **********************************
2025-09-30 06:43:55.315595 | controller | Tuesday 30 September 2025  06:43:55 +0000 (0:00:00.171)       0:00:17.117 *****
2025-09-30 06:43:55.315610 | controller | skipping: [instance]
2025-09-30 06:43:55.354715 | controller |
2025-09-30 06:43:55.354746 | controller | TASK [compliance : Delete all resources created by the role] *******************
2025-09-30 06:43:55.354754 | controller | Tuesday 30 September 2025  06:43:55 +0000 (0:00:00.014)       0:00:17.131 *****
2025-09-30 06:43:55.354764 | controller | skipping: [instance]
2025-09-30 06:43:55.948917 | controller |
2025-09-30 06:43:55.948949 | controller | PLAY [Converge test of compute tasks] ******************************************
2025-09-30 06:43:55.948957 | controller |
2025-09-30 06:43:55.948963 | controller | TASK [Gathering Facts] *********************************************************
2025-09-30 06:43:55.948968 | controller | Tuesday 30 September 2025  06:43:55 +0000 (0:00:00.039)       0:00:17.170 *****
2025-09-30 06:43:55.948978 | controller | ok: [instance]
2025-09-30 06:43:56.159982 | controller |
2025-09-30 06:43:56.160012 | controller | TASK [compliance : Ensure compliance folder exists] ****************************
2025-09-30 06:43:56.160035 | controller | Tuesday 30 September 2025  06:43:55 +0000 (0:00:00.594)       0:00:17.765 *****
2025-09-30 06:43:56.160056 | controller | ok: [instance]
2025-09-30 06:43:56.187597 | controller |
2025-09-30 06:43:56.187618 | controller | TASK [compliance : Install the compliance operator and plugin] *****************
2025-09-30 06:43:56.187625 | controller | Tuesday 30 September 2025  06:43:56 +0000 (0:00:00.211)       0:00:17.976 *****
2025-09-30 06:43:56.187635 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/install.yml for instance
2025-09-30 06:43:56.204767 | controller |
2025-09-30 06:43:56.204789 | controller | TASK [compliance : Create the compliance namespace] ****************************
2025-09-30 06:43:56.204797 | controller | Tuesday 30 September 2025  06:43:56 +0000 (0:00:00.027)       0:00:18.004 *****
2025-09-30 06:43:56.204806 | controller | skipping: [instance]
2025-09-30 06:43:56.221674 | controller |
2025-09-30 06:43:56.221697 | controller | TASK [compliance : Ensure OperatorGroup for the compliance-operator is present] ***
2025-09-30 06:43:56.221706 | controller | Tuesday 30 September 2025  06:43:56 +0000 (0:00:00.017)       0:00:18.021 *****
2025-09-30 06:43:56.221718 | controller | skipping: [instance]
2025-09-30 06:43:56.238140 | controller |
2025-09-30 06:43:56.238174 | controller | TASK [compliance : Ensure Subscription for the compliance-operator is present] ***
2025-09-30 06:43:56.238183 | controller | Tuesday 30 September 2025  06:43:56 +0000 (0:00:00.016)       0:00:18.038 *****
2025-09-30 06:43:56.238194 | controller | skipping: [instance]
2025-09-30 06:43:56.254521 | controller |
2025-09-30 06:43:56.254543 | controller | TASK [compliance : Wait until the compliance-operator csv is present] **********
2025-09-30 06:43:56.254551 | controller | Tuesday 30 September 2025  06:43:56 +0000 (0:00:00.016)       0:00:18.054 *****
2025-09-30 06:43:56.254560 | controller | skipping: [instance]
2025-09-30 06:43:56.271014 | controller |
2025-09-30 06:43:56.271055 | controller | TASK [compliance : Get full name of the compliance-operator csv] ***************
2025-09-30 06:43:56.271063 | controller | Tuesday 30 September 2025  06:43:56 +0000 (0:00:00.016)       0:00:18.071 *****
2025-09-30 06:43:56.271072 | controller | skipping: [instance]
2025-09-30 06:43:56.288916 | controller |
2025-09-30 06:43:56.288937 | controller | TASK [compliance : Wait for the compliance-operator csv to Succeed] ************
2025-09-30 06:43:56.288944 | controller | Tuesday 30 September 2025  06:43:56 +0000 (0:00:00.016)       0:00:18.087 *****
2025-09-30 06:43:56.288953 | controller | skipping: [instance]
2025-09-30 06:43:56.945085 | controller |
2025-09-30 06:43:56.945114 | controller | TASK [podman : Ensure podman is installed] *************************************
2025-09-30 06:43:56.945123 | controller | Tuesday 30 September 2025  06:43:56 +0000 (0:00:00.017)       0:00:18.105 *****
2025-09-30 06:43:56.945133 | controller | ok: [instance]
2025-09-30 06:43:56.945501 | controller |
2025-09-30 06:43:57.157515 | controller | TASK [podman : Enable loginctl linger for ansible_user_id] *********************
2025-09-30 06:43:57.157543 | controller | Tuesday 30 September 2025  06:43:56 +0000 (0:00:00.656)       0:00:18.761 *****
2025-09-30 06:43:57.157555 | controller | changed: [instance]
2025-09-30 06:43:57.181156 | controller |
2025-09-30 06:43:57.181194 | controller | TASK [podman : Ensure subordinate UID entry exists for zuul] *******************
2025-09-30 06:43:57.181205 | controller | Tuesday 30 September 2025  06:43:57 +0000 (0:00:00.212)       0:00:18.973 *****
2025-09-30 06:43:57.181217 | controller | skipping: [instance]
2025-09-30 06:43:57.181240 | controller |
2025-09-30 06:43:57.181307 | controller | TASK [podman : Ensure subordinate GID entry exists for zuul] *******************
2025-09-30 06:43:57.181436 | controller | Tuesday 30 September 2025  06:43:57 +0000 (0:00:00.024)       0:00:18.997 *****
2025-09-30 06:43:57.200964 | controller | skipping: [instance]
2025-09-30 06:43:57.224926 | controller |
2025-09-30 06:43:57.224948 | controller | TASK [podman : Run podman system migrate if subuid/subgid files were changed] ***
2025-09-30 06:43:57.224958 | controller | Tuesday 30 September 2025  06:43:57 +0000 (0:00:00.019)       0:00:19.017 *****
2025-09-30 06:43:57.224975 | controller | skipping: [instance]
2025-09-30 06:43:57.241247 | controller |
2025-09-30 06:43:57.241264 | controller | TASK [compliance : Log into registry] ******************************************
2025-09-30 06:43:57.241271 | controller | Tuesday 30 September 2025  06:43:57 +0000 (0:00:00.023)       0:00:19.041 *****
2025-09-30 06:43:57.241280 | controller | skipping: [instance]
2025-09-30 06:43:57.258123 | controller |
2025-09-30 06:43:57.258156 | controller | TASK [compliance : Copy out the compliance plugin from the oc-compliance image] ***
2025-09-30 06:43:57.258164 | controller | Tuesday 30 September 2025  06:43:57 +0000 (0:00:00.016)       0:00:19.057 *****
2025-09-30 06:43:57.258174 | controller | skipping: [instance]
2025-09-30 06:43:57.258200 | controller |
2025-09-30 06:43:57.258206 | controller | TASK [compliance : Install packages to process results] ************************
2025-09-30 06:43:57.258213 | controller | Tuesday 30 September 2025  06:43:57 +0000 (0:00:00.017)       0:00:19.074 *****
2025-09-30 06:43:57.911944 | controller | ok: [instance]
2025-09-30 06:43:57.955147 | controller |
2025-09-30 06:43:57.955189 | controller | TASK [compliance : Run compliance scans and extract results] *******************
2025-09-30 06:43:57.955201 | controller | Tuesday 30 September 2025  06:43:57 +0000 (0:00:00.653)       0:00:19.728 *****
2025-09-30 06:43:57.955215 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_suite_scan.yml for instance => (item=cis)
2025-09-30 06:43:57.955247 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_suite_scan.yml for instance => (item=e8)
2025-09-30 06:43:57.955256 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_suite_scan.yml for instance => (item=high)
2025-09-30 06:43:57.955265 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_suite_scan.yml for instance => (item=moderate)
2025-09-30 06:43:57.955878 | 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-09-30 06:43:57.972918 | 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-09-30 06:43:57.972942 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_suite_scan.yml for instance => (item=stig)
2025-09-30 06:43:57.972951 | controller |
2025-09-30 06:43:57.972959 | controller | TASK [compliance : Log start of scans] *****************************************
2025-09-30 06:43:57.972966 | controller | Tuesday 30 September 2025  06:43:57 +0000 (0:00:00.043)       0:00:19.771 *****
2025-09-30 06:43:57.972977 | controller | ok: [instance] =>
2025-09-30 06:43:57.997617 | controller |   msg: Starting scans for cis
2025-09-30 06:43:57.997640 | controller |
2025-09-30 06:43:57.997649 | controller | TASK [compliance : Run scans and extract results] ******************************
2025-09-30 06:43:57.997657 | controller | Tuesday 30 September 2025  06:43:57 +0000 (0:00:00.017)       0:00:19.789 *****
2025-09-30 06:43:57.997666 | 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-09-30 06:43:58.018865 | controller |
2025-09-30 06:43:58.018885 | controller | TASK [compliance : Set output directory] ***************************************
2025-09-30 06:43:58.018893 | controller | Tuesday 30 September 2025  06:43:57 +0000 (0:00:00.024)       0:00:19.814 *****
2025-09-30 06:43:58.018902 | controller | ok: [instance]
2025-09-30 06:43:58.033650 | controller |
2025-09-30 06:43:58.033668 | controller | TASK [compliance : Create scan binding] ****************************************
2025-09-30 06:43:58.033675 | controller | Tuesday 30 September 2025  06:43:58 +0000 (0:00:00.021)       0:00:19.835 *****
2025-09-30 06:43:58.033685 | controller | skipping: [instance]
2025-09-30 06:43:58.048874 | controller |
2025-09-30 06:43:58.048892 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-09-30 06:43:58.048900 | controller | Tuesday 30 September 2025  06:43:58 +0000 (0:00:00.014)       0:00:19.850 *****
2025-09-30 06:43:58.048908 | controller | skipping: [instance]
2025-09-30 06:43:58.222678 | controller |
2025-09-30 06:43:58.222707 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-09-30 06:43:58.222715 | controller | Tuesday 30 September 2025  06:43:58 +0000 (0:00:00.015)       0:00:19.865 *****
2025-09-30 06:43:58.222725 | controller | changed: [instance]
2025-09-30 06:43:58.407104 | controller |
2025-09-30 06:43:58.407135 | controller | TASK [compliance : Create directory for scan results] **************************
2025-09-30 06:43:58.407152 | controller | Tuesday 30 September 2025  06:43:58 +0000 (0:00:00.173)       0:00:20.038 *****
2025-09-30 06:43:58.407164 | controller | changed: [instance]
2025-09-30 06:43:58.407226 | controller |
2025-09-30 06:43:58.407235 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-09-30 06:43:58.407243 | controller | Tuesday 30 September 2025  06:43:58 +0000 (0:00:00.184)       0:00:20.223 *****
2025-09-30 06:43:58.423846 | controller | skipping: [instance]
2025-09-30 06:43:58.599245 | controller |
2025-09-30 06:43:58.599278 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-09-30 06:43:58.599287 | controller | Tuesday 30 September 2025  06:43:58 +0000 (0:00:00.016)       0:00:20.239 *****
2025-09-30 06:43:58.599310 | controller | ok: [instance]
2025-09-30 06:43:58.614122 | controller |
2025-09-30 06:43:58.614143 | controller | TASK [compliance : Process scan result files] **********************************
2025-09-30 06:43:58.614152 | controller | Tuesday 30 September 2025  06:43:58 +0000 (0:00:00.175)       0:00:20.415 *****
2025-09-30 06:43:58.614162 | controller | skipping: [instance]
2025-09-30 06:43:58.633148 | controller |
2025-09-30 06:43:58.633165 | controller | TASK [compliance : Log start of scans] *****************************************
2025-09-30 06:43:58.633172 | controller | Tuesday 30 September 2025  06:43:58 +0000 (0:00:00.015)       0:00:20.430 *****
2025-09-30 06:43:58.633181 | controller | ok: [instance] =>
2025-09-30 06:43:58.665005 | controller |   msg: Starting scans for e8
2025-09-30 06:43:58.665038 | controller |
2025-09-30 06:43:58.665048 | controller | TASK [compliance : Run scans and extract results] ******************************
2025-09-30 06:43:58.665060 | controller | Tuesday 30 September 2025  06:43:58 +0000 (0:00:00.018)       0:00:20.449 *****
2025-09-30 06:43:58.665069 | 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-09-30 06:43:58.688133 | 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-09-30 06:43:58.688175 | controller |
2025-09-30 06:43:58.688185 | controller | TASK [compliance : Set output directory] ***************************************
2025-09-30 06:43:58.688193 | controller | Tuesday 30 September 2025  06:43:58 +0000 (0:00:00.031)       0:00:20.481 *****
2025-09-30 06:43:58.688205 | controller | ok: [instance]
2025-09-30 06:43:58.688245 | controller |
2025-09-30 06:43:58.688257 | controller | TASK [compliance : Create scan binding] ****************************************
2025-09-30 06:43:58.688435 | controller | Tuesday 30 September 2025  06:43:58 +0000 (0:00:00.023)       0:00:20.504 *****
2025-09-30 06:43:58.705118 | controller | skipping: [instance]
2025-09-30 06:43:58.705159 | controller |
2025-09-30 06:43:58.705168 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-09-30 06:43:58.705177 | controller | Tuesday 30 September 2025  06:43:58 +0000 (0:00:00.016)       0:00:20.521 *****
2025-09-30 06:43:58.723544 | controller | skipping: [instance]
2025-09-30 06:43:58.906849 | controller |
2025-09-30 06:43:58.906879 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-09-30 06:43:58.906888 | controller | Tuesday 30 September 2025  06:43:58 +0000 (0:00:00.018)       0:00:20.540 *****
2025-09-30 06:43:58.906899 | controller | changed: [instance]
2025-09-30 06:43:59.096876 | controller |
2025-09-30 06:43:59.096908 | controller | TASK [compliance : Create directory for scan results] **************************
2025-09-30 06:43:59.096916 | controller | Tuesday 30 September 2025  06:43:58 +0000 (0:00:00.182)       0:00:20.723 *****
2025-09-30 06:43:59.096926 | controller | changed: [instance]
2025-09-30 06:43:59.113513 | controller |
2025-09-30 06:43:59.113538 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-09-30 06:43:59.113545 | controller | Tuesday 30 September 2025  06:43:59 +0000 (0:00:00.189)       0:00:20.913 *****
2025-09-30 06:43:59.113555 | controller | skipping: [instance]
2025-09-30 06:43:59.290095 | controller |
2025-09-30 06:43:59.290127 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-09-30 06:43:59.290136 | controller | Tuesday 30 September 2025  06:43:59 +0000 (0:00:00.016)       0:00:20.929 *****
2025-09-30 06:43:59.290152 | controller | ok: [instance]
2025-09-30 06:43:59.290436 | controller |
2025-09-30 06:43:59.290451 | controller | TASK [compliance : Process scan result files] **********************************
2025-09-30 06:43:59.304869 | controller | Tuesday 30 September 2025  06:43:59 +0000 (0:00:00.176)       0:00:21.106 *****
2025-09-30 06:43:59.304890 | controller | skipping: [instance]
2025-09-30 06:43:59.328015 | controller |
2025-09-30 06:43:59.328047 | controller | TASK [compliance : Set output directory] ***************************************
2025-09-30 06:43:59.328055 | controller | Tuesday 30 September 2025  06:43:59 +0000 (0:00:00.014)       0:00:21.121 *****
2025-09-30 06:43:59.328064 | controller | ok: [instance]
2025-09-30 06:43:59.345772 | controller |
2025-09-30 06:43:59.345794 | controller | TASK [compliance : Create scan binding] ****************************************
2025-09-30 06:43:59.345802 | controller | Tuesday 30 September 2025  06:43:59 +0000 (0:00:00.023)       0:00:21.144 *****
2025-09-30 06:43:59.345811 | controller | skipping: [instance]
2025-09-30 06:43:59.362998 | controller |
2025-09-30 06:43:59.363040 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-09-30 06:43:59.363053 | controller | Tuesday 30 September 2025  06:43:59 +0000 (0:00:00.017)       0:00:21.162 *****
2025-09-30 06:43:59.363065 | controller | skipping: [instance]
2025-09-30 06:43:59.543090 | controller |
2025-09-30 06:43:59.543121 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-09-30 06:43:59.543129 | controller | Tuesday 30 September 2025  06:43:59 +0000 (0:00:00.017)       0:00:21.179 *****
2025-09-30 06:43:59.543140 | controller | changed: [instance]
2025-09-30 06:43:59.543166 | controller |
2025-09-30 06:43:59.543381 | controller | TASK [compliance : Create directory for scan results] **************************
2025-09-30 06:43:59.733000 | controller | Tuesday 30 September 2025  06:43:59 +0000 (0:00:00.180)       0:00:21.359 *****
2025-09-30 06:43:59.733050 | controller | changed: [instance]
2025-09-30 06:43:59.750685 | controller |
2025-09-30 06:43:59.750706 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-09-30 06:43:59.750716 | controller | Tuesday 30 September 2025  06:43:59 +0000 (0:00:00.189)       0:00:21.549 *****
2025-09-30 06:43:59.750726 | controller | skipping: [instance]
2025-09-30 06:43:59.923928 | controller |
2025-09-30 06:43:59.923960 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-09-30 06:43:59.923968 | controller | Tuesday 30 September 2025  06:43:59 +0000 (0:00:00.017)       0:00:21.566 *****
2025-09-30 06:43:59.923983 | controller | ok: [instance]
2025-09-30 06:43:59.938670 | controller |
2025-09-30 06:43:59.938694 | controller | TASK [compliance : Process scan result files] **********************************
2025-09-30 06:43:59.938703 | controller | Tuesday 30 September 2025  06:43:59 +0000 (0:00:00.173)       0:00:21.740 *****
2025-09-30 06:43:59.938715 | controller | skipping: [instance]
2025-09-30 06:43:59.957238 | controller |
2025-09-30 06:43:59.957258 | controller | TASK [compliance : Log start of scans] *****************************************
2025-09-30 06:43:59.957267 | controller | Tuesday 30 September 2025  06:43:59 +0000 (0:00:00.014)       0:00:21.755 *****
2025-09-30 06:43:59.957278 | controller | ok: [instance] =>
2025-09-30 06:44:00.011988 | controller |   msg: Starting scans for high
2025-09-30 06:44:00.012012 | controller |
2025-09-30 06:44:00.012038 | controller | TASK [compliance : Run scans and extract results] ******************************
2025-09-30 06:44:00.012048 | controller | Tuesday 30 September 2025  06:43:59 +0000 (0:00:00.018)       0:00:21.773 *****
2025-09-30 06:44:00.012058 | 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-09-30 06:44:00.035816 | 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-09-30 06:44:00.035837 | 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-09-30 06:44:00.035844 | controller |
2025-09-30 06:44:00.035851 | controller | TASK [compliance : Set output directory] ***************************************
2025-09-30 06:44:00.035857 | controller | Tuesday 30 September 2025  06:44:00 +0000 (0:00:00.054)       0:00:21.828 *****
2025-09-30 06:44:00.035871 | controller | ok: [instance]
2025-09-30 06:44:00.052868 | controller |
2025-09-30 06:44:00.052886 | controller | TASK [compliance : Create scan binding] ****************************************
2025-09-30 06:44:00.052893 | controller | Tuesday 30 September 2025  06:44:00 +0000 (0:00:00.024)       0:00:21.852 *****
2025-09-30 06:44:00.052902 | controller | skipping: [instance]
2025-09-30 06:44:00.070385 | controller |
2025-09-30 06:44:00.070405 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-09-30 06:44:00.070414 | controller | Tuesday 30 September 2025  06:44:00 +0000 (0:00:00.017)       0:00:21.869 *****
2025-09-30 06:44:00.070424 | controller | skipping: [instance]
2025-09-30 06:44:00.251885 | controller |
2025-09-30 06:44:00.251916 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-09-30 06:44:00.251926 | controller | Tuesday 30 September 2025  06:44:00 +0000 (0:00:00.017)       0:00:21.886 *****
2025-09-30 06:44:00.251936 | controller | changed: [instance]
2025-09-30 06:44:00.441507 | controller |
2025-09-30 06:44:00.441542 | controller | TASK [compliance : Create directory for scan results] **************************
2025-09-30 06:44:00.441551 | controller | Tuesday 30 September 2025  06:44:00 +0000 (0:00:00.181)       0:00:22.068 *****
2025-09-30 06:44:00.441561 | controller | changed: [instance]
2025-09-30 06:44:00.458660 | controller |
2025-09-30 06:44:00.458688 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-09-30 06:44:00.458698 | controller | Tuesday 30 September 2025  06:44:00 +0000 (0:00:00.189)       0:00:22.257 *****
2025-09-30 06:44:00.458709 | controller | skipping: [instance]
2025-09-30 06:44:00.633513 | controller |
2025-09-30 06:44:00.633543 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-09-30 06:44:00.633551 | controller | Tuesday 30 September 2025  06:44:00 +0000 (0:00:00.017)       0:00:22.274 *****
2025-09-30 06:44:00.633561 | controller | ok: [instance]
2025-09-30 06:44:00.648503 | controller |
2025-09-30 06:44:00.648527 | controller | TASK [compliance : Process scan result files] **********************************
2025-09-30 06:44:00.648536 | controller | Tuesday 30 September 2025  06:44:00 +0000 (0:00:00.174)       0:00:22.449 *****
2025-09-30 06:44:00.648548 | controller | skipping: [instance]
2025-09-30 06:44:00.671567 | controller |
2025-09-30 06:44:00.671589 | controller | TASK [compliance : Set output directory] ***************************************
2025-09-30 06:44:00.671597 | controller | Tuesday 30 September 2025  06:44:00 +0000 (0:00:00.015)       0:00:22.464 *****
2025-09-30 06:44:00.671608 | controller | ok: [instance]
2025-09-30 06:44:00.690786 | controller |
2025-09-30 06:44:00.690805 | controller | TASK [compliance : Create scan binding] ****************************************
2025-09-30 06:44:00.690811 | controller | Tuesday 30 September 2025  06:44:00 +0000 (0:00:00.023)       0:00:22.487 *****
2025-09-30 06:44:00.690820 | controller | skipping: [instance]
2025-09-30 06:44:00.707873 | controller |
2025-09-30 06:44:00.707890 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-09-30 06:44:00.707897 | controller | Tuesday 30 September 2025  06:44:00 +0000 (0:00:00.019)       0:00:22.507 *****
2025-09-30 06:44:00.707906 | controller | skipping: [instance]
2025-09-30 06:44:00.886101 | controller |
2025-09-30 06:44:00.886133 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-09-30 06:44:00.886142 | controller | Tuesday 30 September 2025  06:44:00 +0000 (0:00:00.017)       0:00:22.524 *****
2025-09-30 06:44:00.886153 | controller | changed: [instance]
2025-09-30 06:44:01.079967 | controller |
2025-09-30 06:44:01.080000 | controller | TASK [compliance : Create directory for scan results] **************************
2025-09-30 06:44:01.080031 | controller | Tuesday 30 September 2025  06:44:00 +0000 (0:00:00.177)       0:00:22.702 *****
2025-09-30 06:44:01.080047 | controller | changed: [instance]
2025-09-30 06:44:01.080070 | controller |
2025-09-30 06:44:01.080338 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-09-30 06:44:01.098886 | controller | Tuesday 30 September 2025  06:44:01 +0000 (0:00:00.194)       0:00:22.896 *****
2025-09-30 06:44:01.098925 | controller | skipping: [instance]
2025-09-30 06:44:01.272505 | controller |
2025-09-30 06:44:01.272536 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-09-30 06:44:01.272549 | controller | Tuesday 30 September 2025  06:44:01 +0000 (0:00:00.018)       0:00:22.915 *****
2025-09-30 06:44:01.272560 | controller | ok: [instance]
2025-09-30 06:44:01.286739 | controller |
2025-09-30 06:44:01.286764 | controller | TASK [compliance : Process scan result files] **********************************
2025-09-30 06:44:01.286771 | controller | Tuesday 30 September 2025  06:44:01 +0000 (0:00:00.173)       0:00:23.088 *****
2025-09-30 06:44:01.286781 | controller | skipping: [instance]
2025-09-30 06:44:01.309001 | controller |
2025-09-30 06:44:01.309045 | controller | TASK [compliance : Set output directory] ***************************************
2025-09-30 06:44:01.309057 | controller | Tuesday 30 September 2025  06:44:01 +0000 (0:00:00.014)       0:00:23.103 *****
2025-09-30 06:44:01.309068 | controller | ok: [instance]
2025-09-30 06:44:01.325122 | controller |
2025-09-30 06:44:01.325142 | controller | TASK [compliance : Create scan binding] ****************************************
2025-09-30 06:44:01.325150 | controller | Tuesday 30 September 2025  06:44:01 +0000 (0:00:00.022)       0:00:23.125 *****
2025-09-30 06:44:01.325160 | controller | skipping: [instance]
2025-09-30 06:44:01.341985 | controller |
2025-09-30 06:44:01.342002 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-09-30 06:44:01.342009 | controller | Tuesday 30 September 2025  06:44:01 +0000 (0:00:00.016)       0:00:23.141 *****
2025-09-30 06:44:01.342018 | controller | skipping: [instance]
2025-09-30 06:44:01.522540 | controller |
2025-09-30 06:44:01.522570 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-09-30 06:44:01.522584 | controller | Tuesday 30 September 2025  06:44:01 +0000 (0:00:00.016)       0:00:23.158 *****
2025-09-30 06:44:01.522595 | controller | changed: [instance]
2025-09-30 06:44:01.708647 | controller |
2025-09-30 06:44:01.708678 | controller | TASK [compliance : Create directory for scan results] **************************
2025-09-30 06:44:01.708687 | controller | Tuesday 30 September 2025  06:44:01 +0000 (0:00:00.178)       0:00:23.337 *****
2025-09-30 06:44:01.708698 | controller | changed: [instance]
2025-09-30 06:44:01.724717 | controller |
2025-09-30 06:44:01.724740 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-09-30 06:44:01.724748 | controller | Tuesday 30 September 2025  06:44:01 +0000 (0:00:00.187)       0:00:23.524 *****
2025-09-30 06:44:01.724758 | controller | skipping: [instance]
2025-09-30 06:44:01.895381 | controller |
2025-09-30 06:44:01.895413 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-09-30 06:44:01.895421 | controller | Tuesday 30 September 2025  06:44:01 +0000 (0:00:00.016)       0:00:23.541 *****
2025-09-30 06:44:01.895432 | controller | ok: [instance]
2025-09-30 06:44:01.908108 | controller |
2025-09-30 06:44:01.908139 | controller | TASK [compliance : Process scan result files] **********************************
2025-09-30 06:44:01.908147 | controller | Tuesday 30 September 2025  06:44:01 +0000 (0:00:00.170)       0:00:23.711 *****
2025-09-30 06:44:01.908157 | controller | skipping: [instance]
2025-09-30 06:44:01.908182 | controller |
2025-09-30 06:44:01.908468 | controller | TASK [compliance : Log start of scans] *****************************************
2025-09-30 06:44:01.925953 | controller | Tuesday 30 September 2025  06:44:01 +0000 (0:00:00.013)       0:00:23.724 *****
2025-09-30 06:44:01.925986 | controller | ok: [instance] =>
2025-09-30 06:44:01.961130 | controller |   msg: Starting scans for moderate
2025-09-30 06:44:01.961158 | controller |
2025-09-30 06:44:01.961168 | controller | TASK [compliance : Run scans and extract results] ******************************
2025-09-30 06:44:01.961176 | controller | Tuesday 30 September 2025  06:44:01 +0000 (0:00:00.017)       0:00:23.742 *****
2025-09-30 06:44:01.961187 | 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-09-30 06:44:01.961216 | 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-09-30 06:44:01.961226 | 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-09-30 06:44:01.961498 | controller |
2025-09-30 06:44:01.982310 | controller | TASK [compliance : Set output directory] ***************************************
2025-09-30 06:44:01.982345 | controller | Tuesday 30 September 2025  06:44:01 +0000 (0:00:00.035)       0:00:23.777 *****
2025-09-30 06:44:01.982359 | controller | ok: [instance]
2025-09-30 06:44:01.982540 | controller |
2025-09-30 06:44:01.982716 | controller | TASK [compliance : Create scan binding] ****************************************
2025-09-30 06:44:01.982895 | controller | Tuesday 30 September 2025  06:44:01 +0000 (0:00:00.021)       0:00:23.798 *****
2025-09-30 06:44:01.997343 | controller | skipping: [instance]
2025-09-30 06:44:01.997537 | controller |
2025-09-30 06:44:01.997713 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-09-30 06:44:01.997895 | controller | Tuesday 30 September 2025  06:44:01 +0000 (0:00:00.015)       0:00:23.814 *****
2025-09-30 06:44:02.012235 | controller | skipping: [instance]
2025-09-30 06:44:02.012395 | controller |
2025-09-30 06:44:02.012535 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-09-30 06:44:02.012668 | controller | Tuesday 30 September 2025  06:44:02 +0000 (0:00:00.014)       0:00:23.829 *****
2025-09-30 06:44:02.181491 | controller | changed: [instance]
2025-09-30 06:44:02.362623 | controller |
2025-09-30 06:44:02.362654 | controller | TASK [compliance : Create directory for scan results] **************************
2025-09-30 06:44:02.362662 | controller | Tuesday 30 September 2025  06:44:02 +0000 (0:00:00.168)       0:00:23.997 *****
2025-09-30 06:44:02.362672 | controller | changed: [instance]
2025-09-30 06:44:02.377598 | controller |
2025-09-30 06:44:02.377622 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-09-30 06:44:02.377630 | controller | Tuesday 30 September 2025  06:44:02 +0000 (0:00:00.181)       0:00:24.178 *****
2025-09-30 06:44:02.377640 | controller | skipping: [instance]
2025-09-30 06:44:02.546118 | controller |
2025-09-30 06:44:02.546146 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-09-30 06:44:02.546154 | controller | Tuesday 30 September 2025  06:44:02 +0000 (0:00:00.015)       0:00:24.194 *****
2025-09-30 06:44:02.546164 | controller | ok: [instance]
2025-09-30 06:44:02.546228 | controller |
2025-09-30 06:44:02.546245 | controller | TASK [compliance : Process scan result files] **********************************
2025-09-30 06:44:02.546255 | controller | Tuesday 30 September 2025  06:44:02 +0000 (0:00:00.168)       0:00:24.362 *****
2025-09-30 06:44:02.559002 | controller | skipping: [instance]
2025-09-30 06:44:02.580203 | controller |
2025-09-30 06:44:02.580226 | controller | TASK [compliance : Set output directory] ***************************************
2025-09-30 06:44:02.580236 | controller | Tuesday 30 September 2025  06:44:02 +0000 (0:00:00.013)       0:00:24.375 *****
2025-09-30 06:44:02.580247 | controller | ok: [instance]
2025-09-30 06:44:02.595105 | controller |
2025-09-30 06:44:02.595131 | controller | TASK [compliance : Create scan binding] ****************************************
2025-09-30 06:44:02.595140 | controller | Tuesday 30 September 2025  06:44:02 +0000 (0:00:00.021)       0:00:24.396 *****
2025-09-30 06:44:02.595151 | controller | skipping: [instance]
2025-09-30 06:44:02.595178 | controller |
2025-09-30 06:44:02.595188 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-09-30 06:44:02.595333 | controller | Tuesday 30 September 2025  06:44:02 +0000 (0:00:00.015)       0:00:24.411 *****
2025-09-30 06:44:02.610399 | controller | skipping: [instance]
2025-09-30 06:44:02.784330 | controller |
2025-09-30 06:44:02.784365 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-09-30 06:44:02.784373 | controller | Tuesday 30 September 2025  06:44:02 +0000 (0:00:00.015)       0:00:24.426 *****
2025-09-30 06:44:02.784384 | controller | changed: [instance]
2025-09-30 06:44:02.969620 | controller |
2025-09-30 06:44:02.969650 | controller | TASK [compliance : Create directory for scan results] **************************
2025-09-30 06:44:02.969658 | controller | Tuesday 30 September 2025  06:44:02 +0000 (0:00:00.173)       0:00:24.600 *****
2025-09-30 06:44:02.969668 | controller | changed: [instance]
2025-09-30 06:44:02.986247 | controller |
2025-09-30 06:44:02.986268 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-09-30 06:44:02.986275 | controller | Tuesday 30 September 2025  06:44:02 +0000 (0:00:00.185)       0:00:24.785 *****
2025-09-30 06:44:02.986284 | controller | skipping: [instance]
2025-09-30 06:44:03.157896 | controller |
2025-09-30 06:44:03.157926 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-09-30 06:44:03.157934 | controller | Tuesday 30 September 2025  06:44:02 +0000 (0:00:00.016)       0:00:24.802 *****
2025-09-30 06:44:03.157944 | controller | ok: [instance]
2025-09-30 06:44:03.171228 | controller |
2025-09-30 06:44:03.171248 | controller | TASK [compliance : Process scan result files] **********************************
2025-09-30 06:44:03.171255 | controller | Tuesday 30 September 2025  06:44:03 +0000 (0:00:00.171)       0:00:24.974 *****
2025-09-30 06:44:03.171264 | controller | skipping: [instance]
2025-09-30 06:44:03.193480 | controller |
2025-09-30 06:44:03.193505 | controller | TASK [compliance : Set output directory] ***************************************
2025-09-30 06:44:03.193513 | controller | Tuesday 30 September 2025  06:44:03 +0000 (0:00:00.013)       0:00:24.987 *****
2025-09-30 06:44:03.193523 | controller | ok: [instance]
2025-09-30 06:44:03.209073 | controller |
2025-09-30 06:44:03.209108 | controller | TASK [compliance : Create scan binding] ****************************************
2025-09-30 06:44:03.209121 | controller | Tuesday 30 September 2025  06:44:03 +0000 (0:00:00.022)       0:00:25.009 *****
2025-09-30 06:44:03.209133 | controller | skipping: [instance]
2025-09-30 06:44:03.224511 | controller |
2025-09-30 06:44:03.224535 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-09-30 06:44:03.224543 | controller | Tuesday 30 September 2025  06:44:03 +0000 (0:00:00.015)       0:00:25.025 *****
2025-09-30 06:44:03.224553 | controller | skipping: [instance]
2025-09-30 06:44:03.400612 | controller |
2025-09-30 06:44:03.400643 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-09-30 06:44:03.400651 | controller | Tuesday 30 September 2025  06:44:03 +0000 (0:00:00.015)       0:00:25.040 *****
2025-09-30 06:44:03.400661 | controller | changed: [instance]
2025-09-30 06:44:03.582932 | controller |
2025-09-30 06:44:03.582962 | controller | TASK [compliance : Create directory for scan results] **************************
2025-09-30 06:44:03.582970 | controller | Tuesday 30 September 2025  06:44:03 +0000 (0:00:00.175)       0:00:25.216 *****
2025-09-30 06:44:03.582980 | controller | changed: [instance]
2025-09-30 06:44:03.597927 | controller |
2025-09-30 06:44:03.597947 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-09-30 06:44:03.597956 | controller | Tuesday 30 September 2025  06:44:03 +0000 (0:00:00.182)       0:00:25.399 *****
2025-09-30 06:44:03.597967 | controller | skipping: [instance]
2025-09-30 06:44:03.770466 | controller |
2025-09-30 06:44:03.770494 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-09-30 06:44:03.770502 | controller | Tuesday 30 September 2025  06:44:03 +0000 (0:00:00.015)       0:00:25.414 *****
2025-09-30 06:44:03.770512 | controller | ok: [instance]
2025-09-30 06:44:03.783662 | controller |
2025-09-30 06:44:03.783684 | controller | TASK [compliance : Process scan result files] **********************************
2025-09-30 06:44:03.783691 | controller | Tuesday 30 September 2025  06:44:03 +0000 (0:00:00.172)       0:00:25.586 *****
2025-09-30 06:44:03.783701 | controller | skipping: [instance]
2025-09-30 06:44:03.800957 | controller |
2025-09-30 06:44:03.800978 | controller | TASK [compliance : Log start of scans] *****************************************
2025-09-30 06:44:03.800985 | controller | Tuesday 30 September 2025  06:44:03 +0000 (0:00:00.013)       0:00:25.600 *****
2025-09-30 06:44:03.800995 | controller | ok: [instance] =>
2025-09-30 06:44:03.832911 | controller |   msg: Starting scans for nerc-cip
2025-09-30 06:44:03.832941 | controller |
2025-09-30 06:44:03.832949 | controller | TASK [compliance : Run scans and extract results] ******************************
2025-09-30 06:44:03.832956 | controller | Tuesday 30 September 2025  06:44:03 +0000 (0:00:00.017)       0:00:25.617 *****
2025-09-30 06:44:03.832966 | 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-09-30 06:44:03.855225 | 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-09-30 06:44:03.855252 | controller |
2025-09-30 06:44:03.855261 | controller | TASK [compliance : Set output directory] ***************************************
2025-09-30 06:44:03.855274 | controller | Tuesday 30 September 2025  06:44:03 +0000 (0:00:00.031)       0:00:25.649 *****
2025-09-30 06:44:03.855286 | controller | ok: [instance]
2025-09-30 06:44:03.855484 | controller |
2025-09-30 06:44:03.855656 | controller | TASK [compliance : Create scan binding] ****************************************
2025-09-30 06:44:03.855810 | controller | Tuesday 30 September 2025  06:44:03 +0000 (0:00:00.022)       0:00:25.671 *****
2025-09-30 06:44:03.871963 | controller | skipping: [instance]
2025-09-30 06:44:03.872179 | controller |
2025-09-30 06:44:03.872374 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-09-30 06:44:03.872540 | controller | Tuesday 30 September 2025  06:44:03 +0000 (0:00:00.016)       0:00:25.688 *****
2025-09-30 06:44:03.888131 | controller | skipping: [instance]
2025-09-30 06:44:03.888338 | controller |
2025-09-30 06:44:03.888516 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-09-30 06:44:03.888683 | controller | Tuesday 30 September 2025  06:44:03 +0000 (0:00:00.016)       0:00:25.704 *****
2025-09-30 06:44:04.062669 | controller | changed: [instance]
2025-09-30 06:44:04.244639 | controller |
2025-09-30 06:44:04.244669 | controller | TASK [compliance : Create directory for scan results] **************************
2025-09-30 06:44:04.244677 | controller | Tuesday 30 September 2025  06:44:04 +0000 (0:00:00.174)       0:00:25.878 *****
2025-09-30 06:44:04.244687 | controller | changed: [instance]
2025-09-30 06:44:04.259676 | controller |
2025-09-30 06:44:04.259699 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-09-30 06:44:04.259706 | controller | Tuesday 30 September 2025  06:44:04 +0000 (0:00:00.181)       0:00:26.060 *****
2025-09-30 06:44:04.259716 | controller | skipping: [instance]
2025-09-30 06:44:04.436628 | controller |
2025-09-30 06:44:04.436657 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-09-30 06:44:04.436665 | controller | Tuesday 30 September 2025  06:44:04 +0000 (0:00:00.015)       0:00:26.076 *****
2025-09-30 06:44:04.436680 | controller | ok: [instance]
2025-09-30 06:44:04.449673 | controller |
2025-09-30 06:44:04.449695 | controller | TASK [compliance : Process scan result files] **********************************
2025-09-30 06:44:04.449702 | controller | Tuesday 30 September 2025  06:44:04 +0000 (0:00:00.176)       0:00:26.252 *****
2025-09-30 06:44:04.449712 | controller | skipping: [instance]
2025-09-30 06:44:04.471085 | controller |
2025-09-30 06:44:04.471108 | controller | TASK [compliance : Set output directory] ***************************************
2025-09-30 06:44:04.471116 | controller | Tuesday 30 September 2025  06:44:04 +0000 (0:00:00.013)       0:00:26.266 *****
2025-09-30 06:44:04.471126 | controller | ok: [instance]
2025-09-30 06:44:04.471148 | controller |
2025-09-30 06:44:04.471435 | controller | TASK [compliance : Create scan binding] ****************************************
2025-09-30 06:44:04.486617 | controller | Tuesday 30 September 2025  06:44:04 +0000 (0:00:00.021)       0:00:26.287 *****
2025-09-30 06:44:04.486643 | controller | skipping: [instance]
2025-09-30 06:44:04.501645 | controller |
2025-09-30 06:44:04.501665 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-09-30 06:44:04.501673 | controller | Tuesday 30 September 2025  06:44:04 +0000 (0:00:00.015)       0:00:26.303 *****
2025-09-30 06:44:04.501684 | controller | skipping: [instance]
2025-09-30 06:44:04.671670 | controller |
2025-09-30 06:44:04.671699 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-09-30 06:44:04.671707 | controller | Tuesday 30 September 2025  06:44:04 +0000 (0:00:00.015)       0:00:26.318 *****
2025-09-30 06:44:04.671717 | controller | changed: [instance]
2025-09-30 06:44:04.855422 | controller |
2025-09-30 06:44:04.855450 | controller | TASK [compliance : Create directory for scan results] **************************
2025-09-30 06:44:04.855459 | controller | Tuesday 30 September 2025  06:44:04 +0000 (0:00:00.169)       0:00:26.487 *****
2025-09-30 06:44:04.855469 | controller | changed: [instance]
2025-09-30 06:44:04.871115 | controller |
2025-09-30 06:44:04.871146 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-09-30 06:44:04.871154 | controller | Tuesday 30 September 2025  06:44:04 +0000 (0:00:00.183)       0:00:26.671 *****
2025-09-30 06:44:04.871165 | controller | skipping: [instance]
2025-09-30 06:44:05.047621 | controller |
2025-09-30 06:44:05.047649 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-09-30 06:44:05.047662 | controller | Tuesday 30 September 2025  06:44:04 +0000 (0:00:00.015)       0:00:26.687 *****
2025-09-30 06:44:05.047672 | controller | ok: [instance]
2025-09-30 06:44:05.060874 | controller |
2025-09-30 06:44:05.060894 | controller | TASK [compliance : Process scan result files] **********************************
2025-09-30 06:44:05.060903 | controller | Tuesday 30 September 2025  06:44:05 +0000 (0:00:00.176)       0:00:26.863 *****
2025-09-30 06:44:05.060914 | controller | skipping: [instance]
2025-09-30 06:44:05.077721 | controller |
2025-09-30 06:44:05.077739 | controller | TASK [compliance : Log start of scans] *****************************************
2025-09-30 06:44:05.077747 | controller | Tuesday 30 September 2025  06:44:05 +0000 (0:00:00.013)       0:00:26.877 *****
2025-09-30 06:44:05.077756 | controller | ok: [instance] =>
2025-09-30 06:44:05.107951 | controller |   msg: Starting scans for pci-dss
2025-09-30 06:44:05.107973 | controller |
2025-09-30 06:44:05.107982 | controller | TASK [compliance : Run scans and extract results] ******************************
2025-09-30 06:44:05.107989 | controller | Tuesday 30 September 2025  06:44:05 +0000 (0:00:00.016)       0:00:26.894 *****
2025-09-30 06:44:05.108000 | 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-09-30 06:44:05.129594 | 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-09-30 06:44:05.129612 | controller |
2025-09-30 06:44:05.129620 | controller | TASK [compliance : Set output directory] ***************************************
2025-09-30 06:44:05.129626 | controller | Tuesday 30 September 2025  06:44:05 +0000 (0:00:00.030)       0:00:26.924 *****
2025-09-30 06:44:05.129634 | controller | ok: [instance]
2025-09-30 06:44:05.144696 | controller |
2025-09-30 06:44:05.144713 | controller | TASK [compliance : Create scan binding] ****************************************
2025-09-30 06:44:05.144720 | controller | Tuesday 30 September 2025  06:44:05 +0000 (0:00:00.021)       0:00:26.945 *****
2025-09-30 06:44:05.144728 | controller | skipping: [instance]
2025-09-30 06:44:05.159957 | controller |
2025-09-30 06:44:05.159976 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-09-30 06:44:05.159984 | controller | Tuesday 30 September 2025  06:44:05 +0000 (0:00:00.015)       0:00:26.961 *****
2025-09-30 06:44:05.159994 | controller | skipping: [instance]
2025-09-30 06:44:05.332681 | controller |
2025-09-30 06:44:05.332712 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-09-30 06:44:05.332720 | controller | Tuesday 30 September 2025  06:44:05 +0000 (0:00:00.015)       0:00:26.976 *****
2025-09-30 06:44:05.332730 | controller | changed: [instance]
2025-09-30 06:44:05.514723 | controller |
2025-09-30 06:44:05.514752 | controller | TASK [compliance : Create directory for scan results] **************************
2025-09-30 06:44:05.514765 | controller | Tuesday 30 September 2025  06:44:05 +0000 (0:00:00.172)       0:00:27.148 *****
2025-09-30 06:44:05.514776 | controller | changed: [instance]
2025-09-30 06:44:05.529960 | controller |
2025-09-30 06:44:05.529985 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-09-30 06:44:05.529992 | controller | Tuesday 30 September 2025  06:44:05 +0000 (0:00:00.182)       0:00:27.331 *****
2025-09-30 06:44:05.530002 | controller | skipping: [instance]
2025-09-30 06:44:05.698634 | controller |
2025-09-30 06:44:05.698662 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-09-30 06:44:05.698671 | controller | Tuesday 30 September 2025  06:44:05 +0000 (0:00:00.015)       0:00:27.346 *****
2025-09-30 06:44:05.698680 | controller | ok: [instance]
2025-09-30 06:44:05.711942 | controller |
2025-09-30 06:44:05.711964 | controller | TASK [compliance : Process scan result files] **********************************
2025-09-30 06:44:05.711973 | controller | Tuesday 30 September 2025  06:44:05 +0000 (0:00:00.168)       0:00:27.514 *****
2025-09-30 06:44:05.711984 | controller | skipping: [instance]
2025-09-30 06:44:05.734079 | controller |
2025-09-30 06:44:05.734110 | controller | TASK [compliance : Set output directory] ***************************************
2025-09-30 06:44:05.734121 | controller | Tuesday 30 September 2025  06:44:05 +0000 (0:00:00.013)       0:00:27.528 *****
2025-09-30 06:44:05.734138 | controller | ok: [instance]
2025-09-30 06:44:05.749838 | controller |
2025-09-30 06:44:05.749862 | controller | TASK [compliance : Create scan binding] ****************************************
2025-09-30 06:44:05.749870 | controller | Tuesday 30 September 2025  06:44:05 +0000 (0:00:00.022)       0:00:27.550 *****
2025-09-30 06:44:05.749880 | controller | skipping: [instance]
2025-09-30 06:44:05.765833 | controller |
2025-09-30 06:44:05.765858 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-09-30 06:44:05.765865 | controller | Tuesday 30 September 2025  06:44:05 +0000 (0:00:00.015)       0:00:27.566 *****
2025-09-30 06:44:05.765875 | controller | skipping: [instance]
2025-09-30 06:44:05.936093 | controller |
2025-09-30 06:44:05.936123 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-09-30 06:44:05.936131 | controller | Tuesday 30 September 2025  06:44:05 +0000 (0:00:00.015)       0:00:27.582 *****
2025-09-30 06:44:05.936141 | controller | changed: [instance]
2025-09-30 06:44:05.936164 | controller |
2025-09-30 06:44:05.936404 | controller | TASK [compliance : Create directory for scan results] **************************
2025-09-30 06:44:06.114112 | controller | Tuesday 30 September 2025  06:44:05 +0000 (0:00:00.170)       0:00:27.752 *****
2025-09-30 06:44:06.114144 | controller | changed: [instance]
2025-09-30 06:44:06.114168 | controller |
2025-09-30 06:44:06.114177 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-09-30 06:44:06.114312 | controller | Tuesday 30 September 2025  06:44:06 +0000 (0:00:00.177)       0:00:27.930 *****
2025-09-30 06:44:06.129373 | controller | skipping: [instance]
2025-09-30 06:44:06.304472 | controller |
2025-09-30 06:44:06.304500 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-09-30 06:44:06.304508 | controller | Tuesday 30 September 2025  06:44:06 +0000 (0:00:00.015)       0:00:27.945 *****
2025-09-30 06:44:06.304517 | controller | ok: [instance]
2025-09-30 06:44:06.317075 | controller |
2025-09-30 06:44:06.317098 | controller | TASK [compliance : Process scan result files] **********************************
2025-09-30 06:44:06.317107 | controller | Tuesday 30 September 2025  06:44:06 +0000 (0:00:00.174)       0:00:28.120 *****
2025-09-30 06:44:06.317118 | controller | skipping: [instance]
2025-09-30 06:44:06.317482 | controller |
2025-09-30 06:44:06.334078 | controller | TASK [compliance : Log start of scans] *****************************************
2025-09-30 06:44:06.334107 | controller | Tuesday 30 September 2025  06:44:06 +0000 (0:00:00.012)       0:00:28.133 *****
2025-09-30 06:44:06.334121 | controller | ok: [instance] =>
2025-09-30 06:44:06.370794 | controller |   msg: Starting scans for stig
2025-09-30 06:44:06.370821 | controller |
2025-09-30 06:44:06.370837 | controller | TASK [compliance : Run scans and extract results] ******************************
2025-09-30 06:44:06.370845 | controller | Tuesday 30 September 2025  06:44:06 +0000 (0:00:00.016)       0:00:28.150 *****
2025-09-30 06:44:06.370856 | 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-09-30 06:44:06.391803 | 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-09-30 06:44:06.391836 | 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-09-30 06:44:06.391845 | controller |
2025-09-30 06:44:06.391852 | controller | TASK [compliance : Set output directory] ***************************************
2025-09-30 06:44:06.391860 | controller | Tuesday 30 September 2025  06:44:06 +0000 (0:00:00.036)       0:00:28.187 *****
2025-09-30 06:44:06.391871 | controller | ok: [instance]
2025-09-30 06:44:06.406752 | controller |
2025-09-30 06:44:06.406780 | controller | TASK [compliance : Create scan binding] ****************************************
2025-09-30 06:44:06.406790 | controller | Tuesday 30 September 2025  06:44:06 +0000 (0:00:00.021)       0:00:28.208 *****
2025-09-30 06:44:06.406803 | controller | skipping: [instance]
2025-09-30 06:44:06.421466 | controller |
2025-09-30 06:44:06.421494 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-09-30 06:44:06.421510 | controller | Tuesday 30 September 2025  06:44:06 +0000 (0:00:00.015)       0:00:28.223 *****
2025-09-30 06:44:06.421530 | controller | skipping: [instance]
2025-09-30 06:44:06.594131 | controller |
2025-09-30 06:44:06.594164 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-09-30 06:44:06.594173 | controller | Tuesday 30 September 2025  06:44:06 +0000 (0:00:00.014)       0:00:28.237 *****
2025-09-30 06:44:06.594183 | controller | changed: [instance]
2025-09-30 06:44:06.789846 | controller |
2025-09-30 06:44:06.789880 | controller | TASK [compliance : Create directory for scan results] **************************
2025-09-30 06:44:06.789889 | controller | Tuesday 30 September 2025  06:44:06 +0000 (0:00:00.172)       0:00:28.410 *****
2025-09-30 06:44:06.789900 | controller | changed: [instance]
2025-09-30 06:44:06.806537 | controller |
2025-09-30 06:44:06.806562 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-09-30 06:44:06.806570 | controller | Tuesday 30 September 2025  06:44:06 +0000 (0:00:00.195)       0:00:28.606 *****
2025-09-30 06:44:06.806580 | controller | skipping: [instance]
2025-09-30 06:44:06.979521 | controller |
2025-09-30 06:44:06.979556 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-09-30 06:44:06.979565 | controller | Tuesday 30 September 2025  06:44:06 +0000 (0:00:00.016)       0:00:28.622 *****
2025-09-30 06:44:06.979576 | controller | ok: [instance]
2025-09-30 06:44:06.994140 | controller |
2025-09-30 06:44:06.994175 | controller | TASK [compliance : Process scan result files] **********************************
2025-09-30 06:44:06.994184 | controller | Tuesday 30 September 2025  06:44:06 +0000 (0:00:00.172)       0:00:28.795 *****
2025-09-30 06:44:06.994195 | controller | skipping: [instance]
2025-09-30 06:44:06.994267 | controller |
2025-09-30 06:44:06.994279 | controller | TASK [compliance : Set output directory] ***************************************
2025-09-30 06:44:06.994288 | controller | Tuesday 30 September 2025  06:44:06 +0000 (0:00:00.014)       0:00:28.810 *****
2025-09-30 06:44:07.016905 | controller | ok: [instance]
2025-09-30 06:44:07.016986 | controller |
2025-09-30 06:44:07.017110 | controller | TASK [compliance : Create scan binding] ****************************************
2025-09-30 06:44:07.017210 | controller | Tuesday 30 September 2025  06:44:07 +0000 (0:00:00.023)       0:00:28.833 *****
2025-09-30 06:44:07.033476 | controller | skipping: [instance]
2025-09-30 06:44:07.033562 | controller |
2025-09-30 06:44:07.033666 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-09-30 06:44:07.033765 | controller | Tuesday 30 September 2025  06:44:07 +0000 (0:00:00.016)       0:00:28.850 *****
2025-09-30 06:44:07.050078 | controller | skipping: [instance]
2025-09-30 06:44:07.050170 | controller |
2025-09-30 06:44:07.050276 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-09-30 06:44:07.050399 | controller | Tuesday 30 September 2025  06:44:07 +0000 (0:00:00.016)       0:00:28.866 *****
2025-09-30 06:44:07.229776 | controller | changed: [instance]
2025-09-30 06:44:07.229828 | controller |
2025-09-30 06:44:07.229997 | controller | TASK [compliance : Create directory for scan results] **************************
2025-09-30 06:44:07.230106 | controller | Tuesday 30 September 2025  06:44:07 +0000 (0:00:00.179)       0:00:29.046 *****
2025-09-30 06:44:07.424549 | controller | changed: [instance]
2025-09-30 06:44:07.424636 | controller |
2025-09-30 06:44:07.424746 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-09-30 06:44:07.424840 | controller | Tuesday 30 September 2025  06:44:07 +0000 (0:00:00.194)       0:00:29.241 *****
2025-09-30 06:44:07.442168 | controller | skipping: [instance]
2025-09-30 06:44:07.442256 | controller |
2025-09-30 06:44:07.442397 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-09-30 06:44:07.442516 | controller | Tuesday 30 September 2025  06:44:07 +0000 (0:00:00.017)       0:00:29.258 *****
2025-09-30 06:44:07.612900 | controller | ok: [instance]
2025-09-30 06:44:07.612975 | controller |
2025-09-30 06:44:07.613111 | controller | TASK [compliance : Process scan result files] **********************************
2025-09-30 06:44:07.613212 | controller | Tuesday 30 September 2025  06:44:07 +0000 (0:00:00.170)       0:00:29.429 *****
2025-09-30 06:44:07.627693 | controller | skipping: [instance]
2025-09-30 06:44:07.627769 | controller |
2025-09-30 06:44:07.627867 | controller | TASK [compliance : Set output directory] ***************************************
2025-09-30 06:44:07.627967 | controller | Tuesday 30 September 2025  06:44:07 +0000 (0:00:00.015)       0:00:29.444 *****
2025-09-30 06:44:07.651326 | controller | ok: [instance]
2025-09-30 06:44:07.651435 | controller |
2025-09-30 06:44:07.651553 | controller | TASK [compliance : Create scan binding] ****************************************
2025-09-30 06:44:07.651684 | controller | Tuesday 30 September 2025  06:44:07 +0000 (0:00:00.023)       0:00:29.467 *****
2025-09-30 06:44:07.667884 | controller | skipping: [instance]
2025-09-30 06:44:07.667985 | controller |
2025-09-30 06:44:07.668113 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-09-30 06:44:07.668205 | controller | Tuesday 30 September 2025  06:44:07 +0000 (0:00:00.016)       0:00:29.484 *****
2025-09-30 06:44:07.683908 | controller | skipping: [instance]
2025-09-30 06:44:07.683992 | controller |
2025-09-30 06:44:07.684112 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-09-30 06:44:07.684207 | controller | Tuesday 30 September 2025  06:44:07 +0000 (0:00:00.016)       0:00:29.500 *****
2025-09-30 06:44:07.862007 | controller | changed: [instance]
2025-09-30 06:44:07.862367 | controller |
2025-09-30 06:44:08.048803 | controller | TASK [compliance : Create directory for scan results] **************************
2025-09-30 06:44:08.048833 | controller | Tuesday 30 September 2025  06:44:07 +0000 (0:00:00.177)       0:00:29.678 *****
2025-09-30 06:44:08.048846 | controller | changed: [instance]
2025-09-30 06:44:08.065121 | controller |
2025-09-30 06:44:08.065155 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-09-30 06:44:08.065163 | controller | Tuesday 30 September 2025  06:44:08 +0000 (0:00:00.186)       0:00:29.865 *****
2025-09-30 06:44:08.065173 | controller | skipping: [instance]
2025-09-30 06:44:08.065402 | controller |
2025-09-30 06:44:08.065430 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-09-30 06:44:08.236155 | controller | Tuesday 30 September 2025  06:44:08 +0000 (0:00:00.016)       0:00:29.881 *****
2025-09-30 06:44:08.236189 | controller | ok: [instance]
2025-09-30 06:44:08.251035 | controller |
2025-09-30 06:44:08.251061 | controller | TASK [compliance : Process scan result files] **********************************
2025-09-30 06:44:08.251069 | controller | Tuesday 30 September 2025  06:44:08 +0000 (0:00:00.170)       0:00:30.052 *****
2025-09-30 06:44:08.251079 | controller | skipping: [instance]
2025-09-30 06:44:08.284151 | controller |
2025-09-30 06:44:08.284186 | controller | TASK [compliance : Delete all resources created by the role] *******************
2025-09-30 06:44:08.284196 | controller | Tuesday 30 September 2025  06:44:08 +0000 (0:00:00.014)       0:00:30.067 *****
2025-09-30 06:44:08.284208 | controller | skipping: [instance]
2025-09-30 06:44:08.284238 | controller |
2025-09-30 06:44:08.284246 | controller | PLAY RECAP *********************************************************************
2025-09-30 06:44:08.284254 | controller | instance                   : ok=200  changed=52   unreachable=0    failed=0    skipped=152  rescued=0    ignored=0
2025-09-30 06:44:08.284262 | controller |
2025-09-30 06:44:08.286359 | controller | Tuesday 30 September 2025  06:44:08 +0000 (0:00:00.032)       0:00:30.099 *****
2025-09-30 06:44:08.343498 | controller | ===============================================================================
2025-09-30 06:44:08.343530 | controller | compliance : Install packages to process results ------------------------ 4.00s
2025-09-30 06:44:08.343538 | controller | Gathering Facts --------------------------------------------------------- 0.82s
2025-09-30 06:44:08.343544 | controller | podman : Ensure podman is installed ------------------------------------- 0.79s
2025-09-30 06:44:08.343549 | controller | podman : Ensure podman is installed ------------------------------------- 0.66s
2025-09-30 06:44:08.343554 | controller | compliance : Install packages to process results ------------------------ 0.65s
2025-09-30 06:44:08.343560 | controller | Gathering Facts --------------------------------------------------------- 0.59s
2025-09-30 06:44:08.343565 | controller | podman : Enable loginctl linger for ansible_user_id --------------------- 0.34s
2025-09-30 06:44:08.343570 | controller | compliance : Ensure compliance folder exists ---------------------------- 0.31s
2025-09-30 06:44:08.343575 | controller | compliance : Find all the compressed scan results ----------------------- 0.26s
2025-09-30 06:44:08.343580 | controller | podman : Enable loginctl linger for ansible_user_id --------------------- 0.21s
2025-09-30 06:44:08.343590 | controller | compliance : Ensure compliance folder exists ---------------------------- 0.21s
2025-09-30 06:44:08.343595 | controller | compliance : Create directory for scan results -------------------------- 0.20s
2025-09-30 06:44:08.343601 | controller | compliance : Create directory for scan results -------------------------- 0.20s
2025-09-30 06:44:08.343609 | controller | compliance : Find all the compressed scan results ----------------------- 0.20s
2025-09-30 06:44:08.343614 | controller | compliance : Delete directory for scan results if it exists ------------- 0.20s
2025-09-30 06:44:08.343620 | controller | compliance : Create directory for scan results -------------------------- 0.20s
2025-09-30 06:44:08.343625 | controller | compliance : Create directory for scan results -------------------------- 0.20s
2025-09-30 06:44:08.343630 | controller | compliance : Create directory for scan results -------------------------- 0.20s
2025-09-30 06:44:08.343635 | controller | compliance : Find all the compressed scan results ----------------------- 0.20s
2025-09-30 06:44:08.343641 | controller | compliance : Create directory for scan results -------------------------- 0.19s
2025-09-30 06:44:08.343651 | controller | INFO     Running default > cleanup
2025-09-30 06:44:08.344094 | controller | WARNING  Skipping, cleanup playbook not configured.
2025-09-30 06:44:08.344722 | controller | INFO     Writing /tmp/report.html report.
2025-09-30 06:44:08.531281 | controller | changed
2025-09-30 06:44:08.558981 | 
2025-09-30 06:44:08.559052 | PLAY RECAP
2025-09-30 06:44:08.559096 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-09-30 06:44:08.559119 | 
2025-09-30 06:44:08.649481 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-09-30 06:44:08.650420 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-09-30 06:44:09.222792 | 
2025-09-30 06:44:09.222905 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-09-30 06:44:09.244638 | 
2025-09-30 06:44:09.244715 | TASK [Filter out host if needed]
2025-09-30 06:44:09.253617 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-09-30 06:44:09.258341 | 
2025-09-30 06:44:09.258410 | TASK [Ensure file is present]
2025-09-30 06:44:09.572936 | controller | ok
2025-09-30 06:44:09.582994 | 
2025-09-30 06:44:09.583080 | TASK [Manage molecule report file]
2025-09-30 06:44:10.109716 | controller | changed
2025-09-30 06:44:10.119236 | 
2025-09-30 06:44:10.119305 | TASK [Check if we get ci-framework-data basedir]
2025-09-30 06:44:10.301112 | controller | ok
2025-09-30 06:44:10.307174 | 
2025-09-30 06:44:10.307247 | TASK [Create ci-framework-data log directory for zuul]
2025-09-30 06:44:10.632175 | controller | changed
2025-09-30 06:44:10.637761 | 
2025-09-30 06:44:10.637833 | TASK [Copy ci-framework interesting files]
2025-09-30 06:44:11.168234 | controller | changed
2025-09-30 06:44:11.175227 | 
2025-09-30 06:44:11.175330 | TASK [Get SELinux listing]
2025-09-30 06:44:11.702011 | controller | changed
2025-09-30 06:44:11.708822 | 
2025-09-30 06:44:11.708929 | TASK [Generate log index]
2025-09-30 06:44:12.340114 | controller | changed
2025-09-30 06:44:12.345081 | 
2025-09-30 06:44:12.345152 | TASK [Get some env related data]
2025-09-30 06:44:12.871942 | controller | /home/zuul/.local/bin/ansible
2025-09-30 06:44:13.869410 | controller | changed
2025-09-30 06:44:13.874770 | 
2025-09-30 06:44:13.874834 | TASK [Generate list of logs to collect in home directory]
2025-09-30 06:44:14.166904 | controller | ok: All paths examined
2025-09-30 06:44:14.172821 | 
2025-09-30 06:44:14.172944 | LOOP [Copy logs from home directory]
2025-09-30 06:44:14.491606 | controller | changed:
2025-09-30 06:44:14.491935 | controller | {
2025-09-30 06:44:14.491974 | controller |   "atime": 1759214580.8563232,
2025-09-30 06:44:14.492114 | controller |   "ctime": 1759214597.7575169,
2025-09-30 06:44:14.492143 | controller |   "dev": 64513,
2025-09-30 06:44:14.492162 | controller |   "gid": 1000,
2025-09-30 06:44:14.492179 | controller |   "gr_name": "zuul",
2025-09-30 06:44:14.492195 | controller |   "inode": 4405933,
2025-09-30 06:44:14.492211 | controller |   "isblk": false,
2025-09-30 06:44:14.492226 | controller |   "ischr": false,
2025-09-30 06:44:14.492247 | controller |   "isdir": false,
2025-09-30 06:44:14.492263 | controller |   "isfifo": false,
2025-09-30 06:44:14.492277 | controller |   "isgid": false,
2025-09-30 06:44:14.492292 | controller |   "islnk": false,
2025-09-30 06:44:14.492307 | controller |   "isreg": true,
2025-09-30 06:44:14.492322 | controller |   "issock": false,
2025-09-30 06:44:14.492337 | controller |   "isuid": false,
2025-09-30 06:44:14.492352 | controller |   "mode": "0644",
2025-09-30 06:44:14.492366 | controller |   "mtime": 1759214597.7575169,
2025-09-30 06:44:14.492380 | controller |   "nlink": 1,
2025-09-30 06:44:14.492395 | controller |   "path": "/home/zuul/ansible.log",
2025-09-30 06:44:14.492409 | controller |   "pw_name": "zuul",
2025-09-30 06:44:14.492424 | controller |   "rgrp": true,
2025-09-30 06:44:14.492439 | controller |   "roth": true,
2025-09-30 06:44:14.492453 | controller |   "rusr": true,
2025-09-30 06:44:14.492467 | controller |   "size": 6749,
2025-09-30 06:44:14.492480 | controller |   "uid": 1000,
2025-09-30 06:44:14.492493 | controller |   "wgrp": false,
2025-09-30 06:44:14.492507 | controller |   "woth": false,
2025-09-30 06:44:14.492520 | controller |   "wusr": true,
2025-09-30 06:44:14.492536 | controller |   "xgrp": false,
2025-09-30 06:44:14.492550 | controller |   "xoth": false,
2025-09-30 06:44:14.492564 | controller |   "xusr": false
2025-09-30 06:44:14.492579 | controller | }
2025-09-30 06:44:14.504433 | 
2025-09-30 06:44:14.504497 | TASK [Copy crio stats log file]
2025-09-30 06:44:14.518830 | controller | skipping: Conditional result was False
2025-09-30 06:44:14.524389 | 
2025-09-30 06:44:14.524470 | TASK [Get SELinux related data]
2025-09-30 06:44:14.730803 | controller | <no matches>
2025-09-30 06:44:15.052289 | controller | ERROR
2025-09-30 06:44:15.052437 | controller | {
2025-09-30 06:44:15.052474 | controller |   "delta": "0:00:00.009343",
2025-09-30 06:44:15.052499 | controller |   "end": "2025-09-30 06:44:14.731828",
2025-09-30 06:44:15.052607 | controller |   "msg": "non-zero return code",
2025-09-30 06:44:15.052640 | controller |   "rc": 1,
2025-09-30 06:44:15.052665 | controller |   "start": "2025-09-30 06:44:14.722485"
2025-09-30 06:44:15.052686 | controller | }
2025-09-30 06:44:15.052715 | controller | ERROR: Ignoring Errors
2025-09-30 06:44:15.058619 | 
2025-09-30 06:44:15.058692 | TASK [Create system configuration directory]
2025-09-30 06:44:15.241978 | controller | changed
2025-09-30 06:44:15.246876 | 
2025-09-30 06:44:15.246961 | TASK [Get some of the system configurations]
2025-09-30 06:44:15.774724 | controller | changed
2025-09-30 06:44:15.781729 | 
2025-09-30 06:44:15.781889 | TASK [Copy generated documentation if available]
2025-09-30 06:44:15.797657 | controller | skipping: Conditional result was False
2025-09-30 06:44:15.804373 | 
2025-09-30 06:44:15.804462 | TASK [Copy generated AsciiDoc documentation if available]
2025-09-30 06:44:15.819524 | controller | skipping: Conditional result was False
2025-09-30 06:44:15.827231 | 
2025-09-30 06:44:15.827335 | TASK [Compress logs bigger than 2MB]
2025-09-30 06:44:16.356926 | controller | changed
2025-09-30 06:44:16.363884 | 
2025-09-30 06:44:16.363971 | TASK [Copy files from workspace on node]
2025-09-30 06:44:16.385826 | controller | ok
2025-09-30 06:44:16.410871 | 
2025-09-30 06:44:16.410965 | TASK [fetch-output : Set log path for multiple nodes]
2025-09-30 06:44:16.435485 | controller | skipping: Conditional result was False
2025-09-30 06:44:16.441083 | 
2025-09-30 06:44:16.441157 | TASK [fetch-output : Set log path for single node]
2025-09-30 06:44:16.470501 | controller | ok
2025-09-30 06:44:16.481128 | 
2025-09-30 06:44:16.481225 | LOOP [fetch-output : Ensure local output dirs]
2025-09-30 06:44:16.702288 | controller -> localhost | ok: "/var/lib/zuul/builds/0f9667c155b24790be8b9199741cc743/work/logs"
2025-09-30 06:44:16.702664 | controller -> localhost | changed: All items complete
2025-09-30 06:44:16.702695 | 
2025-09-30 06:44:16.899769 | controller -> localhost | changed: "/var/lib/zuul/builds/0f9667c155b24790be8b9199741cc743/work/artifacts"
2025-09-30 06:44:17.095136 | controller -> localhost | changed: "/var/lib/zuul/builds/0f9667c155b24790be8b9199741cc743/work/docs"
2025-09-30 06:44:17.113424 | 
2025-09-30 06:44:17.113603 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-09-30 06:44:17.694272 | controller | changed:
2025-09-30 06:44:17.694490 | controller | .d..t...... ./
2025-09-30 06:44:17.694521 | controller | >f+++++++++ README.html
2025-09-30 06:44:17.694544 | controller | >f+++++++++ ansible-execution.log
2025-09-30 06:44:17.694565 | controller | >f+++++++++ ansible.log
2025-09-30 06:44:17.694584 | controller | >f+++++++++ dmesg.log
2025-09-30 06:44:17.694601 | controller | >f+++++++++ installed-pkgs.log
2025-09-30 06:44:17.694618 | controller | >f+++++++++ python.log
2025-09-30 06:44:17.694638 | controller | >f+++++++++ registries.conf
2025-09-30 06:44:17.694657 | controller | >f+++++++++ report.html
2025-09-30 06:44:17.694674 | controller | >f+++++++++ selinux-denials.log
2025-09-30 06:44:17.694693 | controller | >f+++++++++ selinux-listing.log
2025-09-30 06:44:17.694711 | controller | cd+++++++++ ci-framework-data/
2025-09-30 06:44:17.694728 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-09-30 06:44:17.694745 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-09-30 06:44:17.694763 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-09-30 06:44:17.694780 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-09-30 06:44:17.694797 | controller | cd+++++++++ ci-framework-data/logs/
2025-09-30 06:44:17.695019 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2025-09-30 06:44:17.695046 | controller | cd+++++++++ ci-framework-data/tests/
2025-09-30 06:44:17.695065 | controller | cd+++++++++ ci-framework-data/tests/compliance/
2025-09-30 06:44:17.695083 | controller | cd+++++++++ ci-framework-data/tests/compliance/cis/
2025-09-30 06:44:17.695099 | controller | cd+++++++++ ci-framework-data/tests/compliance/cis/ocp4-cis/
2025-09-30 06:44:17.695115 | controller | cd+++++++++ ci-framework-data/tests/compliance/e8/
2025-09-30 06:44:17.695132 | controller | cd+++++++++ ci-framework-data/tests/compliance/e8/ocp4-e8/
2025-09-30 06:44:17.695149 | controller | cd+++++++++ ci-framework-data/tests/compliance/e8/rhcos4-e8/
2025-09-30 06:44:17.695166 | controller | cd+++++++++ ci-framework-data/tests/compliance/high/
2025-09-30 06:44:17.695183 | controller | cd+++++++++ ci-framework-data/tests/compliance/high/ocp4-high-node/
2025-09-30 06:44:17.695200 | controller | cd+++++++++ ci-framework-data/tests/compliance/high/ocp4-high/
2025-09-30 06:44:17.695216 | controller | cd+++++++++ ci-framework-data/tests/compliance/high/rhcos4-high/
2025-09-30 06:44:17.695232 | controller | cd+++++++++ ci-framework-data/tests/compliance/moderate/
2025-09-30 06:44:17.695249 | controller | cd+++++++++ ci-framework-data/tests/compliance/moderate/ocp4-moderate-node/
2025-09-30 06:44:17.695265 | controller | cd+++++++++ ci-framework-data/tests/compliance/moderate/ocp4-moderate/
2025-09-30 06:44:17.695280 | controller | cd+++++++++ ci-framework-data/tests/compliance/moderate/rhcos4-moderate/
2025-09-30 06:44:17.695296 | controller | cd+++++++++ ci-framework-data/tests/compliance/nerc-cip/
2025-09-30 06:44:17.695311 | controller | cd+++++++++ ci-framework-data/tests/compliance/nerc-cip/ocp4-nerc-cip-node/
2025-09-30 06:44:17.695327 | controller | cd+++++++++ ci-framework-data/tests/compliance/nerc-cip/ocp4-nerc-cip/
2025-09-30 06:44:17.695343 | controller | cd+++++++++ ci-framework-data/tests/compliance/pci-dss/
2025-09-30 06:44:17.695360 | controller | cd+++++++++ ci-framework-data/tests/compliance/pci-dss/ocp4-pci-dss-node/
2025-09-30 06:44:17.695375 | controller | cd+++++++++ ci-framework-data/tests/compliance/pci-dss/ocp4-pci-dss/
2025-09-30 06:44:17.695391 | controller | cd+++++++++ ci-framework-data/tests/compliance/stig/
2025-09-30 06:44:17.695407 | controller | cd+++++++++ ci-framework-data/tests/compliance/stig/ocp4-stig-node/
2025-09-30 06:44:17.695428 | controller | cd+++++++++ ci-framework-data/tests/compliance/stig/ocp4-stig/
2025-09-30 06:44:17.695445 | controller | cd+++++++++ ci-framework-data/tests/compliance/stig/rhcos4-stig/
2025-09-30 06:44:17.695461 | controller | cd+++++++++ registries.conf.d/
2025-09-30 06:44:17.695477 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-09-30 06:44:17.695493 | controller | cd+++++++++ system-config/
2025-09-30 06:44:17.695509 | controller | cd+++++++++ system-config/libvirt/
2025-09-30 06:44:17.695524 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-09-30 06:44:17.695539 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-09-30 06:44:18.100860 | controller | changed: .d..t...... ./
2025-09-30 06:44:18.483409 | controller | changed: .d..t...... ./
2025-09-30 06:44:18.503139 | 
2025-09-30 06:44:18.503231 | TASK [Return artifact to Zuul]
2025-09-30 06:44:18.532292 | controller | ok
2025-09-30 06:44:18.551080 | 
2025-09-30 06:44:18.551169 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-09-30 06:44:18.551269 | 
2025-09-30 06:44:18.551296 | PLAY RECAP
2025-09-30 06:44:18.551334 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2025-09-30 06:44:18.551356 | 
2025-09-30 06:44:18.639097 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-09-30 06:44:18.639900 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-09-30 06:44:19.203725 | 
2025-09-30 06:44:19.203833 | PLAY [all]
2025-09-30 06:44:19.223068 | 
2025-09-30 06:44:19.223150 | TASK [include_role : fetch-output]
2025-09-30 06:44:19.252357 | controller | ok
2025-09-30 06:44:19.270973 | 
2025-09-30 06:44:19.271111 | TASK [fetch-output : Set log path for multiple nodes]
2025-09-30 06:44:19.316060 | controller | skipping: Conditional result was False
2025-09-30 06:44:19.322218 | 
2025-09-30 06:44:19.322309 | TASK [fetch-output : Set log path for single node]
2025-09-30 06:44:19.352022 | controller | ok
2025-09-30 06:44:19.357301 | 
2025-09-30 06:44:19.357378 | LOOP [fetch-output : Ensure local output dirs]
2025-09-30 06:44:19.705690 | controller -> localhost | ok: "/var/lib/zuul/builds/0f9667c155b24790be8b9199741cc743/work/logs"
2025-09-30 06:44:19.909760 | controller -> localhost | ok: "/var/lib/zuul/builds/0f9667c155b24790be8b9199741cc743/work/artifacts"
2025-09-30 06:44:20.106321 | controller -> localhost | ok: "/var/lib/zuul/builds/0f9667c155b24790be8b9199741cc743/work/docs"
2025-09-30 06:44:20.120224 | 
2025-09-30 06:44:20.120475 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-09-30 06:44:20.672330 | controller | ok
2025-09-30 06:44:20.672637 | controller | ok: All items complete
2025-09-30 06:44:20.672676 | 
2025-09-30 06:44:21.050756 | controller | ok
2025-09-30 06:44:21.433298 | controller | ok
2025-09-30 06:44:21.447826 | 
2025-09-30 06:44:21.447933 | TASK [include_role : fetch-output-openshift]
2025-09-30 06:44:21.463618 | controller | skipping: Conditional result was False
2025-09-30 06:44:21.471102 | 
2025-09-30 06:44:21.471183 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-09-30 06:44:21.810373 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.005896
2025-09-30 06:44:22.012711 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.006103
2025-09-30 06:44:22.042029 | 
2025-09-30 06:44:22.042117 | PLAY [all]
2025-09-30 06:44:22.057000 | 
2025-09-30 06:44:22.057071 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-09-30 06:44:22.452027 | controller | changed
2025-09-30 06:44:22.477333 | 
2025-09-30 06:44:22.477394 | PLAY RECAP
2025-09-30 06:44:22.477438 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-09-30 06:44:22.477461 | 
2025-09-30 06:44:22.566654 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-09-30 06:44:22.567478 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-09-30 06:44:23.148661 | 
2025-09-30 06:44:23.148784 | PLAY [localhost]
2025-09-30 06:44:23.167735 | 
2025-09-30 06:44:23.167820 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-09-30 06:44:23.480424 | localhost | changed
2025-09-30 06:44:23.485248 | 
2025-09-30 06:44:23.485335 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-09-30 06:44:23.513656 | localhost | ok
2025-09-30 06:44:23.521749 | 
2025-09-30 06:44:23.521862 | TASK [add-fileserver : Create SSH private key tempfile]
2025-09-30 06:44:23.854677 | localhost | changed
2025-09-30 06:44:23.860521 | 
2025-09-30 06:44:23.860618 | TASK [add-fileserver : Create SSH private key from secret]
2025-09-30 06:44:24.446938 | localhost | changed
2025-09-30 06:44:24.452294 | 
2025-09-30 06:44:24.452387 | TASK [add-fileserver : Add fileserver ssh key]
2025-09-30 06:44:24.815747 | localhost | Identity added: /var/lib/zuul/builds/0f9667c155b24790be8b9199741cc743/work/tmp/ansible.90ftw25h (/var/lib/zuul/builds/0f9667c155b24790be8b9199741cc743/work/tmp/ansible.90ftw25h)
2025-09-30 06:44:24.816007 | localhost | ok: Runtime: 0:00:00.007967
2025-09-30 06:44:24.820776 | 
2025-09-30 06:44:24.820861 | TASK [add-fileserver : Remove SSH private key from disk]
2025-09-30 06:44:25.072043 | localhost | ok: Runtime: 0:00:00.005811
2025-09-30 06:44:25.077322 | 
2025-09-30 06:44:25.077398 | TASK [add-fileserver : Add fileserver to inventory]
2025-09-30 06:44:25.127578 | localhost | changed
2025-09-30 06:44:25.131733 | 
2025-09-30 06:44:25.131812 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-09-30 06:44:25.494865 | localhost | changed
2025-09-30 06:44:25.516513 | 
2025-09-30 06:44:25.516623 | PLAY [localhost]
2025-09-30 06:44:25.528697 | 
2025-09-30 06:44:25.528763 | TASK [Generate bulk log download script]
2025-09-30 06:44:25.548473 | localhost | ok
2025-09-30 06:44:25.560662 | 
2025-09-30 06:44:25.560727 | TASK [local-log-download : Check API endpoint is defined]
2025-09-30 06:44:25.588924 | localhost | ok: All assertions passed
2025-09-30 06:44:25.593472 | 
2025-09-30 06:44:25.593535 | TASK [local-log-download : Create download script]
2025-09-30 06:44:25.993572 | localhost -> localhost | changed
2025-09-30 06:44:26.003372 | 
2025-09-30 06:44:26.003452 | TASK [Register quick-download link]
2025-09-30 06:44:26.022666 | localhost | ok
2025-09-30 06:44:26.075238 | 
2025-09-30 06:44:26.075357 | PLAY [logserver.rdoproject.org]
2025-09-30 06:44:26.086451 | 
2025-09-30 06:44:26.086514 | TASK [Set zuul-log-path fact]
2025-09-30 06:44:26.103529 | logserver.rdoproject.org | ok
2025-09-30 06:44:26.115533 | 
2025-09-30 06:44:26.115605 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-09-30 06:44:26.142942 | logserver.rdoproject.org | ok
2025-09-30 06:44:26.148634 | 
2025-09-30 06:44:26.148703 | TASK [upload-logs : Create log directories]
2025-09-30 06:44:27.974641 | logserver.rdoproject.org | changed
2025-09-30 06:44:27.978176 | 
2025-09-30 06:44:27.978244 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-09-30 06:44:28.236120 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.006396
2025-09-30 06:44:28.241657 | 
2025-09-30 06:44:28.241735 | TASK [upload-logs : Upload logs to log server]
2025-09-30 06:44:29.798174 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-09-30 06:44:29.801654 | 
2025-09-30 06:44:29.801729 | LOOP [upload-logs : Compress console log and json output]
2025-09-30 06:44:29.843033 | logserver.rdoproject.org | skipping: Conditional result was False
2025-09-30 06:44:29.853624 | logserver.rdoproject.org | skipping: Conditional result was False
2025-09-30 06:44:29.859928 | 
2025-09-30 06:44:29.860040 | LOOP [upload-logs : Upload compressed console log and json output]
2025-09-30 06:44:29.897120 | logserver.rdoproject.org | skipping: Conditional result was False
2025-09-30 06:44:29.897339 | 
2025-09-30 06:44:29.900992 | logserver.rdoproject.org | skipping: Conditional result was False
2025-09-30 06:44:29.907853 | 
2025-09-30 06:44:29.907980 | LOOP [upload-logs : Upload console log and json output]