2026-01-21 08:13:19.904976 | Job console starting...
2026-01-21 08:13:20.121356 | Updating repositories
2026-01-21 08:13:20.958765 | Preparing job workspace
2026-01-21 08:13:41.728032 | Running Ansible setup...
2026-01-21 08:13:51.264976 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2026-01-21 08:13:53.497251 | 
2026-01-21 08:13:53.497393 | PLAY [localhost]
2026-01-21 08:13:53.514747 | 
2026-01-21 08:13:53.514826 | TASK [Gathering Facts]
2026-01-21 08:13:56.809927 | localhost | ok
2026-01-21 08:13:56.862582 | 
2026-01-21 08:13:56.862704 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-01-21 08:13:58.907512 | localhost -> localhost | changed
2026-01-21 08:13:58.920271 | 
2026-01-21 08:13:58.920376 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-01-21 08:14:01.704940 | localhost -> localhost | changed
2026-01-21 08:14:01.713540 | 
2026-01-21 08:14:01.713628 | TASK [Setup log path fact]
2026-01-21 08:14:01.783060 | localhost | ok
2026-01-21 08:14:01.830888 | 
2026-01-21 08:14:01.830996 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-01-21 08:14:01.904458 | localhost | ok
2026-01-21 08:14:01.915571 | 
2026-01-21 08:14:01.915656 | TASK [emit-job-header : Print job information]
2026-01-21 08:14:02.001734 | # Job Information
2026-01-21 08:14:02.002020 | Ansible Version: 2.15.12
2026-01-21 08:14:02.002057 | Job: cifmw-molecule-run_hook
2026-01-21 08:14:02.002081 | Pipeline: github-check
2026-01-21 08:14:02.002101 | Executor: ibm-bm4-ze.softwarefactory-project.io
2026-01-21 08:14:02.002121 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3451
2026-01-21 08:14:02.002141 | Log URL (when completed): https://logserver.rdoproject.org/798/rdoproject.org/798c83b8c7b546458fe0cf737d87cb07/
2026-01-21 08:14:02.002161 | Event ID: bdf95640-f6a0-11f0-8ef2-a23e9e56eab1
2026-01-21 08:14:02.006868 | 
2026-01-21 08:14:02.006945 | LOOP [emit-job-header : Print node information]
2026-01-21 08:14:02.385863 | localhost | ok:
2026-01-21 08:14:02.386084 | localhost | # Node Information
2026-01-21 08:14:02.386124 | localhost | Inventory Hostname: controller
2026-01-21 08:14:02.386191 | localhost | Hostname: np0005590028
2026-01-21 08:14:02.386226 | localhost | Username: zuul
2026-01-21 08:14:02.386252 | localhost | Distro: CentOS 9
2026-01-21 08:14:02.386274 | localhost | Provider: ibm-bm4-nodepool
2026-01-21 08:14:02.386348 | localhost | Region: regionOne
2026-01-21 08:14:02.386386 | localhost | Label: cloud-centos-9-stream-tripleo-ibm
2026-01-21 08:14:02.386414 | localhost | Product Name: OpenStack Compute
2026-01-21 08:14:02.386513 | localhost | Interface IP: 192.168.26.150
2026-01-21 08:14:02.467758 | 
2026-01-21 08:14:02.467854 | PLAY [all]
2026-01-21 08:14:02.532568 | 
2026-01-21 08:14:02.532891 | TASK [Gather network facts]
2026-01-21 08:14:02.979795 | controller | ok
2026-01-21 08:14:03.059794 | 
2026-01-21 08:14:03.059904 | TASK [include_role : start-zuul-console]
2026-01-21 08:14:03.117816 | controller | ok
2026-01-21 08:14:03.184450 | 
2026-01-21 08:14:03.184550 | TASK [start-zuul-console : Start zuul_console daemon.]
2026-01-21 08:14:04.092024 | controller | ok
2026-01-21 08:14:04.122815 | 
2026-01-21 08:14:04.122943 | TASK [include_role : add-build-sshkey]
2026-01-21 08:14:04.230782 | controller | ok
2026-01-21 08:14:04.338086 | 
2026-01-21 08:14:04.338222 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-01-21 08:14:05.671684 | controller -> localhost | ok
2026-01-21 08:14:05.677531 | 
2026-01-21 08:14:05.677626 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-01-21 08:14:05.777683 | controller | ok
2026-01-21 08:14:05.846727 | controller | included: /var/lib/zuul/builds/798c83b8c7b546458fe0cf737d87cb07/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-01-21 08:14:05.887749 | 
2026-01-21 08:14:05.887854 | TASK [add-build-sshkey : Create Temp SSH key]
2026-01-21 08:14:07.862822 | controller -> localhost | Generating public/private rsa key pair.
2026-01-21 08:14:07.863022 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/798c83b8c7b546458fe0cf737d87cb07/work/798c83b8c7b546458fe0cf737d87cb07_id_rsa.
2026-01-21 08:14:07.863062 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/798c83b8c7b546458fe0cf737d87cb07/work/798c83b8c7b546458fe0cf737d87cb07_id_rsa.pub.
2026-01-21 08:14:07.863088 | controller -> localhost | The key fingerprint is:
2026-01-21 08:14:07.863110 | controller -> localhost | SHA256:f/VDwakX4f59xoelKnIs5t4rNlslxEuvd/iy6fxOqFM zuul-build-sshkey
2026-01-21 08:14:07.863130 | controller -> localhost | The key's randomart image is:
2026-01-21 08:14:07.863150 | controller -> localhost | +---[RSA 3072]----+
2026-01-21 08:14:07.863169 | controller -> localhost | |               . |
2026-01-21 08:14:07.863202 | controller -> localhost | |         .    o o|
2026-01-21 08:14:07.863223 | controller -> localhost | |          +    * |
2026-01-21 08:14:07.863243 | controller -> localhost | |         o o  o o|
2026-01-21 08:14:07.863262 | controller -> localhost | |        S o o..+.|
2026-01-21 08:14:07.863280 | controller -> localhost | |         . +E+o*o|
2026-01-21 08:14:07.863329 | controller -> localhost | |         .+.= =.O|
2026-01-21 08:14:07.863354 | controller -> localhost | |        Bo==.* .+|
2026-01-21 08:14:07.863374 | controller -> localhost | |       =+B+=*=+  |
2026-01-21 08:14:07.863393 | controller -> localhost | +----[SHA256]-----+
2026-01-21 08:14:07.863442 | controller -> localhost | ok: Runtime: 0:00:00.078516
2026-01-21 08:14:07.873244 | 
2026-01-21 08:14:07.873333 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-01-21 08:14:07.969506 | controller | ok
2026-01-21 08:14:08.002400 | controller | included: /var/lib/zuul/builds/798c83b8c7b546458fe0cf737d87cb07/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-01-21 08:14:08.059203 | 
2026-01-21 08:14:08.059330 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-01-21 08:14:08.158817 | controller | skipping: Conditional result was False
2026-01-21 08:14:08.167090 | 
2026-01-21 08:14:08.167207 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-01-21 08:14:09.130934 | controller | changed
2026-01-21 08:14:09.147381 | 
2026-01-21 08:14:09.147466 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-01-21 08:14:09.562063 | controller | ok
2026-01-21 08:14:09.581327 | 
2026-01-21 08:14:09.581406 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-01-21 08:14:10.396708 | controller | changed
2026-01-21 08:14:10.412076 | 
2026-01-21 08:14:10.412167 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-01-21 08:14:11.278497 | controller | changed
2026-01-21 08:14:11.284005 | 
2026-01-21 08:14:11.284130 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-01-21 08:14:11.381348 | controller | skipping: Conditional result was False
2026-01-21 08:14:11.389809 | 
2026-01-21 08:14:11.389888 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-01-21 08:14:12.849552 | controller -> localhost | changed
2026-01-21 08:14:12.864994 | 
2026-01-21 08:14:12.865078 | TASK [add-build-sshkey : Add back temp key]
2026-01-21 08:14:14.166495 | controller -> localhost | Identity added: /var/lib/zuul/builds/798c83b8c7b546458fe0cf737d87cb07/work/798c83b8c7b546458fe0cf737d87cb07_id_rsa (zuul-build-sshkey)
2026-01-21 08:14:14.166747 | controller -> localhost | ok: Runtime: 0:00:00.051598
2026-01-21 08:14:14.173403 | 
2026-01-21 08:14:14.173487 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-01-21 08:14:15.082519 | controller | ok
2026-01-21 08:14:15.088115 | 
2026-01-21 08:14:15.088207 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-01-21 08:14:15.148379 | controller | skipping: Conditional result was False
2026-01-21 08:14:15.159864 | 
2026-01-21 08:14:15.162014 | TASK [include_role : validate-host]
2026-01-21 08:14:15.218660 | controller | ok
2026-01-21 08:14:15.292476 | 
2026-01-21 08:14:15.292568 | TASK [validate-host : Define zuul_info_dir fact]
2026-01-21 08:14:15.355696 | controller | ok
2026-01-21 08:14:15.361713 | 
2026-01-21 08:14:15.361788 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2026-01-21 08:14:16.672737 | controller -> localhost | ok
2026-01-21 08:14:16.679003 | 
2026-01-21 08:14:16.679090 | TASK [validate-host : Collect information about the host]
2026-01-21 08:14:17.315404 | controller | ok
2026-01-21 08:14:17.328647 | 
2026-01-21 08:14:17.341992 | TASK [validate-host : Sanitize hostname]
2026-01-21 08:14:17.496802 | controller | ok
2026-01-21 08:14:17.504177 | 
2026-01-21 08:14:17.504275 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2026-01-21 08:14:19.675989 | controller -> localhost | changed
2026-01-21 08:14:19.682858 | 
2026-01-21 08:14:19.682950 | TASK [validate-host : Collect information about zuul worker]
2026-01-21 08:14:20.578160 | controller | ok
2026-01-21 08:14:20.582990 | 
2026-01-21 08:14:20.583070 | TASK [validate-host : Write out all zuul information for each host]
2026-01-21 08:14:22.058707 | controller -> localhost | changed
2026-01-21 08:14:22.074723 | 
2026-01-21 08:14:22.074815 | TASK [include_role : prepare-workspace-openshift]
2026-01-21 08:14:22.139120 | controller | skipping: Conditional result was False
2026-01-21 08:14:22.144594 | 
2026-01-21 08:14:22.144680 | TASK [include_role : remove-zuul-sshkey]
2026-01-21 08:14:22.223701 | controller | skipping: Conditional result was False
2026-01-21 08:14:22.229946 | 
2026-01-21 08:14:22.230036 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-01-21 08:14:22.506316 | controller | ok: "logs"
2026-01-21 08:14:22.506531 | controller | ok: All items complete
2026-01-21 08:14:22.506562 | 
2026-01-21 08:14:22.698648 | controller | ok: "artifacts"
2026-01-21 08:14:22.887502 | controller | ok: "docs"
2026-01-21 08:14:22.897983 | 
2026-01-21 08:14:22.898092 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-01-21 08:14:23.167513 | controller | changed: "logs"
2026-01-21 08:14:23.349736 | controller | changed: "artifacts"
2026-01-21 08:14:23.638103 | controller | changed: "docs"
2026-01-21 08:14:23.853140 | 
2026-01-21 08:14:23.853467 | PLAY RECAP
2026-01-21 08:14:23.854590 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2026-01-21 08:14:23.854648 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-01-21 08:14:23.854676 | 
2026-01-21 08:14:24.334009 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2026-01-21 08:14:24.334723 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2026-01-21 08:14:26.463018 | 
2026-01-21 08:14:26.463556 | PLAY [all]
2026-01-21 08:14:26.505466 | 
2026-01-21 08:14:26.505567 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2026-01-21 08:14:26.607752 | controller | ok
2026-01-21 08:14:26.624054 | 
2026-01-21 08:14:26.624155 | TASK [mirror-info-fork : Create /etc/ci]
2026-01-21 08:14:27.426223 | controller | changed
2026-01-21 08:14:27.434480 | 
2026-01-21 08:14:27.434564 | TASK [mirror-info-fork : Install ci_mirror script]
2026-01-21 08:14:28.999259 | controller | changed
2026-01-21 08:14:29.034126 | 
2026-01-21 08:14:29.034249 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2026-01-21 08:14:29.863939 | controller | changed:
2026-01-21 08:14:29.864100 | controller | {
2026-01-21 08:14:29.864134 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2026-01-21 08:14:29.864165 | controller | }
2026-01-21 08:14:30.085784 | controller | changed:
2026-01-21 08:14:30.086159 | controller | {
2026-01-21 08:14:30.086219 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2026-01-21 08:14:30.086246 | controller | }
2026-01-21 08:14:30.319041 | controller | changed:
2026-01-21 08:14:30.319120 | controller | {
2026-01-21 08:14:30.319407 | 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 08:14:30.319446 | controller | }
2026-01-21 08:14:30.541337 | controller | changed:
2026-01-21 08:14:30.541424 | controller | {
2026-01-21 08:14:30.541453 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2026-01-21 08:14:30.541476 | controller | }
2026-01-21 08:14:30.790177 | controller | changed:
2026-01-21 08:14:30.790277 | controller | {
2026-01-21 08:14:30.790326 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2026-01-21 08:14:30.790352 | controller | }
2026-01-21 08:14:31.009610 | controller | changed:
2026-01-21 08:14:31.009697 | controller | {
2026-01-21 08:14:31.009728 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2026-01-21 08:14:31.009751 | controller | }
2026-01-21 08:14:31.227954 | controller | changed:
2026-01-21 08:14:31.228030 | controller | {
2026-01-21 08:14:31.228058 | 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 08:14:31.228084 | controller | }
2026-01-21 08:14:31.452205 | controller | changed:
2026-01-21 08:14:31.453139 | controller | {
2026-01-21 08:14:31.453203 | 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 08:14:31.453234 | controller | }
2026-01-21 08:14:31.740050 | controller | changed:
2026-01-21 08:14:31.740151 | controller | {
2026-01-21 08:14:31.740180 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2026-01-21 08:14:31.740216 | controller | }
2026-01-21 08:14:31.912115 | controller | changed:
2026-01-21 08:14:31.912581 | controller | {
2026-01-21 08:14:31.912628 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2026-01-21 08:14:31.912654 | controller | }
2026-01-21 08:14:32.155242 | controller | changed:
2026-01-21 08:14:32.156992 | controller | {
2026-01-21 08:14:32.157041 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2026-01-21 08:14:32.157067 | controller | }
2026-01-21 08:14:32.381203 | controller | changed:
2026-01-21 08:14:32.381858 | controller | {
2026-01-21 08:14:32.381915 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2026-01-21 08:14:32.381944 | controller | }
2026-01-21 08:14:32.621516 | controller | changed:
2026-01-21 08:14:32.621604 | controller | {
2026-01-21 08:14:32.621631 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2026-01-21 08:14:32.621653 | controller | }
2026-01-21 08:14:32.876809 | controller | changed:
2026-01-21 08:14:32.876898 | controller | {
2026-01-21 08:14:32.876926 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2026-01-21 08:14:32.876948 | controller | }
2026-01-21 08:14:33.101934 | controller | changed:
2026-01-21 08:14:33.102025 | controller | {
2026-01-21 08:14:33.102056 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2026-01-21 08:14:33.102080 | controller | }
2026-01-21 08:14:33.353023 | controller | changed:
2026-01-21 08:14:33.353100 | controller | {
2026-01-21 08:14:33.353128 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2026-01-21 08:14:33.353150 | controller | }
2026-01-21 08:14:33.603583 | controller | changed:
2026-01-21 08:14:33.603680 | controller | {
2026-01-21 08:14:33.603715 | 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 08:14:33.603740 | controller | }
2026-01-21 08:14:33.832760 | controller | changed:
2026-01-21 08:14:33.832894 | controller | {
2026-01-21 08:14:33.832928 | 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 08:14:33.832960 | controller | }
2026-01-21 08:14:34.071075 | controller | changed:
2026-01-21 08:14:34.071159 | controller | {
2026-01-21 08:14:34.071187 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2026-01-21 08:14:34.071223 | controller | }
2026-01-21 08:14:34.315057 | controller | changed:
2026-01-21 08:14:34.315145 | controller | {
2026-01-21 08:14:34.315173 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2026-01-21 08:14:34.315208 | controller | }
2026-01-21 08:14:34.546034 | controller | changed:
2026-01-21 08:14:34.546125 | controller | {
2026-01-21 08:14:34.546154 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2026-01-21 08:14:34.546177 | controller | }
2026-01-21 08:14:34.771741 | controller | changed:
2026-01-21 08:14:34.771812 | controller | {
2026-01-21 08:14:34.771840 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2026-01-21 08:14:34.771861 | controller | }
2026-01-21 08:14:35.001129 | controller | changed:
2026-01-21 08:14:35.001221 | controller | {
2026-01-21 08:14:35.001252 | 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 08:14:35.001284 | controller | }
2026-01-21 08:14:35.230640 | controller | changed:
2026-01-21 08:14:35.230720 | controller | {
2026-01-21 08:14:35.230748 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2026-01-21 08:14:35.230769 | controller | }
2026-01-21 08:14:35.477399 | controller | changed:
2026-01-21 08:14:35.477481 | controller | {
2026-01-21 08:14:35.477508 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2026-01-21 08:14:35.477529 | controller | }
2026-01-21 08:14:35.707265 | controller | changed:
2026-01-21 08:14:35.707384 | controller | {
2026-01-21 08:14:35.707415 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2026-01-21 08:14:35.707438 | controller | }
2026-01-21 08:14:35.741161 | 
2026-01-21 08:14:35.741255 | TASK [Set timezone to UTC]
2026-01-21 08:14:36.528422 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2026-01-21 08:14:36.534412 | 
2026-01-21 08:14:36.534495 | TASK [Create nodepool directory]
2026-01-21 08:14:36.824179 | controller | changed
2026-01-21 08:14:36.839808 | 
2026-01-21 08:14:36.839907 | TASK [Create nodepool sub_nodes file]
2026-01-21 08:14:37.555259 | controller | changed
2026-01-21 08:14:37.560589 | 
2026-01-21 08:14:37.560682 | TASK [Create nodepool sub_nodes_private file]
2026-01-21 08:14:38.181653 | controller | changed
2026-01-21 08:14:38.200526 | 
2026-01-21 08:14:38.200628 | LOOP [Populate nodepool sub_nodes file]
2026-01-21 08:14:38.293242 | 
2026-01-21 08:14:38.293444 | LOOP [Populate nodepool sub_nodes_private file]
2026-01-21 08:14:38.346820 | 
2026-01-21 08:14:38.346969 | TASK [Create nodepool primary file]
2026-01-21 08:14:38.392506 | controller | skipping: Conditional result was False
2026-01-21 08:14:38.398412 | 
2026-01-21 08:14:38.398516 | TASK [Create nodepool node_private for this node]
2026-01-21 08:14:39.110837 | controller | changed
2026-01-21 08:14:39.116660 | 
2026-01-21 08:14:39.116729 | LOOP [Copy ssh keys to nodepool directory]
2026-01-21 08:14:39.685963 | controller | ok: Item: id_rsa Runtime: 0:00:00.004165
2026-01-21 08:14:39.686143 | 
2026-01-21 08:14:39.852611 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.004073
2026-01-21 08:14:39.881850 | 
2026-01-21 08:14:39.881951 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2026-01-21 08:14:40.594740 | controller | changed
2026-01-21 08:14:40.634079 | 
2026-01-21 08:14:40.634174 | TASK [Validate sudoers config after edits]
2026-01-21 08:14:40.962412 | controller | /etc/sudoers: parsed OK
2026-01-21 08:14:40.962483 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2026-01-21 08:14:40.962495 | controller | /etc/sudoers.d/zuul: parsed OK
2026-01-21 08:14:40.962503 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2026-01-21 08:14:41.262717 | controller | ok: Runtime: 0:00:00.005237
2026-01-21 08:14:41.275927 | 
2026-01-21 08:14:41.276042 | TASK [Show the environment passed in to job shell scripts]
2026-01-21 08:14:41.631561 | controller | SHELL=/bin/bash
2026-01-21 08:14:41.631605 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2026-01-21 08:14:41.631615 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2026-01-21 08:14:41.631623 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/51/3451/3b80a23c9439dd2e7554486894e6cc29bcabd667
2026-01-21 08:14:41.631629 | controller | PWD=/home/zuul
2026-01-21 08:14:41.631635 | controller | ZUUL_PIPELINE=github-check
2026-01-21 08:14:41.631640 | controller | LOGNAME=zuul
2026-01-21 08:14:41.631646 | controller | XDG_SESSION_TYPE=tty
2026-01-21 08:14:41.631651 | controller | _=/usr/bin/env
2026-01-21 08:14:41.631657 | controller | MOTD_SHOWN=pam
2026-01-21 08:14:41.631697 | controller | HOME=/home/zuul
2026-01-21 08:14:41.631706 | controller | LANG=en_US.UTF-8
2026-01-21 08:14:41.631712 | controller | SSH_CONNECTION=192.168.26.12 38470 192.168.26.150 22
2026-01-21 08:14:41.631718 | 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 08:14:41.631726 | controller | ZUUL_CHANGE_IDS=3451,3b80a23c9439dd2e7554486894e6cc29bcabd667
2026-01-21 08:14:41.631732 | controller | WORKSPACE=/home/zuul/workspace
2026-01-21 08:14:41.631737 | controller | XDG_SESSION_CLASS=user
2026-01-21 08:14:41.631743 | controller | SELINUX_ROLE_REQUESTED=
2026-01-21 08:14:41.631748 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2026-01-21 08:14:41.631753 | controller | USER=zuul
2026-01-21 08:14:41.631759 | controller | ZUUL_VOTING=True
2026-01-21 08:14:41.631764 | controller | BUILD_TIMEOUT=1800000
2026-01-21 08:14:41.631770 | controller | SELINUX_USE_CURRENT_RANGE=
2026-01-21 08:14:41.631775 | controller | SHLVL=1
2026-01-21 08:14:41.631781 | controller | ZUUL_PATCHSET=3b80a23c9439dd2e7554486894e6cc29bcabd667
2026-01-21 08:14:41.631786 | controller | XDG_SESSION_ID=1
2026-01-21 08:14:41.631792 | controller | ZUUL_BRANCH=main
2026-01-21 08:14:41.631797 | controller | XDG_RUNTIME_DIR=/run/user/1000
2026-01-21 08:14:41.631802 | controller | SSH_CLIENT=192.168.26.12 38470 22
2026-01-21 08:14:41.631810 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2026-01-21 08:14:41.631815 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2026-01-21 08:14:41.631821 | controller | which_declare=declare -f
2026-01-21 08:14:41.631826 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2026-01-21 08:14:41.631832 | controller | SELINUX_LEVEL_REQUESTED=
2026-01-21 08:14:41.631837 | controller | ZUUL_CHANGE=3451
2026-01-21 08:14:41.631843 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2026-01-21 08:14:41.631848 | controller | ZUUL_UUID=798c83b8c7b546458fe0cf737d87cb07
2026-01-21 08:14:41.631854 | controller | BASH_FUNC_which%%=() {  ( alias;
2026-01-21 08:14:41.631859 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2026-01-21 08:14:41.631864 | controller | }
2026-01-21 08:14:41.851848 | controller | ok: Runtime: 0:00:00.005578
2026-01-21 08:14:41.858825 | 
2026-01-21 08:14:41.858914 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2026-01-21 08:14:41.904237 | controller | skipping: Conditional result was False
2026-01-21 08:14:41.919060 | 
2026-01-21 08:14:41.919167 | TASK [Symlink /home/zuul-worker/workspace]
2026-01-21 08:14:42.507786 | controller | skipping: Conditional result was False
2026-01-21 08:14:42.513522 | 
2026-01-21 08:14:42.513593 | TASK [Ensure legacy workspace directory]
2026-01-21 08:14:42.826331 | controller | changed
2026-01-21 08:14:42.908092 | 
2026-01-21 08:14:42.908176 | PLAY RECAP
2026-01-21 08:14:42.908238 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2026-01-21 08:14:42.908265 | 
2026-01-21 08:14:43.111258 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2026-01-21 08:14:43.112036 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2026-01-21 08:14:44.454159 | 
2026-01-21 08:14:44.454281 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2026-01-21 08:14:44.495819 | 
2026-01-21 08:14:44.495924 | TASK [Create zuul-output directory]
2026-01-21 08:14:44.962462 | controller | changed
2026-01-21 08:14:44.969415 | 
2026-01-21 08:14:44.969515 | TASK [Slurp Zuul inventory test]
2026-01-21 08:14:45.782923 | controller -> localhost | ok
2026-01-21 08:14:45.794112 | 
2026-01-21 08:14:45.794250 | TASK [Save zuul inventory]
2026-01-21 08:14:46.690963 | controller | changed
2026-01-21 08:14:46.699794 | 
2026-01-21 08:14:46.699904 | TASK [Save zuul vars without the change_message]
2026-01-21 08:14:47.385337 | controller | changed
2026-01-21 08:14:47.427757 | 
2026-01-21 08:14:47.427873 | PLAY RECAP
2026-01-21 08:14:47.427946 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-01-21 08:14:47.427972 | 
2026-01-21 08:14:47.560031 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2026-01-21 08:14:47.560854 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2026-01-21 08:14:48.191571 | 
2026-01-21 08:14:48.191682 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2026-01-21 08:14:48.212982 | 
2026-01-21 08:14:48.213064 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2026-01-21 08:14:48.231504 | controller | ok
2026-01-21 08:14:48.251924 | 
2026-01-21 08:14:48.252031 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2026-01-21 08:14:48.276122 | controller | skipping: Conditional result was False
2026-01-21 08:14:48.282554 | 
2026-01-21 08:14:48.282640 | TASK [mirror-info-fork : Create /etc/ci]
2026-01-21 08:14:48.618453 | controller | ok
2026-01-21 08:14:48.624165 | 
2026-01-21 08:14:48.624266 | TASK [mirror-info-fork : Install ci_mirror script]
2026-01-21 08:14:49.230000 | controller | ok
2026-01-21 08:14:49.241286 | 
2026-01-21 08:14:49.241386 | TASK [Prepare workspace]
2026-01-21 08:14:49.260493 | controller | ok
2026-01-21 08:14:49.278582 | 
2026-01-21 08:14:49.278655 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-01-21 08:14:49.577927 | controller | ok
2026-01-21 08:14:49.589218 | 
2026-01-21 08:14:49.589324 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-01-21 08:14:52.474406 | controller | Output suppressed because no_log was given
2026-01-21 08:14:52.487219 | 
2026-01-21 08:14:52.487326 | LOOP [Create zuul-output directory]
2026-01-21 08:14:52.728669 | controller | changed: "/home/zuul/ci-framework-data/logs"
2026-01-21 08:14:52.896550 | controller | ok: "/home/zuul/zuul-output/logs"
2026-01-21 08:14:52.919329 | 
2026-01-21 08:14:52.919425 | TASK [Install required packages]
2026-01-21 08:15:30.632964 | controller | changed
2026-01-21 08:15:30.639108 | 
2026-01-21 08:15:30.639192 | TASK [Install venv]
2026-01-21 08:16:33.508515 | controller | changed
2026-01-21 08:16:33.534996 | 
2026-01-21 08:16:33.535104 | PLAY RECAP
2026-01-21 08:16:33.535150 | controller | ok: 7 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-01-21 08:16:33.535174 | 
2026-01-21 08:16:33.627987 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2026-01-21 08:16:33.628862 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2026-01-21 08:16:34.208194 | 
2026-01-21 08:16:34.208318 | PLAY [Run ci/playbooks/molecule-test.yml]
2026-01-21 08:16:34.230767 | 
2026-01-21 08:16:34.230851 | TASK [Gather required facts]
2026-01-21 08:16:34.739639 | controller | ok
2026-01-21 08:16:34.745335 | 
2026-01-21 08:16:34.745432 | TASK [Load environment var if instructed to]
2026-01-21 08:16:34.770280 | controller | skipping: Conditional result was False
2026-01-21 08:16:34.777451 | 
2026-01-21 08:16:34.777605 | TASK [Ensure group_vars dir exists]
2026-01-21 08:16:35.091431 | controller | ok
2026-01-21 08:16:35.097631 | 
2026-01-21 08:16:35.097707 | TASK [Print related variables]
2026-01-21 08:16:35.126018 | controller | ok:
2026-01-21 08:16:35.126144 | controller | mol_config_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config_local.yml
2026-01-21 08:16:35.126175 | controller | roles_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/run_hook
2026-01-21 08:16:35.131085 | 
2026-01-21 08:16:35.131153 | TASK [Run molecule]
2026-01-21 08:16:36.096866 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2026-01-21 08:16:36.175844 | controller | INFO     Running default > prepare
2026-01-21 08:16:36.854116 | controller |
2026-01-21 08:16:36.854248 | controller | PLAY [Prepare] *****************************************************************
2026-01-21 08:16:36.854344 | controller |
2026-01-21 08:16:36.854830 | controller | TASK [Gathering Facts] *********************************************************
2026-01-21 08:16:36.854946 | controller | Wednesday 21 January 2026  08:16:36 +0000 (0:00:00.011)       0:00:00.011 *****
2026-01-21 08:16:37.699887 | controller | ok: [instance]
2026-01-21 08:16:37.699939 | controller |
2026-01-21 08:16:37.700063 | controller | TASK [Create dummy env file] ***************************************************
2026-01-21 08:16:37.700171 | controller | Wednesday 21 January 2026  08:16:37 +0000 (0:00:00.846)       0:00:00.857 *****
2026-01-21 08:16:38.213457 | controller | changed: [instance]
2026-01-21 08:16:38.213527 | controller |
2026-01-21 08:16:38.213631 | controller | TASK [Create dummy playbook] ***************************************************
2026-01-21 08:16:38.213731 | controller | Wednesday 21 January 2026  08:16:38 +0000 (0:00:00.513)       0:00:01.371 *****
2026-01-21 08:16:40.147228 | controller | changed: [instance] => (item=dummy-1.yml)
2026-01-21 08:16:40.147313 | controller | changed: [instance] => (item=dummy-2.yml)
2026-01-21 08:16:40.147415 | controller | changed: [instance] => (item=dummy-3.yml)
2026-01-21 08:16:40.147513 | controller | changed: [instance] => (item=dummy-4.yml)
2026-01-21 08:16:40.147607 | controller | changed: [instance] => (item=dummy-5.yml)
2026-01-21 08:16:40.147714 | controller | changed: [instance] => (item=dummy-6.yml)
2026-01-21 08:16:40.147808 | controller |
2026-01-21 08:16:40.147904 | controller | TASK [Remove dummy file for retry playbook test] *******************************
2026-01-21 08:16:40.148047 | controller | Wednesday 21 January 2026  08:16:40 +0000 (0:00:01.933)       0:00:03.304 *****
2026-01-21 08:16:40.424074 | controller | ok: [instance]
2026-01-21 08:16:40.424142 | controller |
2026-01-21 08:16:40.424278 | controller | TASK [Create dummy retry playbook] *********************************************
2026-01-21 08:16:40.424378 | controller | Wednesday 21 January 2026  08:16:40 +0000 (0:00:00.276)       0:00:03.581 *****
2026-01-21 08:16:40.763217 | controller | changed: [instance]
2026-01-21 08:16:40.763301 | controller |
2026-01-21 08:16:40.763415 | controller | PLAY RECAP *********************************************************************
2026-01-21 08:16:40.763532 | controller | instance                   : ok=5    changed=3    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-01-21 08:16:40.763632 | controller |
2026-01-21 08:16:40.763731 | controller | Wednesday 21 January 2026  08:16:40 +0000 (0:00:00.339)       0:00:03.921 *****
2026-01-21 08:16:40.763833 | controller | ===============================================================================
2026-01-21 08:16:40.763943 | controller | Create dummy playbook --------------------------------------------------- 1.93s
2026-01-21 08:16:40.764071 | controller | Gathering Facts --------------------------------------------------------- 0.85s
2026-01-21 08:16:40.764185 | controller | Create dummy env file --------------------------------------------------- 0.51s
2026-01-21 08:16:40.764285 | controller | Create dummy retry playbook --------------------------------------------- 0.34s
2026-01-21 08:16:40.764383 | controller | Remove dummy file for retry playbook test ------------------------------- 0.28s
2026-01-21 08:16:40.817873 | controller | INFO     Running default > converge
2026-01-21 08:16:41.244180 | controller |
2026-01-21 08:16:41.244602 | controller | PLAY [Converge] ****************************************************************
2026-01-21 08:16:42.037899 | controller |
2026-01-21 08:16:42.037932 | controller | TASK [Gathering Facts] *********************************************************
2026-01-21 08:16:42.037943 | controller | Wednesday 21 January 2026  08:16:41 +0000 (0:00:00.016)       0:00:00.016 *****
2026-01-21 08:16:42.037953 | controller | ok: [instance]
2026-01-21 08:16:42.067811 | controller |
2026-01-21 08:16:42.067847 | controller | TASK [No hook] *****************************************************************
2026-01-21 08:16:42.067856 | controller | Wednesday 21 January 2026  08:16:42 +0000 (0:00:00.793)       0:00:00.809 *****
2026-01-21 08:16:42.067868 | controller |
2026-01-21 08:16:42.127648 | controller | TASK [run_hook : Assert parameters are valid] **********************************
2026-01-21 08:16:42.127698 | controller | Wednesday 21 January 2026  08:16:42 +0000 (0:00:00.029)       0:00:00.839 *****
2026-01-21 08:16:42.127710 | controller | ok: [instance]
2026-01-21 08:16:42.209069 | controller |
2026-01-21 08:16:42.209109 | controller | TASK [run_hook : Assert single hooks are all mappings] *************************
2026-01-21 08:16:42.209119 | controller | Wednesday 21 January 2026  08:16:42 +0000 (0:00:00.059)       0:00:00.899 *****
2026-01-21 08:16:42.209132 | controller | ok: [instance]
2026-01-21 08:16:42.291123 | controller |
2026-01-21 08:16:42.291187 | controller | TASK [run_hook : Loop on hooks for no_hook] ************************************
2026-01-21 08:16:42.291201 | controller | Wednesday 21 January 2026  08:16:42 +0000 (0:00:00.081)       0:00:00.980 *****
2026-01-21 08:16:42.291216 | controller | skipping: [instance]
2026-01-21 08:16:42.291250 | controller |
2026-01-21 08:16:42.292581 | controller | TASK [Ensure we do not have ceph_uuid] *****************************************
2026-01-21 08:16:42.317002 | controller | Wednesday 21 January 2026  08:16:42 +0000 (0:00:00.082)       0:00:01.063 *****
2026-01-21 08:16:42.317041 | controller | ok: [instance] => changed=false
2026-01-21 08:16:42.347941 | controller |   msg: All assertions passed
2026-01-21 08:16:42.347965 | controller |
2026-01-21 08:16:42.347995 | controller | TASK [Combined hooks] **********************************************************
2026-01-21 08:16:42.348006 | controller | Wednesday 21 January 2026  08:16:42 +0000 (0:00:00.025)       0:00:01.088 *****
2026-01-21 08:16:42.348018 | controller |
2026-01-21 08:16:42.406909 | controller | TASK [run_hook : Assert parameters are valid] **********************************
2026-01-21 08:16:42.406936 | controller | Wednesday 21 January 2026  08:16:42 +0000 (0:00:00.031)       0:00:01.120 *****
2026-01-21 08:16:42.406948 | controller | ok: [instance]
2026-01-21 08:16:42.407133 | controller |
2026-01-21 08:16:42.407307 | controller | TASK [run_hook : Assert single hooks are all mappings] *************************
2026-01-21 08:16:42.407448 | controller | Wednesday 21 January 2026  08:16:42 +0000 (0:00:00.059)       0:00:01.179 *****
2026-01-21 08:16:42.487546 | controller | ok: [instance]
2026-01-21 08:16:42.487717 | controller |
2026-01-21 08:16:42.487873 | controller | TASK [run_hook : Loop on hooks for run_molecule] *******************************
2026-01-21 08:16:42.488040 | controller | Wednesday 21 January 2026  08:16:42 +0000 (0:00:00.080)       0:00:01.259 *****
2026-01-21 08:16:42.617719 | 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 08:16:42.617967 | 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 08:16:42.618199 | 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 08:16:42.618523 | controller |
2026-01-21 08:16:42.618661 | controller | TASK [run_hook : Set playbook path for 01 Default noop hook] *******************
2026-01-21 08:16:42.618795 | controller | Wednesday 21 January 2026  08:16:42 +0000 (0:00:00.129)       0:00:01.389 *****
2026-01-21 08:16:42.666562 | controller | ok: [instance]
2026-01-21 08:16:42.666766 | controller |
2026-01-21 08:16:42.666941 | controller | TASK [run_hook : Get file stat] ************************************************
2026-01-21 08:16:42.667135 | controller | Wednesday 21 January 2026  08:16:42 +0000 (0:00:00.048)       0:00:01.438 *****
2026-01-21 08:16:42.967046 | controller | ok: [instance]
2026-01-21 08:16:42.983842 | controller |
2026-01-21 08:16:42.983870 | controller | TASK [run_hook : Fail if playbook doesn't exist] *******************************
2026-01-21 08:16:42.983881 | controller | Wednesday 21 January 2026  08:16:42 +0000 (0:00:00.300)       0:00:01.738 *****
2026-01-21 08:16:42.983891 | controller | skipping: [instance]
2026-01-21 08:16:43.272811 | controller |
2026-01-21 08:16:43.272840 | controller | TASK [run_hook : Get parameters files] *****************************************
2026-01-21 08:16:43.272848 | controller | Wednesday 21 January 2026  08:16:42 +0000 (0:00:00.016)       0:00:01.755 *****
2026-01-21 08:16:43.272864 | controller | ok: [instance]
2026-01-21 08:16:43.293418 | controller |
2026-01-21 08:16:43.293444 | controller | TASK [run_hook : Add parameters artifacts as extra variables] ******************
2026-01-21 08:16:43.293451 | controller | Wednesday 21 January 2026  08:16:43 +0000 (0:00:00.288)       0:00:02.044 *****
2026-01-21 08:16:43.293461 | controller | ok: [instance]
2026-01-21 08:16:43.594013 | controller |
2026-01-21 08:16:43.594047 | controller | TASK [run_hook : Ensure log directory exists] **********************************
2026-01-21 08:16:43.594056 | controller | Wednesday 21 January 2026  08:16:43 +0000 (0:00:00.020)       0:00:02.065 *****
2026-01-21 08:16:43.594066 | controller | ok: [instance]
2026-01-21 08:16:43.777614 | controller |
2026-01-21 08:16:43.777649 | controller | TASK [run_hook : Ensure artifacts directory exists] ****************************
2026-01-21 08:16:43.777658 | controller | Wednesday 21 January 2026  08:16:43 +0000 (0:00:00.300)       0:00:02.365 *****
2026-01-21 08:16:43.777669 | controller | ok: [instance]
2026-01-21 08:16:44.377842 | controller |
2026-01-21 08:16:44.377879 | controller | TASK [run_hook : Run hook without retry - 01 Default noop hook] ****************
2026-01-21 08:16:44.377888 | controller | Wednesday 21 January 2026  08:16:43 +0000 (0:00:00.183)       0:00:02.549 *****
2026-01-21 08:16:44.377899 | controller | Follow script's output here: /home/zuul/ci-framework-data/logs/ci_script_000_run_hook_without_retry_01.log
2026-01-21 08:16:44.408418 | controller | changed: [instance]
2026-01-21 08:16:44.408453 | controller |
2026-01-21 08:16:44.408461 | controller | TASK [run_hook : Run hook with retry - 01 Default noop hook] *******************
2026-01-21 08:16:44.408471 | controller | Wednesday 21 January 2026  08:16:44 +0000 (0:00:00.600)       0:00:03.149 *****
2026-01-21 08:16:44.408482 | controller | skipping: [instance]
2026-01-21 08:16:44.584217 | controller |
2026-01-21 08:16:44.584259 | controller | TASK [run_hook : Check if we have a file] **************************************
2026-01-21 08:16:44.584268 | controller | Wednesday 21 January 2026  08:16:44 +0000 (0:00:00.030)       0:00:03.180 *****
2026-01-21 08:16:44.584279 | controller | ok: [instance]
2026-01-21 08:16:44.602725 | controller |
2026-01-21 08:16:44.602764 | controller | TASK [run_hook : Load generated content in main playbook] **********************
2026-01-21 08:16:44.602772 | controller | Wednesday 21 January 2026  08:16:44 +0000 (0:00:00.175)       0:00:03.356 *****
2026-01-21 08:16:44.602784 | controller | skipping: [instance]
2026-01-21 08:16:44.651512 | controller |
2026-01-21 08:16:44.651553 | controller | TASK [run_hook : Set playbook path for 02 Re-run noop] *************************
2026-01-21 08:16:44.651562 | controller | Wednesday 21 January 2026  08:16:44 +0000 (0:00:00.018)       0:00:03.374 *****
2026-01-21 08:16:44.651573 | controller | ok: [instance]
2026-01-21 08:16:44.844178 | controller |
2026-01-21 08:16:44.844217 | controller | TASK [run_hook : Get file stat] ************************************************
2026-01-21 08:16:44.844226 | controller | Wednesday 21 January 2026  08:16:44 +0000 (0:00:00.048)       0:00:03.423 *****
2026-01-21 08:16:44.844238 | controller | ok: [instance]
2026-01-21 08:16:44.863846 | controller |
2026-01-21 08:16:44.863888 | controller | TASK [run_hook : Fail if playbook doesn't exist] *******************************
2026-01-21 08:16:44.863897 | controller | Wednesday 21 January 2026  08:16:44 +0000 (0:00:00.192)       0:00:03.616 *****
2026-01-21 08:16:44.863908 | controller | skipping: [instance]
2026-01-21 08:16:45.056441 | controller |
2026-01-21 08:16:45.056487 | controller | TASK [run_hook : Get parameters files] *****************************************
2026-01-21 08:16:45.056496 | controller | Wednesday 21 January 2026  08:16:44 +0000 (0:00:00.019)       0:00:03.635 *****
2026-01-21 08:16:45.056508 | controller | ok: [instance]
2026-01-21 08:16:45.077039 | controller |
2026-01-21 08:16:45.077077 | controller | TASK [run_hook : Add parameters artifacts as extra variables] ******************
2026-01-21 08:16:45.077085 | controller | Wednesday 21 January 2026  08:16:45 +0000 (0:00:00.192)       0:00:03.828 *****
2026-01-21 08:16:45.077098 | controller | ok: [instance]
2026-01-21 08:16:45.281793 | controller |
2026-01-21 08:16:45.281858 | controller | TASK [run_hook : Ensure log directory exists] **********************************
2026-01-21 08:16:45.281868 | controller | Wednesday 21 January 2026  08:16:45 +0000 (0:00:00.020)       0:00:03.849 *****
2026-01-21 08:16:45.281880 | controller | ok: [instance]
2026-01-21 08:16:45.472048 | controller |
2026-01-21 08:16:45.472104 | controller | TASK [run_hook : Ensure artifacts directory exists] ****************************
2026-01-21 08:16:45.472113 | controller | Wednesday 21 January 2026  08:16:45 +0000 (0:00:00.204)       0:00:04.053 *****
2026-01-21 08:16:45.472125 | controller | ok: [instance]
2026-01-21 08:16:45.472230 | controller |
2026-01-21 08:16:45.472265 | controller | TASK [run_hook : Run hook without retry - 02 Re-run noop] **********************
2026-01-21 08:16:46.100569 | controller | Wednesday 21 January 2026  08:16:45 +0000 (0:00:00.190)       0:00:04.244 *****
2026-01-21 08:16:46.100608 | 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 08:16:46.129074 | controller | changed: [instance]
2026-01-21 08:16:46.129109 | controller |
2026-01-21 08:16:46.129117 | controller | TASK [run_hook : Run hook with retry - 02 Re-run noop] *************************
2026-01-21 08:16:46.129124 | controller | Wednesday 21 January 2026  08:16:46 +0000 (0:00:00.628)       0:00:04.872 *****
2026-01-21 08:16:46.129135 | controller | skipping: [instance]
2026-01-21 08:16:46.129173 | controller |
2026-01-21 08:16:46.129182 | controller | TASK [run_hook : Check if we have a file] **************************************
2026-01-21 08:16:46.129297 | controller | Wednesday 21 January 2026  08:16:46 +0000 (0:00:00.028)       0:00:04.901 *****
2026-01-21 08:16:46.304923 | controller | ok: [instance]
2026-01-21 08:16:46.322760 | controller |
2026-01-21 08:16:46.322784 | controller | TASK [run_hook : Load generated content in main playbook] **********************
2026-01-21 08:16:46.322792 | controller | Wednesday 21 January 2026  08:16:46 +0000 (0:00:00.175)       0:00:05.076 *****
2026-01-21 08:16:46.322802 | controller | skipping: [instance]
2026-01-21 08:16:46.370642 | controller |
2026-01-21 08:16:46.370673 | controller | TASK [run_hook : Set playbook path for 03 single hook] *************************
2026-01-21 08:16:46.370682 | controller | Wednesday 21 January 2026  08:16:46 +0000 (0:00:00.018)       0:00:05.094 *****
2026-01-21 08:16:46.370693 | controller | ok: [instance]
2026-01-21 08:16:46.581725 | controller |
2026-01-21 08:16:46.581759 | controller | TASK [run_hook : Get file stat] ************************************************
2026-01-21 08:16:46.581767 | controller | Wednesday 21 January 2026  08:16:46 +0000 (0:00:00.047)       0:00:05.142 *****
2026-01-21 08:16:46.581777 | controller | ok: [instance]
2026-01-21 08:16:46.600794 | controller |
2026-01-21 08:16:46.600819 | controller | TASK [run_hook : Fail if playbook doesn't exist] *******************************
2026-01-21 08:16:46.600827 | controller | Wednesday 21 January 2026  08:16:46 +0000 (0:00:00.209)       0:00:05.352 *****
2026-01-21 08:16:46.600838 | controller | skipping: [instance]
2026-01-21 08:16:46.797436 | controller |
2026-01-21 08:16:46.797467 | controller | TASK [run_hook : Get parameters files] *****************************************
2026-01-21 08:16:46.797475 | controller | Wednesday 21 January 2026  08:16:46 +0000 (0:00:00.020)       0:00:05.372 *****
2026-01-21 08:16:46.797485 | controller | ok: [instance]
2026-01-21 08:16:46.817593 | controller |
2026-01-21 08:16:46.817619 | controller | TASK [run_hook : Add parameters artifacts as extra variables] ******************
2026-01-21 08:16:46.817627 | controller | Wednesday 21 January 2026  08:16:46 +0000 (0:00:00.196)       0:00:05.569 *****
2026-01-21 08:16:46.817637 | controller | ok: [instance]
2026-01-21 08:16:47.014458 | controller |
2026-01-21 08:16:47.014488 | controller | TASK [run_hook : Ensure log directory exists] **********************************
2026-01-21 08:16:47.014496 | controller | Wednesday 21 January 2026  08:16:46 +0000 (0:00:00.020)       0:00:05.589 *****
2026-01-21 08:16:47.014506 | controller | ok: [instance]
2026-01-21 08:16:47.202542 | controller |
2026-01-21 08:16:47.202573 | controller | TASK [run_hook : Ensure artifacts directory exists] ****************************
2026-01-21 08:16:47.202582 | controller | Wednesday 21 January 2026  08:16:47 +0000 (0:00:00.196)       0:00:05.786 *****
2026-01-21 08:16:47.202592 | controller | ok: [instance]
2026-01-21 08:16:49.132703 | controller |
2026-01-21 08:16:49.132735 | controller | TASK [run_hook : Run hook without retry - 03 single hook] **********************
2026-01-21 08:16:49.132743 | controller | Wednesday 21 January 2026  08:16:47 +0000 (0:00:00.188)       0:00:05.974 *****
2026-01-21 08:16:49.132753 | controller | Follow script's output here: /home/zuul/ci-framework-data/logs/ci_script_002_run_hook_without_retry_03.log
2026-01-21 08:16:49.163094 | controller | changed: [instance]
2026-01-21 08:16:49.163136 | controller |
2026-01-21 08:16:49.163147 | controller | TASK [run_hook : Run hook with retry - 03 single hook] *************************
2026-01-21 08:16:49.163165 | controller | Wednesday 21 January 2026  08:16:49 +0000 (0:00:01.930)       0:00:07.904 *****
2026-01-21 08:16:49.163178 | controller | skipping: [instance]
2026-01-21 08:16:49.350016 | controller |
2026-01-21 08:16:49.350059 | controller | TASK [run_hook : Check if we have a file] **************************************
2026-01-21 08:16:49.350072 | controller | Wednesday 21 January 2026  08:16:49 +0000 (0:00:00.030)       0:00:07.935 *****
2026-01-21 08:16:49.350082 | controller | ok: [instance]
2026-01-21 08:16:49.376056 | controller |
2026-01-21 08:16:49.376081 | controller | TASK [run_hook : Load generated content in main playbook] **********************
2026-01-21 08:16:49.376091 | controller | Wednesday 21 January 2026  08:16:49 +0000 (0:00:00.186)       0:00:08.121 *****
2026-01-21 08:16:49.376102 | controller | ok: [instance]
2026-01-21 08:16:49.376280 | controller |
2026-01-21 08:16:49.376297 | controller | TASK [Ensure we have the ceph_uuid variable now] *******************************
2026-01-21 08:16:49.401810 | controller | Wednesday 21 January 2026  08:16:49 +0000 (0:00:00.026)       0:00:08.148 *****
2026-01-21 08:16:49.401838 | controller | ok: [instance] => changed=false
2026-01-21 08:16:49.435722 | controller |   msg: All assertions passed
2026-01-21 08:16:49.435751 | controller |
2026-01-21 08:16:49.435760 | controller | TASK [Only listed hooks] *******************************************************
2026-01-21 08:16:49.435768 | controller | Wednesday 21 January 2026  08:16:49 +0000 (0:00:00.024)       0:00:08.173 *****
2026-01-21 08:16:49.435779 | controller |
2026-01-21 08:16:49.496793 | controller | TASK [run_hook : Assert parameters are valid] **********************************
2026-01-21 08:16:49.496826 | controller | Wednesday 21 January 2026  08:16:49 +0000 (0:00:00.034)       0:00:08.207 *****
2026-01-21 08:16:49.496841 | controller | ok: [instance]
2026-01-21 08:16:49.576941 | controller |
2026-01-21 08:16:49.576994 | controller | TASK [run_hook : Assert single hooks are all mappings] *************************
2026-01-21 08:16:49.577009 | controller | Wednesday 21 January 2026  08:16:49 +0000 (0:00:00.060)       0:00:08.268 *****
2026-01-21 08:16:49.577022 | controller | ok: [instance]
2026-01-21 08:16:49.692072 | controller |
2026-01-21 08:16:49.692108 | controller | TASK [run_hook : Loop on hooks for list_hooks] *********************************
2026-01-21 08:16:49.692118 | controller | Wednesday 21 January 2026  08:16:49 +0000 (0:00:00.080)       0:00:08.348 *****
2026-01-21 08:16:49.692131 | 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 08:16:49.692178 | 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 08:16:49.692187 | controller |
2026-01-21 08:16:49.692197 | controller | TASK [run_hook : Set playbook path for Run dummy-2] ****************************
2026-01-21 08:16:49.692297 | controller | Wednesday 21 January 2026  08:16:49 +0000 (0:00:00.115)       0:00:08.463 *****
2026-01-21 08:16:49.738053 | controller | ok: [instance]
2026-01-21 08:16:49.924592 | controller |
2026-01-21 08:16:49.924621 | controller | TASK [run_hook : Get file stat] ************************************************
2026-01-21 08:16:49.924629 | controller | Wednesday 21 January 2026  08:16:49 +0000 (0:00:00.046)       0:00:08.510 *****
2026-01-21 08:16:49.924639 | controller | ok: [instance]
2026-01-21 08:16:49.941610 | controller |
2026-01-21 08:16:49.941635 | controller | TASK [run_hook : Fail if playbook doesn't exist] *******************************
2026-01-21 08:16:49.941644 | controller | Wednesday 21 January 2026  08:16:49 +0000 (0:00:00.186)       0:00:08.696 *****
2026-01-21 08:16:49.941655 | controller | skipping: [instance]
2026-01-21 08:16:50.126355 | controller |
2026-01-21 08:16:50.126385 | controller | TASK [run_hook : Get parameters files] *****************************************
2026-01-21 08:16:50.126393 | controller | Wednesday 21 January 2026  08:16:49 +0000 (0:00:00.017)       0:00:08.713 *****
2026-01-21 08:16:50.126404 | controller | ok: [instance]
2026-01-21 08:16:50.145396 | controller |
2026-01-21 08:16:50.145416 | controller | TASK [run_hook : Add parameters artifacts as extra variables] ******************
2026-01-21 08:16:50.145424 | controller | Wednesday 21 January 2026  08:16:50 +0000 (0:00:00.184)       0:00:08.898 *****
2026-01-21 08:16:50.145433 | controller | ok: [instance]
2026-01-21 08:16:50.334855 | controller |
2026-01-21 08:16:50.334885 | controller | TASK [run_hook : Ensure log directory exists] **********************************
2026-01-21 08:16:50.334893 | controller | Wednesday 21 January 2026  08:16:50 +0000 (0:00:00.019)       0:00:08.917 *****
2026-01-21 08:16:50.334904 | controller | ok: [instance]
2026-01-21 08:16:50.519480 | controller |
2026-01-21 08:16:50.519511 | controller | TASK [run_hook : Ensure artifacts directory exists] ****************************
2026-01-21 08:16:50.519519 | controller | Wednesday 21 January 2026  08:16:50 +0000 (0:00:00.189)       0:00:09.106 *****
2026-01-21 08:16:50.519529 | controller | ok: [instance]
2026-01-21 08:16:52.458057 | controller |
2026-01-21 08:16:52.458088 | controller | TASK [run_hook : Run hook without retry - Run dummy-2] *************************
2026-01-21 08:16:52.458096 | controller | Wednesday 21 January 2026  08:16:50 +0000 (0:00:00.184)       0:00:09.291 *****
2026-01-21 08:16:52.458106 | controller | Follow script's output here: /home/zuul/ci-framework-data/logs/ci_script_003_run_hook_without_retry_run.log
2026-01-21 08:16:52.491865 | controller | changed: [instance]
2026-01-21 08:16:52.491886 | controller |
2026-01-21 08:16:52.491894 | controller | TASK [run_hook : Run hook with retry - Run dummy-2] ****************************
2026-01-21 08:16:52.491900 | controller | Wednesday 21 January 2026  08:16:52 +0000 (0:00:01.938)       0:00:11.229 *****
2026-01-21 08:16:52.491915 | controller | skipping: [instance]
2026-01-21 08:16:52.701662 | controller |
2026-01-21 08:16:52.701692 | controller | TASK [run_hook : Check if we have a file] **************************************
2026-01-21 08:16:52.701700 | controller | Wednesday 21 January 2026  08:16:52 +0000 (0:00:00.034)       0:00:11.263 *****
2026-01-21 08:16:52.701711 | controller | ok: [instance]
2026-01-21 08:16:52.726519 | controller |
2026-01-21 08:16:52.726547 | controller | TASK [run_hook : Load generated content in main playbook] **********************
2026-01-21 08:16:52.726555 | controller | Wednesday 21 January 2026  08:16:52 +0000 (0:00:00.209)       0:00:11.473 *****
2026-01-21 08:16:52.726565 | controller | ok: [instance]
2026-01-21 08:16:52.776059 | controller |
2026-01-21 08:16:52.776088 | controller | TASK [run_hook : Set playbook path for Run dummy-3] ****************************
2026-01-21 08:16:52.776097 | controller | Wednesday 21 January 2026  08:16:52 +0000 (0:00:00.024)       0:00:11.498 *****
2026-01-21 08:16:52.776109 | controller | ok: [instance]
2026-01-21 08:16:52.973792 | controller |
2026-01-21 08:16:52.973822 | controller | TASK [run_hook : Get file stat] ************************************************
2026-01-21 08:16:52.973830 | controller | Wednesday 21 January 2026  08:16:52 +0000 (0:00:00.049)       0:00:11.547 *****
2026-01-21 08:16:52.973840 | controller | ok: [instance]
2026-01-21 08:16:52.991595 | controller |
2026-01-21 08:16:52.991619 | controller | TASK [run_hook : Fail if playbook doesn't exist] *******************************
2026-01-21 08:16:52.991628 | controller | Wednesday 21 January 2026  08:16:52 +0000 (0:00:00.197)       0:00:11.745 *****
2026-01-21 08:16:52.991639 | controller | skipping: [instance]
2026-01-21 08:16:53.180046 | controller |
2026-01-21 08:16:53.180079 | controller | TASK [run_hook : Get parameters files] *****************************************
2026-01-21 08:16:53.180087 | controller | Wednesday 21 January 2026  08:16:52 +0000 (0:00:00.018)       0:00:11.763 *****
2026-01-21 08:16:53.180098 | controller | ok: [instance]
2026-01-21 08:16:53.180122 | controller |
2026-01-21 08:16:53.180130 | controller | TASK [run_hook : Add parameters artifacts as extra variables] ******************
2026-01-21 08:16:53.180275 | controller | Wednesday 21 January 2026  08:16:53 +0000 (0:00:00.188)       0:00:11.951 *****
2026-01-21 08:16:53.199511 | controller | ok: [instance]
2026-01-21 08:16:53.394039 | controller |
2026-01-21 08:16:53.394068 | controller | TASK [run_hook : Ensure log directory exists] **********************************
2026-01-21 08:16:53.394076 | controller | Wednesday 21 January 2026  08:16:53 +0000 (0:00:00.019)       0:00:11.971 *****
2026-01-21 08:16:53.394086 | controller | ok: [instance]
2026-01-21 08:16:53.394112 | controller |
2026-01-21 08:16:53.394363 | controller | TASK [run_hook : Ensure artifacts directory exists] ****************************
2026-01-21 08:16:53.580860 | controller | Wednesday 21 January 2026  08:16:53 +0000 (0:00:00.194)       0:00:12.166 *****
2026-01-21 08:16:53.580896 | controller | ok: [instance]
2026-01-21 08:16:55.515087 | controller |
2026-01-21 08:16:55.515115 | controller | TASK [run_hook : Run hook without retry - Run dummy-3] *************************
2026-01-21 08:16:55.515123 | controller | Wednesday 21 January 2026  08:16:53 +0000 (0:00:00.186)       0:00:12.352 *****
2026-01-21 08:16:55.515133 | controller | Follow script's output here: /home/zuul/ci-framework-data/logs/ci_script_004_run_hook_without_retry_run.log
2026-01-21 08:16:55.546007 | controller | changed: [instance]
2026-01-21 08:16:55.546034 | controller |
2026-01-21 08:16:55.546043 | controller | TASK [run_hook : Run hook with retry - Run dummy-3] ****************************
2026-01-21 08:16:55.546050 | controller | Wednesday 21 January 2026  08:16:55 +0000 (0:00:01.934)       0:00:14.286 *****
2026-01-21 08:16:55.546062 | controller | skipping: [instance]
2026-01-21 08:16:55.737953 | controller |
2026-01-21 08:16:55.738021 | controller | TASK [run_hook : Check if we have a file] **************************************
2026-01-21 08:16:55.738031 | controller | Wednesday 21 January 2026  08:16:55 +0000 (0:00:00.031)       0:00:14.318 *****
2026-01-21 08:16:55.738044 | controller | ok: [instance]
2026-01-21 08:16:55.764936 | controller |
2026-01-21 08:16:55.764956 | controller | TASK [run_hook : Load generated content in main playbook] **********************
2026-01-21 08:16:55.764963 | controller | Wednesday 21 January 2026  08:16:55 +0000 (0:00:00.191)       0:00:14.509 *****
2026-01-21 08:16:55.764988 | controller | ok: [instance]
2026-01-21 08:16:55.789922 | controller |
2026-01-21 08:16:55.789941 | controller | TASK [Ensure we have the ceph_uuid variable now] *******************************
2026-01-21 08:16:55.789949 | controller | Wednesday 21 January 2026  08:16:55 +0000 (0:00:00.027)       0:00:14.536 *****
2026-01-21 08:16:55.789960 | controller | ok: [instance] => changed=false
2026-01-21 08:16:55.826426 | controller |   msg: All assertions passed
2026-01-21 08:16:55.826444 | controller |
2026-01-21 08:16:55.826452 | controller | TASK [Only filtered hooks] *****************************************************
2026-01-21 08:16:55.826458 | controller | Wednesday 21 January 2026  08:16:55 +0000 (0:00:00.024)       0:00:14.561 *****
2026-01-21 08:16:55.826466 | controller |
2026-01-21 08:16:55.883657 | controller | TASK [run_hook : Assert parameters are valid] **********************************
2026-01-21 08:16:55.883698 | controller | Wednesday 21 January 2026  08:16:55 +0000 (0:00:00.036)       0:00:14.598 *****
2026-01-21 08:16:55.883710 | controller | ok: [instance]
2026-01-21 08:16:55.962732 | controller |
2026-01-21 08:16:55.962752 | controller | TASK [run_hook : Assert single hooks are all mappings] *************************
2026-01-21 08:16:55.962760 | controller | Wednesday 21 January 2026  08:16:55 +0000 (0:00:00.056)       0:00:14.655 *****
2026-01-21 08:16:55.962770 | controller | ok: [instance]
2026-01-21 08:16:55.962913 | controller |
2026-01-21 08:16:55.963087 | controller | TASK [run_hook : Loop on hooks for filtered_hooks] *****************************
2026-01-21 08:16:55.963254 | controller | Wednesday 21 January 2026  08:16:55 +0000 (0:00:00.079)       0:00:14.735 *****
2026-01-21 08:16:56.063704 | 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 08:16:56.063931 | controller |
2026-01-21 08:16:56.064193 | controller | TASK [run_hook : Set playbook path for 01 my hook] *****************************
2026-01-21 08:16:56.064368 | controller | Wednesday 21 January 2026  08:16:56 +0000 (0:00:00.100)       0:00:14.835 *****
2026-01-21 08:16:56.110619 | controller | ok: [instance]
2026-01-21 08:16:56.110776 | controller |
2026-01-21 08:16:56.110930 | controller | TASK [run_hook : Get file stat] ************************************************
2026-01-21 08:16:56.111105 | controller | Wednesday 21 January 2026  08:16:56 +0000 (0:00:00.047)       0:00:14.883 *****
2026-01-21 08:16:56.302055 | controller | ok: [instance]
2026-01-21 08:16:56.302103 | controller |
2026-01-21 08:16:56.302114 | controller | TASK [run_hook : Fail if playbook doesn't exist] *******************************
2026-01-21 08:16:56.302290 | controller | Wednesday 21 January 2026  08:16:56 +0000 (0:00:00.191)       0:00:15.074 *****
2026-01-21 08:16:56.319783 | controller | skipping: [instance]
2026-01-21 08:16:56.510994 | controller |
2026-01-21 08:16:56.511027 | controller | TASK [run_hook : Get parameters files] *****************************************
2026-01-21 08:16:56.511036 | controller | Wednesday 21 January 2026  08:16:56 +0000 (0:00:00.017)       0:00:15.091 *****
2026-01-21 08:16:56.511046 | controller | ok: [instance]
2026-01-21 08:16:56.531057 | controller |
2026-01-21 08:16:56.531087 | controller | TASK [run_hook : Add parameters artifacts as extra variables] ******************
2026-01-21 08:16:56.531097 | controller | Wednesday 21 January 2026  08:16:56 +0000 (0:00:00.190)       0:00:15.282 *****
2026-01-21 08:16:56.531108 | controller | ok: [instance]
2026-01-21 08:16:56.531137 | controller |
2026-01-21 08:16:56.531343 | controller | TASK [run_hook : Ensure log directory exists] **********************************
2026-01-21 08:16:56.726032 | controller | Wednesday 21 January 2026  08:16:56 +0000 (0:00:00.020)       0:00:15.303 *****
2026-01-21 08:16:56.726065 | controller | ok: [instance]
2026-01-21 08:16:56.910787 | controller |
2026-01-21 08:16:56.910816 | controller | TASK [run_hook : Ensure artifacts directory exists] ****************************
2026-01-21 08:16:56.910824 | controller | Wednesday 21 January 2026  08:16:56 +0000 (0:00:00.194)       0:00:15.497 *****
2026-01-21 08:16:56.910834 | controller | ok: [instance]
2026-01-21 08:16:58.835072 | controller |
2026-01-21 08:16:58.835106 | controller | TASK [run_hook : Run hook without retry - 01 my hook] **************************
2026-01-21 08:16:58.835120 | controller | Wednesday 21 January 2026  08:16:56 +0000 (0:00:00.184)       0:00:15.682 *****
2026-01-21 08:16:58.835131 | 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 08:16:58.835151 | controller | changed: [instance]
2026-01-21 08:16:58.835494 | controller |
2026-01-21 08:16:58.866966 | controller | TASK [run_hook : Run hook with retry - 01 my hook] *****************************
2026-01-21 08:16:58.867018 | controller | Wednesday 21 January 2026  08:16:58 +0000 (0:00:01.924)       0:00:17.607 *****
2026-01-21 08:16:58.867031 | controller | skipping: [instance]
2026-01-21 08:16:59.057052 | controller |
2026-01-21 08:16:59.057082 | controller | TASK [run_hook : Check if we have a file] **************************************
2026-01-21 08:16:59.057090 | controller | Wednesday 21 January 2026  08:16:58 +0000 (0:00:00.031)       0:00:17.638 *****
2026-01-21 08:16:59.057100 | controller | ok: [instance]
2026-01-21 08:16:59.057127 | controller |
2026-01-21 08:16:59.057381 | controller | TASK [run_hook : Load generated content in main playbook] **********************
2026-01-21 08:16:59.084054 | controller | Wednesday 21 January 2026  08:16:59 +0000 (0:00:00.190)       0:00:17.829 *****
2026-01-21 08:16:59.084089 | controller | ok: [instance]
2026-01-21 08:16:59.109995 | controller |
2026-01-21 08:16:59.110028 | controller | TASK [Ensure we have the ceph_uuid variable now] *******************************
2026-01-21 08:16:59.110038 | controller | Wednesday 21 January 2026  08:16:59 +0000 (0:00:00.026)       0:00:17.856 *****
2026-01-21 08:16:59.110050 | controller | ok: [instance] => changed=false
2026-01-21 08:16:59.147712 | controller |   msg: All assertions passed
2026-01-21 08:16:59.147741 | controller |
2026-01-21 08:16:59.147750 | controller | TASK [Direct hooks] ************************************************************
2026-01-21 08:16:59.147757 | controller | Wednesday 21 January 2026  08:16:59 +0000 (0:00:00.025)       0:00:17.881 *****
2026-01-21 08:16:59.147780 | controller |
2026-01-21 08:16:59.209659 | controller | TASK [run_hook : Assert parameters are valid] **********************************
2026-01-21 08:16:59.209687 | controller | Wednesday 21 January 2026  08:16:59 +0000 (0:00:00.038)       0:00:17.919 *****
2026-01-21 08:16:59.209698 | controller | ok: [instance]
2026-01-21 08:16:59.290374 | controller |
2026-01-21 08:16:59.290410 | controller | TASK [run_hook : Assert single hooks are all mappings] *************************
2026-01-21 08:16:59.290420 | controller | Wednesday 21 January 2026  08:16:59 +0000 (0:00:00.061)       0:00:17.981 *****
2026-01-21 08:16:59.290432 | controller | ok: [instance]
2026-01-21 08:16:59.290610 | controller |
2026-01-21 08:16:59.290767 | controller | TASK [run_hook : Loop on hooks for no_hook] ************************************
2026-01-21 08:16:59.290903 | controller | Wednesday 21 January 2026  08:16:59 +0000 (0:00:00.080)       0:00:18.062 *****
2026-01-21 08:16:59.388323 | 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 08:16:59.388505 | controller |
2026-01-21 08:16:59.388655 | controller | TASK [run_hook : Set playbook path for Dummy-5] ********************************
2026-01-21 08:16:59.388797 | controller | Wednesday 21 January 2026  08:16:59 +0000 (0:00:00.098)       0:00:18.160 *****
2026-01-21 08:16:59.436277 | controller | ok: [instance]
2026-01-21 08:16:59.436467 | controller |
2026-01-21 08:16:59.436640 | controller | TASK [run_hook : Get file stat] ************************************************
2026-01-21 08:16:59.436821 | controller | Wednesday 21 January 2026  08:16:59 +0000 (0:00:00.047)       0:00:18.208 *****
2026-01-21 08:16:59.642527 | controller | ok: [instance]
2026-01-21 08:16:59.660202 | controller |
2026-01-21 08:16:59.660228 | controller | TASK [run_hook : Fail if playbook doesn't exist] *******************************
2026-01-21 08:16:59.660236 | controller | Wednesday 21 January 2026  08:16:59 +0000 (0:00:00.205)       0:00:18.414 *****
2026-01-21 08:16:59.660246 | controller | skipping: [instance]
2026-01-21 08:16:59.850866 | controller |
2026-01-21 08:16:59.850898 | controller | TASK [run_hook : Get parameters files] *****************************************
2026-01-21 08:16:59.850907 | controller | Wednesday 21 January 2026  08:16:59 +0000 (0:00:00.017)       0:00:18.432 *****
2026-01-21 08:16:59.850917 | controller | ok: [instance]
2026-01-21 08:16:59.871709 | controller |
2026-01-21 08:16:59.871733 | controller | TASK [run_hook : Add parameters artifacts as extra variables] ******************
2026-01-21 08:16:59.871740 | controller | Wednesday 21 January 2026  08:16:59 +0000 (0:00:00.190)       0:00:18.622 *****
2026-01-21 08:16:59.871750 | controller | ok: [instance]
2026-01-21 08:17:00.067046 | controller |
2026-01-21 08:17:00.067085 | controller | TASK [run_hook : Ensure log directory exists] **********************************
2026-01-21 08:17:00.067094 | controller | Wednesday 21 January 2026  08:16:59 +0000 (0:00:00.021)       0:00:18.643 *****
2026-01-21 08:17:00.067105 | controller | ok: [instance]
2026-01-21 08:17:00.252521 | controller |
2026-01-21 08:17:00.252551 | controller | TASK [run_hook : Ensure artifacts directory exists] ****************************
2026-01-21 08:17:00.252559 | controller | Wednesday 21 January 2026  08:17:00 +0000 (0:00:00.194)       0:00:18.838 *****
2026-01-21 08:17:00.252569 | controller | ok: [instance]
2026-01-21 08:17:02.164581 | controller |
2026-01-21 08:17:02.164611 | controller | TASK [run_hook : Run hook without retry - Dummy-5] *****************************
2026-01-21 08:17:02.164619 | controller | Wednesday 21 January 2026  08:17:00 +0000 (0:00:00.185)       0:00:19.024 *****
2026-01-21 08:17:02.164629 | controller | Follow script's output here: /home/zuul/ci-framework-data/logs/ci_script_006_run_hook_without_retry_dummy.log
2026-01-21 08:17:02.195586 | controller | changed: [instance]
2026-01-21 08:17:02.195610 | controller |
2026-01-21 08:17:02.195619 | controller | TASK [run_hook : Run hook with retry - Dummy-5] ********************************
2026-01-21 08:17:02.195627 | controller | Wednesday 21 January 2026  08:17:02 +0000 (0:00:01.911)       0:00:20.936 *****
2026-01-21 08:17:02.195638 | controller | skipping: [instance]
2026-01-21 08:17:02.384041 | controller |
2026-01-21 08:17:02.384072 | controller | TASK [run_hook : Check if we have a file] **************************************
2026-01-21 08:17:02.384081 | controller | Wednesday 21 January 2026  08:17:02 +0000 (0:00:00.031)       0:00:20.967 *****
2026-01-21 08:17:02.384091 | controller | ok: [instance]
2026-01-21 08:17:02.384115 | controller |
2026-01-21 08:17:02.384333 | controller | TASK [run_hook : Load generated content in main playbook] **********************
2026-01-21 08:17:02.409440 | controller | Wednesday 21 January 2026  08:17:02 +0000 (0:00:00.188)       0:00:21.156 *****
2026-01-21 08:17:02.409466 | controller | ok: [instance]
2026-01-21 08:17:02.465088 | controller |
2026-01-21 08:17:02.465120 | controller | TASK [Ensure we have the ceph_uuid variable now] *******************************
2026-01-21 08:17:02.465129 | controller | Wednesday 21 January 2026  08:17:02 +0000 (0:00:00.025)       0:00:21.181 *****
2026-01-21 08:17:02.465145 | controller | ok: [instance] => changed=false
2026-01-21 08:17:02.465180 | controller |   msg: All assertions passed
2026-01-21 08:17:02.465188 | controller |
2026-01-21 08:17:02.465196 | controller | TASK [Direct hooks as param] ***************************************************
2026-01-21 08:17:02.465233 | controller | Wednesday 21 January 2026  08:17:02 +0000 (0:00:00.055)       0:00:21.237 *****
2026-01-21 08:17:02.504457 | controller |
2026-01-21 08:17:02.561694 | controller | TASK [run_hook : Assert parameters are valid] **********************************
2026-01-21 08:17:02.561721 | controller | Wednesday 21 January 2026  08:17:02 +0000 (0:00:00.039)       0:00:21.276 *****
2026-01-21 08:17:02.561735 | controller | ok: [instance]
2026-01-21 08:17:02.638915 | controller |
2026-01-21 08:17:02.638943 | controller | TASK [run_hook : Assert single hooks are all mappings] *************************
2026-01-21 08:17:02.638953 | controller | Wednesday 21 January 2026  08:17:02 +0000 (0:00:00.057)       0:00:21.333 *****
2026-01-21 08:17:02.638964 | controller | ok: [instance]
2026-01-21 08:17:02.737071 | controller |
2026-01-21 08:17:02.737103 | controller | TASK [run_hook : Loop on hooks for no_hook] ************************************
2026-01-21 08:17:02.737113 | controller | Wednesday 21 January 2026  08:17:02 +0000 (0:00:00.077)       0:00:21.410 *****
2026-01-21 08:17:02.737131 | 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 08:17:02.737166 | controller |
2026-01-21 08:17:02.737195 | controller | TASK [run_hook : Set playbook path for Hook as param] **************************
2026-01-21 08:17:02.737206 | controller | Wednesday 21 January 2026  08:17:02 +0000 (0:00:00.098)       0:00:21.508 *****
2026-01-21 08:17:02.782367 | controller | ok: [instance]
2026-01-21 08:17:02.966469 | controller |
2026-01-21 08:17:02.966498 | controller | TASK [run_hook : Get file stat] ************************************************
2026-01-21 08:17:02.966506 | controller | Wednesday 21 January 2026  08:17:02 +0000 (0:00:00.045)       0:00:21.554 *****
2026-01-21 08:17:02.966516 | controller | ok: [instance]
2026-01-21 08:17:02.982656 | controller |
2026-01-21 08:17:02.982677 | controller | TASK [run_hook : Fail if playbook doesn't exist] *******************************
2026-01-21 08:17:02.982685 | controller | Wednesday 21 January 2026  08:17:02 +0000 (0:00:00.183)       0:00:21.738 *****
2026-01-21 08:17:02.982694 | controller | skipping: [instance]
2026-01-21 08:17:03.166334 | controller |
2026-01-21 08:17:03.166365 | controller | TASK [run_hook : Get parameters files] *****************************************
2026-01-21 08:17:03.166373 | controller | Wednesday 21 January 2026  08:17:02 +0000 (0:00:00.016)       0:00:21.754 *****
2026-01-21 08:17:03.166383 | controller | ok: [instance]
2026-01-21 08:17:03.188054 | controller |
2026-01-21 08:17:03.188082 | controller | TASK [run_hook : Add parameters artifacts as extra variables] ******************
2026-01-21 08:17:03.188091 | controller | Wednesday 21 January 2026  08:17:03 +0000 (0:00:00.183)       0:00:21.938 *****
2026-01-21 08:17:03.188101 | controller | ok: [instance]
2026-01-21 08:17:03.188108 | controller |
2026-01-21 08:17:03.188114 | controller | TASK [run_hook : Ensure log directory exists] **********************************
2026-01-21 08:17:03.188121 | controller | Wednesday 21 January 2026  08:17:03 +0000 (0:00:00.021)       0:00:21.960 *****
2026-01-21 08:17:03.381740 | controller | ok: [instance]
2026-01-21 08:17:03.568076 | controller |
2026-01-21 08:17:03.568105 | controller | TASK [run_hook : Ensure artifacts directory exists] ****************************
2026-01-21 08:17:03.568114 | controller | Wednesday 21 January 2026  08:17:03 +0000 (0:00:00.193)       0:00:22.153 *****
2026-01-21 08:17:03.568124 | controller | ok: [instance]
2026-01-21 08:17:03.568555 | controller |
2026-01-21 08:17:05.497994 | controller | TASK [run_hook : Run hook without retry - Hook as param] ***********************
2026-01-21 08:17:05.498024 | controller | Wednesday 21 January 2026  08:17:03 +0000 (0:00:00.186)       0:00:22.340 *****
2026-01-21 08:17:05.498037 | controller | Follow script's output here: /home/zuul/ci-framework-data/logs/ci_script_007_run_hook_without_retry_hook.log
2026-01-21 08:17:05.528733 | controller | changed: [instance]
2026-01-21 08:17:05.528761 | controller |
2026-01-21 08:17:05.528769 | controller | TASK [run_hook : Run hook with retry - Hook as param] **************************
2026-01-21 08:17:05.528776 | controller | Wednesday 21 January 2026  08:17:05 +0000 (0:00:01.929)       0:00:24.269 *****
2026-01-21 08:17:05.528785 | controller | skipping: [instance]
2026-01-21 08:17:05.719677 | controller |
2026-01-21 08:17:05.719708 | controller | TASK [run_hook : Check if we have a file] **************************************
2026-01-21 08:17:05.719723 | controller | Wednesday 21 January 2026  08:17:05 +0000 (0:00:00.030)       0:00:24.300 *****
2026-01-21 08:17:05.719739 | controller | ok: [instance]
2026-01-21 08:17:05.747064 | controller |
2026-01-21 08:17:05.747095 | controller | TASK [run_hook : Load generated content in main playbook] **********************
2026-01-21 08:17:05.747104 | controller | Wednesday 21 January 2026  08:17:05 +0000 (0:00:00.190)       0:00:24.491 *****
2026-01-21 08:17:05.747114 | controller | ok: [instance]
2026-01-21 08:17:05.772923 | controller |
2026-01-21 08:17:05.772949 | controller | TASK [Ensure we have the ceph_uuid variable now] *******************************
2026-01-21 08:17:05.772957 | controller | Wednesday 21 January 2026  08:17:05 +0000 (0:00:00.027)       0:00:24.519 *****
2026-01-21 08:17:05.772985 | controller | ok: [instance] => changed=false
2026-01-21 08:17:05.773042 | controller |   msg: All assertions passed
2026-01-21 08:17:05.773135 | controller |
2026-01-21 08:17:05.773361 | controller | TASK [Ensure we have the test_list variable now] *******************************
2026-01-21 08:17:05.809933 | controller | Wednesday 21 January 2026  08:17:05 +0000 (0:00:00.026)       0:00:24.545 *****
2026-01-21 08:17:05.809969 | controller | ok: [instance] => changed=false
2026-01-21 08:17:05.837607 | controller |   msg: All assertions passed
2026-01-21 08:17:05.837629 | controller |
2026-01-21 08:17:05.837639 | controller | TASK [Run hook with retry] *****************************************************
2026-01-21 08:17:05.837646 | controller | Wednesday 21 January 2026  08:17:05 +0000 (0:00:00.036)       0:00:24.581 *****
2026-01-21 08:17:05.837657 | controller |
2026-01-21 08:17:05.895715 | controller | TASK [run_hook : Assert parameters are valid] **********************************
2026-01-21 08:17:05.895746 | controller | Wednesday 21 January 2026  08:17:05 +0000 (0:00:00.028)       0:00:24.609 *****
2026-01-21 08:17:05.895758 | controller | ok: [instance]
2026-01-21 08:17:05.975936 | controller |
2026-01-21 08:17:05.975967 | controller | TASK [run_hook : Assert single hooks are all mappings] *************************
2026-01-21 08:17:05.975998 | controller | Wednesday 21 January 2026  08:17:05 +0000 (0:00:00.057)       0:00:24.667 *****
2026-01-21 08:17:05.976011 | controller | ok: [instance]
2026-01-21 08:17:06.077058 | controller |
2026-01-21 08:17:06.077089 | controller | TASK [run_hook : Loop on hooks for retry_hook] *********************************
2026-01-21 08:17:06.077097 | controller | Wednesday 21 January 2026  08:17:05 +0000 (0:00:00.080)       0:00:24.747 *****
2026-01-21 08:17:06.077107 | 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 08:17:06.077271 | controller |
2026-01-21 08:17:06.077416 | controller | TASK [run_hook : Set playbook path for Run hook with retry] ********************
2026-01-21 08:17:06.077552 | controller | Wednesday 21 January 2026  08:17:06 +0000 (0:00:00.101)       0:00:24.849 *****
2026-01-21 08:17:06.125583 | controller | ok: [instance]
2026-01-21 08:17:06.125742 | controller |
2026-01-21 08:17:06.125883 | controller | TASK [run_hook : Get file stat] ************************************************
2026-01-21 08:17:06.126044 | controller | Wednesday 21 January 2026  08:17:06 +0000 (0:00:00.048)       0:00:24.897 *****
2026-01-21 08:17:06.322928 | controller | ok: [instance]
2026-01-21 08:17:06.339075 | controller |
2026-01-21 08:17:06.339106 | controller | TASK [run_hook : Fail if playbook doesn't exist] *******************************
2026-01-21 08:17:06.339114 | controller | Wednesday 21 January 2026  08:17:06 +0000 (0:00:00.196)       0:00:25.094 *****
2026-01-21 08:17:06.339125 | controller | skipping: [instance]
2026-01-21 08:17:06.531089 | controller |
2026-01-21 08:17:06.531122 | controller | TASK [run_hook : Get parameters files] *****************************************
2026-01-21 08:17:06.531130 | controller | Wednesday 21 January 2026  08:17:06 +0000 (0:00:00.016)       0:00:25.111 *****
2026-01-21 08:17:06.531140 | controller | ok: [instance]
2026-01-21 08:17:06.550910 | controller |
2026-01-21 08:17:06.550941 | controller | TASK [run_hook : Add parameters artifacts as extra variables] ******************
2026-01-21 08:17:06.550949 | controller | Wednesday 21 January 2026  08:17:06 +0000 (0:00:00.191)       0:00:25.302 *****
2026-01-21 08:17:06.550960 | controller | ok: [instance]
2026-01-21 08:17:06.745705 | controller |
2026-01-21 08:17:06.745738 | controller | TASK [run_hook : Ensure log directory exists] **********************************
2026-01-21 08:17:06.745752 | controller | Wednesday 21 January 2026  08:17:06 +0000 (0:00:00.019)       0:00:25.322 *****
2026-01-21 08:17:06.745763 | controller | ok: [instance]
2026-01-21 08:17:06.937816 | controller |
2026-01-21 08:17:06.937849 | controller | TASK [run_hook : Ensure artifacts directory exists] ****************************
2026-01-21 08:17:06.937857 | controller | Wednesday 21 January 2026  08:17:06 +0000 (0:00:00.195)       0:00:25.517 *****
2026-01-21 08:17:06.937867 | controller | ok: [instance]
2026-01-21 08:17:08.878342 | controller |
2026-01-21 08:17:08.878376 | controller | TASK [run_hook : Run hook without retry - Run hook with retry] *****************
2026-01-21 08:17:08.878385 | controller | Wednesday 21 January 2026  08:17:06 +0000 (0:00:00.192)       0:00:25.709 *****
2026-01-21 08:17:08.878395 | controller | Follow script's output here: /home/zuul/ci-framework-data/logs/ci_script_008_run_hook_without_retry_run.log
2026-01-21 08:17:08.909139 | controller | changed: [instance]
2026-01-21 08:17:08.909165 | controller |
2026-01-21 08:17:08.909172 | controller | TASK [run_hook : Run hook with retry - Run hook with retry] ********************
2026-01-21 08:17:08.909196 | controller | Wednesday 21 January 2026  08:17:08 +0000 (0:00:01.940)       0:00:27.650 *****
2026-01-21 08:17:08.909206 | controller | skipping: [instance]
2026-01-21 08:17:09.085057 | controller |
2026-01-21 08:17:09.085093 | controller | TASK [run_hook : Check if we have a file] **************************************
2026-01-21 08:17:09.085102 | controller | Wednesday 21 January 2026  08:17:08 +0000 (0:00:00.030)       0:00:27.681 *****
2026-01-21 08:17:09.085113 | controller | ok: [instance]
2026-01-21 08:17:09.085139 | controller |
2026-01-21 08:17:09.085405 | controller | TASK [run_hook : Load generated content in main playbook] **********************
2026-01-21 08:17:09.107843 | controller | Wednesday 21 January 2026  08:17:09 +0000 (0:00:00.176)       0:00:27.857 *****
2026-01-21 08:17:09.107875 | controller | skipping: [instance]
2026-01-21 08:17:09.296958 | controller |
2026-01-21 08:17:09.297004 | controller | TASK [Check if fake file exists for retry playbook] ****************************
2026-01-21 08:17:09.297012 | controller | Wednesday 21 January 2026  08:17:09 +0000 (0:00:00.022)       0:00:27.879 *****
2026-01-21 08:17:09.297023 | controller | ok: [instance]
2026-01-21 08:17:09.317026 | controller |
2026-01-21 08:17:09.317045 | controller | TASK [Ensure file exists and was created on retry] *****************************
2026-01-21 08:17:09.317053 | controller | Wednesday 21 January 2026  08:17:09 +0000 (0:00:00.188)       0:00:28.068 *****
2026-01-21 08:17:09.317062 | controller | ok: [instance] => changed=false
2026-01-21 08:17:09.317232 | controller |   msg: All assertions passed
2026-01-21 08:17:09.317244 | controller |
2026-01-21 08:17:09.317250 | controller | TASK [Remove generated file] ***************************************************
2026-01-21 08:17:09.317259 | controller | Wednesday 21 January 2026  08:17:09 +0000 (0:00:00.020)       0:00:28.089 *****
2026-01-21 08:17:09.512726 | controller | changed: [instance]
2026-01-21 08:17:09.576933 | controller |
2026-01-21 08:17:09.576961 | controller | PLAY RECAP *********************************************************************
2026-01-21 08:17:09.576989 | controller | instance                   : ok=112  changed=10   unreachable=0    failed=0    skipped=22   rescued=0    ignored=0
2026-01-21 08:17:09.576998 | controller |
2026-01-21 08:17:09.577005 | controller | Wednesday 21 January 2026  08:17:09 +0000 (0:00:00.193)       0:00:28.282 *****
2026-01-21 08:17:09.577010 | controller | ===============================================================================
2026-01-21 08:17:09.577016 | controller | run_hook : Run hook without retry - Run hook with retry ----------------- 1.94s
2026-01-21 08:17:09.577021 | controller | run_hook : Run hook without retry - Run dummy-2 ------------------------- 1.94s
2026-01-21 08:17:09.577026 | controller | run_hook : Run hook without retry - Run dummy-3 ------------------------- 1.93s
2026-01-21 08:17:09.577032 | controller | run_hook : Run hook without retry - 03 single hook ---------------------- 1.93s
2026-01-21 08:17:09.577037 | controller | run_hook : Run hook without retry - Hook as param ----------------------- 1.93s
2026-01-21 08:17:09.577042 | controller | run_hook : Run hook without retry - 01 my hook -------------------------- 1.92s
2026-01-21 08:17:09.577048 | controller | run_hook : Run hook without retry - Dummy-5 ----------------------------- 1.91s
2026-01-21 08:17:09.577053 | controller | Gathering Facts --------------------------------------------------------- 0.79s
2026-01-21 08:17:09.577063 | controller | run_hook : Run hook without retry - 02 Re-run noop ---------------------- 0.63s
2026-01-21 08:17:09.577069 | controller | run_hook : Run hook without retry - 01 Default noop hook ---------------- 0.60s
2026-01-21 08:17:09.577074 | controller | run_hook : Ensure log directory exists ---------------------------------- 0.30s
2026-01-21 08:17:09.577079 | controller | run_hook : Get file stat ------------------------------------------------ 0.30s
2026-01-21 08:17:09.577085 | controller | run_hook : Get parameters files ----------------------------------------- 0.29s
2026-01-21 08:17:09.577090 | controller | run_hook : Get file stat ------------------------------------------------ 0.21s
2026-01-21 08:17:09.577095 | controller | run_hook : Check if we have a file -------------------------------------- 0.21s
2026-01-21 08:17:09.577100 | controller | run_hook : Get file stat ------------------------------------------------ 0.21s
2026-01-21 08:17:09.577106 | controller | run_hook : Ensure log directory exists ---------------------------------- 0.20s
2026-01-21 08:17:09.577111 | controller | run_hook : Get file stat ------------------------------------------------ 0.20s
2026-01-21 08:17:09.577116 | controller | run_hook : Get file stat ------------------------------------------------ 0.20s
2026-01-21 08:17:09.577122 | controller | run_hook : Ensure log directory exists ---------------------------------- 0.20s
2026-01-21 08:17:09.577131 | controller | INFO     Running default > cleanup
2026-01-21 08:17:09.577582 | controller | WARNING  Skipping, cleanup playbook not configured.
2026-01-21 08:17:09.578124 | controller | INFO     Writing /tmp/report.html report.
2026-01-21 08:17:09.695898 | controller | changed
2026-01-21 08:17:09.720054 | 
2026-01-21 08:17:09.720144 | PLAY RECAP
2026-01-21 08:17:09.720188 | controller | ok: 4 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-01-21 08:17:09.720211 | 
2026-01-21 08:17:09.813866 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2026-01-21 08:17:09.814685 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2026-01-21 08:17:10.400181 | 
2026-01-21 08:17:10.400338 | PLAY [Run ci/playbooks/collect-logs.yml]
2026-01-21 08:17:10.421147 | 
2026-01-21 08:17:10.421228 | TASK [Filter out host if needed]
2026-01-21 08:17:10.429794 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2026-01-21 08:17:10.434367 | 
2026-01-21 08:17:10.434437 | TASK [Ensure file is present]
2026-01-21 08:17:10.757085 | controller | ok
2026-01-21 08:17:10.764246 | 
2026-01-21 08:17:10.764396 | TASK [Manage molecule report file]
2026-01-21 08:17:11.290998 | controller | changed
2026-01-21 08:17:11.296472 | 
2026-01-21 08:17:11.296560 | TASK [Check if we get ci-framework-data basedir]
2026-01-21 08:17:11.488930 | controller | ok
2026-01-21 08:17:11.494601 | 
2026-01-21 08:17:11.494677 | TASK [Create ci-framework-data log directory for zuul]
2026-01-21 08:17:11.807776 | controller | changed
2026-01-21 08:17:11.813074 | 
2026-01-21 08:17:11.813152 | TASK [Copy ci-framework interesting files]
2026-01-21 08:17:12.023519 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2026-01-21 08:17:12.336970 | controller | changed
2026-01-21 08:17:12.342438 | 
2026-01-21 08:17:12.342515 | TASK [Get SELinux listing]
2026-01-21 08:17:12.869744 | controller | changed
2026-01-21 08:17:12.875732 | 
2026-01-21 08:17:12.875822 | TASK [Generate log index]
2026-01-21 08:17:13.515232 | controller | changed
2026-01-21 08:17:13.520874 | 
2026-01-21 08:17:13.520945 | TASK [Get some env related data]
2026-01-21 08:17:14.058375 | controller | /home/zuul/.local/bin/ansible
2026-01-21 08:17:15.055307 | controller | changed
2026-01-21 08:17:15.061052 | 
2026-01-21 08:17:15.061131 | TASK [Generate list of logs to collect in home directory]
2026-01-21 08:17:15.364008 | controller | ok: All paths examined
2026-01-21 08:17:15.369125 | 
2026-01-21 08:17:15.369201 | LOOP [Copy logs from home directory]
2026-01-21 08:17:15.676599 | controller | changed:
2026-01-21 08:17:15.677089 | controller | {
2026-01-21 08:17:15.677131 | controller |   "atime": 1768983376.3154342,
2026-01-21 08:17:15.677155 | controller |   "ctime": 1768983393.082599,
2026-01-21 08:17:15.677174 | controller |   "dev": 64513,
2026-01-21 08:17:15.677191 | controller |   "gid": 1000,
2026-01-21 08:17:15.677206 | controller |   "gr_name": "zuul",
2026-01-21 08:17:15.677223 | controller |   "inode": 4441064,
2026-01-21 08:17:15.677239 | controller |   "isblk": false,
2026-01-21 08:17:15.677255 | controller |   "ischr": false,
2026-01-21 08:17:15.677269 | controller |   "isdir": false,
2026-01-21 08:17:15.677283 | controller |   "isfifo": false,
2026-01-21 08:17:15.677320 | controller |   "isgid": false,
2026-01-21 08:17:15.677340 | controller |   "islnk": false,
2026-01-21 08:17:15.677357 | controller |   "isreg": true,
2026-01-21 08:17:15.677372 | controller |   "issock": false,
2026-01-21 08:17:15.677399 | controller |   "isuid": false,
2026-01-21 08:17:15.677417 | controller |   "mode": "0644",
2026-01-21 08:17:15.677431 | controller |   "mtime": 1768983393.082599,
2026-01-21 08:17:15.677445 | controller |   "nlink": 1,
2026-01-21 08:17:15.677459 | controller |   "path": "/home/zuul/ansible.log",
2026-01-21 08:17:15.677475 | controller |   "pw_name": "zuul",
2026-01-21 08:17:15.677491 | controller |   "rgrp": true,
2026-01-21 08:17:15.677505 | controller |   "roth": true,
2026-01-21 08:17:15.677519 | controller |   "rusr": true,
2026-01-21 08:17:15.677534 | controller |   "size": 6749,
2026-01-21 08:17:15.677548 | controller |   "uid": 1000,
2026-01-21 08:17:15.677561 | controller |   "wgrp": false,
2026-01-21 08:17:15.677574 | controller |   "woth": false,
2026-01-21 08:17:15.677590 | controller |   "wusr": true,
2026-01-21 08:17:15.677605 | controller |   "xgrp": false,
2026-01-21 08:17:15.677618 | controller |   "xoth": false,
2026-01-21 08:17:15.677631 | controller |   "xusr": false
2026-01-21 08:17:15.677644 | controller | }
2026-01-21 08:17:15.690935 | 
2026-01-21 08:17:15.690999 | TASK [Copy crio stats log file]
2026-01-21 08:17:15.704439 | controller | skipping: Conditional result was False
2026-01-21 08:17:15.710183 | 
2026-01-21 08:17:15.710260 | TASK [Get SELinux related data]
2026-01-21 08:17:15.924824 | controller | <no matches>
2026-01-21 08:17:16.238427 | controller | ERROR
2026-01-21 08:17:16.238589 | controller | {
2026-01-21 08:17:16.238623 | controller |   "delta": "0:00:00.009025",
2026-01-21 08:17:16.238646 | controller |   "end": "2026-01-21 08:17:15.925877",
2026-01-21 08:17:16.238663 | controller |   "msg": "non-zero return code",
2026-01-21 08:17:16.238679 | controller |   "rc": 1,
2026-01-21 08:17:16.238697 | controller |   "start": "2026-01-21 08:17:15.916852"
2026-01-21 08:17:16.238713 | controller | }
2026-01-21 08:17:16.238737 | controller | ERROR: Ignoring Errors
2026-01-21 08:17:16.243973 | 
2026-01-21 08:17:16.244039 | TASK [Create system configuration directory]
2026-01-21 08:17:16.434431 | controller | changed
2026-01-21 08:17:16.439423 | 
2026-01-21 08:17:16.439499 | TASK [Get some of the system configurations]
2026-01-21 08:17:16.966717 | controller | changed
2026-01-21 08:17:16.972751 | 
2026-01-21 08:17:16.972860 | TASK [Copy generated documentation if available]
2026-01-21 08:17:16.987003 | controller | skipping: Conditional result was False
2026-01-21 08:17:16.993438 | 
2026-01-21 08:17:16.993536 | TASK [Copy generated AsciiDoc documentation if available]
2026-01-21 08:17:17.017348 | controller | skipping: Conditional result was False
2026-01-21 08:17:17.023798 | 
2026-01-21 08:17:17.023891 | TASK [Compress logs bigger than 2MB]
2026-01-21 08:17:17.550145 | controller | changed
2026-01-21 08:17:17.555890 | 
2026-01-21 08:17:17.555971 | TASK [Copy files from workspace on node]
2026-01-21 08:17:17.573857 | controller | ok
2026-01-21 08:17:17.596726 | 
2026-01-21 08:17:17.596804 | TASK [fetch-output : Set log path for multiple nodes]
2026-01-21 08:17:17.610243 | controller | skipping: Conditional result was False
2026-01-21 08:17:17.616107 | 
2026-01-21 08:17:17.616185 | TASK [fetch-output : Set log path for single node]
2026-01-21 08:17:17.644185 | controller | ok
2026-01-21 08:17:17.650278 | 
2026-01-21 08:17:17.650382 | LOOP [fetch-output : Ensure local output dirs]
2026-01-21 08:17:17.850404 | controller -> localhost | ok: "/var/lib/zuul/builds/798c83b8c7b546458fe0cf737d87cb07/work/logs"
2026-01-21 08:17:17.850740 | controller -> localhost | changed: All items complete
2026-01-21 08:17:17.850770 | 
2026-01-21 08:17:18.037587 | controller -> localhost | changed: "/var/lib/zuul/builds/798c83b8c7b546458fe0cf737d87cb07/work/artifacts"
2026-01-21 08:17:18.232588 | controller -> localhost | changed: "/var/lib/zuul/builds/798c83b8c7b546458fe0cf737d87cb07/work/docs"
2026-01-21 08:17:18.245491 | 
2026-01-21 08:17:18.245595 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-01-21 08:17:18.807272 | controller | changed:
2026-01-21 08:17:18.807507 | controller | .d..t...... ./
2026-01-21 08:17:18.807541 | controller | >f+++++++++ README.html
2026-01-21 08:17:18.807562 | controller | >f+++++++++ ansible-execution.log
2026-01-21 08:17:18.807582 | controller | >f+++++++++ ansible.log
2026-01-21 08:17:18.807601 | controller | >f+++++++++ dmesg.log
2026-01-21 08:17:18.807618 | controller | >f+++++++++ installed-pkgs.log
2026-01-21 08:17:18.807636 | controller | >f+++++++++ python.log
2026-01-21 08:17:18.807653 | controller | >f+++++++++ registries.conf
2026-01-21 08:17:18.807669 | controller | >f+++++++++ report.html
2026-01-21 08:17:18.807688 | controller | >f+++++++++ selinux-denials.log
2026-01-21 08:17:18.807706 | controller | >f+++++++++ selinux-listing.log
2026-01-21 08:17:18.807729 | controller | cd+++++++++ ci-framework-data/
2026-01-21 08:17:18.807746 | controller | cd+++++++++ ci-framework-data/artifacts/
2026-01-21 08:17:18.807762 | controller | >f+++++++++ ci-framework-data/artifacts/ci_script_000_run_hook_without_retry_01.sh
2026-01-21 08:17:18.807779 | controller | >f+++++++++ ci-framework-data/artifacts/ci_script_001_run_hook_without_retry_02_re.sh
2026-01-21 08:17:18.807801 | controller | >f+++++++++ ci-framework-data/artifacts/ci_script_002_run_hook_without_retry_03.sh
2026-01-21 08:17:18.813709 | controller | >f+++++++++ ci-framework-data/artifacts/ci_script_003_run_hook_without_retry_run.sh
2026-01-21 08:17:18.813835 | controller | >f+++++++++ ci-framework-data/artifacts/ci_script_004_run_hook_without_retry_run.sh
2026-01-21 08:17:18.813870 | controller | >f+++++++++ ci-framework-data/artifacts/ci_script_005_run_hook_without_retry_01_my.sh
2026-01-21 08:17:18.813893 | controller | >f+++++++++ ci-framework-data/artifacts/ci_script_006_run_hook_without_retry_dummy.sh
2026-01-21 08:17:18.813912 | controller | >f+++++++++ ci-framework-data/artifacts/ci_script_007_run_hook_without_retry_hook.sh
2026-01-21 08:17:18.813929 | controller | >f+++++++++ ci-framework-data/artifacts/ci_script_008_run_hook_without_retry_run.sh
2026-01-21 08:17:18.813946 | controller | >f+++++++++ ci-framework-data/artifacts/filtered_hooks_01_my_hook.yml
2026-01-21 08:17:18.813963 | controller | >f+++++++++ ci-framework-data/artifacts/list_hooks_run_dummy-2.yml
2026-01-21 08:17:18.813980 | controller | >f+++++++++ ci-framework-data/artifacts/list_hooks_run_dummy-3.yml
2026-01-21 08:17:18.813995 | controller | >f+++++++++ ci-framework-data/artifacts/no_hook_dummy-5.yml
2026-01-21 08:17:18.814011 | controller | >f+++++++++ ci-framework-data/artifacts/no_hook_hook_as_param.yml
2026-01-21 08:17:18.814026 | controller | >f+++++++++ ci-framework-data/artifacts/run_molecule_03_single_hook.yml
2026-01-21 08:17:18.814040 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2026-01-21 08:17:18.814055 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2026-01-21 08:17:18.814073 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2026-01-21 08:17:18.814089 | controller | cd+++++++++ ci-framework-data/logs/
2026-01-21 08:17:18.814105 | controller | >f+++++++++ ci-framework-data/logs/ci_script_000_run_hook_without_retry_01.log
2026-01-21 08:17:18.814121 | controller | >f+++++++++ ci-framework-data/logs/ci_script_001_run_hook_without_retry_02_re.log
2026-01-21 08:17:18.814136 | controller | >f+++++++++ ci-framework-data/logs/ci_script_002_run_hook_without_retry_03.log
2026-01-21 08:17:18.814150 | controller | >f+++++++++ ci-framework-data/logs/ci_script_003_run_hook_without_retry_run.log
2026-01-21 08:17:18.814165 | controller | >f+++++++++ ci-framework-data/logs/ci_script_004_run_hook_without_retry_run.log
2026-01-21 08:17:18.814179 | controller | >f+++++++++ ci-framework-data/logs/ci_script_005_run_hook_without_retry_01_my.log
2026-01-21 08:17:18.814194 | controller | >f+++++++++ ci-framework-data/logs/ci_script_006_run_hook_without_retry_dummy.log
2026-01-21 08:17:18.814209 | controller | >f+++++++++ ci-framework-data/logs/ci_script_007_run_hook_without_retry_hook.log
2026-01-21 08:17:18.814224 | controller | >f+++++++++ ci-framework-data/logs/ci_script_008_run_hook_without_retry_run.log
2026-01-21 08:17:18.814238 | controller | >f+++++++++ ci-framework-data/logs/filtered_hooks_01_my_hook.log
2026-01-21 08:17:18.814254 | controller | >f+++++++++ ci-framework-data/logs/list_hooks_run_dummy-2.log
2026-01-21 08:17:18.814269 | controller | >f+++++++++ ci-framework-data/logs/list_hooks_run_dummy-3.log
2026-01-21 08:17:18.814284 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2026-01-21 08:17:18.814320 | controller | >f+++++++++ ci-framework-data/logs/no_hook_dummy-5.log
2026-01-21 08:17:18.814338 | controller | >f+++++++++ ci-framework-data/logs/no_hook_hook_as_param.log
2026-01-21 08:17:18.814353 | controller | >f+++++++++ ci-framework-data/logs/retry_hook_run_hook_with_retry.log
2026-01-21 08:17:18.814368 | controller | >f+++++++++ ci-framework-data/logs/run_molecule_01_default_noop_hook.log
2026-01-21 08:17:18.814382 | controller | >f+++++++++ ci-framework-data/logs/run_molecule_02_re-run_noop.log
2026-01-21 08:17:18.814396 | controller | >f+++++++++ ci-framework-data/logs/run_molecule_03_single_hook.log
2026-01-21 08:17:18.814411 | controller | cd+++++++++ registries.conf.d/
2026-01-21 08:17:18.814437 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2026-01-21 08:17:18.814457 | controller | cd+++++++++ system-config/
2026-01-21 08:17:18.814472 | controller | cd+++++++++ system-config/libvirt/
2026-01-21 08:17:18.814487 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2026-01-21 08:17:18.814502 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2026-01-21 08:17:19.202699 | controller | changed: .d..t...... ./
2026-01-21 08:17:19.592204 | controller | changed: .d..t...... ./
2026-01-21 08:17:19.606134 | 
2026-01-21 08:17:19.606248 | TASK [Return artifact to Zuul]
2026-01-21 08:17:19.645418 | controller | ok
2026-01-21 08:17:19.667556 | 
2026-01-21 08:17:19.667628 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2026-01-21 08:17:19.667730 | 
2026-01-21 08:17:19.667758 | PLAY RECAP
2026-01-21 08:17:19.667796 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2026-01-21 08:17:19.667818 | 
2026-01-21 08:17:19.763921 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2026-01-21 08:17:19.764666 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2026-01-21 08:17:20.320348 | 
2026-01-21 08:17:20.320499 | PLAY [all]
2026-01-21 08:17:20.339167 | 
2026-01-21 08:17:20.339244 | TASK [include_role : fetch-output]
2026-01-21 08:17:20.367201 | controller | ok
2026-01-21 08:17:20.384092 | 
2026-01-21 08:17:20.384170 | TASK [fetch-output : Set log path for multiple nodes]
2026-01-21 08:17:20.428267 | controller | skipping: Conditional result was False
2026-01-21 08:17:20.433926 | 
2026-01-21 08:17:20.434005 | TASK [fetch-output : Set log path for single node]
2026-01-21 08:17:20.461886 | controller | ok
2026-01-21 08:17:20.467041 | 
2026-01-21 08:17:20.467111 | LOOP [fetch-output : Ensure local output dirs]
2026-01-21 08:17:20.836941 | controller -> localhost | ok: "/var/lib/zuul/builds/798c83b8c7b546458fe0cf737d87cb07/work/logs"
2026-01-21 08:17:21.050495 | controller -> localhost | ok: "/var/lib/zuul/builds/798c83b8c7b546458fe0cf737d87cb07/work/artifacts"
2026-01-21 08:17:21.252519 | controller -> localhost | ok: "/var/lib/zuul/builds/798c83b8c7b546458fe0cf737d87cb07/work/docs"
2026-01-21 08:17:21.263497 | 
2026-01-21 08:17:21.263579 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-01-21 08:17:21.812856 | controller | ok
2026-01-21 08:17:21.813135 | controller | ok: All items complete
2026-01-21 08:17:21.813176 | 
2026-01-21 08:17:22.196855 | controller | ok
2026-01-21 08:17:22.575955 | controller | ok
2026-01-21 08:17:22.595179 | 
2026-01-21 08:17:22.595268 | TASK [include_role : fetch-output-openshift]
2026-01-21 08:17:22.618914 | controller | skipping: Conditional result was False
2026-01-21 08:17:22.624964 | 
2026-01-21 08:17:22.625049 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-01-21 08:17:22.995280 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.006815
2026-01-21 08:17:23.191593 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.007028
2026-01-21 08:17:23.226222 | 
2026-01-21 08:17:23.226327 | PLAY [all]
2026-01-21 08:17:23.242260 | 
2026-01-21 08:17:23.242366 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-01-21 08:17:23.649195 | controller | changed
2026-01-21 08:17:23.673114 | 
2026-01-21 08:17:23.673274 | PLAY RECAP
2026-01-21 08:17:23.673353 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2026-01-21 08:17:23.673382 | 
2026-01-21 08:17:23.768006 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2026-01-21 08:17:23.768731 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2026-01-21 08:17:24.328348 | 
2026-01-21 08:17:24.328453 | PLAY [localhost]
2026-01-21 08:17:24.345779 | 
2026-01-21 08:17:24.345854 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-01-21 08:17:24.670868 | localhost | changed
2026-01-21 08:17:24.675729 | 
2026-01-21 08:17:24.675895 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-01-21 08:17:24.703586 | localhost | ok
2026-01-21 08:17:24.711689 | 
2026-01-21 08:17:24.711766 | TASK [add-fileserver : Create SSH private key tempfile]
2026-01-21 08:17:25.022926 | localhost | changed
2026-01-21 08:17:25.028763 | 
2026-01-21 08:17:25.028853 | TASK [add-fileserver : Create SSH private key from secret]
2026-01-21 08:17:25.618389 | localhost | changed
2026-01-21 08:17:25.623604 | 
2026-01-21 08:17:25.623683 | TASK [add-fileserver : Add fileserver ssh key]
2026-01-21 08:17:25.975535 | localhost | Identity added: /var/lib/zuul/builds/798c83b8c7b546458fe0cf737d87cb07/work/tmp/ansible.w_ytc9tt (/var/lib/zuul/builds/798c83b8c7b546458fe0cf737d87cb07/work/tmp/ansible.w_ytc9tt)
2026-01-21 08:17:25.975708 | localhost | ok: Runtime: 0:00:00.006829
2026-01-21 08:17:25.980237 | 
2026-01-21 08:17:25.980334 | TASK [add-fileserver : Remove SSH private key from disk]
2026-01-21 08:17:26.210882 | localhost | ok: Runtime: 0:00:00.004912
2026-01-21 08:17:26.215623 | 
2026-01-21 08:17:26.215696 | TASK [add-fileserver : Add fileserver to inventory]
2026-01-21 08:17:26.264973 | localhost | changed
2026-01-21 08:17:26.269113 | 
2026-01-21 08:17:26.269188 | TASK [add-fileserver : Add fileserver server to known hosts]
2026-01-21 08:17:26.611426 | localhost | changed
2026-01-21 08:17:26.632380 | 
2026-01-21 08:17:26.632485 | PLAY [localhost]
2026-01-21 08:17:26.645242 | 
2026-01-21 08:17:26.645335 | TASK [Generate bulk log download script]
2026-01-21 08:17:26.665133 | localhost | ok
2026-01-21 08:17:26.678373 | 
2026-01-21 08:17:26.678465 | TASK [local-log-download : Check API endpoint is defined]
2026-01-21 08:17:26.712237 | localhost | ok: All assertions passed
2026-01-21 08:17:26.717125 | 
2026-01-21 08:17:26.717207 | TASK [local-log-download : Create download script]
2026-01-21 08:17:27.098080 | localhost -> localhost | changed
2026-01-21 08:17:27.107726 | 
2026-01-21 08:17:27.107828 | TASK [Register quick-download link]
2026-01-21 08:17:27.125528 | localhost | ok
2026-01-21 08:17:27.165525 | 
2026-01-21 08:17:27.165630 | PLAY [logserver.rdoproject.org]
2026-01-21 08:17:27.177572 | 
2026-01-21 08:17:27.177644 | TASK [Set zuul-log-path fact]
2026-01-21 08:17:27.193671 | logserver.rdoproject.org | ok
2026-01-21 08:17:27.205247 | 
2026-01-21 08:17:27.205439 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-01-21 08:17:27.231477 | logserver.rdoproject.org | ok
2026-01-21 08:17:27.237619 | 
2026-01-21 08:17:27.237709 | TASK [upload-logs : Create log directories]
2026-01-21 08:17:28.836861 | logserver.rdoproject.org | changed
2026-01-21 08:17:28.840473 | 
2026-01-21 08:17:28.840566 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-01-21 08:17:29.077316 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.004797
2026-01-21 08:17:29.081933 | 
2026-01-21 08:17:29.082010 | TASK [upload-logs : Upload logs to log server]
2026-01-21 08:17:30.548685 | logserver.rdoproject.org | Output suppressed because no_log was given
2026-01-21 08:17:30.552540 | 
2026-01-21 08:17:30.552607 | LOOP [upload-logs : Compress console log and json output]
2026-01-21 08:17:30.610990 | logserver.rdoproject.org | skipping: Conditional result was False
2026-01-21 08:17:30.614647 | logserver.rdoproject.org | skipping: Conditional result was False
2026-01-21 08:17:30.620894 | 
2026-01-21 08:17:30.621008 | LOOP [upload-logs : Upload compressed console log and json output]
2026-01-21 08:17:30.675959 | logserver.rdoproject.org | skipping: Conditional result was False
2026-01-21 08:17:30.676192 | 
2026-01-21 08:17:30.676836 | logserver.rdoproject.org | skipping: Conditional result was False
2026-01-21 08:17:30.681107 | 
2026-01-21 08:17:30.681213 | LOOP [upload-logs : Upload console log and json output]