2025-10-13 10:09:53.554986 | Job console starting...
2025-10-13 10:09:53.796743 | Updating repositories
2025-10-13 10:09:54.542864 | Preparing job workspace
2025-10-13 10:10:03.052240 | Running Ansible setup...
2025-10-13 10:10:06.865717 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-10-13 10:10:07.941803 | 
2025-10-13 10:10:07.941934 | PLAY [localhost]
2025-10-13 10:10:07.975563 | 
2025-10-13 10:10:07.975669 | TASK [Gathering Facts]
2025-10-13 10:10:09.921148 | localhost | ok
2025-10-13 10:10:09.944660 | 
2025-10-13 10:10:09.944759 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-10-13 10:10:10.744995 | localhost -> localhost | changed
2025-10-13 10:10:10.750373 | 
2025-10-13 10:10:10.750444 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-10-13 10:10:12.334865 | localhost -> localhost | changed
2025-10-13 10:10:12.343807 | 
2025-10-13 10:10:12.343873 | TASK [Setup log path fact]
2025-10-13 10:10:12.398792 | localhost | ok
2025-10-13 10:10:12.428394 | 
2025-10-13 10:10:12.428476 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-13 10:10:12.485285 | localhost | ok
2025-10-13 10:10:12.496435 | 
2025-10-13 10:10:12.496507 | TASK [emit-job-header : Print job information]
2025-10-13 10:10:12.589932 | # Job Information
2025-10-13 10:10:12.590075 | Ansible Version: 2.15.12
2025-10-13 10:10:12.590105 | Job: cifmw-molecule-compliance
2025-10-13 10:10:12.590128 | Pipeline: github-check
2025-10-13 10:10:12.590148 | Executor: ibm-bm4-ze.softwarefactory-project.io
2025-10-13 10:10:12.590168 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3402
2025-10-13 10:10:12.590188 | Log URL (when completed): https://logserver.rdoproject.org/190/rdoproject.org/1901e6a94542400fbdb973e84d0b41ef/
2025-10-13 10:10:12.590208 | Event ID: d1a3c640-a81b-11f0-8130-c9720bfa03f3
2025-10-13 10:10:12.594743 | 
2025-10-13 10:10:12.594811 | LOOP [emit-job-header : Print node information]
2025-10-13 10:10:12.824976 | localhost | ok:
2025-10-13 10:10:12.825173 | localhost | # Node Information
2025-10-13 10:10:12.825204 | localhost | Inventory Hostname: controller
2025-10-13 10:10:12.825230 | localhost | Hostname: np0005483337
2025-10-13 10:10:12.825252 | localhost | Username: zuul
2025-10-13 10:10:12.825272 | localhost | Distro: CentOS 9
2025-10-13 10:10:12.825291 | localhost | Provider: ibm-bm4-nodepool
2025-10-13 10:10:12.825308 | localhost | Region: regionOne
2025-10-13 10:10:12.825325 | localhost | Label: cloud-centos-9-stream-tripleo-ibm
2025-10-13 10:10:12.825342 | localhost | Product Name: OpenStack Compute
2025-10-13 10:10:12.825358 | localhost | Interface IP: 192.168.26.29
2025-10-13 10:10:12.877667 | 
2025-10-13 10:10:12.877735 | PLAY [all]
2025-10-13 10:10:12.894060 | 
2025-10-13 10:10:12.894127 | TASK [Gather network facts]
2025-10-13 10:10:13.297089 | controller | ok
2025-10-13 10:10:13.316338 | 
2025-10-13 10:10:13.316494 | TASK [include_role : start-zuul-console]
2025-10-13 10:10:13.354972 | controller | ok
2025-10-13 10:10:13.379419 | 
2025-10-13 10:10:13.379510 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-10-13 10:10:14.015628 | controller | ok
2025-10-13 10:10:14.024358 | 
2025-10-13 10:10:14.024434 | TASK [include_role : add-build-sshkey]
2025-10-13 10:10:14.093473 | controller | ok
2025-10-13 10:10:14.107690 | 
2025-10-13 10:10:14.107762 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-10-13 10:10:14.645772 | controller -> localhost | ok
2025-10-13 10:10:14.652274 | 
2025-10-13 10:10:14.652351 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-10-13 10:10:14.717237 | controller | ok
2025-10-13 10:10:14.741286 | controller | included: /var/lib/zuul/builds/1901e6a94542400fbdb973e84d0b41ef/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-10-13 10:10:14.768926 | 
2025-10-13 10:10:14.769027 | TASK [add-build-sshkey : Create Temp SSH key]
2025-10-13 10:10:15.830388 | controller -> localhost | Generating public/private rsa key pair.
2025-10-13 10:10:15.830557 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/1901e6a94542400fbdb973e84d0b41ef/work/1901e6a94542400fbdb973e84d0b41ef_id_rsa.
2025-10-13 10:10:15.830619 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/1901e6a94542400fbdb973e84d0b41ef/work/1901e6a94542400fbdb973e84d0b41ef_id_rsa.pub.
2025-10-13 10:10:15.830647 | controller -> localhost | The key fingerprint is:
2025-10-13 10:10:15.830668 | controller -> localhost | SHA256:P8EMUXRumpLait0GvDIi9OYhAAEM3M0Eg4i2oK1vztw zuul-build-sshkey
2025-10-13 10:10:15.830689 | controller -> localhost | The key's randomart image is:
2025-10-13 10:10:15.830708 | controller -> localhost | +---[RSA 3072]----+
2025-10-13 10:10:15.830726 | controller -> localhost | |@ oo=.  .oo .    |
2025-10-13 10:10:15.830744 | controller -> localhost | |+* ..o   . o     |
2025-10-13 10:10:15.830762 | controller -> localhost | |=..     .   o    |
2025-10-13 10:10:15.830780 | controller -> localhost | |o..      = +     |
2025-10-13 10:10:15.830798 | controller -> localhost | |..   .  S *      |
2025-10-13 10:10:15.830815 | controller -> localhost | |o.    oo o .     |
2025-10-13 10:10:15.830832 | controller -> localhost | |.o..  .o. o      |
2025-10-13 10:10:15.830850 | controller -> localhost | |.o=++o.o.  .     |
2025-10-13 10:10:15.830867 | controller -> localhost | | +*oEoo..        |
2025-10-13 10:10:15.830884 | controller -> localhost | +----[SHA256]-----+
2025-10-13 10:10:15.830950 | controller -> localhost | ok: Runtime: 0:00:00.333762
2025-10-13 10:10:15.836806 | 
2025-10-13 10:10:15.836868 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-10-13 10:10:15.887117 | controller | ok
2025-10-13 10:10:15.897367 | controller | included: /var/lib/zuul/builds/1901e6a94542400fbdb973e84d0b41ef/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-10-13 10:10:15.905335 | 
2025-10-13 10:10:15.905400 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-10-13 10:10:15.919019 | controller | skipping: Conditional result was False
2025-10-13 10:10:15.925800 | 
2025-10-13 10:10:15.925876 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-10-13 10:10:16.486535 | controller | changed
2025-10-13 10:10:16.492200 | 
2025-10-13 10:10:16.492264 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-10-13 10:10:16.733206 | controller | ok
2025-10-13 10:10:16.738143 | 
2025-10-13 10:10:16.738211 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-10-13 10:10:17.492581 | controller | changed
2025-10-13 10:10:17.511079 | 
2025-10-13 10:10:17.511174 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-10-13 10:10:18.263950 | controller | changed
2025-10-13 10:10:18.270470 | 
2025-10-13 10:10:18.270547 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-10-13 10:10:18.304948 | controller | skipping: Conditional result was False
2025-10-13 10:10:18.310520 | 
2025-10-13 10:10:18.310613 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-10-13 10:10:19.108090 | controller -> localhost | changed
2025-10-13 10:10:19.118395 | 
2025-10-13 10:10:19.118465 | TASK [add-build-sshkey : Add back temp key]
2025-10-13 10:10:19.696491 | controller -> localhost | Identity added: /var/lib/zuul/builds/1901e6a94542400fbdb973e84d0b41ef/work/1901e6a94542400fbdb973e84d0b41ef_id_rsa (zuul-build-sshkey)
2025-10-13 10:10:19.696691 | controller -> localhost | ok: Runtime: 0:00:00.031965
2025-10-13 10:10:19.702225 | 
2025-10-13 10:10:19.702288 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-10-13 10:10:20.173025 | controller | ok
2025-10-13 10:10:20.220399 | 
2025-10-13 10:10:20.220483 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-10-13 10:10:20.254011 | controller | skipping: Conditional result was False
2025-10-13 10:10:20.263475 | 
2025-10-13 10:10:20.263547 | TASK [include_role : validate-host]
2025-10-13 10:10:20.314955 | controller | ok
2025-10-13 10:10:20.346951 | 
2025-10-13 10:10:20.347045 | TASK [validate-host : Define zuul_info_dir fact]
2025-10-13 10:10:20.385703 | controller | ok
2025-10-13 10:10:20.400647 | 
2025-10-13 10:10:20.400723 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-10-13 10:10:20.728970 | controller -> localhost | ok
2025-10-13 10:10:20.734703 | 
2025-10-13 10:10:20.734769 | TASK [validate-host : Collect information about the host]
2025-10-13 10:10:21.436252 | controller | ok
2025-10-13 10:10:21.455838 | 
2025-10-13 10:10:21.455903 | TASK [validate-host : Sanitize hostname]
2025-10-13 10:10:21.565245 | controller | ok
2025-10-13 10:10:21.570303 | 
2025-10-13 10:10:21.570369 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-10-13 10:10:22.520001 | controller -> localhost | changed
2025-10-13 10:10:22.526175 | 
2025-10-13 10:10:22.526251 | TASK [validate-host : Collect information about zuul worker]
2025-10-13 10:10:22.879622 | controller | ok
2025-10-13 10:10:22.897422 | 
2025-10-13 10:10:22.897506 | TASK [validate-host : Write out all zuul information for each host]
2025-10-13 10:10:23.936830 | controller -> localhost | changed
2025-10-13 10:10:23.947758 | 
2025-10-13 10:10:23.947854 | TASK [include_role : prepare-workspace-openshift]
2025-10-13 10:10:23.995222 | controller | skipping: Conditional result was False
2025-10-13 10:10:24.000860 | 
2025-10-13 10:10:24.000973 | TASK [include_role : remove-zuul-sshkey]
2025-10-13 10:10:24.044393 | controller | skipping: Conditional result was False
2025-10-13 10:10:24.050430 | 
2025-10-13 10:10:24.050517 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-10-13 10:10:24.319954 | controller | ok: "logs"
2025-10-13 10:10:24.320235 | controller | ok: All items complete
2025-10-13 10:10:24.320269 | 
2025-10-13 10:10:24.496752 | controller | ok: "artifacts"
2025-10-13 10:10:24.685269 | controller | ok: "docs"
2025-10-13 10:10:24.700634 | 
2025-10-13 10:10:24.700706 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-10-13 10:10:24.978379 | controller | changed: "logs"
2025-10-13 10:10:25.170859 | controller | changed: "artifacts"
2025-10-13 10:10:25.344843 | controller | changed: "docs"
2025-10-13 10:10:25.386147 | 
2025-10-13 10:10:25.386211 | PLAY RECAP
2025-10-13 10:10:25.386254 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-10-13 10:10:25.386283 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-13 10:10:25.386303 | 
2025-10-13 10:10:25.485261 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-10-13 10:10:25.486029 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-10-13 10:10:26.627617 | 
2025-10-13 10:10:26.627751 | PLAY [all]
2025-10-13 10:10:26.648364 | 
2025-10-13 10:10:26.648447 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-10-13 10:10:26.728088 | controller | ok
2025-10-13 10:10:26.737812 | 
2025-10-13 10:10:26.737944 | TASK [mirror-info-fork : Create /etc/ci]
2025-10-13 10:10:27.105948 | controller | changed
2025-10-13 10:10:27.113510 | 
2025-10-13 10:10:27.113601 | TASK [mirror-info-fork : Install ci_mirror script]
2025-10-13 10:10:28.309686 | controller | changed
2025-10-13 10:10:28.320321 | 
2025-10-13 10:10:28.320646 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-10-13 10:10:28.750177 | controller | changed:
2025-10-13 10:10:28.750331 | controller | {
2025-10-13 10:10:28.750361 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-10-13 10:10:28.750391 | controller | }
2025-10-13 10:10:28.926696 | controller | changed:
2025-10-13 10:10:28.926777 | controller | {
2025-10-13 10:10:28.926806 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-10-13 10:10:28.926830 | controller | }
2025-10-13 10:10:29.153896 | controller | changed:
2025-10-13 10:10:29.154017 | controller | {
2025-10-13 10:10:29.154045 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com"
2025-10-13 10:10:29.154068 | controller | }
2025-10-13 10:10:29.377323 | controller | changed:
2025-10-13 10:10:29.377406 | controller | {
2025-10-13 10:10:29.377436 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-10-13 10:10:29.377458 | controller | }
2025-10-13 10:10:29.604296 | controller | changed:
2025-10-13 10:10:29.604388 | controller | {
2025-10-13 10:10:29.604417 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-10-13 10:10:29.604440 | controller | }
2025-10-13 10:10:29.823779 | controller | changed:
2025-10-13 10:10:29.823880 | controller | {
2025-10-13 10:10:29.824018 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-10-13 10:10:29.824056 | controller | }
2025-10-13 10:10:30.021205 | controller | changed:
2025-10-13 10:10:30.023693 | controller | {
2025-10-13 10:10:30.023749 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDLOQd4ZLtkZXQGY6UwAr/06ppWQK4fDO3HaqxPk98csyOCBXsliSKK39Bso828+5srIXiW7aI6aC9P5mwi4mUZlGPfJlQbfrcGvY+b/SocuvaGK+1RrHLoJCT52LBhwgrzlXio2jeksZeein8iaTrhsPrOAs7KggIL/rB9hEiB3NaOPWhhoCP4vlW6MEMExGcqB/1FVxXFBPnLkEyW0Lk7ycVflZl2ocRxbfjZi0+tI1Wlinp8PvSQSc/WVrAcDgKjc/mB4ODPOyYy3G8FHgfMsrXSDEyjBKgLKMsdCrAUcqJQWjkqXleXSYOV4q3pzL+9umK+q/e3P/bIoSFQzmJKTU1eDfuvPXmow9F5H54fii/Da7ezlMJ+wPGHJrRAkmzvMbALy7xwswLhZMkOGNtRcPqaKYRmIBKpw3o6bCTtcNUHOtOQnzwY8JzrM2eBWJBXAANYw+9/ho80JIiwhg29CFNpVBuHbql2YxJQNrnl90guN65rYNpDxdIluweyUf8= anbanerj@kaermorhen"
2025-10-13 10:10:30.023778 | controller | }
2025-10-13 10:10:30.250375 | controller | changed:
2025-10-13 10:10:30.250505 | controller | {
2025-10-13 10:10:30.250535 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQC3VwV8Im9kRm49lt3tM36hj4Zv27FxGo4C1Q/0jqhzFmHY7RHbmeRr8ObhwWoHjXSozKWg8FL5ER0z3hTwL0W6lez3sL7hUaCmSuZmG5Hnl3x4vTSxDI9JZ/Y65rtYiiWQo2fC5xJhU/4+0e5e/pseCm8cKRSu+SaxhO+sd6FDojA2x1BzOzKiQRDy/1zWGp/cZkxcEuB1wHI5LMzN03c67vmbu+fhZRAUO4dQkvcnj2LrhQtpa+ytvnSjr8icMDosf1OsbSffwZFyHB/hfWGAfe0eIeSA2XPraxiPknXxiPKx2MJsaUTYbsZcm3EjFdHBBMumw5rBI74zLrMRvCO9GwBEmGT4rFng1nP+yw5DB8sn2zqpOsPg1LYRwCPOUveC13P6pgsZZPh812e8v5EKnETct+5XI3dVpdw6CnNiLwAyVAF15DJvBGT/u1k0Myg/bQn+Gv9k2MSj6LvQmf6WbZu2Wgjm30z3FyCneBqTL7mLF19YXzeC0ufHz5pnO1E= dasm@fedora"
2025-10-13 10:10:30.250558 | controller | }
2025-10-13 10:10:30.459543 | controller | changed:
2025-10-13 10:10:30.459653 | controller | {
2025-10-13 10:10:30.459682 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-10-13 10:10:30.459704 | controller | }
2025-10-13 10:10:30.667437 | controller | changed:
2025-10-13 10:10:30.667523 | controller | {
2025-10-13 10:10:30.667550 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-10-13 10:10:30.667572 | controller | }
2025-10-13 10:10:30.923666 | controller | changed:
2025-10-13 10:10:30.923746 | controller | {
2025-10-13 10:10:30.923774 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-10-13 10:10:30.923796 | controller | }
2025-10-13 10:10:31.117121 | controller | changed:
2025-10-13 10:10:31.117195 | controller | {
2025-10-13 10:10:31.117223 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-10-13 10:10:31.117244 | controller | }
2025-10-13 10:10:31.344196 | controller | changed:
2025-10-13 10:10:31.344272 | controller | {
2025-10-13 10:10:31.344301 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-10-13 10:10:31.344324 | controller | }
2025-10-13 10:10:31.554585 | controller | changed:
2025-10-13 10:10:31.554670 | controller | {
2025-10-13 10:10:31.554698 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-10-13 10:10:31.554720 | controller | }
2025-10-13 10:10:31.768844 | controller | changed:
2025-10-13 10:10:31.768943 | controller | {
2025-10-13 10:10:31.768972 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-10-13 10:10:31.768996 | controller | }
2025-10-13 10:10:31.987136 | controller | changed:
2025-10-13 10:10:31.987210 | controller | {
2025-10-13 10:10:31.987237 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-10-13 10:10:31.987258 | controller | }
2025-10-13 10:10:32.201225 | controller | changed:
2025-10-13 10:10:32.201320 | controller | {
2025-10-13 10:10:32.201349 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQCfNtF7NvKl915TGsGGoseUb06Hj8L/S4toWf0hExeY+F00woL6NvBlJD0nDct+P5a22I4EhvoQCRQ8reaPCm1lybR3uiRIJsj+8zkVvLwby9LXzfZorlNG9ofjd00FEmB09uW/YvTl6Q9XwwwX6tInzIOv3TMqTHHGOL74ibbj8J/FJR0cFEyj0z4WQRvtkh32xAHl83gbuINryMt0sqRI+clj2381NKL55DRLQrVw0gsfqqxiHAnXg21qWmc4J+b9e9kiuAFQjcjwTVkwJCcg3xbPwC/qokYRby/Y5S40UUd7/jEARGXT7RZgpzTuDd1oZiCVrnrqJNPaMNdVv5MLeFdf1B7iIe5aa/fGouX7AO4SdKhZUdnJmCFAGvjC6S3JMZ2wAcUl+OHnssfmdj7XL50cLo27vjuzMtLAgSqi6N99m92WCF2s8J9aVzszX7Xz9OKZCeGsiVJp3/NdABKzSEAyM9xBD/5Vho894Sav+otpySHe3p6RUTgbB5Zu8VyZRZ/UtB3ueXxyo764yrc6qWIDqrehm84Xm9g+/jpIBzGPl07NUNJpdt/6Sgf9RIKXw/7XypO5yZfUcuFNGTxLfqjTNrtgLZNcjfav6sSdVXVcMPL//XNuRdKmVFaO76eV/oGMQGr1fGcCD+N+CpI7+Q+fCNB6VFWG4nZFuI/Iuw== averdagu@redhat.com"
2025-10-13 10:10:32.201372 | controller | }
2025-10-13 10:10:32.416470 | controller | changed:
2025-10-13 10:10:32.416559 | controller | {
2025-10-13 10:10:32.416595 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDq8l27xI+QlQVdS4djp9ogSoyrNE2+Ox6vKPdhSNL1J3PE5w+WCSvMz9A5gnNuH810zwbekEApbxTze/gLQJwBHA52CChfURpXrFaxY7ePXRElwKAL3mJfzBWY/c5jnNL9TCVmFJTGZkFZP3Nh+BMgZvL6xBkt3WKm6Uq18qzd9XeKcZusrA+O+uLv1fVeQnadY9RIqOCyeFYCzLWrUfTyE8x/XG0hAWIM7qpnF2cALQS2h9n4hW5ybiUN790H08wf9hFwEf5nxY9Z9dVkPFQiTSGKNBzmnCXU9skxS/xhpFjJ5duGSZdtAHe9O+nGZm9c67hxgtf8e5PDuqAdXEv2cf6e3VBAt+Bz8EKI3yosTj0oZHfwr42Yzb1l/SKy14Rggsrc9KAQlrGXan6+u2jcQqqx7l+SWmnpFiWTV9u5cWj2IgOhApOitmRBPYqk9rE2usfO0hLn/Pj/R/Nau4803e1/EikdLE7Ps95s9mX5jRDjAoUa2JwFF5RsVFyL910= ashigupt@ashigupt.remote.csb"
2025-10-13 10:10:32.416628 | controller | }
2025-10-13 10:10:32.630736 | controller | changed:
2025-10-13 10:10:32.630822 | controller | {
2025-10-13 10:10:32.630849 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-10-13 10:10:32.630870 | controller | }
2025-10-13 10:10:32.842882 | controller | changed:
2025-10-13 10:10:32.842994 | controller | {
2025-10-13 10:10:32.843022 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-10-13 10:10:32.843045 | controller | }
2025-10-13 10:10:33.054760 | controller | changed:
2025-10-13 10:10:33.054946 | controller | {
2025-10-13 10:10:33.054987 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-10-13 10:10:33.055012 | controller | }
2025-10-13 10:10:33.263133 | controller | changed:
2025-10-13 10:10:33.263316 | controller | {
2025-10-13 10:10:33.263354 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-10-13 10:10:33.263379 | controller | }
2025-10-13 10:10:33.476864 | controller | changed:
2025-10-13 10:10:33.478482 | controller | {
2025-10-13 10:10:33.478523 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQDZdI7t1cxYx65heVI24HTV4F7oQLW1zyfxHreL2TIJKxjyrUUKIFEUmTutcBlJRLNT2Eoix6x1sOw9YrchloCLcn//SGfTElr9mSc5jbjb7QXEU+zJMhtxyEJ1Po3CUGnj7ckiIXw7wcawZtrEOAQ9pH3ExYCJcEMiyNjRQZCxT3tPK+S4B95EWh5Fsrz9CkwpjNRPPH7LigCeQTM3Wc7r97utAslBUUvYceDSLA7rMgkitJE38b7rZBeYzsGQ8YYUBjTCtehqQXxCRjizbHWaaZkBU+N3zkKB6n/iCNGIO690NK7A/qb6msTijiz1PeuM8ThOsi9qXnbX5v0PoTpcFSojV7NHAQ71f0XXuS43FhZctT+Dcx44dT8Fb5vJu2cJGrk+qF8ZgJYNpRS7gPg0EG2EqjK7JMf9ULdjSu0r+KlqIAyLvtzT4eOnQipoKlb/WG5D/0ohKv7OMQ352ggfkBFIQsRXyyTCT98Ft9juqPuahi3CAQmP4H9dyE+7+Kz437PEtsxLmfm6naNmWi7Ee1DqWPwS8rEajsm4sNM4wW9gdBboJQtc0uZw0DfLj1I9r3Mc8Ol0jYtz0yNQDSzVLrGCaJlC311trU70tZ+ZkAVV6Mn8lOhSbj1cK0lvSr6ZK4dgqGl3I1eTZJJhbLNdg7UOVaiRx9543+C/p/As7w== brjackma@redhat.com"
2025-10-13 10:10:33.478549 | controller | }
2025-10-13 10:10:33.706436 | controller | changed:
2025-10-13 10:10:33.706524 | controller | {
2025-10-13 10:10:33.706550 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-10-13 10:10:33.706570 | controller | }
2025-10-13 10:10:33.910871 | controller | changed:
2025-10-13 10:10:33.910971 | controller | {
2025-10-13 10:10:33.910999 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-10-13 10:10:33.911021 | controller | }
2025-10-13 10:10:34.122821 | controller | changed:
2025-10-13 10:10:34.122932 | controller | {
2025-10-13 10:10:34.122964 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-10-13 10:10:34.122987 | controller | }
2025-10-13 10:10:34.148627 | 
2025-10-13 10:10:34.148714 | TASK [Set timezone to UTC]
2025-10-13 10:10:34.682504 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-10-13 10:10:34.695426 | 
2025-10-13 10:10:34.695518 | TASK [Create nodepool directory]
2025-10-13 10:10:34.929187 | controller | changed
2025-10-13 10:10:34.934868 | 
2025-10-13 10:10:34.934970 | TASK [Create nodepool sub_nodes file]
2025-10-13 10:10:35.485632 | controller | changed
2025-10-13 10:10:35.491360 | 
2025-10-13 10:10:35.491441 | TASK [Create nodepool sub_nodes_private file]
2025-10-13 10:10:35.999876 | controller | changed
2025-10-13 10:10:36.004842 | 
2025-10-13 10:10:36.004947 | LOOP [Populate nodepool sub_nodes file]
2025-10-13 10:10:36.033535 | 
2025-10-13 10:10:36.033681 | LOOP [Populate nodepool sub_nodes_private file]
2025-10-13 10:10:36.062418 | 
2025-10-13 10:10:36.062951 | TASK [Create nodepool primary file]
2025-10-13 10:10:36.085848 | controller | skipping: Conditional result was False
2025-10-13 10:10:36.091059 | 
2025-10-13 10:10:36.091138 | TASK [Create nodepool node_private for this node]
2025-10-13 10:10:36.632956 | controller | changed
2025-10-13 10:10:36.643047 | 
2025-10-13 10:10:36.643126 | LOOP [Copy ssh keys to nodepool directory]
2025-10-13 10:10:37.081637 | controller | ok: Item: id_rsa Runtime: 0:00:00.005829
2025-10-13 10:10:37.081814 | 
2025-10-13 10:10:37.249227 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.004655
2025-10-13 10:10:37.264015 | 
2025-10-13 10:10:37.264127 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-10-13 10:10:37.863483 | controller | changed
2025-10-13 10:10:37.870204 | 
2025-10-13 10:10:37.870274 | TASK [Validate sudoers config after edits]
2025-10-13 10:10:38.119878 | controller | /etc/sudoers: parsed OK
2025-10-13 10:10:38.119993 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-10-13 10:10:38.120005 | controller | /etc/sudoers.d/zuul: parsed OK
2025-10-13 10:10:38.120011 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-10-13 10:10:38.403437 | controller | ok: Runtime: 0:00:00.006976
2025-10-13 10:10:38.409325 | 
2025-10-13 10:10:38.409399 | TASK [Show the environment passed in to job shell scripts]
2025-10-13 10:10:38.630744 | controller | SHELL=/bin/bash
2025-10-13 10:10:38.630810 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-10-13 10:10:38.630821 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-10-13 10:10:38.630828 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/02/3402/0029ba8cf646fb53936389652feaf095d4937d80
2025-10-13 10:10:38.630891 | controller | PWD=/home/zuul
2025-10-13 10:10:38.630902 | controller | ZUUL_PIPELINE=github-check
2025-10-13 10:10:38.630908 | controller | LOGNAME=zuul
2025-10-13 10:10:38.630914 | controller | XDG_SESSION_TYPE=tty
2025-10-13 10:10:38.630919 | controller | _=/usr/bin/env
2025-10-13 10:10:38.630925 | controller | MOTD_SHOWN=pam
2025-10-13 10:10:38.630930 | controller | HOME=/home/zuul
2025-10-13 10:10:38.630936 | controller | LANG=en_US.UTF-8
2025-10-13 10:10:38.630941 | controller | SSH_CONNECTION=192.168.26.12 36358 192.168.26.29 22
2025-10-13 10:10:38.630947 | controller | TOX_TESTENV_PASSENV=ZUUL_UUID ZUUL_PROJECT ZUUL_SHORT_PROJECT_NAME ZUUL_PIPELINE ZUUL_VOTING WORKSPACE BUILD_TIMEOUT ZUUL_BRANCH ZUUL_CHANGES ZUUL_CHANGE_IDS ZUUL_CHANGE ZUUL_PATCHSET
2025-10-13 10:10:38.630957 | controller | ZUUL_CHANGE_IDS=3402,0029ba8cf646fb53936389652feaf095d4937d80
2025-10-13 10:10:38.630962 | controller | WORKSPACE=/home/zuul/workspace
2025-10-13 10:10:38.630968 | controller | XDG_SESSION_CLASS=user
2025-10-13 10:10:38.630974 | controller | SELINUX_ROLE_REQUESTED=
2025-10-13 10:10:38.630979 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-10-13 10:10:38.630984 | controller | USER=zuul
2025-10-13 10:10:38.630990 | controller | ZUUL_VOTING=True
2025-10-13 10:10:38.630995 | controller | BUILD_TIMEOUT=1800000
2025-10-13 10:10:38.631000 | controller | SELINUX_USE_CURRENT_RANGE=
2025-10-13 10:10:38.631006 | controller | SHLVL=1
2025-10-13 10:10:38.631011 | controller | ZUUL_PATCHSET=0029ba8cf646fb53936389652feaf095d4937d80
2025-10-13 10:10:38.631017 | controller | XDG_SESSION_ID=1
2025-10-13 10:10:38.631022 | controller | ZUUL_BRANCH=main
2025-10-13 10:10:38.631028 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-10-13 10:10:38.631034 | controller | SSH_CLIENT=192.168.26.12 36358 22
2025-10-13 10:10:38.631041 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-10-13 10:10:38.631046 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-10-13 10:10:38.631052 | controller | which_declare=declare -f
2025-10-13 10:10:38.631059 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2025-10-13 10:10:38.631065 | controller | SELINUX_LEVEL_REQUESTED=
2025-10-13 10:10:38.631070 | controller | ZUUL_CHANGE=3402
2025-10-13 10:10:38.631076 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-10-13 10:10:38.631081 | controller | ZUUL_UUID=1901e6a94542400fbdb973e84d0b41ef
2025-10-13 10:10:38.631087 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-10-13 10:10:38.631092 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-10-13 10:10:38.631099 | controller | }
2025-10-13 10:10:38.937241 | controller | ok: Runtime: 0:00:00.006253
2025-10-13 10:10:38.942859 | 
2025-10-13 10:10:38.942947 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-10-13 10:10:38.956815 | controller | skipping: Conditional result was False
2025-10-13 10:10:38.963073 | 
2025-10-13 10:10:38.963153 | TASK [Symlink /home/zuul-worker/workspace]
2025-10-13 10:10:39.499675 | controller | skipping: Conditional result was False
2025-10-13 10:10:39.505544 | 
2025-10-13 10:10:39.505641 | TASK [Ensure legacy workspace directory]
2025-10-13 10:10:39.706457 | controller | changed
2025-10-13 10:10:39.724668 | 
2025-10-13 10:10:39.724741 | PLAY RECAP
2025-10-13 10:10:39.724790 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-10-13 10:10:39.724814 | 
2025-10-13 10:10:39.820756 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-10-13 10:10:39.821495 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-10-13 10:10:40.378062 | 
2025-10-13 10:10:40.378168 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-10-13 10:10:40.398560 | 
2025-10-13 10:10:40.398668 | TASK [Create zuul-output directory]
2025-10-13 10:10:40.743420 | controller | changed
2025-10-13 10:10:40.748375 | 
2025-10-13 10:10:40.748450 | TASK [Slurp Zuul inventory test]
2025-10-13 10:10:41.039584 | controller -> localhost | ok
2025-10-13 10:10:41.057327 | 
2025-10-13 10:10:41.057419 | TASK [Save zuul inventory]
2025-10-13 10:10:41.850853 | controller | changed
2025-10-13 10:10:41.855727 | 
2025-10-13 10:10:41.858421 | TASK [Save zuul vars without the change_message]
2025-10-13 10:10:42.450867 | controller | changed
2025-10-13 10:10:42.470181 | 
2025-10-13 10:10:42.470260 | PLAY RECAP
2025-10-13 10:10:42.470306 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-13 10:10:42.470329 | 
2025-10-13 10:10:42.554971 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-10-13 10:10:42.555764 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-10-13 10:10:43.105507 | 
2025-10-13 10:10:43.105616 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2025-10-13 10:10:43.127498 | 
2025-10-13 10:10:43.127580 | TASK [Read group_vars]
2025-10-13 10:10:43.145725 | controller | ok
2025-10-13 10:10:43.163107 | 
2025-10-13 10:10:43.163195 | TASK [cifmw_helpers : Check directory is available]
2025-10-13 10:10:43.450977 | controller | ok
2025-10-13 10:10:43.456512 | 
2025-10-13 10:10:43.456609 | TASK [cifmw_helpers : Print var]
2025-10-13 10:10:43.479211 | controller | ok:
2025-10-13 10:10:43.480205 | controller | provide_var is /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/group_vars
2025-10-13 10:10:43.480255 | controller |  and registered one is {'changed': False, 'stat': {'exists': False}, 'failed': False}
2025-10-13 10:10:43.485946 | 
2025-10-13 10:10:43.486026 | TASK [cifmw_helpers : List dir in ansible user home]
2025-10-13 10:10:43.794926 | controller | ls: cannot access '/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/group_vars': No such file or directory
2025-10-13 10:10:43.796439 | controller | ls: cannot access '/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/group_vars': No such file or directory
2025-10-13 10:10:43.797863 | controller | ls: cannot access '/root/src/github.com/openstack-k8s-operators/ci-framework/group_vars': Permission denied
2025-10-13 10:10:43.800580 | controller | ls: cannot access '/workspace/src/github.com/openstack-k8s-operators/ci-framework/group_vars': No such file or directory
2025-10-13 10:10:44.012669 | controller | ERROR
2025-10-13 10:10:44.012832 | controller | {
2025-10-13 10:10:44.012863 | controller |   "delta": "0:00:00.010819",
2025-10-13 10:10:44.012890 | controller |   "end": "2025-10-13 10:10:43.800873",
2025-10-13 10:10:44.012931 | controller |   "msg": "non-zero return code",
2025-10-13 10:10:44.012964 | controller |   "rc": 2,
2025-10-13 10:10:44.012982 | controller |   "start": "2025-10-13 10:10:43.790054"
2025-10-13 10:10:44.012997 | controller | }
2025-10-13 10:10:44.013022 | controller | ERROR: Ignoring Errors
2025-10-13 10:10:44.018715 | 
2025-10-13 10:10:44.018779 | TASK [cifmw_helpers : List available files]
2025-10-13 10:10:44.545718 | controller | skipping: Conditional result was False
2025-10-13 10:10:44.551932 | 
2025-10-13 10:10:44.552018 | LOOP [cifmw_helpers : Read vars]
2025-10-13 10:10:44.591038 | 
2025-10-13 10:10:44.591197 | LOOP [cifmw_helpers : Call task to parse all files as fact]
2025-10-13 10:10:44.626036 | 
2025-10-13 10:10:44.626193 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2025-10-13 10:10:44.678686 | controller | ok
2025-10-13 10:10:44.698066 | 
2025-10-13 10:10:44.698154 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-10-13 10:10:44.726178 | controller | skipping: Conditional result was False
2025-10-13 10:10:44.732045 | 
2025-10-13 10:10:44.732130 | TASK [mirror-info-fork : Create /etc/ci]
2025-10-13 10:10:45.023715 | controller | ok
2025-10-13 10:10:45.029427 | 
2025-10-13 10:10:45.029493 | TASK [mirror-info-fork : Install ci_mirror script]
2025-10-13 10:10:45.536222 | controller | ok
2025-10-13 10:10:45.545453 | 
2025-10-13 10:10:45.545526 | TASK [Prepare workspace]
2025-10-13 10:10:45.563901 | controller | ok
2025-10-13 10:10:45.583962 | 
2025-10-13 10:10:45.584031 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-10-13 10:10:45.856721 | controller | ok
2025-10-13 10:10:45.862541 | 
2025-10-13 10:10:45.862613 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-10-13 10:10:48.341832 | controller | Output suppressed because no_log was given
2025-10-13 10:10:48.352504 | 
2025-10-13 10:10:48.352603 | LOOP [Create zuul-output directory]
2025-10-13 10:10:48.544527 | controller | changed: "/home/zuul/ci-framework-data/logs"
2025-10-13 10:10:48.544832 | 
2025-10-13 10:10:48.699019 | controller | ok: "/home/zuul/zuul-output/logs"
2025-10-13 10:10:48.709459 | 
2025-10-13 10:10:48.709559 | TASK [Install required packages]
2025-10-13 10:11:41.303765 | controller | changed
2025-10-13 10:11:41.313200 | 
2025-10-13 10:11:41.313273 | TASK [Install venv]
2025-10-13 10:12:47.339581 | controller | changed
2025-10-13 10:12:47.365766 | 
2025-10-13 10:12:47.365860 | PLAY RECAP
2025-10-13 10:12:47.365905 | controller | ok: 10 changed: 5 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2025-10-13 10:12:47.365963 | 
2025-10-13 10:12:47.462863 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-10-13 10:12:47.463694 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-10-13 10:12:48.038387 | 
2025-10-13 10:12:48.038497 | PLAY [Run ci/playbooks/molecule-test.yml]
2025-10-13 10:12:48.060199 | 
2025-10-13 10:12:48.060295 | TASK [Gather required facts]
2025-10-13 10:12:48.566143 | controller | ok
2025-10-13 10:12:48.571520 | 
2025-10-13 10:12:48.571624 | TASK [Load environment var if instructed to]
2025-10-13 10:12:48.595351 | controller | skipping: Conditional result was False
2025-10-13 10:12:48.601509 | 
2025-10-13 10:12:48.601585 | TASK [Ensure group_vars dir exists]
2025-10-13 10:12:48.945774 | controller | ok
2025-10-13 10:12:48.956480 | 
2025-10-13 10:12:48.956577 | TASK [Run molecule]
2025-10-13 10:12:49.945161 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2025-10-13 10:12:50.015479 | controller | INFO     Performing prerun with role_name_check=0...
2025-10-13 10:13:08.846744 | controller | WARNING  Another version of '@NAMESPACE@.@NAME@' 3.1.4 was found installed in /home/zuul/.cache/ansible-compat/29a3e1/collections/ansible_collections, only the first one will be used, 3.1.4 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-13 10:13:08.847157 | controller | WARNING  Another version of 'ansible.netcommon' 7.1.0 was found installed in /home/zuul/.cache/ansible-compat/29a3e1/collections/ansible_collections, only the first one will be used, 7.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-13 10:13:08.847644 | controller | WARNING  Another version of 'ansible.posix' 1.6.2 was found installed in /home/zuul/.cache/ansible-compat/29a3e1/collections/ansible_collections, only the first one will be used, 1.6.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-13 10:13:08.848098 | controller | WARNING  Another version of 'ansible.utils' 5.1.2 was found installed in /home/zuul/.cache/ansible-compat/29a3e1/collections/ansible_collections, only the first one will be used, 5.1.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-13 10:13:08.848609 | controller | WARNING  Another version of 'cifmw.general' 1.0.0+0029ba8c was found installed in /home/zuul/.cache/ansible-compat/29a3e1/collections/ansible_collections, only the first one will be used, 1.0.0+0029ba8c (/home/zuul/.ansible/collections/ansible_collections).
2025-10-13 10:13:08.849053 | controller | WARNING  Another version of 'cisco.ios' 9.0.3 was found installed in /home/zuul/.cache/ansible-compat/29a3e1/collections/ansible_collections, only the first one will be used, 9.0.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-13 10:13:08.849506 | controller | WARNING  Another version of 'community.crypto' 2.22.3 was found installed in /home/zuul/.cache/ansible-compat/29a3e1/collections/ansible_collections, only the first one will be used, 2.22.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-13 10:13:08.850034 | controller | WARNING  Another version of 'community.general' 10.0.1 was found installed in /home/zuul/.cache/ansible-compat/29a3e1/collections/ansible_collections, only the first one will be used, 10.0.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-13 10:13:08.850471 | controller | WARNING  Another version of 'community.libvirt' 1.3.0 was found installed in /home/zuul/.cache/ansible-compat/29a3e1/collections/ansible_collections, only the first one will be used, 1.3.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-13 10:13:08.850927 | controller | WARNING  Another version of 'community.okd' 4.0.0 was found installed in /home/zuul/.cache/ansible-compat/29a3e1/collections/ansible_collections, only the first one will be used, 4.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-13 10:13:08.851395 | controller | WARNING  Another version of 'containers.podman' 1.16.2 was found installed in /home/zuul/.cache/ansible-compat/29a3e1/collections/ansible_collections, only the first one will be used, 1.16.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-13 10:13:08.851863 | controller | WARNING  Another version of 'junipernetworks.junos' 9.1.0 was found installed in /home/zuul/.cache/ansible-compat/29a3e1/collections/ansible_collections, only the first one will be used, 9.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-13 10:13:08.852559 | controller | WARNING  Another version of 'kubernetes.core' 5.0.0 was found installed in /home/zuul/.cache/ansible-compat/29a3e1/collections/ansible_collections, only the first one will be used, 5.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-13 10:13:08.852974 | controller | WARNING  Another version of 'mellanox.onyx' 1.0.0 was found installed in /home/zuul/.cache/ansible-compat/29a3e1/collections/ansible_collections, only the first one will be used, 1.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-13 10:13:08.853432 | controller | WARNING  Another version of 'openstack.config_template' 2.1.1 was found installed in /home/zuul/.cache/ansible-compat/29a3e1/collections/ansible_collections, only the first one will be used, 2.1.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-13 10:13:08.863137 | controller | INFO     Running default > prepare
2025-10-13 10:13:08.863606 | controller | WARNING  Skipping, prepare playbook not configured.
2025-10-13 10:13:08.871306 | controller | INFO     Running default > converge
2025-10-13 10:13:09.540830 | controller |
2025-10-13 10:13:09.540924 | controller | PLAY [Converge test of controller tasks] ***************************************
2025-10-13 10:13:09.541015 | controller |
2025-10-13 10:13:09.541115 | controller | TASK [Gathering Facts] *********************************************************
2025-10-13 10:13:09.541212 | controller | Monday 13 October 2025  10:13:09 +0000 (0:00:00.011)       0:00:00.011 ********
2025-10-13 10:13:10.368114 | controller | ok: [instance]
2025-10-13 10:13:10.368170 | controller |
2025-10-13 10:13:10.368299 | controller | TASK [compliance : Ensure compliance folder exists] ****************************
2025-10-13 10:13:10.368408 | controller | Monday 13 October 2025  10:13:10 +0000 (0:00:00.827)       0:00:00.839 ********
2025-10-13 10:13:10.669091 | controller | changed: [instance]
2025-10-13 10:13:10.669162 | controller |
2025-10-13 10:13:10.669297 | controller | TASK [compliance : Install the compliance operator and plugin] *****************
2025-10-13 10:13:10.669409 | controller | Monday 13 October 2025  10:13:10 +0000 (0:00:00.300)       0:00:01.139 ********
2025-10-13 10:13:10.703627 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/install.yml for instance
2025-10-13 10:13:10.703701 | controller |
2025-10-13 10:13:10.703806 | controller | TASK [compliance : Create the compliance namespace] ****************************
2025-10-13 10:13:10.703912 | controller | Monday 13 October 2025  10:13:10 +0000 (0:00:00.034)       0:00:01.174 ********
2025-10-13 10:13:10.731179 | controller | skipping: [instance]
2025-10-13 10:13:10.731241 | controller |
2025-10-13 10:13:10.731385 | controller | TASK [compliance : Ensure OperatorGroup for the compliance-operator is present] ***
2025-10-13 10:13:10.731498 | controller | Monday 13 October 2025  10:13:10 +0000 (0:00:00.027)       0:00:01.202 ********
2025-10-13 10:13:10.757964 | controller | skipping: [instance]
2025-10-13 10:13:10.758042 | controller |
2025-10-13 10:13:10.758147 | controller | TASK [compliance : Ensure Subscription for the compliance-operator is present] ***
2025-10-13 10:13:10.758266 | controller | Monday 13 October 2025  10:13:10 +0000 (0:00:00.026)       0:00:01.229 ********
2025-10-13 10:13:10.786408 | controller | skipping: [instance]
2025-10-13 10:13:10.786490 | controller |
2025-10-13 10:13:10.786595 | controller | TASK [compliance : Wait until the compliance-operator csv is present] **********
2025-10-13 10:13:10.786715 | controller | Monday 13 October 2025  10:13:10 +0000 (0:00:00.028)       0:00:01.257 ********
2025-10-13 10:13:10.813013 | controller | skipping: [instance]
2025-10-13 10:13:10.813099 | controller |
2025-10-13 10:13:10.813203 | controller | TASK [compliance : Get full name of the compliance-operator csv] ***************
2025-10-13 10:13:10.813333 | controller | Monday 13 October 2025  10:13:10 +0000 (0:00:00.026)       0:00:01.284 ********
2025-10-13 10:13:10.840637 | controller | skipping: [instance]
2025-10-13 10:13:10.840731 | controller |
2025-10-13 10:13:10.840858 | controller | TASK [compliance : Wait for the compliance-operator csv to Succeed] ************
2025-10-13 10:13:10.840994 | controller | Monday 13 October 2025  10:13:10 +0000 (0:00:00.027)       0:00:01.311 ********
2025-10-13 10:13:10.867975 | controller | skipping: [instance]
2025-10-13 10:13:10.868050 | controller |
2025-10-13 10:13:10.868155 | controller | TASK [podman : Ensure podman is installed] *************************************
2025-10-13 10:13:10.868288 | controller | Monday 13 October 2025  10:13:10 +0000 (0:00:00.027)       0:00:01.339 ********
2025-10-13 10:13:11.625426 | controller | ok: [instance]
2025-10-13 10:13:11.625882 | controller |
2025-10-13 10:13:11.626002 | controller | TASK [podman : Enable loginctl linger for ansible_user_id] *********************
2025-10-13 10:13:11.626116 | controller | Monday 13 October 2025  10:13:11 +0000 (0:00:00.757)       0:00:02.096 ********
2025-10-13 10:13:11.949533 | controller | changed: [instance]
2025-10-13 10:13:11.949639 | controller |
2025-10-13 10:13:11.949793 | controller | TASK [podman : Ensure subordinate UID entry exists for zuul] *******************
2025-10-13 10:13:11.949922 | controller | Monday 13 October 2025  10:13:11 +0000 (0:00:00.324)       0:00:02.420 ********
2025-10-13 10:13:11.972361 | controller | skipping: [instance]
2025-10-13 10:13:11.972391 | controller |
2025-10-13 10:13:11.972399 | controller | TASK [podman : Ensure subordinate GID entry exists for zuul] *******************
2025-10-13 10:13:11.972407 | controller | Monday 13 October 2025  10:13:11 +0000 (0:00:00.022)       0:00:02.443 ********
2025-10-13 10:13:11.991811 | controller | skipping: [instance]
2025-10-13 10:13:11.991902 | controller |
2025-10-13 10:13:11.992015 | controller | TASK [podman : Run podman system migrate if subuid/subgid files were changed] ***
2025-10-13 10:13:11.992122 | controller | Monday 13 October 2025  10:13:11 +0000 (0:00:00.019)       0:00:02.463 ********
2025-10-13 10:13:12.014737 | controller | skipping: [instance]
2025-10-13 10:13:12.014850 | controller |
2025-10-13 10:13:12.014983 | controller | TASK [compliance : Log into registry] ******************************************
2025-10-13 10:13:12.015111 | controller | Monday 13 October 2025  10:13:12 +0000 (0:00:00.022)       0:00:02.485 ********
2025-10-13 10:13:12.030611 | controller | skipping: [instance]
2025-10-13 10:13:12.030737 | controller |
2025-10-13 10:13:12.030870 | controller | TASK [compliance : Copy out the compliance plugin from the oc-compliance image] ***
2025-10-13 10:13:12.031004 | controller | Monday 13 October 2025  10:13:12 +0000 (0:00:00.015)       0:00:02.501 ********
2025-10-13 10:13:12.046601 | controller | skipping: [instance]
2025-10-13 10:13:12.046699 | controller |
2025-10-13 10:13:12.046809 | controller | TASK [compliance : Install packages to process results] ************************
2025-10-13 10:13:12.046913 | controller | Monday 13 October 2025  10:13:12 +0000 (0:00:00.016)       0:00:02.517 ********
2025-10-13 10:13:16.140982 | controller | changed: [instance]
2025-10-13 10:13:16.141029 | controller |
2025-10-13 10:13:16.141265 | controller | TASK [compliance : Run compliance scans and extract results] *******************
2025-10-13 10:13:16.186394 | controller | Monday 13 October 2025  10:13:16 +0000 (0:00:04.093)       0:00:06.611 ********
2025-10-13 10:13:16.186438 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_suite_scan.yml for instance => (item=cis)
2025-10-13 10:13:16.186469 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_suite_scan.yml for instance => (item=e8)
2025-10-13 10:13:16.186478 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_suite_scan.yml for instance => (item=high)
2025-10-13 10:13:16.186485 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_suite_scan.yml for instance => (item=moderate)
2025-10-13 10:13:16.186492 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_suite_scan.yml for instance => (item=nerc-cip)
2025-10-13 10:13:16.186499 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_suite_scan.yml for instance => (item=pci-dss)
2025-10-13 10:13:16.186506 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_suite_scan.yml for instance => (item=stig)
2025-10-13 10:13:16.186513 | controller |
2025-10-13 10:13:16.186522 | controller | TASK [compliance : Log start of scans] *****************************************
2025-10-13 10:13:16.186591 | controller | Monday 13 October 2025  10:13:16 +0000 (0:00:00.044)       0:00:06.656 ********
2025-10-13 10:13:16.205801 | controller | ok: [instance] =>
2025-10-13 10:13:16.233500 | controller |   msg: Starting scans for cis
2025-10-13 10:13:16.233526 | controller |
2025-10-13 10:13:16.233534 | controller | TASK [compliance : Run scans and extract results] ******************************
2025-10-13 10:13:16.233541 | controller | Monday 13 October 2025  10:13:16 +0000 (0:00:00.020)       0:00:06.676 ********
2025-10-13 10:13:16.233550 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_operator_scan.yml for instance => (item=ocp4-cis)
2025-10-13 10:13:16.233692 | controller |
2025-10-13 10:13:16.233835 | controller | TASK [compliance : Set output directory] ***************************************
2025-10-13 10:13:16.233997 | controller | Monday 13 October 2025  10:13:16 +0000 (0:00:00.028)       0:00:06.704 ********
2025-10-13 10:13:16.256221 | controller | ok: [instance]
2025-10-13 10:13:16.256433 | controller |
2025-10-13 10:13:16.256585 | controller | TASK [compliance : Create scan binding] ****************************************
2025-10-13 10:13:16.256725 | controller | Monday 13 October 2025  10:13:16 +0000 (0:00:00.022)       0:00:06.727 ********
2025-10-13 10:13:16.272796 | controller | skipping: [instance]
2025-10-13 10:13:16.288714 | controller |
2025-10-13 10:13:16.288733 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-10-13 10:13:16.288741 | controller | Monday 13 October 2025  10:13:16 +0000 (0:00:00.016)       0:00:06.743 ********
2025-10-13 10:13:16.288749 | controller | skipping: [instance]
2025-10-13 10:13:16.467351 | controller |
2025-10-13 10:13:16.467386 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-10-13 10:13:16.467394 | controller | Monday 13 October 2025  10:13:16 +0000 (0:00:00.015)       0:00:06.759 ********
2025-10-13 10:13:16.467404 | controller | ok: [instance]
2025-10-13 10:13:16.467432 | controller |
2025-10-13 10:13:16.467647 | controller | TASK [compliance : Create directory for scan results] **************************
2025-10-13 10:13:16.656278 | controller | Monday 13 October 2025  10:13:16 +0000 (0:00:00.178)       0:00:06.938 ********
2025-10-13 10:13:16.656316 | controller | changed: [instance]
2025-10-13 10:13:16.672799 | controller |
2025-10-13 10:13:16.672821 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-10-13 10:13:16.672829 | controller | Monday 13 October 2025  10:13:16 +0000 (0:00:00.188)       0:00:07.126 ********
2025-10-13 10:13:16.672839 | controller | skipping: [instance]
2025-10-13 10:13:16.951005 | controller |
2025-10-13 10:13:16.951034 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-10-13 10:13:16.951042 | controller | Monday 13 October 2025  10:13:16 +0000 (0:00:00.016)       0:00:07.143 ********
2025-10-13 10:13:16.951052 | controller | ok: [instance]
2025-10-13 10:13:16.965314 | controller |
2025-10-13 10:13:16.965338 | controller | TASK [compliance : Process scan result files] **********************************
2025-10-13 10:13:16.965345 | controller | Monday 13 October 2025  10:13:16 +0000 (0:00:00.278)       0:00:07.421 ********
2025-10-13 10:13:16.965355 | controller | skipping: [instance]
2025-10-13 10:13:16.965434 | controller |
2025-10-13 10:13:16.965452 | controller | TASK [compliance : Log start of scans] *****************************************
2025-10-13 10:13:16.965463 | controller | Monday 13 October 2025  10:13:16 +0000 (0:00:00.014)       0:00:07.436 ********
2025-10-13 10:13:16.983318 | controller | ok: [instance] =>
2025-10-13 10:13:16.983358 | controller |   msg: Starting scans for e8
2025-10-13 10:13:16.983366 | controller |
2025-10-13 10:13:16.983372 | controller | TASK [compliance : Run scans and extract results] ******************************
2025-10-13 10:13:16.983380 | controller | Monday 13 October 2025  10:13:16 +0000 (0:00:00.017)       0:00:07.454 ********
2025-10-13 10:13:17.015171 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_operator_scan.yml for instance => (item=ocp4-e8)
2025-10-13 10:13:17.037324 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_operator_scan.yml for instance => (item=rhcos4-e8)
2025-10-13 10:13:17.037373 | controller |
2025-10-13 10:13:17.037382 | controller | TASK [compliance : Set output directory] ***************************************
2025-10-13 10:13:17.037388 | controller | Monday 13 October 2025  10:13:17 +0000 (0:00:00.031)       0:00:07.485 ********
2025-10-13 10:13:17.037400 | controller | ok: [instance]
2025-10-13 10:13:17.054198 | controller |
2025-10-13 10:13:17.054227 | controller | TASK [compliance : Create scan binding] ****************************************
2025-10-13 10:13:17.054236 | controller | Monday 13 October 2025  10:13:17 +0000 (0:00:00.022)       0:00:07.508 ********
2025-10-13 10:13:17.054245 | controller | skipping: [instance]
2025-10-13 10:13:17.071175 | controller |
2025-10-13 10:13:17.071214 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-10-13 10:13:17.071223 | controller | Monday 13 October 2025  10:13:17 +0000 (0:00:00.016)       0:00:07.525 ********
2025-10-13 10:13:17.071233 | controller | skipping: [instance]
2025-10-13 10:13:17.262065 | controller |
2025-10-13 10:13:17.262095 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-10-13 10:13:17.262110 | controller | Monday 13 October 2025  10:13:17 +0000 (0:00:00.016)       0:00:07.541 ********
2025-10-13 10:13:17.262121 | controller | ok: [instance]
2025-10-13 10:13:17.452491 | controller |
2025-10-13 10:13:17.452521 | controller | TASK [compliance : Create directory for scan results] **************************
2025-10-13 10:13:17.452529 | controller | Monday 13 October 2025  10:13:17 +0000 (0:00:00.190)       0:00:07.732 ********
2025-10-13 10:13:17.452539 | controller | changed: [instance]
2025-10-13 10:13:17.469943 | controller |
2025-10-13 10:13:17.469968 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-10-13 10:13:17.469975 | controller | Monday 13 October 2025  10:13:17 +0000 (0:00:00.190)       0:00:07.923 ********
2025-10-13 10:13:17.469990 | controller | skipping: [instance]
2025-10-13 10:13:17.645823 | controller |
2025-10-13 10:13:17.645854 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-10-13 10:13:17.645862 | controller | Monday 13 October 2025  10:13:17 +0000 (0:00:00.017)       0:00:07.940 ********
2025-10-13 10:13:17.645872 | controller | ok: [instance]
2025-10-13 10:13:17.660340 | controller |
2025-10-13 10:13:17.660364 | controller | TASK [compliance : Process scan result files] **********************************
2025-10-13 10:13:17.660372 | controller | Monday 13 October 2025  10:13:17 +0000 (0:00:00.175)       0:00:08.116 ********
2025-10-13 10:13:17.660381 | controller | skipping: [instance]
2025-10-13 10:13:17.684245 | controller |
2025-10-13 10:13:17.684284 | controller | TASK [compliance : Set output directory] ***************************************
2025-10-13 10:13:17.684292 | controller | Monday 13 October 2025  10:13:17 +0000 (0:00:00.014)       0:00:08.131 ********
2025-10-13 10:13:17.684301 | controller | ok: [instance]
2025-10-13 10:13:17.701324 | controller |
2025-10-13 10:13:17.701350 | controller | TASK [compliance : Create scan binding] ****************************************
2025-10-13 10:13:17.701357 | controller | Monday 13 October 2025  10:13:17 +0000 (0:00:00.023)       0:00:08.154 ********
2025-10-13 10:13:17.701368 | controller | skipping: [instance]
2025-10-13 10:13:17.718087 | controller |
2025-10-13 10:13:17.718121 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-10-13 10:13:17.718130 | controller | Monday 13 October 2025  10:13:17 +0000 (0:00:00.017)       0:00:08.171 ********
2025-10-13 10:13:17.718141 | controller | skipping: [instance]
2025-10-13 10:13:17.718338 | controller |
2025-10-13 10:13:17.718488 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-10-13 10:13:17.718627 | controller | Monday 13 October 2025  10:13:17 +0000 (0:00:00.017)       0:00:08.189 ********
2025-10-13 10:13:17.896331 | controller | ok: [instance]
2025-10-13 10:13:17.896362 | controller |
2025-10-13 10:13:17.896371 | controller | TASK [compliance : Create directory for scan results] **************************
2025-10-13 10:13:17.896380 | controller | Monday 13 October 2025  10:13:17 +0000 (0:00:00.177)       0:00:08.366 ********
2025-10-13 10:13:18.088345 | controller | changed: [instance]
2025-10-13 10:13:18.088561 | controller |
2025-10-13 10:13:18.088596 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-10-13 10:13:18.105138 | controller | Monday 13 October 2025  10:13:18 +0000 (0:00:00.192)       0:00:08.559 ********
2025-10-13 10:13:18.105160 | controller | skipping: [instance]
2025-10-13 10:13:18.282902 | controller |
2025-10-13 10:13:18.282946 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-10-13 10:13:18.282955 | controller | Monday 13 October 2025  10:13:18 +0000 (0:00:00.016)       0:00:08.576 ********
2025-10-13 10:13:18.282965 | controller | ok: [instance]
2025-10-13 10:13:18.296993 | controller |
2025-10-13 10:13:18.297016 | controller | TASK [compliance : Process scan result files] **********************************
2025-10-13 10:13:18.297024 | controller | Monday 13 October 2025  10:13:18 +0000 (0:00:00.177)       0:00:08.753 ********
2025-10-13 10:13:18.297033 | controller | skipping: [instance]
2025-10-13 10:13:18.317170 | controller |
2025-10-13 10:13:18.317194 | controller | TASK [compliance : Log start of scans] *****************************************
2025-10-13 10:13:18.317202 | controller | Monday 13 October 2025  10:13:18 +0000 (0:00:00.014)       0:00:08.767 ********
2025-10-13 10:13:18.317211 | controller | ok: [instance] =>
2025-10-13 10:13:18.355374 | controller |   msg: Starting scans for high
2025-10-13 10:13:18.355416 | controller |
2025-10-13 10:13:18.355426 | controller | TASK [compliance : Run scans and extract results] ******************************
2025-10-13 10:13:18.355434 | controller | Monday 13 October 2025  10:13:18 +0000 (0:00:00.019)       0:00:08.787 ********
2025-10-13 10:13:18.355446 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_operator_scan.yml for instance => (item=ocp4-high)
2025-10-13 10:13:18.378578 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_operator_scan.yml for instance => (item=ocp4-high-node)
2025-10-13 10:13:18.378604 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_operator_scan.yml for instance => (item=rhcos4-high)
2025-10-13 10:13:18.378612 | controller |
2025-10-13 10:13:18.378618 | controller | TASK [compliance : Set output directory] ***************************************
2025-10-13 10:13:18.378624 | controller | Monday 13 October 2025  10:13:18 +0000 (0:00:00.037)       0:00:08.825 ********
2025-10-13 10:13:18.378633 | controller | ok: [instance]
2025-10-13 10:13:18.395736 | controller |
2025-10-13 10:13:18.395755 | controller | TASK [compliance : Create scan binding] ****************************************
2025-10-13 10:13:18.395763 | controller | Monday 13 October 2025  10:13:18 +0000 (0:00:00.023)       0:00:08.849 ********
2025-10-13 10:13:18.395772 | controller | skipping: [instance]
2025-10-13 10:13:18.412329 | controller |
2025-10-13 10:13:18.412362 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-10-13 10:13:18.412370 | controller | Monday 13 October 2025  10:13:18 +0000 (0:00:00.017)       0:00:08.866 ********
2025-10-13 10:13:18.412380 | controller | skipping: [instance]
2025-10-13 10:13:18.412570 | controller |
2025-10-13 10:13:18.412593 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-10-13 10:13:18.604361 | controller | Monday 13 October 2025  10:13:18 +0000 (0:00:00.016)       0:00:08.883 ********
2025-10-13 10:13:18.604398 | controller | ok: [instance]
2025-10-13 10:13:18.792327 | controller |
2025-10-13 10:13:18.792359 | controller | TASK [compliance : Create directory for scan results] **************************
2025-10-13 10:13:18.792367 | controller | Monday 13 October 2025  10:13:18 +0000 (0:00:00.191)       0:00:09.075 ********
2025-10-13 10:13:18.792377 | controller | changed: [instance]
2025-10-13 10:13:18.792402 | controller |
2025-10-13 10:13:18.792653 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-10-13 10:13:18.809012 | controller | Monday 13 October 2025  10:13:18 +0000 (0:00:00.188)       0:00:09.263 ********
2025-10-13 10:13:18.809051 | controller | skipping: [instance]
2025-10-13 10:13:18.986968 | controller |
2025-10-13 10:13:18.987008 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-10-13 10:13:18.987017 | controller | Monday 13 October 2025  10:13:18 +0000 (0:00:00.016)       0:00:09.279 ********
2025-10-13 10:13:18.987028 | controller | ok: [instance]
2025-10-13 10:13:19.001456 | controller |
2025-10-13 10:13:19.001477 | controller | TASK [compliance : Process scan result files] **********************************
2025-10-13 10:13:19.001486 | controller | Monday 13 October 2025  10:13:18 +0000 (0:00:00.177)       0:00:09.457 ********
2025-10-13 10:13:19.001498 | controller | skipping: [instance]
2025-10-13 10:13:19.023370 | controller |
2025-10-13 10:13:19.023406 | controller | TASK [compliance : Set output directory] ***************************************
2025-10-13 10:13:19.023415 | controller | Monday 13 October 2025  10:13:18 +0000 (0:00:00.015)       0:00:09.472 ********
2025-10-13 10:13:19.023425 | controller | ok: [instance]
2025-10-13 10:13:19.023475 | controller |
2025-10-13 10:13:19.023492 | controller | TASK [compliance : Create scan binding] ****************************************
2025-10-13 10:13:19.023503 | controller | Monday 13 October 2025  10:13:19 +0000 (0:00:00.022)       0:00:09.494 ********
2025-10-13 10:13:19.040047 | controller | skipping: [instance]
2025-10-13 10:13:19.056648 | controller |
2025-10-13 10:13:19.056668 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-10-13 10:13:19.056678 | controller | Monday 13 October 2025  10:13:19 +0000 (0:00:00.016)       0:00:09.510 ********
2025-10-13 10:13:19.056688 | controller | skipping: [instance]
2025-10-13 10:13:19.056868 | controller |
2025-10-13 10:13:19.057035 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-10-13 10:13:19.057179 | controller | Monday 13 October 2025  10:13:19 +0000 (0:00:00.017)       0:00:09.527 ********
2025-10-13 10:13:19.236368 | controller | ok: [instance]
2025-10-13 10:13:19.236404 | controller |
2025-10-13 10:13:19.236414 | controller | TASK [compliance : Create directory for scan results] **************************
2025-10-13 10:13:19.236424 | controller | Monday 13 October 2025  10:13:19 +0000 (0:00:00.179)       0:00:09.707 ********
2025-10-13 10:13:19.423675 | controller | changed: [instance]
2025-10-13 10:13:19.440151 | controller |
2025-10-13 10:13:19.440181 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-10-13 10:13:19.440191 | controller | Monday 13 October 2025  10:13:19 +0000 (0:00:00.187)       0:00:09.894 ********
2025-10-13 10:13:19.440203 | controller | skipping: [instance]
2025-10-13 10:13:19.626762 | controller |
2025-10-13 10:13:19.626791 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-10-13 10:13:19.626800 | controller | Monday 13 October 2025  10:13:19 +0000 (0:00:00.016)       0:00:09.910 ********
2025-10-13 10:13:19.626810 | controller | ok: [instance]
2025-10-13 10:13:19.641481 | controller |
2025-10-13 10:13:19.641504 | controller | TASK [compliance : Process scan result files] **********************************
2025-10-13 10:13:19.641512 | controller | Monday 13 October 2025  10:13:19 +0000 (0:00:00.186)       0:00:10.097 ********
2025-10-13 10:13:19.641521 | controller | skipping: [instance]
2025-10-13 10:13:19.665132 | controller |
2025-10-13 10:13:19.665155 | controller | TASK [compliance : Set output directory] ***************************************
2025-10-13 10:13:19.665162 | controller | Monday 13 October 2025  10:13:19 +0000 (0:00:00.014)       0:00:10.112 ********
2025-10-13 10:13:19.665172 | controller | ok: [instance]
2025-10-13 10:13:19.682050 | controller |
2025-10-13 10:13:19.682075 | controller | TASK [compliance : Create scan binding] ****************************************
2025-10-13 10:13:19.682082 | controller | Monday 13 October 2025  10:13:19 +0000 (0:00:00.023)       0:00:10.135 ********
2025-10-13 10:13:19.682093 | controller | skipping: [instance]
2025-10-13 10:13:19.699380 | controller |
2025-10-13 10:13:19.699417 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-10-13 10:13:19.699428 | controller | Monday 13 October 2025  10:13:19 +0000 (0:00:00.016)       0:00:10.152 ********
2025-10-13 10:13:19.699450 | controller | skipping: [instance]
2025-10-13 10:13:19.699483 | controller |
2025-10-13 10:13:19.699491 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-10-13 10:13:19.699501 | controller | Monday 13 October 2025  10:13:19 +0000 (0:00:00.017)       0:00:10.170 ********
2025-10-13 10:13:19.886214 | controller | ok: [instance]
2025-10-13 10:13:20.076475 | controller |
2025-10-13 10:13:20.076507 | controller | TASK [compliance : Create directory for scan results] **************************
2025-10-13 10:13:20.076515 | controller | Monday 13 October 2025  10:13:19 +0000 (0:00:00.186)       0:00:10.356 ********
2025-10-13 10:13:20.076525 | controller | changed: [instance]
2025-10-13 10:13:20.093840 | controller |
2025-10-13 10:13:20.093863 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-10-13 10:13:20.093871 | controller | Monday 13 October 2025  10:13:20 +0000 (0:00:00.190)       0:00:10.547 ********
2025-10-13 10:13:20.093881 | controller | skipping: [instance]
2025-10-13 10:13:20.270773 | controller |
2025-10-13 10:13:20.270804 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-10-13 10:13:20.270812 | controller | Monday 13 October 2025  10:13:20 +0000 (0:00:00.017)       0:00:10.564 ********
2025-10-13 10:13:20.270822 | controller | ok: [instance]
2025-10-13 10:13:20.285324 | controller |
2025-10-13 10:13:20.285347 | controller | TASK [compliance : Process scan result files] **********************************
2025-10-13 10:13:20.285355 | controller | Monday 13 October 2025  10:13:20 +0000 (0:00:00.176)       0:00:10.741 ********
2025-10-13 10:13:20.285364 | controller | skipping: [instance]
2025-10-13 10:13:20.285387 | controller |
2025-10-13 10:13:20.285394 | controller | TASK [compliance : Log start of scans] *****************************************
2025-10-13 10:13:20.285471 | controller | Monday 13 October 2025  10:13:20 +0000 (0:00:00.014)       0:00:10.756 ********
2025-10-13 10:13:20.304130 | controller | ok: [instance] =>
2025-10-13 10:13:20.341823 | controller |   msg: Starting scans for moderate
2025-10-13 10:13:20.341841 | controller |
2025-10-13 10:13:20.341848 | controller | TASK [compliance : Run scans and extract results] ******************************
2025-10-13 10:13:20.341855 | controller | Monday 13 October 2025  10:13:20 +0000 (0:00:00.018)       0:00:10.774 ********
2025-10-13 10:13:20.341863 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_operator_scan.yml for instance => (item=ocp4-moderate)
2025-10-13 10:13:20.365345 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_operator_scan.yml for instance => (item=ocp4-moderate-node)
2025-10-13 10:13:20.365381 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_operator_scan.yml for instance => (item=rhcos4-moderate)
2025-10-13 10:13:20.365389 | controller |
2025-10-13 10:13:20.365396 | controller | TASK [compliance : Set output directory] ***************************************
2025-10-13 10:13:20.365402 | controller | Monday 13 October 2025  10:13:20 +0000 (0:00:00.037)       0:00:10.812 ********
2025-10-13 10:13:20.365412 | controller | ok: [instance]
2025-10-13 10:13:20.365442 | controller |
2025-10-13 10:13:20.365580 | controller | TASK [compliance : Create scan binding] ****************************************
2025-10-13 10:13:20.365600 | controller | Monday 13 October 2025  10:13:20 +0000 (0:00:00.023)       0:00:10.836 ********
2025-10-13 10:13:20.382354 | controller | skipping: [instance]
2025-10-13 10:13:20.382585 | controller |
2025-10-13 10:13:20.382602 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-10-13 10:13:20.398994 | controller | Monday 13 October 2025  10:13:20 +0000 (0:00:00.017)       0:00:10.853 ********
2025-10-13 10:13:20.399014 | controller | skipping: [instance]
2025-10-13 10:13:20.580760 | controller |
2025-10-13 10:13:20.580790 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-10-13 10:13:20.580798 | controller | Monday 13 October 2025  10:13:20 +0000 (0:00:00.016)       0:00:10.869 ********
2025-10-13 10:13:20.580808 | controller | ok: [instance]
2025-10-13 10:13:20.769959 | controller |
2025-10-13 10:13:20.769989 | controller | TASK [compliance : Create directory for scan results] **************************
2025-10-13 10:13:20.769998 | controller | Monday 13 October 2025  10:13:20 +0000 (0:00:00.181)       0:00:11.051 ********
2025-10-13 10:13:20.770008 | controller | changed: [instance]
2025-10-13 10:13:20.787125 | controller |
2025-10-13 10:13:20.787150 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-10-13 10:13:20.787159 | controller | Monday 13 October 2025  10:13:20 +0000 (0:00:00.189)       0:00:11.240 ********
2025-10-13 10:13:20.787170 | controller | skipping: [instance]
2025-10-13 10:13:20.959395 | controller |
2025-10-13 10:13:20.959424 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-10-13 10:13:20.959433 | controller | Monday 13 October 2025  10:13:20 +0000 (0:00:00.017)       0:00:11.257 ********
2025-10-13 10:13:20.959443 | controller | ok: [instance]
2025-10-13 10:13:20.959828 | controller |
2025-10-13 10:13:20.973203 | controller | TASK [compliance : Process scan result files] **********************************
2025-10-13 10:13:20.973223 | controller | Monday 13 October 2025  10:13:20 +0000 (0:00:00.172)       0:00:11.430 ********
2025-10-13 10:13:20.973234 | controller | skipping: [instance]
2025-10-13 10:13:20.995664 | controller |
2025-10-13 10:13:20.995689 | controller | TASK [compliance : Set output directory] ***************************************
2025-10-13 10:13:20.995702 | controller | Monday 13 October 2025  10:13:20 +0000 (0:00:00.013)       0:00:11.444 ********
2025-10-13 10:13:20.995712 | controller | ok: [instance]
2025-10-13 10:13:21.011967 | controller |
2025-10-13 10:13:21.011991 | controller | TASK [compliance : Create scan binding] ****************************************
2025-10-13 10:13:21.012000 | controller | Monday 13 October 2025  10:13:20 +0000 (0:00:00.022)       0:00:11.466 ********
2025-10-13 10:13:21.012009 | controller | skipping: [instance]
2025-10-13 10:13:21.028289 | controller |
2025-10-13 10:13:21.028312 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-10-13 10:13:21.028320 | controller | Monday 13 October 2025  10:13:21 +0000 (0:00:00.016)       0:00:11.482 ********
2025-10-13 10:13:21.028336 | controller | skipping: [instance]
2025-10-13 10:13:21.204852 | controller |
2025-10-13 10:13:21.204882 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-10-13 10:13:21.204890 | controller | Monday 13 October 2025  10:13:21 +0000 (0:00:00.016)       0:00:11.499 ********
2025-10-13 10:13:21.204900 | controller | ok: [instance]
2025-10-13 10:13:21.387587 | controller |
2025-10-13 10:13:21.387616 | controller | TASK [compliance : Create directory for scan results] **************************
2025-10-13 10:13:21.387624 | controller | Monday 13 October 2025  10:13:21 +0000 (0:00:00.176)       0:00:11.675 ********
2025-10-13 10:13:21.387634 | controller | changed: [instance]
2025-10-13 10:13:21.403692 | controller |
2025-10-13 10:13:21.403715 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-10-13 10:13:21.403723 | controller | Monday 13 October 2025  10:13:21 +0000 (0:00:00.182)       0:00:11.858 ********
2025-10-13 10:13:21.403732 | controller | skipping: [instance]
2025-10-13 10:13:21.575998 | controller |
2025-10-13 10:13:21.576027 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-10-13 10:13:21.576035 | controller | Monday 13 October 2025  10:13:21 +0000 (0:00:00.016)       0:00:11.874 ********
2025-10-13 10:13:21.576046 | controller | ok: [instance]
2025-10-13 10:13:21.589950 | controller |
2025-10-13 10:13:21.589974 | controller | TASK [compliance : Process scan result files] **********************************
2025-10-13 10:13:21.589981 | controller | Monday 13 October 2025  10:13:21 +0000 (0:00:00.172)       0:00:12.046 ********
2025-10-13 10:13:21.589991 | controller | skipping: [instance]
2025-10-13 10:13:21.611858 | controller |
2025-10-13 10:13:21.611886 | controller | TASK [compliance : Set output directory] ***************************************
2025-10-13 10:13:21.611893 | controller | Monday 13 October 2025  10:13:21 +0000 (0:00:00.014)       0:00:12.060 ********
2025-10-13 10:13:21.611903 | controller | ok: [instance]
2025-10-13 10:13:21.626936 | controller |
2025-10-13 10:13:21.626962 | controller | TASK [compliance : Create scan binding] ****************************************
2025-10-13 10:13:21.626970 | controller | Monday 13 October 2025  10:13:21 +0000 (0:00:00.021)       0:00:12.082 ********
2025-10-13 10:13:21.626979 | controller | skipping: [instance]
2025-10-13 10:13:21.642160 | controller |
2025-10-13 10:13:21.642181 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-10-13 10:13:21.642188 | controller | Monday 13 October 2025  10:13:21 +0000 (0:00:00.015)       0:00:12.097 ********
2025-10-13 10:13:21.642197 | controller | skipping: [instance]
2025-10-13 10:13:21.814101 | controller |
2025-10-13 10:13:21.814132 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-10-13 10:13:21.814141 | controller | Monday 13 October 2025  10:13:21 +0000 (0:00:00.015)       0:00:12.112 ********
2025-10-13 10:13:21.814152 | controller | ok: [instance]
2025-10-13 10:13:21.993880 | controller |
2025-10-13 10:13:21.993910 | controller | TASK [compliance : Create directory for scan results] **************************
2025-10-13 10:13:21.993918 | controller | Monday 13 October 2025  10:13:21 +0000 (0:00:00.171)       0:00:12.284 ********
2025-10-13 10:13:21.993928 | controller | changed: [instance]
2025-10-13 10:13:22.009085 | controller |
2025-10-13 10:13:22.009128 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-10-13 10:13:22.009137 | controller | Monday 13 October 2025  10:13:21 +0000 (0:00:00.179)       0:00:12.464 ********
2025-10-13 10:13:22.009148 | controller | skipping: [instance]
2025-10-13 10:13:22.177985 | controller |
2025-10-13 10:13:22.178017 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-10-13 10:13:22.178025 | controller | Monday 13 October 2025  10:13:22 +0000 (0:00:00.015)       0:00:12.479 ********
2025-10-13 10:13:22.178035 | controller | ok: [instance]
2025-10-13 10:13:22.190989 | controller |
2025-10-13 10:13:22.191010 | controller | TASK [compliance : Process scan result files] **********************************
2025-10-13 10:13:22.191018 | controller | Monday 13 October 2025  10:13:22 +0000 (0:00:00.168)       0:00:12.648 ********
2025-10-13 10:13:22.191031 | controller | skipping: [instance]
2025-10-13 10:13:22.208421 | controller |
2025-10-13 10:13:22.208440 | controller | TASK [compliance : Log start of scans] *****************************************
2025-10-13 10:13:22.208453 | controller | Monday 13 October 2025  10:13:22 +0000 (0:00:00.013)       0:00:12.661 ********
2025-10-13 10:13:22.208462 | controller | ok: [instance] =>
2025-10-13 10:13:22.238366 | controller |   msg: Starting scans for nerc-cip
2025-10-13 10:13:22.238402 | controller |
2025-10-13 10:13:22.238412 | controller | TASK [compliance : Run scans and extract results] ******************************
2025-10-13 10:13:22.238420 | controller | Monday 13 October 2025  10:13:22 +0000 (0:00:00.017)       0:00:12.679 ********
2025-10-13 10:13:22.238433 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_operator_scan.yml for instance => (item=ocp4-nerc-cip)
2025-10-13 10:13:22.238461 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_operator_scan.yml for instance => (item=ocp4-nerc-cip-node)
2025-10-13 10:13:22.238469 | controller |
2025-10-13 10:13:22.238478 | controller | TASK [compliance : Set output directory] ***************************************
2025-10-13 10:13:22.238567 | controller | Monday 13 October 2025  10:13:22 +0000 (0:00:00.030)       0:00:12.709 ********
2025-10-13 10:13:22.259958 | controller | ok: [instance]
2025-10-13 10:13:22.275969 | controller |
2025-10-13 10:13:22.275989 | controller | TASK [compliance : Create scan binding] ****************************************
2025-10-13 10:13:22.275996 | controller | Monday 13 October 2025  10:13:22 +0000 (0:00:00.021)       0:00:12.730 ********
2025-10-13 10:13:22.276005 | controller | skipping: [instance]
2025-10-13 10:13:22.276171 | controller |
2025-10-13 10:13:22.276352 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-10-13 10:13:22.276500 | controller | Monday 13 October 2025  10:13:22 +0000 (0:00:00.016)       0:00:12.747 ********
2025-10-13 10:13:22.291431 | controller | skipping: [instance]
2025-10-13 10:13:22.291612 | controller |
2025-10-13 10:13:22.291785 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-10-13 10:13:22.291954 | controller | Monday 13 October 2025  10:13:22 +0000 (0:00:00.015)       0:00:12.762 ********
2025-10-13 10:13:22.467036 | controller | ok: [instance]
2025-10-13 10:13:22.652325 | controller |
2025-10-13 10:13:22.652356 | controller | TASK [compliance : Create directory for scan results] **************************
2025-10-13 10:13:22.652365 | controller | Monday 13 October 2025  10:13:22 +0000 (0:00:00.175)       0:00:12.937 ********
2025-10-13 10:13:22.652376 | controller | changed: [instance]
2025-10-13 10:13:22.652456 | controller |
2025-10-13 10:13:22.652486 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-10-13 10:13:22.652499 | controller | Monday 13 October 2025  10:13:22 +0000 (0:00:00.185)       0:00:13.123 ********
2025-10-13 10:13:22.667510 | controller | skipping: [instance]
2025-10-13 10:13:22.837331 | controller |
2025-10-13 10:13:22.837361 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-10-13 10:13:22.837370 | controller | Monday 13 October 2025  10:13:22 +0000 (0:00:00.015)       0:00:13.138 ********
2025-10-13 10:13:22.837381 | controller | ok: [instance]
2025-10-13 10:13:22.851768 | controller |
2025-10-13 10:13:22.851804 | controller | TASK [compliance : Process scan result files] **********************************
2025-10-13 10:13:22.851814 | controller | Monday 13 October 2025  10:13:22 +0000 (0:00:00.169)       0:00:13.308 ********
2025-10-13 10:13:22.851827 | controller | skipping: [instance]
2025-10-13 10:13:22.872945 | controller |
2025-10-13 10:13:22.872964 | controller | TASK [compliance : Set output directory] ***************************************
2025-10-13 10:13:22.872972 | controller | Monday 13 October 2025  10:13:22 +0000 (0:00:00.014)       0:00:13.322 ********
2025-10-13 10:13:22.872983 | controller | ok: [instance]
2025-10-13 10:13:22.889448 | controller |
2025-10-13 10:13:22.889464 | controller | TASK [compliance : Create scan binding] ****************************************
2025-10-13 10:13:22.889470 | controller | Monday 13 October 2025  10:13:22 +0000 (0:00:00.021)       0:00:13.343 ********
2025-10-13 10:13:22.889479 | controller | skipping: [instance]
2025-10-13 10:13:22.904165 | controller |
2025-10-13 10:13:22.904184 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-10-13 10:13:22.904192 | controller | Monday 13 October 2025  10:13:22 +0000 (0:00:00.016)       0:00:13.360 ********
2025-10-13 10:13:22.904202 | controller | skipping: [instance]
2025-10-13 10:13:23.074328 | controller |
2025-10-13 10:13:23.074358 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-10-13 10:13:23.074367 | controller | Monday 13 October 2025  10:13:22 +0000 (0:00:00.014)       0:00:13.374 ********
2025-10-13 10:13:23.074378 | controller | ok: [instance]
2025-10-13 10:13:23.074596 | controller |
2025-10-13 10:13:23.074629 | controller | TASK [compliance : Create directory for scan results] **************************
2025-10-13 10:13:23.254811 | controller | Monday 13 October 2025  10:13:23 +0000 (0:00:00.170)       0:00:13.545 ********
2025-10-13 10:13:23.254845 | controller | changed: [instance]
2025-10-13 10:13:23.269998 | controller |
2025-10-13 10:13:23.270024 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-10-13 10:13:23.270034 | controller | Monday 13 October 2025  10:13:23 +0000 (0:00:00.180)       0:00:13.725 ********
2025-10-13 10:13:23.270045 | controller | skipping: [instance]
2025-10-13 10:13:23.438879 | controller |
2025-10-13 10:13:23.438909 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-10-13 10:13:23.438917 | controller | Monday 13 October 2025  10:13:23 +0000 (0:00:00.015)       0:00:13.740 ********
2025-10-13 10:13:23.438927 | controller | ok: [instance]
2025-10-13 10:13:23.451595 | controller |
2025-10-13 10:13:23.451612 | controller | TASK [compliance : Process scan result files] **********************************
2025-10-13 10:13:23.451619 | controller | Monday 13 October 2025  10:13:23 +0000 (0:00:00.168)       0:00:13.909 ********
2025-10-13 10:13:23.451628 | controller | skipping: [instance]
2025-10-13 10:13:23.468336 | controller |
2025-10-13 10:13:23.468366 | controller | TASK [compliance : Log start of scans] *****************************************
2025-10-13 10:13:23.468375 | controller | Monday 13 October 2025  10:13:23 +0000 (0:00:00.012)       0:00:13.922 ********
2025-10-13 10:13:23.468385 | controller | ok: [instance] =>
2025-10-13 10:13:23.468452 | controller |   msg: Starting scans for pci-dss
2025-10-13 10:13:23.468473 | controller |
2025-10-13 10:13:23.468481 | controller | TASK [compliance : Run scans and extract results] ******************************
2025-10-13 10:13:23.468490 | controller | Monday 13 October 2025  10:13:23 +0000 (0:00:00.016)       0:00:13.939 ********
2025-10-13 10:13:23.500384 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_operator_scan.yml for instance => (item=ocp4-pci-dss)
2025-10-13 10:13:23.523165 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_operator_scan.yml for instance => (item=ocp4-pci-dss-node)
2025-10-13 10:13:23.523190 | controller |
2025-10-13 10:13:23.523198 | controller | TASK [compliance : Set output directory] ***************************************
2025-10-13 10:13:23.523204 | controller | Monday 13 October 2025  10:13:23 +0000 (0:00:00.031)       0:00:13.971 ********
2025-10-13 10:13:23.523213 | controller | ok: [instance]
2025-10-13 10:13:23.538332 | controller |
2025-10-13 10:13:23.538349 | controller | TASK [compliance : Create scan binding] ****************************************
2025-10-13 10:13:23.538357 | controller | Monday 13 October 2025  10:13:23 +0000 (0:00:00.022)       0:00:13.994 ********
2025-10-13 10:13:23.538371 | controller | skipping: [instance]
2025-10-13 10:13:23.553214 | controller |
2025-10-13 10:13:23.553232 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-10-13 10:13:23.553239 | controller | Monday 13 October 2025  10:13:23 +0000 (0:00:00.015)       0:00:14.009 ********
2025-10-13 10:13:23.553247 | controller | skipping: [instance]
2025-10-13 10:13:23.731326 | controller |
2025-10-13 10:13:23.731358 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-10-13 10:13:23.731367 | controller | Monday 13 October 2025  10:13:23 +0000 (0:00:00.014)       0:00:14.024 ********
2025-10-13 10:13:23.731377 | controller | ok: [instance]
2025-10-13 10:13:23.916394 | controller |
2025-10-13 10:13:23.916425 | controller | TASK [compliance : Create directory for scan results] **************************
2025-10-13 10:13:23.916433 | controller | Monday 13 October 2025  10:13:23 +0000 (0:00:00.177)       0:00:14.201 ********
2025-10-13 10:13:23.916443 | controller | changed: [instance]
2025-10-13 10:13:23.931338 | controller |
2025-10-13 10:13:23.931370 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-10-13 10:13:23.931385 | controller | Monday 13 October 2025  10:13:23 +0000 (0:00:00.185)       0:00:14.387 ********
2025-10-13 10:13:23.931396 | controller | skipping: [instance]
2025-10-13 10:13:23.931421 | controller |
2025-10-13 10:13:23.931632 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-10-13 10:13:24.111157 | controller | Monday 13 October 2025  10:13:23 +0000 (0:00:00.015)       0:00:14.402 ********
2025-10-13 10:13:24.111209 | controller | ok: [instance]
2025-10-13 10:13:24.124329 | controller |
2025-10-13 10:13:24.124369 | controller | TASK [compliance : Process scan result files] **********************************
2025-10-13 10:13:24.124380 | controller | Monday 13 October 2025  10:13:24 +0000 (0:00:00.179)       0:00:14.581 ********
2025-10-13 10:13:24.124394 | controller | skipping: [instance]
2025-10-13 10:13:24.145336 | controller |
2025-10-13 10:13:24.145362 | controller | TASK [compliance : Set output directory] ***************************************
2025-10-13 10:13:24.145370 | controller | Monday 13 October 2025  10:13:24 +0000 (0:00:00.013)       0:00:14.595 ********
2025-10-13 10:13:24.145381 | controller | ok: [instance]
2025-10-13 10:13:24.145412 | controller |
2025-10-13 10:13:24.145599 | controller | TASK [compliance : Create scan binding] ****************************************
2025-10-13 10:13:24.160337 | controller | Monday 13 October 2025  10:13:24 +0000 (0:00:00.021)       0:00:14.616 ********
2025-10-13 10:13:24.160365 | controller | skipping: [instance]
2025-10-13 10:13:24.160394 | controller |
2025-10-13 10:13:24.160404 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-10-13 10:13:24.160509 | controller | Monday 13 October 2025  10:13:24 +0000 (0:00:00.014)       0:00:14.631 ********
2025-10-13 10:13:24.175312 | controller | skipping: [instance]
2025-10-13 10:13:24.175516 | controller |
2025-10-13 10:13:24.175537 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-10-13 10:13:24.351039 | controller | Monday 13 October 2025  10:13:24 +0000 (0:00:00.014)       0:00:14.646 ********
2025-10-13 10:13:24.351073 | controller | ok: [instance]
2025-10-13 10:13:24.547315 | controller |
2025-10-13 10:13:24.547345 | controller | TASK [compliance : Create directory for scan results] **************************
2025-10-13 10:13:24.547353 | controller | Monday 13 October 2025  10:13:24 +0000 (0:00:00.175)       0:00:14.821 ********
2025-10-13 10:13:24.547362 | controller | changed: [instance]
2025-10-13 10:13:24.547559 | controller |
2025-10-13 10:13:24.547573 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-10-13 10:13:24.564036 | controller | Monday 13 October 2025  10:13:24 +0000 (0:00:00.196)       0:00:15.018 ********
2025-10-13 10:13:24.564059 | controller | skipping: [instance]
2025-10-13 10:13:24.734733 | controller |
2025-10-13 10:13:24.734763 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-10-13 10:13:24.734771 | controller | Monday 13 October 2025  10:13:24 +0000 (0:00:00.016)       0:00:15.034 ********
2025-10-13 10:13:24.734781 | controller | ok: [instance]
2025-10-13 10:13:24.749050 | controller |
2025-10-13 10:13:24.749070 | controller | TASK [compliance : Process scan result files] **********************************
2025-10-13 10:13:24.749077 | controller | Monday 13 October 2025  10:13:24 +0000 (0:00:00.170)       0:00:15.205 ********
2025-10-13 10:13:24.749086 | controller | skipping: [instance]
2025-10-13 10:13:24.767633 | controller |
2025-10-13 10:13:24.767658 | controller | TASK [compliance : Log start of scans] *****************************************
2025-10-13 10:13:24.767666 | controller | Monday 13 October 2025  10:13:24 +0000 (0:00:00.014)       0:00:15.219 ********
2025-10-13 10:13:24.767677 | controller | ok: [instance] =>
2025-10-13 10:13:24.805355 | controller |   msg: Starting scans for stig
2025-10-13 10:13:24.805382 | controller |
2025-10-13 10:13:24.805392 | controller | TASK [compliance : Run scans and extract results] ******************************
2025-10-13 10:13:24.805399 | controller | Monday 13 October 2025  10:13:24 +0000 (0:00:00.018)       0:00:15.238 ********
2025-10-13 10:13:24.805410 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_operator_scan.yml for instance => (item=ocp4-stig)
2025-10-13 10:13:24.827349 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_operator_scan.yml for instance => (item=ocp4-stig-node)
2025-10-13 10:13:24.827392 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_operator_scan.yml for instance => (item=rhcos4-stig)
2025-10-13 10:13:24.827403 | controller |
2025-10-13 10:13:24.827411 | controller | TASK [compliance : Set output directory] ***************************************
2025-10-13 10:13:24.827418 | controller | Monday 13 October 2025  10:13:24 +0000 (0:00:00.037)       0:00:15.275 ********
2025-10-13 10:13:24.827430 | controller | ok: [instance]
2025-10-13 10:13:24.827460 | controller |
2025-10-13 10:13:24.827470 | controller | TASK [compliance : Create scan binding] ****************************************
2025-10-13 10:13:24.827560 | controller | Monday 13 October 2025  10:13:24 +0000 (0:00:00.022)       0:00:15.298 ********
2025-10-13 10:13:24.843899 | controller | skipping: [instance]
2025-10-13 10:13:24.859794 | controller |
2025-10-13 10:13:24.859814 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-10-13 10:13:24.859822 | controller | Monday 13 October 2025  10:13:24 +0000 (0:00:00.016)       0:00:15.314 ********
2025-10-13 10:13:24.859831 | controller | skipping: [instance]
2025-10-13 10:13:25.035849 | controller |
2025-10-13 10:13:25.035878 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-10-13 10:13:25.035886 | controller | Monday 13 October 2025  10:13:24 +0000 (0:00:00.015)       0:00:15.330 ********
2025-10-13 10:13:25.035896 | controller | ok: [instance]
2025-10-13 10:13:25.229914 | controller |
2025-10-13 10:13:25.229944 | controller | TASK [compliance : Create directory for scan results] **************************
2025-10-13 10:13:25.229953 | controller | Monday 13 October 2025  10:13:25 +0000 (0:00:00.175)       0:00:15.506 ********
2025-10-13 10:13:25.229962 | controller | changed: [instance]
2025-10-13 10:13:25.246723 | controller |
2025-10-13 10:13:25.246751 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-10-13 10:13:25.246759 | controller | Monday 13 October 2025  10:13:25 +0000 (0:00:00.194)       0:00:15.700 ********
2025-10-13 10:13:25.246770 | controller | skipping: [instance]
2025-10-13 10:13:25.418903 | controller |
2025-10-13 10:13:25.418932 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-10-13 10:13:25.418940 | controller | Monday 13 October 2025  10:13:25 +0000 (0:00:00.016)       0:00:15.717 ********
2025-10-13 10:13:25.418950 | controller | ok: [instance]
2025-10-13 10:13:25.433457 | controller |
2025-10-13 10:13:25.433475 | controller | TASK [compliance : Process scan result files] **********************************
2025-10-13 10:13:25.433482 | controller | Monday 13 October 2025  10:13:25 +0000 (0:00:00.172)       0:00:15.889 ********
2025-10-13 10:13:25.433491 | controller | skipping: [instance]
2025-10-13 10:13:25.456330 | controller |
2025-10-13 10:13:25.456351 | controller | TASK [compliance : Set output directory] ***************************************
2025-10-13 10:13:25.456359 | controller | Monday 13 October 2025  10:13:25 +0000 (0:00:00.014)       0:00:15.904 ********
2025-10-13 10:13:25.456374 | controller | ok: [instance]
2025-10-13 10:13:25.456397 | controller |
2025-10-13 10:13:25.456586 | controller | TASK [compliance : Create scan binding] ****************************************
2025-10-13 10:13:25.473661 | controller | Monday 13 October 2025  10:13:25 +0000 (0:00:00.022)       0:00:15.927 ********
2025-10-13 10:13:25.473681 | controller | skipping: [instance]
2025-10-13 10:13:25.491045 | controller |
2025-10-13 10:13:25.491072 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-10-13 10:13:25.491081 | controller | Monday 13 October 2025  10:13:25 +0000 (0:00:00.017)       0:00:15.944 ********
2025-10-13 10:13:25.491092 | controller | skipping: [instance]
2025-10-13 10:13:25.673471 | controller |
2025-10-13 10:13:25.673502 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-10-13 10:13:25.673511 | controller | Monday 13 October 2025  10:13:25 +0000 (0:00:00.017)       0:00:15.961 ********
2025-10-13 10:13:25.673520 | controller | ok: [instance]
2025-10-13 10:13:25.862359 | controller |
2025-10-13 10:13:25.862396 | controller | TASK [compliance : Create directory for scan results] **************************
2025-10-13 10:13:25.862405 | controller | Monday 13 October 2025  10:13:25 +0000 (0:00:00.182)       0:00:16.144 ********
2025-10-13 10:13:25.862416 | controller | changed: [instance]
2025-10-13 10:13:25.879365 | controller |
2025-10-13 10:13:25.879401 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-10-13 10:13:25.879418 | controller | Monday 13 October 2025  10:13:25 +0000 (0:00:00.188)       0:00:16.332 ********
2025-10-13 10:13:25.879430 | controller | skipping: [instance]
2025-10-13 10:13:25.879459 | controller |
2025-10-13 10:13:25.879732 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-10-13 10:13:26.053836 | controller | Monday 13 October 2025  10:13:25 +0000 (0:00:00.017)       0:00:16.350 ********
2025-10-13 10:13:26.053870 | controller | ok: [instance]
2025-10-13 10:13:26.067986 | controller |
2025-10-13 10:13:26.068008 | controller | TASK [compliance : Process scan result files] **********************************
2025-10-13 10:13:26.068016 | controller | Monday 13 October 2025  10:13:26 +0000 (0:00:00.173)       0:00:16.524 ********
2025-10-13 10:13:26.068025 | controller | skipping: [instance]
2025-10-13 10:13:26.091340 | controller |
2025-10-13 10:13:26.091374 | controller | TASK [compliance : Set output directory] ***************************************
2025-10-13 10:13:26.091382 | controller | Monday 13 October 2025  10:13:26 +0000 (0:00:00.014)       0:00:16.538 ********
2025-10-13 10:13:26.091393 | controller | ok: [instance]
2025-10-13 10:13:26.108070 | controller |
2025-10-13 10:13:26.108101 | controller | TASK [compliance : Create scan binding] ****************************************
2025-10-13 10:13:26.108111 | controller | Monday 13 October 2025  10:13:26 +0000 (0:00:00.023)       0:00:16.562 ********
2025-10-13 10:13:26.108122 | controller | skipping: [instance]
2025-10-13 10:13:26.124410 | controller |
2025-10-13 10:13:26.124435 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-10-13 10:13:26.124444 | controller | Monday 13 October 2025  10:13:26 +0000 (0:00:00.016)       0:00:16.578 ********
2025-10-13 10:13:26.124455 | controller | skipping: [instance]
2025-10-13 10:13:26.303739 | controller |
2025-10-13 10:13:26.303770 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-10-13 10:13:26.303779 | controller | Monday 13 October 2025  10:13:26 +0000 (0:00:00.016)       0:00:16.595 ********
2025-10-13 10:13:26.303788 | controller | ok: [instance]
2025-10-13 10:13:26.493341 | controller |
2025-10-13 10:13:26.493372 | controller | TASK [compliance : Create directory for scan results] **************************
2025-10-13 10:13:26.493380 | controller | Monday 13 October 2025  10:13:26 +0000 (0:00:00.179)       0:00:16.774 ********
2025-10-13 10:13:26.493390 | controller | changed: [instance]
2025-10-13 10:13:26.493499 | controller |
2025-10-13 10:13:26.493510 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-10-13 10:13:26.493518 | controller | Monday 13 October 2025  10:13:26 +0000 (0:00:00.189)       0:00:16.964 ********
2025-10-13 10:13:26.509387 | controller | skipping: [instance]
2025-10-13 10:13:26.682218 | controller |
2025-10-13 10:13:26.682264 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-10-13 10:13:26.682276 | controller | Monday 13 October 2025  10:13:26 +0000 (0:00:00.016)       0:00:16.980 ********
2025-10-13 10:13:26.682286 | controller | ok: [instance]
2025-10-13 10:13:26.696337 | controller |
2025-10-13 10:13:26.696363 | controller | TASK [compliance : Process scan result files] **********************************
2025-10-13 10:13:26.696372 | controller | Monday 13 October 2025  10:13:26 +0000 (0:00:00.172)       0:00:17.152 ********
2025-10-13 10:13:26.696383 | controller | skipping: [instance]
2025-10-13 10:13:26.696806 | controller |
2025-10-13 10:13:26.735203 | controller | TASK [compliance : Delete all resources created by the role] *******************
2025-10-13 10:13:26.735240 | controller | Monday 13 October 2025  10:13:26 +0000 (0:00:00.014)       0:00:17.167 ********
2025-10-13 10:13:26.735267 | controller | skipping: [instance]
2025-10-13 10:13:27.323903 | controller |
2025-10-13 10:13:27.323935 | controller | PLAY [Converge test of compute tasks] ******************************************
2025-10-13 10:13:27.323944 | controller |
2025-10-13 10:13:27.323950 | controller | TASK [Gathering Facts] *********************************************************
2025-10-13 10:13:27.323955 | controller | Monday 13 October 2025  10:13:26 +0000 (0:00:00.038)       0:00:17.205 ********
2025-10-13 10:13:27.323964 | controller | ok: [instance]
2025-10-13 10:13:27.539380 | controller |
2025-10-13 10:13:27.539410 | controller | TASK [compliance : Ensure compliance folder exists] ****************************
2025-10-13 10:13:27.539418 | controller | Monday 13 October 2025  10:13:27 +0000 (0:00:00.588)       0:00:17.794 ********
2025-10-13 10:13:27.539435 | controller | ok: [instance]
2025-10-13 10:13:27.567352 | controller |
2025-10-13 10:13:27.567383 | controller | TASK [compliance : Install the compliance operator and plugin] *****************
2025-10-13 10:13:27.567392 | controller | Monday 13 October 2025  10:13:27 +0000 (0:00:00.215)       0:00:18.009 ********
2025-10-13 10:13:27.567404 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/install.yml for instance
2025-10-13 10:13:27.567488 | controller |
2025-10-13 10:13:27.567522 | controller | TASK [compliance : Create the compliance namespace] ****************************
2025-10-13 10:13:27.567534 | controller | Monday 13 October 2025  10:13:27 +0000 (0:00:00.028)       0:00:18.038 ********
2025-10-13 10:13:27.584267 | controller | skipping: [instance]
2025-10-13 10:13:27.600771 | controller |
2025-10-13 10:13:27.600790 | controller | TASK [compliance : Ensure OperatorGroup for the compliance-operator is present] ***
2025-10-13 10:13:27.600800 | controller | Monday 13 October 2025  10:13:27 +0000 (0:00:00.016)       0:00:18.055 ********
2025-10-13 10:13:27.600810 | controller | skipping: [instance]
2025-10-13 10:13:27.617222 | controller |
2025-10-13 10:13:27.617247 | controller | TASK [compliance : Ensure Subscription for the compliance-operator is present] ***
2025-10-13 10:13:27.617278 | controller | Monday 13 October 2025  10:13:27 +0000 (0:00:00.016)       0:00:18.071 ********
2025-10-13 10:13:27.617290 | controller | skipping: [instance]
2025-10-13 10:13:27.633189 | controller |
2025-10-13 10:13:27.633212 | controller | TASK [compliance : Wait until the compliance-operator csv is present] **********
2025-10-13 10:13:27.633219 | controller | Monday 13 October 2025  10:13:27 +0000 (0:00:00.016)       0:00:18.088 ********
2025-10-13 10:13:27.633228 | controller | skipping: [instance]
2025-10-13 10:13:27.633403 | controller |
2025-10-13 10:13:27.633553 | controller | TASK [compliance : Get full name of the compliance-operator csv] ***************
2025-10-13 10:13:27.633694 | controller | Monday 13 October 2025  10:13:27 +0000 (0:00:00.016)       0:00:18.104 ********
2025-10-13 10:13:27.649514 | controller | skipping: [instance]
2025-10-13 10:13:27.649668 | controller |
2025-10-13 10:13:27.649807 | controller | TASK [compliance : Wait for the compliance-operator csv to Succeed] ************
2025-10-13 10:13:27.649943 | controller | Monday 13 October 2025  10:13:27 +0000 (0:00:00.016)       0:00:18.120 ********
2025-10-13 10:13:27.666762 | controller | skipping: [instance]
2025-10-13 10:13:27.666911 | controller |
2025-10-13 10:13:27.667112 | controller | TASK [podman : Ensure podman is installed] *************************************
2025-10-13 10:13:27.667266 | controller | Monday 13 October 2025  10:13:27 +0000 (0:00:00.017)       0:00:18.137 ********
2025-10-13 10:13:28.311795 | controller | ok: [instance]
2025-10-13 10:13:28.517357 | controller |
2025-10-13 10:13:28.517404 | controller | TASK [podman : Enable loginctl linger for ansible_user_id] *********************
2025-10-13 10:13:28.517413 | controller | Monday 13 October 2025  10:13:28 +0000 (0:00:00.644)       0:00:18.782 ********
2025-10-13 10:13:28.517423 | controller | changed: [instance]
2025-10-13 10:13:28.517445 | controller |
2025-10-13 10:13:28.517454 | controller | TASK [podman : Ensure subordinate UID entry exists for zuul] *******************
2025-10-13 10:13:28.517503 | controller | Monday 13 October 2025  10:13:28 +0000 (0:00:00.205)       0:00:18.988 ********
2025-10-13 10:13:28.540311 | controller | skipping: [instance]
2025-10-13 10:13:28.540368 | controller |
2025-10-13 10:13:28.540392 | controller | TASK [podman : Ensure subordinate GID entry exists for zuul] *******************
2025-10-13 10:13:28.540400 | controller | Monday 13 October 2025  10:13:28 +0000 (0:00:00.022)       0:00:19.011 ********
2025-10-13 10:13:28.558860 | controller | skipping: [instance]
2025-10-13 10:13:28.581306 | controller |
2025-10-13 10:13:28.581333 | controller | TASK [podman : Run podman system migrate if subuid/subgid files were changed] ***
2025-10-13 10:13:28.581341 | controller | Monday 13 October 2025  10:13:28 +0000 (0:00:00.018)       0:00:19.029 ********
2025-10-13 10:13:28.581351 | controller | skipping: [instance]
2025-10-13 10:13:28.597050 | controller |
2025-10-13 10:13:28.597077 | controller | TASK [compliance : Log into registry] ******************************************
2025-10-13 10:13:28.597090 | controller | Monday 13 October 2025  10:13:28 +0000 (0:00:00.022)       0:00:19.052 ********
2025-10-13 10:13:28.597100 | controller | skipping: [instance]
2025-10-13 10:13:28.611839 | controller |
2025-10-13 10:13:28.611861 | controller | TASK [compliance : Copy out the compliance plugin from the oc-compliance image] ***
2025-10-13 10:13:28.611869 | controller | Monday 13 October 2025  10:13:28 +0000 (0:00:00.015)       0:00:19.067 ********
2025-10-13 10:13:28.611878 | controller | skipping: [instance]
2025-10-13 10:13:29.279298 | controller |
2025-10-13 10:13:29.279331 | controller | TASK [compliance : Install packages to process results] ************************
2025-10-13 10:13:29.279340 | controller | Monday 13 October 2025  10:13:28 +0000 (0:00:00.014)       0:00:19.082 ********
2025-10-13 10:13:29.279350 | controller | ok: [instance]
2025-10-13 10:13:29.324385 | controller |
2025-10-13 10:13:29.324432 | controller | TASK [compliance : Run compliance scans and extract results] *******************
2025-10-13 10:13:29.324442 | controller | Monday 13 October 2025  10:13:29 +0000 (0:00:00.667)       0:00:19.749 ********
2025-10-13 10:13:29.324453 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_suite_scan.yml for instance => (item=cis)
2025-10-13 10:13:29.324481 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_suite_scan.yml for instance => (item=e8)
2025-10-13 10:13:29.324489 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_suite_scan.yml for instance => (item=high)
2025-10-13 10:13:29.325167 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_suite_scan.yml for instance => (item=moderate)
2025-10-13 10:13:29.344920 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_suite_scan.yml for instance => (item=nerc-cip)
2025-10-13 10:13:29.344955 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_suite_scan.yml for instance => (item=pci-dss)
2025-10-13 10:13:29.344963 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_suite_scan.yml for instance => (item=stig)
2025-10-13 10:13:29.344969 | controller |
2025-10-13 10:13:29.344975 | controller | TASK [compliance : Log start of scans] *****************************************
2025-10-13 10:13:29.344981 | controller | Monday 13 October 2025  10:13:29 +0000 (0:00:00.045)       0:00:19.795 ********
2025-10-13 10:13:29.344992 | controller | ok: [instance] =>
2025-10-13 10:13:29.371158 | controller |   msg: Starting scans for cis
2025-10-13 10:13:29.371192 | controller |
2025-10-13 10:13:29.371200 | controller | TASK [compliance : Run scans and extract results] ******************************
2025-10-13 10:13:29.371206 | controller | Monday 13 October 2025  10:13:29 +0000 (0:00:00.020)       0:00:19.815 ********
2025-10-13 10:13:29.371216 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_operator_scan.yml for instance => (item=ocp4-cis)
2025-10-13 10:13:29.394060 | controller |
2025-10-13 10:13:29.394093 | controller | TASK [compliance : Set output directory] ***************************************
2025-10-13 10:13:29.394101 | controller | Monday 13 October 2025  10:13:29 +0000 (0:00:00.026)       0:00:19.841 ********
2025-10-13 10:13:29.394111 | controller | ok: [instance]
2025-10-13 10:13:29.409360 | controller |
2025-10-13 10:13:29.409390 | controller | TASK [compliance : Create scan binding] ****************************************
2025-10-13 10:13:29.409398 | controller | Monday 13 October 2025  10:13:29 +0000 (0:00:00.022)       0:00:19.864 ********
2025-10-13 10:13:29.409424 | controller | skipping: [instance]
2025-10-13 10:13:29.424166 | controller |
2025-10-13 10:13:29.424187 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-10-13 10:13:29.424194 | controller | Monday 13 October 2025  10:13:29 +0000 (0:00:00.015)       0:00:19.880 ********
2025-10-13 10:13:29.424203 | controller | skipping: [instance]
2025-10-13 10:13:29.600431 | controller |
2025-10-13 10:13:29.600462 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-10-13 10:13:29.600471 | controller | Monday 13 October 2025  10:13:29 +0000 (0:00:00.014)       0:00:19.894 ********
2025-10-13 10:13:29.600481 | controller | changed: [instance]
2025-10-13 10:13:29.780319 | controller |
2025-10-13 10:13:29.780348 | controller | TASK [compliance : Create directory for scan results] **************************
2025-10-13 10:13:29.780364 | controller | Monday 13 October 2025  10:13:29 +0000 (0:00:00.176)       0:00:20.071 ********
2025-10-13 10:13:29.780375 | controller | changed: [instance]
2025-10-13 10:13:29.780398 | controller |
2025-10-13 10:13:29.780407 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-10-13 10:13:29.780545 | controller | Monday 13 October 2025  10:13:29 +0000 (0:00:00.180)       0:00:20.251 ********
2025-10-13 10:13:29.795536 | controller | skipping: [instance]
2025-10-13 10:13:29.964322 | controller |
2025-10-13 10:13:29.964352 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-10-13 10:13:29.964360 | controller | Monday 13 October 2025  10:13:29 +0000 (0:00:00.015)       0:00:20.266 ********
2025-10-13 10:13:29.964370 | controller | ok: [instance]
2025-10-13 10:13:29.964392 | controller |
2025-10-13 10:13:29.964398 | controller | TASK [compliance : Process scan result files] **********************************
2025-10-13 10:13:29.964406 | controller | Monday 13 October 2025  10:13:29 +0000 (0:00:00.168)       0:00:20.435 ********
2025-10-13 10:13:29.978767 | controller | skipping: [instance]
2025-10-13 10:13:29.997900 | controller |
2025-10-13 10:13:29.997918 | controller | TASK [compliance : Log start of scans] *****************************************
2025-10-13 10:13:29.997926 | controller | Monday 13 October 2025  10:13:29 +0000 (0:00:00.014)       0:00:20.449 ********
2025-10-13 10:13:29.997934 | controller | ok: [instance] =>
2025-10-13 10:13:30.030229 | controller |   msg: Starting scans for e8
2025-10-13 10:13:30.030246 | controller |
2025-10-13 10:13:30.030273 | controller | TASK [compliance : Run scans and extract results] ******************************
2025-10-13 10:13:30.030280 | controller | Monday 13 October 2025  10:13:29 +0000 (0:00:00.019)       0:00:20.468 ********
2025-10-13 10:13:30.030294 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_operator_scan.yml for instance => (item=ocp4-e8)
2025-10-13 10:13:30.053302 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_operator_scan.yml for instance => (item=rhcos4-e8)
2025-10-13 10:13:30.053324 | controller |
2025-10-13 10:13:30.053331 | controller | TASK [compliance : Set output directory] ***************************************
2025-10-13 10:13:30.053337 | controller | Monday 13 October 2025  10:13:30 +0000 (0:00:00.032)       0:00:20.500 ********
2025-10-13 10:13:30.053346 | controller | ok: [instance]
2025-10-13 10:13:30.053408 | controller |
2025-10-13 10:13:30.053417 | controller | TASK [compliance : Create scan binding] ****************************************
2025-10-13 10:13:30.053425 | controller | Monday 13 October 2025  10:13:30 +0000 (0:00:00.023)       0:00:20.524 ********
2025-10-13 10:13:30.070310 | controller | skipping: [instance]
2025-10-13 10:13:30.070545 | controller |
2025-10-13 10:13:30.070563 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-10-13 10:13:30.087072 | controller | Monday 13 October 2025  10:13:30 +0000 (0:00:00.017)       0:00:20.541 ********
2025-10-13 10:13:30.087096 | controller | skipping: [instance]
2025-10-13 10:13:30.267262 | controller |
2025-10-13 10:13:30.267294 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-10-13 10:13:30.267302 | controller | Monday 13 October 2025  10:13:30 +0000 (0:00:00.016)       0:00:20.557 ********
2025-10-13 10:13:30.267313 | controller | changed: [instance]
2025-10-13 10:13:30.454944 | controller |
2025-10-13 10:13:30.454975 | controller | TASK [compliance : Create directory for scan results] **************************
2025-10-13 10:13:30.454983 | controller | Monday 13 October 2025  10:13:30 +0000 (0:00:00.179)       0:00:20.737 ********
2025-10-13 10:13:30.454993 | controller | changed: [instance]
2025-10-13 10:13:30.471980 | controller |
2025-10-13 10:13:30.472004 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-10-13 10:13:30.472011 | controller | Monday 13 October 2025  10:13:30 +0000 (0:00:00.187)       0:00:20.925 ********
2025-10-13 10:13:30.472021 | controller | skipping: [instance]
2025-10-13 10:13:30.643815 | controller |
2025-10-13 10:13:30.643846 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-10-13 10:13:30.643855 | controller | Monday 13 October 2025  10:13:30 +0000 (0:00:00.017)       0:00:20.942 ********
2025-10-13 10:13:30.643869 | controller | ok: [instance]
2025-10-13 10:13:30.658732 | controller |
2025-10-13 10:13:30.658755 | controller | TASK [compliance : Process scan result files] **********************************
2025-10-13 10:13:30.658762 | controller | Monday 13 October 2025  10:13:30 +0000 (0:00:00.171)       0:00:21.114 ********
2025-10-13 10:13:30.658772 | controller | skipping: [instance]
2025-10-13 10:13:30.682060 | controller |
2025-10-13 10:13:30.682085 | controller | TASK [compliance : Set output directory] ***************************************
2025-10-13 10:13:30.682092 | controller | Monday 13 October 2025  10:13:30 +0000 (0:00:00.015)       0:00:21.129 ********
2025-10-13 10:13:30.682101 | controller | ok: [instance]
2025-10-13 10:13:30.698761 | controller |
2025-10-13 10:13:30.698783 | controller | TASK [compliance : Create scan binding] ****************************************
2025-10-13 10:13:30.698790 | controller | Monday 13 October 2025  10:13:30 +0000 (0:00:00.023)       0:00:21.152 ********
2025-10-13 10:13:30.698799 | controller | skipping: [instance]
2025-10-13 10:13:30.715288 | controller |
2025-10-13 10:13:30.715312 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-10-13 10:13:30.715320 | controller | Monday 13 October 2025  10:13:30 +0000 (0:00:00.016)       0:00:21.169 ********
2025-10-13 10:13:30.715329 | controller | skipping: [instance]
2025-10-13 10:13:30.889614 | controller |
2025-10-13 10:13:30.889645 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-10-13 10:13:30.889653 | controller | Monday 13 October 2025  10:13:30 +0000 (0:00:00.016)       0:00:21.186 ********
2025-10-13 10:13:30.889663 | controller | changed: [instance]
2025-10-13 10:13:31.075794 | controller |
2025-10-13 10:13:31.075823 | controller | TASK [compliance : Create directory for scan results] **************************
2025-10-13 10:13:31.075831 | controller | Monday 13 October 2025  10:13:30 +0000 (0:00:00.174)       0:00:21.360 ********
2025-10-13 10:13:31.075842 | controller | changed: [instance]
2025-10-13 10:13:31.092297 | controller |
2025-10-13 10:13:31.092322 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-10-13 10:13:31.092330 | controller | Monday 13 October 2025  10:13:31 +0000 (0:00:00.186)       0:00:21.546 ********
2025-10-13 10:13:31.092339 | controller | skipping: [instance]
2025-10-13 10:13:31.261939 | controller |
2025-10-13 10:13:31.261970 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-10-13 10:13:31.261978 | controller | Monday 13 October 2025  10:13:31 +0000 (0:00:00.016)       0:00:21.563 ********
2025-10-13 10:13:31.261988 | controller | ok: [instance]
2025-10-13 10:13:31.276149 | controller |
2025-10-13 10:13:31.276171 | controller | TASK [compliance : Process scan result files] **********************************
2025-10-13 10:13:31.276184 | controller | Monday 13 October 2025  10:13:31 +0000 (0:00:00.169)       0:00:21.732 ********
2025-10-13 10:13:31.276194 | controller | skipping: [instance]
2025-10-13 10:13:31.294321 | controller |
2025-10-13 10:13:31.294346 | controller | TASK [compliance : Log start of scans] *****************************************
2025-10-13 10:13:31.294355 | controller | Monday 13 October 2025  10:13:31 +0000 (0:00:00.014)       0:00:21.747 ********
2025-10-13 10:13:31.294366 | controller | ok: [instance] =>
2025-10-13 10:13:31.294392 | controller |   msg: Starting scans for high
2025-10-13 10:13:31.294401 | controller |
2025-10-13 10:13:31.294410 | controller | TASK [compliance : Run scans and extract results] ******************************
2025-10-13 10:13:31.294502 | controller | Monday 13 October 2025  10:13:31 +0000 (0:00:00.018)       0:00:21.765 ********
2025-10-13 10:13:31.333398 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_operator_scan.yml for instance => (item=ocp4-high)
2025-10-13 10:13:31.333421 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_operator_scan.yml for instance => (item=ocp4-high-node)
2025-10-13 10:13:31.333431 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_operator_scan.yml for instance => (item=rhcos4-high)
2025-10-13 10:13:31.333438 | controller |
2025-10-13 10:13:31.333445 | controller | TASK [compliance : Set output directory] ***************************************
2025-10-13 10:13:31.333455 | controller | Monday 13 October 2025  10:13:31 +0000 (0:00:00.038)       0:00:21.803 ********
2025-10-13 10:13:31.356846 | controller | ok: [instance]
2025-10-13 10:13:31.373330 | controller |
2025-10-13 10:13:31.373355 | controller | TASK [compliance : Create scan binding] ****************************************
2025-10-13 10:13:31.373365 | controller | Monday 13 October 2025  10:13:31 +0000 (0:00:00.023)       0:00:21.827 ********
2025-10-13 10:13:31.373376 | controller | skipping: [instance]
2025-10-13 10:13:31.373551 | controller |
2025-10-13 10:13:31.373569 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-10-13 10:13:31.390193 | controller | Monday 13 October 2025  10:13:31 +0000 (0:00:00.016)       0:00:21.844 ********
2025-10-13 10:13:31.390214 | controller | skipping: [instance]
2025-10-13 10:13:31.564752 | controller |
2025-10-13 10:13:31.564782 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-10-13 10:13:31.564790 | controller | Monday 13 October 2025  10:13:31 +0000 (0:00:00.016)       0:00:21.861 ********
2025-10-13 10:13:31.564800 | controller | changed: [instance]
2025-10-13 10:13:31.748051 | controller |
2025-10-13 10:13:31.748081 | controller | TASK [compliance : Create directory for scan results] **************************
2025-10-13 10:13:31.748089 | controller | Monday 13 October 2025  10:13:31 +0000 (0:00:00.174)       0:00:22.035 ********
2025-10-13 10:13:31.748098 | controller | changed: [instance]
2025-10-13 10:13:31.764719 | controller |
2025-10-13 10:13:31.764742 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-10-13 10:13:31.764749 | controller | Monday 13 October 2025  10:13:31 +0000 (0:00:00.183)       0:00:22.218 ********
2025-10-13 10:13:31.764758 | controller | skipping: [instance]
2025-10-13 10:13:31.944766 | controller |
2025-10-13 10:13:31.944795 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-10-13 10:13:31.944803 | controller | Monday 13 October 2025  10:13:31 +0000 (0:00:00.016)       0:00:22.235 ********
2025-10-13 10:13:31.944813 | controller | ok: [instance]
2025-10-13 10:13:31.958880 | controller |
2025-10-13 10:13:31.958898 | controller | TASK [compliance : Process scan result files] **********************************
2025-10-13 10:13:31.958905 | controller | Monday 13 October 2025  10:13:31 +0000 (0:00:00.179)       0:00:22.415 ********
2025-10-13 10:13:31.958914 | controller | skipping: [instance]
2025-10-13 10:13:31.981963 | controller |
2025-10-13 10:13:31.981982 | controller | TASK [compliance : Set output directory] ***************************************
2025-10-13 10:13:31.981990 | controller | Monday 13 October 2025  10:13:31 +0000 (0:00:00.014)       0:00:22.429 ********
2025-10-13 10:13:31.981998 | controller | ok: [instance]
2025-10-13 10:13:31.998422 | controller |
2025-10-13 10:13:31.998440 | controller | TASK [compliance : Create scan binding] ****************************************
2025-10-13 10:13:31.998447 | controller | Monday 13 October 2025  10:13:31 +0000 (0:00:00.022)       0:00:22.452 ********
2025-10-13 10:13:31.998456 | controller | skipping: [instance]
2025-10-13 10:13:32.016227 | controller |
2025-10-13 10:13:32.016245 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-10-13 10:13:32.016273 | controller | Monday 13 October 2025  10:13:31 +0000 (0:00:00.016)       0:00:22.469 ********
2025-10-13 10:13:32.016283 | controller | skipping: [instance]
2025-10-13 10:13:32.195073 | controller |
2025-10-13 10:13:32.195102 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-10-13 10:13:32.195110 | controller | Monday 13 October 2025  10:13:32 +0000 (0:00:00.017)       0:00:22.486 ********
2025-10-13 10:13:32.195120 | controller | changed: [instance]
2025-10-13 10:13:32.380486 | controller |
2025-10-13 10:13:32.380515 | controller | TASK [compliance : Create directory for scan results] **************************
2025-10-13 10:13:32.380523 | controller | Monday 13 October 2025  10:13:32 +0000 (0:00:00.178)       0:00:22.665 ********
2025-10-13 10:13:32.380549 | controller | changed: [instance]
2025-10-13 10:13:32.396997 | controller |
2025-10-13 10:13:32.397019 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-10-13 10:13:32.397026 | controller | Monday 13 October 2025  10:13:32 +0000 (0:00:00.185)       0:00:22.851 ********
2025-10-13 10:13:32.397035 | controller | skipping: [instance]
2025-10-13 10:13:32.570507 | controller |
2025-10-13 10:13:32.570540 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-10-13 10:13:32.570573 | controller | Monday 13 October 2025  10:13:32 +0000 (0:00:00.016)       0:00:22.867 ********
2025-10-13 10:13:32.570584 | controller | ok: [instance]
2025-10-13 10:13:32.585216 | controller |
2025-10-13 10:13:32.585233 | controller | TASK [compliance : Process scan result files] **********************************
2025-10-13 10:13:32.585240 | controller | Monday 13 October 2025  10:13:32 +0000 (0:00:00.173)       0:00:23.041 ********
2025-10-13 10:13:32.585265 | controller | skipping: [instance]
2025-10-13 10:13:32.609824 | controller |
2025-10-13 10:13:32.609845 | controller | TASK [compliance : Set output directory] ***************************************
2025-10-13 10:13:32.609854 | controller | Monday 13 October 2025  10:13:32 +0000 (0:00:00.014)       0:00:23.056 ********
2025-10-13 10:13:32.609864 | controller | ok: [instance]
2025-10-13 10:13:32.628017 | controller |
2025-10-13 10:13:32.628036 | controller | TASK [compliance : Create scan binding] ****************************************
2025-10-13 10:13:32.628043 | controller | Monday 13 October 2025  10:13:32 +0000 (0:00:00.024)       0:00:23.080 ********
2025-10-13 10:13:32.628052 | controller | skipping: [instance]
2025-10-13 10:13:32.644908 | controller |
2025-10-13 10:13:32.644927 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-10-13 10:13:32.644934 | controller | Monday 13 October 2025  10:13:32 +0000 (0:00:00.018)       0:00:23.098 ********
2025-10-13 10:13:32.644943 | controller | skipping: [instance]
2025-10-13 10:13:32.830021 | controller |
2025-10-13 10:13:32.830053 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-10-13 10:13:32.830067 | controller | Monday 13 October 2025  10:13:32 +0000 (0:00:00.016)       0:00:23.115 ********
2025-10-13 10:13:32.830077 | controller | changed: [instance]
2025-10-13 10:13:33.016228 | controller |
2025-10-13 10:13:33.016281 | controller | TASK [compliance : Create directory for scan results] **************************
2025-10-13 10:13:33.016292 | controller | Monday 13 October 2025  10:13:32 +0000 (0:00:00.184)       0:00:23.300 ********
2025-10-13 10:13:33.016303 | controller | changed: [instance]
2025-10-13 10:13:33.033077 | controller |
2025-10-13 10:13:33.033095 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-10-13 10:13:33.033102 | controller | Monday 13 October 2025  10:13:33 +0000 (0:00:00.186)       0:00:23.486 ********
2025-10-13 10:13:33.033112 | controller | skipping: [instance]
2025-10-13 10:13:33.206300 | controller |
2025-10-13 10:13:33.206329 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-10-13 10:13:33.206337 | controller | Monday 13 October 2025  10:13:33 +0000 (0:00:00.017)       0:00:23.503 ********
2025-10-13 10:13:33.206347 | controller | ok: [instance]
2025-10-13 10:13:33.220842 | controller |
2025-10-13 10:13:33.220864 | controller | TASK [compliance : Process scan result files] **********************************
2025-10-13 10:13:33.220872 | controller | Monday 13 October 2025  10:13:33 +0000 (0:00:00.172)       0:00:23.676 ********
2025-10-13 10:13:33.220882 | controller | skipping: [instance]
2025-10-13 10:13:33.239331 | controller |
2025-10-13 10:13:33.239364 | controller | TASK [compliance : Log start of scans] *****************************************
2025-10-13 10:13:33.239373 | controller | Monday 13 October 2025  10:13:33 +0000 (0:00:00.014)       0:00:23.691 ********
2025-10-13 10:13:33.239383 | controller | ok: [instance] =>
2025-10-13 10:13:33.278086 | controller |   msg: Starting scans for moderate
2025-10-13 10:13:33.278107 | controller |
2025-10-13 10:13:33.278114 | controller | TASK [compliance : Run scans and extract results] ******************************
2025-10-13 10:13:33.278121 | controller | Monday 13 October 2025  10:13:33 +0000 (0:00:00.018)       0:00:23.710 ********
2025-10-13 10:13:33.278130 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_operator_scan.yml for instance => (item=ocp4-moderate)
2025-10-13 10:13:33.300797 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_operator_scan.yml for instance => (item=ocp4-moderate-node)
2025-10-13 10:13:33.300815 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_operator_scan.yml for instance => (item=rhcos4-moderate)
2025-10-13 10:13:33.300823 | controller |
2025-10-13 10:13:33.300830 | controller | TASK [compliance : Set output directory] ***************************************
2025-10-13 10:13:33.300839 | controller | Monday 13 October 2025  10:13:33 +0000 (0:00:00.038)       0:00:23.748 ********
2025-10-13 10:13:33.300847 | controller | ok: [instance]
2025-10-13 10:13:33.316335 | controller |
2025-10-13 10:13:33.316376 | controller | TASK [compliance : Create scan binding] ****************************************
2025-10-13 10:13:33.316386 | controller | Monday 13 October 2025  10:13:33 +0000 (0:00:00.022)       0:00:23.771 ********
2025-10-13 10:13:33.316400 | controller | skipping: [instance]
2025-10-13 10:13:33.316645 | controller |
2025-10-13 10:13:33.332349 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-10-13 10:13:33.332373 | controller | Monday 13 October 2025  10:13:33 +0000 (0:00:00.015)       0:00:23.787 ********
2025-10-13 10:13:33.332385 | controller | skipping: [instance]
2025-10-13 10:13:33.521688 | controller |
2025-10-13 10:13:33.521718 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-10-13 10:13:33.521727 | controller | Monday 13 October 2025  10:13:33 +0000 (0:00:00.015)       0:00:23.803 ********
2025-10-13 10:13:33.521736 | controller | changed: [instance]
2025-10-13 10:13:33.714006 | controller |
2025-10-13 10:13:33.714035 | controller | TASK [compliance : Create directory for scan results] **************************
2025-10-13 10:13:33.714043 | controller | Monday 13 October 2025  10:13:33 +0000 (0:00:00.189)       0:00:23.992 ********
2025-10-13 10:13:33.714053 | controller | changed: [instance]
2025-10-13 10:13:33.731706 | controller |
2025-10-13 10:13:33.731723 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-10-13 10:13:33.731731 | controller | Monday 13 October 2025  10:13:33 +0000 (0:00:00.192)       0:00:24.184 ********
2025-10-13 10:13:33.731739 | controller | skipping: [instance]
2025-10-13 10:13:33.924091 | controller |
2025-10-13 10:13:33.924125 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-10-13 10:13:33.924134 | controller | Monday 13 October 2025  10:13:33 +0000 (0:00:00.017)       0:00:24.202 ********
2025-10-13 10:13:33.924144 | controller | ok: [instance]
2025-10-13 10:13:33.938180 | controller |
2025-10-13 10:13:33.938202 | controller | TASK [compliance : Process scan result files] **********************************
2025-10-13 10:13:33.938210 | controller | Monday 13 October 2025  10:13:33 +0000 (0:00:00.192)       0:00:24.394 ********
2025-10-13 10:13:33.938219 | controller | skipping: [instance]
2025-10-13 10:13:33.960317 | controller |
2025-10-13 10:13:33.960343 | controller | TASK [compliance : Set output directory] ***************************************
2025-10-13 10:13:33.960351 | controller | Monday 13 October 2025  10:13:33 +0000 (0:00:00.014)       0:00:24.409 ********
2025-10-13 10:13:33.960361 | controller | ok: [instance]
2025-10-13 10:13:33.960384 | controller |
2025-10-13 10:13:33.960581 | controller | TASK [compliance : Create scan binding] ****************************************
2025-10-13 10:13:33.977731 | controller | Monday 13 October 2025  10:13:33 +0000 (0:00:00.022)       0:00:24.431 ********
2025-10-13 10:13:33.977756 | controller | skipping: [instance]
2025-10-13 10:13:33.994420 | controller |
2025-10-13 10:13:33.994475 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-10-13 10:13:33.994487 | controller | Monday 13 October 2025  10:13:33 +0000 (0:00:00.017)       0:00:24.448 ********
2025-10-13 10:13:33.994501 | controller | skipping: [instance]
2025-10-13 10:13:33.994538 | controller |
2025-10-13 10:13:33.994664 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-10-13 10:13:33.994697 | controller | Monday 13 October 2025  10:13:33 +0000 (0:00:00.016)       0:00:24.465 ********
2025-10-13 10:13:34.196520 | controller | changed: [instance]
2025-10-13 10:13:34.407564 | controller |
2025-10-13 10:13:34.407605 | controller | TASK [compliance : Create directory for scan results] **************************
2025-10-13 10:13:34.407613 | controller | Monday 13 October 2025  10:13:34 +0000 (0:00:00.201)       0:00:24.667 ********
2025-10-13 10:13:34.407623 | controller | changed: [instance]
2025-10-13 10:13:34.424895 | controller |
2025-10-13 10:13:34.424917 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-10-13 10:13:34.424925 | controller | Monday 13 October 2025  10:13:34 +0000 (0:00:00.211)       0:00:24.878 ********
2025-10-13 10:13:34.424934 | controller | skipping: [instance]
2025-10-13 10:13:34.616710 | controller |
2025-10-13 10:13:34.616740 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-10-13 10:13:34.616748 | controller | Monday 13 October 2025  10:13:34 +0000 (0:00:00.017)       0:00:24.895 ********
2025-10-13 10:13:34.616758 | controller | ok: [instance]
2025-10-13 10:13:34.633018 | controller |
2025-10-13 10:13:34.633049 | controller | TASK [compliance : Process scan result files] **********************************
2025-10-13 10:13:34.633057 | controller | Monday 13 October 2025  10:13:34 +0000 (0:00:00.191)       0:00:25.087 ********
2025-10-13 10:13:34.633067 | controller | skipping: [instance]
2025-10-13 10:13:34.657016 | controller |
2025-10-13 10:13:34.657045 | controller | TASK [compliance : Set output directory] ***************************************
2025-10-13 10:13:34.657053 | controller | Monday 13 October 2025  10:13:34 +0000 (0:00:00.016)       0:00:25.103 ********
2025-10-13 10:13:34.657062 | controller | ok: [instance]
2025-10-13 10:13:34.674334 | controller |
2025-10-13 10:13:34.674362 | controller | TASK [compliance : Create scan binding] ****************************************
2025-10-13 10:13:34.674374 | controller | Monday 13 October 2025  10:13:34 +0000 (0:00:00.024)       0:00:25.127 ********
2025-10-13 10:13:34.674384 | controller | skipping: [instance]
2025-10-13 10:13:34.674602 | controller |
2025-10-13 10:13:34.674617 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-10-13 10:13:34.691413 | controller | Monday 13 October 2025  10:13:34 +0000 (0:00:00.017)       0:00:25.145 ********
2025-10-13 10:13:34.691473 | controller | skipping: [instance]
2025-10-13 10:13:34.691483 | controller |
2025-10-13 10:13:34.691489 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-10-13 10:13:34.691498 | controller | Monday 13 October 2025  10:13:34 +0000 (0:00:00.017)       0:00:25.162 ********
2025-10-13 10:13:34.885201 | controller | changed: [instance]
2025-10-13 10:13:35.071503 | controller |
2025-10-13 10:13:35.071532 | controller | TASK [compliance : Create directory for scan results] **************************
2025-10-13 10:13:35.071540 | controller | Monday 13 October 2025  10:13:34 +0000 (0:00:00.193)       0:00:25.355 ********
2025-10-13 10:13:35.071550 | controller | changed: [instance]
2025-10-13 10:13:35.087245 | controller |
2025-10-13 10:13:35.087288 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-10-13 10:13:35.087297 | controller | Monday 13 October 2025  10:13:35 +0000 (0:00:00.186)       0:00:25.542 ********
2025-10-13 10:13:35.087309 | controller | skipping: [instance]
2025-10-13 10:13:35.258764 | controller |
2025-10-13 10:13:35.258794 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-10-13 10:13:35.258802 | controller | Monday 13 October 2025  10:13:35 +0000 (0:00:00.015)       0:00:25.558 ********
2025-10-13 10:13:35.258812 | controller | ok: [instance]
2025-10-13 10:13:35.272780 | controller |
2025-10-13 10:13:35.272800 | controller | TASK [compliance : Process scan result files] **********************************
2025-10-13 10:13:35.272808 | controller | Monday 13 October 2025  10:13:35 +0000 (0:00:00.171)       0:00:25.729 ********
2025-10-13 10:13:35.272817 | controller | skipping: [instance]
2025-10-13 10:13:35.290367 | controller |
2025-10-13 10:13:35.290411 | controller | TASK [compliance : Log start of scans] *****************************************
2025-10-13 10:13:35.290422 | controller | Monday 13 October 2025  10:13:35 +0000 (0:00:00.014)       0:00:25.743 ********
2025-10-13 10:13:35.290435 | controller | ok: [instance] =>
2025-10-13 10:13:35.290465 | controller |   msg: Starting scans for nerc-cip
2025-10-13 10:13:35.290492 | controller |
2025-10-13 10:13:35.290741 | controller | TASK [compliance : Run scans and extract results] ******************************
2025-10-13 10:13:35.322337 | controller | Monday 13 October 2025  10:13:35 +0000 (0:00:00.017)       0:00:25.761 ********
2025-10-13 10:13:35.322370 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_operator_scan.yml for instance => (item=ocp4-nerc-cip)
2025-10-13 10:13:35.322401 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_operator_scan.yml for instance => (item=ocp4-nerc-cip-node)
2025-10-13 10:13:35.322411 | controller |
2025-10-13 10:13:35.322608 | controller | TASK [compliance : Set output directory] ***************************************
2025-10-13 10:13:35.344321 | controller | Monday 13 October 2025  10:13:35 +0000 (0:00:00.031)       0:00:25.793 ********
2025-10-13 10:13:35.344354 | controller | ok: [instance]
2025-10-13 10:13:35.359842 | controller |
2025-10-13 10:13:35.359864 | controller | TASK [compliance : Create scan binding] ****************************************
2025-10-13 10:13:35.359872 | controller | Monday 13 October 2025  10:13:35 +0000 (0:00:00.022)       0:00:25.815 ********
2025-10-13 10:13:35.359881 | controller | skipping: [instance]
2025-10-13 10:13:35.376181 | controller |
2025-10-13 10:13:35.376205 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-10-13 10:13:35.376213 | controller | Monday 13 October 2025  10:13:35 +0000 (0:00:00.015)       0:00:25.830 ********
2025-10-13 10:13:35.376221 | controller | skipping: [instance]
2025-10-13 10:13:35.549367 | controller |
2025-10-13 10:13:35.549395 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-10-13 10:13:35.549403 | controller | Monday 13 October 2025  10:13:35 +0000 (0:00:00.016)       0:00:25.847 ********
2025-10-13 10:13:35.549413 | controller | changed: [instance]
2025-10-13 10:13:35.731331 | controller |
2025-10-13 10:13:35.731365 | controller | TASK [compliance : Create directory for scan results] **************************
2025-10-13 10:13:35.731373 | controller | Monday 13 October 2025  10:13:35 +0000 (0:00:00.172)       0:00:26.019 ********
2025-10-13 10:13:35.731384 | controller | changed: [instance]
2025-10-13 10:13:35.731566 | controller |
2025-10-13 10:13:35.731584 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-10-13 10:13:35.747457 | controller | Monday 13 October 2025  10:13:35 +0000 (0:00:00.182)       0:00:26.202 ********
2025-10-13 10:13:35.747480 | controller | skipping: [instance]
2025-10-13 10:13:35.915907 | controller |
2025-10-13 10:13:35.915936 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-10-13 10:13:35.915944 | controller | Monday 13 October 2025  10:13:35 +0000 (0:00:00.016)       0:00:26.218 ********
2025-10-13 10:13:35.915960 | controller | ok: [instance]
2025-10-13 10:13:35.929282 | controller |
2025-10-13 10:13:35.929304 | controller | TASK [compliance : Process scan result files] **********************************
2025-10-13 10:13:35.929311 | controller | Monday 13 October 2025  10:13:35 +0000 (0:00:00.168)       0:00:26.386 ********
2025-10-13 10:13:35.929321 | controller | skipping: [instance]
2025-10-13 10:13:35.951139 | controller |
2025-10-13 10:13:35.951162 | controller | TASK [compliance : Set output directory] ***************************************
2025-10-13 10:13:35.951170 | controller | Monday 13 October 2025  10:13:35 +0000 (0:00:00.013)       0:00:26.400 ********
2025-10-13 10:13:35.951179 | controller | ok: [instance]
2025-10-13 10:13:35.966727 | controller |
2025-10-13 10:13:35.966748 | controller | TASK [compliance : Create scan binding] ****************************************
2025-10-13 10:13:35.966755 | controller | Monday 13 October 2025  10:13:35 +0000 (0:00:00.021)       0:00:26.422 ********
2025-10-13 10:13:35.966763 | controller | skipping: [instance]
2025-10-13 10:13:35.982395 | controller |
2025-10-13 10:13:35.982434 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-10-13 10:13:35.982444 | controller | Monday 13 October 2025  10:13:35 +0000 (0:00:00.015)       0:00:26.437 ********
2025-10-13 10:13:35.982457 | controller | skipping: [instance]
2025-10-13 10:13:36.157931 | controller |
2025-10-13 10:13:36.157961 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-10-13 10:13:36.157968 | controller | Monday 13 October 2025  10:13:35 +0000 (0:00:00.015)       0:00:26.453 ********
2025-10-13 10:13:36.157979 | controller | changed: [instance]
2025-10-13 10:13:36.344747 | controller |
2025-10-13 10:13:36.344779 | controller | TASK [compliance : Create directory for scan results] **************************
2025-10-13 10:13:36.344787 | controller | Monday 13 October 2025  10:13:36 +0000 (0:00:00.175)       0:00:26.628 ********
2025-10-13 10:13:36.344798 | controller | changed: [instance]
2025-10-13 10:13:36.361864 | controller |
2025-10-13 10:13:36.361883 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-10-13 10:13:36.361892 | controller | Monday 13 October 2025  10:13:36 +0000 (0:00:00.186)       0:00:26.815 ********
2025-10-13 10:13:36.361902 | controller | skipping: [instance]
2025-10-13 10:13:36.534677 | controller |
2025-10-13 10:13:36.534709 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-10-13 10:13:36.534723 | controller | Monday 13 October 2025  10:13:36 +0000 (0:00:00.017)       0:00:26.832 ********
2025-10-13 10:13:36.534733 | controller | ok: [instance]
2025-10-13 10:13:36.549195 | controller |
2025-10-13 10:13:36.549215 | controller | TASK [compliance : Process scan result files] **********************************
2025-10-13 10:13:36.549224 | controller | Monday 13 October 2025  10:13:36 +0000 (0:00:00.172)       0:00:27.005 ********
2025-10-13 10:13:36.549234 | controller | skipping: [instance]
2025-10-13 10:13:36.567288 | controller |
2025-10-13 10:13:36.567309 | controller | TASK [compliance : Log start of scans] *****************************************
2025-10-13 10:13:36.567318 | controller | Monday 13 October 2025  10:13:36 +0000 (0:00:00.014)       0:00:27.020 ********
2025-10-13 10:13:36.567328 | controller | ok: [instance] =>
2025-10-13 10:13:36.599238 | controller |   msg: Starting scans for pci-dss
2025-10-13 10:13:36.599279 | controller |
2025-10-13 10:13:36.599291 | controller | TASK [compliance : Run scans and extract results] ******************************
2025-10-13 10:13:36.599298 | controller | Monday 13 October 2025  10:13:36 +0000 (0:00:00.017)       0:00:27.038 ********
2025-10-13 10:13:36.599308 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_operator_scan.yml for instance => (item=ocp4-pci-dss)
2025-10-13 10:13:36.621313 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_operator_scan.yml for instance => (item=ocp4-pci-dss-node)
2025-10-13 10:13:36.621333 | controller |
2025-10-13 10:13:36.621340 | controller | TASK [compliance : Set output directory] ***************************************
2025-10-13 10:13:36.621346 | controller | Monday 13 October 2025  10:13:36 +0000 (0:00:00.031)       0:00:27.069 ********
2025-10-13 10:13:36.621355 | controller | ok: [instance]
2025-10-13 10:13:36.621578 | controller |
2025-10-13 10:13:36.621605 | controller | TASK [compliance : Create scan binding] ****************************************
2025-10-13 10:13:36.636757 | controller | Monday 13 October 2025  10:13:36 +0000 (0:00:00.022)       0:00:27.092 ********
2025-10-13 10:13:36.636776 | controller | skipping: [instance]
2025-10-13 10:13:36.653446 | controller |
2025-10-13 10:13:36.653471 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-10-13 10:13:36.653479 | controller | Monday 13 October 2025  10:13:36 +0000 (0:00:00.015)       0:00:27.107 ********
2025-10-13 10:13:36.653489 | controller | skipping: [instance]
2025-10-13 10:13:36.833348 | controller |
2025-10-13 10:13:36.833379 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-10-13 10:13:36.833387 | controller | Monday 13 October 2025  10:13:36 +0000 (0:00:00.016)       0:00:27.124 ********
2025-10-13 10:13:36.833397 | controller | changed: [instance]
2025-10-13 10:13:36.833421 | controller |
2025-10-13 10:13:36.833429 | controller | TASK [compliance : Create directory for scan results] **************************
2025-10-13 10:13:36.833603 | controller | Monday 13 October 2025  10:13:36 +0000 (0:00:00.179)       0:00:27.304 ********
2025-10-13 10:13:37.021122 | controller | changed: [instance]
2025-10-13 10:13:37.037834 | controller |
2025-10-13 10:13:37.037856 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-10-13 10:13:37.037865 | controller | Monday 13 October 2025  10:13:37 +0000 (0:00:00.187)       0:00:27.491 ********
2025-10-13 10:13:37.037876 | controller | skipping: [instance]
2025-10-13 10:13:37.215245 | controller |
2025-10-13 10:13:37.215295 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-10-13 10:13:37.215303 | controller | Monday 13 October 2025  10:13:37 +0000 (0:00:00.016)       0:00:27.508 ********
2025-10-13 10:13:37.215313 | controller | ok: [instance]
2025-10-13 10:13:37.229894 | controller |
2025-10-13 10:13:37.229924 | controller | TASK [compliance : Process scan result files] **********************************
2025-10-13 10:13:37.229934 | controller | Monday 13 October 2025  10:13:37 +0000 (0:00:00.177)       0:00:27.685 ********
2025-10-13 10:13:37.229946 | controller | skipping: [instance]
2025-10-13 10:13:37.253340 | controller |
2025-10-13 10:13:37.253372 | controller | TASK [compliance : Set output directory] ***************************************
2025-10-13 10:13:37.253380 | controller | Monday 13 October 2025  10:13:37 +0000 (0:00:00.014)       0:00:27.700 ********
2025-10-13 10:13:37.253395 | controller | ok: [instance]
2025-10-13 10:13:37.253505 | controller |
2025-10-13 10:13:37.253534 | controller | TASK [compliance : Create scan binding] ****************************************
2025-10-13 10:13:37.253546 | controller | Monday 13 October 2025  10:13:37 +0000 (0:00:00.023)       0:00:27.724 ********
2025-10-13 10:13:37.270113 | controller | skipping: [instance]
2025-10-13 10:13:37.285975 | controller |
2025-10-13 10:13:37.286014 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-10-13 10:13:37.286024 | controller | Monday 13 October 2025  10:13:37 +0000 (0:00:00.016)       0:00:27.740 ********
2025-10-13 10:13:37.286035 | controller | skipping: [instance]
2025-10-13 10:13:37.466328 | controller |
2025-10-13 10:13:37.466361 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-10-13 10:13:37.466370 | controller | Monday 13 October 2025  10:13:37 +0000 (0:00:00.016)       0:00:27.756 ********
2025-10-13 10:13:37.466380 | controller | changed: [instance]
2025-10-13 10:13:37.652531 | controller |
2025-10-13 10:13:37.652560 | controller | TASK [compliance : Create directory for scan results] **************************
2025-10-13 10:13:37.652568 | controller | Monday 13 October 2025  10:13:37 +0000 (0:00:00.180)       0:00:27.936 ********
2025-10-13 10:13:37.652578 | controller | changed: [instance]
2025-10-13 10:13:37.668930 | controller |
2025-10-13 10:13:37.668947 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-10-13 10:13:37.668954 | controller | Monday 13 October 2025  10:13:37 +0000 (0:00:00.186)       0:00:28.123 ********
2025-10-13 10:13:37.668963 | controller | skipping: [instance]
2025-10-13 10:13:37.852167 | controller |
2025-10-13 10:13:37.852196 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-10-13 10:13:37.852204 | controller | Monday 13 October 2025  10:13:37 +0000 (0:00:00.016)       0:00:28.139 ********
2025-10-13 10:13:37.852214 | controller | ok: [instance]
2025-10-13 10:13:37.865848 | controller |
2025-10-13 10:13:37.865865 | controller | TASK [compliance : Process scan result files] **********************************
2025-10-13 10:13:37.865873 | controller | Monday 13 October 2025  10:13:37 +0000 (0:00:00.182)       0:00:28.322 ********
2025-10-13 10:13:37.865882 | controller | skipping: [instance]
2025-10-13 10:13:37.884453 | controller |
2025-10-13 10:13:37.884473 | controller | TASK [compliance : Log start of scans] *****************************************
2025-10-13 10:13:37.884480 | controller | Monday 13 October 2025  10:13:37 +0000 (0:00:00.013)       0:00:28.336 ********
2025-10-13 10:13:37.884488 | controller | ok: [instance] =>
2025-10-13 10:13:37.921349 | controller |   msg: Starting scans for stig
2025-10-13 10:13:37.921377 | controller |
2025-10-13 10:13:37.921386 | controller | TASK [compliance : Run scans and extract results] ******************************
2025-10-13 10:13:37.921395 | controller | Monday 13 October 2025  10:13:37 +0000 (0:00:00.018)       0:00:28.355 ********
2025-10-13 10:13:37.921406 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_operator_scan.yml for instance => (item=ocp4-stig)
2025-10-13 10:13:37.921432 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_operator_scan.yml for instance => (item=ocp4-stig-node)
2025-10-13 10:13:37.921440 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/compliance/tasks/run_operator_scan.yml for instance => (item=rhcos4-stig)
2025-10-13 10:13:37.921448 | controller |
2025-10-13 10:13:37.921626 | controller | TASK [compliance : Set output directory] ***************************************
2025-10-13 10:13:37.944534 | controller | Monday 13 October 2025  10:13:37 +0000 (0:00:00.036)       0:00:28.392 ********
2025-10-13 10:13:37.944555 | controller | ok: [instance]
2025-10-13 10:13:37.960865 | controller |
2025-10-13 10:13:37.960885 | controller | TASK [compliance : Create scan binding] ****************************************
2025-10-13 10:13:37.960894 | controller | Monday 13 October 2025  10:13:37 +0000 (0:00:00.023)       0:00:28.415 ********
2025-10-13 10:13:37.960908 | controller | skipping: [instance]
2025-10-13 10:13:37.977024 | controller |
2025-10-13 10:13:37.977041 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-10-13 10:13:37.977048 | controller | Monday 13 October 2025  10:13:37 +0000 (0:00:00.016)       0:00:28.431 ********
2025-10-13 10:13:37.977061 | controller | skipping: [instance]
2025-10-13 10:13:38.154449 | controller |
2025-10-13 10:13:38.154480 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-10-13 10:13:38.154488 | controller | Monday 13 October 2025  10:13:37 +0000 (0:00:00.016)       0:00:28.447 ********
2025-10-13 10:13:38.154498 | controller | changed: [instance]
2025-10-13 10:13:38.342718 | controller |
2025-10-13 10:13:38.342750 | controller | TASK [compliance : Create directory for scan results] **************************
2025-10-13 10:13:38.342758 | controller | Monday 13 October 2025  10:13:38 +0000 (0:00:00.177)       0:00:28.625 ********
2025-10-13 10:13:38.342768 | controller | changed: [instance]
2025-10-13 10:13:38.359843 | controller |
2025-10-13 10:13:38.359865 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-10-13 10:13:38.359874 | controller | Monday 13 October 2025  10:13:38 +0000 (0:00:00.188)       0:00:28.813 ********
2025-10-13 10:13:38.359884 | controller | skipping: [instance]
2025-10-13 10:13:38.534955 | controller |
2025-10-13 10:13:38.534986 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-10-13 10:13:38.534994 | controller | Monday 13 October 2025  10:13:38 +0000 (0:00:00.017)       0:00:28.830 ********
2025-10-13 10:13:38.535004 | controller | ok: [instance]
2025-10-13 10:13:38.550172 | controller |
2025-10-13 10:13:38.550193 | controller | TASK [compliance : Process scan result files] **********************************
2025-10-13 10:13:38.550201 | controller | Monday 13 October 2025  10:13:38 +0000 (0:00:00.175)       0:00:29.005 ********
2025-10-13 10:13:38.550212 | controller | skipping: [instance]
2025-10-13 10:13:38.573708 | controller |
2025-10-13 10:13:38.573723 | controller | TASK [compliance : Set output directory] ***************************************
2025-10-13 10:13:38.573730 | controller | Monday 13 October 2025  10:13:38 +0000 (0:00:00.015)       0:00:29.021 ********
2025-10-13 10:13:38.573738 | controller | ok: [instance]
2025-10-13 10:13:38.590411 | controller |
2025-10-13 10:13:38.590450 | controller | TASK [compliance : Create scan binding] ****************************************
2025-10-13 10:13:38.590460 | controller | Monday 13 October 2025  10:13:38 +0000 (0:00:00.023)       0:00:29.044 ********
2025-10-13 10:13:38.590473 | controller | skipping: [instance]
2025-10-13 10:13:38.590502 | controller |
2025-10-13 10:13:38.590512 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-10-13 10:13:38.590538 | controller | Monday 13 October 2025  10:13:38 +0000 (0:00:00.016)       0:00:29.061 ********
2025-10-13 10:13:38.607344 | controller | skipping: [instance]
2025-10-13 10:13:38.607531 | controller |
2025-10-13 10:13:38.607561 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-10-13 10:13:38.796321 | controller | Monday 13 October 2025  10:13:38 +0000 (0:00:00.016)       0:00:29.078 ********
2025-10-13 10:13:38.796355 | controller | changed: [instance]
2025-10-13 10:13:38.796427 | controller |
2025-10-13 10:13:38.796437 | controller | TASK [compliance : Create directory for scan results] **************************
2025-10-13 10:13:38.796445 | controller | Monday 13 October 2025  10:13:38 +0000 (0:00:00.188)       0:00:29.266 ********
2025-10-13 10:13:38.982682 | controller | changed: [instance]
2025-10-13 10:13:38.999365 | controller |
2025-10-13 10:13:38.999404 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-10-13 10:13:38.999415 | controller | Monday 13 October 2025  10:13:38 +0000 (0:00:00.186)       0:00:29.453 ********
2025-10-13 10:13:38.999427 | controller | skipping: [instance]
2025-10-13 10:13:38.999458 | controller |
2025-10-13 10:13:38.999645 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-10-13 10:13:39.172307 | controller | Monday 13 October 2025  10:13:38 +0000 (0:00:00.017)       0:00:29.470 ********
2025-10-13 10:13:39.172343 | controller | ok: [instance]
2025-10-13 10:13:39.187652 | controller |
2025-10-13 10:13:39.187672 | controller | TASK [compliance : Process scan result files] **********************************
2025-10-13 10:13:39.187680 | controller | Monday 13 October 2025  10:13:39 +0000 (0:00:00.172)       0:00:29.642 ********
2025-10-13 10:13:39.187689 | controller | skipping: [instance]
2025-10-13 10:13:39.211106 | controller |
2025-10-13 10:13:39.211124 | controller | TASK [compliance : Set output directory] ***************************************
2025-10-13 10:13:39.211136 | controller | Monday 13 October 2025  10:13:39 +0000 (0:00:00.015)       0:00:29.658 ********
2025-10-13 10:13:39.211145 | controller | ok: [instance]
2025-10-13 10:13:39.228685 | controller |
2025-10-13 10:13:39.228705 | controller | TASK [compliance : Create scan binding] ****************************************
2025-10-13 10:13:39.228717 | controller | Monday 13 October 2025  10:13:39 +0000 (0:00:00.023)       0:00:29.682 ********
2025-10-13 10:13:39.228727 | controller | skipping: [instance]
2025-10-13 10:13:39.246724 | controller |
2025-10-13 10:13:39.246755 | controller | TASK [compliance : Wait for the scan to complete] ******************************
2025-10-13 10:13:39.246763 | controller | Monday 13 October 2025  10:13:39 +0000 (0:00:00.017)       0:00:29.699 ********
2025-10-13 10:13:39.246772 | controller | skipping: [instance]
2025-10-13 10:13:39.431680 | controller |
2025-10-13 10:13:39.431711 | controller | TASK [compliance : Delete directory for scan results if it exists] *************
2025-10-13 10:13:39.431719 | controller | Monday 13 October 2025  10:13:39 +0000 (0:00:00.017)       0:00:29.717 ********
2025-10-13 10:13:39.431729 | controller | changed: [instance]
2025-10-13 10:13:39.623503 | controller |
2025-10-13 10:13:39.623534 | controller | TASK [compliance : Create directory for scan results] **************************
2025-10-13 10:13:39.623542 | controller | Monday 13 October 2025  10:13:39 +0000 (0:00:00.184)       0:00:29.902 ********
2025-10-13 10:13:39.623552 | controller | changed: [instance]
2025-10-13 10:13:39.641402 | controller |
2025-10-13 10:13:39.641421 | controller | TASK [compliance : Retrieve scan results] **************************************
2025-10-13 10:13:39.641428 | controller | Monday 13 October 2025  10:13:39 +0000 (0:00:00.191)       0:00:30.094 ********
2025-10-13 10:13:39.641436 | controller | skipping: [instance]
2025-10-13 10:13:39.819044 | controller |
2025-10-13 10:13:39.819075 | controller | TASK [compliance : Find all the compressed scan results] ***********************
2025-10-13 10:13:39.819083 | controller | Monday 13 October 2025  10:13:39 +0000 (0:00:00.018)       0:00:30.112 ********
2025-10-13 10:13:39.819093 | controller | ok: [instance]
2025-10-13 10:13:39.834799 | controller |
2025-10-13 10:13:39.834821 | controller | TASK [compliance : Process scan result files] **********************************
2025-10-13 10:13:39.834830 | controller | Monday 13 October 2025  10:13:39 +0000 (0:00:00.176)       0:00:30.289 ********
2025-10-13 10:13:39.834840 | controller | skipping: [instance]
2025-10-13 10:13:39.869389 | controller |
2025-10-13 10:13:39.869428 | controller | TASK [compliance : Delete all resources created by the role] *******************
2025-10-13 10:13:39.869439 | controller | Monday 13 October 2025  10:13:39 +0000 (0:00:00.016)       0:00:30.305 ********
2025-10-13 10:13:39.869451 | controller | skipping: [instance]
2025-10-13 10:13:39.869482 | controller |
2025-10-13 10:13:39.871814 | controller | PLAY RECAP *********************************************************************
2025-10-13 10:13:39.938919 | controller | instance                   : ok=200  changed=52   unreachable=0    failed=0    skipped=152  rescued=0    ignored=0
2025-10-13 10:13:39.938940 | controller |
2025-10-13 10:13:39.938948 | controller | Monday 13 October 2025  10:13:39 +0000 (0:00:00.034)       0:00:30.339 ********
2025-10-13 10:13:39.938955 | controller | ===============================================================================
2025-10-13 10:13:39.938960 | controller | compliance : Install packages to process results ------------------------ 4.09s
2025-10-13 10:13:39.938965 | controller | Gathering Facts --------------------------------------------------------- 0.83s
2025-10-13 10:13:39.938971 | controller | podman : Ensure podman is installed ------------------------------------- 0.76s
2025-10-13 10:13:39.938976 | controller | compliance : Install packages to process results ------------------------ 0.67s
2025-10-13 10:13:39.938981 | controller | podman : Ensure podman is installed ------------------------------------- 0.64s
2025-10-13 10:13:39.938986 | controller | Gathering Facts --------------------------------------------------------- 0.59s
2025-10-13 10:13:39.938991 | controller | podman : Enable loginctl linger for ansible_user_id --------------------- 0.32s
2025-10-13 10:13:39.938996 | controller | compliance : Ensure compliance folder exists ---------------------------- 0.30s
2025-10-13 10:13:39.939002 | controller | compliance : Find all the compressed scan results ----------------------- 0.28s
2025-10-13 10:13:39.939007 | controller | compliance : Ensure compliance folder exists ---------------------------- 0.22s
2025-10-13 10:13:39.939017 | controller | compliance : Create directory for scan results -------------------------- 0.21s
2025-10-13 10:13:39.939022 | controller | podman : Enable loginctl linger for ansible_user_id --------------------- 0.21s
2025-10-13 10:13:39.939027 | controller | compliance : Delete directory for scan results if it exists ------------- 0.20s
2025-10-13 10:13:39.939035 | controller | compliance : Create directory for scan results -------------------------- 0.20s
2025-10-13 10:13:39.939040 | controller | compliance : Create directory for scan results -------------------------- 0.19s
2025-10-13 10:13:39.939046 | controller | compliance : Delete directory for scan results if it exists ------------- 0.19s
2025-10-13 10:13:39.939051 | controller | compliance : Create directory for scan results -------------------------- 0.19s
2025-10-13 10:13:39.939056 | controller | compliance : Create directory for scan results -------------------------- 0.19s
2025-10-13 10:13:39.939061 | controller | compliance : Find all the compressed scan results ----------------------- 0.19s
2025-10-13 10:13:39.939066 | controller | compliance : Create directory for scan results -------------------------- 0.19s
2025-10-13 10:13:39.939075 | controller | INFO     Running default > cleanup
2025-10-13 10:13:39.939519 | controller | WARNING  Skipping, cleanup playbook not configured.
2025-10-13 10:13:39.940072 | controller | INFO     Writing /tmp/report.html report.
2025-10-13 10:13:40.046629 | controller | changed
2025-10-13 10:13:40.095720 | 
2025-10-13 10:13:40.095803 | PLAY RECAP
2025-10-13 10:13:40.095846 | controller | ok: 3 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-10-13 10:13:40.095871 | 
2025-10-13 10:13:40.205350 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-10-13 10:13:40.206209 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-10-13 10:13:40.751669 | 
2025-10-13 10:13:40.751774 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-10-13 10:13:40.771858 | 
2025-10-13 10:13:40.771957 | TASK [Filter out host if needed]
2025-10-13 10:13:40.780432 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-10-13 10:13:40.784863 | 
2025-10-13 10:13:40.784956 | TASK [Ensure file is present]
2025-10-13 10:13:41.097975 | controller | ok
2025-10-13 10:13:41.104828 | 
2025-10-13 10:13:41.104905 | TASK [Manage molecule report file]
2025-10-13 10:13:41.630048 | controller | changed
2025-10-13 10:13:41.635649 | 
2025-10-13 10:13:41.635714 | TASK [Check if we get ci-framework-data basedir]
2025-10-13 10:13:41.815649 | controller | ok
2025-10-13 10:13:41.820905 | 
2025-10-13 10:13:41.820989 | TASK [Create ci-framework-data log directory for zuul]
2025-10-13 10:13:42.122923 | controller | changed
2025-10-13 10:13:42.127961 | 
2025-10-13 10:13:42.128030 | TASK [Copy ci-framework interesting files]
2025-10-13 10:13:42.654621 | controller | changed
2025-10-13 10:13:42.660154 | 
2025-10-13 10:13:42.660220 | TASK [Get SELinux listing]
2025-10-13 10:13:43.187126 | controller | changed
2025-10-13 10:13:43.193527 | 
2025-10-13 10:13:43.193641 | TASK [Generate log index]
2025-10-13 10:13:43.834207 | controller | changed
2025-10-13 10:13:43.839312 | 
2025-10-13 10:13:43.839383 | TASK [Get some env related data]
2025-10-13 10:13:44.375338 | controller | /home/zuul/.local/bin/ansible
2025-10-13 10:13:45.367239 | controller | changed
2025-10-13 10:13:45.372466 | 
2025-10-13 10:13:45.372547 | TASK [Generate list of logs to collect in home directory]
2025-10-13 10:13:45.654476 | controller | ok: All paths examined
2025-10-13 10:13:45.659782 | 
2025-10-13 10:13:45.659861 | LOOP [Copy logs from home directory]
2025-10-13 10:13:45.966088 | controller | changed:
2025-10-13 10:13:45.966196 | controller | {
2025-10-13 10:13:45.966225 | controller |   "atime": 1760350349.9175794,
2025-10-13 10:13:45.966247 | controller |   "ctime": 1760350366.9026368,
2025-10-13 10:13:45.966265 | controller |   "dev": 64513,
2025-10-13 10:13:45.966283 | controller |   "gid": 1000,
2025-10-13 10:13:45.966301 | controller |   "gr_name": "zuul",
2025-10-13 10:13:45.966317 | controller |   "inode": 4488390,
2025-10-13 10:13:45.966335 | controller |   "isblk": false,
2025-10-13 10:13:45.966351 | controller |   "ischr": false,
2025-10-13 10:13:45.966369 | controller |   "isdir": false,
2025-10-13 10:13:45.966385 | controller |   "isfifo": false,
2025-10-13 10:13:45.966401 | controller |   "isgid": false,
2025-10-13 10:13:45.966418 | controller |   "islnk": false,
2025-10-13 10:13:45.966434 | controller |   "isreg": true,
2025-10-13 10:13:45.966449 | controller |   "issock": false,
2025-10-13 10:13:45.966464 | controller |   "isuid": false,
2025-10-13 10:13:45.966478 | controller |   "mode": "0644",
2025-10-13 10:13:45.966493 | controller |   "mtime": 1760350366.9026368,
2025-10-13 10:13:45.966509 | controller |   "nlink": 1,
2025-10-13 10:13:45.966524 | controller |   "path": "/home/zuul/ansible.log",
2025-10-13 10:13:45.966541 | controller |   "pw_name": "zuul",
2025-10-13 10:13:45.966559 | controller |   "rgrp": true,
2025-10-13 10:13:45.966575 | controller |   "roth": true,
2025-10-13 10:13:45.966591 | controller |   "rusr": true,
2025-10-13 10:13:45.966621 | controller |   "size": 6749,
2025-10-13 10:13:45.966638 | controller |   "uid": 1000,
2025-10-13 10:13:45.966654 | controller |   "wgrp": false,
2025-10-13 10:13:45.966670 | controller |   "woth": false,
2025-10-13 10:13:45.966685 | controller |   "wusr": true,
2025-10-13 10:13:45.966703 | controller |   "xgrp": false,
2025-10-13 10:13:45.966719 | controller |   "xoth": false,
2025-10-13 10:13:45.966734 | controller |   "xusr": false
2025-10-13 10:13:45.966749 | controller | }
2025-10-13 10:13:45.980736 | 
2025-10-13 10:13:45.980838 | TASK [Copy crio stats log file]
2025-10-13 10:13:46.004523 | controller | skipping: Conditional result was False
2025-10-13 10:13:46.013036 | 
2025-10-13 10:13:46.013127 | TASK [Get SELinux related data]
2025-10-13 10:13:46.243291 | controller | <no matches>
2025-10-13 10:13:46.540546 | controller | ERROR
2025-10-13 10:13:46.540739 | controller | {
2025-10-13 10:13:46.540777 | controller |   "delta": "0:00:00.010516",
2025-10-13 10:13:46.540800 | controller |   "end": "2025-10-13 10:13:46.244638",
2025-10-13 10:13:46.540819 | controller |   "msg": "non-zero return code",
2025-10-13 10:13:46.540836 | controller |   "rc": 1,
2025-10-13 10:13:46.540853 | controller |   "start": "2025-10-13 10:13:46.234122"
2025-10-13 10:13:46.540869 | controller | }
2025-10-13 10:13:46.540894 | controller | ERROR: Ignoring Errors
2025-10-13 10:13:46.546080 | 
2025-10-13 10:13:46.546144 | TASK [Create system configuration directory]
2025-10-13 10:13:46.727323 | controller | changed
2025-10-13 10:13:46.733063 | 
2025-10-13 10:13:46.733148 | TASK [Get some of the system configurations]
2025-10-13 10:13:47.260189 | controller | changed
2025-10-13 10:13:47.265407 | 
2025-10-13 10:13:47.265474 | TASK [Copy generated documentation if available]
2025-10-13 10:13:47.279792 | controller | skipping: Conditional result was False
2025-10-13 10:13:47.285769 | 
2025-10-13 10:13:47.285858 | TASK [Copy generated AsciiDoc documentation if available]
2025-10-13 10:13:47.311924 | controller | skipping: Conditional result was False
2025-10-13 10:13:47.317778 | 
2025-10-13 10:13:47.317860 | TASK [Compress logs bigger than 2MB]
2025-10-13 10:13:47.875551 | controller | changed
2025-10-13 10:13:47.881455 | 
2025-10-13 10:13:47.881536 | TASK [Copy files from workspace on node]
2025-10-13 10:13:47.900131 | controller | ok
2025-10-13 10:13:47.923184 | 
2025-10-13 10:13:47.923271 | TASK [fetch-output : Set log path for multiple nodes]
2025-10-13 10:13:47.936691 | controller | skipping: Conditional result was False
2025-10-13 10:13:47.942418 | 
2025-10-13 10:13:47.942486 | TASK [fetch-output : Set log path for single node]
2025-10-13 10:13:47.970443 | controller | ok
2025-10-13 10:13:47.976379 | 
2025-10-13 10:13:47.976448 | LOOP [fetch-output : Ensure local output dirs]
2025-10-13 10:13:48.181024 | controller -> localhost | ok: "/var/lib/zuul/builds/1901e6a94542400fbdb973e84d0b41ef/work/logs"
2025-10-13 10:13:48.181235 | controller -> localhost | changed: All items complete
2025-10-13 10:13:48.181264 | 
2025-10-13 10:13:48.362774 | controller -> localhost | changed: "/var/lib/zuul/builds/1901e6a94542400fbdb973e84d0b41ef/work/artifacts"
2025-10-13 10:13:48.575095 | controller -> localhost | changed: "/var/lib/zuul/builds/1901e6a94542400fbdb973e84d0b41ef/work/docs"
2025-10-13 10:13:48.586363 | 
2025-10-13 10:13:48.586455 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-10-13 10:13:49.191014 | controller | changed:
2025-10-13 10:13:49.191185 | controller | .d..t...... ./
2025-10-13 10:13:49.191216 | controller | >f+++++++++ README.html
2025-10-13 10:13:49.191238 | controller | >f+++++++++ ansible-execution.log
2025-10-13 10:13:49.191261 | controller | >f+++++++++ ansible.log
2025-10-13 10:13:49.191281 | controller | >f+++++++++ dmesg.log
2025-10-13 10:13:49.191300 | controller | >f+++++++++ installed-pkgs.log
2025-10-13 10:13:49.191318 | controller | >f+++++++++ python.log
2025-10-13 10:13:49.191339 | controller | >f+++++++++ registries.conf
2025-10-13 10:13:49.191361 | controller | >f+++++++++ report.html
2025-10-13 10:13:49.191380 | controller | >f+++++++++ selinux-denials.log
2025-10-13 10:13:49.191400 | controller | >f+++++++++ selinux-listing.log
2025-10-13 10:13:49.191418 | controller | cd+++++++++ ci-framework-data/
2025-10-13 10:13:49.191436 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-10-13 10:13:49.191454 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-10-13 10:13:49.191472 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-10-13 10:13:49.191489 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-10-13 10:13:49.191507 | controller | cd+++++++++ ci-framework-data/logs/
2025-10-13 10:13:49.191523 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2025-10-13 10:13:49.191541 | controller | cd+++++++++ ci-framework-data/tests/
2025-10-13 10:13:49.191558 | controller | cd+++++++++ ci-framework-data/tests/compliance/
2025-10-13 10:13:49.191576 | controller | cd+++++++++ ci-framework-data/tests/compliance/cis/
2025-10-13 10:13:49.191615 | controller | cd+++++++++ ci-framework-data/tests/compliance/cis/ocp4-cis/
2025-10-13 10:13:49.191637 | controller | cd+++++++++ ci-framework-data/tests/compliance/e8/
2025-10-13 10:13:49.191656 | controller | cd+++++++++ ci-framework-data/tests/compliance/e8/ocp4-e8/
2025-10-13 10:13:49.191674 | controller | cd+++++++++ ci-framework-data/tests/compliance/e8/rhcos4-e8/
2025-10-13 10:13:49.191691 | controller | cd+++++++++ ci-framework-data/tests/compliance/high/
2025-10-13 10:13:49.191708 | controller | cd+++++++++ ci-framework-data/tests/compliance/high/ocp4-high-node/
2025-10-13 10:13:49.191725 | controller | cd+++++++++ ci-framework-data/tests/compliance/high/ocp4-high/
2025-10-13 10:13:49.191743 | controller | cd+++++++++ ci-framework-data/tests/compliance/high/rhcos4-high/
2025-10-13 10:13:49.191765 | controller | cd+++++++++ ci-framework-data/tests/compliance/moderate/
2025-10-13 10:13:49.191783 | controller | cd+++++++++ ci-framework-data/tests/compliance/moderate/ocp4-moderate-node/
2025-10-13 10:13:49.191801 | controller | cd+++++++++ ci-framework-data/tests/compliance/moderate/ocp4-moderate/
2025-10-13 10:13:49.191818 | controller | cd+++++++++ ci-framework-data/tests/compliance/moderate/rhcos4-moderate/
2025-10-13 10:13:49.191835 | controller | cd+++++++++ ci-framework-data/tests/compliance/nerc-cip/
2025-10-13 10:13:49.191852 | controller | cd+++++++++ ci-framework-data/tests/compliance/nerc-cip/ocp4-nerc-cip-node/
2025-10-13 10:13:49.191868 | controller | cd+++++++++ ci-framework-data/tests/compliance/nerc-cip/ocp4-nerc-cip/
2025-10-13 10:13:49.191884 | controller | cd+++++++++ ci-framework-data/tests/compliance/pci-dss/
2025-10-13 10:13:49.191901 | controller | cd+++++++++ ci-framework-data/tests/compliance/pci-dss/ocp4-pci-dss-node/
2025-10-13 10:13:49.191940 | controller | cd+++++++++ ci-framework-data/tests/compliance/pci-dss/ocp4-pci-dss/
2025-10-13 10:13:49.191962 | controller | cd+++++++++ ci-framework-data/tests/compliance/stig/
2025-10-13 10:13:49.191981 | controller | cd+++++++++ ci-framework-data/tests/compliance/stig/ocp4-stig-node/
2025-10-13 10:13:49.191998 | controller | cd+++++++++ ci-framework-data/tests/compliance/stig/ocp4-stig/
2025-10-13 10:13:49.192015 | controller | cd+++++++++ ci-framework-data/tests/compliance/stig/rhcos4-stig/
2025-10-13 10:13:49.192033 | controller | cd+++++++++ registries.conf.d/
2025-10-13 10:13:49.192051 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-10-13 10:13:49.192069 | controller | cd+++++++++ system-config/
2025-10-13 10:13:49.192088 | controller | cd+++++++++ system-config/libvirt/
2025-10-13 10:13:49.192105 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-10-13 10:13:49.192122 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-10-13 10:13:49.590573 | controller | changed: .d..t...... ./
2025-10-13 10:13:50.040616 | controller | changed: .d..t...... ./
2025-10-13 10:13:50.062251 | 
2025-10-13 10:13:50.062337 | TASK [Return artifact to Zuul]
2025-10-13 10:13:50.090502 | controller | ok
2025-10-13 10:13:50.110121 | 
2025-10-13 10:13:50.111217 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-10-13 10:13:50.111328 | 
2025-10-13 10:13:50.111358 | PLAY RECAP
2025-10-13 10:13:50.111396 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2025-10-13 10:13:50.111419 | 
2025-10-13 10:13:50.196164 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-10-13 10:13:50.200459 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-10-13 10:13:50.710379 | 
2025-10-13 10:13:50.710507 | PLAY [all]
2025-10-13 10:13:50.728560 | 
2025-10-13 10:13:50.728643 | TASK [include_role : fetch-output]
2025-10-13 10:13:50.756650 | controller | ok
2025-10-13 10:13:50.773045 | 
2025-10-13 10:13:50.773132 | TASK [fetch-output : Set log path for multiple nodes]
2025-10-13 10:13:50.816805 | controller | skipping: Conditional result was False
2025-10-13 10:13:50.822241 | 
2025-10-13 10:13:50.822320 | TASK [fetch-output : Set log path for single node]
2025-10-13 10:13:50.850063 | controller | ok
2025-10-13 10:13:50.854904 | 
2025-10-13 10:13:50.854990 | LOOP [fetch-output : Ensure local output dirs]
2025-10-13 10:13:51.197616 | controller -> localhost | ok: "/var/lib/zuul/builds/1901e6a94542400fbdb973e84d0b41ef/work/logs"
2025-10-13 10:13:51.397447 | controller -> localhost | ok: "/var/lib/zuul/builds/1901e6a94542400fbdb973e84d0b41ef/work/artifacts"
2025-10-13 10:13:51.588434 | controller -> localhost | ok: "/var/lib/zuul/builds/1901e6a94542400fbdb973e84d0b41ef/work/docs"
2025-10-13 10:13:51.596638 | 
2025-10-13 10:13:51.596739 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-10-13 10:13:52.124966 | controller | ok
2025-10-13 10:13:52.125154 | controller | ok: All items complete
2025-10-13 10:13:52.125184 | 
2025-10-13 10:13:52.507365 | controller | ok
2025-10-13 10:13:52.879470 | controller | ok
2025-10-13 10:13:52.891335 | 
2025-10-13 10:13:52.891543 | TASK [include_role : fetch-output-openshift]
2025-10-13 10:13:52.905321 | controller | skipping: Conditional result was False
2025-10-13 10:13:52.911440 | 
2025-10-13 10:13:52.911512 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-10-13 10:13:53.285720 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.006968
2025-10-13 10:13:53.499232 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.009969
2025-10-13 10:13:53.534396 | 
2025-10-13 10:13:53.534494 | PLAY [all]
2025-10-13 10:13:53.551669 | 
2025-10-13 10:13:53.551759 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-10-13 10:13:53.942283 | controller | changed
2025-10-13 10:13:53.964061 | 
2025-10-13 10:13:53.964124 | PLAY RECAP
2025-10-13 10:13:53.964166 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-10-13 10:13:53.964188 | 
2025-10-13 10:13:54.038131 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-10-13 10:13:54.038885 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-10-13 10:13:54.624492 | 
2025-10-13 10:13:54.624609 | PLAY [localhost]
2025-10-13 10:13:54.642369 | 
2025-10-13 10:13:54.642464 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-10-13 10:13:54.963554 | localhost | changed
2025-10-13 10:13:54.968266 | 
2025-10-13 10:13:54.968345 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-10-13 10:13:54.995262 | localhost | ok
2025-10-13 10:13:55.003099 | 
2025-10-13 10:13:55.003197 | TASK [add-fileserver : Create SSH private key tempfile]
2025-10-13 10:13:55.314612 | localhost | changed
2025-10-13 10:13:55.320552 | 
2025-10-13 10:13:55.320644 | TASK [add-fileserver : Create SSH private key from secret]
2025-10-13 10:13:55.896588 | localhost | changed
2025-10-13 10:13:55.902196 | 
2025-10-13 10:13:55.902283 | TASK [add-fileserver : Add fileserver ssh key]
2025-10-13 10:13:56.254583 | localhost | Identity added: /var/lib/zuul/builds/1901e6a94542400fbdb973e84d0b41ef/work/tmp/ansible.f9k3c2ce (/var/lib/zuul/builds/1901e6a94542400fbdb973e84d0b41ef/work/tmp/ansible.f9k3c2ce)
2025-10-13 10:13:56.254763 | localhost | ok: Runtime: 0:00:00.012163
2025-10-13 10:13:56.259126 | 
2025-10-13 10:13:56.259195 | TASK [add-fileserver : Remove SSH private key from disk]
2025-10-13 10:13:56.496560 | localhost | ok: Runtime: 0:00:00.006475
2025-10-13 10:13:56.501285 | 
2025-10-13 10:13:56.501353 | TASK [add-fileserver : Add fileserver to inventory]
2025-10-13 10:13:56.549529 | localhost | changed
2025-10-13 10:13:56.554288 | 
2025-10-13 10:13:56.554357 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-10-13 10:13:56.885736 | localhost | changed
2025-10-13 10:13:56.909589 | 
2025-10-13 10:13:56.909692 | PLAY [localhost]
2025-10-13 10:13:56.921774 | 
2025-10-13 10:13:56.921835 | TASK [Generate bulk log download script]
2025-10-13 10:13:56.950000 | localhost | ok
2025-10-13 10:13:56.962245 | 
2025-10-13 10:13:56.962322 | TASK [local-log-download : Check API endpoint is defined]
2025-10-13 10:13:56.988905 | localhost | ok: All assertions passed
2025-10-13 10:13:56.994389 | 
2025-10-13 10:13:56.994457 | TASK [local-log-download : Create download script]
2025-10-13 10:13:57.386076 | localhost -> localhost | changed
2025-10-13 10:13:57.395280 | 
2025-10-13 10:13:57.395352 | TASK [Register quick-download link]
2025-10-13 10:13:57.416454 | localhost | ok
2025-10-13 10:13:57.454195 | 
2025-10-13 10:13:57.454282 | PLAY [logserver.rdoproject.org]
2025-10-13 10:13:57.465726 | 
2025-10-13 10:13:57.465793 | TASK [Set zuul-log-path fact]
2025-10-13 10:13:57.502024 | logserver.rdoproject.org | ok
2025-10-13 10:13:57.513348 | 
2025-10-13 10:13:57.513441 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-13 10:13:57.539769 | logserver.rdoproject.org | ok
2025-10-13 10:13:57.546397 | 
2025-10-13 10:13:57.546476 | TASK [upload-logs : Create log directories]
2025-10-13 10:13:59.326823 | logserver.rdoproject.org | changed
2025-10-13 10:13:59.330233 | 
2025-10-13 10:13:59.330302 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-10-13 10:13:59.581132 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.007851
2025-10-13 10:13:59.586323 | 
2025-10-13 10:13:59.586406 | TASK [upload-logs : Upload logs to log server]
2025-10-13 10:14:01.113743 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-10-13 10:14:01.116945 | 
2025-10-13 10:14:01.117019 | LOOP [upload-logs : Compress console log and json output]
2025-10-13 10:14:01.170306 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-13 10:14:01.179920 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-13 10:14:01.185373 | 
2025-10-13 10:14:01.185457 | LOOP [upload-logs : Upload compressed console log and json output]
2025-10-13 10:14:01.221529 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-13 10:14:01.221775 | 
2025-10-13 10:14:01.224947 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-13 10:14:01.232310 | 
2025-10-13 10:14:01.232385 | LOOP [upload-logs : Upload console log and json output]