2025-10-10 08:43:41.596200 | Job console starting...
2025-10-10 08:43:41.849106 | Updating repositories
2025-10-10 08:43:42.560381 | Preparing job workspace
2025-10-10 08:43:50.425486 | Running Ansible setup...
2025-10-10 08:43:53.369008 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-10-10 08:43:53.906477 | 
2025-10-10 08:43:53.906579 | PLAY [localhost]
2025-10-10 08:43:53.915037 | 
2025-10-10 08:43:53.915108 | TASK [Gathering Facts]
2025-10-10 08:43:54.730193 | localhost | ok
2025-10-10 08:43:54.743608 | 
2025-10-10 08:43:54.743696 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-10-10 08:43:55.071345 | localhost -> localhost | changed
2025-10-10 08:43:55.077392 | 
2025-10-10 08:43:55.077576 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-10-10 08:43:55.811762 | localhost -> localhost | changed
2025-10-10 08:43:55.821395 | 
2025-10-10 08:43:55.821499 | TASK [Setup log path fact]
2025-10-10 08:43:55.840265 | localhost | ok
2025-10-10 08:43:55.852693 | 
2025-10-10 08:43:55.852812 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-10 08:43:55.871258 | localhost | ok
2025-10-10 08:43:55.879540 | 
2025-10-10 08:43:55.879612 | TASK [emit-job-header : Print job information]
2025-10-10 08:43:55.907699 | # Job Information
2025-10-10 08:43:55.907859 | Ansible Version: 2.15.12
2025-10-10 08:43:55.907890 | Job: cifmw-molecule-run_hook
2025-10-10 08:43:55.907936 | Pipeline: github-check
2025-10-10 08:43:55.907963 | Executor: ibm-bm4-ze.softwarefactory-project.io
2025-10-10 08:43:55.907985 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3368
2025-10-10 08:43:55.908005 | Log URL (when completed): https://logserver.rdoproject.org/034/rdoproject.org/0341fee42c1e4c0abc747939cb2f8d37/
2025-10-10 08:43:55.908026 | Event ID: dc051e10-a5b2-11f0-8a63-96c3b7c31c2e
2025-10-10 08:43:55.911875 | 
2025-10-10 08:43:55.911963 | LOOP [emit-job-header : Print node information]
2025-10-10 08:43:56.001874 | localhost | ok:
2025-10-10 08:43:56.002238 | localhost | # Node Information
2025-10-10 08:43:56.002274 | localhost | Inventory Hostname: controller
2025-10-10 08:43:56.002299 | localhost | Hostname: np0005479772
2025-10-10 08:43:56.002320 | localhost | Username: zuul
2025-10-10 08:43:56.002341 | localhost | Distro: CentOS 9
2025-10-10 08:43:56.002360 | localhost | Provider: ibm-bm4-nodepool
2025-10-10 08:43:56.002377 | localhost | Region: regionOne
2025-10-10 08:43:56.002395 | localhost | Label: cloud-centos-9-stream-tripleo-ibm
2025-10-10 08:43:56.002412 | localhost | Product Name: OpenStack Compute
2025-10-10 08:43:56.002430 | localhost | Interface IP: 192.168.26.90
2025-10-10 08:43:56.026845 | 
2025-10-10 08:43:56.026925 | PLAY [all]
2025-10-10 08:43:56.034866 | 
2025-10-10 08:43:56.034950 | TASK [Gather network facts]
2025-10-10 08:43:56.389074 | controller | ok
2025-10-10 08:43:56.403670 | 
2025-10-10 08:43:56.403767 | TASK [include_role : start-zuul-console]
2025-10-10 08:43:56.422626 | controller | ok
2025-10-10 08:43:56.435358 | 
2025-10-10 08:43:56.435431 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-10-10 08:43:56.738389 | controller | ok
2025-10-10 08:43:56.747453 | 
2025-10-10 08:43:56.747527 | TASK [include_role : add-build-sshkey]
2025-10-10 08:43:56.765248 | controller | ok
2025-10-10 08:43:56.780267 | 
2025-10-10 08:43:56.780333 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-10-10 08:43:56.977127 | controller -> localhost | ok
2025-10-10 08:43:56.983391 | 
2025-10-10 08:43:56.983458 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-10-10 08:43:57.002309 | controller | ok
2025-10-10 08:43:57.016353 | controller | included: /var/lib/zuul/builds/0341fee42c1e4c0abc747939cb2f8d37/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-10-10 08:43:57.022960 | 
2025-10-10 08:43:57.023025 | TASK [add-build-sshkey : Create Temp SSH key]
2025-10-10 08:43:57.461488 | controller -> localhost | Generating public/private rsa key pair.
2025-10-10 08:43:57.461677 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/0341fee42c1e4c0abc747939cb2f8d37/work/0341fee42c1e4c0abc747939cb2f8d37_id_rsa.
2025-10-10 08:43:57.461711 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/0341fee42c1e4c0abc747939cb2f8d37/work/0341fee42c1e4c0abc747939cb2f8d37_id_rsa.pub.
2025-10-10 08:43:57.461744 | controller -> localhost | The key fingerprint is:
2025-10-10 08:43:57.461768 | controller -> localhost | SHA256:aFPSxbnB0uK2lsAglwBG5RvU2vr7M48oyZ6gJ5j8Qug zuul-build-sshkey
2025-10-10 08:43:57.461789 | controller -> localhost | The key's randomart image is:
2025-10-10 08:43:57.461807 | controller -> localhost | +---[RSA 3072]----+
2025-10-10 08:43:57.461826 | controller -> localhost | |o+o+..   +..     |
2025-10-10 08:43:57.461845 | controller -> localhost | |. + +. .o.*      |
2025-10-10 08:43:57.461862 | controller -> localhost | |   =oo..oo o     |
2025-10-10 08:43:57.461879 | controller -> localhost | |   .o.o+o .      |
2025-10-10 08:43:57.461896 | controller -> localhost | |.  .. +oSo       |
2025-10-10 08:43:57.461929 | controller -> localhost | |.. . . .+        |
2025-10-10 08:43:57.461953 | controller -> localhost | |=o. o  .         |
2025-10-10 08:43:57.461973 | controller -> localhost | |=Eo+...o.        |
2025-10-10 08:43:57.461989 | controller -> localhost | |.o++.ooo+.       |
2025-10-10 08:43:57.462005 | controller -> localhost | +----[SHA256]-----+
2025-10-10 08:43:57.462044 | controller -> localhost | ok: Runtime: 0:00:00.105774
2025-10-10 08:43:57.467813 | 
2025-10-10 08:43:57.467879 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-10-10 08:43:57.486316 | controller | ok
2025-10-10 08:43:57.496252 | controller | included: /var/lib/zuul/builds/0341fee42c1e4c0abc747939cb2f8d37/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-10-10 08:43:57.504252 | 
2025-10-10 08:43:57.504320 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-10-10 08:43:57.518779 | controller | skipping: Conditional result was False
2025-10-10 08:43:57.524117 | 
2025-10-10 08:43:57.524188 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-10-10 08:43:57.890105 | controller | changed
2025-10-10 08:43:57.897311 | 
2025-10-10 08:43:57.897428 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-10-10 08:43:58.091125 | controller | ok
2025-10-10 08:43:58.096427 | 
2025-10-10 08:43:58.096513 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-10-10 08:43:58.641449 | controller | changed
2025-10-10 08:43:58.646947 | 
2025-10-10 08:43:58.647029 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-10-10 08:43:59.172534 | controller | changed
2025-10-10 08:43:59.177680 | 
2025-10-10 08:43:59.177787 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-10-10 08:43:59.191460 | controller | skipping: Conditional result was False
2025-10-10 08:43:59.198267 | 
2025-10-10 08:43:59.198351 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-10-10 08:43:59.521344 | controller -> localhost | changed
2025-10-10 08:43:59.531394 | 
2025-10-10 08:43:59.531481 | TASK [add-build-sshkey : Add back temp key]
2025-10-10 08:43:59.789825 | controller -> localhost | Identity added: /var/lib/zuul/builds/0341fee42c1e4c0abc747939cb2f8d37/work/0341fee42c1e4c0abc747939cb2f8d37_id_rsa (zuul-build-sshkey)
2025-10-10 08:43:59.790359 | controller -> localhost | ok: Runtime: 0:00:00.007337
2025-10-10 08:43:59.796127 | 
2025-10-10 08:43:59.796190 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-10-10 08:44:00.088982 | controller | ok
2025-10-10 08:44:00.094019 | 
2025-10-10 08:44:00.094091 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-10-10 08:44:00.129248 | controller | skipping: Conditional result was False
2025-10-10 08:44:00.139246 | 
2025-10-10 08:44:00.139335 | TASK [include_role : validate-host]
2025-10-10 08:44:00.169289 | controller | ok
2025-10-10 08:44:00.190406 | 
2025-10-10 08:44:00.190505 | TASK [validate-host : Define zuul_info_dir fact]
2025-10-10 08:44:00.220678 | controller | ok
2025-10-10 08:44:00.226336 | 
2025-10-10 08:44:00.226407 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-10-10 08:44:00.454319 | controller -> localhost | ok
2025-10-10 08:44:00.460740 | 
2025-10-10 08:44:00.460810 | TASK [validate-host : Collect information about the host]
2025-10-10 08:44:01.040993 | controller | ok
2025-10-10 08:44:01.049876 | 
2025-10-10 08:44:01.049986 | TASK [validate-host : Sanitize hostname]
2025-10-10 08:44:01.119776 | controller | ok
2025-10-10 08:44:01.124636 | 
2025-10-10 08:44:01.124703 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-10-10 08:44:01.537453 | controller -> localhost | changed
2025-10-10 08:44:01.542491 | 
2025-10-10 08:44:01.542554 | TASK [validate-host : Collect information about zuul worker]
2025-10-10 08:44:01.847009 | controller | ok
2025-10-10 08:44:01.852299 | 
2025-10-10 08:44:01.852369 | TASK [validate-host : Write out all zuul information for each host]
2025-10-10 08:44:02.231475 | controller -> localhost | changed
2025-10-10 08:44:02.240709 | 
2025-10-10 08:44:02.240786 | TASK [include_role : prepare-workspace-openshift]
2025-10-10 08:44:02.254841 | controller | skipping: Conditional result was False
2025-10-10 08:44:02.259925 | 
2025-10-10 08:44:02.259991 | TASK [include_role : remove-zuul-sshkey]
2025-10-10 08:44:02.273972 | controller | skipping: Conditional result was False
2025-10-10 08:44:02.279214 | 
2025-10-10 08:44:02.279282 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-10-10 08:44:02.466462 | controller | ok: "logs"
2025-10-10 08:44:02.466820 | controller | ok: All items complete
2025-10-10 08:44:02.466864 | 
2025-10-10 08:44:02.631793 | controller | ok: "artifacts"
2025-10-10 08:44:02.791847 | controller | ok: "docs"
2025-10-10 08:44:02.799262 | 
2025-10-10 08:44:02.799333 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-10-10 08:44:03.008310 | controller | changed: "logs"
2025-10-10 08:44:03.186858 | controller | changed: "artifacts"
2025-10-10 08:44:03.366427 | controller | changed: "docs"
2025-10-10 08:44:03.392150 | 
2025-10-10 08:44:03.392222 | PLAY RECAP
2025-10-10 08:44:03.392269 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-10-10 08:44:03.392299 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-10 08:44:03.392318 | 
2025-10-10 08:44:03.471838 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-10-10 08:44:03.472624 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-10-10 08:44:04.054584 | 
2025-10-10 08:44:04.054697 | PLAY [all]
2025-10-10 08:44:04.074663 | 
2025-10-10 08:44:04.074762 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-10-10 08:44:04.123806 | controller | ok
2025-10-10 08:44:04.128859 | 
2025-10-10 08:44:04.128965 | TASK [mirror-info-fork : Create /etc/ci]
2025-10-10 08:44:04.482340 | controller | changed
2025-10-10 08:44:04.487947 | 
2025-10-10 08:44:04.488042 | TASK [mirror-info-fork : Install ci_mirror script]
2025-10-10 08:44:05.322389 | controller | changed
2025-10-10 08:44:05.338880 | 
2025-10-10 08:44:05.338986 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-10-10 08:44:05.703352 | controller | changed:
2025-10-10 08:44:05.703677 | controller | {
2025-10-10 08:44:05.703707 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-10-10 08:44:05.703773 | controller | }
2025-10-10 08:44:05.910140 | controller | changed:
2025-10-10 08:44:05.910395 | controller | {
2025-10-10 08:44:05.910433 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-10-10 08:44:05.910470 | controller | }
2025-10-10 08:44:06.113350 | controller | changed:
2025-10-10 08:44:06.113453 | controller | {
2025-10-10 08:44:06.113482 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com"
2025-10-10 08:44:06.113505 | controller | }
2025-10-10 08:44:06.347592 | controller | changed:
2025-10-10 08:44:06.347793 | controller | {
2025-10-10 08:44:06.347832 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-10-10 08:44:06.347857 | controller | }
2025-10-10 08:44:06.547571 | controller | changed:
2025-10-10 08:44:06.547664 | controller | {
2025-10-10 08:44:06.547692 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-10-10 08:44:06.547713 | controller | }
2025-10-10 08:44:06.749962 | controller | changed:
2025-10-10 08:44:06.750051 | controller | {
2025-10-10 08:44:06.750079 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-10-10 08:44:06.750100 | controller | }
2025-10-10 08:44:06.956708 | controller | changed:
2025-10-10 08:44:06.956805 | controller | {
2025-10-10 08:44:06.956834 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDLOQd4ZLtkZXQGY6UwAr/06ppWQK4fDO3HaqxPk98csyOCBXsliSKK39Bso828+5srIXiW7aI6aC9P5mwi4mUZlGPfJlQbfrcGvY+b/SocuvaGK+1RrHLoJCT52LBhwgrzlXio2jeksZeein8iaTrhsPrOAs7KggIL/rB9hEiB3NaOPWhhoCP4vlW6MEMExGcqB/1FVxXFBPnLkEyW0Lk7ycVflZl2ocRxbfjZi0+tI1Wlinp8PvSQSc/WVrAcDgKjc/mB4ODPOyYy3G8FHgfMsrXSDEyjBKgLKMsdCrAUcqJQWjkqXleXSYOV4q3pzL+9umK+q/e3P/bIoSFQzmJKTU1eDfuvPXmow9F5H54fii/Da7ezlMJ+wPGHJrRAkmzvMbALy7xwswLhZMkOGNtRcPqaKYRmIBKpw3o6bCTtcNUHOtOQnzwY8JzrM2eBWJBXAANYw+9/ho80JIiwhg29CFNpVBuHbql2YxJQNrnl90guN65rYNpDxdIluweyUf8= anbanerj@kaermorhen"
2025-10-10 08:44:06.956856 | controller | }
2025-10-10 08:44:07.175420 | controller | changed:
2025-10-10 08:44:07.175563 | controller | {
2025-10-10 08:44:07.175592 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQC3VwV8Im9kRm49lt3tM36hj4Zv27FxGo4C1Q/0jqhzFmHY7RHbmeRr8ObhwWoHjXSozKWg8FL5ER0z3hTwL0W6lez3sL7hUaCmSuZmG5Hnl3x4vTSxDI9JZ/Y65rtYiiWQo2fC5xJhU/4+0e5e/pseCm8cKRSu+SaxhO+sd6FDojA2x1BzOzKiQRDy/1zWGp/cZkxcEuB1wHI5LMzN03c67vmbu+fhZRAUO4dQkvcnj2LrhQtpa+ytvnSjr8icMDosf1OsbSffwZFyHB/hfWGAfe0eIeSA2XPraxiPknXxiPKx2MJsaUTYbsZcm3EjFdHBBMumw5rBI74zLrMRvCO9GwBEmGT4rFng1nP+yw5DB8sn2zqpOsPg1LYRwCPOUveC13P6pgsZZPh812e8v5EKnETct+5XI3dVpdw6CnNiLwAyVAF15DJvBGT/u1k0Myg/bQn+Gv9k2MSj6LvQmf6WbZu2Wgjm30z3FyCneBqTL7mLF19YXzeC0ufHz5pnO1E= dasm@fedora"
2025-10-10 08:44:07.175614 | controller | }
2025-10-10 08:44:07.379058 | controller | changed:
2025-10-10 08:44:07.379209 | controller | {
2025-10-10 08:44:07.379237 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-10-10 08:44:07.379258 | controller | }
2025-10-10 08:44:07.583420 | controller | changed:
2025-10-10 08:44:07.583556 | controller | {
2025-10-10 08:44:07.583586 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-10-10 08:44:07.583606 | controller | }
2025-10-10 08:44:07.795692 | controller | changed:
2025-10-10 08:44:07.795837 | controller | {
2025-10-10 08:44:07.795865 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-10-10 08:44:07.795886 | controller | }
2025-10-10 08:44:08.028799 | controller | changed:
2025-10-10 08:44:08.028887 | controller | {
2025-10-10 08:44:08.028935 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-10-10 08:44:08.028960 | controller | }
2025-10-10 08:44:08.241441 | controller | changed:
2025-10-10 08:44:08.241609 | controller | {
2025-10-10 08:44:08.241645 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-10-10 08:44:08.241669 | controller | }
2025-10-10 08:44:08.444601 | controller | changed:
2025-10-10 08:44:08.444680 | controller | {
2025-10-10 08:44:08.444706 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-10-10 08:44:08.444726 | controller | }
2025-10-10 08:44:08.652815 | controller | changed:
2025-10-10 08:44:08.652899 | controller | {
2025-10-10 08:44:08.652952 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-10-10 08:44:08.652976 | controller | }
2025-10-10 08:44:08.868413 | controller | changed:
2025-10-10 08:44:08.868504 | controller | {
2025-10-10 08:44:08.868531 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-10-10 08:44:08.868552 | controller | }
2025-10-10 08:44:09.083207 | controller | changed:
2025-10-10 08:44:09.083298 | controller | {
2025-10-10 08:44:09.083324 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQCfNtF7NvKl915TGsGGoseUb06Hj8L/S4toWf0hExeY+F00woL6NvBlJD0nDct+P5a22I4EhvoQCRQ8reaPCm1lybR3uiRIJsj+8zkVvLwby9LXzfZorlNG9ofjd00FEmB09uW/YvTl6Q9XwwwX6tInzIOv3TMqTHHGOL74ibbj8J/FJR0cFEyj0z4WQRvtkh32xAHl83gbuINryMt0sqRI+clj2381NKL55DRLQrVw0gsfqqxiHAnXg21qWmc4J+b9e9kiuAFQjcjwTVkwJCcg3xbPwC/qokYRby/Y5S40UUd7/jEARGXT7RZgpzTuDd1oZiCVrnrqJNPaMNdVv5MLeFdf1B7iIe5aa/fGouX7AO4SdKhZUdnJmCFAGvjC6S3JMZ2wAcUl+OHnssfmdj7XL50cLo27vjuzMtLAgSqi6N99m92WCF2s8J9aVzszX7Xz9OKZCeGsiVJp3/NdABKzSEAyM9xBD/5Vho894Sav+otpySHe3p6RUTgbB5Zu8VyZRZ/UtB3ueXxyo764yrc6qWIDqrehm84Xm9g+/jpIBzGPl07NUNJpdt/6Sgf9RIKXw/7XypO5yZfUcuFNGTxLfqjTNrtgLZNcjfav6sSdVXVcMPL//XNuRdKmVFaO76eV/oGMQGr1fGcCD+N+CpI7+Q+fCNB6VFWG4nZFuI/Iuw== averdagu@redhat.com"
2025-10-10 08:44:09.083345 | controller | }
2025-10-10 08:44:09.304221 | controller | changed:
2025-10-10 08:44:09.304325 | controller | {
2025-10-10 08:44:09.304587 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDq8l27xI+QlQVdS4djp9ogSoyrNE2+Ox6vKPdhSNL1J3PE5w+WCSvMz9A5gnNuH810zwbekEApbxTze/gLQJwBHA52CChfURpXrFaxY7ePXRElwKAL3mJfzBWY/c5jnNL9TCVmFJTGZkFZP3Nh+BMgZvL6xBkt3WKm6Uq18qzd9XeKcZusrA+O+uLv1fVeQnadY9RIqOCyeFYCzLWrUfTyE8x/XG0hAWIM7qpnF2cALQS2h9n4hW5ybiUN790H08wf9hFwEf5nxY9Z9dVkPFQiTSGKNBzmnCXU9skxS/xhpFjJ5duGSZdtAHe9O+nGZm9c67hxgtf8e5PDuqAdXEv2cf6e3VBAt+Bz8EKI3yosTj0oZHfwr42Yzb1l/SKy14Rggsrc9KAQlrGXan6+u2jcQqqx7l+SWmnpFiWTV9u5cWj2IgOhApOitmRBPYqk9rE2usfO0hLn/Pj/R/Nau4803e1/EikdLE7Ps95s9mX5jRDjAoUa2JwFF5RsVFyL910= ashigupt@ashigupt.remote.csb"
2025-10-10 08:44:09.304747 | controller | }
2025-10-10 08:44:09.524181 | controller | changed:
2025-10-10 08:44:09.524271 | controller | {
2025-10-10 08:44:09.524298 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-10-10 08:44:09.524318 | controller | }
2025-10-10 08:44:09.750621 | controller | changed:
2025-10-10 08:44:09.750805 | controller | {
2025-10-10 08:44:09.750849 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-10-10 08:44:09.750879 | controller | }
2025-10-10 08:44:09.969209 | controller | changed:
2025-10-10 08:44:09.969357 | controller | {
2025-10-10 08:44:09.969383 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-10-10 08:44:09.969404 | controller | }
2025-10-10 08:44:10.188556 | controller | changed:
2025-10-10 08:44:10.188777 | controller | {
2025-10-10 08:44:10.188823 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-10-10 08:44:10.188855 | controller | }
2025-10-10 08:44:10.399557 | controller | changed:
2025-10-10 08:44:10.399694 | controller | {
2025-10-10 08:44:10.399722 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQDZdI7t1cxYx65heVI24HTV4F7oQLW1zyfxHreL2TIJKxjyrUUKIFEUmTutcBlJRLNT2Eoix6x1sOw9YrchloCLcn//SGfTElr9mSc5jbjb7QXEU+zJMhtxyEJ1Po3CUGnj7ckiIXw7wcawZtrEOAQ9pH3ExYCJcEMiyNjRQZCxT3tPK+S4B95EWh5Fsrz9CkwpjNRPPH7LigCeQTM3Wc7r97utAslBUUvYceDSLA7rMgkitJE38b7rZBeYzsGQ8YYUBjTCtehqQXxCRjizbHWaaZkBU+N3zkKB6n/iCNGIO690NK7A/qb6msTijiz1PeuM8ThOsi9qXnbX5v0PoTpcFSojV7NHAQ71f0XXuS43FhZctT+Dcx44dT8Fb5vJu2cJGrk+qF8ZgJYNpRS7gPg0EG2EqjK7JMf9ULdjSu0r+KlqIAyLvtzT4eOnQipoKlb/WG5D/0ohKv7OMQ352ggfkBFIQsRXyyTCT98Ft9juqPuahi3CAQmP4H9dyE+7+Kz437PEtsxLmfm6naNmWi7Ee1DqWPwS8rEajsm4sNM4wW9gdBboJQtc0uZw0DfLj1I9r3Mc8Ol0jYtz0yNQDSzVLrGCaJlC311trU70tZ+ZkAVV6Mn8lOhSbj1cK0lvSr6ZK4dgqGl3I1eTZJJhbLNdg7UOVaiRx9543+C/p/As7w== brjackma@redhat.com"
2025-10-10 08:44:10.399753 | controller | }
2025-10-10 08:44:10.624447 | controller | changed:
2025-10-10 08:44:10.624541 | controller | {
2025-10-10 08:44:10.624571 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-10-10 08:44:10.624592 | controller | }
2025-10-10 08:44:10.839686 | controller | changed:
2025-10-10 08:44:10.839822 | controller | {
2025-10-10 08:44:10.839850 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-10-10 08:44:10.839872 | controller | }
2025-10-10 08:44:11.054622 | controller | changed:
2025-10-10 08:44:11.054713 | controller | {
2025-10-10 08:44:11.054755 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-10-10 08:44:11.054778 | controller | }
2025-10-10 08:44:11.071856 | 
2025-10-10 08:44:11.071947 | TASK [Set timezone to UTC]
2025-10-10 08:44:11.506334 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-10-10 08:44:11.512810 | 
2025-10-10 08:44:11.512887 | TASK [Create nodepool directory]
2025-10-10 08:44:11.745368 | controller | changed
2025-10-10 08:44:11.751667 | 
2025-10-10 08:44:11.751765 | TASK [Create nodepool sub_nodes file]
2025-10-10 08:44:12.348903 | controller | changed
2025-10-10 08:44:12.354525 | 
2025-10-10 08:44:12.354600 | TASK [Create nodepool sub_nodes_private file]
2025-10-10 08:44:12.890527 | controller | changed
2025-10-10 08:44:12.896063 | 
2025-10-10 08:44:12.896137 | LOOP [Populate nodepool sub_nodes file]
2025-10-10 08:44:12.928826 | 
2025-10-10 08:44:12.928984 | LOOP [Populate nodepool sub_nodes_private file]
2025-10-10 08:44:12.960990 | 
2025-10-10 08:44:12.961121 | TASK [Create nodepool primary file]
2025-10-10 08:44:12.984985 | controller | skipping: Conditional result was False
2025-10-10 08:44:12.991087 | 
2025-10-10 08:44:12.991172 | TASK [Create nodepool node_private for this node]
2025-10-10 08:44:13.659234 | controller | changed
2025-10-10 08:44:13.664721 | 
2025-10-10 08:44:13.664798 | LOOP [Copy ssh keys to nodepool directory]
2025-10-10 08:44:14.011396 | controller | ok: Item: id_rsa Runtime: 0:00:00.007958
2025-10-10 08:44:14.011580 | 
2025-10-10 08:44:14.181328 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.005257
2025-10-10 08:44:14.191902 | 
2025-10-10 08:44:14.192098 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-10-10 08:44:14.773341 | controller | changed
2025-10-10 08:44:14.778316 | 
2025-10-10 08:44:14.778381 | TASK [Validate sudoers config after edits]
2025-10-10 08:44:15.005808 | controller | /etc/sudoers: parsed OK
2025-10-10 08:44:15.005891 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-10-10 08:44:15.005903 | controller | /etc/sudoers.d/zuul: parsed OK
2025-10-10 08:44:15.005909 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-10-10 08:44:15.303527 | controller | ok: Runtime: 0:00:00.006285
2025-10-10 08:44:15.309017 | 
2025-10-10 08:44:15.309080 | TASK [Show the environment passed in to job shell scripts]
2025-10-10 08:44:15.525483 | controller | SHELL=/bin/bash
2025-10-10 08:44:15.525555 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-10-10 08:44:15.525565 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-10-10 08:44:15.525572 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/68/3368/c2b06211d634b2d96a02011714110041916012e4
2025-10-10 08:44:15.525631 | controller | PWD=/home/zuul
2025-10-10 08:44:15.525640 | controller | ZUUL_PIPELINE=github-check
2025-10-10 08:44:15.525646 | controller | LOGNAME=zuul
2025-10-10 08:44:15.525651 | controller | XDG_SESSION_TYPE=tty
2025-10-10 08:44:15.525657 | controller | _=/usr/bin/env
2025-10-10 08:44:15.525662 | controller | MOTD_SHOWN=pam
2025-10-10 08:44:15.525668 | controller | HOME=/home/zuul
2025-10-10 08:44:15.525673 | controller | LANG=en_US.UTF-8
2025-10-10 08:44:15.525678 | controller | SSH_CONNECTION=192.168.26.12 53502 192.168.26.90 22
2025-10-10 08:44:15.525684 | controller | TOX_TESTENV_PASSENV=ZUUL_UUID ZUUL_PROJECT ZUUL_SHORT_PROJECT_NAME ZUUL_PIPELINE ZUUL_VOTING WORKSPACE BUILD_TIMEOUT ZUUL_BRANCH ZUUL_CHANGES ZUUL_CHANGE_IDS ZUUL_CHANGE ZUUL_PATCHSET
2025-10-10 08:44:15.525694 | controller | ZUUL_CHANGE_IDS=3368,c2b06211d634b2d96a02011714110041916012e4
2025-10-10 08:44:15.525700 | controller | WORKSPACE=/home/zuul/workspace
2025-10-10 08:44:15.525706 | controller | XDG_SESSION_CLASS=user
2025-10-10 08:44:15.525711 | controller | SELINUX_ROLE_REQUESTED=
2025-10-10 08:44:15.525717 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-10-10 08:44:15.525722 | controller | USER=zuul
2025-10-10 08:44:15.525728 | controller | ZUUL_VOTING=True
2025-10-10 08:44:15.525733 | controller | BUILD_TIMEOUT=1800000
2025-10-10 08:44:15.525739 | controller | SELINUX_USE_CURRENT_RANGE=
2025-10-10 08:44:15.525744 | controller | SHLVL=1
2025-10-10 08:44:15.525750 | controller | ZUUL_PATCHSET=c2b06211d634b2d96a02011714110041916012e4
2025-10-10 08:44:15.525755 | controller | XDG_SESSION_ID=1
2025-10-10 08:44:15.525761 | controller | ZUUL_BRANCH=main
2025-10-10 08:44:15.525766 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-10-10 08:44:15.525772 | controller | SSH_CLIENT=192.168.26.12 53502 22
2025-10-10 08:44:15.525777 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-10-10 08:44:15.525783 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-10-10 08:44:15.525788 | controller | which_declare=declare -f
2025-10-10 08:44:15.525795 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2025-10-10 08:44:15.525800 | controller | SELINUX_LEVEL_REQUESTED=
2025-10-10 08:44:15.525806 | controller | ZUUL_CHANGE=3368
2025-10-10 08:44:15.525812 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-10-10 08:44:15.525817 | controller | ZUUL_UUID=0341fee42c1e4c0abc747939cb2f8d37
2025-10-10 08:44:15.525823 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-10-10 08:44:15.525830 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-10-10 08:44:15.525836 | controller | }
2025-10-10 08:44:15.834664 | controller | ok: Runtime: 0:00:00.007062
2025-10-10 08:44:15.840081 | 
2025-10-10 08:44:15.840235 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-10-10 08:44:15.863861 | controller | skipping: Conditional result was False
2025-10-10 08:44:15.869829 | 
2025-10-10 08:44:15.869925 | TASK [Symlink /home/zuul-worker/workspace]
2025-10-10 08:44:16.395473 | controller | skipping: Conditional result was False
2025-10-10 08:44:16.401993 | 
2025-10-10 08:44:16.402282 | TASK [Ensure legacy workspace directory]
2025-10-10 08:44:16.602902 | controller | changed
2025-10-10 08:44:16.621874 | 
2025-10-10 08:44:16.621978 | PLAY RECAP
2025-10-10 08:44:16.622027 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-10-10 08:44:16.622052 | 
2025-10-10 08:44:16.700099 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-10-10 08:44:16.700893 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-10-10 08:44:17.232728 | 
2025-10-10 08:44:17.232930 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-10-10 08:44:17.263311 | 
2025-10-10 08:44:17.263401 | TASK [Create zuul-output directory]
2025-10-10 08:44:17.587645 | controller | changed
2025-10-10 08:44:17.592715 | 
2025-10-10 08:44:17.592804 | TASK [Slurp Zuul inventory test]
2025-10-10 08:44:17.879674 | controller -> localhost | ok
2025-10-10 08:44:17.886009 | 
2025-10-10 08:44:17.886207 | TASK [Save zuul inventory]
2025-10-10 08:44:18.685020 | controller | changed
2025-10-10 08:44:18.690331 | 
2025-10-10 08:44:18.690401 | TASK [Save zuul vars without the change_message]
2025-10-10 08:44:19.276728 | controller | changed
2025-10-10 08:44:19.296236 | 
2025-10-10 08:44:19.296306 | PLAY RECAP
2025-10-10 08:44:19.296347 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-10 08:44:19.296369 | 
2025-10-10 08:44:19.372279 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-10-10 08:44:19.373077 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-10-10 08:44:19.931885 | 
2025-10-10 08:44:19.932010 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2025-10-10 08:44:19.952017 | 
2025-10-10 08:44:19.952089 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2025-10-10 08:44:19.970076 | controller | ok
2025-10-10 08:44:19.986507 | 
2025-10-10 08:44:19.986588 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-10-10 08:44:20.009544 | controller | skipping: Conditional result was False
2025-10-10 08:44:20.015101 | 
2025-10-10 08:44:20.015178 | TASK [mirror-info-fork : Create /etc/ci]
2025-10-10 08:44:20.346966 | controller | ok
2025-10-10 08:44:20.353830 | 
2025-10-10 08:44:20.353943 | TASK [mirror-info-fork : Install ci_mirror script]
2025-10-10 08:44:21.025097 | controller | ok
2025-10-10 08:44:21.036185 | 
2025-10-10 08:44:21.036261 | TASK [Prepare workspace]
2025-10-10 08:44:21.054992 | controller | ok
2025-10-10 08:44:21.072754 | 
2025-10-10 08:44:21.072844 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-10-10 08:44:21.397824 | controller | ok
2025-10-10 08:44:21.405179 | 
2025-10-10 08:44:21.405255 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-10-10 08:44:23.826580 | controller | Output suppressed because no_log was given
2025-10-10 08:44:23.837170 | 
2025-10-10 08:44:23.837250 | LOOP [Create zuul-output directory]
2025-10-10 08:44:24.043042 | controller | changed: "/home/zuul/ci-framework-data/logs"
2025-10-10 08:44:24.206229 | controller | ok: "/home/zuul/zuul-output/logs"
2025-10-10 08:44:24.212867 | 
2025-10-10 08:44:24.212989 | TASK [Install required packages]
2025-10-10 08:45:26.152300 | controller | changed
2025-10-10 08:45:26.158562 | 
2025-10-10 08:45:26.158646 | TASK [Install venv]
2025-10-10 08:46:27.606297 | controller | changed
2025-10-10 08:46:27.634158 | 
2025-10-10 08:46:27.634289 | PLAY RECAP
2025-10-10 08:46:27.634350 | controller | ok: 7 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-10-10 08:46:27.634375 | 
2025-10-10 08:46:27.725509 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-10-10 08:46:27.726366 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-10-10 08:46:28.259211 | 
2025-10-10 08:46:28.259322 | PLAY [Run ci/playbooks/molecule-test.yml]
2025-10-10 08:46:28.279703 | 
2025-10-10 08:46:28.279813 | TASK [Gather required facts]
2025-10-10 08:46:28.786841 | controller | ok
2025-10-10 08:46:28.793538 | 
2025-10-10 08:46:28.793674 | TASK [Load environment var if instructed to]
2025-10-10 08:46:28.817937 | controller | skipping: Conditional result was False
2025-10-10 08:46:28.824394 | 
2025-10-10 08:46:28.824497 | TASK [Ensure group_vars dir exists]
2025-10-10 08:46:29.209326 | controller | ok
2025-10-10 08:46:29.215164 | 
2025-10-10 08:46:29.215242 | TASK [Run molecule]
2025-10-10 08:46:30.227830 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2025-10-10 08:46:30.306202 | controller | INFO     Running default > prepare
2025-10-10 08:46:30.974434 | controller |
2025-10-10 08:46:30.974529 | controller | PLAY [Prepare] *****************************************************************
2025-10-10 08:46:30.974542 | controller |
2025-10-10 08:46:30.974910 | controller | TASK [Gathering Facts] *********************************************************
2025-10-10 08:46:30.975019 | controller | Friday 10 October 2025  08:46:30 +0000 (0:00:00.011)       0:00:00.011 ********
2025-10-10 08:46:31.857664 | controller | ok: [instance]
2025-10-10 08:46:31.857723 | controller |
2025-10-10 08:46:31.857767 | controller | TASK [Create dummy env file] ***************************************************
2025-10-10 08:46:31.857879 | controller | Friday 10 October 2025  08:46:31 +0000 (0:00:00.884)       0:00:00.895 ********
2025-10-10 08:46:32.397072 | controller | changed: [instance]
2025-10-10 08:46:32.397132 | controller |
2025-10-10 08:46:32.397325 | controller | TASK [Create dummy playbook] ***************************************************
2025-10-10 08:46:32.397403 | controller | Friday 10 October 2025  08:46:32 +0000 (0:00:00.539)       0:00:01.434 ********
2025-10-10 08:46:34.271709 | controller | changed: [instance] => (item=dummy-1.yml)
2025-10-10 08:46:34.271782 | controller | changed: [instance] => (item=dummy-2.yml)
2025-10-10 08:46:34.271893 | controller | changed: [instance] => (item=dummy-3.yml)
2025-10-10 08:46:34.271989 | controller | changed: [instance] => (item=dummy-4.yml)
2025-10-10 08:46:34.272087 | controller | changed: [instance] => (item=dummy-5.yml)
2025-10-10 08:46:34.272184 | controller | changed: [instance] => (item=dummy-6.yml)
2025-10-10 08:46:34.272318 | controller |
2025-10-10 08:46:34.272424 | controller | TASK [Remove dummy file for retry playbook test] *******************************
2025-10-10 08:46:34.272526 | controller | Friday 10 October 2025  08:46:34 +0000 (0:00:01.874)       0:00:03.309 ********
2025-10-10 08:46:34.555151 | controller | ok: [instance]
2025-10-10 08:46:34.555258 | controller |
2025-10-10 08:46:34.555394 | controller | TASK [Create dummy retry playbook] *********************************************
2025-10-10 08:46:34.555498 | controller | Friday 10 October 2025  08:46:34 +0000 (0:00:00.283)       0:00:03.593 ********
2025-10-10 08:46:34.899517 | controller | changed: [instance]
2025-10-10 08:46:34.899577 | controller |
2025-10-10 08:46:34.899687 | controller | PLAY RECAP *********************************************************************
2025-10-10 08:46:34.899805 | controller | instance                   : ok=5    changed=3    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2025-10-10 08:46:34.899908 | controller |
2025-10-10 08:46:34.900004 | controller | Friday 10 October 2025  08:46:34 +0000 (0:00:00.344)       0:00:03.937 ********
2025-10-10 08:46:34.900110 | controller | ===============================================================================
2025-10-10 08:46:34.900237 | controller | Create dummy playbook --------------------------------------------------- 1.87s
2025-10-10 08:46:34.900347 | controller | Gathering Facts --------------------------------------------------------- 0.88s
2025-10-10 08:46:34.900469 | controller | Create dummy env file --------------------------------------------------- 0.54s
2025-10-10 08:46:34.900560 | controller | Create dummy retry playbook --------------------------------------------- 0.34s
2025-10-10 08:46:34.900650 | controller | Remove dummy file for retry playbook test ------------------------------- 0.28s
2025-10-10 08:46:34.955305 | controller | INFO     Running default > converge
2025-10-10 08:46:35.393732 | controller |
2025-10-10 08:46:35.393791 | controller | PLAY [Converge] ****************************************************************
2025-10-10 08:46:35.394134 | controller |
2025-10-10 08:46:36.189370 | controller | TASK [Gathering Facts] *********************************************************
2025-10-10 08:46:36.189406 | controller | Friday 10 October 2025  08:46:35 +0000 (0:00:00.015)       0:00:00.015 ********
2025-10-10 08:46:36.189424 | controller | ok: [instance]
2025-10-10 08:46:36.189454 | controller |
2025-10-10 08:46:36.189617 | controller | TASK [No hook] *****************************************************************
2025-10-10 08:46:36.218378 | controller | Friday 10 October 2025  08:46:36 +0000 (0:00:00.795)       0:00:00.811 ********
2025-10-10 08:46:36.218420 | controller |
2025-10-10 08:46:36.276196 | controller | TASK [run_hook : Assert parameters are valid] **********************************
2025-10-10 08:46:36.276263 | controller | Friday 10 October 2025  08:46:36 +0000 (0:00:00.029)       0:00:00.840 ********
2025-10-10 08:46:36.276296 | controller | ok: [instance]
2025-10-10 08:46:36.351215 | controller |
2025-10-10 08:46:36.351309 | controller | TASK [run_hook : Assert single hooks are all mappings] *************************
2025-10-10 08:46:36.351324 | controller | Friday 10 October 2025  08:46:36 +0000 (0:00:00.057)       0:00:00.897 ********
2025-10-10 08:46:36.351337 | controller | ok: [instance]
2025-10-10 08:46:36.426766 | controller |
2025-10-10 08:46:36.426802 | controller | TASK [run_hook : Loop on hooks for no_hook] ************************************
2025-10-10 08:46:36.426810 | controller | Friday 10 October 2025  08:46:36 +0000 (0:00:00.074)       0:00:00.972 ********
2025-10-10 08:46:36.426820 | controller | skipping: [instance]
2025-10-10 08:46:36.426965 | controller |
2025-10-10 08:46:36.427115 | controller | TASK [Ensure we do not have ceph_uuid] *****************************************
2025-10-10 08:46:36.427310 | controller | Friday 10 October 2025  08:46:36 +0000 (0:00:00.075)       0:00:01.048 ********
2025-10-10 08:46:36.451864 | controller | ok: [instance] => changed=false
2025-10-10 08:46:36.452047 | controller |   msg: All assertions passed
2025-10-10 08:46:36.452183 | controller |
2025-10-10 08:46:36.452366 | controller | TASK [Combined hooks] **********************************************************
2025-10-10 08:46:36.452512 | controller | Friday 10 October 2025  08:46:36 +0000 (0:00:00.025)       0:00:01.073 ********
2025-10-10 08:46:36.482682 | controller |
2025-10-10 08:46:36.482872 | controller | TASK [run_hook : Assert parameters are valid] **********************************
2025-10-10 08:46:36.483017 | controller | Friday 10 October 2025  08:46:36 +0000 (0:00:00.030)       0:00:01.104 ********
2025-10-10 08:46:36.542558 | controller | ok: [instance]
2025-10-10 08:46:36.542759 | controller |
2025-10-10 08:46:36.542929 | controller | TASK [run_hook : Assert single hooks are all mappings] *************************
2025-10-10 08:46:36.543067 | controller | Friday 10 October 2025  08:46:36 +0000 (0:00:00.059)       0:00:01.164 ********
2025-10-10 08:46:36.617025 | controller | ok: [instance]
2025-10-10 08:46:36.617198 | controller |
2025-10-10 08:46:36.617395 | controller | TASK [run_hook : Loop on hooks for run_molecule] *******************************
2025-10-10 08:46:36.617542 | controller | Friday 10 October 2025  08:46:36 +0000 (0:00:00.074)       0:00:01.238 ********
2025-10-10 08:46:36.741339 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/run_hook/tasks/playbook.yml for instance => (item={'name': '01 Default noop hook', 'source': 'noop.yml', 'type': 'playbook'})
2025-10-10 08:46:36.741548 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/run_hook/tasks/playbook.yml for instance => (item={'name': '02 Re-run noop', 'source': 'noop.yml', 'type': 'playbook'})
2025-10-10 08:46:36.741711 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/run_hook/tasks/playbook.yml for instance => (item={'source': '/tmp/dummy-1.yml', 'type': 'playbook', 'extra_vars': {'foo': 'bar', 'file': '/tmp/dummy-env.yml'}, 'name': '03 single hook'})
2025-10-10 08:46:36.741849 | controller |
2025-10-10 08:46:36.741987 | controller | TASK [run_hook : Set playbook path for 01 Default noop hook] *******************
2025-10-10 08:46:36.742121 | controller | Friday 10 October 2025  08:46:36 +0000 (0:00:00.124)       0:00:01.363 ********
2025-10-10 08:46:36.791353 | controller | ok: [instance]
2025-10-10 08:46:36.791529 | controller |
2025-10-10 08:46:36.791681 | controller | TASK [run_hook : Get file stat] ************************************************
2025-10-10 08:46:36.791823 | controller | Friday 10 October 2025  08:46:36 +0000 (0:00:00.049)       0:00:01.413 ********
2025-10-10 08:46:37.094262 | controller | ok: [instance]
2025-10-10 08:46:37.111783 | controller |
2025-10-10 08:46:37.111814 | controller | TASK [run_hook : Fail if playbook doesn't exist] *******************************
2025-10-10 08:46:37.111828 | controller | Friday 10 October 2025  08:46:37 +0000 (0:00:00.302)       0:00:01.715 ********
2025-10-10 08:46:37.111840 | controller | skipping: [instance]
2025-10-10 08:46:37.410754 | controller |
2025-10-10 08:46:37.410790 | controller | TASK [run_hook : Get parameters files] *****************************************
2025-10-10 08:46:37.410798 | controller | Friday 10 October 2025  08:46:37 +0000 (0:00:00.017)       0:00:01.733 ********
2025-10-10 08:46:37.410815 | controller | ok: [instance]
2025-10-10 08:46:37.431590 | controller |
2025-10-10 08:46:37.431620 | controller | TASK [run_hook : Add parameters artifacts as extra variables] ******************
2025-10-10 08:46:37.431628 | controller | Friday 10 October 2025  08:46:37 +0000 (0:00:00.298)       0:00:02.032 ********
2025-10-10 08:46:37.431638 | controller | ok: [instance]
2025-10-10 08:46:37.730801 | controller |
2025-10-10 08:46:37.730832 | controller | TASK [run_hook : Ensure log directory exists] **********************************
2025-10-10 08:46:37.730840 | controller | Friday 10 October 2025  08:46:37 +0000 (0:00:00.020)       0:00:02.053 ********
2025-10-10 08:46:37.730851 | controller | ok: [instance]
2025-10-10 08:46:37.916946 | controller |
2025-10-10 08:46:37.916978 | controller | TASK [run_hook : Ensure artifacts directory exists] ****************************
2025-10-10 08:46:37.916986 | controller | Friday 10 October 2025  08:46:37 +0000 (0:00:00.299)       0:00:02.352 ********
2025-10-10 08:46:37.916996 | controller | ok: [instance]
2025-10-10 08:46:38.529325 | controller |
2025-10-10 08:46:38.529363 | controller | TASK [run_hook : Run hook without retry - 01 Default noop hook] ****************
2025-10-10 08:46:38.529371 | controller | Friday 10 October 2025  08:46:37 +0000 (0:00:00.186)       0:00:02.538 ********
2025-10-10 08:46:38.529382 | controller | Follow script's output here: /home/zuul/ci-framework-data/logs/ci_script_000_run_hook_without_retry_01.log
2025-10-10 08:46:38.559387 | controller | changed: [instance]
2025-10-10 08:46:38.559423 | controller |
2025-10-10 08:46:38.559431 | controller | TASK [run_hook : Run hook with retry - 01 Default noop hook] *******************
2025-10-10 08:46:38.559441 | controller | Friday 10 October 2025  08:46:38 +0000 (0:00:00.612)       0:00:03.150 ********
2025-10-10 08:46:38.559452 | controller | skipping: [instance]
2025-10-10 08:46:38.559476 | controller |
2025-10-10 08:46:38.559484 | controller | TASK [run_hook : Check if we have a file] **************************************
2025-10-10 08:46:38.559611 | controller | Friday 10 October 2025  08:46:38 +0000 (0:00:00.030)       0:00:03.181 ********
2025-10-10 08:46:38.736986 | controller | ok: [instance]
2025-10-10 08:46:38.755657 | controller |
2025-10-10 08:46:38.755691 | controller | TASK [run_hook : Load generated content in main playbook] **********************
2025-10-10 08:46:38.755701 | controller | Friday 10 October 2025  08:46:38 +0000 (0:00:00.177)       0:00:03.358 ********
2025-10-10 08:46:38.755713 | controller | skipping: [instance]
2025-10-10 08:46:38.805163 | controller |
2025-10-10 08:46:38.805197 | controller | TASK [run_hook : Set playbook path for 02 Re-run noop] *************************
2025-10-10 08:46:38.805205 | controller | Friday 10 October 2025  08:46:38 +0000 (0:00:00.018)       0:00:03.377 ********
2025-10-10 08:46:38.805215 | controller | ok: [instance]
2025-10-10 08:46:39.003863 | controller |
2025-10-10 08:46:39.003899 | controller | TASK [run_hook : Get file stat] ************************************************
2025-10-10 08:46:39.003907 | controller | Friday 10 October 2025  08:46:38 +0000 (0:00:00.049)       0:00:03.426 ********
2025-10-10 08:46:39.003918 | controller | ok: [instance]
2025-10-10 08:46:39.021789 | controller |
2025-10-10 08:46:39.021818 | controller | TASK [run_hook : Fail if playbook doesn't exist] *******************************
2025-10-10 08:46:39.021825 | controller | Friday 10 October 2025  08:46:39 +0000 (0:00:00.198)       0:00:03.625 ********
2025-10-10 08:46:39.021836 | controller | skipping: [instance]
2025-10-10 08:46:39.212962 | controller |
2025-10-10 08:46:39.212995 | controller | TASK [run_hook : Get parameters files] *****************************************
2025-10-10 08:46:39.213004 | controller | Friday 10 October 2025  08:46:39 +0000 (0:00:00.018)       0:00:03.643 ********
2025-10-10 08:46:39.213015 | controller | ok: [instance]
2025-10-10 08:46:39.232362 | controller |
2025-10-10 08:46:39.232399 | controller | TASK [run_hook : Add parameters artifacts as extra variables] ******************
2025-10-10 08:46:39.232407 | controller | Friday 10 October 2025  08:46:39 +0000 (0:00:00.190)       0:00:03.834 ********
2025-10-10 08:46:39.232419 | controller | ok: [instance]
2025-10-10 08:46:39.232444 | controller |
2025-10-10 08:46:39.232718 | controller | TASK [run_hook : Ensure log directory exists] **********************************
2025-10-10 08:46:39.427359 | controller | Friday 10 October 2025  08:46:39 +0000 (0:00:00.019)       0:00:03.854 ********
2025-10-10 08:46:39.427397 | controller | ok: [instance]
2025-10-10 08:46:39.612533 | controller |
2025-10-10 08:46:39.612572 | controller | TASK [run_hook : Ensure artifacts directory exists] ****************************
2025-10-10 08:46:39.612581 | controller | Friday 10 October 2025  08:46:39 +0000 (0:00:00.194)       0:00:04.048 ********
2025-10-10 08:46:39.612591 | controller | ok: [instance]
2025-10-10 08:46:40.309108 | controller |
2025-10-10 08:46:40.309140 | controller | TASK [run_hook : Run hook without retry - 02 Re-run noop] **********************
2025-10-10 08:46:40.309148 | controller | Friday 10 October 2025  08:46:39 +0000 (0:00:00.185)       0:00:04.234 ********
2025-10-10 08:46:40.309158 | controller | Follow script's output here: /home/zuul/ci-framework-data/logs/ci_script_001_run_hook_without_retry_02_re.log
2025-10-10 08:46:40.339434 | controller | changed: [instance]
2025-10-10 08:46:40.339489 | controller |
2025-10-10 08:46:40.339497 | controller | TASK [run_hook : Run hook with retry - 02 Re-run noop] *************************
2025-10-10 08:46:40.339504 | controller | Friday 10 October 2025  08:46:40 +0000 (0:00:00.696)       0:00:04.930 ********
2025-10-10 08:46:40.339518 | controller | skipping: [instance]
2025-10-10 08:46:40.538664 | controller |
2025-10-10 08:46:40.538718 | controller | TASK [run_hook : Check if we have a file] **************************************
2025-10-10 08:46:40.538727 | controller | Friday 10 October 2025  08:46:40 +0000 (0:00:00.030)       0:00:04.961 ********
2025-10-10 08:46:40.538741 | controller | ok: [instance]
2025-10-10 08:46:40.558077 | controller |
2025-10-10 08:46:40.558116 | controller | TASK [run_hook : Load generated content in main playbook] **********************
2025-10-10 08:46:40.558125 | controller | Friday 10 October 2025  08:46:40 +0000 (0:00:00.199)       0:00:05.160 ********
2025-10-10 08:46:40.558137 | controller | skipping: [instance]
2025-10-10 08:46:40.607817 | controller |
2025-10-10 08:46:40.607867 | controller | TASK [run_hook : Set playbook path for 03 single hook] *************************
2025-10-10 08:46:40.607874 | controller | Friday 10 October 2025  08:46:40 +0000 (0:00:00.019)       0:00:05.179 ********
2025-10-10 08:46:40.607886 | controller | ok: [instance]
2025-10-10 08:46:40.804565 | controller |
2025-10-10 08:46:40.804609 | controller | TASK [run_hook : Get file stat] ************************************************
2025-10-10 08:46:40.804617 | controller | Friday 10 October 2025  08:46:40 +0000 (0:00:00.049)       0:00:05.229 ********
2025-10-10 08:46:40.804631 | controller | ok: [instance]
2025-10-10 08:46:40.823363 | controller |
2025-10-10 08:46:40.823379 | controller | TASK [run_hook : Fail if playbook doesn't exist] *******************************
2025-10-10 08:46:40.823388 | controller | Friday 10 October 2025  08:46:40 +0000 (0:00:00.196)       0:00:05.426 ********
2025-10-10 08:46:40.823398 | controller | skipping: [instance]
2025-10-10 08:46:40.823639 | controller |
2025-10-10 08:46:40.823675 | controller | TASK [run_hook : Get parameters files] *****************************************
2025-10-10 08:46:41.015736 | controller | Friday 10 October 2025  08:46:40 +0000 (0:00:00.019)       0:00:05.445 ********
2025-10-10 08:46:41.015772 | controller | ok: [instance]
2025-10-10 08:46:41.035784 | controller |
2025-10-10 08:46:41.035813 | controller | TASK [run_hook : Add parameters artifacts as extra variables] ******************
2025-10-10 08:46:41.035823 | controller | Friday 10 October 2025  08:46:41 +0000 (0:00:00.192)       0:00:05.637 ********
2025-10-10 08:46:41.035834 | controller | ok: [instance]
2025-10-10 08:46:41.232471 | controller |
2025-10-10 08:46:41.232504 | controller | TASK [run_hook : Ensure log directory exists] **********************************
2025-10-10 08:46:41.232512 | controller | Friday 10 October 2025  08:46:41 +0000 (0:00:00.020)       0:00:05.657 ********
2025-10-10 08:46:41.232523 | controller | ok: [instance]
2025-10-10 08:46:41.418298 | controller |
2025-10-10 08:46:41.418332 | controller | TASK [run_hook : Ensure artifacts directory exists] ****************************
2025-10-10 08:46:41.418340 | controller | Friday 10 October 2025  08:46:41 +0000 (0:00:00.196)       0:00:05.854 ********
2025-10-10 08:46:41.418351 | controller | ok: [instance]
2025-10-10 08:46:43.425139 | controller |
2025-10-10 08:46:43.425174 | controller | TASK [run_hook : Run hook without retry - 03 single hook] **********************
2025-10-10 08:46:43.425181 | controller | Friday 10 October 2025  08:46:41 +0000 (0:00:00.185)       0:00:06.039 ********
2025-10-10 08:46:43.425192 | controller | Follow script's output here: /home/zuul/ci-framework-data/logs/ci_script_002_run_hook_without_retry_03.log
2025-10-10 08:46:43.456178 | controller | changed: [instance]
2025-10-10 08:46:43.456217 | controller |
2025-10-10 08:46:43.456225 | controller | TASK [run_hook : Run hook with retry - 03 single hook] *************************
2025-10-10 08:46:43.456247 | controller | Friday 10 October 2025  08:46:43 +0000 (0:00:02.006)       0:00:08.046 ********
2025-10-10 08:46:43.456257 | controller | skipping: [instance]
2025-10-10 08:46:43.647840 | controller |
2025-10-10 08:46:43.647872 | controller | TASK [run_hook : Check if we have a file] **************************************
2025-10-10 08:46:43.647880 | controller | Friday 10 October 2025  08:46:43 +0000 (0:00:00.031)       0:00:08.077 ********
2025-10-10 08:46:43.647896 | controller | ok: [instance]
2025-10-10 08:46:43.672912 | controller |
2025-10-10 08:46:43.672942 | controller | TASK [run_hook : Load generated content in main playbook] **********************
2025-10-10 08:46:43.672950 | controller | Friday 10 October 2025  08:46:43 +0000 (0:00:00.191)       0:00:08.269 ********
2025-10-10 08:46:43.672960 | controller | ok: [instance]
2025-10-10 08:46:43.696341 | controller |
2025-10-10 08:46:43.696371 | controller | TASK [Ensure we have the ceph_uuid variable now] *******************************
2025-10-10 08:46:43.696379 | controller | Friday 10 October 2025  08:46:43 +0000 (0:00:00.025)       0:00:08.294 ********
2025-10-10 08:46:43.696390 | controller | ok: [instance] => changed=false
2025-10-10 08:46:43.696411 | controller |   msg: All assertions passed
2025-10-10 08:46:43.696419 | controller |
2025-10-10 08:46:43.696574 | controller | TASK [Only listed hooks] *******************************************************
2025-10-10 08:46:43.727319 | controller | Friday 10 October 2025  08:46:43 +0000 (0:00:00.023)       0:00:08.318 ********
2025-10-10 08:46:43.727352 | controller |
2025-10-10 08:46:43.784758 | controller | TASK [run_hook : Assert parameters are valid] **********************************
2025-10-10 08:46:43.784786 | controller | Friday 10 October 2025  08:46:43 +0000 (0:00:00.030)       0:00:08.349 ********
2025-10-10 08:46:43.784798 | controller | ok: [instance]
2025-10-10 08:46:43.857067 | controller |
2025-10-10 08:46:43.857099 | controller | TASK [run_hook : Assert single hooks are all mappings] *************************
2025-10-10 08:46:43.857107 | controller | Friday 10 October 2025  08:46:43 +0000 (0:00:00.057)       0:00:08.406 ********
2025-10-10 08:46:43.857117 | controller | ok: [instance]
2025-10-10 08:46:43.961347 | controller |
2025-10-10 08:46:43.961380 | controller | TASK [run_hook : Loop on hooks for list_hooks] *********************************
2025-10-10 08:46:43.961388 | controller | Friday 10 October 2025  08:46:43 +0000 (0:00:00.072)       0:00:08.478 ********
2025-10-10 08:46:43.961399 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/run_hook/tasks/playbook.yml for instance => (item={'name': 'Run dummy-2', 'source': '/tmp/dummy-2.yml', 'type': 'playbook', 'extra_vars': {'foo': 'bar', 'file': '/tmp/dummy-env.yml'}})
2025-10-10 08:46:43.961431 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/run_hook/tasks/playbook.yml for instance => (item={'name': 'Run dummy-3', 'source': '/tmp/dummy-3.yml', 'type': 'playbook', 'extra_vars': {'foo': 'bar', 'file': '/tmp/dummy-env.yml'}})
2025-10-10 08:46:43.961439 | controller |
2025-10-10 08:46:43.961445 | controller | TASK [run_hook : Set playbook path for Run dummy-2] ****************************
2025-10-10 08:46:43.961452 | controller | Friday 10 October 2025  08:46:43 +0000 (0:00:00.104)       0:00:08.582 ********
2025-10-10 08:46:44.008063 | controller | ok: [instance]
2025-10-10 08:46:44.185196 | controller |
2025-10-10 08:46:44.185244 | controller | TASK [run_hook : Get file stat] ************************************************
2025-10-10 08:46:44.185255 | controller | Friday 10 October 2025  08:46:44 +0000 (0:00:00.046)       0:00:08.629 ********
2025-10-10 08:46:44.185266 | controller | ok: [instance]
2025-10-10 08:46:44.200569 | controller |
2025-10-10 08:46:44.200590 | controller | TASK [run_hook : Fail if playbook doesn't exist] *******************************
2025-10-10 08:46:44.200597 | controller | Friday 10 October 2025  08:46:44 +0000 (0:00:00.177)       0:00:08.806 ********
2025-10-10 08:46:44.200607 | controller | skipping: [instance]
2025-10-10 08:46:44.378361 | controller |
2025-10-10 08:46:44.378393 | controller | TASK [run_hook : Get parameters files] *****************************************
2025-10-10 08:46:44.378400 | controller | Friday 10 October 2025  08:46:44 +0000 (0:00:00.015)       0:00:08.822 ********
2025-10-10 08:46:44.378411 | controller | ok: [instance]
2025-10-10 08:46:44.378828 | controller |
2025-10-10 08:46:44.396855 | controller | TASK [run_hook : Add parameters artifacts as extra variables] ******************
2025-10-10 08:46:44.396876 | controller | Friday 10 October 2025  08:46:44 +0000 (0:00:00.177)       0:00:09.000 ********
2025-10-10 08:46:44.396886 | controller | ok: [instance]
2025-10-10 08:46:44.585311 | controller |
2025-10-10 08:46:44.585344 | controller | TASK [run_hook : Ensure log directory exists] **********************************
2025-10-10 08:46:44.585353 | controller | Friday 10 October 2025  08:46:44 +0000 (0:00:00.018)       0:00:09.018 ********
2025-10-10 08:46:44.585364 | controller | ok: [instance]
2025-10-10 08:46:44.759225 | controller |
2025-10-10 08:46:44.759332 | controller | TASK [run_hook : Ensure artifacts directory exists] ****************************
2025-10-10 08:46:44.759345 | controller | Friday 10 October 2025  08:46:44 +0000 (0:00:00.188)       0:00:09.206 ********
2025-10-10 08:46:44.759356 | controller | ok: [instance]
2025-10-10 08:46:46.641021 | controller |
2025-10-10 08:46:46.641054 | controller | TASK [run_hook : Run hook without retry - Run dummy-2] *************************
2025-10-10 08:46:46.641062 | controller | Friday 10 October 2025  08:46:44 +0000 (0:00:00.174)       0:00:09.380 ********
2025-10-10 08:46:46.641072 | controller | Follow script's output here: /home/zuul/ci-framework-data/logs/ci_script_003_run_hook_without_retry_run.log
2025-10-10 08:46:46.671389 | controller | changed: [instance]
2025-10-10 08:46:46.671424 | controller |
2025-10-10 08:46:46.671431 | controller | TASK [run_hook : Run hook with retry - Run dummy-2] ****************************
2025-10-10 08:46:46.671438 | controller | Friday 10 October 2025  08:46:46 +0000 (0:00:01.881)       0:00:11.262 ********
2025-10-10 08:46:46.671448 | controller | skipping: [instance]
2025-10-10 08:46:46.860540 | controller |
2025-10-10 08:46:46.860570 | controller | TASK [run_hook : Check if we have a file] **************************************
2025-10-10 08:46:46.860583 | controller | Friday 10 October 2025  08:46:46 +0000 (0:00:00.030)       0:00:11.293 ********
2025-10-10 08:46:46.860594 | controller | ok: [instance]
2025-10-10 08:46:46.883376 | controller |
2025-10-10 08:46:46.883411 | controller | TASK [run_hook : Load generated content in main playbook] **********************
2025-10-10 08:46:46.883419 | controller | Friday 10 October 2025  08:46:46 +0000 (0:00:00.188)       0:00:11.482 ********
2025-10-10 08:46:46.883430 | controller | ok: [instance]
2025-10-10 08:46:46.883454 | controller |
2025-10-10 08:46:46.883462 | controller | TASK [run_hook : Set playbook path for Run dummy-3] ****************************
2025-10-10 08:46:46.883595 | controller | Friday 10 October 2025  08:46:46 +0000 (0:00:00.023)       0:00:11.505 ********
2025-10-10 08:46:46.931911 | controller | ok: [instance]
2025-10-10 08:46:46.932138 | controller |
2025-10-10 08:46:46.932161 | controller | TASK [run_hook : Get file stat] ************************************************
2025-10-10 08:46:47.122927 | controller | Friday 10 October 2025  08:46:46 +0000 (0:00:00.048)       0:00:11.553 ********
2025-10-10 08:46:47.122962 | controller | ok: [instance]
2025-10-10 08:46:47.144090 | controller |
2025-10-10 08:46:47.144107 | controller | TASK [run_hook : Fail if playbook doesn't exist] *******************************
2025-10-10 08:46:47.144114 | controller | Friday 10 October 2025  08:46:47 +0000 (0:00:00.190)       0:00:11.744 ********
2025-10-10 08:46:47.144123 | controller | skipping: [instance]
2025-10-10 08:46:47.346035 | controller |
2025-10-10 08:46:47.346069 | controller | TASK [run_hook : Get parameters files] *****************************************
2025-10-10 08:46:47.346077 | controller | Friday 10 October 2025  08:46:47 +0000 (0:00:00.021)       0:00:11.765 ********
2025-10-10 08:46:47.346088 | controller | ok: [instance]
2025-10-10 08:46:47.381400 | controller |
2025-10-10 08:46:47.381437 | controller | TASK [run_hook : Add parameters artifacts as extra variables] ******************
2025-10-10 08:46:47.381447 | controller | Friday 10 October 2025  08:46:47 +0000 (0:00:00.201)       0:00:11.967 ********
2025-10-10 08:46:47.381460 | controller | ok: [instance]
2025-10-10 08:46:47.381647 | controller |
2025-10-10 08:46:47.381679 | controller | TASK [run_hook : Ensure log directory exists] **********************************
2025-10-10 08:46:47.577424 | controller | Friday 10 October 2025  08:46:47 +0000 (0:00:00.035)       0:00:12.003 ********
2025-10-10 08:46:47.577460 | controller | ok: [instance]
2025-10-10 08:46:47.764678 | controller |
2025-10-10 08:46:47.764710 | controller | TASK [run_hook : Ensure artifacts directory exists] ****************************
2025-10-10 08:46:47.764725 | controller | Friday 10 October 2025  08:46:47 +0000 (0:00:00.195)       0:00:12.198 ********
2025-10-10 08:46:47.764735 | controller | ok: [instance]
2025-10-10 08:46:49.681886 | controller |
2025-10-10 08:46:49.681921 | controller | TASK [run_hook : Run hook without retry - Run dummy-3] *************************
2025-10-10 08:46:49.681929 | controller | Friday 10 October 2025  08:46:47 +0000 (0:00:00.187)       0:00:12.386 ********
2025-10-10 08:46:49.681940 | controller | Follow script's output here: /home/zuul/ci-framework-data/logs/ci_script_004_run_hook_without_retry_run.log
2025-10-10 08:46:49.709835 | controller | changed: [instance]
2025-10-10 08:46:49.709867 | controller |
2025-10-10 08:46:49.709874 | controller | TASK [run_hook : Run hook with retry - Run dummy-3] ****************************
2025-10-10 08:46:49.709881 | controller | Friday 10 October 2025  08:46:49 +0000 (0:00:01.917)       0:00:14.303 ********
2025-10-10 08:46:49.709891 | controller | skipping: [instance]
2025-10-10 08:46:49.891185 | controller |
2025-10-10 08:46:49.891213 | controller | TASK [run_hook : Check if we have a file] **************************************
2025-10-10 08:46:49.891240 | controller | Friday 10 October 2025  08:46:49 +0000 (0:00:00.028)       0:00:14.331 ********
2025-10-10 08:46:49.891251 | controller | ok: [instance]
2025-10-10 08:46:49.915356 | controller |
2025-10-10 08:46:49.915382 | controller | TASK [run_hook : Load generated content in main playbook] **********************
2025-10-10 08:46:49.915390 | controller | Friday 10 October 2025  08:46:49 +0000 (0:00:00.181)       0:00:14.512 ********
2025-10-10 08:46:49.915400 | controller | ok: [instance]
2025-10-10 08:46:49.915423 | controller |
2025-10-10 08:46:49.915605 | controller | TASK [Ensure we have the ceph_uuid variable now] *******************************
2025-10-10 08:46:49.938558 | controller | Friday 10 October 2025  08:46:49 +0000 (0:00:00.024)       0:00:14.537 ********
2025-10-10 08:46:49.938588 | controller | ok: [instance] => changed=false
2025-10-10 08:46:49.971897 | controller |   msg: All assertions passed
2025-10-10 08:46:49.971925 | controller |
2025-10-10 08:46:49.971934 | controller | TASK [Only filtered hooks] *****************************************************
2025-10-10 08:46:49.971941 | controller | Friday 10 October 2025  08:46:49 +0000 (0:00:00.022)       0:00:14.560 ********
2025-10-10 08:46:49.971952 | controller |
2025-10-10 08:46:50.027263 | controller | TASK [run_hook : Assert parameters are valid] **********************************
2025-10-10 08:46:50.027311 | controller | Friday 10 October 2025  08:46:49 +0000 (0:00:00.033)       0:00:14.593 ********
2025-10-10 08:46:50.027331 | controller | ok: [instance]
2025-10-10 08:46:50.099392 | controller |
2025-10-10 08:46:50.099423 | controller | TASK [run_hook : Assert single hooks are all mappings] *************************
2025-10-10 08:46:50.099431 | controller | Friday 10 October 2025  08:46:50 +0000 (0:00:00.055)       0:00:14.648 ********
2025-10-10 08:46:50.099442 | controller | ok: [instance]
2025-10-10 08:46:50.099590 | controller |
2025-10-10 08:46:50.099736 | controller | TASK [run_hook : Loop on hooks for filtered_hooks] *****************************
2025-10-10 08:46:50.099874 | controller | Friday 10 October 2025  08:46:50 +0000 (0:00:00.072)       0:00:14.721 ********
2025-10-10 08:46:50.191630 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/run_hook/tasks/playbook.yml for instance => (item={'source': '/tmp/dummy-4.yml', 'type': 'playbook', 'extra_vars': {'foo': 'bar', 'file': '/tmp/dummy-env.yml'}, 'name': '01 my hook'})
2025-10-10 08:46:50.191819 | controller |
2025-10-10 08:46:50.191966 | controller | TASK [run_hook : Set playbook path for 01 my hook] *****************************
2025-10-10 08:46:50.192105 | controller | Friday 10 October 2025  08:46:50 +0000 (0:00:00.092)       0:00:14.813 ********
2025-10-10 08:46:50.237521 | controller | ok: [instance]
2025-10-10 08:46:50.237725 | controller |
2025-10-10 08:46:50.237895 | controller | TASK [run_hook : Get file stat] ************************************************
2025-10-10 08:46:50.238042 | controller | Friday 10 October 2025  08:46:50 +0000 (0:00:00.045)       0:00:14.859 ********
2025-10-10 08:46:50.418754 | controller | ok: [instance]
2025-10-10 08:46:50.434407 | controller |
2025-10-10 08:46:50.434432 | controller | TASK [run_hook : Fail if playbook doesn't exist] *******************************
2025-10-10 08:46:50.434440 | controller | Friday 10 October 2025  08:46:50 +0000 (0:00:00.180)       0:00:15.040 ********
2025-10-10 08:46:50.434450 | controller | skipping: [instance]
2025-10-10 08:46:50.614933 | controller |
2025-10-10 08:46:50.614965 | controller | TASK [run_hook : Get parameters files] *****************************************
2025-10-10 08:46:50.614973 | controller | Friday 10 October 2025  08:46:50 +0000 (0:00:00.015)       0:00:15.056 ********
2025-10-10 08:46:50.614983 | controller | ok: [instance]
2025-10-10 08:46:50.633863 | controller |
2025-10-10 08:46:50.633889 | controller | TASK [run_hook : Add parameters artifacts as extra variables] ******************
2025-10-10 08:46:50.633897 | controller | Friday 10 October 2025  08:46:50 +0000 (0:00:00.180)       0:00:15.236 ********
2025-10-10 08:46:50.633907 | controller | ok: [instance]
2025-10-10 08:46:50.826829 | controller |
2025-10-10 08:46:50.826858 | controller | TASK [run_hook : Ensure log directory exists] **********************************
2025-10-10 08:46:50.826865 | controller | Friday 10 October 2025  08:46:50 +0000 (0:00:00.019)       0:00:15.255 ********
2025-10-10 08:46:50.826875 | controller | ok: [instance]
2025-10-10 08:46:51.010291 | controller |
2025-10-10 08:46:51.010321 | controller | TASK [run_hook : Ensure artifacts directory exists] ****************************
2025-10-10 08:46:51.010329 | controller | Friday 10 October 2025  08:46:50 +0000 (0:00:00.192)       0:00:15.448 ********
2025-10-10 08:46:51.010339 | controller | ok: [instance]
2025-10-10 08:46:52.936136 | controller |
2025-10-10 08:46:52.936168 | controller | TASK [run_hook : Run hook without retry - 01 my hook] **************************
2025-10-10 08:46:52.936176 | controller | Friday 10 October 2025  08:46:51 +0000 (0:00:00.183)       0:00:15.631 ********
2025-10-10 08:46:52.936187 | controller | Follow script's output here: /home/zuul/ci-framework-data/logs/ci_script_005_run_hook_without_retry_01_my.log
2025-10-10 08:46:52.965889 | controller | changed: [instance]
2025-10-10 08:46:52.965923 | controller |
2025-10-10 08:46:52.965931 | controller | TASK [run_hook : Run hook with retry - 01 my hook] *****************************
2025-10-10 08:46:52.965937 | controller | Friday 10 October 2025  08:46:52 +0000 (0:00:01.925)       0:00:17.557 ********
2025-10-10 08:46:52.965947 | controller | skipping: [instance]
2025-10-10 08:46:53.157338 | controller |
2025-10-10 08:46:53.157369 | controller | TASK [run_hook : Check if we have a file] **************************************
2025-10-10 08:46:53.157377 | controller | Friday 10 October 2025  08:46:52 +0000 (0:00:00.029)       0:00:17.587 ********
2025-10-10 08:46:53.157388 | controller | ok: [instance]
2025-10-10 08:46:53.157471 | controller |
2025-10-10 08:46:53.157501 | controller | TASK [run_hook : Load generated content in main playbook] **********************
2025-10-10 08:46:53.157514 | controller | Friday 10 October 2025  08:46:53 +0000 (0:00:00.191)       0:00:17.778 ********
2025-10-10 08:46:53.183897 | controller | ok: [instance]
2025-10-10 08:46:53.209030 | controller |
2025-10-10 08:46:53.209063 | controller | TASK [Ensure we have the ceph_uuid variable now] *******************************
2025-10-10 08:46:53.209071 | controller | Friday 10 October 2025  08:46:53 +0000 (0:00:00.026)       0:00:17.805 ********
2025-10-10 08:46:53.209082 | controller | ok: [instance] => changed=false
2025-10-10 08:46:53.245464 | controller |   msg: All assertions passed
2025-10-10 08:46:53.245508 | controller |
2025-10-10 08:46:53.245518 | controller | TASK [Direct hooks] ************************************************************
2025-10-10 08:46:53.245526 | controller | Friday 10 October 2025  08:46:53 +0000 (0:00:00.025)       0:00:17.830 ********
2025-10-10 08:46:53.245540 | controller |
2025-10-10 08:46:53.245547 | controller | TASK [run_hook : Assert parameters are valid] **********************************
2025-10-10 08:46:53.245568 | controller | Friday 10 October 2025  08:46:53 +0000 (0:00:00.036)       0:00:17.867 ********
2025-10-10 08:46:53.303934 | controller | ok: [instance]
2025-10-10 08:46:53.304075 | controller |
2025-10-10 08:46:53.304238 | controller | TASK [run_hook : Assert single hooks are all mappings] *************************
2025-10-10 08:46:53.304411 | controller | Friday 10 October 2025  08:46:53 +0000 (0:00:00.058)       0:00:17.925 ********
2025-10-10 08:46:53.378799 | controller | ok: [instance]
2025-10-10 08:46:53.378938 | controller |
2025-10-10 08:46:53.379071 | controller | TASK [run_hook : Loop on hooks for no_hook] ************************************
2025-10-10 08:46:53.379206 | controller | Friday 10 October 2025  08:46:53 +0000 (0:00:00.074)       0:00:18.000 ********
2025-10-10 08:46:53.488066 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/run_hook/tasks/playbook.yml for instance => (item={'name': 'Dummy-5', 'source': '/tmp/dummy-5.yml', 'type': 'playbook', 'extra_vars': {'foo': 'bar', 'file': '/tmp/dummy-env.yml'}})
2025-10-10 08:46:53.488190 | controller |
2025-10-10 08:46:53.488364 | controller | TASK [run_hook : Set playbook path for Dummy-5] ********************************
2025-10-10 08:46:53.488500 | controller | Friday 10 October 2025  08:46:53 +0000 (0:00:00.109)       0:00:18.109 ********
2025-10-10 08:46:53.535241 | controller | ok: [instance]
2025-10-10 08:46:53.535416 | controller |
2025-10-10 08:46:53.535550 | controller | TASK [run_hook : Get file stat] ************************************************
2025-10-10 08:46:53.535673 | controller | Friday 10 October 2025  08:46:53 +0000 (0:00:00.047)       0:00:18.157 ********
2025-10-10 08:46:53.736526 | controller | ok: [instance]
2025-10-10 08:46:53.753745 | controller |
2025-10-10 08:46:53.753773 | controller | TASK [run_hook : Fail if playbook doesn't exist] *******************************
2025-10-10 08:46:53.753782 | controller | Friday 10 October 2025  08:46:53 +0000 (0:00:00.200)       0:00:18.358 ********
2025-10-10 08:46:53.753792 | controller | skipping: [instance]
2025-10-10 08:46:53.946659 | controller |
2025-10-10 08:46:53.946689 | controller | TASK [run_hook : Get parameters files] *****************************************
2025-10-10 08:46:53.946697 | controller | Friday 10 October 2025  08:46:53 +0000 (0:00:00.017)       0:00:18.375 ********
2025-10-10 08:46:53.946707 | controller | ok: [instance]
2025-10-10 08:46:53.966962 | controller |
2025-10-10 08:46:53.966987 | controller | TASK [run_hook : Add parameters artifacts as extra variables] ******************
2025-10-10 08:46:53.966994 | controller | Friday 10 October 2025  08:46:53 +0000 (0:00:00.192)       0:00:18.568 ********
2025-10-10 08:46:53.967004 | controller | ok: [instance]
2025-10-10 08:46:54.161042 | controller |
2025-10-10 08:46:54.161074 | controller | TASK [run_hook : Ensure log directory exists] **********************************
2025-10-10 08:46:54.161082 | controller | Friday 10 October 2025  08:46:53 +0000 (0:00:00.020)       0:00:18.588 ********
2025-10-10 08:46:54.161093 | controller | ok: [instance]
2025-10-10 08:46:54.346077 | controller |
2025-10-10 08:46:54.346108 | controller | TASK [run_hook : Ensure artifacts directory exists] ****************************
2025-10-10 08:46:54.346116 | controller | Friday 10 October 2025  08:46:54 +0000 (0:00:00.193)       0:00:18.781 ********
2025-10-10 08:46:54.346127 | controller | ok: [instance]
2025-10-10 08:46:56.287360 | controller |
2025-10-10 08:46:56.287392 | controller | TASK [run_hook : Run hook without retry - Dummy-5] *****************************
2025-10-10 08:46:56.287400 | controller | Friday 10 October 2025  08:46:54 +0000 (0:00:00.185)       0:00:18.967 ********
2025-10-10 08:46:56.287411 | controller | Follow script's output here: /home/zuul/ci-framework-data/logs/ci_script_006_run_hook_without_retry_dummy.log
2025-10-10 08:46:56.287433 | controller | changed: [instance]
2025-10-10 08:46:56.287440 | controller |
2025-10-10 08:46:56.287447 | controller | TASK [run_hook : Run hook with retry - Dummy-5] ********************************
2025-10-10 08:46:56.287500 | controller | Friday 10 October 2025  08:46:56 +0000 (0:00:01.941)       0:00:20.909 ********
2025-10-10 08:46:56.318425 | controller | skipping: [instance]
2025-10-10 08:46:56.318457 | controller |
2025-10-10 08:46:56.318465 | controller | TASK [run_hook : Check if we have a file] **************************************
2025-10-10 08:46:56.318473 | controller | Friday 10 October 2025  08:46:56 +0000 (0:00:00.031)       0:00:20.940 ********
2025-10-10 08:46:56.513142 | controller | ok: [instance]
2025-10-10 08:46:56.540044 | controller |
2025-10-10 08:46:56.540075 | controller | TASK [run_hook : Load generated content in main playbook] **********************
2025-10-10 08:46:56.540083 | controller | Friday 10 October 2025  08:46:56 +0000 (0:00:00.194)       0:00:21.134 ********
2025-10-10 08:46:56.540093 | controller | ok: [instance]
2025-10-10 08:46:56.565355 | controller |
2025-10-10 08:46:56.565384 | controller | TASK [Ensure we have the ceph_uuid variable now] *******************************
2025-10-10 08:46:56.565392 | controller | Friday 10 October 2025  08:46:56 +0000 (0:00:00.026)       0:00:21.161 ********
2025-10-10 08:46:56.565402 | controller | ok: [instance] => changed=false
2025-10-10 08:46:56.565434 | controller |   msg: All assertions passed
2025-10-10 08:46:56.565449 | controller |
2025-10-10 08:46:56.565457 | controller | TASK [Direct hooks as param] ***************************************************
2025-10-10 08:46:56.565465 | controller | Friday 10 October 2025  08:46:56 +0000 (0:00:00.025)       0:00:21.187 ********
2025-10-10 08:46:56.605035 | controller |
2025-10-10 08:46:56.666830 | controller | TASK [run_hook : Assert parameters are valid] **********************************
2025-10-10 08:46:56.666863 | controller | Friday 10 October 2025  08:46:56 +0000 (0:00:00.039)       0:00:21.226 ********
2025-10-10 08:46:56.666877 | controller | ok: [instance]
2025-10-10 08:46:56.740612 | controller |
2025-10-10 08:46:56.740645 | controller | TASK [run_hook : Assert single hooks are all mappings] *************************
2025-10-10 08:46:56.740653 | controller | Friday 10 October 2025  08:46:56 +0000 (0:00:00.061)       0:00:21.288 ********
2025-10-10 08:46:56.740664 | controller | ok: [instance]
2025-10-10 08:46:56.835349 | controller |
2025-10-10 08:46:56.835391 | controller | TASK [run_hook : Loop on hooks for no_hook] ************************************
2025-10-10 08:46:56.835398 | controller | Friday 10 October 2025  08:46:56 +0000 (0:00:00.073)       0:00:21.361 ********
2025-10-10 08:46:56.835414 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/run_hook/tasks/playbook.yml for instance => (item={'name': 'Hook as param', 'source': '/tmp/dummy-6.yml', 'type': 'playbook', 'extra_vars': {'foo': 'bar', 'file': '/tmp/dummy-env.yml'}})
2025-10-10 08:46:56.835719 | controller |
2025-10-10 08:46:56.884370 | controller | TASK [run_hook : Set playbook path for Hook as param] **************************
2025-10-10 08:46:56.884404 | controller | Friday 10 October 2025  08:46:56 +0000 (0:00:00.095)       0:00:21.457 ********
2025-10-10 08:46:56.884417 | controller | ok: [instance]
2025-10-10 08:46:57.073691 | controller |
2025-10-10 08:46:57.073721 | controller | TASK [run_hook : Get file stat] ************************************************
2025-10-10 08:46:57.073729 | controller | Friday 10 October 2025  08:46:56 +0000 (0:00:00.048)       0:00:21.506 ********
2025-10-10 08:46:57.073739 | controller | ok: [instance]
2025-10-10 08:46:57.090823 | controller |
2025-10-10 08:46:57.090850 | controller | TASK [run_hook : Fail if playbook doesn't exist] *******************************
2025-10-10 08:46:57.090857 | controller | Friday 10 October 2025  08:46:57 +0000 (0:00:00.189)       0:00:21.695 ********
2025-10-10 08:46:57.091058 | controller | skipping: [instance]
2025-10-10 08:46:57.281087 | controller |
2025-10-10 08:46:57.281118 | controller | TASK [run_hook : Get parameters files] *****************************************
2025-10-10 08:46:57.281126 | controller | Friday 10 October 2025  08:46:57 +0000 (0:00:00.017)       0:00:21.712 ********
2025-10-10 08:46:57.281136 | controller | ok: [instance]
2025-10-10 08:46:57.281213 | controller |
2025-10-10 08:46:57.281244 | controller | TASK [run_hook : Add parameters artifacts as extra variables] ******************
2025-10-10 08:46:57.281257 | controller | Friday 10 October 2025  08:46:57 +0000 (0:00:00.190)       0:00:21.902 ********
2025-10-10 08:46:57.300922 | controller | ok: [instance]
2025-10-10 08:46:57.498403 | controller |
2025-10-10 08:46:57.498433 | controller | TASK [run_hook : Ensure log directory exists] **********************************
2025-10-10 08:46:57.498441 | controller | Friday 10 October 2025  08:46:57 +0000 (0:00:00.019)       0:00:21.922 ********
2025-10-10 08:46:57.498452 | controller | ok: [instance]
2025-10-10 08:46:57.683343 | controller |
2025-10-10 08:46:57.683385 | controller | TASK [run_hook : Ensure artifacts directory exists] ****************************
2025-10-10 08:46:57.683394 | controller | Friday 10 October 2025  08:46:57 +0000 (0:00:00.197)       0:00:22.119 ********
2025-10-10 08:46:57.683406 | controller | ok: [instance]
2025-10-10 08:46:57.683673 | controller |
2025-10-10 08:46:59.602639 | controller | TASK [run_hook : Run hook without retry - Hook as param] ***********************
2025-10-10 08:46:59.602670 | controller | Friday 10 October 2025  08:46:57 +0000 (0:00:00.185)       0:00:22.305 ********
2025-10-10 08:46:59.602682 | controller | Follow script's output here: /home/zuul/ci-framework-data/logs/ci_script_007_run_hook_without_retry_hook.log
2025-10-10 08:46:59.632382 | controller | changed: [instance]
2025-10-10 08:46:59.632418 | controller |
2025-10-10 08:46:59.632428 | controller | TASK [run_hook : Run hook with retry - Hook as param] **************************
2025-10-10 08:46:59.632436 | controller | Friday 10 October 2025  08:46:59 +0000 (0:00:01.918)       0:00:24.224 ********
2025-10-10 08:46:59.632448 | controller | skipping: [instance]
2025-10-10 08:46:59.632478 | controller |
2025-10-10 08:46:59.632727 | controller | TASK [run_hook : Check if we have a file] **************************************
2025-10-10 08:46:59.824254 | controller | Friday 10 October 2025  08:46:59 +0000 (0:00:00.030)       0:00:24.254 ********
2025-10-10 08:46:59.824343 | controller | ok: [instance]
2025-10-10 08:46:59.851115 | controller |
2025-10-10 08:46:59.851134 | controller | TASK [run_hook : Load generated content in main playbook] **********************
2025-10-10 08:46:59.851141 | controller | Friday 10 October 2025  08:46:59 +0000 (0:00:00.191)       0:00:24.445 ********
2025-10-10 08:46:59.851172 | controller | ok: [instance]
2025-10-10 08:46:59.876082 | controller |
2025-10-10 08:46:59.876100 | controller | TASK [Ensure we have the ceph_uuid variable now] *******************************
2025-10-10 08:46:59.876107 | controller | Friday 10 October 2025  08:46:59 +0000 (0:00:00.027)       0:00:24.472 ********
2025-10-10 08:46:59.876116 | controller | ok: [instance] => changed=false
2025-10-10 08:46:59.910966 | controller |   msg: All assertions passed
2025-10-10 08:46:59.910990 | controller |
2025-10-10 08:46:59.910997 | controller | TASK [Ensure we have the test_list variable now] *******************************
2025-10-10 08:46:59.911008 | controller | Friday 10 October 2025  08:46:59 +0000 (0:00:00.024)       0:00:24.497 ********
2025-10-10 08:46:59.911018 | controller | ok: [instance] => changed=false
2025-10-10 08:46:59.937373 | controller |   msg: All assertions passed
2025-10-10 08:46:59.937404 | controller |
2025-10-10 08:46:59.937412 | controller | TASK [Run hook with retry] *****************************************************
2025-10-10 08:46:59.937418 | controller | Friday 10 October 2025  08:46:59 +0000 (0:00:00.034)       0:00:24.532 ********
2025-10-10 08:46:59.937428 | controller |
2025-10-10 08:46:59.937595 | controller | TASK [run_hook : Assert parameters are valid] **********************************
2025-10-10 08:46:59.937748 | controller | Friday 10 October 2025  08:46:59 +0000 (0:00:00.026)       0:00:24.559 ********
2025-10-10 08:46:59.995800 | controller | ok: [instance]
2025-10-10 08:46:59.995966 | controller |
2025-10-10 08:46:59.996125 | controller | TASK [run_hook : Assert single hooks are all mappings] *************************
2025-10-10 08:46:59.996319 | controller | Friday 10 October 2025  08:46:59 +0000 (0:00:00.058)       0:00:24.617 ********
2025-10-10 08:47:00.069356 | controller | ok: [instance]
2025-10-10 08:47:00.069473 | controller |
2025-10-10 08:47:00.069606 | controller | TASK [run_hook : Loop on hooks for retry_hook] *********************************
2025-10-10 08:47:00.069728 | controller | Friday 10 October 2025  08:47:00 +0000 (0:00:00.073)       0:00:24.691 ********
2025-10-10 08:47:00.164313 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/run_hook/tasks/playbook.yml for instance => (item={'name': 'Run hook with retry', 'source': '/tmp/dummy-retry.yml', 'type': 'playbook', 'retry_hook': True})
2025-10-10 08:47:00.164450 | controller |
2025-10-10 08:47:00.164584 | controller | TASK [run_hook : Set playbook path for Run hook with retry] ********************
2025-10-10 08:47:00.164702 | controller | Friday 10 October 2025  08:47:00 +0000 (0:00:00.094)       0:00:24.786 ********
2025-10-10 08:47:00.215073 | controller | ok: [instance]
2025-10-10 08:47:00.215251 | controller |
2025-10-10 08:47:00.215430 | controller | TASK [run_hook : Get file stat] ************************************************
2025-10-10 08:47:00.215578 | controller | Friday 10 October 2025  08:47:00 +0000 (0:00:00.050)       0:00:24.837 ********
2025-10-10 08:47:00.407171 | controller | ok: [instance]
2025-10-10 08:47:00.424700 | controller |
2025-10-10 08:47:00.424721 | controller | TASK [run_hook : Fail if playbook doesn't exist] *******************************
2025-10-10 08:47:00.424731 | controller | Friday 10 October 2025  08:47:00 +0000 (0:00:00.191)       0:00:25.028 ********
2025-10-10 08:47:00.424742 | controller | skipping: [instance]
2025-10-10 08:47:00.612814 | controller |
2025-10-10 08:47:00.612845 | controller | TASK [run_hook : Get parameters files] *****************************************
2025-10-10 08:47:00.612853 | controller | Friday 10 October 2025  08:47:00 +0000 (0:00:00.017)       0:00:25.046 ********
2025-10-10 08:47:00.612863 | controller | ok: [instance]
2025-10-10 08:47:00.632834 | controller |
2025-10-10 08:47:00.632857 | controller | TASK [run_hook : Add parameters artifacts as extra variables] ******************
2025-10-10 08:47:00.632865 | controller | Friday 10 October 2025  08:47:00 +0000 (0:00:00.187)       0:00:25.234 ********
2025-10-10 08:47:00.632876 | controller | ok: [instance]
2025-10-10 08:47:00.826683 | controller |
2025-10-10 08:47:00.826714 | controller | TASK [run_hook : Ensure log directory exists] **********************************
2025-10-10 08:47:00.826729 | controller | Friday 10 October 2025  08:47:00 +0000 (0:00:00.020)       0:00:25.254 ********
2025-10-10 08:47:00.826739 | controller | ok: [instance]
2025-10-10 08:47:01.008844 | controller |
2025-10-10 08:47:01.008874 | controller | TASK [run_hook : Ensure artifacts directory exists] ****************************
2025-10-10 08:47:01.008882 | controller | Friday 10 October 2025  08:47:00 +0000 (0:00:00.193)       0:00:25.448 ********
2025-10-10 08:47:01.008892 | controller | ok: [instance]
2025-10-10 08:47:02.944408 | controller |
2025-10-10 08:47:02.944449 | controller | TASK [run_hook : Run hook without retry - Run hook with retry] *****************
2025-10-10 08:47:02.944459 | controller | Friday 10 October 2025  08:47:01 +0000 (0:00:00.182)       0:00:25.630 ********
2025-10-10 08:47:02.944472 | controller | Follow script's output here: /home/zuul/ci-framework-data/logs/ci_script_008_run_hook_without_retry_run.log
2025-10-10 08:47:02.944494 | controller | changed: [instance]
2025-10-10 08:47:02.944520 | controller |
2025-10-10 08:47:02.944760 | controller | TASK [run_hook : Run hook with retry - Run hook with retry] ********************
2025-10-10 08:47:02.977993 | controller | Friday 10 October 2025  08:47:02 +0000 (0:00:01.935)       0:00:27.566 ********
2025-10-10 08:47:02.978028 | controller | skipping: [instance]
2025-10-10 08:47:03.175300 | controller |
2025-10-10 08:47:03.175334 | controller | TASK [run_hook : Check if we have a file] **************************************
2025-10-10 08:47:03.175341 | controller | Friday 10 October 2025  08:47:02 +0000 (0:00:00.033)       0:00:27.599 ********
2025-10-10 08:47:03.175351 | controller | ok: [instance]
2025-10-10 08:47:03.199413 | controller |
2025-10-10 08:47:03.199465 | controller | TASK [run_hook : Load generated content in main playbook] **********************
2025-10-10 08:47:03.199476 | controller | Friday 10 October 2025  08:47:03 +0000 (0:00:00.197)       0:00:27.796 ********
2025-10-10 08:47:03.199498 | controller | skipping: [instance]
2025-10-10 08:47:03.199533 | controller |
2025-10-10 08:47:03.199693 | controller | TASK [Check if fake file exists for retry playbook] ****************************
2025-10-10 08:47:03.199742 | controller | Friday 10 October 2025  08:47:03 +0000 (0:00:00.024)       0:00:27.820 ********
2025-10-10 08:47:03.421607 | controller | ok: [instance]
2025-10-10 08:47:03.444426 | controller |
2025-10-10 08:47:03.444488 | controller | TASK [Ensure file exists and was created on retry] *****************************
2025-10-10 08:47:03.444498 | controller | Friday 10 October 2025  08:47:03 +0000 (0:00:00.221)       0:00:28.042 ********
2025-10-10 08:47:03.444510 | controller | ok: [instance] => changed=false
2025-10-10 08:47:03.651378 | controller |   msg: All assertions passed
2025-10-10 08:47:03.651415 | controller |
2025-10-10 08:47:03.651425 | controller | TASK [Remove generated file] ***************************************************
2025-10-10 08:47:03.651432 | controller | Friday 10 October 2025  08:47:03 +0000 (0:00:00.022)       0:00:28.065 ********
2025-10-10 08:47:03.651444 | controller | changed: [instance]
2025-10-10 08:47:03.651474 | controller |
2025-10-10 08:47:03.651481 | controller | PLAY RECAP *********************************************************************
2025-10-10 08:47:03.651488 | controller | instance                   : ok=112  changed=10   unreachable=0    failed=0    skipped=22   rescued=0    ignored=0
2025-10-10 08:47:03.651495 | controller |
2025-10-10 08:47:03.651502 | controller | Friday 10 October 2025  08:47:03 +0000 (0:00:00.206)       0:00:28.272 ********
2025-10-10 08:47:03.651511 | controller | ===============================================================================
2025-10-10 08:47:03.651537 | controller | run_hook : Run hook without retry - 03 single hook ---------------------- 2.01s
2025-10-10 08:47:03.653410 | controller | run_hook : Run hook without retry - Dummy-5 ----------------------------- 1.94s
2025-10-10 08:47:03.711056 | controller | run_hook : Run hook without retry - Run hook with retry ----------------- 1.94s
2025-10-10 08:47:03.711090 | controller | run_hook : Run hook without retry - 01 my hook -------------------------- 1.93s
2025-10-10 08:47:03.711097 | controller | run_hook : Run hook without retry - Hook as param ----------------------- 1.92s
2025-10-10 08:47:03.711103 | controller | run_hook : Run hook without retry - Run dummy-3 ------------------------- 1.92s
2025-10-10 08:47:03.711108 | controller | run_hook : Run hook without retry - Run dummy-2 ------------------------- 1.88s
2025-10-10 08:47:03.711114 | controller | Gathering Facts --------------------------------------------------------- 0.80s
2025-10-10 08:47:03.711141 | controller | run_hook : Run hook without retry - 02 Re-run noop ---------------------- 0.70s
2025-10-10 08:47:03.711147 | controller | run_hook : Run hook without retry - 01 Default noop hook ---------------- 0.61s
2025-10-10 08:47:03.711153 | controller | run_hook : Get file stat ------------------------------------------------ 0.30s
2025-10-10 08:47:03.711158 | controller | run_hook : Ensure log directory exists ---------------------------------- 0.30s
2025-10-10 08:47:03.711163 | controller | run_hook : Get parameters files ----------------------------------------- 0.30s
2025-10-10 08:47:03.711168 | controller | Check if fake file exists for retry playbook ---------------------------- 0.22s
2025-10-10 08:47:03.711174 | controller | Remove generated file --------------------------------------------------- 0.21s
2025-10-10 08:47:03.711179 | controller | run_hook : Get parameters files ----------------------------------------- 0.20s
2025-10-10 08:47:03.711184 | controller | run_hook : Get file stat ------------------------------------------------ 0.20s
2025-10-10 08:47:03.711189 | controller | run_hook : Check if we have a file -------------------------------------- 0.20s
2025-10-10 08:47:03.711194 | controller | run_hook : Get file stat ------------------------------------------------ 0.20s
2025-10-10 08:47:03.711200 | controller | run_hook : Check if we have a file -------------------------------------- 0.20s
2025-10-10 08:47:03.711210 | controller | INFO     Running default > cleanup
2025-10-10 08:47:03.711723 | controller | WARNING  Skipping, cleanup playbook not configured.
2025-10-10 08:47:03.712374 | controller | INFO     Writing /tmp/report.html report.
2025-10-10 08:47:03.795708 | controller | changed
2025-10-10 08:47:03.823176 | 
2025-10-10 08:47:03.823246 | PLAY RECAP
2025-10-10 08:47:03.823289 | controller | ok: 3 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-10-10 08:47:03.823313 | 
2025-10-10 08:47:03.998274 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-10-10 08:47:03.999106 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-10-10 08:47:04.648645 | 
2025-10-10 08:47:04.648755 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-10-10 08:47:04.670536 | 
2025-10-10 08:47:04.670626 | TASK [Filter out host if needed]
2025-10-10 08:47:04.680176 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-10-10 08:47:04.684882 | 
2025-10-10 08:47:04.684972 | TASK [Ensure file is present]
2025-10-10 08:47:05.024626 | controller | ok
2025-10-10 08:47:05.038699 | 
2025-10-10 08:47:05.038790 | TASK [Manage molecule report file]
2025-10-10 08:47:05.568390 | controller | changed
2025-10-10 08:47:05.573708 | 
2025-10-10 08:47:05.573789 | TASK [Check if we get ci-framework-data basedir]
2025-10-10 08:47:05.764949 | controller | ok
2025-10-10 08:47:05.770520 | 
2025-10-10 08:47:05.770597 | TASK [Create ci-framework-data log directory for zuul]
2025-10-10 08:47:06.101055 | controller | changed
2025-10-10 08:47:06.107038 | 
2025-10-10 08:47:06.107123 | TASK [Copy ci-framework interesting files]
2025-10-10 08:47:06.314819 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2025-10-10 08:47:06.632318 | controller | changed
2025-10-10 08:47:06.638312 | 
2025-10-10 08:47:06.638386 | TASK [Get SELinux listing]
2025-10-10 08:47:07.166588 | controller | changed
2025-10-10 08:47:07.172768 | 
2025-10-10 08:47:07.172873 | TASK [Generate log index]
2025-10-10 08:47:07.871607 | controller | changed
2025-10-10 08:47:07.885295 | 
2025-10-10 08:47:07.885415 | TASK [Get some env related data]
2025-10-10 08:47:08.514877 | controller | /home/zuul/.local/bin/ansible
2025-10-10 08:47:09.501362 | controller | changed
2025-10-10 08:47:09.537849 | 
2025-10-10 08:47:09.541269 | TASK [Generate list of logs to collect in home directory]
2025-10-10 08:47:10.081599 | controller | ok: All paths examined
2025-10-10 08:47:10.092082 | 
2025-10-10 08:47:10.092177 | LOOP [Copy logs from home directory]
2025-10-10 08:47:10.493051 | controller | changed:
2025-10-10 08:47:10.493193 | controller | {
2025-10-10 08:47:10.493223 | controller |   "atime": 1760085971.0432756,
2025-10-10 08:47:10.493245 | controller |   "ctime": 1760085987.1744475,
2025-10-10 08:47:10.493264 | controller |   "dev": 64513,
2025-10-10 08:47:10.493282 | controller |   "gid": 1000,
2025-10-10 08:47:10.493298 | controller |   "gr_name": "zuul",
2025-10-10 08:47:10.493314 | controller |   "inode": 4474098,
2025-10-10 08:47:10.493330 | controller |   "isblk": false,
2025-10-10 08:47:10.493345 | controller |   "ischr": false,
2025-10-10 08:47:10.493360 | controller |   "isdir": false,
2025-10-10 08:47:10.493375 | controller |   "isfifo": false,
2025-10-10 08:47:10.493390 | controller |   "isgid": false,
2025-10-10 08:47:10.493404 | controller |   "islnk": false,
2025-10-10 08:47:10.493419 | controller |   "isreg": true,
2025-10-10 08:47:10.493433 | controller |   "issock": false,
2025-10-10 08:47:10.493448 | controller |   "isuid": false,
2025-10-10 08:47:10.493463 | controller |   "mode": "0644",
2025-10-10 08:47:10.493478 | controller |   "mtime": 1760085987.1744475,
2025-10-10 08:47:10.493492 | controller |   "nlink": 1,
2025-10-10 08:47:10.493507 | controller |   "path": "/home/zuul/ansible.log",
2025-10-10 08:47:10.493523 | controller |   "pw_name": "zuul",
2025-10-10 08:47:10.493538 | controller |   "rgrp": true,
2025-10-10 08:47:10.493553 | controller |   "roth": true,
2025-10-10 08:47:10.493568 | controller |   "rusr": true,
2025-10-10 08:47:10.493583 | controller |   "size": 6749,
2025-10-10 08:47:10.493597 | controller |   "uid": 1000,
2025-10-10 08:47:10.493611 | controller |   "wgrp": false,
2025-10-10 08:47:10.493625 | controller |   "woth": false,
2025-10-10 08:47:10.493642 | controller |   "wusr": true,
2025-10-10 08:47:10.493657 | controller |   "xgrp": false,
2025-10-10 08:47:10.493672 | controller |   "xoth": false,
2025-10-10 08:47:10.493686 | controller |   "xusr": false
2025-10-10 08:47:10.493700 | controller | }
2025-10-10 08:47:10.509173 | 
2025-10-10 08:47:10.509257 | TASK [Copy crio stats log file]
2025-10-10 08:47:10.533643 | controller | skipping: Conditional result was False
2025-10-10 08:47:10.541673 | 
2025-10-10 08:47:10.541778 | TASK [Get SELinux related data]
2025-10-10 08:47:10.769970 | controller | <no matches>
2025-10-10 08:47:11.074376 | controller | ERROR
2025-10-10 08:47:11.074594 | controller | {
2025-10-10 08:47:11.074629 | controller |   "delta": "0:00:00.009103",
2025-10-10 08:47:11.074651 | controller |   "end": "2025-10-10 08:47:10.770700",
2025-10-10 08:47:11.074670 | controller |   "msg": "non-zero return code",
2025-10-10 08:47:11.074692 | controller |   "rc": 1,
2025-10-10 08:47:11.074712 | controller |   "start": "2025-10-10 08:47:10.761597"
2025-10-10 08:47:11.074729 | controller | }
2025-10-10 08:47:11.074754 | controller | ERROR: Ignoring Errors
2025-10-10 08:47:11.081885 | 
2025-10-10 08:47:11.081999 | TASK [Create system configuration directory]
2025-10-10 08:47:11.273334 | controller | changed
2025-10-10 08:47:11.278318 | 
2025-10-10 08:47:11.278400 | TASK [Get some of the system configurations]
2025-10-10 08:47:11.806745 | controller | changed
2025-10-10 08:47:11.812182 | 
2025-10-10 08:47:11.812269 | TASK [Copy generated documentation if available]
2025-10-10 08:47:11.826673 | controller | skipping: Conditional result was False
2025-10-10 08:47:11.833133 | 
2025-10-10 08:47:11.833225 | TASK [Copy generated AsciiDoc documentation if available]
2025-10-10 08:47:11.858523 | controller | skipping: Conditional result was False
2025-10-10 08:47:11.864606 | 
2025-10-10 08:47:11.864687 | TASK [Compress logs bigger than 2MB]
2025-10-10 08:47:12.391649 | controller | changed
2025-10-10 08:47:12.397483 | 
2025-10-10 08:47:12.397585 | TASK [Copy files from workspace on node]
2025-10-10 08:47:12.436477 | controller | ok
2025-10-10 08:47:12.481140 | 
2025-10-10 08:47:12.481244 | TASK [fetch-output : Set log path for multiple nodes]
2025-10-10 08:47:12.526116 | controller | skipping: Conditional result was False
2025-10-10 08:47:12.534483 | 
2025-10-10 08:47:12.534579 | TASK [fetch-output : Set log path for single node]
2025-10-10 08:47:12.585903 | controller | ok
2025-10-10 08:47:12.595320 | 
2025-10-10 08:47:12.595399 | LOOP [fetch-output : Ensure local output dirs]
2025-10-10 08:47:13.031293 | controller -> localhost | ok: "/var/lib/zuul/builds/0341fee42c1e4c0abc747939cb2f8d37/work/logs"
2025-10-10 08:47:13.031537 | controller -> localhost | changed: All items complete
2025-10-10 08:47:13.031568 | 
2025-10-10 08:47:13.271079 | controller -> localhost | changed: "/var/lib/zuul/builds/0341fee42c1e4c0abc747939cb2f8d37/work/artifacts"
2025-10-10 08:47:13.508978 | controller -> localhost | changed: "/var/lib/zuul/builds/0341fee42c1e4c0abc747939cb2f8d37/work/docs"
2025-10-10 08:47:13.524974 | 
2025-10-10 08:47:13.525072 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-10-10 08:47:14.162634 | controller | changed:
2025-10-10 08:47:14.162840 | controller | .d..t...... ./
2025-10-10 08:47:14.162873 | controller | >f+++++++++ README.html
2025-10-10 08:47:14.162897 | controller | >f+++++++++ ansible-execution.log
2025-10-10 08:47:14.162960 | controller | >f+++++++++ ansible.log
2025-10-10 08:47:14.162985 | controller | >f+++++++++ dmesg.log
2025-10-10 08:47:14.163005 | controller | >f+++++++++ installed-pkgs.log
2025-10-10 08:47:14.163027 | controller | >f+++++++++ python.log
2025-10-10 08:47:14.163048 | controller | >f+++++++++ registries.conf
2025-10-10 08:47:14.163065 | controller | >f+++++++++ report.html
2025-10-10 08:47:14.163086 | controller | >f+++++++++ selinux-denials.log
2025-10-10 08:47:14.163104 | controller | >f+++++++++ selinux-listing.log
2025-10-10 08:47:14.163122 | controller | cd+++++++++ ci-framework-data/
2025-10-10 08:47:14.163139 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-10-10 08:47:14.163157 | controller | >f+++++++++ ci-framework-data/artifacts/ci_script_000_run_hook_without_retry_01.sh
2025-10-10 08:47:14.163174 | controller | >f+++++++++ ci-framework-data/artifacts/ci_script_001_run_hook_without_retry_02_re.sh
2025-10-10 08:47:14.163191 | controller | >f+++++++++ ci-framework-data/artifacts/ci_script_002_run_hook_without_retry_03.sh
2025-10-10 08:47:14.163208 | controller | >f+++++++++ ci-framework-data/artifacts/ci_script_003_run_hook_without_retry_run.sh
2025-10-10 08:47:14.163225 | controller | >f+++++++++ ci-framework-data/artifacts/ci_script_004_run_hook_without_retry_run.sh
2025-10-10 08:47:14.163244 | controller | >f+++++++++ ci-framework-data/artifacts/ci_script_005_run_hook_without_retry_01_my.sh
2025-10-10 08:47:14.163262 | controller | >f+++++++++ ci-framework-data/artifacts/ci_script_006_run_hook_without_retry_dummy.sh
2025-10-10 08:47:14.163284 | controller | >f+++++++++ ci-framework-data/artifacts/ci_script_007_run_hook_without_retry_hook.sh
2025-10-10 08:47:14.163303 | controller | >f+++++++++ ci-framework-data/artifacts/ci_script_008_run_hook_without_retry_run.sh
2025-10-10 08:47:14.163322 | controller | >f+++++++++ ci-framework-data/artifacts/filtered_hooks_01_my_hook.yml
2025-10-10 08:47:14.163340 | controller | >f+++++++++ ci-framework-data/artifacts/list_hooks_run_dummy-2.yml
2025-10-10 08:47:14.163357 | controller | >f+++++++++ ci-framework-data/artifacts/list_hooks_run_dummy-3.yml
2025-10-10 08:47:14.163374 | controller | >f+++++++++ ci-framework-data/artifacts/no_hook_dummy-5.yml
2025-10-10 08:47:14.163391 | controller | >f+++++++++ ci-framework-data/artifacts/no_hook_hook_as_param.yml
2025-10-10 08:47:14.163407 | controller | >f+++++++++ ci-framework-data/artifacts/run_molecule_03_single_hook.yml
2025-10-10 08:47:14.163423 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-10-10 08:47:14.163440 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-10-10 08:47:14.163456 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-10-10 08:47:14.163473 | controller | cd+++++++++ ci-framework-data/logs/
2025-10-10 08:47:14.163490 | controller | >f+++++++++ ci-framework-data/logs/ci_script_000_run_hook_without_retry_01.log
2025-10-10 08:47:14.163507 | controller | >f+++++++++ ci-framework-data/logs/ci_script_001_run_hook_without_retry_02_re.log
2025-10-10 08:47:14.163523 | controller | >f+++++++++ ci-framework-data/logs/ci_script_002_run_hook_without_retry_03.log
2025-10-10 08:47:14.163540 | controller | >f+++++++++ ci-framework-data/logs/ci_script_003_run_hook_without_retry_run.log
2025-10-10 08:47:14.163555 | controller | >f+++++++++ ci-framework-data/logs/ci_script_004_run_hook_without_retry_run.log
2025-10-10 08:47:14.163571 | controller | >f+++++++++ ci-framework-data/logs/ci_script_005_run_hook_without_retry_01_my.log
2025-10-10 08:47:14.163588 | controller | >f+++++++++ ci-framework-data/logs/ci_script_006_run_hook_without_retry_dummy.log
2025-10-10 08:47:14.163604 | controller | >f+++++++++ ci-framework-data/logs/ci_script_007_run_hook_without_retry_hook.log
2025-10-10 08:47:14.163620 | controller | >f+++++++++ ci-framework-data/logs/ci_script_008_run_hook_without_retry_run.log
2025-10-10 08:47:14.163636 | controller | >f+++++++++ ci-framework-data/logs/filtered_hooks_01_my_hook.log
2025-10-10 08:47:14.163652 | controller | >f+++++++++ ci-framework-data/logs/list_hooks_run_dummy-2.log
2025-10-10 08:47:14.163667 | controller | >f+++++++++ ci-framework-data/logs/list_hooks_run_dummy-3.log
2025-10-10 08:47:14.163683 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2025-10-10 08:47:14.163698 | controller | >f+++++++++ ci-framework-data/logs/no_hook_dummy-5.log
2025-10-10 08:47:14.163714 | controller | >f+++++++++ ci-framework-data/logs/no_hook_hook_as_param.log
2025-10-10 08:47:14.163729 | controller | >f+++++++++ ci-framework-data/logs/retry_hook_run_hook_with_retry.log
2025-10-10 08:47:14.163746 | controller | >f+++++++++ ci-framework-data/logs/run_molecule_01_default_noop_hook.log
2025-10-10 08:47:14.163764 | controller | >f+++++++++ ci-framework-data/logs/run_molecule_02_re-run_noop.log
2025-10-10 08:47:14.163781 | controller | >f+++++++++ ci-framework-data/logs/run_molecule_03_single_hook.log
2025-10-10 08:47:14.163813 | controller | cd+++++++++ registries.conf.d/
2025-10-10 08:47:14.163832 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-10-10 08:47:14.163850 | controller | cd+++++++++ system-config/
2025-10-10 08:47:14.163869 | controller | cd+++++++++ system-config/libvirt/
2025-10-10 08:47:14.163886 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-10-10 08:47:14.163904 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-10-10 08:47:14.597170 | controller | changed: .d..t...... ./
2025-10-10 08:47:15.015065 | controller | changed: .d..t...... ./
2025-10-10 08:47:15.030033 | 
2025-10-10 08:47:15.030141 | TASK [Return artifact to Zuul]
2025-10-10 08:47:15.060395 | controller | ok
2025-10-10 08:47:15.079826 | 
2025-10-10 08:47:15.079903 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-10-10 08:47:15.080042 | 
2025-10-10 08:47:15.080073 | PLAY RECAP
2025-10-10 08:47:15.080110 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2025-10-10 08:47:15.080132 | 
2025-10-10 08:47:15.164900 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-10-10 08:47:15.165642 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-10-10 08:47:15.704559 | 
2025-10-10 08:47:15.704681 | PLAY [all]
2025-10-10 08:47:15.724561 | 
2025-10-10 08:47:15.724680 | TASK [include_role : fetch-output]
2025-10-10 08:47:15.753566 | controller | ok
2025-10-10 08:47:15.771313 | 
2025-10-10 08:47:15.771434 | TASK [fetch-output : Set log path for multiple nodes]
2025-10-10 08:47:15.816111 | controller | skipping: Conditional result was False
2025-10-10 08:47:15.821792 | 
2025-10-10 08:47:15.821884 | TASK [fetch-output : Set log path for single node]
2025-10-10 08:47:15.850983 | controller | ok
2025-10-10 08:47:15.855992 | 
2025-10-10 08:47:15.856068 | LOOP [fetch-output : Ensure local output dirs]
2025-10-10 08:47:16.203719 | controller -> localhost | ok: "/var/lib/zuul/builds/0341fee42c1e4c0abc747939cb2f8d37/work/logs"
2025-10-10 08:47:16.407558 | controller -> localhost | ok: "/var/lib/zuul/builds/0341fee42c1e4c0abc747939cb2f8d37/work/artifacts"
2025-10-10 08:47:16.633000 | controller -> localhost | ok: "/var/lib/zuul/builds/0341fee42c1e4c0abc747939cb2f8d37/work/docs"
2025-10-10 08:47:16.647309 | 
2025-10-10 08:47:16.647446 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-10-10 08:47:17.356330 | controller | ok
2025-10-10 08:47:17.356683 | controller | ok: All items complete
2025-10-10 08:47:17.356718 | 
2025-10-10 08:47:17.958390 | controller | ok
2025-10-10 08:47:18.395604 | controller | ok
2025-10-10 08:47:18.424192 | 
2025-10-10 08:47:18.424287 | TASK [include_role : fetch-output-openshift]
2025-10-10 08:47:18.451996 | controller | skipping: Conditional result was False
2025-10-10 08:47:18.458120 | 
2025-10-10 08:47:18.458197 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-10-10 08:47:18.844418 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.015479
2025-10-10 08:47:19.092228 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.034054
2025-10-10 08:47:19.125367 | 
2025-10-10 08:47:19.125485 | PLAY [all]
2025-10-10 08:47:19.141138 | 
2025-10-10 08:47:19.141332 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-10-10 08:47:19.579114 | controller | changed
2025-10-10 08:47:19.601368 | 
2025-10-10 08:47:19.601487 | PLAY RECAP
2025-10-10 08:47:19.601537 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-10-10 08:47:19.601560 | 
2025-10-10 08:47:19.740352 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-10-10 08:47:19.741108 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-10-10 08:47:20.302108 | 
2025-10-10 08:47:20.302219 | PLAY [localhost]
2025-10-10 08:47:20.319775 | 
2025-10-10 08:47:20.319893 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-10-10 08:47:20.661819 | localhost | changed
2025-10-10 08:47:20.666265 | 
2025-10-10 08:47:20.666352 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-10-10 08:47:20.694696 | localhost | ok
2025-10-10 08:47:20.702836 | 
2025-10-10 08:47:20.702928 | TASK [add-fileserver : Create SSH private key tempfile]
2025-10-10 08:47:21.024120 | localhost | changed
2025-10-10 08:47:21.029598 | 
2025-10-10 08:47:21.029666 | TASK [add-fileserver : Create SSH private key from secret]
2025-10-10 08:47:21.636537 | localhost | changed
2025-10-10 08:47:21.641988 | 
2025-10-10 08:47:21.642091 | TASK [add-fileserver : Add fileserver ssh key]
2025-10-10 08:47:22.019533 | localhost | Identity added: /var/lib/zuul/builds/0341fee42c1e4c0abc747939cb2f8d37/work/tmp/ansible.sc6aa67s (/var/lib/zuul/builds/0341fee42c1e4c0abc747939cb2f8d37/work/tmp/ansible.sc6aa67s)
2025-10-10 08:47:22.019737 | localhost | ok: Runtime: 0:00:00.006584
2025-10-10 08:47:22.024178 | 
2025-10-10 08:47:22.024248 | TASK [add-fileserver : Remove SSH private key from disk]
2025-10-10 08:47:22.275265 | localhost | ok: Runtime: 0:00:00.004865
2025-10-10 08:47:22.280376 | 
2025-10-10 08:47:22.280502 | TASK [add-fileserver : Add fileserver to inventory]
2025-10-10 08:47:22.328681 | localhost | changed
2025-10-10 08:47:22.333793 | 
2025-10-10 08:47:22.333883 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-10-10 08:47:22.677036 | localhost | changed
2025-10-10 08:47:22.696869 | 
2025-10-10 08:47:22.696979 | PLAY [localhost]
2025-10-10 08:47:22.709400 | 
2025-10-10 08:47:22.709461 | TASK [Generate bulk log download script]
2025-10-10 08:47:22.726113 | localhost | ok
2025-10-10 08:47:22.738172 | 
2025-10-10 08:47:22.738245 | TASK [local-log-download : Check API endpoint is defined]
2025-10-10 08:47:22.764900 | localhost | ok: All assertions passed
2025-10-10 08:47:22.769626 | 
2025-10-10 08:47:22.769694 | TASK [local-log-download : Create download script]
2025-10-10 08:47:23.160500 | localhost -> localhost | changed
2025-10-10 08:47:23.169581 | 
2025-10-10 08:47:23.169661 | TASK [Register quick-download link]
2025-10-10 08:47:23.187629 | localhost | ok
2025-10-10 08:47:23.226600 | 
2025-10-10 08:47:23.226694 | PLAY [logserver.rdoproject.org]
2025-10-10 08:47:23.237934 | 
2025-10-10 08:47:23.237999 | TASK [Set zuul-log-path fact]
2025-10-10 08:47:23.253827 | logserver.rdoproject.org | ok
2025-10-10 08:47:23.264056 | 
2025-10-10 08:47:23.264137 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-10 08:47:23.292199 | logserver.rdoproject.org | ok
2025-10-10 08:47:23.298353 | 
2025-10-10 08:47:23.298425 | TASK [upload-logs : Create log directories]
2025-10-10 08:47:25.239001 | logserver.rdoproject.org | changed
2025-10-10 08:47:25.242196 | 
2025-10-10 08:47:25.242265 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-10-10 08:47:25.523402 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.007926
2025-10-10 08:47:25.529455 | 
2025-10-10 08:47:25.529545 | TASK [upload-logs : Upload logs to log server]
2025-10-10 08:47:27.202345 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-10-10 08:47:27.207941 | 
2025-10-10 08:47:27.208088 | LOOP [upload-logs : Compress console log and json output]
2025-10-10 08:47:27.297167 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-10 08:47:27.297882 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-10 08:47:27.305028 | 
2025-10-10 08:47:27.305103 | LOOP [upload-logs : Upload compressed console log and json output]
2025-10-10 08:47:27.367127 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-10 08:47:27.367420 | 
2025-10-10 08:47:27.367996 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-10 08:47:27.373932 | 
2025-10-10 08:47:27.374009 | LOOP [upload-logs : Upload console log and json output]