2026-01-21 14:59:25.973458 | Job console starting...
2026-01-21 14:59:26.288118 | Updating repositories
2026-01-21 14:59:27.616165 | Preparing job workspace
2026-01-21 14:59:47.442136 | Running Ansible setup...
2026-01-21 14:59:58.312665 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2026-01-21 15:00:01.466579 | 
2026-01-21 15:00:01.466720 | PLAY [localhost]
2026-01-21 15:00:01.549849 | 
2026-01-21 15:00:01.549974 | TASK [Gathering Facts]
2026-01-21 15:00:05.754313 | localhost | ok
2026-01-21 15:00:05.817868 | 
2026-01-21 15:00:05.818001 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-01-21 15:00:07.581359 | localhost -> localhost | changed
2026-01-21 15:00:07.587229 | 
2026-01-21 15:00:07.587347 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-01-21 15:00:11.548785 | localhost -> localhost | changed
2026-01-21 15:00:11.558687 | 
2026-01-21 15:00:11.558771 | TASK [Setup log path fact]
2026-01-21 15:00:11.670264 | localhost | ok
2026-01-21 15:00:11.756946 | 
2026-01-21 15:00:11.757100 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-01-21 15:00:11.869333 | localhost | ok
2026-01-21 15:00:11.934535 | 
2026-01-21 15:00:11.934672 | TASK [emit-job-header : Print job information]
2026-01-21 15:00:12.082104 | # Job Information
2026-01-21 15:00:12.082257 | Ansible Version: 2.15.12
2026-01-21 15:00:12.082289 | Job: cifmw-molecule-run_hook
2026-01-21 15:00:12.082340 | Pipeline: github-check
2026-01-21 15:00:12.082364 | Executor: ibm-bm4-ze.softwarefactory-project.io
2026-01-21 15:00:12.082385 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3612
2026-01-21 15:00:12.082406 | Log URL (when completed): https://logserver.rdoproject.org/827/rdoproject.org/827d5059bab7499a817511eca4b6173f/
2026-01-21 15:00:12.082426 | Event ID: 74607520-f6d9-11f0-9dda-0587936d9c0f
2026-01-21 15:00:12.086943 | 
2026-01-21 15:00:12.087015 | LOOP [emit-job-header : Print node information]
2026-01-21 15:00:12.641785 | localhost | ok:
2026-01-21 15:00:12.641936 | localhost | # Node Information
2026-01-21 15:00:12.641967 | localhost | Inventory Hostname: controller
2026-01-21 15:00:12.641992 | localhost | Hostname: np0005590772
2026-01-21 15:00:12.642013 | localhost | Username: zuul
2026-01-21 15:00:12.642053 | localhost | Distro: CentOS 9
2026-01-21 15:00:12.642075 | localhost | Provider: ibm-bm4-nodepool
2026-01-21 15:00:12.642094 | localhost | Region: regionOne
2026-01-21 15:00:12.642113 | localhost | Label: cloud-centos-9-stream-tripleo-ibm
2026-01-21 15:00:12.642132 | localhost | Product Name: OpenStack Compute
2026-01-21 15:00:12.642149 | localhost | Interface IP: 192.168.26.157
2026-01-21 15:00:12.753199 | 
2026-01-21 15:00:12.753285 | PLAY [all]
2026-01-21 15:00:12.782549 | 
2026-01-21 15:00:12.782622 | TASK [Gather network facts]
2026-01-21 15:00:13.284842 | controller | ok
2026-01-21 15:00:13.374174 | 
2026-01-21 15:00:13.374341 | TASK [include_role : start-zuul-console]
2026-01-21 15:00:13.430254 | controller | ok
2026-01-21 15:00:13.462918 | 
2026-01-21 15:00:13.463047 | TASK [start-zuul-console : Start zuul_console daemon.]
2026-01-21 15:00:14.449539 | controller | ok
2026-01-21 15:00:14.482425 | 
2026-01-21 15:00:14.482532 | TASK [include_role : add-build-sshkey]
2026-01-21 15:00:14.576849 | controller | ok
2026-01-21 15:00:14.623020 | 
2026-01-21 15:00:14.623145 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-01-21 15:00:15.914757 | controller -> localhost | ok
2026-01-21 15:00:15.921610 | 
2026-01-21 15:00:15.921700 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-01-21 15:00:16.201895 | controller | ok
2026-01-21 15:00:16.281358 | controller | included: /var/lib/zuul/builds/827d5059bab7499a817511eca4b6173f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-01-21 15:00:16.350315 | 
2026-01-21 15:00:16.350429 | TASK [add-build-sshkey : Create Temp SSH key]
2026-01-21 15:00:19.155403 | controller -> localhost | Generating public/private rsa key pair.
2026-01-21 15:00:19.155601 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/827d5059bab7499a817511eca4b6173f/work/827d5059bab7499a817511eca4b6173f_id_rsa.
2026-01-21 15:00:19.155636 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/827d5059bab7499a817511eca4b6173f/work/827d5059bab7499a817511eca4b6173f_id_rsa.pub.
2026-01-21 15:00:19.155660 | controller -> localhost | The key fingerprint is:
2026-01-21 15:00:19.155680 | controller -> localhost | SHA256:fbw7oyLK/yDaIyA+nZHcDiPsF8P+G39+Rz8PQ1XXv5M zuul-build-sshkey
2026-01-21 15:00:19.155699 | controller -> localhost | The key's randomart image is:
2026-01-21 15:00:19.155719 | controller -> localhost | +---[RSA 3072]----+
2026-01-21 15:00:19.155738 | controller -> localhost | |                +|
2026-01-21 15:00:19.155758 | controller -> localhost | |                +|
2026-01-21 15:00:19.155775 | controller -> localhost | |                o|
2026-01-21 15:00:19.155793 | controller -> localhost | |         . .   ..|
2026-01-21 15:00:19.155811 | controller -> localhost | |. o o   S . o . o|
2026-01-21 15:00:19.155827 | controller -> localhost | |oo X .     . + E |
2026-01-21 15:00:19.155843 | controller -> localhost | |+.+ X..     o + .|
2026-01-21 15:00:19.155862 | controller -> localhost | | +.O.o+... .oo = |
2026-01-21 15:00:19.155878 | controller -> localhost | |  +.=*o++oo.oo  +|
2026-01-21 15:00:19.155895 | controller -> localhost | +----[SHA256]-----+
2026-01-21 15:00:19.155941 | controller -> localhost | ok: Runtime: 0:00:00.785433
2026-01-21 15:00:19.161635 | 
2026-01-21 15:00:19.161709 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-01-21 15:00:19.276108 | controller | ok
2026-01-21 15:00:19.482197 | controller | included: /var/lib/zuul/builds/827d5059bab7499a817511eca4b6173f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-01-21 15:00:19.530492 | 
2026-01-21 15:00:19.530590 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-01-21 15:00:19.632581 | controller | skipping: Conditional result was False
2026-01-21 15:00:19.638448 | 
2026-01-21 15:00:19.638547 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-01-21 15:00:20.547119 | controller | changed
2026-01-21 15:00:20.559577 | 
2026-01-21 15:00:20.559773 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-01-21 15:00:20.852410 | controller | ok
2026-01-21 15:00:20.858249 | 
2026-01-21 15:00:20.858528 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-01-21 15:00:21.672618 | controller | changed
2026-01-21 15:00:21.677555 | 
2026-01-21 15:00:21.677639 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-01-21 15:00:22.454051 | controller | changed
2026-01-21 15:00:22.462549 | 
2026-01-21 15:00:22.462649 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-01-21 15:00:22.509906 | controller | skipping: Conditional result was False
2026-01-21 15:00:22.516011 | 
2026-01-21 15:00:22.516128 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-01-21 15:00:23.624569 | controller -> localhost | changed
2026-01-21 15:00:23.640793 | 
2026-01-21 15:00:23.641198 | TASK [add-build-sshkey : Add back temp key]
2026-01-21 15:00:24.583931 | controller -> localhost | Identity added: /var/lib/zuul/builds/827d5059bab7499a817511eca4b6173f/work/827d5059bab7499a817511eca4b6173f_id_rsa (zuul-build-sshkey)
2026-01-21 15:00:24.584183 | controller -> localhost | ok: Runtime: 0:00:00.039538
2026-01-21 15:00:24.589882 | 
2026-01-21 15:00:24.589954 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-01-21 15:00:25.033318 | controller | ok
2026-01-21 15:00:25.037913 | 
2026-01-21 15:00:25.037987 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-01-21 15:00:25.113578 | controller | skipping: Conditional result was False
2026-01-21 15:00:25.123136 | 
2026-01-21 15:00:25.123227 | TASK [include_role : validate-host]
2026-01-21 15:00:25.214740 | controller | ok
2026-01-21 15:00:25.285118 | 
2026-01-21 15:00:25.285236 | TASK [validate-host : Define zuul_info_dir fact]
2026-01-21 15:00:25.347088 | controller | ok
2026-01-21 15:00:25.351731 | 
2026-01-21 15:00:25.351798 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2026-01-21 15:00:25.953752 | controller -> localhost | ok
2026-01-21 15:00:25.963999 | 
2026-01-21 15:00:25.964093 | TASK [validate-host : Collect information about the host]
2026-01-21 15:00:26.600798 | controller | ok
2026-01-21 15:00:26.620777 | 
2026-01-21 15:00:26.620897 | TASK [validate-host : Sanitize hostname]
2026-01-21 15:00:26.730333 | controller | ok
2026-01-21 15:00:26.736076 | 
2026-01-21 15:00:26.736148 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2026-01-21 15:00:27.472893 | controller -> localhost | changed
2026-01-21 15:00:27.480913 | 
2026-01-21 15:00:27.481000 | TASK [validate-host : Collect information about zuul worker]
2026-01-21 15:00:28.152808 | controller | ok
2026-01-21 15:00:28.157491 | 
2026-01-21 15:00:28.157561 | TASK [validate-host : Write out all zuul information for each host]
2026-01-21 15:00:29.024348 | controller -> localhost | changed
2026-01-21 15:00:29.070951 | 
2026-01-21 15:00:29.071070 | TASK [include_role : prepare-workspace-openshift]
2026-01-21 15:00:29.149570 | controller | skipping: Conditional result was False
2026-01-21 15:00:29.155796 | 
2026-01-21 15:00:29.155937 | TASK [include_role : remove-zuul-sshkey]
2026-01-21 15:00:29.191119 | controller | skipping: Conditional result was False
2026-01-21 15:00:29.200062 | 
2026-01-21 15:00:29.200172 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-01-21 15:00:29.407712 | controller | ok: "logs"
2026-01-21 15:00:29.408377 | controller | ok: All items complete
2026-01-21 15:00:29.408409 | 
2026-01-21 15:00:29.581142 | controller | ok: "artifacts"
2026-01-21 15:00:29.761945 | controller | ok: "docs"
2026-01-21 15:00:29.770675 | 
2026-01-21 15:00:29.770786 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-01-21 15:00:30.044876 | controller | changed: "logs"
2026-01-21 15:00:30.226366 | controller | changed: "artifacts"
2026-01-21 15:00:30.427854 | controller | changed: "docs"
2026-01-21 15:00:30.465941 | 
2026-01-21 15:00:30.466055 | PLAY RECAP
2026-01-21 15:00:30.466105 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2026-01-21 15:00:30.466135 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-01-21 15:00:30.466154 | 
2026-01-21 15:00:30.578829 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2026-01-21 15:00:30.579575 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2026-01-21 15:00:31.781768 | 
2026-01-21 15:00:31.781898 | PLAY [all]
2026-01-21 15:00:31.809785 | 
2026-01-21 15:00:31.809903 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2026-01-21 15:00:31.974746 | controller | ok
2026-01-21 15:00:31.982032 | 
2026-01-21 15:00:31.982129 | TASK [mirror-info-fork : Create /etc/ci]
2026-01-21 15:00:32.434908 | controller | changed
2026-01-21 15:00:32.441541 | 
2026-01-21 15:00:32.441616 | TASK [mirror-info-fork : Install ci_mirror script]
2026-01-21 15:00:33.405000 | controller | changed
2026-01-21 15:00:33.422905 | 
2026-01-21 15:00:33.422991 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2026-01-21 15:00:33.799627 | controller | changed:
2026-01-21 15:00:33.799803 | controller | {
2026-01-21 15:00:33.799835 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2026-01-21 15:00:33.799867 | controller | }
2026-01-21 15:00:34.017224 | controller | changed:
2026-01-21 15:00:34.017324 | controller | {
2026-01-21 15:00:34.017359 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2026-01-21 15:00:34.017383 | controller | }
2026-01-21 15:00:34.222528 | controller | changed:
2026-01-21 15:00:34.222670 | controller | {
2026-01-21 15:00:34.222702 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com"
2026-01-21 15:00:34.222725 | controller | }
2026-01-21 15:00:34.425815 | controller | changed:
2026-01-21 15:00:34.425914 | controller | {
2026-01-21 15:00:34.425943 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2026-01-21 15:00:34.425964 | controller | }
2026-01-21 15:00:34.629761 | controller | changed:
2026-01-21 15:00:34.629943 | controller | {
2026-01-21 15:00:34.629980 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2026-01-21 15:00:34.630003 | controller | }
2026-01-21 15:00:34.836707 | controller | changed:
2026-01-21 15:00:34.836793 | controller | {
2026-01-21 15:00:34.836823 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2026-01-21 15:00:34.836845 | controller | }
2026-01-21 15:00:35.056558 | controller | changed:
2026-01-21 15:00:35.056666 | controller | {
2026-01-21 15:00:35.056694 | 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"
2026-01-21 15:00:35.056718 | controller | }
2026-01-21 15:00:35.264499 | controller | changed:
2026-01-21 15:00:35.264594 | controller | {
2026-01-21 15:00:35.264623 | 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"
2026-01-21 15:00:35.264645 | controller | }
2026-01-21 15:00:35.481036 | controller | changed:
2026-01-21 15:00:35.481140 | controller | {
2026-01-21 15:00:35.481168 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2026-01-21 15:00:35.481189 | controller | }
2026-01-21 15:00:35.687401 | controller | changed:
2026-01-21 15:00:35.687482 | controller | {
2026-01-21 15:00:35.687513 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2026-01-21 15:00:35.687606 | controller | }
2026-01-21 15:00:35.925222 | controller | changed:
2026-01-21 15:00:35.925353 | controller | {
2026-01-21 15:00:35.925387 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2026-01-21 15:00:35.925409 | controller | }
2026-01-21 15:00:36.121593 | controller | changed:
2026-01-21 15:00:36.121681 | controller | {
2026-01-21 15:00:36.121710 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2026-01-21 15:00:36.121733 | controller | }
2026-01-21 15:00:36.334544 | controller | changed:
2026-01-21 15:00:36.334694 | controller | {
2026-01-21 15:00:36.334731 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2026-01-21 15:00:36.334753 | controller | }
2026-01-21 15:00:36.540549 | controller | changed:
2026-01-21 15:00:36.540628 | controller | {
2026-01-21 15:00:36.540657 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2026-01-21 15:00:36.540678 | controller | }
2026-01-21 15:00:36.741975 | controller | changed:
2026-01-21 15:00:36.742076 | controller | {
2026-01-21 15:00:36.742106 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2026-01-21 15:00:36.742128 | controller | }
2026-01-21 15:00:36.954030 | controller | changed:
2026-01-21 15:00:36.954113 | controller | {
2026-01-21 15:00:36.954145 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2026-01-21 15:00:36.954168 | controller | }
2026-01-21 15:00:37.162096 | controller | changed:
2026-01-21 15:00:37.162186 | controller | {
2026-01-21 15:00:37.162218 | 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"
2026-01-21 15:00:37.162241 | controller | }
2026-01-21 15:00:37.368747 | controller | changed:
2026-01-21 15:00:37.368859 | controller | {
2026-01-21 15:00:37.368888 | 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"
2026-01-21 15:00:37.368919 | controller | }
2026-01-21 15:00:37.577213 | controller | changed:
2026-01-21 15:00:37.577313 | controller | {
2026-01-21 15:00:37.577345 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2026-01-21 15:00:37.577367 | controller | }
2026-01-21 15:00:37.793070 | controller | changed:
2026-01-21 15:00:37.793375 | controller | {
2026-01-21 15:00:37.793425 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2026-01-21 15:00:37.793452 | controller | }
2026-01-21 15:00:38.004489 | controller | changed:
2026-01-21 15:00:38.004572 | controller | {
2026-01-21 15:00:38.004602 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2026-01-21 15:00:38.004624 | controller | }
2026-01-21 15:00:38.209712 | controller | changed:
2026-01-21 15:00:38.209788 | controller | {
2026-01-21 15:00:38.209815 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2026-01-21 15:00:38.209836 | controller | }
2026-01-21 15:00:38.418092 | controller | changed:
2026-01-21 15:00:38.418178 | controller | {
2026-01-21 15:00:38.418206 | 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"
2026-01-21 15:00:38.418229 | controller | }
2026-01-21 15:00:38.628496 | controller | changed:
2026-01-21 15:00:38.628586 | controller | {
2026-01-21 15:00:38.628614 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2026-01-21 15:00:38.628636 | controller | }
2026-01-21 15:00:38.846083 | controller | changed:
2026-01-21 15:00:38.846173 | controller | {
2026-01-21 15:00:38.846200 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2026-01-21 15:00:38.846220 | controller | }
2026-01-21 15:00:39.065382 | controller | changed:
2026-01-21 15:00:39.065477 | controller | {
2026-01-21 15:00:39.065508 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2026-01-21 15:00:39.065530 | controller | }
2026-01-21 15:00:39.093953 | 
2026-01-21 15:00:39.094052 | TASK [Set timezone to UTC]
2026-01-21 15:00:39.554653 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2026-01-21 15:00:39.562580 | 
2026-01-21 15:00:39.562665 | TASK [Create nodepool directory]
2026-01-21 15:00:39.828684 | controller | changed
2026-01-21 15:00:39.836500 | 
2026-01-21 15:00:39.836571 | TASK [Create nodepool sub_nodes file]
2026-01-21 15:00:40.471038 | controller | changed
2026-01-21 15:00:40.476881 | 
2026-01-21 15:00:40.476950 | TASK [Create nodepool sub_nodes_private file]
2026-01-21 15:00:41.045313 | controller | changed
2026-01-21 15:00:41.052969 | 
2026-01-21 15:00:41.053075 | LOOP [Populate nodepool sub_nodes file]
2026-01-21 15:00:41.113262 | 
2026-01-21 15:00:41.113439 | LOOP [Populate nodepool sub_nodes_private file]
2026-01-21 15:00:41.145741 | 
2026-01-21 15:00:41.145896 | TASK [Create nodepool primary file]
2026-01-21 15:00:41.169623 | controller | skipping: Conditional result was False
2026-01-21 15:00:41.176758 | 
2026-01-21 15:00:41.176862 | TASK [Create nodepool node_private for this node]
2026-01-21 15:00:41.791579 | controller | changed
2026-01-21 15:00:41.798651 | 
2026-01-21 15:00:41.798722 | LOOP [Copy ssh keys to nodepool directory]
2026-01-21 15:00:42.251808 | controller | ok: Item: id_rsa Runtime: 0:00:00.004902
2026-01-21 15:00:42.251974 | 
2026-01-21 15:00:42.426795 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.004682
2026-01-21 15:00:42.437471 | 
2026-01-21 15:00:42.437558 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2026-01-21 15:00:43.104900 | controller | changed
2026-01-21 15:00:43.118897 | 
2026-01-21 15:00:43.119009 | TASK [Validate sudoers config after edits]
2026-01-21 15:00:43.363816 | controller | /etc/sudoers: parsed OK
2026-01-21 15:00:43.363946 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2026-01-21 15:00:43.363958 | controller | /etc/sudoers.d/zuul: parsed OK
2026-01-21 15:00:43.363965 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2026-01-21 15:00:43.659991 | controller | ok: Runtime: 0:00:00.006019
2026-01-21 15:00:43.666461 | 
2026-01-21 15:00:43.666526 | TASK [Show the environment passed in to job shell scripts]
2026-01-21 15:00:43.953544 | controller | SHELL=/bin/bash
2026-01-21 15:00:43.953599 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2026-01-21 15:00:43.953612 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2026-01-21 15:00:43.953628 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/12/3612/ad59be263468c9d65538fe1fed8944959129e83c
2026-01-21 15:00:43.953639 | controller | PWD=/home/zuul
2026-01-21 15:00:43.953649 | controller | ZUUL_PIPELINE=github-check
2026-01-21 15:00:43.953698 | controller | LOGNAME=zuul
2026-01-21 15:00:43.953708 | controller | XDG_SESSION_TYPE=tty
2026-01-21 15:00:43.953714 | controller | _=/usr/bin/env
2026-01-21 15:00:43.953720 | controller | MOTD_SHOWN=pam
2026-01-21 15:00:43.953725 | controller | HOME=/home/zuul
2026-01-21 15:00:43.953730 | controller | LANG=en_US.UTF-8
2026-01-21 15:00:43.953736 | controller | SSH_CONNECTION=192.168.26.12 43604 192.168.26.157 22
2026-01-21 15:00:43.953742 | 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
2026-01-21 15:00:43.953750 | controller | ZUUL_CHANGE_IDS=3612,ad59be263468c9d65538fe1fed8944959129e83c
2026-01-21 15:00:43.953756 | controller | WORKSPACE=/home/zuul/workspace
2026-01-21 15:00:43.953762 | controller | XDG_SESSION_CLASS=user
2026-01-21 15:00:43.953767 | controller | SELINUX_ROLE_REQUESTED=
2026-01-21 15:00:43.953772 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2026-01-21 15:00:43.953778 | controller | USER=zuul
2026-01-21 15:00:43.953784 | controller | ZUUL_VOTING=True
2026-01-21 15:00:43.953789 | controller | BUILD_TIMEOUT=1800000
2026-01-21 15:00:43.953794 | controller | SELINUX_USE_CURRENT_RANGE=
2026-01-21 15:00:43.953800 | controller | SHLVL=1
2026-01-21 15:00:43.953805 | controller | ZUUL_PATCHSET=ad59be263468c9d65538fe1fed8944959129e83c
2026-01-21 15:00:43.953811 | controller | XDG_SESSION_ID=1
2026-01-21 15:00:43.953816 | controller | ZUUL_BRANCH=main
2026-01-21 15:00:43.953822 | controller | XDG_RUNTIME_DIR=/run/user/1000
2026-01-21 15:00:43.953827 | controller | SSH_CLIENT=192.168.26.12 43604 22
2026-01-21 15:00:43.953833 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2026-01-21 15:00:43.953838 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2026-01-21 15:00:43.953843 | controller | which_declare=declare -f
2026-01-21 15:00:43.953849 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2026-01-21 15:00:43.953855 | controller | SELINUX_LEVEL_REQUESTED=
2026-01-21 15:00:43.953873 | controller | ZUUL_CHANGE=3612
2026-01-21 15:00:43.953879 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2026-01-21 15:00:43.953884 | controller | ZUUL_UUID=827d5059bab7499a817511eca4b6173f
2026-01-21 15:00:43.953891 | controller | BASH_FUNC_which%%=() {  ( alias;
2026-01-21 15:00:43.953897 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2026-01-21 15:00:43.953902 | controller | }
2026-01-21 15:00:44.248566 | controller | ok: Runtime: 0:00:00.005566
2026-01-21 15:00:44.254275 | 
2026-01-21 15:00:44.254372 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2026-01-21 15:00:44.308514 | controller | skipping: Conditional result was False
2026-01-21 15:00:44.315062 | 
2026-01-21 15:00:44.315152 | TASK [Symlink /home/zuul-worker/workspace]
2026-01-21 15:00:44.889867 | controller | skipping: Conditional result was False
2026-01-21 15:00:44.896552 | 
2026-01-21 15:00:44.896636 | TASK [Ensure legacy workspace directory]
2026-01-21 15:00:45.120868 | controller | changed
2026-01-21 15:00:45.170250 | 
2026-01-21 15:00:45.170389 | PLAY RECAP
2026-01-21 15:00:45.170442 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2026-01-21 15:00:45.170469 | 
2026-01-21 15:00:45.271750 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2026-01-21 15:00:45.272544 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2026-01-21 15:00:45.906148 | 
2026-01-21 15:00:45.906403 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2026-01-21 15:00:45.939168 | 
2026-01-21 15:00:45.944859 | TASK [Create zuul-output directory]
2026-01-21 15:00:46.313766 | controller | changed
2026-01-21 15:00:46.320781 | 
2026-01-21 15:00:46.320909 | TASK [Slurp Zuul inventory test]
2026-01-21 15:00:46.723797 | controller -> localhost | ok
2026-01-21 15:00:46.730438 | 
2026-01-21 15:00:46.730514 | TASK [Save zuul inventory]
2026-01-21 15:00:47.689345 | controller | changed
2026-01-21 15:00:47.694624 | 
2026-01-21 15:00:47.694719 | TASK [Save zuul vars without the change_message]
2026-01-21 15:00:48.320796 | controller | changed
2026-01-21 15:00:48.343915 | 
2026-01-21 15:00:48.344030 | PLAY RECAP
2026-01-21 15:00:48.344080 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-01-21 15:00:48.344104 | 
2026-01-21 15:00:48.421430 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2026-01-21 15:00:48.422188 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2026-01-21 15:00:49.068275 | 
2026-01-21 15:00:49.068400 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2026-01-21 15:00:49.094219 | 
2026-01-21 15:00:49.094315 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2026-01-21 15:00:49.133470 | controller | ok
2026-01-21 15:00:49.154080 | 
2026-01-21 15:00:49.154174 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2026-01-21 15:00:49.189200 | controller | skipping: Conditional result was False
2026-01-21 15:00:49.194698 | 
2026-01-21 15:00:49.194775 | TASK [mirror-info-fork : Create /etc/ci]
2026-01-21 15:00:49.516964 | controller | ok
2026-01-21 15:00:49.522714 | 
2026-01-21 15:00:49.522801 | TASK [mirror-info-fork : Install ci_mirror script]
2026-01-21 15:00:50.170691 | controller | ok
2026-01-21 15:00:50.181202 | 
2026-01-21 15:00:50.181281 | TASK [Prepare workspace]
2026-01-21 15:00:50.199873 | controller | ok
2026-01-21 15:00:50.217773 | 
2026-01-21 15:00:50.217852 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-01-21 15:00:50.511368 | controller | ok
2026-01-21 15:00:50.524643 | 
2026-01-21 15:00:50.525078 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-01-21 15:00:53.733362 | controller | Output suppressed because no_log was given
2026-01-21 15:00:53.753276 | 
2026-01-21 15:00:53.753392 | LOOP [Create zuul-output directory]
2026-01-21 15:00:53.949835 | controller | changed: "/home/zuul/ci-framework-data/logs"
2026-01-21 15:00:54.128971 | controller | ok: "/home/zuul/zuul-output/logs"
2026-01-21 15:00:54.147072 | 
2026-01-21 15:00:54.147165 | TASK [Install required packages]
2026-01-21 15:01:28.648276 | controller | changed
2026-01-21 15:01:28.653990 | 
2026-01-21 15:01:28.654069 | TASK [Install venv]
2026-01-21 15:02:31.433317 | controller | changed
2026-01-21 15:02:31.466334 | 
2026-01-21 15:02:31.466428 | PLAY RECAP
2026-01-21 15:02:31.466478 | controller | ok: 7 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-01-21 15:02:31.466502 | 
2026-01-21 15:02:31.553415 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2026-01-21 15:02:31.554241 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2026-01-21 15:02:32.338548 | 
2026-01-21 15:02:32.338646 | PLAY [Run ci/playbooks/molecule-test.yml]
2026-01-21 15:02:32.360633 | 
2026-01-21 15:02:32.360722 | TASK [Gather required facts]
2026-01-21 15:02:32.984728 | controller | ok
2026-01-21 15:02:32.990438 | 
2026-01-21 15:02:32.990538 | TASK [Load environment var if instructed to]
2026-01-21 15:02:33.021629 | controller | skipping: Conditional result was False
2026-01-21 15:02:33.027204 | 
2026-01-21 15:02:33.027279 | TASK [Ensure group_vars dir exists]
2026-01-21 15:02:33.424897 | controller | ok
2026-01-21 15:02:33.430034 | 
2026-01-21 15:02:33.430121 | TASK [Print related variables]
2026-01-21 15:02:33.480721 | controller | ok:
2026-01-21 15:02:33.480859 | controller | mol_config_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config_local.yml
2026-01-21 15:02:33.480910 | controller | roles_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/run_hook
2026-01-21 15:02:33.486548 | 
2026-01-21 15:02:33.486618 | TASK [Run molecule]
2026-01-21 15:02:34.496554 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2026-01-21 15:02:34.577964 | controller | INFO     Running default > prepare
2026-01-21 15:02:35.223328 | controller |
2026-01-21 15:02:35.223451 | controller | PLAY [Prepare] *****************************************************************
2026-01-21 15:02:35.223552 | controller |
2026-01-21 15:02:35.224037 | controller | TASK [Gathering Facts] *********************************************************
2026-01-21 15:02:35.224176 | controller | Wednesday 21 January 2026  15:02:35 +0000 (0:00:00.011)       0:00:00.011 *****
2026-01-21 15:02:36.073643 | controller | ok: [instance]
2026-01-21 15:02:36.073719 | controller |
2026-01-21 15:02:36.073829 | controller | TASK [Create dummy env file] ***************************************************
2026-01-21 15:02:36.073939 | controller | Wednesday 21 January 2026  15:02:36 +0000 (0:00:00.850)       0:00:00.861 *****
2026-01-21 15:02:36.565175 | controller | changed: [instance]
2026-01-21 15:02:36.565259 | controller |
2026-01-21 15:02:36.565378 | controller | TASK [Create dummy playbook] ***************************************************
2026-01-21 15:02:36.565480 | controller | Wednesday 21 January 2026  15:02:36 +0000 (0:00:00.491)       0:00:01.353 *****
2026-01-21 15:02:38.459477 | controller | changed: [instance] => (item=dummy-1.yml)
2026-01-21 15:02:38.459576 | controller | changed: [instance] => (item=dummy-2.yml)
2026-01-21 15:02:38.459677 | controller | changed: [instance] => (item=dummy-3.yml)
2026-01-21 15:02:38.459780 | controller | changed: [instance] => (item=dummy-4.yml)
2026-01-21 15:02:38.459874 | controller | changed: [instance] => (item=dummy-5.yml)
2026-01-21 15:02:38.459978 | controller | changed: [instance] => (item=dummy-6.yml)
2026-01-21 15:02:38.460065 | controller |
2026-01-21 15:02:38.460179 | controller | TASK [Remove dummy file for retry playbook test] *******************************
2026-01-21 15:02:38.460309 | controller | Wednesday 21 January 2026  15:02:38 +0000 (0:00:01.894)       0:00:03.247 *****
2026-01-21 15:02:38.730313 | controller | ok: [instance]
2026-01-21 15:02:38.730390 | controller |
2026-01-21 15:02:38.730505 | controller | TASK [Create dummy retry playbook] *********************************************
2026-01-21 15:02:38.730608 | controller | Wednesday 21 January 2026  15:02:38 +0000 (0:00:00.270)       0:00:03.518 *****
2026-01-21 15:02:39.070372 | controller | changed: [instance]
2026-01-21 15:02:39.070449 | controller |
2026-01-21 15:02:39.070551 | controller | PLAY RECAP *********************************************************************
2026-01-21 15:02:39.070664 | controller | instance                   : ok=5    changed=3    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-01-21 15:02:39.070762 | controller |
2026-01-21 15:02:39.070852 | controller | Wednesday 21 January 2026  15:02:39 +0000 (0:00:00.340)       0:00:03.859 *****
2026-01-21 15:02:39.070945 | controller | ===============================================================================
2026-01-21 15:02:39.071033 | controller | Create dummy playbook --------------------------------------------------- 1.89s
2026-01-21 15:02:39.071123 | controller | Gathering Facts --------------------------------------------------------- 0.85s
2026-01-21 15:02:39.071295 | controller | Create dummy env file --------------------------------------------------- 0.49s
2026-01-21 15:02:39.071376 | controller | Create dummy retry playbook --------------------------------------------- 0.34s
2026-01-21 15:02:39.071472 | controller | Remove dummy file for retry playbook test ------------------------------- 0.27s
2026-01-21 15:02:39.121307 | controller | INFO     Running default > converge
2026-01-21 15:02:39.538781 | controller |
2026-01-21 15:02:39.538906 | controller | PLAY [Converge] ****************************************************************
2026-01-21 15:02:39.539022 | controller |
2026-01-21 15:02:39.539124 | controller | TASK [Gathering Facts] *********************************************************
2026-01-21 15:02:39.539262 | controller | Wednesday 21 January 2026  15:02:39 +0000 (0:00:00.015)       0:00:00.015 *****
2026-01-21 15:02:40.326648 | controller | ok: [instance]
2026-01-21 15:02:40.326706 | controller |
2026-01-21 15:02:40.326818 | controller | TASK [No hook] *****************************************************************
2026-01-21 15:02:40.326916 | controller | Wednesday 21 January 2026  15:02:40 +0000 (0:00:00.788)       0:00:00.803 *****
2026-01-21 15:02:40.356057 | controller |
2026-01-21 15:02:40.356141 | controller | TASK [run_hook : Assert parameters are valid] **********************************
2026-01-21 15:02:40.356308 | controller | Wednesday 21 January 2026  15:02:40 +0000 (0:00:00.029)       0:00:00.833 *****
2026-01-21 15:02:40.415086 | controller | ok: [instance]
2026-01-21 15:02:40.415129 | controller |
2026-01-21 15:02:40.415447 | controller | TASK [run_hook : Assert single hooks are all mappings] *************************
2026-01-21 15:02:40.415548 | controller | Wednesday 21 January 2026  15:02:40 +0000 (0:00:00.059)       0:00:00.892 *****
2026-01-21 15:02:40.494772 | controller | ok: [instance]
2026-01-21 15:02:40.494872 | controller |
2026-01-21 15:02:40.495016 | controller | TASK [run_hook : Loop on hooks for no_hook] ************************************
2026-01-21 15:02:40.495096 | controller | Wednesday 21 January 2026  15:02:40 +0000 (0:00:00.079)       0:00:00.972 *****
2026-01-21 15:02:40.575696 | controller | skipping: [instance]
2026-01-21 15:02:40.576072 | controller |
2026-01-21 15:02:40.599957 | controller | TASK [Ensure we do not have ceph_uuid] *****************************************
2026-01-21 15:02:40.599990 | controller | Wednesday 21 January 2026  15:02:40 +0000 (0:00:00.080)       0:00:01.052 *****
2026-01-21 15:02:40.600005 | controller | ok: [instance] => changed=false
2026-01-21 15:02:40.631046 | controller |   msg: All assertions passed
2026-01-21 15:02:40.631074 | controller |
2026-01-21 15:02:40.631082 | controller | TASK [Combined hooks] **********************************************************
2026-01-21 15:02:40.631088 | controller | Wednesday 21 January 2026  15:02:40 +0000 (0:00:00.024)       0:00:01.077 *****
2026-01-21 15:02:40.631097 | controller |
2026-01-21 15:02:40.689143 | controller | TASK [run_hook : Assert parameters are valid] **********************************
2026-01-21 15:02:40.689188 | controller | Wednesday 21 January 2026  15:02:40 +0000 (0:00:00.031)       0:00:01.108 *****
2026-01-21 15:02:40.689200 | controller | ok: [instance]
2026-01-21 15:02:40.767057 | controller |
2026-01-21 15:02:40.767091 | controller | TASK [run_hook : Assert single hooks are all mappings] *************************
2026-01-21 15:02:40.767102 | controller | Wednesday 21 January 2026  15:02:40 +0000 (0:00:00.057)       0:00:01.166 *****
2026-01-21 15:02:40.767113 | controller | ok: [instance]
2026-01-21 15:02:40.893350 | controller |
2026-01-21 15:02:40.893395 | controller | TASK [run_hook : Loop on hooks for run_molecule] *******************************
2026-01-21 15:02:40.893406 | controller | Wednesday 21 January 2026  15:02:40 +0000 (0:00:00.077)       0:00:01.244 *****
2026-01-21 15:02:40.893418 | 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'})
2026-01-21 15:02:40.893463 | 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'})
2026-01-21 15:02:40.893927 | 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'})
2026-01-21 15:02:40.941884 | controller |
2026-01-21 15:02:40.941915 | controller | TASK [run_hook : Set playbook path for 01 Default noop hook] *******************
2026-01-21 15:02:40.941923 | controller | Wednesday 21 January 2026  15:02:40 +0000 (0:00:00.126)       0:00:01.370 *****
2026-01-21 15:02:40.941933 | controller | ok: [instance]
2026-01-21 15:02:41.235880 | controller |
2026-01-21 15:02:41.235909 | controller | TASK [run_hook : Get file stat] ************************************************
2026-01-21 15:02:41.235917 | controller | Wednesday 21 January 2026  15:02:40 +0000 (0:00:00.048)       0:00:01.419 *****
2026-01-21 15:02:41.235927 | controller | ok: [instance]
2026-01-21 15:02:41.253060 | controller |
2026-01-21 15:02:41.253087 | controller | TASK [run_hook : Fail if playbook doesn't exist] *******************************
2026-01-21 15:02:41.253099 | controller | Wednesday 21 January 2026  15:02:41 +0000 (0:00:00.293)       0:00:01.712 *****
2026-01-21 15:02:41.253111 | controller | skipping: [instance]
2026-01-21 15:02:41.534000 | controller |
2026-01-21 15:02:41.534041 | controller | TASK [run_hook : Get parameters files] *****************************************
2026-01-21 15:02:41.534050 | controller | Wednesday 21 January 2026  15:02:41 +0000 (0:00:00.017)       0:00:01.730 *****
2026-01-21 15:02:41.534067 | controller | ok: [instance]
2026-01-21 15:02:41.553948 | controller |
2026-01-21 15:02:41.553968 | controller | TASK [run_hook : Add parameters artifacts as extra variables] ******************
2026-01-21 15:02:41.553975 | controller | Wednesday 21 January 2026  15:02:41 +0000 (0:00:00.280)       0:00:02.011 *****
2026-01-21 15:02:41.553985 | controller | ok: [instance]
2026-01-21 15:02:41.853867 | controller |
2026-01-21 15:02:41.853896 | controller | TASK [run_hook : Ensure log directory exists] **********************************
2026-01-21 15:02:41.853904 | controller | Wednesday 21 January 2026  15:02:41 +0000 (0:00:00.020)       0:00:02.031 *****
2026-01-21 15:02:41.853914 | controller | ok: [instance]
2026-01-21 15:02:42.042143 | controller |
2026-01-21 15:02:42.042186 | controller | TASK [run_hook : Ensure artifacts directory exists] ****************************
2026-01-21 15:02:42.042197 | controller | Wednesday 21 January 2026  15:02:41 +0000 (0:00:00.299)       0:00:02.331 *****
2026-01-21 15:02:42.042207 | controller | ok: [instance]
2026-01-21 15:02:42.634590 | controller |
2026-01-21 15:02:42.634619 | controller | TASK [run_hook : Run hook without retry - 01 Default noop hook] ****************
2026-01-21 15:02:42.634627 | controller | Wednesday 21 January 2026  15:02:42 +0000 (0:00:00.188)       0:00:02.519 *****
2026-01-21 15:02:42.634638 | controller | Follow script's output here: /home/zuul/ci-framework-data/logs/ci_script_000_run_hook_without_retry_01.log
2026-01-21 15:02:42.664123 | controller | changed: [instance]
2026-01-21 15:02:42.664150 | controller |
2026-01-21 15:02:42.664158 | controller | TASK [run_hook : Run hook with retry - 01 Default noop hook] *******************
2026-01-21 15:02:42.664167 | controller | Wednesday 21 January 2026  15:02:42 +0000 (0:00:00.592)       0:00:03.111 *****
2026-01-21 15:02:42.664188 | controller | skipping: [instance]
2026-01-21 15:02:42.836619 | controller |
2026-01-21 15:02:42.836649 | controller | TASK [run_hook : Check if we have a file] **************************************
2026-01-21 15:02:42.836657 | controller | Wednesday 21 January 2026  15:02:42 +0000 (0:00:00.029)       0:00:03.141 *****
2026-01-21 15:02:42.836667 | controller | ok: [instance]
2026-01-21 15:02:42.855529 | controller |
2026-01-21 15:02:42.855552 | controller | TASK [run_hook : Load generated content in main playbook] **********************
2026-01-21 15:02:42.855560 | controller | Wednesday 21 January 2026  15:02:42 +0000 (0:00:00.172)       0:00:03.313 *****
2026-01-21 15:02:42.855570 | controller | skipping: [instance]
2026-01-21 15:02:42.902032 | controller |
2026-01-21 15:02:42.902059 | controller | TASK [run_hook : Set playbook path for 02 Re-run noop] *************************
2026-01-21 15:02:42.902066 | controller | Wednesday 21 January 2026  15:02:42 +0000 (0:00:00.019)       0:00:03.332 *****
2026-01-21 15:02:42.902076 | controller | ok: [instance]
2026-01-21 15:02:43.086085 | controller |
2026-01-21 15:02:43.086113 | controller | TASK [run_hook : Get file stat] ************************************************
2026-01-21 15:02:43.086121 | controller | Wednesday 21 January 2026  15:02:42 +0000 (0:00:00.046)       0:00:03.379 *****
2026-01-21 15:02:43.086132 | controller | ok: [instance]
2026-01-21 15:02:43.103030 | controller |
2026-01-21 15:02:43.103051 | controller | TASK [run_hook : Fail if playbook doesn't exist] *******************************
2026-01-21 15:02:43.103059 | controller | Wednesday 21 January 2026  15:02:43 +0000 (0:00:00.183)       0:00:03.563 *****
2026-01-21 15:02:43.103068 | controller | skipping: [instance]
2026-01-21 15:02:43.290960 | controller |
2026-01-21 15:02:43.290990 | controller | TASK [run_hook : Get parameters files] *****************************************
2026-01-21 15:02:43.290998 | controller | Wednesday 21 January 2026  15:02:43 +0000 (0:00:00.017)       0:00:03.580 *****
2026-01-21 15:02:43.291008 | controller | ok: [instance]
2026-01-21 15:02:43.310969 | controller |
2026-01-21 15:02:43.310993 | controller | TASK [run_hook : Add parameters artifacts as extra variables] ******************
2026-01-21 15:02:43.311001 | controller | Wednesday 21 January 2026  15:02:43 +0000 (0:00:00.187)       0:00:03.768 *****
2026-01-21 15:02:43.311011 | controller | ok: [instance]
2026-01-21 15:02:43.500927 | controller |
2026-01-21 15:02:43.500957 | controller | TASK [run_hook : Ensure log directory exists] **********************************
2026-01-21 15:02:43.500966 | controller | Wednesday 21 January 2026  15:02:43 +0000 (0:00:00.020)       0:00:03.788 *****
2026-01-21 15:02:43.500982 | controller | ok: [instance]
2026-01-21 15:02:43.677984 | controller |
2026-01-21 15:02:43.678019 | controller | TASK [run_hook : Ensure artifacts directory exists] ****************************
2026-01-21 15:02:43.678028 | controller | Wednesday 21 January 2026  15:02:43 +0000 (0:00:00.189)       0:00:03.977 *****
2026-01-21 15:02:43.678039 | controller | ok: [instance]
2026-01-21 15:02:44.251335 | controller |
2026-01-21 15:02:44.251368 | controller | TASK [run_hook : Run hook without retry - 02 Re-run noop] **********************
2026-01-21 15:02:44.251377 | controller | Wednesday 21 January 2026  15:02:43 +0000 (0:00:00.177)       0:00:04.155 *****
2026-01-21 15:02:44.251389 | controller | Follow script's output here: /home/zuul/ci-framework-data/logs/ci_script_001_run_hook_without_retry_02_re.log
2026-01-21 15:02:44.280052 | controller | changed: [instance]
2026-01-21 15:02:44.280082 | controller |
2026-01-21 15:02:44.280090 | controller | TASK [run_hook : Run hook with retry - 02 Re-run noop] *************************
2026-01-21 15:02:44.280096 | controller | Wednesday 21 January 2026  15:02:44 +0000 (0:00:00.573)       0:00:04.728 *****
2026-01-21 15:02:44.280106 | controller | skipping: [instance]
2026-01-21 15:02:44.459154 | controller |
2026-01-21 15:02:44.459183 | controller | TASK [run_hook : Check if we have a file] **************************************
2026-01-21 15:02:44.459209 | controller | Wednesday 21 January 2026  15:02:44 +0000 (0:00:00.028)       0:00:04.757 *****
2026-01-21 15:02:44.459220 | controller | ok: [instance]
2026-01-21 15:02:44.477186 | controller |
2026-01-21 15:02:44.477221 | controller | TASK [run_hook : Load generated content in main playbook] **********************
2026-01-21 15:02:44.477229 | controller | Wednesday 21 January 2026  15:02:44 +0000 (0:00:00.178)       0:00:04.936 *****
2026-01-21 15:02:44.477254 | controller | skipping: [instance]
2026-01-21 15:02:44.523647 | controller |
2026-01-21 15:02:44.523665 | controller | TASK [run_hook : Set playbook path for 03 single hook] *************************
2026-01-21 15:02:44.523672 | controller | Wednesday 21 January 2026  15:02:44 +0000 (0:00:00.018)       0:00:04.954 *****
2026-01-21 15:02:44.523681 | controller | ok: [instance]
2026-01-21 15:02:44.716153 | controller |
2026-01-21 15:02:44.716182 | controller | TASK [run_hook : Get file stat] ************************************************
2026-01-21 15:02:44.716189 | controller | Wednesday 21 January 2026  15:02:44 +0000 (0:00:00.046)       0:00:05.001 *****
2026-01-21 15:02:44.716591 | controller | ok: [instance]
2026-01-21 15:02:44.734261 | controller |
2026-01-21 15:02:44.734278 | controller | TASK [run_hook : Fail if playbook doesn't exist] *******************************
2026-01-21 15:02:44.734285 | controller | Wednesday 21 January 2026  15:02:44 +0000 (0:00:00.192)       0:00:05.193 *****
2026-01-21 15:02:44.734294 | controller | skipping: [instance]
2026-01-21 15:02:44.924237 | controller |
2026-01-21 15:02:44.924354 | controller | TASK [run_hook : Get parameters files] *****************************************
2026-01-21 15:02:44.924362 | controller | Wednesday 21 January 2026  15:02:44 +0000 (0:00:00.018)       0:00:05.211 *****
2026-01-21 15:02:44.924373 | controller | ok: [instance]
2026-01-21 15:02:44.944931 | controller |
2026-01-21 15:02:44.944953 | controller | TASK [run_hook : Add parameters artifacts as extra variables] ******************
2026-01-21 15:02:44.944961 | controller | Wednesday 21 January 2026  15:02:44 +0000 (0:00:00.189)       0:00:05.401 *****
2026-01-21 15:02:44.944971 | controller | ok: [instance]
2026-01-21 15:02:45.148104 | controller |
2026-01-21 15:02:45.148132 | controller | TASK [run_hook : Ensure log directory exists] **********************************
2026-01-21 15:02:45.148140 | controller | Wednesday 21 January 2026  15:02:44 +0000 (0:00:00.020)       0:00:05.422 *****
2026-01-21 15:02:45.148150 | controller | ok: [instance]
2026-01-21 15:02:45.335247 | controller |
2026-01-21 15:02:45.335280 | controller | TASK [run_hook : Ensure artifacts directory exists] ****************************
2026-01-21 15:02:45.335289 | controller | Wednesday 21 January 2026  15:02:45 +0000 (0:00:00.202)       0:00:05.625 *****
2026-01-21 15:02:45.335300 | controller | ok: [instance]
2026-01-21 15:02:47.256381 | controller |
2026-01-21 15:02:47.256411 | controller | TASK [run_hook : Run hook without retry - 03 single hook] **********************
2026-01-21 15:02:47.256419 | controller | Wednesday 21 January 2026  15:02:45 +0000 (0:00:00.187)       0:00:05.812 *****
2026-01-21 15:02:47.256429 | controller | Follow script's output here: /home/zuul/ci-framework-data/logs/ci_script_002_run_hook_without_retry_03.log
2026-01-21 15:02:47.287905 | controller | changed: [instance]
2026-01-21 15:02:47.287942 | controller |
2026-01-21 15:02:47.287950 | controller | TASK [run_hook : Run hook with retry - 03 single hook] *************************
2026-01-21 15:02:47.287956 | controller | Wednesday 21 January 2026  15:02:47 +0000 (0:00:01.920)       0:00:07.733 *****
2026-01-21 15:02:47.287966 | controller | skipping: [instance]
2026-01-21 15:02:47.474685 | controller |
2026-01-21 15:02:47.474714 | controller | TASK [run_hook : Check if we have a file] **************************************
2026-01-21 15:02:47.474728 | controller | Wednesday 21 January 2026  15:02:47 +0000 (0:00:00.031)       0:00:07.765 *****
2026-01-21 15:02:47.474739 | controller | ok: [instance]
2026-01-21 15:02:47.500347 | controller |
2026-01-21 15:02:47.500390 | controller | TASK [run_hook : Load generated content in main playbook] **********************
2026-01-21 15:02:47.500399 | controller | Wednesday 21 January 2026  15:02:47 +0000 (0:00:00.186)       0:00:07.951 *****
2026-01-21 15:02:47.500410 | controller | ok: [instance]
2026-01-21 15:02:47.500428 | controller |
2026-01-21 15:02:47.500622 | controller | TASK [Ensure we have the ceph_uuid variable now] *******************************
2026-01-21 15:02:47.527921 | controller | Wednesday 21 January 2026  15:02:47 +0000 (0:00:00.026)       0:00:07.977 *****
2026-01-21 15:02:47.527958 | controller | ok: [instance] => changed=false
2026-01-21 15:02:47.562588 | controller |   msg: All assertions passed
2026-01-21 15:02:47.562618 | controller |
2026-01-21 15:02:47.562626 | controller | TASK [Only listed hooks] *******************************************************
2026-01-21 15:02:47.562632 | controller | Wednesday 21 January 2026  15:02:47 +0000 (0:00:00.026)       0:00:08.004 *****
2026-01-21 15:02:47.562642 | controller |
2026-01-21 15:02:47.621843 | controller | TASK [run_hook : Assert parameters are valid] **********************************
2026-01-21 15:02:47.621874 | controller | Wednesday 21 January 2026  15:02:47 +0000 (0:00:00.034)       0:00:08.039 *****
2026-01-21 15:02:47.621886 | controller | ok: [instance]
2026-01-21 15:02:47.703049 | controller |
2026-01-21 15:02:47.703080 | controller | TASK [run_hook : Assert single hooks are all mappings] *************************
2026-01-21 15:02:47.703088 | controller | Wednesday 21 January 2026  15:02:47 +0000 (0:00:00.059)       0:00:08.098 *****
2026-01-21 15:02:47.703098 | controller | ok: [instance]
2026-01-21 15:02:47.703434 | controller |
2026-01-21 15:02:47.703623 | controller | TASK [run_hook : Loop on hooks for list_hooks] *********************************
2026-01-21 15:02:47.703775 | controller | Wednesday 21 January 2026  15:02:47 +0000 (0:00:00.081)       0:00:08.180 *****
2026-01-21 15:02:47.816940 | 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'}})
2026-01-21 15:02:47.817160 | 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'}})
2026-01-21 15:02:47.817373 | controller |
2026-01-21 15:02:47.817540 | controller | TASK [run_hook : Set playbook path for Run dummy-2] ****************************
2026-01-21 15:02:47.817698 | controller | Wednesday 21 January 2026  15:02:47 +0000 (0:00:00.113)       0:00:08.294 *****
2026-01-21 15:02:47.866385 | controller | ok: [instance]
2026-01-21 15:02:47.866571 | controller |
2026-01-21 15:02:47.866726 | controller | TASK [run_hook : Get file stat] ************************************************
2026-01-21 15:02:47.866869 | controller | Wednesday 21 January 2026  15:02:47 +0000 (0:00:00.049)       0:00:08.343 *****
2026-01-21 15:02:48.063017 | controller | ok: [instance]
2026-01-21 15:02:48.080341 | controller |
2026-01-21 15:02:48.080376 | controller | TASK [run_hook : Fail if playbook doesn't exist] *******************************
2026-01-21 15:02:48.080386 | controller | Wednesday 21 January 2026  15:02:48 +0000 (0:00:00.196)       0:00:08.540 *****
2026-01-21 15:02:48.080398 | controller | skipping: [instance]
2026-01-21 15:02:48.080429 | controller |
2026-01-21 15:02:48.080646 | controller | TASK [run_hook : Get parameters files] *****************************************
2026-01-21 15:02:48.267753 | controller | Wednesday 21 January 2026  15:02:48 +0000 (0:00:00.017)       0:00:08.557 *****
2026-01-21 15:02:48.267786 | controller | ok: [instance]
2026-01-21 15:02:48.287280 | controller |
2026-01-21 15:02:48.287301 | controller | TASK [run_hook : Add parameters artifacts as extra variables] ******************
2026-01-21 15:02:48.287310 | controller | Wednesday 21 January 2026  15:02:48 +0000 (0:00:00.186)       0:00:08.744 *****
2026-01-21 15:02:48.287321 | controller | ok: [instance]
2026-01-21 15:02:48.482824 | controller |
2026-01-21 15:02:48.482853 | controller | TASK [run_hook : Ensure log directory exists] **********************************
2026-01-21 15:02:48.482861 | controller | Wednesday 21 January 2026  15:02:48 +0000 (0:00:00.019)       0:00:08.764 *****
2026-01-21 15:02:48.482872 | controller | ok: [instance]
2026-01-21 15:02:48.671344 | controller |
2026-01-21 15:02:48.671373 | controller | TASK [run_hook : Ensure artifacts directory exists] ****************************
2026-01-21 15:02:48.671381 | controller | Wednesday 21 January 2026  15:02:48 +0000 (0:00:00.195)       0:00:08.959 *****
2026-01-21 15:02:48.671391 | controller | ok: [instance]
2026-01-21 15:02:50.578372 | controller |
2026-01-21 15:02:50.578403 | controller | TASK [run_hook : Run hook without retry - Run dummy-2] *************************
2026-01-21 15:02:50.578411 | controller | Wednesday 21 January 2026  15:02:48 +0000 (0:00:00.188)       0:00:09.148 *****
2026-01-21 15:02:50.578421 | controller | Follow script's output here: /home/zuul/ci-framework-data/logs/ci_script_003_run_hook_without_retry_run.log
2026-01-21 15:02:50.578445 | controller | changed: [instance]
2026-01-21 15:02:50.578841 | controller |
2026-01-21 15:02:50.610114 | controller | TASK [run_hook : Run hook with retry - Run dummy-2] ****************************
2026-01-21 15:02:50.610132 | controller | Wednesday 21 January 2026  15:02:50 +0000 (0:00:01.907)       0:00:11.055 *****
2026-01-21 15:02:50.610148 | controller | skipping: [instance]
2026-01-21 15:02:50.808157 | controller |
2026-01-21 15:02:50.808187 | controller | TASK [run_hook : Check if we have a file] **************************************
2026-01-21 15:02:50.808195 | controller | Wednesday 21 January 2026  15:02:50 +0000 (0:00:00.031)       0:00:11.087 *****
2026-01-21 15:02:50.808207 | controller | ok: [instance]
2026-01-21 15:02:50.831308 | controller |
2026-01-21 15:02:50.831333 | controller | TASK [run_hook : Load generated content in main playbook] **********************
2026-01-21 15:02:50.831343 | controller | Wednesday 21 January 2026  15:02:50 +0000 (0:00:00.197)       0:00:11.285 *****
2026-01-21 15:02:50.831355 | controller | ok: [instance]
2026-01-21 15:02:50.831425 | controller |
2026-01-21 15:02:50.831441 | controller | TASK [run_hook : Set playbook path for Run dummy-3] ****************************
2026-01-21 15:02:50.831451 | controller | Wednesday 21 January 2026  15:02:50 +0000 (0:00:00.023)       0:00:11.308 *****
2026-01-21 15:02:50.880619 | controller | ok: [instance]
2026-01-21 15:02:51.070684 | controller |
2026-01-21 15:02:51.070713 | controller | TASK [run_hook : Get file stat] ************************************************
2026-01-21 15:02:51.070721 | controller | Wednesday 21 January 2026  15:02:50 +0000 (0:00:00.049)       0:00:11.357 *****
2026-01-21 15:02:51.070731 | controller | ok: [instance]
2026-01-21 15:02:51.088324 | controller |
2026-01-21 15:02:51.088384 | controller | TASK [run_hook : Fail if playbook doesn't exist] *******************************
2026-01-21 15:02:51.088395 | controller | Wednesday 21 January 2026  15:02:51 +0000 (0:00:00.189)       0:00:11.547 *****
2026-01-21 15:02:51.088411 | controller | skipping: [instance]
2026-01-21 15:02:51.275388 | controller |
2026-01-21 15:02:51.275427 | controller | TASK [run_hook : Get parameters files] *****************************************
2026-01-21 15:02:51.275435 | controller | Wednesday 21 January 2026  15:02:51 +0000 (0:00:00.017)       0:00:11.565 *****
2026-01-21 15:02:51.275447 | controller | ok: [instance]
2026-01-21 15:02:51.275471 | controller |
2026-01-21 15:02:51.275477 | controller | TASK [run_hook : Add parameters artifacts as extra variables] ******************
2026-01-21 15:02:51.275485 | controller | Wednesday 21 January 2026  15:02:51 +0000 (0:00:00.186)       0:00:11.752 *****
2026-01-21 15:02:51.297138 | controller | ok: [instance]
2026-01-21 15:02:51.484894 | controller |
2026-01-21 15:02:51.484925 | controller | TASK [run_hook : Ensure log directory exists] **********************************
2026-01-21 15:02:51.484933 | controller | Wednesday 21 January 2026  15:02:51 +0000 (0:00:00.021)       0:00:11.774 *****
2026-01-21 15:02:51.484944 | controller | ok: [instance]
2026-01-21 15:02:51.664903 | controller |
2026-01-21 15:02:51.664933 | controller | TASK [run_hook : Ensure artifacts directory exists] ****************************
2026-01-21 15:02:51.664948 | controller | Wednesday 21 January 2026  15:02:51 +0000 (0:00:00.187)       0:00:11.961 *****
2026-01-21 15:02:51.664959 | controller | ok: [instance]
2026-01-21 15:02:53.620319 | controller |
2026-01-21 15:02:53.620355 | controller | TASK [run_hook : Run hook without retry - Run dummy-3] *************************
2026-01-21 15:02:53.620363 | controller | Wednesday 21 January 2026  15:02:51 +0000 (0:00:00.180)       0:00:12.142 *****
2026-01-21 15:02:53.620374 | controller | Follow script's output here: /home/zuul/ci-framework-data/logs/ci_script_004_run_hook_without_retry_run.log
2026-01-21 15:02:53.620399 | controller | changed: [instance]
2026-01-21 15:02:53.620406 | controller |
2026-01-21 15:02:53.620414 | controller | TASK [run_hook : Run hook with retry - Run dummy-3] ****************************
2026-01-21 15:02:53.620510 | controller | Wednesday 21 January 2026  15:02:53 +0000 (0:00:01.955)       0:00:14.097 *****
2026-01-21 15:02:53.650824 | controller | skipping: [instance]
2026-01-21 15:02:53.844042 | controller |
2026-01-21 15:02:53.844073 | controller | TASK [run_hook : Check if we have a file] **************************************
2026-01-21 15:02:53.844081 | controller | Wednesday 21 January 2026  15:02:53 +0000 (0:00:00.030)       0:00:14.128 *****
2026-01-21 15:02:53.844091 | controller | ok: [instance]
2026-01-21 15:02:53.888387 | controller |
2026-01-21 15:02:53.888423 | controller | TASK [run_hook : Load generated content in main playbook] **********************
2026-01-21 15:02:53.888433 | controller | Wednesday 21 January 2026  15:02:53 +0000 (0:00:00.193)       0:00:14.321 *****
2026-01-21 15:02:53.888445 | controller | ok: [instance]
2026-01-21 15:02:53.888487 | controller |
2026-01-21 15:02:53.888622 | controller | TASK [Ensure we have the ceph_uuid variable now] *******************************
2026-01-21 15:02:53.888658 | controller | Wednesday 21 January 2026  15:02:53 +0000 (0:00:00.044)       0:00:14.365 *****
2026-01-21 15:02:53.914370 | controller | ok: [instance] => changed=false
2026-01-21 15:02:53.914480 | controller |   msg: All assertions passed
2026-01-21 15:02:53.914504 | controller |
2026-01-21 15:02:53.914512 | controller | TASK [Only filtered hooks] *****************************************************
2026-01-21 15:02:53.914522 | controller | Wednesday 21 January 2026  15:02:53 +0000 (0:00:00.025)       0:00:14.391 *****
2026-01-21 15:02:53.950056 | controller |
2026-01-21 15:02:54.010353 | controller | TASK [run_hook : Assert parameters are valid] **********************************
2026-01-21 15:02:54.010395 | controller | Wednesday 21 January 2026  15:02:53 +0000 (0:00:00.035)       0:00:14.427 *****
2026-01-21 15:02:54.010409 | controller | ok: [instance]
2026-01-21 15:02:54.010433 | controller |
2026-01-21 15:02:54.010440 | controller | TASK [run_hook : Assert single hooks are all mappings] *************************
2026-01-21 15:02:54.010447 | controller | Wednesday 21 January 2026  15:02:54 +0000 (0:00:00.060)       0:00:14.487 *****
2026-01-21 15:02:54.091698 | controller | ok: [instance]
2026-01-21 15:02:54.191303 | controller |
2026-01-21 15:02:54.191334 | controller | TASK [run_hook : Loop on hooks for filtered_hooks] *****************************
2026-01-21 15:02:54.191342 | controller | Wednesday 21 January 2026  15:02:54 +0000 (0:00:00.081)       0:00:14.568 *****
2026-01-21 15:02:54.191353 | 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'})
2026-01-21 15:02:54.240260 | controller |
2026-01-21 15:02:54.240292 | controller | TASK [run_hook : Set playbook path for 01 my hook] *****************************
2026-01-21 15:02:54.240301 | controller | Wednesday 21 January 2026  15:02:54 +0000 (0:00:00.099)       0:00:14.668 *****
2026-01-21 15:02:54.240311 | controller | ok: [instance]
2026-01-21 15:02:54.433887 | controller |
2026-01-21 15:02:54.433917 | controller | TASK [run_hook : Get file stat] ************************************************
2026-01-21 15:02:54.433925 | controller | Wednesday 21 January 2026  15:02:54 +0000 (0:00:00.048)       0:00:14.717 *****
2026-01-21 15:02:54.433936 | controller | ok: [instance]
2026-01-21 15:02:54.451191 | controller |
2026-01-21 15:02:54.451219 | controller | TASK [run_hook : Fail if playbook doesn't exist] *******************************
2026-01-21 15:02:54.451254 | controller | Wednesday 21 January 2026  15:02:54 +0000 (0:00:00.193)       0:00:14.910 *****
2026-01-21 15:02:54.451269 | controller | skipping: [instance]
2026-01-21 15:02:54.642253 | controller |
2026-01-21 15:02:54.642291 | controller | TASK [run_hook : Get parameters files] *****************************************
2026-01-21 15:02:54.642299 | controller | Wednesday 21 January 2026  15:02:54 +0000 (0:00:00.017)       0:00:14.928 *****
2026-01-21 15:02:54.642309 | controller | ok: [instance]
2026-01-21 15:02:54.662166 | controller |
2026-01-21 15:02:54.662194 | controller | TASK [run_hook : Add parameters artifacts as extra variables] ******************
2026-01-21 15:02:54.662203 | controller | Wednesday 21 January 2026  15:02:54 +0000 (0:00:00.190)       0:00:15.119 *****
2026-01-21 15:02:54.662215 | controller | ok: [instance]
2026-01-21 15:02:54.860908 | controller |
2026-01-21 15:02:54.860939 | controller | TASK [run_hook : Ensure log directory exists] **********************************
2026-01-21 15:02:54.860947 | controller | Wednesday 21 January 2026  15:02:54 +0000 (0:00:00.019)       0:00:15.139 *****
2026-01-21 15:02:54.860957 | controller | ok: [instance]
2026-01-21 15:02:55.048164 | controller |
2026-01-21 15:02:55.048194 | controller | TASK [run_hook : Ensure artifacts directory exists] ****************************
2026-01-21 15:02:55.048202 | controller | Wednesday 21 January 2026  15:02:54 +0000 (0:00:00.198)       0:00:15.338 *****
2026-01-21 15:02:55.048212 | controller | ok: [instance]
2026-01-21 15:02:56.961356 | controller |
2026-01-21 15:02:56.961388 | controller | TASK [run_hook : Run hook without retry - 01 my hook] **************************
2026-01-21 15:02:56.961397 | controller | Wednesday 21 January 2026  15:02:55 +0000 (0:00:00.187)       0:00:15.525 *****
2026-01-21 15:02:56.961407 | controller | Follow script's output here: /home/zuul/ci-framework-data/logs/ci_script_005_run_hook_without_retry_01_my.log
2026-01-21 15:02:56.961431 | controller | changed: [instance]
2026-01-21 15:02:56.961439 | controller |
2026-01-21 15:02:56.961690 | controller | TASK [run_hook : Run hook with retry - 01 my hook] *****************************
2026-01-21 15:02:56.990173 | controller | Wednesday 21 January 2026  15:02:56 +0000 (0:00:01.913)       0:00:17.438 *****
2026-01-21 15:02:56.990202 | controller | skipping: [instance]
2026-01-21 15:02:57.178092 | controller |
2026-01-21 15:02:57.178121 | controller | TASK [run_hook : Check if we have a file] **************************************
2026-01-21 15:02:57.178129 | controller | Wednesday 21 January 2026  15:02:56 +0000 (0:00:00.028)       0:00:17.467 *****
2026-01-21 15:02:57.178139 | controller | ok: [instance]
2026-01-21 15:02:57.203186 | controller |
2026-01-21 15:02:57.203210 | controller | TASK [run_hook : Load generated content in main playbook] **********************
2026-01-21 15:02:57.203218 | controller | Wednesday 21 January 2026  15:02:57 +0000 (0:00:00.187)       0:00:17.655 *****
2026-01-21 15:02:57.203228 | controller | ok: [instance]
2026-01-21 15:02:57.226760 | controller |
2026-01-21 15:02:57.226783 | controller | TASK [Ensure we have the ceph_uuid variable now] *******************************
2026-01-21 15:02:57.226791 | controller | Wednesday 21 January 2026  15:02:57 +0000 (0:00:00.025)       0:00:17.680 *****
2026-01-21 15:02:57.226801 | controller | ok: [instance] => changed=false
2026-01-21 15:02:57.262357 | controller |   msg: All assertions passed
2026-01-21 15:02:57.262385 | controller |
2026-01-21 15:02:57.262395 | controller | TASK [Direct hooks] ************************************************************
2026-01-21 15:02:57.262403 | controller | Wednesday 21 January 2026  15:02:57 +0000 (0:00:00.023)       0:00:17.704 *****
2026-01-21 15:02:57.262420 | controller |
2026-01-21 15:02:57.262450 | controller | TASK [run_hook : Assert parameters are valid] **********************************
2026-01-21 15:02:57.262594 | controller | Wednesday 21 January 2026  15:02:57 +0000 (0:00:00.035)       0:00:17.739 *****
2026-01-21 15:02:57.317723 | controller | ok: [instance]
2026-01-21 15:02:57.392315 | controller |
2026-01-21 15:02:57.392340 | controller | TASK [run_hook : Assert single hooks are all mappings] *************************
2026-01-21 15:02:57.392347 | controller | Wednesday 21 January 2026  15:02:57 +0000 (0:00:00.055)       0:00:17.795 *****
2026-01-21 15:02:57.392357 | controller | ok: [instance]
2026-01-21 15:02:57.392522 | controller |
2026-01-21 15:02:57.392533 | controller | TASK [run_hook : Loop on hooks for no_hook] ************************************
2026-01-21 15:02:57.392541 | controller | Wednesday 21 January 2026  15:02:57 +0000 (0:00:00.074)       0:00:17.869 *****
2026-01-21 15:02:57.487743 | 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'}})
2026-01-21 15:02:57.532969 | controller |
2026-01-21 15:02:57.532995 | controller | TASK [run_hook : Set playbook path for Dummy-5] ********************************
2026-01-21 15:02:57.533005 | controller | Wednesday 21 January 2026  15:02:57 +0000 (0:00:00.095)       0:00:17.964 *****
2026-01-21 15:02:57.533016 | controller | ok: [instance]
2026-01-21 15:02:57.715565 | controller |
2026-01-21 15:02:57.715593 | controller | TASK [run_hook : Get file stat] ************************************************
2026-01-21 15:02:57.715601 | controller | Wednesday 21 January 2026  15:02:57 +0000 (0:00:00.045)       0:00:18.010 *****
2026-01-21 15:02:57.715612 | controller | ok: [instance]
2026-01-21 15:02:57.731749 | controller |
2026-01-21 15:02:57.731774 | controller | TASK [run_hook : Fail if playbook doesn't exist] *******************************
2026-01-21 15:02:57.731782 | controller | Wednesday 21 January 2026  15:02:57 +0000 (0:00:00.182)       0:00:18.192 *****
2026-01-21 15:02:57.731792 | controller | skipping: [instance]
2026-01-21 15:02:57.910999 | controller |
2026-01-21 15:02:57.911029 | controller | TASK [run_hook : Get parameters files] *****************************************
2026-01-21 15:02:57.911037 | controller | Wednesday 21 January 2026  15:02:57 +0000 (0:00:00.016)       0:00:18.209 *****
2026-01-21 15:02:57.911047 | controller | ok: [instance]
2026-01-21 15:02:57.932064 | controller |
2026-01-21 15:02:57.932089 | controller | TASK [run_hook : Add parameters artifacts as extra variables] ******************
2026-01-21 15:02:57.932097 | controller | Wednesday 21 January 2026  15:02:57 +0000 (0:00:00.179)       0:00:18.388 *****
2026-01-21 15:02:57.932107 | controller | ok: [instance]
2026-01-21 15:02:58.125138 | controller |
2026-01-21 15:02:58.125169 | controller | TASK [run_hook : Ensure log directory exists] **********************************
2026-01-21 15:02:58.125177 | controller | Wednesday 21 January 2026  15:02:57 +0000 (0:00:00.021)       0:00:18.409 *****
2026-01-21 15:02:58.125188 | controller | ok: [instance]
2026-01-21 15:02:58.307055 | controller |
2026-01-21 15:02:58.307083 | controller | TASK [run_hook : Ensure artifacts directory exists] ****************************
2026-01-21 15:02:58.307091 | controller | Wednesday 21 January 2026  15:02:58 +0000 (0:00:00.192)       0:00:18.602 *****
2026-01-21 15:02:58.307101 | controller | ok: [instance]
2026-01-21 15:03:00.259887 | controller |
2026-01-21 15:03:00.259918 | controller | TASK [run_hook : Run hook without retry - Dummy-5] *****************************
2026-01-21 15:03:00.259926 | controller | Wednesday 21 January 2026  15:02:58 +0000 (0:00:00.182)       0:00:18.784 *****
2026-01-21 15:03:00.259937 | controller | Follow script's output here: /home/zuul/ci-framework-data/logs/ci_script_006_run_hook_without_retry_dummy.log
2026-01-21 15:03:00.290338 | controller | changed: [instance]
2026-01-21 15:03:00.290372 | controller |
2026-01-21 15:03:00.290380 | controller | TASK [run_hook : Run hook with retry - Dummy-5] ********************************
2026-01-21 15:03:00.290387 | controller | Wednesday 21 January 2026  15:03:00 +0000 (0:00:01.952)       0:00:20.736 *****
2026-01-21 15:03:00.290397 | controller | skipping: [instance]
2026-01-21 15:03:00.290420 | controller |
2026-01-21 15:03:00.290641 | controller | TASK [run_hook : Check if we have a file] **************************************
2026-01-21 15:03:00.486044 | controller | Wednesday 21 January 2026  15:03:00 +0000 (0:00:00.030)       0:00:20.767 *****
2026-01-21 15:03:00.486078 | controller | ok: [instance]
2026-01-21 15:03:00.513515 | controller |
2026-01-21 15:03:00.513544 | controller | TASK [run_hook : Load generated content in main playbook] **********************
2026-01-21 15:03:00.513554 | controller | Wednesday 21 January 2026  15:03:00 +0000 (0:00:00.195)       0:00:20.963 *****
2026-01-21 15:03:00.513565 | controller | ok: [instance]
2026-01-21 15:03:00.539183 | controller |
2026-01-21 15:03:00.539206 | controller | TASK [Ensure we have the ceph_uuid variable now] *******************************
2026-01-21 15:03:00.539213 | controller | Wednesday 21 January 2026  15:03:00 +0000 (0:00:00.027)       0:00:20.990 *****
2026-01-21 15:03:00.539223 | controller | ok: [instance] => changed=false
2026-01-21 15:03:00.580390 | controller |   msg: All assertions passed
2026-01-21 15:03:00.580421 | controller |
2026-01-21 15:03:00.580430 | controller | TASK [Direct hooks as param] ***************************************************
2026-01-21 15:03:00.580444 | controller | Wednesday 21 January 2026  15:03:00 +0000 (0:00:00.025)       0:00:21.016 *****
2026-01-21 15:03:00.580464 | controller |
2026-01-21 15:03:00.674198 | controller | TASK [run_hook : Assert parameters are valid] **********************************
2026-01-21 15:03:00.674231 | controller | Wednesday 21 January 2026  15:03:00 +0000 (0:00:00.041)       0:00:21.057 *****
2026-01-21 15:03:00.674271 | controller | ok: [instance]
2026-01-21 15:03:00.752333 | controller |
2026-01-21 15:03:00.752357 | controller | TASK [run_hook : Assert single hooks are all mappings] *************************
2026-01-21 15:03:00.752365 | controller | Wednesday 21 January 2026  15:03:00 +0000 (0:00:00.093)       0:00:21.151 *****
2026-01-21 15:03:00.752376 | controller | ok: [instance]
2026-01-21 15:03:00.752397 | controller |
2026-01-21 15:03:00.752405 | controller | TASK [run_hook : Loop on hooks for no_hook] ************************************
2026-01-21 15:03:00.752480 | controller | Wednesday 21 January 2026  15:03:00 +0000 (0:00:00.078)       0:00:21.229 *****
2026-01-21 15:03:00.852187 | 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'}})
2026-01-21 15:03:00.902501 | controller |
2026-01-21 15:03:00.902535 | controller | TASK [run_hook : Set playbook path for Hook as param] **************************
2026-01-21 15:03:00.902545 | controller | Wednesday 21 January 2026  15:03:00 +0000 (0:00:00.099)       0:00:21.329 *****
2026-01-21 15:03:00.902558 | controller | ok: [instance]
2026-01-21 15:03:01.093741 | controller |
2026-01-21 15:03:01.093770 | controller | TASK [run_hook : Get file stat] ************************************************
2026-01-21 15:03:01.093778 | controller | Wednesday 21 January 2026  15:03:00 +0000 (0:00:00.050)       0:00:21.379 *****
2026-01-21 15:03:01.093788 | controller | ok: [instance]
2026-01-21 15:03:01.111294 | controller |
2026-01-21 15:03:01.111314 | controller | TASK [run_hook : Fail if playbook doesn't exist] *******************************
2026-01-21 15:03:01.111322 | controller | Wednesday 21 January 2026  15:03:01 +0000 (0:00:00.191)       0:00:21.570 *****
2026-01-21 15:03:01.111331 | controller | skipping: [instance]
2026-01-21 15:03:01.111352 | controller |
2026-01-21 15:03:01.111360 | controller | TASK [run_hook : Get parameters files] *****************************************
2026-01-21 15:03:01.111495 | controller | Wednesday 21 January 2026  15:03:01 +0000 (0:00:00.017)       0:00:21.588 *****
2026-01-21 15:03:01.307940 | controller | ok: [instance]
2026-01-21 15:03:01.328864 | controller |
2026-01-21 15:03:01.328891 | controller | TASK [run_hook : Add parameters artifacts as extra variables] ******************
2026-01-21 15:03:01.328899 | controller | Wednesday 21 January 2026  15:03:01 +0000 (0:00:00.196)       0:00:21.785 *****
2026-01-21 15:03:01.328909 | controller | ok: [instance]
2026-01-21 15:03:01.524333 | controller |
2026-01-21 15:03:01.524364 | controller | TASK [run_hook : Ensure log directory exists] **********************************
2026-01-21 15:03:01.524373 | controller | Wednesday 21 January 2026  15:03:01 +0000 (0:00:00.021)       0:00:21.806 *****
2026-01-21 15:03:01.524383 | controller | ok: [instance]
2026-01-21 15:03:01.524486 | controller |
2026-01-21 15:03:01.524514 | controller | TASK [run_hook : Ensure artifacts directory exists] ****************************
2026-01-21 15:03:01.524526 | controller | Wednesday 21 January 2026  15:03:01 +0000 (0:00:00.195)       0:00:22.001 *****
2026-01-21 15:03:01.711926 | controller | ok: [instance]
2026-01-21 15:03:03.646862 | controller |
2026-01-21 15:03:03.646892 | controller | TASK [run_hook : Run hook without retry - Hook as param] ***********************
2026-01-21 15:03:03.646900 | controller | Wednesday 21 January 2026  15:03:01 +0000 (0:00:00.187)       0:00:22.189 *****
2026-01-21 15:03:03.646910 | controller | Follow script's output here: /home/zuul/ci-framework-data/logs/ci_script_007_run_hook_without_retry_hook.log
2026-01-21 15:03:03.676928 | controller | changed: [instance]
2026-01-21 15:03:03.676954 | controller |
2026-01-21 15:03:03.676962 | controller | TASK [run_hook : Run hook with retry - Hook as param] **************************
2026-01-21 15:03:03.676968 | controller | Wednesday 21 January 2026  15:03:03 +0000 (0:00:01.934)       0:00:24.123 *****
2026-01-21 15:03:03.676978 | controller | skipping: [instance]
2026-01-21 15:03:03.869014 | controller |
2026-01-21 15:03:03.869043 | controller | TASK [run_hook : Check if we have a file] **************************************
2026-01-21 15:03:03.869058 | controller | Wednesday 21 January 2026  15:03:03 +0000 (0:00:00.030)       0:00:24.154 *****
2026-01-21 15:03:03.869073 | controller | ok: [instance]
2026-01-21 15:03:03.896909 | controller |
2026-01-21 15:03:03.896934 | controller | TASK [run_hook : Load generated content in main playbook] **********************
2026-01-21 15:03:03.896942 | controller | Wednesday 21 January 2026  15:03:03 +0000 (0:00:00.191)       0:00:24.346 *****
2026-01-21 15:03:03.896952 | controller | ok: [instance]
2026-01-21 15:03:03.922345 | controller |
2026-01-21 15:03:03.922378 | controller | TASK [Ensure we have the ceph_uuid variable now] *******************************
2026-01-21 15:03:03.922386 | controller | Wednesday 21 January 2026  15:03:03 +0000 (0:00:00.027)       0:00:24.374 *****
2026-01-21 15:03:03.922409 | controller | ok: [instance] => changed=false
2026-01-21 15:03:03.922430 | controller |   msg: All assertions passed
2026-01-21 15:03:03.922538 | controller |
2026-01-21 15:03:03.922770 | controller | TASK [Ensure we have the test_list variable now] *******************************
2026-01-21 15:03:03.960059 | controller | Wednesday 21 January 2026  15:03:03 +0000 (0:00:00.025)       0:00:24.399 *****
2026-01-21 15:03:03.960096 | controller | ok: [instance] => changed=false
2026-01-21 15:03:03.986613 | controller |   msg: All assertions passed
2026-01-21 15:03:03.986820 | controller |
2026-01-21 15:03:03.986828 | controller | TASK [Run hook with retry] *****************************************************
2026-01-21 15:03:03.986834 | controller | Wednesday 21 January 2026  15:03:03 +0000 (0:00:00.037)       0:00:24.437 *****
2026-01-21 15:03:03.986844 | controller |
2026-01-21 15:03:04.045737 | controller | TASK [run_hook : Assert parameters are valid] **********************************
2026-01-21 15:03:04.045767 | controller | Wednesday 21 January 2026  15:03:03 +0000 (0:00:00.026)       0:00:24.464 *****
2026-01-21 15:03:04.045778 | controller | ok: [instance]
2026-01-21 15:03:04.125812 | controller |
2026-01-21 15:03:04.125844 | controller | TASK [run_hook : Assert single hooks are all mappings] *************************
2026-01-21 15:03:04.125852 | controller | Wednesday 21 January 2026  15:03:04 +0000 (0:00:00.058)       0:00:24.522 *****
2026-01-21 15:03:04.125862 | controller | ok: [instance]
2026-01-21 15:03:04.224483 | controller |
2026-01-21 15:03:04.224517 | controller | TASK [run_hook : Loop on hooks for retry_hook] *********************************
2026-01-21 15:03:04.224528 | controller | Wednesday 21 January 2026  15:03:04 +0000 (0:00:00.080)       0:00:24.602 *****
2026-01-21 15:03:04.224540 | 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})
2026-01-21 15:03:04.224715 | controller |
2026-01-21 15:03:04.224886 | controller | TASK [run_hook : Set playbook path for Run hook with retry] ********************
2026-01-21 15:03:04.225021 | controller | Wednesday 21 January 2026  15:03:04 +0000 (0:00:00.098)       0:00:24.701 *****
2026-01-21 15:03:04.272412 | controller | ok: [instance]
2026-01-21 15:03:04.272601 | controller |
2026-01-21 15:03:04.272771 | controller | TASK [run_hook : Get file stat] ************************************************
2026-01-21 15:03:04.272932 | controller | Wednesday 21 January 2026  15:03:04 +0000 (0:00:00.047)       0:00:24.749 *****
2026-01-21 15:03:04.465141 | controller | ok: [instance]
2026-01-21 15:03:04.482566 | controller |
2026-01-21 15:03:04.482592 | controller | TASK [run_hook : Fail if playbook doesn't exist] *******************************
2026-01-21 15:03:04.482599 | controller | Wednesday 21 January 2026  15:03:04 +0000 (0:00:00.192)       0:00:24.942 *****
2026-01-21 15:03:04.482609 | controller | skipping: [instance]
2026-01-21 15:03:04.669911 | controller |
2026-01-21 15:03:04.669940 | controller | TASK [run_hook : Get parameters files] *****************************************
2026-01-21 15:03:04.669949 | controller | Wednesday 21 January 2026  15:03:04 +0000 (0:00:00.017)       0:00:24.959 *****
2026-01-21 15:03:04.669959 | controller | ok: [instance]
2026-01-21 15:03:04.690886 | controller |
2026-01-21 15:03:04.690917 | controller | TASK [run_hook : Add parameters artifacts as extra variables] ******************
2026-01-21 15:03:04.690925 | controller | Wednesday 21 January 2026  15:03:04 +0000 (0:00:00.187)       0:00:25.147 *****
2026-01-21 15:03:04.690934 | controller | ok: [instance]
2026-01-21 15:03:04.883197 | controller |
2026-01-21 15:03:04.883228 | controller | TASK [run_hook : Ensure log directory exists] **********************************
2026-01-21 15:03:04.883260 | controller | Wednesday 21 January 2026  15:03:04 +0000 (0:00:00.021)       0:00:25.168 *****
2026-01-21 15:03:04.883287 | controller | ok: [instance]
2026-01-21 15:03:05.063416 | controller |
2026-01-21 15:03:05.063447 | controller | TASK [run_hook : Ensure artifacts directory exists] ****************************
2026-01-21 15:03:05.063455 | controller | Wednesday 21 January 2026  15:03:04 +0000 (0:00:00.192)       0:00:25.360 *****
2026-01-21 15:03:05.063466 | controller | ok: [instance]
2026-01-21 15:03:06.928320 | controller |
2026-01-21 15:03:06.928350 | controller | TASK [run_hook : Run hook without retry - Run hook with retry] *****************
2026-01-21 15:03:06.928359 | controller | Wednesday 21 January 2026  15:03:05 +0000 (0:00:00.180)       0:00:25.540 *****
2026-01-21 15:03:06.928369 | controller | Follow script's output here: /home/zuul/ci-framework-data/logs/ci_script_008_run_hook_without_retry_run.log
2026-01-21 15:03:06.928388 | controller | changed: [instance]
2026-01-21 15:03:06.928655 | controller |
2026-01-21 15:03:06.957467 | controller | TASK [run_hook : Run hook with retry - Run hook with retry] ********************
2026-01-21 15:03:06.957490 | controller | Wednesday 21 January 2026  15:03:06 +0000 (0:00:01.865)       0:00:27.405 *****
2026-01-21 15:03:06.957502 | controller | skipping: [instance]
2026-01-21 15:03:07.125217 | controller |
2026-01-21 15:03:07.125262 | controller | TASK [run_hook : Check if we have a file] **************************************
2026-01-21 15:03:07.125284 | controller | Wednesday 21 January 2026  15:03:06 +0000 (0:00:00.028)       0:00:27.434 *****
2026-01-21 15:03:07.125295 | controller | ok: [instance]
2026-01-21 15:03:07.145752 | controller |
2026-01-21 15:03:07.145774 | controller | TASK [run_hook : Load generated content in main playbook] **********************
2026-01-21 15:03:07.145789 | controller | Wednesday 21 January 2026  15:03:07 +0000 (0:00:00.167)       0:00:27.602 *****
2026-01-21 15:03:07.145801 | controller | skipping: [instance]
2026-01-21 15:03:07.319182 | controller |
2026-01-21 15:03:07.319210 | controller | TASK [Check if fake file exists for retry playbook] ****************************
2026-01-21 15:03:07.319219 | controller | Wednesday 21 January 2026  15:03:07 +0000 (0:00:00.020)       0:00:27.623 *****
2026-01-21 15:03:07.319229 | controller | ok: [instance]
2026-01-21 15:03:07.337474 | controller |
2026-01-21 15:03:07.337493 | controller | TASK [Ensure file exists and was created on retry] *****************************
2026-01-21 15:03:07.337501 | controller | Wednesday 21 January 2026  15:03:07 +0000 (0:00:00.173)       0:00:27.796 *****
2026-01-21 15:03:07.337509 | controller | ok: [instance] => changed=false
2026-01-21 15:03:07.522346 | controller |   msg: All assertions passed
2026-01-21 15:03:07.522376 | controller |
2026-01-21 15:03:07.522384 | controller | TASK [Remove generated file] ***************************************************
2026-01-21 15:03:07.522390 | controller | Wednesday 21 January 2026  15:03:07 +0000 (0:00:00.018)       0:00:27.814 *****
2026-01-21 15:03:07.522400 | controller | changed: [instance]
2026-01-21 15:03:07.522421 | controller |
2026-01-21 15:03:07.522428 | controller | PLAY RECAP *********************************************************************
2026-01-21 15:03:07.522433 | controller | instance                   : ok=112  changed=10   unreachable=0    failed=0    skipped=22   rescued=0    ignored=0
2026-01-21 15:03:07.522439 | controller |
2026-01-21 15:03:07.522444 | controller | Wednesday 21 January 2026  15:03:07 +0000 (0:00:00.184)       0:00:27.998 *****
2026-01-21 15:03:07.522450 | controller | ===============================================================================
2026-01-21 15:03:07.522455 | controller | run_hook : Run hook without retry - Run dummy-3 ------------------------- 1.96s
2026-01-21 15:03:07.522462 | controller | run_hook : Run hook without retry - Dummy-5 ----------------------------- 1.95s
2026-01-21 15:03:07.522482 | controller | run_hook : Run hook without retry - Hook as param ----------------------- 1.93s
2026-01-21 15:03:07.524094 | controller | run_hook : Run hook without retry - 03 single hook ---------------------- 1.92s
2026-01-21 15:03:07.579714 | controller | run_hook : Run hook without retry - 01 my hook -------------------------- 1.91s
2026-01-21 15:03:07.579745 | controller | run_hook : Run hook without retry - Run dummy-2 ------------------------- 1.91s
2026-01-21 15:03:07.579752 | controller | run_hook : Run hook without retry - Run hook with retry ----------------- 1.87s
2026-01-21 15:03:07.579758 | controller | Gathering Facts --------------------------------------------------------- 0.79s
2026-01-21 15:03:07.579769 | controller | run_hook : Run hook without retry - 01 Default noop hook ---------------- 0.59s
2026-01-21 15:03:07.579774 | controller | run_hook : Run hook without retry - 02 Re-run noop ---------------------- 0.57s
2026-01-21 15:03:07.579780 | controller | run_hook : Ensure log directory exists ---------------------------------- 0.30s
2026-01-21 15:03:07.579785 | controller | run_hook : Get file stat ------------------------------------------------ 0.29s
2026-01-21 15:03:07.579790 | controller | run_hook : Get parameters files ----------------------------------------- 0.28s
2026-01-21 15:03:07.579795 | controller | run_hook : Ensure log directory exists ---------------------------------- 0.20s
2026-01-21 15:03:07.579801 | controller | run_hook : Ensure log directory exists ---------------------------------- 0.20s
2026-01-21 15:03:07.579806 | controller | run_hook : Check if we have a file -------------------------------------- 0.20s
2026-01-21 15:03:07.579811 | controller | run_hook : Get parameters files ----------------------------------------- 0.20s
2026-01-21 15:03:07.579816 | controller | run_hook : Get file stat ------------------------------------------------ 0.20s
2026-01-21 15:03:07.579822 | controller | run_hook : Check if we have a file -------------------------------------- 0.20s
2026-01-21 15:03:07.579827 | controller | run_hook : Ensure log directory exists ---------------------------------- 0.20s
2026-01-21 15:03:07.579838 | controller | INFO     Running default > cleanup
2026-01-21 15:03:07.580305 | controller | WARNING  Skipping, cleanup playbook not configured.
2026-01-21 15:03:07.580862 | controller | INFO     Writing /tmp/report.html report.
2026-01-21 15:03:08.077235 | controller | changed
2026-01-21 15:03:08.098095 | 
2026-01-21 15:03:08.098173 | PLAY RECAP
2026-01-21 15:03:08.098214 | controller | ok: 4 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-01-21 15:03:08.098237 | 
2026-01-21 15:03:08.175987 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2026-01-21 15:03:08.176898 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2026-01-21 15:03:08.775568 | 
2026-01-21 15:03:08.775685 | PLAY [Run ci/playbooks/collect-logs.yml]
2026-01-21 15:03:08.796480 | 
2026-01-21 15:03:08.796569 | TASK [Filter out host if needed]
2026-01-21 15:03:08.805261 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2026-01-21 15:03:08.809818 | 
2026-01-21 15:03:08.809902 | TASK [Ensure file is present]
2026-01-21 15:03:09.112361 | controller | ok
2026-01-21 15:03:09.119253 | 
2026-01-21 15:03:09.119359 | TASK [Manage molecule report file]
2026-01-21 15:03:09.645166 | controller | changed
2026-01-21 15:03:09.650792 | 
2026-01-21 15:03:09.650889 | TASK [Check if we get ci-framework-data basedir]
2026-01-21 15:03:09.831317 | controller | ok
2026-01-21 15:03:09.837686 | 
2026-01-21 15:03:09.837760 | TASK [Create ci-framework-data log directory for zuul]
2026-01-21 15:03:10.149733 | controller | changed
2026-01-21 15:03:10.155324 | 
2026-01-21 15:03:10.155396 | TASK [Copy ci-framework interesting files]
2026-01-21 15:03:10.354515 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2026-01-21 15:03:10.682330 | controller | changed
2026-01-21 15:03:10.687653 | 
2026-01-21 15:03:10.687728 | TASK [Get SELinux listing]
2026-01-21 15:03:11.214796 | controller | changed
2026-01-21 15:03:11.221165 | 
2026-01-21 15:03:11.221242 | TASK [Generate log index]
2026-01-21 15:03:11.828467 | controller | changed
2026-01-21 15:03:11.833342 | 
2026-01-21 15:03:11.833425 | TASK [Get some env related data]
2026-01-21 15:03:12.369209 | controller | /home/zuul/.local/bin/ansible
2026-01-21 15:03:13.362148 | controller | changed
2026-01-21 15:03:13.367451 | 
2026-01-21 15:03:13.367530 | TASK [Generate list of logs to collect in home directory]
2026-01-21 15:03:13.650016 | controller | ok: All paths examined
2026-01-21 15:03:13.655684 | 
2026-01-21 15:03:13.655764 | LOOP [Copy logs from home directory]
2026-01-21 15:03:13.958754 | controller | changed:
2026-01-21 15:03:13.958881 | controller | {
2026-01-21 15:03:13.958910 | controller |   "atime": 1769007734.7025964,
2026-01-21 15:03:13.958931 | controller |   "ctime": 1769007751.016872,
2026-01-21 15:03:13.958948 | controller |   "dev": 64513,
2026-01-21 15:03:13.958964 | controller |   "gid": 1000,
2026-01-21 15:03:13.958979 | controller |   "gr_name": "zuul",
2026-01-21 15:03:13.958995 | controller |   "inode": 4329372,
2026-01-21 15:03:13.959011 | controller |   "isblk": false,
2026-01-21 15:03:13.959028 | controller |   "ischr": false,
2026-01-21 15:03:13.959043 | controller |   "isdir": false,
2026-01-21 15:03:13.959057 | controller |   "isfifo": false,
2026-01-21 15:03:13.959072 | controller |   "isgid": false,
2026-01-21 15:03:13.959085 | controller |   "islnk": false,
2026-01-21 15:03:13.959099 | controller |   "isreg": true,
2026-01-21 15:03:13.959112 | controller |   "issock": false,
2026-01-21 15:03:13.959125 | controller |   "isuid": false,
2026-01-21 15:03:13.959138 | controller |   "mode": "0644",
2026-01-21 15:03:13.959151 | controller |   "mtime": 1769007751.016872,
2026-01-21 15:03:13.959165 | controller |   "nlink": 1,
2026-01-21 15:03:13.959178 | controller |   "path": "/home/zuul/ansible.log",
2026-01-21 15:03:13.959193 | controller |   "pw_name": "zuul",
2026-01-21 15:03:13.959208 | controller |   "rgrp": true,
2026-01-21 15:03:13.959223 | controller |   "roth": true,
2026-01-21 15:03:13.959238 | controller |   "rusr": true,
2026-01-21 15:03:13.959252 | controller |   "size": 6749,
2026-01-21 15:03:13.959266 | controller |   "uid": 1000,
2026-01-21 15:03:13.959279 | controller |   "wgrp": false,
2026-01-21 15:03:13.959309 | controller |   "woth": false,
2026-01-21 15:03:13.959334 | controller |   "wusr": true,
2026-01-21 15:03:13.959352 | controller |   "xgrp": false,
2026-01-21 15:03:13.959367 | controller |   "xoth": false,
2026-01-21 15:03:13.959382 | controller |   "xusr": false
2026-01-21 15:03:13.959396 | controller | }
2026-01-21 15:03:13.974465 | 
2026-01-21 15:03:13.974580 | TASK [Copy crio stats log file]
2026-01-21 15:03:13.989092 | controller | skipping: Conditional result was False
2026-01-21 15:03:13.994921 | 
2026-01-21 15:03:13.995009 | TASK [Get SELinux related data]
2026-01-21 15:03:14.202260 | controller | <no matches>
2026-01-21 15:03:14.525280 | controller | ERROR
2026-01-21 15:03:14.525587 | controller | {
2026-01-21 15:03:14.525628 | controller |   "delta": "0:00:00.008731",
2026-01-21 15:03:14.525652 | controller |   "end": "2026-01-21 15:03:14.202887",
2026-01-21 15:03:14.525673 | controller |   "msg": "non-zero return code",
2026-01-21 15:03:14.525691 | controller |   "rc": 1,
2026-01-21 15:03:14.525708 | controller |   "start": "2026-01-21 15:03:14.194156"
2026-01-21 15:03:14.525725 | controller | }
2026-01-21 15:03:14.525752 | controller | ERROR: Ignoring Errors
2026-01-21 15:03:14.531083 | 
2026-01-21 15:03:14.531153 | TASK [Create system configuration directory]
2026-01-21 15:03:14.712336 | controller | changed
2026-01-21 15:03:14.717466 | 
2026-01-21 15:03:14.717536 | TASK [Get some of the system configurations]
2026-01-21 15:03:15.242179 | controller | changed
2026-01-21 15:03:15.247834 | 
2026-01-21 15:03:15.247910 | TASK [Copy generated documentation if available]
2026-01-21 15:03:15.261168 | controller | skipping: Conditional result was False
2026-01-21 15:03:15.267319 | 
2026-01-21 15:03:15.267392 | TASK [Copy generated AsciiDoc documentation if available]
2026-01-21 15:03:15.280864 | controller | skipping: Conditional result was False
2026-01-21 15:03:15.287280 | 
2026-01-21 15:03:15.287378 | TASK [Compress logs bigger than 2MB]
2026-01-21 15:03:15.813241 | controller | changed
2026-01-21 15:03:15.818721 | 
2026-01-21 15:03:15.818787 | TASK [Copy files from workspace on node]
2026-01-21 15:03:15.836950 | controller | ok
2026-01-21 15:03:15.860443 | 
2026-01-21 15:03:15.860525 | TASK [fetch-output : Set log path for multiple nodes]
2026-01-21 15:03:15.874942 | controller | skipping: Conditional result was False
2026-01-21 15:03:15.880447 | 
2026-01-21 15:03:15.880517 | TASK [fetch-output : Set log path for single node]
2026-01-21 15:03:15.908740 | controller | ok
2026-01-21 15:03:15.915327 | 
2026-01-21 15:03:15.915399 | LOOP [fetch-output : Ensure local output dirs]
2026-01-21 15:03:16.122167 | controller -> localhost | ok: "/var/lib/zuul/builds/827d5059bab7499a817511eca4b6173f/work/logs"
2026-01-21 15:03:16.122493 | controller -> localhost | changed: All items complete
2026-01-21 15:03:16.122527 | 
2026-01-21 15:03:16.306648 | controller -> localhost | changed: "/var/lib/zuul/builds/827d5059bab7499a817511eca4b6173f/work/artifacts"
2026-01-21 15:03:16.492068 | controller -> localhost | changed: "/var/lib/zuul/builds/827d5059bab7499a817511eca4b6173f/work/docs"
2026-01-21 15:03:16.503361 | 
2026-01-21 15:03:16.503486 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-01-21 15:03:17.061676 | controller | changed:
2026-01-21 15:03:17.061866 | controller | .d..t...... ./
2026-01-21 15:03:17.061899 | controller | >f+++++++++ README.html
2026-01-21 15:03:17.061921 | controller | >f+++++++++ ansible-execution.log
2026-01-21 15:03:17.061944 | controller | >f+++++++++ ansible.log
2026-01-21 15:03:17.061962 | controller | >f+++++++++ dmesg.log
2026-01-21 15:03:17.061980 | controller | >f+++++++++ installed-pkgs.log
2026-01-21 15:03:17.062001 | controller | >f+++++++++ python.log
2026-01-21 15:03:17.062021 | controller | >f+++++++++ registries.conf
2026-01-21 15:03:17.062039 | controller | >f+++++++++ report.html
2026-01-21 15:03:17.062058 | controller | >f+++++++++ selinux-denials.log
2026-01-21 15:03:17.062076 | controller | >f+++++++++ selinux-listing.log
2026-01-21 15:03:17.062093 | controller | cd+++++++++ ci-framework-data/
2026-01-21 15:03:17.062109 | controller | cd+++++++++ ci-framework-data/artifacts/
2026-01-21 15:03:17.062125 | controller | >f+++++++++ ci-framework-data/artifacts/ci_script_000_run_hook_without_retry_01.sh
2026-01-21 15:03:17.062141 | controller | >f+++++++++ ci-framework-data/artifacts/ci_script_001_run_hook_without_retry_02_re.sh
2026-01-21 15:03:17.062157 | controller | >f+++++++++ ci-framework-data/artifacts/ci_script_002_run_hook_without_retry_03.sh
2026-01-21 15:03:17.062173 | controller | >f+++++++++ ci-framework-data/artifacts/ci_script_003_run_hook_without_retry_run.sh
2026-01-21 15:03:17.062194 | controller | >f+++++++++ ci-framework-data/artifacts/ci_script_004_run_hook_without_retry_run.sh
2026-01-21 15:03:17.062211 | controller | >f+++++++++ ci-framework-data/artifacts/ci_script_005_run_hook_without_retry_01_my.sh
2026-01-21 15:03:17.062227 | controller | >f+++++++++ ci-framework-data/artifacts/ci_script_006_run_hook_without_retry_dummy.sh
2026-01-21 15:03:17.062244 | controller | >f+++++++++ ci-framework-data/artifacts/ci_script_007_run_hook_without_retry_hook.sh
2026-01-21 15:03:17.062260 | controller | >f+++++++++ ci-framework-data/artifacts/ci_script_008_run_hook_without_retry_run.sh
2026-01-21 15:03:17.062276 | controller | >f+++++++++ ci-framework-data/artifacts/filtered_hooks_01_my_hook.yml
2026-01-21 15:03:17.062308 | controller | >f+++++++++ ci-framework-data/artifacts/list_hooks_run_dummy-2.yml
2026-01-21 15:03:17.062334 | controller | >f+++++++++ ci-framework-data/artifacts/list_hooks_run_dummy-3.yml
2026-01-21 15:03:17.062354 | controller | >f+++++++++ ci-framework-data/artifacts/no_hook_dummy-5.yml
2026-01-21 15:03:17.062371 | controller | >f+++++++++ ci-framework-data/artifacts/no_hook_hook_as_param.yml
2026-01-21 15:03:17.062388 | controller | >f+++++++++ ci-framework-data/artifacts/run_molecule_03_single_hook.yml
2026-01-21 15:03:17.062404 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2026-01-21 15:03:17.062420 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2026-01-21 15:03:17.062436 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2026-01-21 15:03:17.062451 | controller | cd+++++++++ ci-framework-data/logs/
2026-01-21 15:03:17.062467 | controller | >f+++++++++ ci-framework-data/logs/ci_script_000_run_hook_without_retry_01.log
2026-01-21 15:03:17.062493 | controller | >f+++++++++ ci-framework-data/logs/ci_script_001_run_hook_without_retry_02_re.log
2026-01-21 15:03:17.062508 | controller | >f+++++++++ ci-framework-data/logs/ci_script_002_run_hook_without_retry_03.log
2026-01-21 15:03:17.062523 | controller | >f+++++++++ ci-framework-data/logs/ci_script_003_run_hook_without_retry_run.log
2026-01-21 15:03:17.062538 | controller | >f+++++++++ ci-framework-data/logs/ci_script_004_run_hook_without_retry_run.log
2026-01-21 15:03:17.062553 | controller | >f+++++++++ ci-framework-data/logs/ci_script_005_run_hook_without_retry_01_my.log
2026-01-21 15:03:17.062567 | controller | >f+++++++++ ci-framework-data/logs/ci_script_006_run_hook_without_retry_dummy.log
2026-01-21 15:03:17.062582 | controller | >f+++++++++ ci-framework-data/logs/ci_script_007_run_hook_without_retry_hook.log
2026-01-21 15:03:17.062596 | controller | >f+++++++++ ci-framework-data/logs/ci_script_008_run_hook_without_retry_run.log
2026-01-21 15:03:17.062610 | controller | >f+++++++++ ci-framework-data/logs/filtered_hooks_01_my_hook.log
2026-01-21 15:03:17.062626 | controller | >f+++++++++ ci-framework-data/logs/list_hooks_run_dummy-2.log
2026-01-21 15:03:17.062641 | controller | >f+++++++++ ci-framework-data/logs/list_hooks_run_dummy-3.log
2026-01-21 15:03:17.062656 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2026-01-21 15:03:17.062671 | controller | >f+++++++++ ci-framework-data/logs/no_hook_dummy-5.log
2026-01-21 15:03:17.062686 | controller | >f+++++++++ ci-framework-data/logs/no_hook_hook_as_param.log
2026-01-21 15:03:17.062701 | controller | >f+++++++++ ci-framework-data/logs/retry_hook_run_hook_with_retry.log
2026-01-21 15:03:17.062716 | controller | >f+++++++++ ci-framework-data/logs/run_molecule_01_default_noop_hook.log
2026-01-21 15:03:17.062731 | controller | >f+++++++++ ci-framework-data/logs/run_molecule_02_re-run_noop.log
2026-01-21 15:03:17.062747 | controller | >f+++++++++ ci-framework-data/logs/run_molecule_03_single_hook.log
2026-01-21 15:03:17.062762 | controller | cd+++++++++ registries.conf.d/
2026-01-21 15:03:17.062777 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2026-01-21 15:03:17.062795 | controller | cd+++++++++ system-config/
2026-01-21 15:03:17.062812 | controller | cd+++++++++ system-config/libvirt/
2026-01-21 15:03:17.062840 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2026-01-21 15:03:17.062869 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2026-01-21 15:03:17.424383 | controller | changed: .d..t...... ./
2026-01-21 15:03:17.796134 | controller | changed: .d..t...... ./
2026-01-21 15:03:17.816719 | 
2026-01-21 15:03:17.816864 | TASK [Return artifact to Zuul]
2026-01-21 15:03:17.845046 | controller | ok
2026-01-21 15:03:17.863908 | 
2026-01-21 15:03:17.863995 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2026-01-21 15:03:17.864093 | 
2026-01-21 15:03:17.864122 | PLAY RECAP
2026-01-21 15:03:17.864159 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2026-01-21 15:03:17.864180 | 
2026-01-21 15:03:17.961150 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2026-01-21 15:03:17.962044 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2026-01-21 15:03:18.527701 | 
2026-01-21 15:03:18.527831 | PLAY [all]
2026-01-21 15:03:18.546463 | 
2026-01-21 15:03:18.546549 | TASK [include_role : fetch-output]
2026-01-21 15:03:18.575769 | controller | ok
2026-01-21 15:03:18.592987 | 
2026-01-21 15:03:18.593109 | TASK [fetch-output : Set log path for multiple nodes]
2026-01-21 15:03:18.637486 | controller | skipping: Conditional result was False
2026-01-21 15:03:18.643196 | 
2026-01-21 15:03:18.643289 | TASK [fetch-output : Set log path for single node]
2026-01-21 15:03:18.672729 | controller | ok
2026-01-21 15:03:18.677689 | 
2026-01-21 15:03:18.677769 | LOOP [fetch-output : Ensure local output dirs]
2026-01-21 15:03:19.004208 | controller -> localhost | ok: "/var/lib/zuul/builds/827d5059bab7499a817511eca4b6173f/work/logs"
2026-01-21 15:03:19.191686 | controller -> localhost | ok: "/var/lib/zuul/builds/827d5059bab7499a817511eca4b6173f/work/artifacts"
2026-01-21 15:03:19.375400 | controller -> localhost | ok: "/var/lib/zuul/builds/827d5059bab7499a817511eca4b6173f/work/docs"
2026-01-21 15:03:19.387033 | 
2026-01-21 15:03:19.387105 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-01-21 15:03:19.912806 | controller | ok
2026-01-21 15:03:19.912996 | controller | ok: All items complete
2026-01-21 15:03:19.913027 | 
2026-01-21 15:03:20.302466 | controller | ok
2026-01-21 15:03:20.682252 | controller | ok
2026-01-21 15:03:20.701167 | 
2026-01-21 15:03:20.701258 | TASK [include_role : fetch-output-openshift]
2026-01-21 15:03:20.714707 | controller | skipping: Conditional result was False
2026-01-21 15:03:20.721226 | 
2026-01-21 15:03:20.721328 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-01-21 15:03:21.071898 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.006272
2026-01-21 15:03:21.277918 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.005927
2026-01-21 15:03:21.308940 | 
2026-01-21 15:03:21.309028 | PLAY [all]
2026-01-21 15:03:21.323665 | 
2026-01-21 15:03:21.323733 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-01-21 15:03:21.717954 | controller | changed
2026-01-21 15:03:21.741024 | 
2026-01-21 15:03:21.741087 | PLAY RECAP
2026-01-21 15:03:21.741131 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2026-01-21 15:03:21.741155 | 
2026-01-21 15:03:21.821015 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2026-01-21 15:03:21.821875 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2026-01-21 15:03:22.382926 | 
2026-01-21 15:03:22.383032 | PLAY [localhost]
2026-01-21 15:03:22.401190 | 
2026-01-21 15:03:22.401271 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-01-21 15:03:22.712656 | localhost | changed
2026-01-21 15:03:22.717620 | 
2026-01-21 15:03:22.717720 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-01-21 15:03:22.745121 | localhost | ok
2026-01-21 15:03:22.753020 | 
2026-01-21 15:03:22.753099 | TASK [add-fileserver : Create SSH private key tempfile]
2026-01-21 15:03:23.074317 | localhost | changed
2026-01-21 15:03:23.080545 | 
2026-01-21 15:03:23.080622 | TASK [add-fileserver : Create SSH private key from secret]
2026-01-21 15:03:23.645380 | localhost | changed
2026-01-21 15:03:23.650242 | 
2026-01-21 15:03:23.650329 | TASK [add-fileserver : Add fileserver ssh key]
2026-01-21 15:03:23.992228 | localhost | Identity added: /var/lib/zuul/builds/827d5059bab7499a817511eca4b6173f/work/tmp/ansible.9dgl5bbf (/var/lib/zuul/builds/827d5059bab7499a817511eca4b6173f/work/tmp/ansible.9dgl5bbf)
2026-01-21 15:03:23.992419 | localhost | ok: Runtime: 0:00:00.006634
2026-01-21 15:03:23.996618 | 
2026-01-21 15:03:23.996689 | TASK [add-fileserver : Remove SSH private key from disk]
2026-01-21 15:03:24.226467 | localhost | ok: Runtime: 0:00:00.004422
2026-01-21 15:03:24.231312 | 
2026-01-21 15:03:24.231393 | TASK [add-fileserver : Add fileserver to inventory]
2026-01-21 15:03:24.280032 | localhost | changed
2026-01-21 15:03:24.284176 | 
2026-01-21 15:03:24.284250 | TASK [add-fileserver : Add fileserver server to known hosts]
2026-01-21 15:03:24.616871 | localhost | changed
2026-01-21 15:03:24.636585 | 
2026-01-21 15:03:24.636667 | PLAY [localhost]
2026-01-21 15:03:24.648066 | 
2026-01-21 15:03:24.648133 | TASK [Generate bulk log download script]
2026-01-21 15:03:24.666267 | localhost | ok
2026-01-21 15:03:24.678594 | 
2026-01-21 15:03:24.678673 | TASK [local-log-download : Check API endpoint is defined]
2026-01-21 15:03:24.705969 | localhost | ok: All assertions passed
2026-01-21 15:03:24.710598 | 
2026-01-21 15:03:24.710698 | TASK [local-log-download : Create download script]
2026-01-21 15:03:25.099782 | localhost -> localhost | changed
2026-01-21 15:03:25.108925 | 
2026-01-21 15:03:25.109023 | TASK [Register quick-download link]
2026-01-21 15:03:25.126731 | localhost | ok
2026-01-21 15:03:25.166495 | 
2026-01-21 15:03:25.166586 | PLAY [logserver.rdoproject.org]
2026-01-21 15:03:25.176333 | 
2026-01-21 15:03:25.176400 | TASK [Set zuul-log-path fact]
2026-01-21 15:03:25.191804 | logserver.rdoproject.org | ok
2026-01-21 15:03:25.201361 | 
2026-01-21 15:03:25.201438 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-01-21 15:03:25.227578 | logserver.rdoproject.org | ok
2026-01-21 15:03:25.233052 | 
2026-01-21 15:03:25.233120 | TASK [upload-logs : Create log directories]
2026-01-21 15:03:27.111915 | logserver.rdoproject.org | changed
2026-01-21 15:03:27.115341 | 
2026-01-21 15:03:27.115421 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-01-21 15:03:27.342869 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.005022
2026-01-21 15:03:27.347835 | 
2026-01-21 15:03:27.347910 | TASK [upload-logs : Upload logs to log server]
2026-01-21 15:03:28.796507 | logserver.rdoproject.org | Output suppressed because no_log was given
2026-01-21 15:03:28.799592 | 
2026-01-21 15:03:28.799660 | LOOP [upload-logs : Compress console log and json output]
2026-01-21 15:03:28.837848 | logserver.rdoproject.org | skipping: Conditional result was False
2026-01-21 15:03:28.847015 | logserver.rdoproject.org | skipping: Conditional result was False
2026-01-21 15:03:28.857763 | 
2026-01-21 15:03:28.857930 | LOOP [upload-logs : Upload compressed console log and json output]
2026-01-21 15:03:28.897528 | logserver.rdoproject.org | skipping: Conditional result was False
2026-01-21 15:03:28.897760 | 
2026-01-21 15:03:28.901160 | logserver.rdoproject.org | skipping: Conditional result was False
2026-01-21 15:03:28.906836 | 
2026-01-21 15:03:28.906944 | LOOP [upload-logs : Upload console log and json output]