2025-12-05 12:45:53.830342 | Job console starting...
2025-12-05 12:45:54.040393 | Updating repositories
2025-12-05 12:45:54.752365 | Preparing job workspace
2025-12-05 12:46:01.685386 | Running Ansible setup...
2025-12-05 12:46:04.446831 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-12-05 12:46:04.924418 | 
2025-12-05 12:46:04.924540 | PLAY [localhost]
2025-12-05 12:46:04.932059 | 
2025-12-05 12:46:04.932125 | TASK [Gathering Facts]
2025-12-05 12:46:05.726507 | localhost | ok
2025-12-05 12:46:05.739091 | 
2025-12-05 12:46:05.739170 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-12-05 12:46:06.025378 | localhost -> localhost | changed
2025-12-05 12:46:06.030239 | 
2025-12-05 12:46:06.030304 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-12-05 12:46:06.670889 | localhost -> localhost | changed
2025-12-05 12:46:06.679022 | 
2025-12-05 12:46:06.679092 | TASK [Setup log path fact]
2025-12-05 12:46:06.696316 | localhost | ok
2025-12-05 12:46:06.707130 | 
2025-12-05 12:46:06.707195 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-12-05 12:46:06.733158 | localhost | ok
2025-12-05 12:46:06.740052 | 
2025-12-05 12:46:06.740120 | TASK [emit-job-header : Print job information]
2025-12-05 12:46:06.766749 | # Job Information
2025-12-05 12:46:06.766863 | Ansible Version: 2.15.12
2025-12-05 12:46:06.766893 | Job: cifmw-molecule-run_hook
2025-12-05 12:46:06.766943 | Pipeline: github-check
2025-12-05 12:46:06.766966 | Executor: ibm-bm4-ze.softwarefactory-project.io
2025-12-05 12:46:06.766985 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3537
2025-12-05 12:46:06.767003 | Log URL (when completed): https://logserver.rdoproject.org/08f/rdoproject.org/08f1df4608c641238d1d163a42954c98/
2025-12-05 12:46:06.767021 | Event ID: 34e04720-d1d8-11f0-9d3d-e934a308552e
2025-12-05 12:46:06.770141 | 
2025-12-05 12:46:06.770200 | LOOP [emit-job-header : Print node information]
2025-12-05 12:46:06.850407 | localhost | ok:
2025-12-05 12:46:06.850516 | localhost | # Node Information
2025-12-05 12:46:06.850544 | localhost | Inventory Hostname: controller
2025-12-05 12:46:06.850567 | localhost | Hostname: np0005547048
2025-12-05 12:46:06.850586 | localhost | Username: zuul
2025-12-05 12:46:06.850607 | localhost | Distro: CentOS 9
2025-12-05 12:46:06.850626 | localhost | Provider: ibm-bm4-nodepool
2025-12-05 12:46:06.850643 | localhost | Region: regionOne
2025-12-05 12:46:06.850660 | localhost | Label: cloud-centos-9-stream-tripleo-ibm
2025-12-05 12:46:06.850677 | localhost | Product Name: OpenStack Compute
2025-12-05 12:46:06.850693 | localhost | Interface IP: 192.168.26.113
2025-12-05 12:46:06.870300 | 
2025-12-05 12:46:06.870350 | PLAY [all]
2025-12-05 12:46:06.875825 | 
2025-12-05 12:46:06.875881 | TASK [Gather network facts]
2025-12-05 12:46:07.249570 | controller | ok
2025-12-05 12:46:07.263302 | 
2025-12-05 12:46:07.263369 | TASK [include_role : start-zuul-console]
2025-12-05 12:46:07.281934 | controller | ok
2025-12-05 12:46:07.294111 | 
2025-12-05 12:46:07.294175 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-12-05 12:46:07.605476 | controller | ok
2025-12-05 12:46:07.612923 | 
2025-12-05 12:46:07.612982 | TASK [include_role : add-build-sshkey]
2025-12-05 12:46:07.630560 | controller | ok
2025-12-05 12:46:07.642300 | 
2025-12-05 12:46:07.642363 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-12-05 12:46:07.838104 | controller -> localhost | ok
2025-12-05 12:46:07.843478 | 
2025-12-05 12:46:07.843539 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-12-05 12:46:07.861514 | controller | ok
2025-12-05 12:46:07.874510 | controller | included: /var/lib/zuul/builds/08f1df4608c641238d1d163a42954c98/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-12-05 12:46:07.879717 | 
2025-12-05 12:46:07.879786 | TASK [add-build-sshkey : Create Temp SSH key]
2025-12-05 12:46:08.318769 | controller -> localhost | Generating public/private rsa key pair.
2025-12-05 12:46:08.319022 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/08f1df4608c641238d1d163a42954c98/work/08f1df4608c641238d1d163a42954c98_id_rsa.
2025-12-05 12:46:08.319057 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/08f1df4608c641238d1d163a42954c98/work/08f1df4608c641238d1d163a42954c98_id_rsa.pub.
2025-12-05 12:46:08.319082 | controller -> localhost | The key fingerprint is:
2025-12-05 12:46:08.319104 | controller -> localhost | SHA256:jT9tijjciY+XEKvyK0B1lSgTUi5JNSK67YIvWTRokOA zuul-build-sshkey
2025-12-05 12:46:08.319125 | controller -> localhost | The key's randomart image is:
2025-12-05 12:46:08.319145 | controller -> localhost | +---[RSA 3072]----+
2025-12-05 12:46:08.319164 | controller -> localhost | |+=o=. o..        |
2025-12-05 12:46:08.319183 | controller -> localhost | |B =+.o .         |
2025-12-05 12:46:08.319202 | controller -> localhost | |oE..+            |
2025-12-05 12:46:08.319220 | controller -> localhost | |.=+   .  o       |
2025-12-05 12:46:08.319238 | controller -> localhost | |+...   oS .      |
2025-12-05 12:46:08.319256 | controller -> localhost | |o..   o  . .     |
2025-12-05 12:46:08.319274 | controller -> localhost | |o+.  o + oo o    |
2025-12-05 12:46:08.319291 | controller -> localhost | |ooo . +o=. +     |
2025-12-05 12:46:08.319309 | controller -> localhost | | ..+o.o+o .      |
2025-12-05 12:46:08.319326 | controller -> localhost | +----[SHA256]-----+
2025-12-05 12:46:08.319370 | controller -> localhost | ok: Runtime: 0:00:00.126428
2025-12-05 12:46:08.325039 | 
2025-12-05 12:46:08.325102 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-12-05 12:46:08.343081 | controller | ok
2025-12-05 12:46:08.358105 | controller | included: /var/lib/zuul/builds/08f1df4608c641238d1d163a42954c98/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-12-05 12:46:08.365311 | 
2025-12-05 12:46:08.365372 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-12-05 12:46:08.379153 | controller | skipping: Conditional result was False
2025-12-05 12:46:08.384632 | 
2025-12-05 12:46:08.384698 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-12-05 12:46:08.746606 | controller | changed
2025-12-05 12:46:08.752302 | 
2025-12-05 12:46:08.752369 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-12-05 12:46:08.942111 | controller | ok
2025-12-05 12:46:08.946821 | 
2025-12-05 12:46:08.946891 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-12-05 12:46:09.511241 | controller | changed
2025-12-05 12:46:09.515807 | 
2025-12-05 12:46:09.515873 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-12-05 12:46:10.089500 | controller | changed
2025-12-05 12:46:10.094080 | 
2025-12-05 12:46:10.094166 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-12-05 12:46:10.107675 | controller | skipping: Conditional result was False
2025-12-05 12:46:10.113122 | 
2025-12-05 12:46:10.113190 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-12-05 12:46:10.400827 | controller -> localhost | changed
2025-12-05 12:46:10.409432 | 
2025-12-05 12:46:10.409516 | TASK [add-build-sshkey : Add back temp key]
2025-12-05 12:46:10.615470 | controller -> localhost | Identity added: /var/lib/zuul/builds/08f1df4608c641238d1d163a42954c98/work/08f1df4608c641238d1d163a42954c98_id_rsa (zuul-build-sshkey)
2025-12-05 12:46:10.615629 | controller -> localhost | ok: Runtime: 0:00:00.006632
2025-12-05 12:46:10.620813 | 
2025-12-05 12:46:10.620871 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-12-05 12:46:10.891571 | controller | ok
2025-12-05 12:46:10.895764 | 
2025-12-05 12:46:10.895824 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-12-05 12:46:10.919823 | controller | skipping: Conditional result was False
2025-12-05 12:46:10.928542 | 
2025-12-05 12:46:10.928599 | TASK [include_role : validate-host]
2025-12-05 12:46:10.945745 | controller | ok
2025-12-05 12:46:10.964314 | 
2025-12-05 12:46:10.964384 | TASK [validate-host : Define zuul_info_dir fact]
2025-12-05 12:46:10.992095 | controller | ok
2025-12-05 12:46:10.996517 | 
2025-12-05 12:46:10.996583 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-12-05 12:46:11.192386 | controller -> localhost | ok
2025-12-05 12:46:11.197663 | 
2025-12-05 12:46:11.197731 | TASK [validate-host : Collect information about the host]
2025-12-05 12:46:11.804354 | controller | ok
2025-12-05 12:46:11.811704 | 
2025-12-05 12:46:11.811765 | TASK [validate-host : Sanitize hostname]
2025-12-05 12:46:11.879584 | controller | ok
2025-12-05 12:46:11.883952 | 
2025-12-05 12:46:11.884014 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-12-05 12:46:12.243120 | controller -> localhost | changed
2025-12-05 12:46:12.267232 | 
2025-12-05 12:46:12.267322 | TASK [validate-host : Collect information about zuul worker]
2025-12-05 12:46:12.568346 | controller | ok
2025-12-05 12:46:12.572511 | 
2025-12-05 12:46:12.572570 | TASK [validate-host : Write out all zuul information for each host]
2025-12-05 12:46:12.910468 | controller -> localhost | changed
2025-12-05 12:46:12.919841 | 
2025-12-05 12:46:12.919940 | TASK [include_role : prepare-workspace-openshift]
2025-12-05 12:46:12.933231 | controller | skipping: Conditional result was False
2025-12-05 12:46:12.938002 | 
2025-12-05 12:46:12.938066 | TASK [include_role : remove-zuul-sshkey]
2025-12-05 12:46:12.950443 | controller | skipping: Conditional result was False
2025-12-05 12:46:12.955342 | 
2025-12-05 12:46:12.955402 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-12-05 12:46:13.142399 | controller | ok: "logs"
2025-12-05 12:46:13.142628 | controller | ok: All items complete
2025-12-05 12:46:13.142658 | 
2025-12-05 12:46:13.303601 | controller | ok: "artifacts"
2025-12-05 12:46:13.485694 | controller | ok: "docs"
2025-12-05 12:46:13.495469 | 
2025-12-05 12:46:13.495543 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-12-05 12:46:13.687881 | controller | changed: "logs"
2025-12-05 12:46:13.859294 | controller | changed: "artifacts"
2025-12-05 12:46:14.039726 | controller | changed: "docs"
2025-12-05 12:46:14.064293 | 
2025-12-05 12:46:14.064338 | PLAY RECAP
2025-12-05 12:46:14.064379 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-12-05 12:46:14.064404 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-12-05 12:46:14.064420 | 
2025-12-05 12:46:14.148405 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-12-05 12:46:14.149118 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-12-05 12:46:14.661204 | 
2025-12-05 12:46:14.661331 | PLAY [all]
2025-12-05 12:46:14.679039 | 
2025-12-05 12:46:14.679108 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-12-05 12:46:14.737622 | controller | ok
2025-12-05 12:46:14.742178 | 
2025-12-05 12:46:14.742248 | TASK [mirror-info-fork : Create /etc/ci]
2025-12-05 12:46:15.064381 | controller | changed
2025-12-05 12:46:15.069556 | 
2025-12-05 12:46:15.069627 | TASK [mirror-info-fork : Install ci_mirror script]
2025-12-05 12:46:15.827704 | controller | changed
2025-12-05 12:46:15.836481 | 
2025-12-05 12:46:15.836572 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-12-05 12:46:16.167019 | controller | changed:
2025-12-05 12:46:16.167177 | controller | {
2025-12-05 12:46:16.167203 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-12-05 12:46:16.167230 | controller | }
2025-12-05 12:46:16.357429 | controller | changed:
2025-12-05 12:46:16.357479 | controller | {
2025-12-05 12:46:16.357504 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-12-05 12:46:16.357524 | controller | }
2025-12-05 12:46:16.560895 | controller | changed:
2025-12-05 12:46:16.560960 | controller | {
2025-12-05 12:46:16.560985 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com"
2025-12-05 12:46:16.561005 | controller | }
2025-12-05 12:46:16.767189 | controller | changed:
2025-12-05 12:46:16.767272 | controller | {
2025-12-05 12:46:16.767299 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-12-05 12:46:16.767320 | controller | }
2025-12-05 12:46:16.967066 | controller | changed:
2025-12-05 12:46:16.967118 | controller | {
2025-12-05 12:46:16.967143 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-12-05 12:46:16.967164 | controller | }
2025-12-05 12:46:17.159433 | controller | changed:
2025-12-05 12:46:17.159481 | controller | {
2025-12-05 12:46:17.159505 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-12-05 12:46:17.159524 | controller | }
2025-12-05 12:46:17.368802 | controller | changed:
2025-12-05 12:46:17.368848 | controller | {
2025-12-05 12:46:17.368871 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDLOQd4ZLtkZXQGY6UwAr/06ppWQK4fDO3HaqxPk98csyOCBXsliSKK39Bso828+5srIXiW7aI6aC9P5mwi4mUZlGPfJlQbfrcGvY+b/SocuvaGK+1RrHLoJCT52LBhwgrzlXio2jeksZeein8iaTrhsPrOAs7KggIL/rB9hEiB3NaOPWhhoCP4vlW6MEMExGcqB/1FVxXFBPnLkEyW0Lk7ycVflZl2ocRxbfjZi0+tI1Wlinp8PvSQSc/WVrAcDgKjc/mB4ODPOyYy3G8FHgfMsrXSDEyjBKgLKMsdCrAUcqJQWjkqXleXSYOV4q3pzL+9umK+q/e3P/bIoSFQzmJKTU1eDfuvPXmow9F5H54fii/Da7ezlMJ+wPGHJrRAkmzvMbALy7xwswLhZMkOGNtRcPqaKYRmIBKpw3o6bCTtcNUHOtOQnzwY8JzrM2eBWJBXAANYw+9/ho80JIiwhg29CFNpVBuHbql2YxJQNrnl90guN65rYNpDxdIluweyUf8= anbanerj@kaermorhen"
2025-12-05 12:46:17.368900 | controller | }
2025-12-05 12:46:17.575496 | controller | changed:
2025-12-05 12:46:17.575544 | controller | {
2025-12-05 12:46:17.575570 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQC3VwV8Im9kRm49lt3tM36hj4Zv27FxGo4C1Q/0jqhzFmHY7RHbmeRr8ObhwWoHjXSozKWg8FL5ER0z3hTwL0W6lez3sL7hUaCmSuZmG5Hnl3x4vTSxDI9JZ/Y65rtYiiWQo2fC5xJhU/4+0e5e/pseCm8cKRSu+SaxhO+sd6FDojA2x1BzOzKiQRDy/1zWGp/cZkxcEuB1wHI5LMzN03c67vmbu+fhZRAUO4dQkvcnj2LrhQtpa+ytvnSjr8icMDosf1OsbSffwZFyHB/hfWGAfe0eIeSA2XPraxiPknXxiPKx2MJsaUTYbsZcm3EjFdHBBMumw5rBI74zLrMRvCO9GwBEmGT4rFng1nP+yw5DB8sn2zqpOsPg1LYRwCPOUveC13P6pgsZZPh812e8v5EKnETct+5XI3dVpdw6CnNiLwAyVAF15DJvBGT/u1k0Myg/bQn+Gv9k2MSj6LvQmf6WbZu2Wgjm30z3FyCneBqTL7mLF19YXzeC0ufHz5pnO1E= dasm@fedora"
2025-12-05 12:46:17.575602 | controller | }
2025-12-05 12:46:17.778531 | controller | changed:
2025-12-05 12:46:17.778587 | controller | {
2025-12-05 12:46:17.778614 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-12-05 12:46:17.778636 | controller | }
2025-12-05 12:46:17.978062 | controller | changed:
2025-12-05 12:46:17.978111 | controller | {
2025-12-05 12:46:17.978135 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-12-05 12:46:17.978155 | controller | }
2025-12-05 12:46:18.174050 | controller | changed:
2025-12-05 12:46:18.174099 | controller | {
2025-12-05 12:46:18.174124 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-12-05 12:46:18.174143 | controller | }
2025-12-05 12:46:18.405782 | controller | changed:
2025-12-05 12:46:18.405850 | controller | {
2025-12-05 12:46:18.405874 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-12-05 12:46:18.405904 | controller | }
2025-12-05 12:46:18.588496 | controller | changed:
2025-12-05 12:46:18.588544 | controller | {
2025-12-05 12:46:18.588568 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-12-05 12:46:18.588589 | controller | }
2025-12-05 12:46:18.802152 | controller | changed:
2025-12-05 12:46:18.802197 | controller | {
2025-12-05 12:46:18.802220 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-12-05 12:46:18.802238 | controller | }
2025-12-05 12:46:19.018695 | controller | changed:
2025-12-05 12:46:19.018745 | controller | {
2025-12-05 12:46:19.018770 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-12-05 12:46:19.018790 | controller | }
2025-12-05 12:46:19.213062 | controller | changed:
2025-12-05 12:46:19.213111 | controller | {
2025-12-05 12:46:19.213135 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-12-05 12:46:19.213153 | controller | }
2025-12-05 12:46:19.414376 | controller | changed:
2025-12-05 12:46:19.414426 | controller | {
2025-12-05 12:46:19.414452 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQCfNtF7NvKl915TGsGGoseUb06Hj8L/S4toWf0hExeY+F00woL6NvBlJD0nDct+P5a22I4EhvoQCRQ8reaPCm1lybR3uiRIJsj+8zkVvLwby9LXzfZorlNG9ofjd00FEmB09uW/YvTl6Q9XwwwX6tInzIOv3TMqTHHGOL74ibbj8J/FJR0cFEyj0z4WQRvtkh32xAHl83gbuINryMt0sqRI+clj2381NKL55DRLQrVw0gsfqqxiHAnXg21qWmc4J+b9e9kiuAFQjcjwTVkwJCcg3xbPwC/qokYRby/Y5S40UUd7/jEARGXT7RZgpzTuDd1oZiCVrnrqJNPaMNdVv5MLeFdf1B7iIe5aa/fGouX7AO4SdKhZUdnJmCFAGvjC6S3JMZ2wAcUl+OHnssfmdj7XL50cLo27vjuzMtLAgSqi6N99m92WCF2s8J9aVzszX7Xz9OKZCeGsiVJp3/NdABKzSEAyM9xBD/5Vho894Sav+otpySHe3p6RUTgbB5Zu8VyZRZ/UtB3ueXxyo764yrc6qWIDqrehm84Xm9g+/jpIBzGPl07NUNJpdt/6Sgf9RIKXw/7XypO5yZfUcuFNGTxLfqjTNrtgLZNcjfav6sSdVXVcMPL//XNuRdKmVFaO76eV/oGMQGr1fGcCD+N+CpI7+Q+fCNB6VFWG4nZFuI/Iuw== averdagu@redhat.com"
2025-12-05 12:46:19.414474 | controller | }
2025-12-05 12:46:19.618940 | controller | changed:
2025-12-05 12:46:19.619022 | controller | {
2025-12-05 12:46:19.619049 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDq8l27xI+QlQVdS4djp9ogSoyrNE2+Ox6vKPdhSNL1J3PE5w+WCSvMz9A5gnNuH810zwbekEApbxTze/gLQJwBHA52CChfURpXrFaxY7ePXRElwKAL3mJfzBWY/c5jnNL9TCVmFJTGZkFZP3Nh+BMgZvL6xBkt3WKm6Uq18qzd9XeKcZusrA+O+uLv1fVeQnadY9RIqOCyeFYCzLWrUfTyE8x/XG0hAWIM7qpnF2cALQS2h9n4hW5ybiUN790H08wf9hFwEf5nxY9Z9dVkPFQiTSGKNBzmnCXU9skxS/xhpFjJ5duGSZdtAHe9O+nGZm9c67hxgtf8e5PDuqAdXEv2cf6e3VBAt+Bz8EKI3yosTj0oZHfwr42Yzb1l/SKy14Rggsrc9KAQlrGXan6+u2jcQqqx7l+SWmnpFiWTV9u5cWj2IgOhApOitmRBPYqk9rE2usfO0hLn/Pj/R/Nau4803e1/EikdLE7Ps95s9mX5jRDjAoUa2JwFF5RsVFyL910= ashigupt@ashigupt.remote.csb"
2025-12-05 12:46:19.619079 | controller | }
2025-12-05 12:46:19.838232 | controller | changed:
2025-12-05 12:46:19.838318 | controller | {
2025-12-05 12:46:19.838343 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-12-05 12:46:19.838361 | controller | }
2025-12-05 12:46:20.036972 | controller | changed:
2025-12-05 12:46:20.037030 | controller | {
2025-12-05 12:46:20.037057 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-12-05 12:46:20.037079 | controller | }
2025-12-05 12:46:20.243644 | controller | changed:
2025-12-05 12:46:20.243697 | controller | {
2025-12-05 12:46:20.243723 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-12-05 12:46:20.243743 | controller | }
2025-12-05 12:46:20.445310 | controller | changed:
2025-12-05 12:46:20.445362 | controller | {
2025-12-05 12:46:20.445389 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-12-05 12:46:20.445410 | controller | }
2025-12-05 12:46:20.656292 | controller | changed:
2025-12-05 12:46:20.656374 | controller | {
2025-12-05 12:46:20.656401 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQDZdI7t1cxYx65heVI24HTV4F7oQLW1zyfxHreL2TIJKxjyrUUKIFEUmTutcBlJRLNT2Eoix6x1sOw9YrchloCLcn//SGfTElr9mSc5jbjb7QXEU+zJMhtxyEJ1Po3CUGnj7ckiIXw7wcawZtrEOAQ9pH3ExYCJcEMiyNjRQZCxT3tPK+S4B95EWh5Fsrz9CkwpjNRPPH7LigCeQTM3Wc7r97utAslBUUvYceDSLA7rMgkitJE38b7rZBeYzsGQ8YYUBjTCtehqQXxCRjizbHWaaZkBU+N3zkKB6n/iCNGIO690NK7A/qb6msTijiz1PeuM8ThOsi9qXnbX5v0PoTpcFSojV7NHAQ71f0XXuS43FhZctT+Dcx44dT8Fb5vJu2cJGrk+qF8ZgJYNpRS7gPg0EG2EqjK7JMf9ULdjSu0r+KlqIAyLvtzT4eOnQipoKlb/WG5D/0ohKv7OMQ352ggfkBFIQsRXyyTCT98Ft9juqPuahi3CAQmP4H9dyE+7+Kz437PEtsxLmfm6naNmWi7Ee1DqWPwS8rEajsm4sNM4wW9gdBboJQtc0uZw0DfLj1I9r3Mc8Ol0jYtz0yNQDSzVLrGCaJlC311trU70tZ+ZkAVV6Mn8lOhSbj1cK0lvSr6ZK4dgqGl3I1eTZJJhbLNdg7UOVaiRx9543+C/p/As7w== brjackma@redhat.com"
2025-12-05 12:46:20.656424 | controller | }
2025-12-05 12:46:20.859058 | controller | changed:
2025-12-05 12:46:20.859153 | controller | {
2025-12-05 12:46:20.859178 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-12-05 12:46:20.859197 | controller | }
2025-12-05 12:46:21.064630 | controller | changed:
2025-12-05 12:46:21.064701 | controller | {
2025-12-05 12:46:21.064734 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-12-05 12:46:21.064759 | controller | }
2025-12-05 12:46:21.264706 | controller | changed:
2025-12-05 12:46:21.264773 | controller | {
2025-12-05 12:46:21.264798 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-12-05 12:46:21.264816 | controller | }
2025-12-05 12:46:21.282172 | 
2025-12-05 12:46:21.282270 | TASK [Set timezone to UTC]
2025-12-05 12:46:21.675561 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-12-05 12:46:21.680173 | 
2025-12-05 12:46:21.680237 | TASK [Create nodepool directory]
2025-12-05 12:46:21.891379 | controller | changed
2025-12-05 12:46:21.896306 | 
2025-12-05 12:46:21.896367 | TASK [Create nodepool sub_nodes file]
2025-12-05 12:46:22.410314 | controller | changed
2025-12-05 12:46:22.414716 | 
2025-12-05 12:46:22.414774 | TASK [Create nodepool sub_nodes_private file]
2025-12-05 12:46:22.910209 | controller | changed
2025-12-05 12:46:22.915641 | 
2025-12-05 12:46:22.915702 | LOOP [Populate nodepool sub_nodes file]
2025-12-05 12:46:22.944674 | 
2025-12-05 12:46:22.944790 | LOOP [Populate nodepool sub_nodes_private file]
2025-12-05 12:46:22.972345 | 
2025-12-05 12:46:22.972456 | TASK [Create nodepool primary file]
2025-12-05 12:46:22.995221 | controller | skipping: Conditional result was False
2025-12-05 12:46:22.999952 | 
2025-12-05 12:46:23.000018 | TASK [Create nodepool node_private for this node]
2025-12-05 12:46:23.545118 | controller | changed
2025-12-05 12:46:23.550432 | 
2025-12-05 12:46:23.550493 | LOOP [Copy ssh keys to nodepool directory]
2025-12-05 12:46:23.870566 | controller | ok: Item: id_rsa Runtime: 0:00:00.004527
2025-12-05 12:46:23.870737 | 
2025-12-05 12:46:24.037854 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.006672
2025-12-05 12:46:24.056192 | 
2025-12-05 12:46:24.056258 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-12-05 12:46:24.590146 | controller | changed
2025-12-05 12:46:24.594675 | 
2025-12-05 12:46:24.594739 | TASK [Validate sudoers config after edits]
2025-12-05 12:46:24.803298 | controller | /etc/sudoers: parsed OK
2025-12-05 12:46:24.803382 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-12-05 12:46:24.803393 | controller | /etc/sudoers.d/zuul: parsed OK
2025-12-05 12:46:24.803400 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-12-05 12:46:25.125489 | controller | ok: Runtime: 0:00:00.006515
2025-12-05 12:46:25.130762 | 
2025-12-05 12:46:25.130826 | TASK [Show the environment passed in to job shell scripts]
2025-12-05 12:46:25.326518 | controller | SHELL=/bin/bash
2025-12-05 12:46:25.326576 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-12-05 12:46:25.326586 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-12-05 12:46:25.326595 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/37/3537/eb73450086809293a6f3a6959cb5d293c3a8518e
2025-12-05 12:46:25.326602 | controller | PWD=/home/zuul
2025-12-05 12:46:25.326619 | controller | ZUUL_PIPELINE=github-check
2025-12-05 12:46:25.326634 | controller | LOGNAME=zuul
2025-12-05 12:46:25.326639 | controller | XDG_SESSION_TYPE=tty
2025-12-05 12:46:25.326651 | controller | _=/usr/bin/env
2025-12-05 12:46:25.326662 | controller | MOTD_SHOWN=pam
2025-12-05 12:46:25.326674 | controller | HOME=/home/zuul
2025-12-05 12:46:25.326686 | controller | LANG=en_US.UTF-8
2025-12-05 12:46:25.326753 | controller | SSH_CONNECTION=192.168.26.12 33480 192.168.26.113 22
2025-12-05 12:46:25.326763 | controller | TOX_TESTENV_PASSENV=ZUUL_UUID ZUUL_PROJECT ZUUL_SHORT_PROJECT_NAME ZUUL_PIPELINE ZUUL_VOTING WORKSPACE BUILD_TIMEOUT ZUUL_BRANCH ZUUL_CHANGES ZUUL_CHANGE_IDS ZUUL_CHANGE ZUUL_PATCHSET
2025-12-05 12:46:25.326773 | controller | ZUUL_CHANGE_IDS=3537,eb73450086809293a6f3a6959cb5d293c3a8518e
2025-12-05 12:46:25.326779 | controller | WORKSPACE=/home/zuul/workspace
2025-12-05 12:46:25.326786 | controller | XDG_SESSION_CLASS=user
2025-12-05 12:46:25.326791 | controller | SELINUX_ROLE_REQUESTED=
2025-12-05 12:46:25.326797 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-12-05 12:46:25.326802 | controller | USER=zuul
2025-12-05 12:46:25.326808 | controller | ZUUL_VOTING=True
2025-12-05 12:46:25.326814 | controller | BUILD_TIMEOUT=1800000
2025-12-05 12:46:25.326819 | controller | SELINUX_USE_CURRENT_RANGE=
2025-12-05 12:46:25.326825 | controller | SHLVL=1
2025-12-05 12:46:25.326830 | controller | ZUUL_PATCHSET=eb73450086809293a6f3a6959cb5d293c3a8518e
2025-12-05 12:46:25.326836 | controller | XDG_SESSION_ID=1
2025-12-05 12:46:25.326842 | controller | ZUUL_BRANCH=main
2025-12-05 12:46:25.326847 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-12-05 12:46:25.326853 | controller | SSH_CLIENT=192.168.26.12 33480 22
2025-12-05 12:46:25.326859 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-12-05 12:46:25.326864 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-12-05 12:46:25.326870 | controller | which_declare=declare -f
2025-12-05 12:46:25.326877 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2025-12-05 12:46:25.326883 | controller | SELINUX_LEVEL_REQUESTED=
2025-12-05 12:46:25.326888 | controller | ZUUL_CHANGE=3537
2025-12-05 12:46:25.326894 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-12-05 12:46:25.326899 | controller | ZUUL_UUID=08f1df4608c641238d1d163a42954c98
2025-12-05 12:46:25.326905 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-12-05 12:46:25.326910 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-12-05 12:46:25.326916 | controller | }
2025-12-05 12:46:25.655976 | controller | ok: Runtime: 0:00:00.008365
2025-12-05 12:46:25.660775 | 
2025-12-05 12:46:25.660833 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-12-05 12:46:25.674485 | controller | skipping: Conditional result was False
2025-12-05 12:46:25.679479 | 
2025-12-05 12:46:25.679536 | TASK [Symlink /home/zuul-worker/workspace]
2025-12-05 12:46:26.203776 | controller | skipping: Conditional result was False
2025-12-05 12:46:26.208701 | 
2025-12-05 12:46:26.208761 | TASK [Ensure legacy workspace directory]
2025-12-05 12:46:26.397904 | controller | changed
2025-12-05 12:46:26.413822 | 
2025-12-05 12:46:26.413884 | PLAY RECAP
2025-12-05 12:46:26.413947 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-12-05 12:46:26.413972 | 
2025-12-05 12:46:26.485529 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-12-05 12:46:26.486322 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-12-05 12:46:26.959202 | 
2025-12-05 12:46:26.959296 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-12-05 12:46:26.978762 | 
2025-12-05 12:46:26.978835 | TASK [Create zuul-output directory]
2025-12-05 12:46:27.291730 | controller | changed
2025-12-05 12:46:27.296940 | 
2025-12-05 12:46:27.297017 | TASK [Slurp Zuul inventory test]
2025-12-05 12:46:27.563729 | controller -> localhost | ok
2025-12-05 12:46:27.569902 | 
2025-12-05 12:46:27.569994 | TASK [Save zuul inventory]
2025-12-05 12:46:28.276548 | controller | changed
2025-12-05 12:46:28.281351 | 
2025-12-05 12:46:28.281416 | TASK [Save zuul vars without the change_message]
2025-12-05 12:46:28.860050 | controller | changed
2025-12-05 12:46:28.877468 | 
2025-12-05 12:46:28.877520 | PLAY RECAP
2025-12-05 12:46:28.877563 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-12-05 12:46:28.877586 | 
2025-12-05 12:46:28.936403 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-12-05 12:46:28.937118 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-12-05 12:46:29.431427 | 
2025-12-05 12:46:29.431526 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2025-12-05 12:46:29.450760 | 
2025-12-05 12:46:29.450824 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2025-12-05 12:46:29.469148 | controller | ok
2025-12-05 12:46:29.484968 | 
2025-12-05 12:46:29.485047 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-12-05 12:46:29.508746 | controller | skipping: Conditional result was False
2025-12-05 12:46:29.514131 | 
2025-12-05 12:46:29.514196 | TASK [mirror-info-fork : Create /etc/ci]
2025-12-05 12:46:29.804698 | controller | ok
2025-12-05 12:46:29.810224 | 
2025-12-05 12:46:29.810297 | TASK [mirror-info-fork : Install ci_mirror script]
2025-12-05 12:46:30.416950 | controller | ok
2025-12-05 12:46:30.426520 | 
2025-12-05 12:46:30.426578 | TASK [Prepare workspace]
2025-12-05 12:46:30.444695 | controller | ok
2025-12-05 12:46:30.460640 | 
2025-12-05 12:46:30.460703 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-12-05 12:46:30.741875 | controller | ok
2025-12-05 12:46:30.747423 | 
2025-12-05 12:46:30.747487 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-12-05 12:46:33.076775 | controller | Output suppressed because no_log was given
2025-12-05 12:46:33.085982 | 
2025-12-05 12:46:33.086047 | LOOP [Create zuul-output directory]
2025-12-05 12:46:33.288133 | controller | changed: "/home/zuul/ci-framework-data/logs"
2025-12-05 12:46:33.448220 | controller | ok: "/home/zuul/zuul-output/logs"
2025-12-05 12:46:33.460570 | 
2025-12-05 12:46:33.460645 | TASK [Install required packages]
2025-12-05 12:47:30.923026 | controller | changed
2025-12-05 12:47:30.927689 | 
2025-12-05 12:47:30.927745 | TASK [Install venv]
2025-12-05 12:48:29.558274 | controller | changed
2025-12-05 12:48:29.580870 | 
2025-12-05 12:48:29.580946 | PLAY RECAP
2025-12-05 12:48:29.580991 | controller | ok: 7 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-12-05 12:48:29.581012 | 
2025-12-05 12:48:29.647112 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-12-05 12:48:29.647927 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-12-05 12:48:30.120837 | 
2025-12-05 12:48:30.120952 | PLAY [Run ci/playbooks/molecule-test.yml]
2025-12-05 12:48:30.139500 | 
2025-12-05 12:48:30.139565 | TASK [Gather required facts]
2025-12-05 12:48:30.594991 | controller | ok
2025-12-05 12:48:30.600261 | 
2025-12-05 12:48:30.600336 | TASK [Load environment var if instructed to]
2025-12-05 12:48:30.614460 | controller | skipping: Conditional result was False
2025-12-05 12:48:30.620007 | 
2025-12-05 12:48:30.620073 | TASK [Ensure group_vars dir exists]
2025-12-05 12:48:30.920784 | controller | ok
2025-12-05 12:48:30.926605 | 
2025-12-05 12:48:30.926669 | TASK [Print related variables]
2025-12-05 12:48:30.954759 | controller | ok:
2025-12-05 12:48:30.954870 | controller | mol_config_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config_local.yml
2025-12-05 12:48:30.954901 | controller | roles_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/run_hook
2025-12-05 12:48:30.959593 | 
2025-12-05 12:48:30.959656 | TASK [Run molecule]
2025-12-05 12:48:31.833612 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2025-12-05 12:48:31.905571 | controller | INFO     Running default > prepare
2025-12-05 12:48:32.506078 | controller |
2025-12-05 12:48:32.506183 | controller | PLAY [Prepare] *****************************************************************
2025-12-05 12:48:32.506272 | controller |
2025-12-05 12:48:32.506682 | controller | TASK [Gathering Facts] *********************************************************
2025-12-05 12:48:32.506751 | controller | Friday 05 December 2025  12:48:32 +0000 (0:00:00.009)       0:00:00.009 *******
2025-12-05 12:48:33.278971 | controller | ok: [instance]
2025-12-05 12:48:33.279053 | controller |
2025-12-05 12:48:33.279169 | controller | TASK [Create dummy env file] ***************************************************
2025-12-05 12:48:33.279269 | controller | Friday 05 December 2025  12:48:33 +0000 (0:00:00.773)       0:00:00.783 *******
2025-12-05 12:48:33.761839 | controller | changed: [instance]
2025-12-05 12:48:33.761921 | controller |
2025-12-05 12:48:33.761933 | controller | TASK [Create dummy playbook] ***************************************************
2025-12-05 12:48:33.762037 | controller | Friday 05 December 2025  12:48:33 +0000 (0:00:00.482)       0:00:01.266 *******
2025-12-05 12:48:35.506781 | controller | changed: [instance] => (item=dummy-1.yml)
2025-12-05 12:48:35.507010 | controller | changed: [instance] => (item=dummy-2.yml)
2025-12-05 12:48:35.507572 | controller | changed: [instance] => (item=dummy-3.yml)
2025-12-05 12:48:35.507608 | controller | changed: [instance] => (item=dummy-4.yml)
2025-12-05 12:48:35.765775 | controller | changed: [instance] => (item=dummy-5.yml)
2025-12-05 12:48:35.765797 | controller | changed: [instance] => (item=dummy-6.yml)
2025-12-05 12:48:35.765805 | controller |
2025-12-05 12:48:35.765811 | controller | TASK [Remove dummy file for retry playbook test] *******************************
2025-12-05 12:48:35.765817 | controller | Friday 05 December 2025  12:48:35 +0000 (0:00:01.744)       0:00:03.010 *******
2025-12-05 12:48:35.765826 | controller | ok: [instance]
2025-12-05 12:48:36.084007 | controller |
2025-12-05 12:48:36.084030 | controller | TASK [Create dummy retry playbook] *********************************************
2025-12-05 12:48:36.084038 | controller | Friday 05 December 2025  12:48:35 +0000 (0:00:00.258)       0:00:03.269 *******
2025-12-05 12:48:36.084047 | controller | changed: [instance]
2025-12-05 12:48:36.084242 | controller |
2025-12-05 12:48:36.084255 | controller | PLAY RECAP *********************************************************************
2025-12-05 12:48:36.084261 | controller | instance                   : ok=5    changed=3    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2025-12-05 12:48:36.084273 | controller |
2025-12-05 12:48:36.084279 | controller | Friday 05 December 2025  12:48:36 +0000 (0:00:00.317)       0:00:03.587 *******
2025-12-05 12:48:36.084284 | controller | ===============================================================================
2025-12-05 12:48:36.084290 | controller | Create dummy playbook --------------------------------------------------- 1.74s
2025-12-05 12:48:36.084295 | controller | Gathering Facts --------------------------------------------------------- 0.77s
2025-12-05 12:48:36.084301 | controller | Create dummy env file --------------------------------------------------- 0.48s
2025-12-05 12:48:36.084309 | controller | Create dummy retry playbook --------------------------------------------- 0.32s
2025-12-05 12:48:36.134709 | controller | Remove dummy file for retry playbook test ------------------------------- 0.26s
2025-12-05 12:48:36.134744 | controller | INFO     Running default > converge
2025-12-05 12:48:36.524792 | controller |
2025-12-05 12:48:36.524953 | controller | PLAY [Converge] ****************************************************************
2025-12-05 12:48:36.525126 | controller |
2025-12-05 12:48:36.525256 | controller | TASK [Gathering Facts] *********************************************************
2025-12-05 12:48:36.525386 | controller | Friday 05 December 2025  12:48:36 +0000 (0:00:00.014)       0:00:00.014 *******
2025-12-05 12:48:37.280609 | controller | ok: [instance]
2025-12-05 12:48:37.307418 | controller |
2025-12-05 12:48:37.307442 | controller | TASK [No hook] *****************************************************************
2025-12-05 12:48:37.307453 | controller | Friday 05 December 2025  12:48:37 +0000 (0:00:00.755)       0:00:00.769 *******
2025-12-05 12:48:37.307465 | controller |
2025-12-05 12:48:37.361029 | controller | TASK [run_hook : Assert parameters are valid] **********************************
2025-12-05 12:48:37.361107 | controller | Friday 05 December 2025  12:48:37 +0000 (0:00:00.026)       0:00:00.796 *******
2025-12-05 12:48:37.361125 | controller | ok: [instance]
2025-12-05 12:48:37.361158 | controller |
2025-12-05 12:48:37.361176 | controller | TASK [run_hook : Assert single hooks are all mappings] *************************
2025-12-05 12:48:37.361186 | controller | Friday 05 December 2025  12:48:37 +0000 (0:00:00.053)       0:00:00.850 *******
2025-12-05 12:48:37.430401 | controller | ok: [instance]
2025-12-05 12:48:37.500862 | controller |
2025-12-05 12:48:37.500879 | controller | TASK [run_hook : Loop on hooks for no_hook] ************************************
2025-12-05 12:48:37.500886 | controller | Friday 05 December 2025  12:48:37 +0000 (0:00:00.069)       0:00:00.920 *******
2025-12-05 12:48:37.500898 | controller | skipping: [instance]
2025-12-05 12:48:37.522645 | controller |
2025-12-05 12:48:37.522662 | controller | TASK [Ensure we do not have ceph_uuid] *****************************************
2025-12-05 12:48:37.522674 | controller | Friday 05 December 2025  12:48:37 +0000 (0:00:00.070)       0:00:00.990 *******
2025-12-05 12:48:37.522683 | controller | ok: [instance] => changed=false
2025-12-05 12:48:37.550941 | controller |   msg: All assertions passed
2025-12-05 12:48:37.550985 | controller |
2025-12-05 12:48:37.550998 | controller | TASK [Combined hooks] **********************************************************
2025-12-05 12:48:37.551006 | controller | Friday 05 December 2025  12:48:37 +0000 (0:00:00.021)       0:00:01.012 *******
2025-12-05 12:48:37.551018 | controller |
2025-12-05 12:48:37.607018 | controller | TASK [run_hook : Assert parameters are valid] **********************************
2025-12-05 12:48:37.607036 | controller | Friday 05 December 2025  12:48:37 +0000 (0:00:00.028)       0:00:01.040 *******
2025-12-05 12:48:37.607046 | controller | ok: [instance]
2025-12-05 12:48:37.680235 | controller |
2025-12-05 12:48:37.680253 | controller | TASK [run_hook : Assert single hooks are all mappings] *************************
2025-12-05 12:48:37.680261 | controller | Friday 05 December 2025  12:48:37 +0000 (0:00:00.055)       0:00:01.096 *******
2025-12-05 12:48:37.680270 | controller | ok: [instance]
2025-12-05 12:48:37.797176 | controller |
2025-12-05 12:48:37.797219 | controller | TASK [run_hook : Loop on hooks for run_molecule] *******************************
2025-12-05 12:48:37.797230 | controller | Friday 05 December 2025  12:48:37 +0000 (0:00:00.073)       0:00:01.169 *******
2025-12-05 12:48:37.797244 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/run_hook/tasks/playbook.yml for instance => (item={'name': '01 Default noop hook', 'source': 'noop.yml', 'type': 'playbook'})
2025-12-05 12:48:37.797366 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/run_hook/tasks/playbook.yml for instance => (item={'name': '02 Re-run noop', 'source': 'noop.yml', 'type': 'playbook'})
2025-12-05 12:48:37.797380 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/run_hook/tasks/playbook.yml for instance => (item={'source': '/tmp/dummy-1.yml', 'type': 'playbook', 'extra_vars': {'foo': 'bar', 'file': '/tmp/dummy-env.yml'}, 'name': '03 single hook'})
2025-12-05 12:48:37.797396 | controller |
2025-12-05 12:48:37.797473 | controller | TASK [run_hook : Set playbook path for 01 Default noop hook] *******************
2025-12-05 12:48:37.797610 | controller | Friday 05 December 2025  12:48:37 +0000 (0:00:00.116)       0:00:01.286 *******
2025-12-05 12:48:37.839559 | controller | ok: [instance]
2025-12-05 12:48:37.839700 | controller |
2025-12-05 12:48:37.839832 | controller | TASK [run_hook : Get file stat] ************************************************
2025-12-05 12:48:37.839977 | controller | Friday 05 December 2025  12:48:37 +0000 (0:00:00.042)       0:00:01.329 *******
2025-12-05 12:48:38.119342 | controller | ok: [instance]
2025-12-05 12:48:38.136055 | controller |
2025-12-05 12:48:38.136087 | controller | TASK [run_hook : Fail if playbook doesn't exist] *******************************
2025-12-05 12:48:38.136099 | controller | Friday 05 December 2025  12:48:38 +0000 (0:00:00.279)       0:00:01.608 *******
2025-12-05 12:48:38.136119 | controller | skipping: [instance]
2025-12-05 12:48:38.136143 | controller |
2025-12-05 12:48:38.136151 | controller | TASK [run_hook : Get parameters files] *****************************************
2025-12-05 12:48:38.136233 | controller | Friday 05 December 2025  12:48:38 +0000 (0:00:00.016)       0:00:01.625 *******
2025-12-05 12:48:38.412360 | controller | ok: [instance]
2025-12-05 12:48:38.431566 | controller |
2025-12-05 12:48:38.431582 | controller | TASK [run_hook : Add parameters artifacts as extra variables] ******************
2025-12-05 12:48:38.431589 | controller | Friday 05 December 2025  12:48:38 +0000 (0:00:00.276)       0:00:01.901 *******
2025-12-05 12:48:38.431597 | controller | ok: [instance]
2025-12-05 12:48:38.703849 | controller |
2025-12-05 12:48:38.703869 | controller | TASK [run_hook : Ensure log directory exists] **********************************
2025-12-05 12:48:38.703876 | controller | Friday 05 December 2025  12:48:38 +0000 (0:00:00.019)       0:00:01.921 *******
2025-12-05 12:48:38.703884 | controller | ok: [instance]
2025-12-05 12:48:38.890773 | controller |
2025-12-05 12:48:38.890803 | controller | TASK [run_hook : Ensure artifacts directory exists] ****************************
2025-12-05 12:48:38.890811 | controller | Friday 05 December 2025  12:48:38 +0000 (0:00:00.272)       0:00:02.193 *******
2025-12-05 12:48:38.890822 | controller | ok: [instance]
2025-12-05 12:48:39.460444 | controller |
2025-12-05 12:48:39.460472 | controller | TASK [run_hook : Run hook without retry - 01 Default noop hook] ****************
2025-12-05 12:48:39.460480 | controller | Friday 05 December 2025  12:48:38 +0000 (0:00:00.186)       0:00:02.380 *******
2025-12-05 12:48:39.460490 | controller | Follow script's output here: /home/zuul/ci-framework-data/logs/ci_script_000_run_hook_without_retry_01.log
2025-12-05 12:48:39.489409 | controller | changed: [instance]
2025-12-05 12:48:39.489425 | controller |
2025-12-05 12:48:39.489431 | controller | TASK [run_hook : Run hook with retry - 01 Default noop hook] *******************
2025-12-05 12:48:39.489440 | controller | Friday 05 December 2025  12:48:39 +0000 (0:00:00.569)       0:00:02.949 *******
2025-12-05 12:48:39.489448 | controller | skipping: [instance]
2025-12-05 12:48:39.651878 | controller |
2025-12-05 12:48:39.651902 | controller | TASK [run_hook : Check if we have a file] **************************************
2025-12-05 12:48:39.651910 | controller | Friday 05 December 2025  12:48:39 +0000 (0:00:00.029)       0:00:02.979 *******
2025-12-05 12:48:39.651919 | controller | ok: [instance]
2025-12-05 12:48:39.669019 | controller |
2025-12-05 12:48:39.669041 | controller | TASK [run_hook : Load generated content in main playbook] **********************
2025-12-05 12:48:39.669050 | controller | Friday 05 December 2025  12:48:39 +0000 (0:00:00.162)       0:00:03.141 *******
2025-12-05 12:48:39.669063 | controller | skipping: [instance]
2025-12-05 12:48:39.669280 | controller |
2025-12-05 12:48:39.669296 | controller | TASK [run_hook : Set playbook path for 02 Re-run noop] *************************
2025-12-05 12:48:39.669308 | controller | Friday 05 December 2025  12:48:39 +0000 (0:00:00.017)       0:00:03.158 *******
2025-12-05 12:48:39.712247 | controller | ok: [instance]
2025-12-05 12:48:39.907908 | controller |
2025-12-05 12:48:39.907929 | controller | TASK [run_hook : Get file stat] ************************************************
2025-12-05 12:48:39.907937 | controller | Friday 05 December 2025  12:48:39 +0000 (0:00:00.043)       0:00:03.201 *******
2025-12-05 12:48:39.907946 | controller | ok: [instance]
2025-12-05 12:48:39.925459 | controller |
2025-12-05 12:48:39.925479 | controller | TASK [run_hook : Fail if playbook doesn't exist] *******************************
2025-12-05 12:48:39.925486 | controller | Friday 05 December 2025  12:48:39 +0000 (0:00:00.195)       0:00:03.397 *******
2025-12-05 12:48:39.925496 | controller | skipping: [instance]
2025-12-05 12:48:40.123082 | controller |
2025-12-05 12:48:40.123130 | controller | TASK [run_hook : Get parameters files] *****************************************
2025-12-05 12:48:40.123141 | controller | Friday 05 December 2025  12:48:39 +0000 (0:00:00.017)       0:00:03.415 *******
2025-12-05 12:48:40.123153 | controller | ok: [instance]
2025-12-05 12:48:40.123182 | controller |
2025-12-05 12:48:40.123192 | controller | TASK [run_hook : Add parameters artifacts as extra variables] ******************
2025-12-05 12:48:40.123376 | controller | Friday 05 December 2025  12:48:40 +0000 (0:00:00.197)       0:00:03.612 *******
2025-12-05 12:48:40.143036 | controller | ok: [instance]
2025-12-05 12:48:40.143385 | controller |
2025-12-05 12:48:40.326486 | controller | TASK [run_hook : Ensure log directory exists] **********************************
2025-12-05 12:48:40.326512 | controller | Friday 05 December 2025  12:48:40 +0000 (0:00:00.020)       0:00:03.632 *******
2025-12-05 12:48:40.326522 | controller | ok: [instance]
2025-12-05 12:48:40.499219 | controller |
2025-12-05 12:48:40.499246 | controller | TASK [run_hook : Ensure artifacts directory exists] ****************************
2025-12-05 12:48:40.499254 | controller | Friday 05 December 2025  12:48:40 +0000 (0:00:00.183)       0:00:03.816 *******
2025-12-05 12:48:40.499263 | controller | ok: [instance]
2025-12-05 12:48:41.054842 | controller |
2025-12-05 12:48:41.054874 | controller | TASK [run_hook : Run hook without retry - 02 Re-run noop] **********************
2025-12-05 12:48:41.054882 | controller | Friday 05 December 2025  12:48:40 +0000 (0:00:00.172)       0:00:03.988 *******
2025-12-05 12:48:41.054893 | controller | Follow script's output here: /home/zuul/ci-framework-data/logs/ci_script_001_run_hook_without_retry_02_re.log
2025-12-05 12:48:41.083817 | controller | changed: [instance]
2025-12-05 12:48:41.083839 | controller |
2025-12-05 12:48:41.083848 | controller | TASK [run_hook : Run hook with retry - 02 Re-run noop] *************************
2025-12-05 12:48:41.083856 | controller | Friday 05 December 2025  12:48:41 +0000 (0:00:00.555)       0:00:04.544 *******
2025-12-05 12:48:41.083868 | controller | skipping: [instance]
2025-12-05 12:48:41.249433 | controller |
2025-12-05 12:48:41.249452 | controller | TASK [run_hook : Check if we have a file] **************************************
2025-12-05 12:48:41.249460 | controller | Friday 05 December 2025  12:48:41 +0000 (0:00:00.029)       0:00:04.573 *******
2025-12-05 12:48:41.249469 | controller | ok: [instance]
2025-12-05 12:48:41.266316 | controller |
2025-12-05 12:48:41.266339 | controller | TASK [run_hook : Load generated content in main playbook] **********************
2025-12-05 12:48:41.266347 | controller | Friday 05 December 2025  12:48:41 +0000 (0:00:00.165)       0:00:04.738 *******
2025-12-05 12:48:41.266356 | controller | skipping: [instance]
2025-12-05 12:48:41.311778 | controller |
2025-12-05 12:48:41.311795 | controller | TASK [run_hook : Set playbook path for 03 single hook] *************************
2025-12-05 12:48:41.311802 | controller | Friday 05 December 2025  12:48:41 +0000 (0:00:00.016)       0:00:04.755 *******
2025-12-05 12:48:41.311810 | controller | ok: [instance]
2025-12-05 12:48:41.490288 | controller |
2025-12-05 12:48:41.490305 | controller | TASK [run_hook : Get file stat] ************************************************
2025-12-05 12:48:41.490312 | controller | Friday 05 December 2025  12:48:41 +0000 (0:00:00.045)       0:00:04.801 *******
2025-12-05 12:48:41.490320 | controller | ok: [instance]
2025-12-05 12:48:41.505671 | controller |
2025-12-05 12:48:41.505687 | controller | TASK [run_hook : Fail if playbook doesn't exist] *******************************
2025-12-05 12:48:41.505693 | controller | Friday 05 December 2025  12:48:41 +0000 (0:00:00.178)       0:00:04.979 *******
2025-12-05 12:48:41.505702 | controller | skipping: [instance]
2025-12-05 12:48:41.682472 | controller |
2025-12-05 12:48:41.682497 | controller | TASK [run_hook : Get parameters files] *****************************************
2025-12-05 12:48:41.682504 | controller | Friday 05 December 2025  12:48:41 +0000 (0:00:00.015)       0:00:04.995 *******
2025-12-05 12:48:41.682513 | controller | ok: [instance]
2025-12-05 12:48:41.701535 | controller |
2025-12-05 12:48:41.701555 | controller | TASK [run_hook : Add parameters artifacts as extra variables] ******************
2025-12-05 12:48:41.701562 | controller | Friday 05 December 2025  12:48:41 +0000 (0:00:00.176)       0:00:05.172 *******
2025-12-05 12:48:41.701571 | controller | ok: [instance]
2025-12-05 12:48:41.883556 | controller |
2025-12-05 12:48:41.883577 | controller | TASK [run_hook : Ensure log directory exists] **********************************
2025-12-05 12:48:41.883585 | controller | Friday 05 December 2025  12:48:41 +0000 (0:00:00.019)       0:00:05.191 *******
2025-12-05 12:48:41.883594 | controller | ok: [instance]
2025-12-05 12:48:42.063536 | controller |
2025-12-05 12:48:42.063557 | controller | TASK [run_hook : Ensure artifacts directory exists] ****************************
2025-12-05 12:48:42.063566 | controller | Friday 05 December 2025  12:48:41 +0000 (0:00:00.181)       0:00:05.373 *******
2025-12-05 12:48:42.063575 | controller | ok: [instance]
2025-12-05 12:48:43.867802 | controller |
2025-12-05 12:48:43.867832 | controller | TASK [run_hook : Run hook without retry - 03 single hook] **********************
2025-12-05 12:48:43.867840 | controller | Friday 05 December 2025  12:48:42 +0000 (0:00:00.180)       0:00:05.553 *******
2025-12-05 12:48:43.867850 | controller | Follow script's output here: /home/zuul/ci-framework-data/logs/ci_script_002_run_hook_without_retry_03.log
2025-12-05 12:48:43.897556 | controller | changed: [instance]
2025-12-05 12:48:43.897579 | controller |
2025-12-05 12:48:43.897586 | controller | TASK [run_hook : Run hook with retry - 03 single hook] *************************
2025-12-05 12:48:43.897591 | controller | Friday 05 December 2025  12:48:43 +0000 (0:00:01.804)       0:00:07.357 *******
2025-12-05 12:48:43.897599 | controller | skipping: [instance]
2025-12-05 12:48:44.075657 | controller |
2025-12-05 12:48:44.075676 | controller | TASK [run_hook : Check if we have a file] **************************************
2025-12-05 12:48:44.075688 | controller | Friday 05 December 2025  12:48:43 +0000 (0:00:00.030)       0:00:07.387 *******
2025-12-05 12:48:44.075698 | controller | ok: [instance]
2025-12-05 12:48:44.101439 | controller |
2025-12-05 12:48:44.101457 | controller | TASK [run_hook : Load generated content in main playbook] **********************
2025-12-05 12:48:44.101464 | controller | Friday 05 December 2025  12:48:44 +0000 (0:00:00.177)       0:00:07.565 *******
2025-12-05 12:48:44.101472 | controller | ok: [instance]
2025-12-05 12:48:44.125092 | controller |
2025-12-05 12:48:44.125135 | controller | TASK [Ensure we have the ceph_uuid variable now] *******************************
2025-12-05 12:48:44.125143 | controller | Friday 05 December 2025  12:48:44 +0000 (0:00:00.025)       0:00:07.591 *******
2025-12-05 12:48:44.125162 | controller | ok: [instance] => changed=false
2025-12-05 12:48:44.125181 | controller |   msg: All assertions passed
2025-12-05 12:48:44.125190 | controller |
2025-12-05 12:48:44.125275 | controller | TASK [Only listed hooks] *******************************************************
2025-12-05 12:48:44.125380 | controller | Friday 05 December 2025  12:48:44 +0000 (0:00:00.023)       0:00:07.614 *******
2025-12-05 12:48:44.155752 | controller |
2025-12-05 12:48:44.210336 | controller | TASK [run_hook : Assert parameters are valid] **********************************
2025-12-05 12:48:44.210359 | controller | Friday 05 December 2025  12:48:44 +0000 (0:00:00.030)       0:00:07.645 *******
2025-12-05 12:48:44.210374 | controller | ok: [instance]
2025-12-05 12:48:44.278037 | controller |
2025-12-05 12:48:44.278081 | controller | TASK [run_hook : Assert single hooks are all mappings] *************************
2025-12-05 12:48:44.278089 | controller | Friday 05 December 2025  12:48:44 +0000 (0:00:00.054)       0:00:07.699 *******
2025-12-05 12:48:44.278099 | controller | ok: [instance]
2025-12-05 12:48:44.278124 | controller |
2025-12-05 12:48:44.278249 | controller | TASK [run_hook : Loop on hooks for list_hooks] *********************************
2025-12-05 12:48:44.278270 | controller | Friday 05 December 2025  12:48:44 +0000 (0:00:00.068)       0:00:07.767 *******
2025-12-05 12:48:44.378897 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/run_hook/tasks/playbook.yml for instance => (item={'name': 'Run dummy-2', 'source': '/tmp/dummy-2.yml', 'type': 'playbook', 'extra_vars': {'foo': 'bar', 'file': '/tmp/dummy-env.yml'}})
2025-12-05 12:48:44.423916 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/run_hook/tasks/playbook.yml for instance => (item={'name': 'Run dummy-3', 'source': '/tmp/dummy-3.yml', 'type': 'playbook', 'extra_vars': {'foo': 'bar', 'file': '/tmp/dummy-env.yml'}})
2025-12-05 12:48:44.423948 | controller |
2025-12-05 12:48:44.424001 | controller | TASK [run_hook : Set playbook path for Run dummy-2] ****************************
2025-12-05 12:48:44.424019 | controller | Friday 05 December 2025  12:48:44 +0000 (0:00:00.100)       0:00:07.867 *******
2025-12-05 12:48:44.424035 | controller | ok: [instance]
2025-12-05 12:48:44.424329 | controller |
2025-12-05 12:48:44.424609 | controller | TASK [run_hook : Get file stat] ************************************************
2025-12-05 12:48:44.424826 | controller | Friday 05 December 2025  12:48:44 +0000 (0:00:00.045)       0:00:07.913 *******
2025-12-05 12:48:44.603721 | controller | ok: [instance]
2025-12-05 12:48:44.620493 | controller |
2025-12-05 12:48:44.620516 | controller | TASK [run_hook : Fail if playbook doesn't exist] *******************************
2025-12-05 12:48:44.620525 | controller | Friday 05 December 2025  12:48:44 +0000 (0:00:00.179)       0:00:08.093 *******
2025-12-05 12:48:44.620534 | controller | skipping: [instance]
2025-12-05 12:48:44.792999 | controller |
2025-12-05 12:48:44.793017 | controller | TASK [run_hook : Get parameters files] *****************************************
2025-12-05 12:48:44.793025 | controller | Friday 05 December 2025  12:48:44 +0000 (0:00:00.016)       0:00:08.109 *******
2025-12-05 12:48:44.793033 | controller | ok: [instance]
2025-12-05 12:48:44.793263 | controller |
2025-12-05 12:48:44.793277 | controller | TASK [run_hook : Add parameters artifacts as extra variables] ******************
2025-12-05 12:48:44.813298 | controller | Friday 05 December 2025  12:48:44 +0000 (0:00:00.172)       0:00:08.282 *******
2025-12-05 12:48:44.813318 | controller | ok: [instance]
2025-12-05 12:48:44.996064 | controller |
2025-12-05 12:48:44.996106 | controller | TASK [run_hook : Ensure log directory exists] **********************************
2025-12-05 12:48:44.996114 | controller | Friday 05 December 2025  12:48:44 +0000 (0:00:00.020)       0:00:08.302 *******
2025-12-05 12:48:44.996124 | controller | ok: [instance]
2025-12-05 12:48:44.996141 | controller |
2025-12-05 12:48:44.996149 | controller | TASK [run_hook : Ensure artifacts directory exists] ****************************
2025-12-05 12:48:44.996233 | controller | Friday 05 December 2025  12:48:44 +0000 (0:00:00.182)       0:00:08.485 *******
2025-12-05 12:48:45.172754 | controller | ok: [instance]
2025-12-05 12:48:47.002479 | controller |
2025-12-05 12:48:47.002510 | controller | TASK [run_hook : Run hook without retry - Run dummy-2] *************************
2025-12-05 12:48:47.002517 | controller | Friday 05 December 2025  12:48:45 +0000 (0:00:00.176)       0:00:08.662 *******
2025-12-05 12:48:47.002526 | controller | Follow script's output here: /home/zuul/ci-framework-data/logs/ci_script_003_run_hook_without_retry_run.log
2025-12-05 12:48:47.031519 | controller | changed: [instance]
2025-12-05 12:48:47.031536 | controller |
2025-12-05 12:48:47.031543 | controller | TASK [run_hook : Run hook with retry - Run dummy-2] ****************************
2025-12-05 12:48:47.031549 | controller | Friday 05 December 2025  12:48:47 +0000 (0:00:01.829)       0:00:10.491 *******
2025-12-05 12:48:47.031562 | controller | skipping: [instance]
2025-12-05 12:48:47.211767 | controller |
2025-12-05 12:48:47.211788 | controller | TASK [run_hook : Check if we have a file] **************************************
2025-12-05 12:48:47.211795 | controller | Friday 05 December 2025  12:48:47 +0000 (0:00:00.029)       0:00:10.521 *******
2025-12-05 12:48:47.211803 | controller | ok: [instance]
2025-12-05 12:48:47.234812 | controller |
2025-12-05 12:48:47.234829 | controller | TASK [run_hook : Load generated content in main playbook] **********************
2025-12-05 12:48:47.234836 | controller | Friday 05 December 2025  12:48:47 +0000 (0:00:00.180)       0:00:10.701 *******
2025-12-05 12:48:47.234844 | controller | ok: [instance]
2025-12-05 12:48:47.279495 | controller |
2025-12-05 12:48:47.279512 | controller | TASK [run_hook : Set playbook path for Run dummy-3] ****************************
2025-12-05 12:48:47.279519 | controller | Friday 05 December 2025  12:48:47 +0000 (0:00:00.023)       0:00:10.724 *******
2025-12-05 12:48:47.279527 | controller | ok: [instance]
2025-12-05 12:48:47.464464 | controller |
2025-12-05 12:48:47.464494 | controller | TASK [run_hook : Get file stat] ************************************************
2025-12-05 12:48:47.464502 | controller | Friday 05 December 2025  12:48:47 +0000 (0:00:00.044)       0:00:10.769 *******
2025-12-05 12:48:47.464511 | controller | ok: [instance]
2025-12-05 12:48:47.483047 | controller |
2025-12-05 12:48:47.483069 | controller | TASK [run_hook : Fail if playbook doesn't exist] *******************************
2025-12-05 12:48:47.483077 | controller | Friday 05 December 2025  12:48:47 +0000 (0:00:00.184)       0:00:10.953 *******
2025-12-05 12:48:47.483086 | controller | skipping: [instance]
2025-12-05 12:48:47.667344 | controller |
2025-12-05 12:48:47.667370 | controller | TASK [run_hook : Get parameters files] *****************************************
2025-12-05 12:48:47.667379 | controller | Friday 05 December 2025  12:48:47 +0000 (0:00:00.018)       0:00:10.972 *******
2025-12-05 12:48:47.667388 | controller | ok: [instance]
2025-12-05 12:48:47.686855 | controller |
2025-12-05 12:48:47.686877 | controller | TASK [run_hook : Add parameters artifacts as extra variables] ******************
2025-12-05 12:48:47.686884 | controller | Friday 05 December 2025  12:48:47 +0000 (0:00:00.184)       0:00:11.156 *******
2025-12-05 12:48:47.686893 | controller | ok: [instance]
2025-12-05 12:48:47.876501 | controller |
2025-12-05 12:48:47.876518 | controller | TASK [run_hook : Ensure log directory exists] **********************************
2025-12-05 12:48:47.876525 | controller | Friday 05 December 2025  12:48:47 +0000 (0:00:00.019)       0:00:11.176 *******
2025-12-05 12:48:47.876534 | controller | ok: [instance]
2025-12-05 12:48:48.044248 | controller |
2025-12-05 12:48:48.044272 | controller | TASK [run_hook : Ensure artifacts directory exists] ****************************
2025-12-05 12:48:48.044286 | controller | Friday 05 December 2025  12:48:47 +0000 (0:00:00.189)       0:00:11.366 *******
2025-12-05 12:48:48.044295 | controller | ok: [instance]
2025-12-05 12:48:49.857428 | controller |
2025-12-05 12:48:49.857458 | controller | TASK [run_hook : Run hook without retry - Run dummy-3] *************************
2025-12-05 12:48:49.857466 | controller | Friday 05 December 2025  12:48:48 +0000 (0:00:00.167)       0:00:11.533 *******
2025-12-05 12:48:49.857477 | controller | Follow script's output here: /home/zuul/ci-framework-data/logs/ci_script_004_run_hook_without_retry_run.log
2025-12-05 12:48:49.885940 | controller | changed: [instance]
2025-12-05 12:48:49.885957 | controller |
2025-12-05 12:48:49.885983 | controller | TASK [run_hook : Run hook with retry - Run dummy-3] ****************************
2025-12-05 12:48:49.885989 | controller | Friday 05 December 2025  12:48:49 +0000 (0:00:01.812)       0:00:13.346 *******
2025-12-05 12:48:49.885998 | controller | skipping: [instance]
2025-12-05 12:48:50.063944 | controller |
2025-12-05 12:48:50.063979 | controller | TASK [run_hook : Check if we have a file] **************************************
2025-12-05 12:48:50.063989 | controller | Friday 05 December 2025  12:48:49 +0000 (0:00:00.028)       0:00:13.375 *******
2025-12-05 12:48:50.063999 | controller | ok: [instance]
2025-12-05 12:48:50.089438 | controller |
2025-12-05 12:48:50.089455 | controller | TASK [run_hook : Load generated content in main playbook] **********************
2025-12-05 12:48:50.089464 | controller | Friday 05 December 2025  12:48:50 +0000 (0:00:00.177)       0:00:13.553 *******
2025-12-05 12:48:50.089475 | controller | ok: [instance]
2025-12-05 12:48:50.113099 | controller |
2025-12-05 12:48:50.113137 | controller | TASK [Ensure we have the ceph_uuid variable now] *******************************
2025-12-05 12:48:50.113147 | controller | Friday 05 December 2025  12:48:50 +0000 (0:00:00.025)       0:00:13.579 *******
2025-12-05 12:48:50.113159 | controller | ok: [instance] => changed=false
2025-12-05 12:48:50.145924 | controller |   msg: All assertions passed
2025-12-05 12:48:50.145952 | controller |
2025-12-05 12:48:50.146004 | controller | TASK [Only filtered hooks] *****************************************************
2025-12-05 12:48:50.146015 | controller | Friday 05 December 2025  12:48:50 +0000 (0:00:00.023)       0:00:13.602 *******
2025-12-05 12:48:50.146028 | controller |
2025-12-05 12:48:50.201419 | controller | TASK [run_hook : Assert parameters are valid] **********************************
2025-12-05 12:48:50.201440 | controller | Friday 05 December 2025  12:48:50 +0000 (0:00:00.032)       0:00:13.635 *******
2025-12-05 12:48:50.201450 | controller | ok: [instance]
2025-12-05 12:48:50.272025 | controller |
2025-12-05 12:48:50.272049 | controller | TASK [run_hook : Assert single hooks are all mappings] *************************
2025-12-05 12:48:50.272059 | controller | Friday 05 December 2025  12:48:50 +0000 (0:00:00.055)       0:00:13.691 *******
2025-12-05 12:48:50.272072 | controller | ok: [instance]
2025-12-05 12:48:50.272489 | controller |
2025-12-05 12:48:50.360134 | controller | TASK [run_hook : Loop on hooks for filtered_hooks] *****************************
2025-12-05 12:48:50.360179 | controller | Friday 05 December 2025  12:48:50 +0000 (0:00:00.070)       0:00:13.761 *******
2025-12-05 12:48:50.360217 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/run_hook/tasks/playbook.yml for instance => (item={'source': '/tmp/dummy-4.yml', 'type': 'playbook', 'extra_vars': {'foo': 'bar', 'file': '/tmp/dummy-env.yml'}, 'name': '01 my hook'})
2025-12-05 12:48:50.404021 | controller |
2025-12-05 12:48:50.404054 | controller | TASK [run_hook : Set playbook path for 01 my hook] *****************************
2025-12-05 12:48:50.404063 | controller | Friday 05 December 2025  12:48:50 +0000 (0:00:00.087)       0:00:13.849 *******
2025-12-05 12:48:50.404073 | controller | ok: [instance]
2025-12-05 12:48:50.588368 | controller |
2025-12-05 12:48:50.588393 | controller | TASK [run_hook : Get file stat] ************************************************
2025-12-05 12:48:50.588402 | controller | Friday 05 December 2025  12:48:50 +0000 (0:00:00.043)       0:00:13.893 *******
2025-12-05 12:48:50.588411 | controller | ok: [instance]
2025-12-05 12:48:50.606074 | controller |
2025-12-05 12:48:50.606112 | controller | TASK [run_hook : Fail if playbook doesn't exist] *******************************
2025-12-05 12:48:50.606122 | controller | Friday 05 December 2025  12:48:50 +0000 (0:00:00.184)       0:00:14.077 *******
2025-12-05 12:48:50.606134 | controller | skipping: [instance]
2025-12-05 12:48:50.606168 | controller |
2025-12-05 12:48:50.606178 | controller | TASK [run_hook : Get parameters files] *****************************************
2025-12-05 12:48:50.606290 | controller | Friday 05 December 2025  12:48:50 +0000 (0:00:00.018)       0:00:14.095 *******
2025-12-05 12:48:50.789925 | controller | ok: [instance]
2025-12-05 12:48:50.809401 | controller |
2025-12-05 12:48:50.809420 | controller | TASK [run_hook : Add parameters artifacts as extra variables] ******************
2025-12-05 12:48:50.809428 | controller | Friday 05 December 2025  12:48:50 +0000 (0:00:00.183)       0:00:14.279 *******
2025-12-05 12:48:50.809437 | controller | ok: [instance]
2025-12-05 12:48:50.998001 | controller |
2025-12-05 12:48:50.998027 | controller | TASK [run_hook : Ensure log directory exists] **********************************
2025-12-05 12:48:50.998035 | controller | Friday 05 December 2025  12:48:50 +0000 (0:00:00.019)       0:00:14.299 *******
2025-12-05 12:48:50.998046 | controller | ok: [instance]
2025-12-05 12:48:51.174041 | controller |
2025-12-05 12:48:51.174069 | controller | TASK [run_hook : Ensure artifacts directory exists] ****************************
2025-12-05 12:48:51.174076 | controller | Friday 05 December 2025  12:48:50 +0000 (0:00:00.188)       0:00:14.487 *******
2025-12-05 12:48:51.174086 | controller | ok: [instance]
2025-12-05 12:48:51.174184 | controller |
2025-12-05 12:48:51.174195 | controller | TASK [run_hook : Run hook without retry - 01 my hook] **************************
2025-12-05 12:48:51.174217 | controller | Friday 05 December 2025  12:48:51 +0000 (0:00:00.176)       0:00:14.663 *******
2025-12-05 12:48:53.018458 | controller | Follow script's output here: /home/zuul/ci-framework-data/logs/ci_script_005_run_hook_without_retry_01_my.log
2025-12-05 12:48:53.046031 | controller | changed: [instance]
2025-12-05 12:48:53.046064 | controller |
2025-12-05 12:48:53.046074 | controller | TASK [run_hook : Run hook with retry - 01 my hook] *****************************
2025-12-05 12:48:53.046081 | controller | Friday 05 December 2025  12:48:53 +0000 (0:00:01.844)       0:00:16.507 *******
2025-12-05 12:48:53.046092 | controller | skipping: [instance]
2025-12-05 12:48:53.046112 | controller |
2025-12-05 12:48:53.046176 | controller | TASK [run_hook : Check if we have a file] **************************************
2025-12-05 12:48:53.046279 | controller | Friday 05 December 2025  12:48:53 +0000 (0:00:00.028)       0:00:16.535 *******
2025-12-05 12:48:53.226622 | controller | ok: [instance]
2025-12-05 12:48:53.251771 | controller |
2025-12-05 12:48:53.251789 | controller | TASK [run_hook : Load generated content in main playbook] **********************
2025-12-05 12:48:53.251797 | controller | Friday 05 December 2025  12:48:53 +0000 (0:00:00.180)       0:00:16.716 *******
2025-12-05 12:48:53.251805 | controller | ok: [instance]
2025-12-05 12:48:53.275031 | controller |
2025-12-05 12:48:53.275054 | controller | TASK [Ensure we have the ceph_uuid variable now] *******************************
2025-12-05 12:48:53.275064 | controller | Friday 05 December 2025  12:48:53 +0000 (0:00:00.025)       0:00:16.741 *******
2025-12-05 12:48:53.275075 | controller | ok: [instance] => changed=false
2025-12-05 12:48:53.275103 | controller |   msg: All assertions passed
2025-12-05 12:48:53.275371 | controller |
2025-12-05 12:48:53.310935 | controller | TASK [Direct hooks] ************************************************************
2025-12-05 12:48:53.310955 | controller | Friday 05 December 2025  12:48:53 +0000 (0:00:00.023)       0:00:16.764 *******
2025-12-05 12:48:53.310992 | controller |
2025-12-05 12:48:53.363428 | controller | TASK [run_hook : Assert parameters are valid] **********************************
2025-12-05 12:48:53.363448 | controller | Friday 05 December 2025  12:48:53 +0000 (0:00:00.035)       0:00:16.800 *******
2025-12-05 12:48:53.363460 | controller | ok: [instance]
2025-12-05 12:48:53.452017 | controller |
2025-12-05 12:48:53.452036 | controller | TASK [run_hook : Assert single hooks are all mappings] *************************
2025-12-05 12:48:53.452044 | controller | Friday 05 December 2025  12:48:53 +0000 (0:00:00.052)       0:00:16.852 *******
2025-12-05 12:48:53.452054 | controller | ok: [instance]
2025-12-05 12:48:53.452079 | controller |
2025-12-05 12:48:53.452317 | controller | TASK [run_hook : Loop on hooks for no_hook] ************************************
2025-12-05 12:48:53.557992 | controller | Friday 05 December 2025  12:48:53 +0000 (0:00:00.089)       0:00:16.941 *******
2025-12-05 12:48:53.558034 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/run_hook/tasks/playbook.yml for instance => (item={'name': 'Dummy-5', 'source': '/tmp/dummy-5.yml', 'type': 'playbook', 'extra_vars': {'foo': 'bar', 'file': '/tmp/dummy-env.yml'}})
2025-12-05 12:48:53.603006 | controller |
2025-12-05 12:48:53.603026 | controller | TASK [run_hook : Set playbook path for Dummy-5] ********************************
2025-12-05 12:48:53.603033 | controller | Friday 05 December 2025  12:48:53 +0000 (0:00:00.105)       0:00:17.047 *******
2025-12-05 12:48:53.603042 | controller | ok: [instance]
2025-12-05 12:48:53.603213 | controller |
2025-12-05 12:48:53.603243 | controller | TASK [run_hook : Get file stat] ************************************************
2025-12-05 12:48:53.781079 | controller | Friday 05 December 2025  12:48:53 +0000 (0:00:00.045)       0:00:17.092 *******
2025-12-05 12:48:53.781117 | controller | ok: [instance]
2025-12-05 12:48:53.781421 | controller |
2025-12-05 12:48:53.798482 | controller | TASK [run_hook : Fail if playbook doesn't exist] *******************************
2025-12-05 12:48:53.798513 | controller | Friday 05 December 2025  12:48:53 +0000 (0:00:00.178)       0:00:17.270 *******
2025-12-05 12:48:53.798527 | controller | skipping: [instance]
2025-12-05 12:48:53.986793 | controller |
2025-12-05 12:48:53.986827 | controller | TASK [run_hook : Get parameters files] *****************************************
2025-12-05 12:48:53.986836 | controller | Friday 05 December 2025  12:48:53 +0000 (0:00:00.017)       0:00:17.287 *******
2025-12-05 12:48:53.986846 | controller | ok: [instance]
2025-12-05 12:48:54.006933 | controller |
2025-12-05 12:48:54.006973 | controller | TASK [run_hook : Add parameters artifacts as extra variables] ******************
2025-12-05 12:48:54.006985 | controller | Friday 05 December 2025  12:48:53 +0000 (0:00:00.188)       0:00:17.476 *******
2025-12-05 12:48:54.006996 | controller | ok: [instance]
2025-12-05 12:48:54.189570 | controller |
2025-12-05 12:48:54.189601 | controller | TASK [run_hook : Ensure log directory exists] **********************************
2025-12-05 12:48:54.189609 | controller | Friday 05 December 2025  12:48:54 +0000 (0:00:00.020)       0:00:17.496 *******
2025-12-05 12:48:54.189618 | controller | ok: [instance]
2025-12-05 12:48:54.361468 | controller |
2025-12-05 12:48:54.361499 | controller | TASK [run_hook : Ensure artifacts directory exists] ****************************
2025-12-05 12:48:54.361507 | controller | Friday 05 December 2025  12:48:54 +0000 (0:00:00.182)       0:00:17.678 *******
2025-12-05 12:48:54.361518 | controller | ok: [instance]
2025-12-05 12:48:56.172718 | controller |
2025-12-05 12:48:56.172749 | controller | TASK [run_hook : Run hook without retry - Dummy-5] *****************************
2025-12-05 12:48:56.172757 | controller | Friday 05 December 2025  12:48:54 +0000 (0:00:00.171)       0:00:17.850 *******
2025-12-05 12:48:56.172767 | controller | Follow script's output here: /home/zuul/ci-framework-data/logs/ci_script_006_run_hook_without_retry_dummy.log
2025-12-05 12:48:56.201902 | controller | changed: [instance]
2025-12-05 12:48:56.201929 | controller |
2025-12-05 12:48:56.201937 | controller | TASK [run_hook : Run hook with retry - Dummy-5] ********************************
2025-12-05 12:48:56.201943 | controller | Friday 05 December 2025  12:48:56 +0000 (0:00:01.811)       0:00:19.662 *******
2025-12-05 12:48:56.201953 | controller | skipping: [instance]
2025-12-05 12:48:56.374495 | controller |
2025-12-05 12:48:56.374525 | controller | TASK [run_hook : Check if we have a file] **************************************
2025-12-05 12:48:56.374533 | controller | Friday 05 December 2025  12:48:56 +0000 (0:00:00.029)       0:00:19.691 *******
2025-12-05 12:48:56.374542 | controller | ok: [instance]
2025-12-05 12:48:56.398852 | controller |
2025-12-05 12:48:56.398877 | controller | TASK [run_hook : Load generated content in main playbook] **********************
2025-12-05 12:48:56.398886 | controller | Friday 05 December 2025  12:48:56 +0000 (0:00:00.172)       0:00:19.863 *******
2025-12-05 12:48:56.398898 | controller | ok: [instance]
2025-12-05 12:48:56.420691 | controller |
2025-12-05 12:48:56.420713 | controller | TASK [Ensure we have the ceph_uuid variable now] *******************************
2025-12-05 12:48:56.420719 | controller | Friday 05 December 2025  12:48:56 +0000 (0:00:00.024)       0:00:19.888 *******
2025-12-05 12:48:56.420728 | controller | ok: [instance] => changed=false
2025-12-05 12:48:56.455887 | controller |   msg: All assertions passed
2025-12-05 12:48:56.455909 | controller |
2025-12-05 12:48:56.455918 | controller | TASK [Direct hooks as param] ***************************************************
2025-12-05 12:48:56.455932 | controller | Friday 05 December 2025  12:48:56 +0000 (0:00:00.021)       0:00:19.910 *******
2025-12-05 12:48:56.455948 | controller |
2025-12-05 12:48:56.508828 | controller | TASK [run_hook : Assert parameters are valid] **********************************
2025-12-05 12:48:56.508851 | controller | Friday 05 December 2025  12:48:56 +0000 (0:00:00.035)       0:00:19.945 *******
2025-12-05 12:48:56.508861 | controller | ok: [instance]
2025-12-05 12:48:56.578844 | controller |
2025-12-05 12:48:56.578869 | controller | TASK [run_hook : Assert single hooks are all mappings] *************************
2025-12-05 12:48:56.578877 | controller | Friday 05 December 2025  12:48:56 +0000 (0:00:00.052)       0:00:19.998 *******
2025-12-05 12:48:56.578887 | controller | ok: [instance]
2025-12-05 12:48:56.667437 | controller |
2025-12-05 12:48:56.667464 | controller | TASK [run_hook : Loop on hooks for no_hook] ************************************
2025-12-05 12:48:56.667473 | controller | Friday 05 December 2025  12:48:56 +0000 (0:00:00.069)       0:00:20.068 *******
2025-12-05 12:48:56.667490 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/run_hook/tasks/playbook.yml for instance => (item={'name': 'Hook as param', 'source': '/tmp/dummy-6.yml', 'type': 'playbook', 'extra_vars': {'foo': 'bar', 'file': '/tmp/dummy-env.yml'}})
2025-12-05 12:48:56.709727 | controller |
2025-12-05 12:48:56.709747 | controller | TASK [run_hook : Set playbook path for Hook as param] **************************
2025-12-05 12:48:56.709755 | controller | Friday 05 December 2025  12:48:56 +0000 (0:00:00.088)       0:00:20.156 *******
2025-12-05 12:48:56.709764 | controller | ok: [instance]
2025-12-05 12:48:56.888918 | controller |
2025-12-05 12:48:56.888948 | controller | TASK [run_hook : Get file stat] ************************************************
2025-12-05 12:48:56.888967 | controller | Friday 05 December 2025  12:48:56 +0000 (0:00:00.042)       0:00:20.199 *******
2025-12-05 12:48:56.888981 | controller | ok: [instance]
2025-12-05 12:48:56.904641 | controller |
2025-12-05 12:48:56.904667 | controller | TASK [run_hook : Fail if playbook doesn't exist] *******************************
2025-12-05 12:48:56.904675 | controller | Friday 05 December 2025  12:48:56 +0000 (0:00:00.178)       0:00:20.378 *******
2025-12-05 12:48:56.904685 | controller | skipping: [instance]
2025-12-05 12:48:57.084491 | controller |
2025-12-05 12:48:57.084523 | controller | TASK [run_hook : Get parameters files] *****************************************
2025-12-05 12:48:57.084531 | controller | Friday 05 December 2025  12:48:56 +0000 (0:00:00.015)       0:00:20.394 *******
2025-12-05 12:48:57.084541 | controller | ok: [instance]
2025-12-05 12:48:57.104072 | controller |
2025-12-05 12:48:57.104109 | controller | TASK [run_hook : Add parameters artifacts as extra variables] ******************
2025-12-05 12:48:57.104127 | controller | Friday 05 December 2025  12:48:57 +0000 (0:00:00.179)       0:00:20.573 *******
2025-12-05 12:48:57.104140 | controller | ok: [instance]
2025-12-05 12:48:57.104160 | controller |
2025-12-05 12:48:57.104170 | controller | TASK [run_hook : Ensure log directory exists] **********************************
2025-12-05 12:48:57.104289 | controller | Friday 05 December 2025  12:48:57 +0000 (0:00:00.019)       0:00:20.593 *******
2025-12-05 12:48:57.286348 | controller | ok: [instance]
2025-12-05 12:48:57.461641 | controller |
2025-12-05 12:48:57.461674 | controller | TASK [run_hook : Ensure artifacts directory exists] ****************************
2025-12-05 12:48:57.461682 | controller | Friday 05 December 2025  12:48:57 +0000 (0:00:00.181)       0:00:20.775 *******
2025-12-05 12:48:57.461693 | controller | ok: [instance]
2025-12-05 12:48:59.284811 | controller |
2025-12-05 12:48:59.284847 | controller | TASK [run_hook : Run hook without retry - Hook as param] ***********************
2025-12-05 12:48:59.284857 | controller | Friday 05 December 2025  12:48:57 +0000 (0:00:00.175)       0:00:20.950 *******
2025-12-05 12:48:59.284870 | controller | Follow script's output here: /home/zuul/ci-framework-data/logs/ci_script_007_run_hook_without_retry_hook.log
2025-12-05 12:48:59.313583 | controller | changed: [instance]
2025-12-05 12:48:59.313602 | controller |
2025-12-05 12:48:59.313609 | controller | TASK [run_hook : Run hook with retry - Hook as param] **************************
2025-12-05 12:48:59.313615 | controller | Friday 05 December 2025  12:48:59 +0000 (0:00:01.822)       0:00:22.773 *******
2025-12-05 12:48:59.313624 | controller | skipping: [instance]
2025-12-05 12:48:59.490631 | controller |
2025-12-05 12:48:59.490656 | controller | TASK [run_hook : Check if we have a file] **************************************
2025-12-05 12:48:59.490669 | controller | Friday 05 December 2025  12:48:59 +0000 (0:00:00.029)       0:00:22.803 *******
2025-12-05 12:48:59.490683 | controller | ok: [instance]
2025-12-05 12:48:59.516987 | controller |
2025-12-05 12:48:59.517008 | controller | TASK [run_hook : Load generated content in main playbook] **********************
2025-12-05 12:48:59.517015 | controller | Friday 05 December 2025  12:48:59 +0000 (0:00:00.176)       0:00:22.980 *******
2025-12-05 12:48:59.517024 | controller | ok: [instance]
2025-12-05 12:48:59.541634 | controller |
2025-12-05 12:48:59.541652 | controller | TASK [Ensure we have the ceph_uuid variable now] *******************************
2025-12-05 12:48:59.541659 | controller | Friday 05 December 2025  12:48:59 +0000 (0:00:00.026)       0:00:23.006 *******
2025-12-05 12:48:59.541672 | controller | ok: [instance] => changed=false
2025-12-05 12:48:59.575030 | controller |   msg: All assertions passed
2025-12-05 12:48:59.575062 | controller |
2025-12-05 12:48:59.575071 | controller | TASK [Ensure we have the test_list variable now] *******************************
2025-12-05 12:48:59.575078 | controller | Friday 05 December 2025  12:48:59 +0000 (0:00:00.024)       0:00:23.031 *******
2025-12-05 12:48:59.575087 | controller | ok: [instance] => changed=false
2025-12-05 12:48:59.575111 | controller |   msg: All assertions passed
2025-12-05 12:48:59.575117 | controller |
2025-12-05 12:48:59.575125 | controller | TASK [Run hook with retry] *****************************************************
2025-12-05 12:48:59.575217 | controller | Friday 05 December 2025  12:48:59 +0000 (0:00:00.033)       0:00:23.064 *******
2025-12-05 12:48:59.600433 | controller |
2025-12-05 12:48:59.655030 | controller | TASK [run_hook : Assert parameters are valid] **********************************
2025-12-05 12:48:59.655057 | controller | Friday 05 December 2025  12:48:59 +0000 (0:00:00.025)       0:00:23.090 *******
2025-12-05 12:48:59.655068 | controller | ok: [instance]
2025-12-05 12:48:59.655094 | controller |
2025-12-05 12:48:59.655389 | controller | TASK [run_hook : Assert single hooks are all mappings] *************************
2025-12-05 12:48:59.728912 | controller | Friday 05 December 2025  12:48:59 +0000 (0:00:00.054)       0:00:23.144 *******
2025-12-05 12:48:59.728944 | controller | ok: [instance]
2025-12-05 12:48:59.818043 | controller |
2025-12-05 12:48:59.818077 | controller | TASK [run_hook : Loop on hooks for retry_hook] *********************************
2025-12-05 12:48:59.818087 | controller | Friday 05 December 2025  12:48:59 +0000 (0:00:00.073)       0:00:23.218 *******
2025-12-05 12:48:59.818099 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/run_hook/tasks/playbook.yml for instance => (item={'name': 'Run hook with retry', 'source': '/tmp/dummy-retry.yml', 'type': 'playbook', 'retry_hook': True})
2025-12-05 12:48:59.861030 | controller |
2025-12-05 12:48:59.861065 | controller | TASK [run_hook : Set playbook path for Run hook with retry] ********************
2025-12-05 12:48:59.861073 | controller | Friday 05 December 2025  12:48:59 +0000 (0:00:00.088)       0:00:23.307 *******
2025-12-05 12:48:59.861082 | controller | ok: [instance]
2025-12-05 12:48:59.861105 | controller |
2025-12-05 12:48:59.861256 | controller | TASK [run_hook : Get file stat] ************************************************
2025-12-05 12:49:00.038036 | controller | Friday 05 December 2025  12:48:59 +0000 (0:00:00.043)       0:00:23.350 *******
2025-12-05 12:49:00.038067 | controller | ok: [instance]
2025-12-05 12:49:00.055188 | controller |
2025-12-05 12:49:00.055222 | controller | TASK [run_hook : Fail if playbook doesn't exist] *******************************
2025-12-05 12:49:00.055230 | controller | Friday 05 December 2025  12:49:00 +0000 (0:00:00.176)       0:00:23.527 *******
2025-12-05 12:49:00.055241 | controller | skipping: [instance]
2025-12-05 12:49:00.232847 | controller |
2025-12-05 12:49:00.232880 | controller | TASK [run_hook : Get parameters files] *****************************************
2025-12-05 12:49:00.232888 | controller | Friday 05 December 2025  12:49:00 +0000 (0:00:00.017)       0:00:23.544 *******
2025-12-05 12:49:00.232898 | controller | ok: [instance]
2025-12-05 12:49:00.252926 | controller |
2025-12-05 12:49:00.252944 | controller | TASK [run_hook : Add parameters artifacts as extra variables] ******************
2025-12-05 12:49:00.252951 | controller | Friday 05 December 2025  12:49:00 +0000 (0:00:00.177)       0:00:23.722 *******
2025-12-05 12:49:00.252983 | controller | ok: [instance]
2025-12-05 12:49:00.434038 | controller |
2025-12-05 12:49:00.434069 | controller | TASK [run_hook : Ensure log directory exists] **********************************
2025-12-05 12:49:00.434085 | controller | Friday 05 December 2025  12:49:00 +0000 (0:00:00.020)       0:00:23.742 *******
2025-12-05 12:49:00.434095 | controller | ok: [instance]
2025-12-05 12:49:00.434120 | controller |
2025-12-05 12:49:00.434128 | controller | TASK [run_hook : Ensure artifacts directory exists] ****************************
2025-12-05 12:49:00.434279 | controller | Friday 05 December 2025  12:49:00 +0000 (0:00:00.180)       0:00:23.923 *******
2025-12-05 12:49:00.610525 | controller | ok: [instance]
2025-12-05 12:49:02.419938 | controller |
2025-12-05 12:49:02.419984 | controller | TASK [run_hook : Run hook without retry - Run hook with retry] *****************
2025-12-05 12:49:02.420008 | controller | Friday 05 December 2025  12:49:00 +0000 (0:00:00.176)       0:00:24.099 *******
2025-12-05 12:49:02.420020 | controller | Follow script's output here: /home/zuul/ci-framework-data/logs/ci_script_008_run_hook_without_retry_run.log
2025-12-05 12:49:02.448999 | controller | changed: [instance]
2025-12-05 12:49:02.449016 | controller |
2025-12-05 12:49:02.449023 | controller | TASK [run_hook : Run hook with retry - Run hook with retry] ********************
2025-12-05 12:49:02.449030 | controller | Friday 05 December 2025  12:49:02 +0000 (0:00:01.809)       0:00:25.909 *******
2025-12-05 12:49:02.449039 | controller | skipping: [instance]
2025-12-05 12:49:02.616796 | controller |
2025-12-05 12:49:02.616827 | controller | TASK [run_hook : Check if we have a file] **************************************
2025-12-05 12:49:02.616835 | controller | Friday 05 December 2025  12:49:02 +0000 (0:00:00.029)       0:00:25.938 *******
2025-12-05 12:49:02.616846 | controller | ok: [instance]
2025-12-05 12:49:02.638071 | controller |
2025-12-05 12:49:02.638108 | controller | TASK [run_hook : Load generated content in main playbook] **********************
2025-12-05 12:49:02.638124 | controller | Friday 05 December 2025  12:49:02 +0000 (0:00:00.167)       0:00:26.105 *******
2025-12-05 12:49:02.638137 | controller | skipping: [instance]
2025-12-05 12:49:02.638146 | controller |
2025-12-05 12:49:02.638152 | controller | TASK [Check if fake file exists for retry playbook] ****************************
2025-12-05 12:49:02.638162 | controller | Friday 05 December 2025  12:49:02 +0000 (0:00:00.021)       0:00:26.127 *******
2025-12-05 12:49:02.812993 | controller | ok: [instance]
2025-12-05 12:49:02.832382 | controller |
2025-12-05 12:49:02.832402 | controller | TASK [Ensure file exists and was created on retry] *****************************
2025-12-05 12:49:02.832411 | controller | Friday 05 December 2025  12:49:02 +0000 (0:00:00.174)       0:00:26.302 *******
2025-12-05 12:49:02.832421 | controller | ok: [instance] => changed=false
2025-12-05 12:49:03.017013 | controller |   msg: All assertions passed
2025-12-05 12:49:03.017036 | controller |
2025-12-05 12:49:03.017044 | controller | TASK [Remove generated file] ***************************************************
2025-12-05 12:49:03.017050 | controller | Friday 05 December 2025  12:49:02 +0000 (0:00:00.019)       0:00:26.321 *******
2025-12-05 12:49:03.017059 | controller | changed: [instance]
2025-12-05 12:49:03.018946 | controller |
2025-12-05 12:49:03.018976 | controller | PLAY RECAP *********************************************************************
2025-12-05 12:49:03.018986 | controller | instance                   : ok=112  changed=10   unreachable=0    failed=0    skipped=22   rescued=0    ignored=0
2025-12-05 12:49:03.018992 | controller |
2025-12-05 12:49:03.018998 | controller | Friday 05 December 2025  12:49:03 +0000 (0:00:00.184)       0:00:26.506 *******
2025-12-05 12:49:03.019007 | controller | ===============================================================================
2025-12-05 12:49:03.072159 | controller | run_hook : Run hook without retry - 01 my hook -------------------------- 1.84s
2025-12-05 12:49:03.072193 | controller | run_hook : Run hook without retry - Run dummy-2 ------------------------- 1.83s
2025-12-05 12:49:03.072201 | controller | run_hook : Run hook without retry - Hook as param ----------------------- 1.82s
2025-12-05 12:49:03.072207 | controller | run_hook : Run hook without retry - Run dummy-3 ------------------------- 1.81s
2025-12-05 12:49:03.072212 | controller | run_hook : Run hook without retry - Dummy-5 ----------------------------- 1.81s
2025-12-05 12:49:03.072218 | controller | run_hook : Run hook without retry - Run hook with retry ----------------- 1.81s
2025-12-05 12:49:03.072223 | controller | run_hook : Run hook without retry - 03 single hook ---------------------- 1.80s
2025-12-05 12:49:03.072228 | controller | Gathering Facts --------------------------------------------------------- 0.76s
2025-12-05 12:49:03.072238 | controller | run_hook : Run hook without retry - 01 Default noop hook ---------------- 0.57s
2025-12-05 12:49:03.072244 | controller | run_hook : Run hook without retry - 02 Re-run noop ---------------------- 0.56s
2025-12-05 12:49:03.072249 | controller | run_hook : Get file stat ------------------------------------------------ 0.28s
2025-12-05 12:49:03.072255 | controller | run_hook : Get parameters files ----------------------------------------- 0.28s
2025-12-05 12:49:03.072260 | controller | run_hook : Ensure log directory exists ---------------------------------- 0.27s
2025-12-05 12:49:03.072266 | controller | run_hook : Get parameters files ----------------------------------------- 0.20s
2025-12-05 12:49:03.072271 | controller | run_hook : Get file stat ------------------------------------------------ 0.20s
2025-12-05 12:49:03.072288 | controller | run_hook : Ensure log directory exists ---------------------------------- 0.19s
2025-12-05 12:49:03.072294 | controller | run_hook : Ensure log directory exists ---------------------------------- 0.19s
2025-12-05 12:49:03.072299 | controller | run_hook : Get parameters files ----------------------------------------- 0.19s
2025-12-05 12:49:03.072305 | controller | run_hook : Ensure artifacts directory exists ---------------------------- 0.19s
2025-12-05 12:49:03.072310 | controller | run_hook : Get file stat ------------------------------------------------ 0.18s
2025-12-05 12:49:03.072320 | controller | INFO     Running default > cleanup
2025-12-05 12:49:03.072768 | controller | WARNING  Skipping, cleanup playbook not configured.
2025-12-05 12:49:03.073398 | controller | INFO     Writing /tmp/report.html report.
2025-12-05 12:49:03.521899 | controller | changed
2025-12-05 12:49:03.542892 | 
2025-12-05 12:49:03.542981 | PLAY RECAP
2025-12-05 12:49:03.543049 | controller | ok: 4 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-12-05 12:49:03.543077 | 
2025-12-05 12:49:03.602933 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-12-05 12:49:03.603730 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-12-05 12:49:04.097438 | 
2025-12-05 12:49:04.097528 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-12-05 12:49:04.115828 | 
2025-12-05 12:49:04.115895 | TASK [Filter out host if needed]
2025-12-05 12:49:04.123568 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-12-05 12:49:04.127596 | 
2025-12-05 12:49:04.127664 | TASK [Ensure file is present]
2025-12-05 12:49:04.428723 | controller | ok
2025-12-05 12:49:04.434867 | 
2025-12-05 12:49:04.434955 | TASK [Manage molecule report file]
2025-12-05 12:49:04.959217 | controller | changed
2025-12-05 12:49:04.964237 | 
2025-12-05 12:49:04.964296 | TASK [Check if we get ci-framework-data basedir]
2025-12-05 12:49:05.144541 | controller | ok
2025-12-05 12:49:05.149277 | 
2025-12-05 12:49:05.149335 | TASK [Create ci-framework-data log directory for zuul]
2025-12-05 12:49:05.432809 | controller | changed
2025-12-05 12:49:05.438970 | 
2025-12-05 12:49:05.439033 | TASK [Copy ci-framework interesting files]
2025-12-05 12:49:05.624212 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2025-12-05 12:49:05.962700 | controller | changed
2025-12-05 12:49:05.967374 | 
2025-12-05 12:49:05.967432 | TASK [Get SELinux listing]
2025-12-05 12:49:06.490554 | controller | changed
2025-12-05 12:49:06.495759 | 
2025-12-05 12:49:06.495817 | TASK [Generate log index]
2025-12-05 12:49:07.093368 | controller | changed
2025-12-05 12:49:07.098090 | 
2025-12-05 12:49:07.098154 | TASK [Get some env related data]
2025-12-05 12:49:07.641527 | controller | /home/zuul/.local/bin/ansible
2025-12-05 12:49:08.633882 | controller | changed
2025-12-05 12:49:08.638960 | 
2025-12-05 12:49:08.639024 | TASK [Generate list of logs to collect in home directory]
2025-12-05 12:49:08.919723 | controller | ok: All paths examined
2025-12-05 12:49:08.924638 | 
2025-12-05 12:49:08.924711 | LOOP [Copy logs from home directory]
2025-12-05 12:49:09.265386 | controller | changed:
2025-12-05 12:49:09.265500 | controller | {
2025-12-05 12:49:09.265526 | controller |   "atime": 1764938893.6090288,
2025-12-05 12:49:09.265544 | controller |   "ctime": 1764938909.1242948,
2025-12-05 12:49:09.265560 | controller |   "dev": 64513,
2025-12-05 12:49:09.265575 | controller |   "gid": 1000,
2025-12-05 12:49:09.265589 | controller |   "gr_name": "zuul",
2025-12-05 12:49:09.265603 | controller |   "inode": 4333822,
2025-12-05 12:49:09.265617 | controller |   "isblk": false,
2025-12-05 12:49:09.265632 | controller |   "ischr": false,
2025-12-05 12:49:09.265658 | controller |   "isdir": false,
2025-12-05 12:49:09.265674 | controller |   "isfifo": false,
2025-12-05 12:49:09.265688 | controller |   "isgid": false,
2025-12-05 12:49:09.265702 | controller |   "islnk": false,
2025-12-05 12:49:09.265714 | controller |   "isreg": true,
2025-12-05 12:49:09.265727 | controller |   "issock": false,
2025-12-05 12:49:09.265739 | controller |   "isuid": false,
2025-12-05 12:49:09.265752 | controller |   "mode": "0644",
2025-12-05 12:49:09.265764 | controller |   "mtime": 1764938909.1242948,
2025-12-05 12:49:09.265776 | controller |   "nlink": 1,
2025-12-05 12:49:09.265790 | controller |   "path": "/home/zuul/ansible.log",
2025-12-05 12:49:09.265806 | controller |   "pw_name": "zuul",
2025-12-05 12:49:09.265820 | controller |   "rgrp": true,
2025-12-05 12:49:09.265835 | controller |   "roth": true,
2025-12-05 12:49:09.265850 | controller |   "rusr": true,
2025-12-05 12:49:09.265865 | controller |   "size": 6749,
2025-12-05 12:49:09.265879 | controller |   "uid": 1000,
2025-12-05 12:49:09.265893 | controller |   "wgrp": false,
2025-12-05 12:49:09.265924 | controller |   "woth": false,
2025-12-05 12:49:09.265950 | controller |   "wusr": true,
2025-12-05 12:49:09.265967 | controller |   "xgrp": false,
2025-12-05 12:49:09.265983 | controller |   "xoth": false,
2025-12-05 12:49:09.265998 | controller |   "xusr": false
2025-12-05 12:49:09.266013 | controller | }
2025-12-05 12:49:09.279189 | 
2025-12-05 12:49:09.279264 | TASK [Copy crio stats log file]
2025-12-05 12:49:09.292817 | controller | skipping: Conditional result was False
2025-12-05 12:49:09.297766 | 
2025-12-05 12:49:09.297831 | TASK [Get SELinux related data]
2025-12-05 12:49:09.498398 | controller | <no matches>
2025-12-05 12:49:09.823543 | controller | ERROR
2025-12-05 12:49:09.823763 | controller | {
2025-12-05 12:49:09.823819 | controller |   "delta": "0:00:00.012158",
2025-12-05 12:49:09.823857 | controller |   "end": "2025-12-05 12:49:09.499514",
2025-12-05 12:49:09.823888 | controller |   "msg": "non-zero return code",
2025-12-05 12:49:09.823989 | controller |   "rc": 1,
2025-12-05 12:49:09.824032 | controller |   "start": "2025-12-05 12:49:09.487356"
2025-12-05 12:49:09.824063 | controller | }
2025-12-05 12:49:09.824103 | controller | ERROR: Ignoring Errors
2025-12-05 12:49:09.828622 | 
2025-12-05 12:49:09.828693 | TASK [Create system configuration directory]
2025-12-05 12:49:10.019497 | controller | changed
2025-12-05 12:49:10.024172 | 
2025-12-05 12:49:10.024242 | TASK [Get some of the system configurations]
2025-12-05 12:49:10.550350 | controller | changed
2025-12-05 12:49:10.555470 | 
2025-12-05 12:49:10.555530 | TASK [Copy generated documentation if available]
2025-12-05 12:49:10.568570 | controller | skipping: Conditional result was False
2025-12-05 12:49:10.573460 | 
2025-12-05 12:49:10.573520 | TASK [Copy generated AsciiDoc documentation if available]
2025-12-05 12:49:10.586031 | controller | skipping: Conditional result was False
2025-12-05 12:49:10.591352 | 
2025-12-05 12:49:10.591413 | TASK [Compress logs bigger than 2MB]
2025-12-05 12:49:11.116431 | controller | changed
2025-12-05 12:49:11.121616 | 
2025-12-05 12:49:11.121687 | TASK [Copy files from workspace on node]
2025-12-05 12:49:11.139792 | controller | ok
2025-12-05 12:49:11.160593 | 
2025-12-05 12:49:11.160673 | TASK [fetch-output : Set log path for multiple nodes]
2025-12-05 12:49:11.174040 | controller | skipping: Conditional result was False
2025-12-05 12:49:11.178951 | 
2025-12-05 12:49:11.179014 | TASK [fetch-output : Set log path for single node]
2025-12-05 12:49:11.206569 | controller | ok
2025-12-05 12:49:11.212076 | 
2025-12-05 12:49:11.212133 | LOOP [fetch-output : Ensure local output dirs]
2025-12-05 12:49:11.389901 | controller -> localhost | ok: "/var/lib/zuul/builds/08f1df4608c641238d1d163a42954c98/work/logs"
2025-12-05 12:49:11.390125 | controller -> localhost | changed: All items complete
2025-12-05 12:49:11.390153 | 
2025-12-05 12:49:11.551404 | controller -> localhost | changed: "/var/lib/zuul/builds/08f1df4608c641238d1d163a42954c98/work/artifacts"
2025-12-05 12:49:11.715387 | controller -> localhost | changed: "/var/lib/zuul/builds/08f1df4608c641238d1d163a42954c98/work/docs"
2025-12-05 12:49:11.728200 | 
2025-12-05 12:49:11.728263 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-12-05 12:49:12.294056 | controller | changed:
2025-12-05 12:49:12.294259 | controller | .d..t...... ./
2025-12-05 12:49:12.294290 | controller | >f+++++++++ README.html
2025-12-05 12:49:12.294310 | controller | >f+++++++++ ansible-execution.log
2025-12-05 12:49:12.294330 | controller | >f+++++++++ ansible.log
2025-12-05 12:49:12.294348 | controller | >f+++++++++ dmesg.log
2025-12-05 12:49:12.294365 | controller | >f+++++++++ installed-pkgs.log
2025-12-05 12:49:12.294383 | controller | >f+++++++++ python.log
2025-12-05 12:49:12.294402 | controller | >f+++++++++ registries.conf
2025-12-05 12:49:12.294418 | controller | >f+++++++++ report.html
2025-12-05 12:49:12.294435 | controller | >f+++++++++ selinux-denials.log
2025-12-05 12:49:12.294450 | controller | >f+++++++++ selinux-listing.log
2025-12-05 12:49:12.294465 | controller | cd+++++++++ ci-framework-data/
2025-12-05 12:49:12.294479 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-12-05 12:49:12.294494 | controller | >f+++++++++ ci-framework-data/artifacts/ci_script_000_run_hook_without_retry_01.sh
2025-12-05 12:49:12.294508 | controller | >f+++++++++ ci-framework-data/artifacts/ci_script_001_run_hook_without_retry_02_re.sh
2025-12-05 12:49:12.294522 | controller | >f+++++++++ ci-framework-data/artifacts/ci_script_002_run_hook_without_retry_03.sh
2025-12-05 12:49:12.294536 | controller | >f+++++++++ ci-framework-data/artifacts/ci_script_003_run_hook_without_retry_run.sh
2025-12-05 12:49:12.294554 | controller | >f+++++++++ ci-framework-data/artifacts/ci_script_004_run_hook_without_retry_run.sh
2025-12-05 12:49:12.294569 | controller | >f+++++++++ ci-framework-data/artifacts/ci_script_005_run_hook_without_retry_01_my.sh
2025-12-05 12:49:12.294583 | controller | >f+++++++++ ci-framework-data/artifacts/ci_script_006_run_hook_without_retry_dummy.sh
2025-12-05 12:49:12.294598 | controller | >f+++++++++ ci-framework-data/artifacts/ci_script_007_run_hook_without_retry_hook.sh
2025-12-05 12:49:12.294611 | controller | >f+++++++++ ci-framework-data/artifacts/ci_script_008_run_hook_without_retry_run.sh
2025-12-05 12:49:12.294625 | controller | >f+++++++++ ci-framework-data/artifacts/filtered_hooks_01_my_hook.yml
2025-12-05 12:49:12.294649 | controller | >f+++++++++ ci-framework-data/artifacts/list_hooks_run_dummy-2.yml
2025-12-05 12:49:12.294666 | controller | >f+++++++++ ci-framework-data/artifacts/list_hooks_run_dummy-3.yml
2025-12-05 12:49:12.294680 | controller | >f+++++++++ ci-framework-data/artifacts/no_hook_dummy-5.yml
2025-12-05 12:49:12.294695 | controller | >f+++++++++ ci-framework-data/artifacts/no_hook_hook_as_param.yml
2025-12-05 12:49:12.294709 | controller | >f+++++++++ ci-framework-data/artifacts/run_molecule_03_single_hook.yml
2025-12-05 12:49:12.294723 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-12-05 12:49:12.294737 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-12-05 12:49:12.294751 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-12-05 12:49:12.294764 | controller | cd+++++++++ ci-framework-data/logs/
2025-12-05 12:49:12.294777 | controller | >f+++++++++ ci-framework-data/logs/ci_script_000_run_hook_without_retry_01.log
2025-12-05 12:49:12.294790 | controller | >f+++++++++ ci-framework-data/logs/ci_script_001_run_hook_without_retry_02_re.log
2025-12-05 12:49:12.294803 | controller | >f+++++++++ ci-framework-data/logs/ci_script_002_run_hook_without_retry_03.log
2025-12-05 12:49:12.294816 | controller | >f+++++++++ ci-framework-data/logs/ci_script_003_run_hook_without_retry_run.log
2025-12-05 12:49:12.294829 | controller | >f+++++++++ ci-framework-data/logs/ci_script_004_run_hook_without_retry_run.log
2025-12-05 12:49:12.294842 | controller | >f+++++++++ ci-framework-data/logs/ci_script_005_run_hook_without_retry_01_my.log
2025-12-05 12:49:12.294855 | controller | >f+++++++++ ci-framework-data/logs/ci_script_006_run_hook_without_retry_dummy.log
2025-12-05 12:49:12.294867 | controller | >f+++++++++ ci-framework-data/logs/ci_script_007_run_hook_without_retry_hook.log
2025-12-05 12:49:12.294880 | controller | >f+++++++++ ci-framework-data/logs/ci_script_008_run_hook_without_retry_run.log
2025-12-05 12:49:12.294893 | controller | >f+++++++++ ci-framework-data/logs/filtered_hooks_01_my_hook.log
2025-12-05 12:49:12.294930 | controller | >f+++++++++ ci-framework-data/logs/list_hooks_run_dummy-2.log
2025-12-05 12:49:12.294956 | controller | >f+++++++++ ci-framework-data/logs/list_hooks_run_dummy-3.log
2025-12-05 12:49:12.294973 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2025-12-05 12:49:12.294988 | controller | >f+++++++++ ci-framework-data/logs/no_hook_dummy-5.log
2025-12-05 12:49:12.295002 | controller | >f+++++++++ ci-framework-data/logs/no_hook_hook_as_param.log
2025-12-05 12:49:12.295015 | controller | >f+++++++++ ci-framework-data/logs/retry_hook_run_hook_with_retry.log
2025-12-05 12:49:12.295028 | controller | >f+++++++++ ci-framework-data/logs/run_molecule_01_default_noop_hook.log
2025-12-05 12:49:12.295042 | controller | >f+++++++++ ci-framework-data/logs/run_molecule_02_re-run_noop.log
2025-12-05 12:49:12.295055 | controller | >f+++++++++ ci-framework-data/logs/run_molecule_03_single_hook.log
2025-12-05 12:49:12.295068 | controller | cd+++++++++ registries.conf.d/
2025-12-05 12:49:12.295082 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-12-05 12:49:12.295098 | controller | cd+++++++++ system-config/
2025-12-05 12:49:12.295112 | controller | cd+++++++++ system-config/libvirt/
2025-12-05 12:49:12.295125 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-12-05 12:49:12.295138 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-12-05 12:49:12.645730 | controller | changed: .d..t...... ./
2025-12-05 12:49:13.011294 | controller | changed: .d..t...... ./
2025-12-05 12:49:13.023524 | 
2025-12-05 12:49:13.023587 | TASK [Return artifact to Zuul]
2025-12-05 12:49:13.052150 | controller | ok
2025-12-05 12:49:13.070969 | 
2025-12-05 12:49:13.071023 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-12-05 12:49:13.071111 | 
2025-12-05 12:49:13.071137 | PLAY RECAP
2025-12-05 12:49:13.071173 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2025-12-05 12:49:13.071193 | 
2025-12-05 12:49:13.139475 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-12-05 12:49:13.140190 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-12-05 12:49:13.614582 | 
2025-12-05 12:49:13.614719 | PLAY [all]
2025-12-05 12:49:13.632766 | 
2025-12-05 12:49:13.632903 | TASK [include_role : fetch-output]
2025-12-05 12:49:13.661563 | controller | ok
2025-12-05 12:49:13.677633 | 
2025-12-05 12:49:13.677724 | TASK [fetch-output : Set log path for multiple nodes]
2025-12-05 12:49:13.721458 | controller | skipping: Conditional result was False
2025-12-05 12:49:13.726810 | 
2025-12-05 12:49:13.726878 | TASK [fetch-output : Set log path for single node]
2025-12-05 12:49:13.754584 | controller | ok
2025-12-05 12:49:13.759351 | 
2025-12-05 12:49:13.759419 | LOOP [fetch-output : Ensure local output dirs]
2025-12-05 12:49:14.057940 | controller -> localhost | ok: "/var/lib/zuul/builds/08f1df4608c641238d1d163a42954c98/work/logs"
2025-12-05 12:49:14.232963 | controller -> localhost | ok: "/var/lib/zuul/builds/08f1df4608c641238d1d163a42954c98/work/artifacts"
2025-12-05 12:49:14.398899 | controller -> localhost | ok: "/var/lib/zuul/builds/08f1df4608c641238d1d163a42954c98/work/docs"
2025-12-05 12:49:14.408519 | 
2025-12-05 12:49:14.408599 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-12-05 12:49:14.903061 | controller | ok
2025-12-05 12:49:14.903214 | controller | ok: All items complete
2025-12-05 12:49:14.903241 | 
2025-12-05 12:49:15.264431 | controller | ok
2025-12-05 12:49:15.621800 | controller | ok
2025-12-05 12:49:15.641059 | 
2025-12-05 12:49:15.641164 | TASK [include_role : fetch-output-openshift]
2025-12-05 12:49:15.654662 | controller | skipping: Conditional result was False
2025-12-05 12:49:15.660234 | 
2025-12-05 12:49:15.660299 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-12-05 12:49:15.979889 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.006286
2025-12-05 12:49:16.163846 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.006108
2025-12-05 12:49:16.193928 | 
2025-12-05 12:49:16.194019 | PLAY [all]
2025-12-05 12:49:16.207797 | 
2025-12-05 12:49:16.207865 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-12-05 12:49:16.644234 | controller | changed
2025-12-05 12:49:16.668399 | 
2025-12-05 12:49:16.668476 | PLAY RECAP
2025-12-05 12:49:16.668530 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-12-05 12:49:16.668552 | 
2025-12-05 12:49:16.805184 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-12-05 12:49:16.805957 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-12-05 12:49:17.348223 | 
2025-12-05 12:49:17.348356 | PLAY [localhost]
2025-12-05 12:49:17.366621 | 
2025-12-05 12:49:17.366710 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-12-05 12:49:17.658151 | localhost | changed
2025-12-05 12:49:17.662653 | 
2025-12-05 12:49:17.662751 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-12-05 12:49:17.680367 | localhost | ok
2025-12-05 12:49:17.688050 | 
2025-12-05 12:49:17.688124 | TASK [add-fileserver : Create SSH private key tempfile]
2025-12-05 12:49:17.978601 | localhost | changed
2025-12-05 12:49:17.984345 | 
2025-12-05 12:49:17.984416 | TASK [add-fileserver : Create SSH private key from secret]
2025-12-05 12:49:18.497919 | localhost | changed
2025-12-05 12:49:18.502241 | 
2025-12-05 12:49:18.502301 | TASK [add-fileserver : Add fileserver ssh key]
2025-12-05 12:49:18.812833 | localhost | Identity added: /var/lib/zuul/builds/08f1df4608c641238d1d163a42954c98/work/tmp/ansible.bsj8rlkl (/var/lib/zuul/builds/08f1df4608c641238d1d163a42954c98/work/tmp/ansible.bsj8rlkl)
2025-12-05 12:49:18.812998 | localhost | ok: Runtime: 0:00:00.006253
2025-12-05 12:49:18.816867 | 
2025-12-05 12:49:18.816951 | TASK [add-fileserver : Remove SSH private key from disk]
2025-12-05 12:49:19.026559 | localhost | ok: Runtime: 0:00:00.003846
2025-12-05 12:49:19.031040 | 
2025-12-05 12:49:19.031109 | TASK [add-fileserver : Add fileserver to inventory]
2025-12-05 12:49:19.078409 | localhost | changed
2025-12-05 12:49:19.082861 | 
2025-12-05 12:49:19.082938 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-12-05 12:49:19.372544 | localhost | changed
2025-12-05 12:49:19.391259 | 
2025-12-05 12:49:19.391315 | PLAY [localhost]
2025-12-05 12:49:19.402198 | 
2025-12-05 12:49:19.402262 | TASK [Generate bulk log download script]
2025-12-05 12:49:19.420064 | localhost | ok
2025-12-05 12:49:19.430282 | 
2025-12-05 12:49:19.430341 | TASK [local-log-download : Check API endpoint is defined]
2025-12-05 12:49:19.457393 | localhost | ok: All assertions passed
2025-12-05 12:49:19.461418 | 
2025-12-05 12:49:19.461477 | TASK [local-log-download : Create download script]
2025-12-05 12:49:19.777971 | localhost -> localhost | changed
2025-12-05 12:49:19.786062 | 
2025-12-05 12:49:19.786129 | TASK [Register quick-download link]
2025-12-05 12:49:19.802802 | localhost | ok
2025-12-05 12:49:19.834126 | 
2025-12-05 12:49:19.834190 | PLAY [logserver.rdoproject.org]
2025-12-05 12:49:19.842660 | 
2025-12-05 12:49:19.842716 | TASK [Set zuul-log-path fact]
2025-12-05 12:49:19.857925 | logserver.rdoproject.org | ok
2025-12-05 12:49:19.865827 | 
2025-12-05 12:49:19.865886 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-12-05 12:49:19.881250 | logserver.rdoproject.org | ok
2025-12-05 12:49:19.885972 | 
2025-12-05 12:49:19.886028 | TASK [upload-logs : Create log directories]
2025-12-05 12:49:21.643747 | logserver.rdoproject.org | changed
2025-12-05 12:49:21.646874 | 
2025-12-05 12:49:21.646954 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-12-05 12:49:21.873610 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.005297
2025-12-05 12:49:21.878483 | 
2025-12-05 12:49:21.878578 | TASK [upload-logs : Upload logs to log server]
2025-12-05 12:49:23.318750 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-12-05 12:49:23.321541 | 
2025-12-05 12:49:23.321603 | LOOP [upload-logs : Compress console log and json output]
2025-12-05 12:49:23.358714 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-05 12:49:23.366701 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-05 12:49:23.379060 | 
2025-12-05 12:49:23.379180 | LOOP [upload-logs : Upload compressed console log and json output]
2025-12-05 12:49:23.414226 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-05 12:49:23.414498 | 
2025-12-05 12:49:23.417050 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-05 12:49:23.426314 | 
2025-12-05 12:49:23.426438 | LOOP [upload-logs : Upload console log and json output]