2025-09-29 15:24:16.819405 | Job console starting...
2025-09-29 15:24:17.059557 | Updating repositories
2025-09-29 15:24:17.743608 | Preparing job workspace
2025-09-29 15:24:24.592744 | Running Ansible setup...
2025-09-29 15:24:27.464602 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-09-29 15:24:27.976186 | 
2025-09-29 15:24:27.976294 | PLAY [localhost]
2025-09-29 15:24:27.985187 | 
2025-09-29 15:24:27.985264 | TASK [Gathering Facts]
2025-09-29 15:24:28.781735 | localhost | ok
2025-09-29 15:24:28.794801 | 
2025-09-29 15:24:28.794881 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-09-29 15:24:29.103370 | localhost -> localhost | changed
2025-09-29 15:24:29.109074 | 
2025-09-29 15:24:29.109161 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-09-29 15:24:29.761549 | localhost -> localhost | changed
2025-09-29 15:24:29.769506 | 
2025-09-29 15:24:29.769571 | TASK [Setup log path fact]
2025-09-29 15:24:29.785784 | localhost | ok
2025-09-29 15:24:29.796555 | 
2025-09-29 15:24:29.796623 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-09-29 15:24:29.813068 | localhost | ok
2025-09-29 15:24:29.819726 | 
2025-09-29 15:24:29.819788 | TASK [emit-job-header : Print job information]
2025-09-29 15:24:29.845516 | # Job Information
2025-09-29 15:24:29.845628 | Ansible Version: 2.15.12
2025-09-29 15:24:29.845656 | Job: cifmw-molecule-adoption_osp_deploy
2025-09-29 15:24:29.845677 | Pipeline: github-check
2025-09-29 15:24:29.845695 | Executor: ibm-bm4-ze.softwarefactory-project.io
2025-09-29 15:24:29.845713 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3346
2025-09-29 15:24:29.845730 | Log URL (when completed): https://logserver.rdoproject.org/ada/rdoproject.org/adabffc053744a5f9b11c86a495f04ae/
2025-09-29 15:24:29.845747 | Event ID: 2deb8020-9d48-11f0-9fd2-92fac1648764
2025-09-29 15:24:29.849061 | 
2025-09-29 15:24:29.849131 | LOOP [emit-job-header : Print node information]
2025-09-29 15:24:29.932144 | localhost | ok:
2025-09-29 15:24:29.932289 | localhost | # Node Information
2025-09-29 15:24:29.932317 | localhost | Inventory Hostname: controller
2025-09-29 15:24:29.932341 | localhost | Hostname: np0005459977
2025-09-29 15:24:29.932361 | localhost | Username: zuul
2025-09-29 15:24:29.932380 | localhost | Distro: CentOS 9
2025-09-29 15:24:29.932397 | localhost | Provider: ibm-bm4-nodepool
2025-09-29 15:24:29.932413 | localhost | Region: regionOne
2025-09-29 15:24:29.932428 | localhost | Label: cloud-centos-9-stream-tripleo-ibm
2025-09-29 15:24:29.932444 | localhost | Product Name: OpenStack Compute
2025-09-29 15:24:29.932460 | localhost | Interface IP: 192.168.26.52
2025-09-29 15:24:29.950754 | 
2025-09-29 15:24:29.950810 | PLAY [all]
2025-09-29 15:24:29.957248 | 
2025-09-29 15:24:29.957307 | TASK [Gather network facts]
2025-09-29 15:24:30.321587 | controller | ok
2025-09-29 15:24:30.334731 | 
2025-09-29 15:24:30.334793 | TASK [include_role : start-zuul-console]
2025-09-29 15:24:30.351869 | controller | ok
2025-09-29 15:24:30.362795 | 
2025-09-29 15:24:30.362858 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-09-29 15:24:30.663992 | controller | ok
2025-09-29 15:24:30.671509 | 
2025-09-29 15:24:30.671574 | TASK [include_role : add-build-sshkey]
2025-09-29 15:24:30.688601 | controller | ok
2025-09-29 15:24:30.700873 | 
2025-09-29 15:24:30.700958 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-09-29 15:24:30.886119 | controller -> localhost | ok
2025-09-29 15:24:30.892094 | 
2025-09-29 15:24:30.892196 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-09-29 15:24:30.909717 | controller | ok
2025-09-29 15:24:30.923736 | controller | included: /var/lib/zuul/builds/adabffc053744a5f9b11c86a495f04ae/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-09-29 15:24:30.929235 | 
2025-09-29 15:24:30.929298 | TASK [add-build-sshkey : Create Temp SSH key]
2025-09-29 15:24:31.387687 | controller -> localhost | Generating public/private rsa key pair.
2025-09-29 15:24:31.387879 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/adabffc053744a5f9b11c86a495f04ae/work/adabffc053744a5f9b11c86a495f04ae_id_rsa.
2025-09-29 15:24:31.387931 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/adabffc053744a5f9b11c86a495f04ae/work/adabffc053744a5f9b11c86a495f04ae_id_rsa.pub.
2025-09-29 15:24:31.387963 | controller -> localhost | The key fingerprint is:
2025-09-29 15:24:31.387986 | controller -> localhost | SHA256:KLT9ACm7r++cyCb5AQ/9PNo9y9EOGqpcN96ctk0sOOQ zuul-build-sshkey
2025-09-29 15:24:31.388006 | controller -> localhost | The key's randomart image is:
2025-09-29 15:24:31.388025 | controller -> localhost | +---[RSA 3072]----+
2025-09-29 15:24:31.388043 | controller -> localhost | |                 |
2025-09-29 15:24:31.388062 | controller -> localhost | |     .           |
2025-09-29 15:24:31.388080 | controller -> localhost | |  . +            |
2025-09-29 15:24:31.388111 | controller -> localhost | | . + + .         |
2025-09-29 15:24:31.388133 | controller -> localhost | |o o o.+ S        |
2025-09-29 15:24:31.388152 | controller -> localhost | | + +o..+.        |
2025-09-29 15:24:31.388169 | controller -> localhost | | .+.=Eo.oo       |
2025-09-29 15:24:31.388188 | controller -> localhost | |+.oOoB*==        |
2025-09-29 15:24:31.388205 | controller -> localhost | |.*B+O.=Bo.       |
2025-09-29 15:24:31.388222 | controller -> localhost | +----[SHA256]-----+
2025-09-29 15:24:31.388268 | controller -> localhost | ok: Runtime: 0:00:00.124369
2025-09-29 15:24:31.394095 | 
2025-09-29 15:24:31.394290 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-09-29 15:24:31.412592 | controller | ok
2025-09-29 15:24:31.421816 | controller | included: /var/lib/zuul/builds/adabffc053744a5f9b11c86a495f04ae/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-09-29 15:24:31.429328 | 
2025-09-29 15:24:31.429393 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-09-29 15:24:31.443397 | controller | skipping: Conditional result was False
2025-09-29 15:24:31.449264 | 
2025-09-29 15:24:31.449338 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-09-29 15:24:31.833749 | controller | changed
2025-09-29 15:24:31.839506 | 
2025-09-29 15:24:31.839569 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-09-29 15:24:32.032084 | controller | ok
2025-09-29 15:24:32.036842 | 
2025-09-29 15:24:32.036905 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-09-29 15:24:32.611713 | controller | changed
2025-09-29 15:24:32.616735 | 
2025-09-29 15:24:32.616828 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-09-29 15:24:33.194223 | controller | changed
2025-09-29 15:24:33.198945 | 
2025-09-29 15:24:33.199012 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-09-29 15:24:33.223096 | controller | skipping: Conditional result was False
2025-09-29 15:24:33.229016 | 
2025-09-29 15:24:33.229082 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-09-29 15:24:33.527084 | controller -> localhost | changed
2025-09-29 15:24:33.535427 | 
2025-09-29 15:24:33.535489 | TASK [add-build-sshkey : Add back temp key]
2025-09-29 15:24:33.752286 | controller -> localhost | Identity added: /var/lib/zuul/builds/adabffc053744a5f9b11c86a495f04ae/work/adabffc053744a5f9b11c86a495f04ae_id_rsa (zuul-build-sshkey)
2025-09-29 15:24:33.752456 | controller -> localhost | ok: Runtime: 0:00:00.007236
2025-09-29 15:24:33.757802 | 
2025-09-29 15:24:33.757862 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-09-29 15:24:34.049941 | controller | ok
2025-09-29 15:24:34.054196 | 
2025-09-29 15:24:34.054256 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-09-29 15:24:34.077949 | controller | skipping: Conditional result was False
2025-09-29 15:24:34.086642 | 
2025-09-29 15:24:34.086704 | TASK [include_role : validate-host]
2025-09-29 15:24:34.104901 | controller | ok
2025-09-29 15:24:34.123156 | 
2025-09-29 15:24:34.123219 | TASK [validate-host : Define zuul_info_dir fact]
2025-09-29 15:24:34.150940 | controller | ok
2025-09-29 15:24:34.155132 | 
2025-09-29 15:24:34.155190 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-09-29 15:24:34.341211 | controller -> localhost | ok
2025-09-29 15:24:34.347125 | 
2025-09-29 15:24:34.347192 | TASK [validate-host : Collect information about the host]
2025-09-29 15:24:34.967998 | controller | ok
2025-09-29 15:24:34.975309 | 
2025-09-29 15:24:34.975371 | TASK [validate-host : Sanitize hostname]
2025-09-29 15:24:35.043779 | controller | ok
2025-09-29 15:24:35.049243 | 
2025-09-29 15:24:35.049307 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-09-29 15:24:35.422822 | controller -> localhost | changed
2025-09-29 15:24:35.427698 | 
2025-09-29 15:24:35.427761 | TASK [validate-host : Collect information about zuul worker]
2025-09-29 15:24:35.739979 | controller | ok
2025-09-29 15:24:35.744524 | 
2025-09-29 15:24:35.744591 | TASK [validate-host : Write out all zuul information for each host]
2025-09-29 15:24:36.102768 | controller -> localhost | changed
2025-09-29 15:24:36.112219 | 
2025-09-29 15:24:36.112285 | TASK [include_role : prepare-workspace-openshift]
2025-09-29 15:24:36.127121 | controller | skipping: Conditional result was False
2025-09-29 15:24:36.132987 | 
2025-09-29 15:24:36.133054 | TASK [include_role : remove-zuul-sshkey]
2025-09-29 15:24:36.147041 | controller | skipping: Conditional result was False
2025-09-29 15:24:36.152634 | 
2025-09-29 15:24:36.152704 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-09-29 15:24:36.341319 | controller | ok: "logs"
2025-09-29 15:24:36.341591 | controller | ok: All items complete
2025-09-29 15:24:36.341626 | 
2025-09-29 15:24:36.506671 | controller | ok: "artifacts"
2025-09-29 15:24:36.670319 | controller | ok: "docs"
2025-09-29 15:24:36.682053 | 
2025-09-29 15:24:36.682126 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-09-29 15:24:36.876253 | controller | changed: "logs"
2025-09-29 15:24:37.039342 | controller | changed: "artifacts"
2025-09-29 15:24:37.201423 | controller | changed: "docs"
2025-09-29 15:24:37.222159 | 
2025-09-29 15:24:37.222209 | PLAY RECAP
2025-09-29 15:24:37.222252 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-09-29 15:24:37.222278 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-09-29 15:24:37.222296 | 
2025-09-29 15:24:37.299658 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-09-29 15:24:37.300418 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-09-29 15:24:37.861605 | 
2025-09-29 15:24:37.861783 | PLAY [all]
2025-09-29 15:24:37.880508 | 
2025-09-29 15:24:37.880579 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-09-29 15:24:37.942695 | controller | ok
2025-09-29 15:24:37.947745 | 
2025-09-29 15:24:37.947846 | TASK [mirror-info-fork : Create /etc/ci]
2025-09-29 15:24:38.285159 | controller | changed
2025-09-29 15:24:38.290804 | 
2025-09-29 15:24:38.290879 | TASK [mirror-info-fork : Install ci_mirror script]
2025-09-29 15:24:39.040459 | controller | changed
2025-09-29 15:24:39.049274 | 
2025-09-29 15:24:39.049344 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-09-29 15:24:39.406644 | controller | changed:
2025-09-29 15:24:39.406898 | controller | {
2025-09-29 15:24:39.406957 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-09-29 15:24:39.406992 | controller | }
2025-09-29 15:24:39.597649 | controller | changed:
2025-09-29 15:24:39.597708 | controller | {
2025-09-29 15:24:39.597738 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-09-29 15:24:39.597760 | controller | }
2025-09-29 15:24:39.791013 | controller | changed:
2025-09-29 15:24:39.791064 | controller | {
2025-09-29 15:24:39.791088 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com"
2025-09-29 15:24:39.791114 | controller | }
2025-09-29 15:24:39.997696 | controller | changed:
2025-09-29 15:24:39.997750 | controller | {
2025-09-29 15:24:39.997776 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-09-29 15:24:39.997796 | controller | }
2025-09-29 15:24:40.193280 | controller | changed:
2025-09-29 15:24:40.193328 | controller | {
2025-09-29 15:24:40.193352 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-09-29 15:24:40.193371 | controller | }
2025-09-29 15:24:40.389566 | controller | changed:
2025-09-29 15:24:40.389618 | controller | {
2025-09-29 15:24:40.389644 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-09-29 15:24:40.389664 | controller | }
2025-09-29 15:24:40.587239 | controller | changed:
2025-09-29 15:24:40.587299 | controller | {
2025-09-29 15:24:40.587325 | 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-09-29 15:24:40.587349 | controller | }
2025-09-29 15:24:40.788080 | controller | changed:
2025-09-29 15:24:40.788219 | controller | {
2025-09-29 15:24:40.788254 | 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-09-29 15:24:40.788277 | controller | }
2025-09-29 15:24:40.988318 | controller | changed:
2025-09-29 15:24:40.988389 | controller | {
2025-09-29 15:24:40.988415 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-09-29 15:24:40.988435 | controller | }
2025-09-29 15:24:41.192641 | controller | changed:
2025-09-29 15:24:41.192735 | controller | {
2025-09-29 15:24:41.192762 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-09-29 15:24:41.192782 | controller | }
2025-09-29 15:24:41.386350 | controller | changed:
2025-09-29 15:24:41.386410 | controller | {
2025-09-29 15:24:41.386434 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-09-29 15:24:41.386453 | controller | }
2025-09-29 15:24:41.610853 | controller | changed:
2025-09-29 15:24:41.611076 | controller | {
2025-09-29 15:24:41.611115 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-09-29 15:24:41.611137 | controller | }
2025-09-29 15:24:41.776969 | controller | changed:
2025-09-29 15:24:41.777162 | controller | {
2025-09-29 15:24:41.777190 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-09-29 15:24:41.777210 | controller | }
2025-09-29 15:24:41.977811 | controller | changed:
2025-09-29 15:24:41.977880 | controller | {
2025-09-29 15:24:41.977921 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-09-29 15:24:41.977949 | controller | }
2025-09-29 15:24:42.174610 | controller | changed:
2025-09-29 15:24:42.174667 | controller | {
2025-09-29 15:24:42.174690 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-09-29 15:24:42.174708 | controller | }
2025-09-29 15:24:42.369709 | controller | changed:
2025-09-29 15:24:42.369762 | controller | {
2025-09-29 15:24:42.369786 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-09-29 15:24:42.369804 | controller | }
2025-09-29 15:24:42.577521 | controller | changed:
2025-09-29 15:24:42.577579 | controller | {
2025-09-29 15:24:42.577602 | 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-09-29 15:24:42.577621 | controller | }
2025-09-29 15:24:42.776298 | controller | changed:
2025-09-29 15:24:42.776424 | controller | {
2025-09-29 15:24:42.776454 | 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-09-29 15:24:42.776487 | controller | }
2025-09-29 15:24:42.988360 | controller | changed:
2025-09-29 15:24:42.988571 | controller | {
2025-09-29 15:24:42.988605 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-09-29 15:24:42.988624 | controller | }
2025-09-29 15:24:43.186613 | controller | changed:
2025-09-29 15:24:43.186698 | controller | {
2025-09-29 15:24:43.186726 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-09-29 15:24:43.186747 | controller | }
2025-09-29 15:24:43.381879 | controller | changed:
2025-09-29 15:24:43.381956 | controller | {
2025-09-29 15:24:43.381982 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-09-29 15:24:43.382000 | controller | }
2025-09-29 15:24:43.579024 | controller | changed:
2025-09-29 15:24:43.579086 | controller | {
2025-09-29 15:24:43.579120 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-09-29 15:24:43.579140 | controller | }
2025-09-29 15:24:43.780982 | controller | changed:
2025-09-29 15:24:43.781171 | controller | {
2025-09-29 15:24:43.781200 | 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-09-29 15:24:43.781222 | controller | }
2025-09-29 15:24:43.980778 | controller | changed:
2025-09-29 15:24:43.980991 | controller | {
2025-09-29 15:24:43.981018 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-09-29 15:24:43.981038 | controller | }
2025-09-29 15:24:44.174995 | controller | changed:
2025-09-29 15:24:44.175083 | controller | {
2025-09-29 15:24:44.175116 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-09-29 15:24:44.175135 | controller | }
2025-09-29 15:24:44.376500 | controller | changed:
2025-09-29 15:24:44.376580 | controller | {
2025-09-29 15:24:44.376605 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-09-29 15:24:44.376623 | controller | }
2025-09-29 15:24:44.402580 | 
2025-09-29 15:24:44.402742 | TASK [Set timezone to UTC]
2025-09-29 15:24:44.786930 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-09-29 15:24:44.791485 | 
2025-09-29 15:24:44.791567 | TASK [Create nodepool directory]
2025-09-29 15:24:45.005003 | controller | changed
2025-09-29 15:24:45.010410 | 
2025-09-29 15:24:45.010474 | TASK [Create nodepool sub_nodes file]
2025-09-29 15:24:45.547589 | controller | changed
2025-09-29 15:24:45.552615 | 
2025-09-29 15:24:45.552687 | TASK [Create nodepool sub_nodes_private file]
2025-09-29 15:24:46.097071 | controller | changed
2025-09-29 15:24:46.102323 | 
2025-09-29 15:24:46.102384 | LOOP [Populate nodepool sub_nodes file]
2025-09-29 15:24:46.131090 | 
2025-09-29 15:24:46.131223 | LOOP [Populate nodepool sub_nodes_private file]
2025-09-29 15:24:46.159497 | 
2025-09-29 15:24:46.159628 | TASK [Create nodepool primary file]
2025-09-29 15:24:46.182524 | controller | skipping: Conditional result was False
2025-09-29 15:24:46.187717 | 
2025-09-29 15:24:46.187787 | TASK [Create nodepool node_private for this node]
2025-09-29 15:24:46.741833 | controller | changed
2025-09-29 15:24:46.747347 | 
2025-09-29 15:24:46.747412 | LOOP [Copy ssh keys to nodepool directory]
2025-09-29 15:24:47.076031 | controller | ok: Item: id_rsa Runtime: 0:00:00.005832
2025-09-29 15:24:47.076300 | 
2025-09-29 15:24:47.234376 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.004421
2025-09-29 15:24:47.245366 | 
2025-09-29 15:24:47.245544 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-09-29 15:24:47.807013 | controller | changed
2025-09-29 15:24:47.812174 | 
2025-09-29 15:24:47.812243 | TASK [Validate sudoers config after edits]
2025-09-29 15:24:48.031297 | controller | /etc/sudoers: parsed OK
2025-09-29 15:24:48.031374 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-09-29 15:24:48.031385 | controller | /etc/sudoers.d/zuul: parsed OK
2025-09-29 15:24:48.031391 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-09-29 15:24:48.337532 | controller | ok: Runtime: 0:00:00.005744
2025-09-29 15:24:48.343465 | 
2025-09-29 15:24:48.343526 | TASK [Show the environment passed in to job shell scripts]
2025-09-29 15:24:48.557439 | controller | SHELL=/bin/bash
2025-09-29 15:24:48.557542 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-09-29 15:24:48.557554 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-09-29 15:24:48.557560 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/46/3346/6a421188dcf3783293fbf634ec10ba9563693bb1
2025-09-29 15:24:48.557566 | controller | PWD=/home/zuul
2025-09-29 15:24:48.557572 | controller | ZUUL_PIPELINE=github-check
2025-09-29 15:24:48.557577 | controller | LOGNAME=zuul
2025-09-29 15:24:48.557583 | controller | XDG_SESSION_TYPE=tty
2025-09-29 15:24:48.557588 | controller | _=/usr/bin/env
2025-09-29 15:24:48.557593 | controller | MOTD_SHOWN=pam
2025-09-29 15:24:48.557598 | controller | HOME=/home/zuul
2025-09-29 15:24:48.557604 | controller | LANG=en_US.UTF-8
2025-09-29 15:24:48.557609 | controller | SSH_CONNECTION=192.168.26.12 38286 192.168.26.52 22
2025-09-29 15:24:48.557615 | 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-09-29 15:24:48.557623 | controller | ZUUL_CHANGE_IDS=3346,6a421188dcf3783293fbf634ec10ba9563693bb1
2025-09-29 15:24:48.557629 | controller | WORKSPACE=/home/zuul/workspace
2025-09-29 15:24:48.557634 | controller | XDG_SESSION_CLASS=user
2025-09-29 15:24:48.557639 | controller | SELINUX_ROLE_REQUESTED=
2025-09-29 15:24:48.557645 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-09-29 15:24:48.557650 | controller | USER=zuul
2025-09-29 15:24:48.557655 | controller | ZUUL_VOTING=True
2025-09-29 15:24:48.557660 | controller | BUILD_TIMEOUT=1800000
2025-09-29 15:24:48.557666 | controller | SELINUX_USE_CURRENT_RANGE=
2025-09-29 15:24:48.557671 | controller | SHLVL=1
2025-09-29 15:24:48.557676 | controller | ZUUL_PATCHSET=6a421188dcf3783293fbf634ec10ba9563693bb1
2025-09-29 15:24:48.557682 | controller | XDG_SESSION_ID=1
2025-09-29 15:24:48.557687 | controller | ZUUL_BRANCH=main
2025-09-29 15:24:48.557692 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-09-29 15:24:48.557697 | controller | SSH_CLIENT=192.168.26.12 38286 22
2025-09-29 15:24:48.557703 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-09-29 15:24:48.557708 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-09-29 15:24:48.557713 | controller | which_declare=declare -f
2025-09-29 15:24:48.557719 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2025-09-29 15:24:48.557724 | controller | SELINUX_LEVEL_REQUESTED=
2025-09-29 15:24:48.557730 | controller | ZUUL_CHANGE=3346
2025-09-29 15:24:48.557735 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-09-29 15:24:48.557740 | controller | ZUUL_UUID=adabffc053744a5f9b11c86a495f04ae
2025-09-29 15:24:48.557745 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-09-29 15:24:48.557751 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-09-29 15:24:48.557756 | controller | }
2025-09-29 15:24:48.871066 | controller | ok: Runtime: 0:00:00.006182
2025-09-29 15:24:48.876880 | 
2025-09-29 15:24:48.876958 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-09-29 15:24:48.891491 | controller | skipping: Conditional result was False
2025-09-29 15:24:48.897001 | 
2025-09-29 15:24:48.897065 | TASK [Symlink /home/zuul-worker/workspace]
2025-09-29 15:24:49.420469 | controller | skipping: Conditional result was False
2025-09-29 15:24:49.426648 | 
2025-09-29 15:24:49.426716 | TASK [Ensure legacy workspace directory]
2025-09-29 15:24:49.618313 | controller | changed
2025-09-29 15:24:49.636493 | 
2025-09-29 15:24:49.636554 | PLAY RECAP
2025-09-29 15:24:49.636606 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-09-29 15:24:49.636629 | 
2025-09-29 15:24:49.723425 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-09-29 15:24:49.724428 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/read_global_vars.yml@main]
2025-09-29 15:24:50.238189 | 
2025-09-29 15:24:50.238291 | PLAY [Load global variables]
2025-09-29 15:24:50.257660 | 
2025-09-29 15:24:50.257725 | TASK [Read group_vars all file]
2025-09-29 15:24:50.276560 | controller | ok
2025-09-29 15:24:50.290319 | 
2025-09-29 15:24:50.290397 | TASK [cifmw_helpers : Check if file is available]
2025-09-29 15:24:50.541340 | controller | ok
2025-09-29 15:24:50.546177 | 
2025-09-29 15:24:50.546249 | TASK [cifmw_helpers : Read vars]
2025-09-29 15:24:50.568283 | 
2025-09-29 15:24:50.568622 | LOOP [cifmw_helpers : Set vars as fact]
2025-09-29 15:24:50.623778 | 
2025-09-29 15:24:50.623872 | PLAY RECAP
2025-09-29 15:24:50.623931 | controller | ok: 1 changed: 0 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-09-29 15:24:50.623958 | 
2025-09-29 15:24:50.708538 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/read_global_vars.yml@main]
2025-09-29 15:24:50.709594 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-09-29 15:24:51.208203 | 
2025-09-29 15:24:51.208352 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-09-29 15:24:51.230285 | 
2025-09-29 15:24:51.230363 | TASK [Create zuul-output directory]
2025-09-29 15:24:51.556077 | controller | changed
2025-09-29 15:24:51.560965 | 
2025-09-29 15:24:51.561038 | TASK [Slurp Zuul inventory test]
2025-09-29 15:24:51.839953 | controller -> localhost | ok
2025-09-29 15:24:51.846647 | 
2025-09-29 15:24:51.846718 | TASK [Save zuul inventory]
2025-09-29 15:24:52.630022 | controller | changed
2025-09-29 15:24:52.635030 | 
2025-09-29 15:24:52.635110 | TASK [Save zuul vars without the change_message]
2025-09-29 15:24:53.193073 | controller | changed
2025-09-29 15:24:53.210626 | 
2025-09-29 15:24:53.210687 | PLAY RECAP
2025-09-29 15:24:53.210739 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-09-29 15:24:53.210762 | 
2025-09-29 15:24:53.282057 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-09-29 15:24:53.282861 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-09-29 15:24:53.814359 | 
2025-09-29 15:24:53.814476 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2025-09-29 15:24:53.834331 | 
2025-09-29 15:24:53.834405 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2025-09-29 15:24:53.854467 | controller | ok
2025-09-29 15:24:53.871568 | 
2025-09-29 15:24:53.871654 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-09-29 15:24:53.896226 | controller | skipping: Conditional result was False
2025-09-29 15:24:53.901769 | 
2025-09-29 15:24:53.901833 | TASK [mirror-info-fork : Create /etc/ci]
2025-09-29 15:24:54.224681 | controller | ok
2025-09-29 15:24:54.230115 | 
2025-09-29 15:24:54.230185 | TASK [mirror-info-fork : Install ci_mirror script]
2025-09-29 15:24:54.819980 | controller | ok
2025-09-29 15:24:54.830990 | 
2025-09-29 15:24:54.831057 | TASK [Prepare workspace]
2025-09-29 15:24:54.849109 | controller | ok
2025-09-29 15:24:54.865606 | 
2025-09-29 15:24:54.865676 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-09-29 15:24:55.157384 | controller | ok
2025-09-29 15:24:55.163260 | 
2025-09-29 15:24:55.163329 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-09-29 15:24:57.087694 | controller | Output suppressed because no_log was given
2025-09-29 15:24:57.096848 | 
2025-09-29 15:24:57.096960 | LOOP [Create zuul-output directory]
2025-09-29 15:24:57.281973 | controller | changed: "/home/zuul/ci-framework-data/logs"
2025-09-29 15:24:57.433518 | controller | ok: "/home/zuul/zuul-output/logs"
2025-09-29 15:24:57.440752 | 
2025-09-29 15:24:57.440842 | TASK [Install required packages]
2025-09-29 15:26:01.565265 | controller | changed
2025-09-29 15:26:01.570439 | 
2025-09-29 15:26:01.570497 | TASK [Install venv]
2025-09-29 15:27:01.794116 | controller | changed
2025-09-29 15:27:01.817652 | 
2025-09-29 15:27:01.817719 | PLAY RECAP
2025-09-29 15:27:01.817763 | controller | ok: 7 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-09-29 15:27:01.817785 | 
2025-09-29 15:27:01.901398 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-09-29 15:27:01.902261 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-09-29 15:27:02.391717 | 
2025-09-29 15:27:02.391813 | PLAY [Run ci/playbooks/molecule-test.yml]
2025-09-29 15:27:02.410244 | 
2025-09-29 15:27:02.410310 | TASK [Gather required facts]
2025-09-29 15:27:02.887747 | controller | ok
2025-09-29 15:27:02.892796 | 
2025-09-29 15:27:02.892871 | TASK [Load environment var if instructed to]
2025-09-29 15:27:02.916928 | controller | skipping: Conditional result was False
2025-09-29 15:27:02.922148 | 
2025-09-29 15:27:02.922212 | TASK [Run molecule]
2025-09-29 15:27:03.831519 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2025-09-29 15:27:03.897810 | controller | WARNING  Driver podman does not provide a schema.
2025-09-29 15:27:03.900202 | controller | INFO     Performing prerun with role_name_check=0...
2025-09-29 15:27:20.360235 | controller | WARNING  Another version of '@NAMESPACE@.@NAME@' 3.1.4 was found installed in /home/zuul/.cache/ansible-compat/82d78a/collections/ansible_collections, only the first one will be used, 3.1.4 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 15:27:20.360675 | controller | WARNING  Another version of 'ansible.netcommon' 7.1.0 was found installed in /home/zuul/.cache/ansible-compat/82d78a/collections/ansible_collections, only the first one will be used, 7.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 15:27:20.361141 | controller | WARNING  Another version of 'ansible.posix' 1.6.2 was found installed in /home/zuul/.cache/ansible-compat/82d78a/collections/ansible_collections, only the first one will be used, 1.6.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 15:27:20.361630 | controller | WARNING  Another version of 'ansible.utils' 5.1.2 was found installed in /home/zuul/.cache/ansible-compat/82d78a/collections/ansible_collections, only the first one will be used, 5.1.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 15:27:20.362130 | controller | WARNING  Another version of 'cifmw.general' 1.0.0+9dd70aab was found installed in /home/zuul/.cache/ansible-compat/82d78a/collections/ansible_collections, only the first one will be used, 1.0.0+9dd70aab (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 15:27:20.362594 | controller | WARNING  Another version of 'cisco.ios' 9.0.3 was found installed in /home/zuul/.cache/ansible-compat/82d78a/collections/ansible_collections, only the first one will be used, 9.0.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 15:27:20.363048 | controller | WARNING  Another version of 'community.crypto' 2.22.3 was found installed in /home/zuul/.cache/ansible-compat/82d78a/collections/ansible_collections, only the first one will be used, 2.22.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 15:27:20.363499 | controller | WARNING  Another version of 'community.general' 10.0.1 was found installed in /home/zuul/.cache/ansible-compat/82d78a/collections/ansible_collections, only the first one will be used, 10.0.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 15:27:20.363947 | controller | WARNING  Another version of 'community.libvirt' 1.3.0 was found installed in /home/zuul/.cache/ansible-compat/82d78a/collections/ansible_collections, only the first one will be used, 1.3.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 15:27:20.364374 | controller | WARNING  Another version of 'community.okd' 4.0.0 was found installed in /home/zuul/.cache/ansible-compat/82d78a/collections/ansible_collections, only the first one will be used, 4.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 15:27:20.364809 | controller | WARNING  Another version of 'containers.podman' 1.16.2 was found installed in /home/zuul/.cache/ansible-compat/82d78a/collections/ansible_collections, only the first one will be used, 1.16.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 15:27:20.365263 | controller | WARNING  Another version of 'junipernetworks.junos' 9.1.0 was found installed in /home/zuul/.cache/ansible-compat/82d78a/collections/ansible_collections, only the first one will be used, 9.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 15:27:20.365720 | controller | WARNING  Another version of 'kubernetes.core' 5.0.0 was found installed in /home/zuul/.cache/ansible-compat/82d78a/collections/ansible_collections, only the first one will be used, 5.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 15:27:20.366157 | controller | WARNING  Another version of 'mellanox.onyx' 1.0.0 was found installed in /home/zuul/.cache/ansible-compat/82d78a/collections/ansible_collections, only the first one will be used, 1.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 15:27:20.366618 | controller | WARNING  Another version of 'openstack.config_template' 2.1.1 was found installed in /home/zuul/.cache/ansible-compat/82d78a/collections/ansible_collections, only the first one will be used, 2.1.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-09-29 15:27:20.376227 | controller | INFO     Running default > destroy
2025-09-29 15:27:20.600345 | controller | INFO     Sanity checks: 'podman'
2025-09-29 15:27:21.484986 | controller |
2025-09-29 15:27:21.485087 | controller | PLAY [Destroy] *****************************************************************
2025-09-29 15:27:21.485181 | controller |
2025-09-29 15:27:21.485306 | controller | TASK [Get passwd entries for USER env] *****************************************
2025-09-29 15:27:21.485409 | controller | Monday 29 September 2025  15:27:21 +0000 (0:00:00.013)       0:00:00.013 ******
2025-09-29 15:27:21.767101 | controller | ok: [localhost]
2025-09-29 15:27:21.767183 | controller |
2025-09-29 15:27:21.767316 | controller | TASK [Get shell async_dir] *****************************************************
2025-09-29 15:27:21.767423 | controller | Monday 29 September 2025  15:27:21 +0000 (0:00:00.282)       0:00:00.296 ******
2025-09-29 15:27:21.807900 | controller | ok: [localhost]
2025-09-29 15:27:21.808013 | controller |
2025-09-29 15:27:21.808137 | controller | TASK [Set async_dir for HOME env] **********************************************
2025-09-29 15:27:21.808281 | controller | Monday 29 September 2025  15:27:21 +0000 (0:00:00.040)       0:00:00.337 ******
2025-09-29 15:27:21.840431 | controller | skipping: [localhost]
2025-09-29 15:27:21.840541 | controller |
2025-09-29 15:27:21.840665 | controller | TASK [Destroy molecule instance(s)] ********************************************
2025-09-29 15:27:21.840789 | controller | Monday 29 September 2025  15:27:21 +0000 (0:00:00.032)       0:00:00.370 ******
2025-09-29 15:27:22.220686 | controller | changed: [localhost] => (item={'command': '/sbin/init', 'dockerfile': 'containerfile', 'image': 'registry.access.redhat.com/ubi9/ubi-init', 'name': 'instance', 'pre_build_image': True})
2025-09-29 15:27:22.220735 | controller |
2025-09-29 15:27:22.220864 | controller | TASK [Wait for instance(s) deletion to complete] *******************************
2025-09-29 15:27:22.220978 | controller | Monday 29 September 2025  15:27:22 +0000 (0:00:00.377)       0:00:00.747 ******
2025-09-29 15:27:27.672273 | controller | FAILED - RETRYING: [localhost]: Wait for instance(s) deletion to complete (300 retries left).
2025-09-29 15:27:27.672365 | controller | changed: [localhost] => (item={'failed': 0, 'started': 1, 'finished': 0, 'ansible_job_id': 'j698256943820.26197', 'results_file': '/home/zuul/.ansible_async/j698256943820.26197', 'changed': True, 'item': {'command': '/sbin/init', 'dockerfile': 'containerfile', 'image': 'registry.access.redhat.com/ubi9/ubi-init', 'name': 'instance', 'pre_build_image': True}, 'ansible_loop_var': 'item'})
2025-09-29 15:27:27.672467 | controller |
2025-09-29 15:27:27.672575 | controller | TASK [Delete podman network dedicated to this scenario] ************************
2025-09-29 15:27:27.672681 | controller | Monday 29 September 2025  15:27:27 +0000 (0:00:05.453)       0:00:06.201 ******
2025-09-29 15:27:27.722386 | controller | skipping: [localhost] => (item=instance: None specified)
2025-09-29 15:27:27.722496 | controller | skipping: [localhost]
2025-09-29 15:27:27.722631 | controller |
2025-09-29 15:27:27.722750 | controller | PLAY RECAP *********************************************************************
2025-09-29 15:27:27.722900 | controller | localhost                  : ok=4    changed=2    unreachable=0    failed=0    skipped=2    rescued=0    ignored=0
2025-09-29 15:27:27.723012 | controller |
2025-09-29 15:27:27.723116 | controller | Monday 29 September 2025  15:27:27 +0000 (0:00:00.050)       0:00:06.252 ******
2025-09-29 15:27:27.723211 | controller | ===============================================================================
2025-09-29 15:27:27.723338 | controller | Wait for instance(s) deletion to complete ------------------------------- 5.45s
2025-09-29 15:27:27.723436 | controller | Destroy molecule instance(s) -------------------------------------------- 0.38s
2025-09-29 15:27:27.723532 | controller | Get passwd entries for USER env ----------------------------------------- 0.28s
2025-09-29 15:27:27.723629 | controller | Delete podman network dedicated to this scenario ------------------------ 0.05s
2025-09-29 15:27:27.723728 | controller | Get shell async_dir ----------------------------------------------------- 0.04s
2025-09-29 15:27:27.723830 | controller | Set async_dir for HOME env ---------------------------------------------- 0.03s
2025-09-29 15:27:27.774391 | controller | INFO     Running default > create
2025-09-29 15:27:28.170971 | controller |
2025-09-29 15:27:28.171049 | controller | PLAY [Create] ******************************************************************
2025-09-29 15:27:28.171142 | controller |
2025-09-29 15:27:28.171247 | controller | TASK [Get podman executable path] **********************************************
2025-09-29 15:27:28.171345 | controller | Monday 29 September 2025  15:27:28 +0000 (0:00:00.012)       0:00:00.012 ******
2025-09-29 15:27:28.453027 | controller | ok: [localhost]
2025-09-29 15:27:28.453109 | controller |
2025-09-29 15:27:28.453219 | controller | TASK [Register podman executable path] *****************************************
2025-09-29 15:27:28.453368 | controller | Monday 29 September 2025  15:27:28 +0000 (0:00:00.282)       0:00:00.295 ******
2025-09-29 15:27:28.490092 | controller | ok: [localhost]
2025-09-29 15:27:28.490138 | controller |
2025-09-29 15:27:28.490242 | controller | TASK [Get passwd entries for USER env] *****************************************
2025-09-29 15:27:28.490370 | controller | Monday 29 September 2025  15:27:28 +0000 (0:00:00.037)       0:00:00.332 ******
2025-09-29 15:27:28.760043 | controller | ok: [localhost]
2025-09-29 15:27:28.760120 | controller |
2025-09-29 15:27:28.760228 | controller | TASK [Get shell async_dir] *****************************************************
2025-09-29 15:27:28.760373 | controller | Monday 29 September 2025  15:27:28 +0000 (0:00:00.269)       0:00:00.602 ******
2025-09-29 15:27:28.801790 | controller | ok: [localhost]
2025-09-29 15:27:28.801892 | controller |
2025-09-29 15:27:28.802001 | controller | TASK [Set async_dir for HOME env] **********************************************
2025-09-29 15:27:28.802107 | controller | Monday 29 September 2025  15:27:28 +0000 (0:00:00.041)       0:00:00.644 ******
2025-09-29 15:27:28.833299 | controller | skipping: [localhost]
2025-09-29 15:27:28.833370 | controller |
2025-09-29 15:27:28.833518 | controller | TASK [Log into a container registry] *******************************************
2025-09-29 15:27:28.833565 | controller | Monday 29 September 2025  15:27:28 +0000 (0:00:00.031)       0:00:00.675 ******
2025-09-29 15:27:28.873899 | controller | skipping: [localhost] => (item="instance registry username: None specified")
2025-09-29 15:27:28.873999 | controller | skipping: [localhost]
2025-09-29 15:27:28.874098 | controller |
2025-09-29 15:27:28.874210 | controller | TASK [Check presence of custom Dockerfiles] ************************************
2025-09-29 15:27:28.874334 | controller | Monday 29 September 2025  15:27:28 +0000 (0:00:00.040)       0:00:00.716 ******
2025-09-29 15:27:29.166488 | controller | ok: [localhost] => (item=Dockerfile: containerfile)
2025-09-29 15:27:29.166551 | controller |
2025-09-29 15:27:29.166665 | controller | TASK [Create Dockerfiles from image names] *************************************
2025-09-29 15:27:29.166760 | controller | Monday 29 September 2025  15:27:29 +0000 (0:00:00.292)       0:00:01.008 ******
2025-09-29 15:27:29.206493 | controller | skipping: [localhost] => (item="Dockerfile: containerfile; Image: registry.access.redhat.com/ubi9/ubi-init")
2025-09-29 15:27:29.206627 | controller | skipping: [localhost]
2025-09-29 15:27:29.206746 | controller |
2025-09-29 15:27:29.206866 | controller | TASK [Discover local Podman images] ********************************************
2025-09-29 15:27:29.206979 | controller | Monday 29 September 2025  15:27:29 +0000 (0:00:00.040)       0:00:01.048 ******
2025-09-29 15:27:29.537971 | controller | ok: [localhost] => (item=instance)
2025-09-29 15:27:29.538055 | controller |
2025-09-29 15:27:29.538162 | controller | TASK [Build an Ansible compatible image] ***************************************
2025-09-29 15:27:29.538282 | controller | Monday 29 September 2025  15:27:29 +0000 (0:00:00.331)       0:00:01.380 ******
2025-09-29 15:27:29.577110 | controller | skipping: [localhost] => (item=registry.access.redhat.com/ubi9/ubi-init)
2025-09-29 15:27:29.577208 | controller | skipping: [localhost]
2025-09-29 15:27:29.577338 | controller |
2025-09-29 15:27:29.577444 | controller | TASK [Determine the CMD directives] ********************************************
2025-09-29 15:27:29.577549 | controller | Monday 29 September 2025  15:27:29 +0000 (0:00:00.039)       0:00:01.419 ******
2025-09-29 15:27:29.624744 | controller | ok: [localhost] => (item="instance command: /sbin/init")
2025-09-29 15:27:29.624865 | controller |
2025-09-29 15:27:29.625006 | controller | TASK [Remove possible pre-existing containers] *********************************
2025-09-29 15:27:29.625132 | controller | Monday 29 September 2025  15:27:29 +0000 (0:00:00.047)       0:00:01.467 ******
2025-09-29 15:27:29.864743 | controller | changed: [localhost]
2025-09-29 15:27:29.864823 | controller |
2025-09-29 15:27:29.864942 | controller | TASK [Discover local podman networks] ******************************************
2025-09-29 15:27:29.865039 | controller | Monday 29 September 2025  15:27:29 +0000 (0:00:00.237)       0:00:01.704 ******
2025-09-29 15:27:29.907277 | controller | skipping: [localhost] => (item=instance: None specified)
2025-09-29 15:27:29.907379 | controller | skipping: [localhost]
2025-09-29 15:27:29.907467 | controller |
2025-09-29 15:27:29.907564 | controller | TASK [Create podman network dedicated to this scenario] ************************
2025-09-29 15:27:29.907659 | controller | Monday 29 September 2025  15:27:29 +0000 (0:00:00.044)       0:00:01.749 ******
2025-09-29 15:27:29.944635 | controller | skipping: [localhost]
2025-09-29 15:27:29.944722 | controller |
2025-09-29 15:27:29.944823 | controller | TASK [Create molecule instance(s)] *********************************************
2025-09-29 15:27:29.944946 | controller | Monday 29 September 2025  15:27:29 +0000 (0:00:00.037)       0:00:01.787 ******
2025-09-29 15:27:30.259133 | controller | changed: [localhost] => (item=instance)
2025-09-29 15:27:30.259200 | controller |
2025-09-29 15:27:30.259334 | controller | TASK [Wait for instance(s) creation to complete] *******************************
2025-09-29 15:27:30.259440 | controller | Monday 29 September 2025  15:27:30 +0000 (0:00:00.313)       0:00:02.100 ******
2025-09-29 15:27:40.888347 | controller | FAILED - RETRYING: [localhost]: Wait for instance(s) creation to complete (300 retries left).
2025-09-29 15:27:40.888443 | controller | FAILED - RETRYING: [localhost]: Wait for instance(s) creation to complete (299 retries left).
2025-09-29 15:27:40.890904 | controller | changed: [localhost] => (item=instance)
2025-09-29 15:27:40.937852 | controller |
2025-09-29 15:27:40.937884 | controller | PLAY RECAP *********************************************************************
2025-09-29 15:27:40.937893 | controller | localhost                  : ok=10   changed=3    unreachable=0    failed=0    skipped=6    rescued=0    ignored=0
2025-09-29 15:27:40.937899 | controller |
2025-09-29 15:27:40.937905 | controller | Monday 29 September 2025  15:27:40 +0000 (0:00:10.630)       0:00:12.730 ******
2025-09-29 15:27:40.937910 | controller | ===============================================================================
2025-09-29 15:27:40.937916 | controller | Wait for instance(s) creation to complete ------------------------------ 10.63s
2025-09-29 15:27:40.937921 | controller | Discover local Podman images -------------------------------------------- 0.33s
2025-09-29 15:27:40.937926 | controller | Create molecule instance(s) --------------------------------------------- 0.31s
2025-09-29 15:27:40.937932 | controller | Check presence of custom Dockerfiles ------------------------------------ 0.29s
2025-09-29 15:27:40.937937 | controller | Get podman executable path ---------------------------------------------- 0.28s
2025-09-29 15:27:40.937942 | controller | Get passwd entries for USER env ----------------------------------------- 0.27s
2025-09-29 15:27:40.937947 | controller | Remove possible pre-existing containers --------------------------------- 0.24s
2025-09-29 15:27:40.937953 | controller | Determine the CMD directives -------------------------------------------- 0.05s
2025-09-29 15:27:40.937958 | controller | Discover local podman networks ------------------------------------------ 0.04s
2025-09-29 15:27:40.937964 | controller | Get shell async_dir ----------------------------------------------------- 0.04s
2025-09-29 15:27:40.937969 | controller | Log into a container registry ------------------------------------------- 0.04s
2025-09-29 15:27:40.937975 | controller | Create Dockerfiles from image names ------------------------------------- 0.04s
2025-09-29 15:27:40.937985 | controller | Build an Ansible compatible image --------------------------------------- 0.04s
2025-09-29 15:27:40.937991 | controller | Create podman network dedicated to this scenario ------------------------ 0.04s
2025-09-29 15:27:40.937996 | controller | Register podman executable path ----------------------------------------- 0.04s
2025-09-29 15:27:40.938001 | controller | Set async_dir for HOME env ---------------------------------------------- 0.03s
2025-09-29 15:27:40.938016 | controller | INFO     Running default > prepare
2025-09-29 15:27:40.938496 | controller | WARNING  Skipping, prepare playbook not configured.
2025-09-29 15:27:40.946424 | controller | INFO     Running default > converge
2025-09-29 15:27:41.361853 | controller |
2025-09-29 15:27:41.387749 | controller | PLAY [Converge] ****************************************************************
2025-09-29 15:27:41.387770 | controller |
2025-09-29 15:27:41.387778 | controller | TASK [Gather stack nodes and facts] ********************************************
2025-09-29 15:27:41.387784 | controller | Monday 29 September 2025  15:27:41 +0000 (0:00:00.018)       0:00:00.018 ******
2025-09-29 15:27:41.387797 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/adoption_osp_deploy/tasks/gather_stack_nodes.yml for instance => (item={'stackname': 'overcloud', 'network_data_file': 'network_data.yaml.j2', 'vips_data_file': 'vips_data_overcloud.yaml', 'stack_nodes': ['osp-controllers']})
2025-09-29 15:27:41.422459 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/adoption_osp_deploy/tasks/gather_stack_nodes.yml for instance => (item={'stackname': 'cell1', 'network_data_file': 'network_data.yaml.j2', 'vips_data_file': 'vips_data_cell1.yaml', 'stack_nodes': ['cell1-osp-computes', 'cell1-osp-controllers']})
2025-09-29 15:27:41.422495 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/adoption_osp_deploy/tasks/gather_stack_nodes.yml for instance => (item={'stackname': 'cell2', 'network_data_file': 'network_data.yaml.j2', 'vips_data_file': 'vips_data_cell2.yaml', 'stack_nodes': ['cell2-osp-computes', 'cell2-osp-controllers']})
2025-09-29 15:27:41.422504 | controller |
2025-09-29 15:27:41.422513 | controller | TASK [Get main facts for the overcloud stack] **********************************
2025-09-29 15:27:41.422520 | controller | Monday 29 September 2025  15:27:41 +0000 (0:00:00.028)       0:00:00.047 ******
2025-09-29 15:27:41.422532 | controller | ok: [instance]
2025-09-29 15:27:41.491288 | controller |
2025-09-29 15:27:41.491314 | controller | TASK [Gather other facts for stack overcloud"] *********************************
2025-09-29 15:27:41.491323 | controller | Monday 29 September 2025  15:27:41 +0000 (0:00:00.034)       0:00:00.082 ******
2025-09-29 15:27:41.491334 | controller | fatal: [instance]: FAILED! =>
2025-09-29 15:27:41.491356 | controller |   msg: |-
2025-09-29 15:27:41.491363 | controller |     The task includes an option with an undefined variable. The error was: Unable to look up a name or access an attribute in template string ({{
2025-09-29 15:27:41.491370 | controller |       [cifmw_adoption_osp_deploy_user_dir,
2025-09-29 15:27:41.491376 | controller |        'network_data_' ~  _overcloud_name ~'.yaml'
2025-09-29 15:27:41.491382 | controller |       ] | path_join
2025-09-29 15:27:41.491387 | controller |     }}).
2025-09-29 15:27:41.491393 | controller |     Make sure your variable name does not contain invalid characters like '-': expected str, bytes or os.PathLike object, not AnsibleUndefined. expected str, bytes or os.PathLike object, not AnsibleUndefined. Unable to look up a name or access an attribute in template string ({{
2025-09-29 15:27:41.491399 | controller |       [cifmw_adoption_osp_deploy_user_dir,
2025-09-29 15:27:41.491405 | controller |        'network_data_' ~  _overcloud_name ~'.yaml'
2025-09-29 15:27:41.491410 | controller |       ] | path_join
2025-09-29 15:27:41.491415 | controller |     }}).
2025-09-29 15:27:41.491423 | controller |     Make sure your variable name does not contain invalid characters like '-': expected str, bytes or os.PathLike object, not AnsibleUndefined. expected str, bytes or os.PathLike object, not AnsibleUndefined
2025-09-29 15:27:41.492006 | controller |
2025-09-29 15:27:41.492024 | controller |     The error appears to be in '/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/adoption_osp_deploy/tasks/gather_stack_nodes.yml': line 16, column 3, but may
2025-09-29 15:27:41.492032 | controller |     be elsewhere in the file depending on the exact syntax problem.
2025-09-29 15:27:41.492038 | controller |
2025-09-29 15:27:41.492043 | controller |     The offending line appears to be:
2025-09-29 15:27:41.492049 | controller |
2025-09-29 15:27:41.492054 | controller |
2025-09-29 15:27:41.492062 | controller |     - name: Gather other facts for stack {{ _overcloud_name }}"
2025-09-29 15:27:41.493913 | controller |       ^ here
2025-09-29 15:27:41.493929 | controller |     We could be wrong, but this one looks like it might be an issue with
2025-09-29 15:27:41.493936 | controller |     missing quotes. Always quote template expression brackets when they
2025-09-29 15:27:41.493944 | controller |     start a value. For instance:
2025-09-29 15:27:41.531293 | controller |
2025-09-29 15:27:41.531321 | controller |         with_items:
2025-09-29 15:27:41.531329 | controller |           - {{ foo }}
2025-09-29 15:27:41.531334 | controller |
2025-09-29 15:27:41.531339 | controller |     Should be written as:
2025-09-29 15:27:41.531344 | controller |
2025-09-29 15:27:41.531349 | controller |         with_items:
2025-09-29 15:27:41.531354 | controller |           - "{{ foo }}"
2025-09-29 15:27:41.531359 | controller |
2025-09-29 15:27:41.531367 | controller | PLAY RECAP *********************************************************************
2025-09-29 15:27:41.531372 | controller | instance                   : ok=4    changed=0    unreachable=0    failed=1    skipped=0    rescued=0    ignored=0
2025-09-29 15:27:41.531377 | controller |
2025-09-29 15:27:41.531382 | controller | Monday 29 September 2025  15:27:41 +0000 (0:00:00.069)       0:00:00.151 ******
2025-09-29 15:27:41.531387 | controller | ===============================================================================
2025-09-29 15:27:41.531392 | controller | Gather other facts for stack overcloud" --------------------------------- 0.07s
2025-09-29 15:27:41.531397 | controller | Get main facts for the overcloud stack ---------------------------------- 0.03s
2025-09-29 15:27:41.531402 | controller | Gather stack nodes and facts -------------------------------------------- 0.03s
2025-09-29 15:27:41.531411 | controller | CRITICAL Ansible return code was 2, command was: ['ansible-playbook', '--inventory', '/home/zuul/.cache/molecule/adoption_osp_deploy/default/inventory', '--skip-tags', 'molecule-notest,notest', '/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/adoption_osp_deploy/molecule/default/converge.yml']
2025-09-29 15:27:41.531836 | controller | WARNING  An error occurred during the test sequence action: 'converge'. Cleaning up.
2025-09-29 15:27:41.541788 | controller | INFO     Running default > cleanup
2025-09-29 15:27:41.542308 | controller | WARNING  Skipping, cleanup playbook not configured.
2025-09-29 15:27:41.550417 | controller | INFO     Running default > destroy
2025-09-29 15:27:41.948998 | controller |
2025-09-29 15:27:41.949178 | controller | PLAY [Destroy] *****************************************************************
2025-09-29 15:27:41.949329 | controller |
2025-09-29 15:27:41.949466 | controller | TASK [Get passwd entries for USER env] *****************************************
2025-09-29 15:27:41.949599 | controller | Monday 29 September 2025  15:27:41 +0000 (0:00:00.012)       0:00:00.012 ******
2025-09-29 15:27:42.228200 | controller | ok: [localhost]
2025-09-29 15:27:42.268658 | controller |
2025-09-29 15:27:42.268677 | controller | TASK [Get shell async_dir] *****************************************************
2025-09-29 15:27:42.268685 | controller | Monday 29 September 2025  15:27:42 +0000 (0:00:00.278)       0:00:00.291 ******
2025-09-29 15:27:42.268694 | controller | ok: [localhost]
2025-09-29 15:27:42.300327 | controller |
2025-09-29 15:27:42.300360 | controller | TASK [Set async_dir for HOME env] **********************************************
2025-09-29 15:27:42.300374 | controller | Monday 29 September 2025  15:27:42 +0000 (0:00:00.040)       0:00:00.332 ******
2025-09-29 15:27:42.300390 | controller | skipping: [localhost]
2025-09-29 15:27:42.663998 | controller |
2025-09-29 15:27:42.664018 | controller | TASK [Destroy molecule instance(s)] ********************************************
2025-09-29 15:27:42.664026 | controller | Monday 29 September 2025  15:27:42 +0000 (0:00:00.031)       0:00:00.363 ******
2025-09-29 15:27:42.664035 | controller | changed: [localhost] => (item={'command': '/sbin/init', 'dockerfile': 'containerfile', 'image': 'registry.access.redhat.com/ubi9/ubi-init', 'name': 'instance', 'pre_build_image': True})
2025-09-29 15:27:48.115259 | controller |
2025-09-29 15:27:48.115292 | controller | TASK [Wait for instance(s) deletion to complete] *******************************
2025-09-29 15:27:48.115300 | controller | Monday 29 September 2025  15:27:42 +0000 (0:00:00.363)       0:00:00.727 ******
2025-09-29 15:27:48.115311 | controller | FAILED - RETRYING: [localhost]: Wait for instance(s) deletion to complete (300 retries left).
2025-09-29 15:27:48.165068 | controller | changed: [localhost] => (item={'failed': 0, 'started': 1, 'finished': 0, 'ansible_job_id': 'j499837640784.26637', 'results_file': '/home/zuul/.ansible_async/j499837640784.26637', 'changed': True, 'item': {'command': '/sbin/init', 'dockerfile': 'containerfile', 'image': 'registry.access.redhat.com/ubi9/ubi-init', 'name': 'instance', 'pre_build_image': True}, 'ansible_loop_var': 'item'})
2025-09-29 15:27:48.165093 | controller |
2025-09-29 15:27:48.165101 | controller | TASK [Delete podman network dedicated to this scenario] ************************
2025-09-29 15:27:48.165112 | controller | Monday 29 September 2025  15:27:48 +0000 (0:00:05.450)       0:00:06.178 ******
2025-09-29 15:27:48.165121 | controller | skipping: [localhost] => (item=instance: None specified)
2025-09-29 15:27:48.208138 | controller | skipping: [localhost]
2025-09-29 15:27:48.208158 | controller |
2025-09-29 15:27:48.208166 | controller | PLAY RECAP *********************************************************************
2025-09-29 15:27:48.208171 | controller | localhost                  : ok=4    changed=2    unreachable=0    failed=0    skipped=2    rescued=0    ignored=0
2025-09-29 15:27:48.208177 | controller |
2025-09-29 15:27:48.208182 | controller | Monday 29 September 2025  15:27:48 +0000 (0:00:00.049)       0:00:06.228 ******
2025-09-29 15:27:48.208187 | controller | ===============================================================================
2025-09-29 15:27:48.208193 | controller | Wait for instance(s) deletion to complete ------------------------------- 5.45s
2025-09-29 15:27:48.208198 | controller | Destroy molecule instance(s) -------------------------------------------- 0.36s
2025-09-29 15:27:48.208203 | controller | Get passwd entries for USER env ----------------------------------------- 0.28s
2025-09-29 15:27:48.208208 | controller | Delete podman network dedicated to this scenario ------------------------ 0.05s
2025-09-29 15:27:48.208214 | controller | Get shell async_dir ----------------------------------------------------- 0.04s
2025-09-29 15:27:48.208219 | controller | Set async_dir for HOME env ---------------------------------------------- 0.03s
2025-09-29 15:27:48.208227 | controller | INFO     Pruning extra files from scenario ephemeral directory
2025-09-29 15:27:48.210017 | controller | INFO     Writing /tmp/report.html report.
2025-09-29 15:27:48.500109 | controller | ERROR
2025-09-29 15:27:48.500210 | controller | {
2025-09-29 15:27:48.500239 | controller |   "delta": "0:00:45.033251",
2025-09-29 15:27:48.500259 | controller |   "end": "2025-09-29 15:27:48.255086",
2025-09-29 15:27:48.500276 | controller |   "msg": "non-zero return code",
2025-09-29 15:27:48.500303 | controller |   "rc": 1,
2025-09-29 15:27:48.500322 | controller |   "start": "2025-09-29 15:27:03.221835"
2025-09-29 15:27:48.500338 | controller | }
failure
2025-09-29 15:27:48.503815 | 
2025-09-29 15:27:48.503939 | PLAY RECAP
2025-09-29 15:27:48.503988 | controller | ok: 1 changed: 0 unreachable: 0 failed: 1 skipped: 1 rescued: 0 ignored: 0
2025-09-29 15:27:48.504011 | 
2025-09-29 15:27:48.568542 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-09-29 15:27:48.570050 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-09-29 15:27:49.063945 | 
2025-09-29 15:27:49.064061 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-09-29 15:27:49.082582 | 
2025-09-29 15:27:49.082655 | TASK [Filter out host if needed]
2025-09-29 15:27:49.090574 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-09-29 15:27:49.094690 | 
2025-09-29 15:27:49.094752 | TASK [Ensure file is present]
2025-09-29 15:27:49.387128 | controller | ok
2025-09-29 15:27:49.393858 | 
2025-09-29 15:27:49.393942 | TASK [Manage molecule report file]
2025-09-29 15:27:49.919297 | controller | changed
2025-09-29 15:27:49.924598 | 
2025-09-29 15:27:49.924657 | TASK [Check if we get ci-framework-data basedir]
2025-09-29 15:27:50.105925 | controller | ok
2025-09-29 15:27:50.110711 | 
2025-09-29 15:27:50.110769 | TASK [Create ci-framework-data log directory for zuul]
2025-09-29 15:27:50.413941 | controller | changed
2025-09-29 15:27:50.419132 | 
2025-09-29 15:27:50.419201 | TASK [Copy ci-framework interesting files]
2025-09-29 15:27:50.615925 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2025-09-29 15:27:50.942413 | controller | changed
2025-09-29 15:27:50.947545 | 
2025-09-29 15:27:50.947607 | TASK [Get SELinux listing]
2025-09-29 15:27:51.470733 | controller | changed
2025-09-29 15:27:51.476372 | 
2025-09-29 15:27:51.476443 | TASK [Generate log index]
2025-09-29 15:27:52.095691 | controller | changed
2025-09-29 15:27:52.100353 | 
2025-09-29 15:27:52.100420 | TASK [Get some env related data]
2025-09-29 15:27:52.601494 | controller | /home/zuul/.local/bin/ansible
2025-09-29 15:27:53.129385 | controller | changed
2025-09-29 15:27:53.133867 | 
2025-09-29 15:27:53.133939 | TASK [Generate list of logs to collect in home directory]
2025-09-29 15:27:53.415411 | controller | ok: All paths examined
2025-09-29 15:27:53.420248 | 
2025-09-29 15:27:53.420305 | LOOP [Copy logs from home directory]
2025-09-29 15:27:53.720525 | controller | changed:
2025-09-29 15:27:53.720644 | controller | {
2025-09-29 15:27:53.720671 | controller |   "atime": 1759159605.435214,
2025-09-29 15:27:53.720690 | controller |   "ctime": 1759159621.3842475,
2025-09-29 15:27:53.720709 | controller |   "dev": 64513,
2025-09-29 15:27:53.720726 | controller |   "gid": 1000,
2025-09-29 15:27:53.720742 | controller |   "gr_name": "zuul",
2025-09-29 15:27:53.720757 | controller |   "inode": 4409644,
2025-09-29 15:27:53.720772 | controller |   "isblk": false,
2025-09-29 15:27:53.720785 | controller |   "ischr": false,
2025-09-29 15:27:53.720798 | controller |   "isdir": false,
2025-09-29 15:27:53.720812 | controller |   "isfifo": false,
2025-09-29 15:27:53.720825 | controller |   "isgid": false,
2025-09-29 15:27:53.720839 | controller |   "islnk": false,
2025-09-29 15:27:53.720851 | controller |   "isreg": true,
2025-09-29 15:27:53.720864 | controller |   "issock": false,
2025-09-29 15:27:53.720877 | controller |   "isuid": false,
2025-09-29 15:27:53.720889 | controller |   "mode": "0644",
2025-09-29 15:27:53.720902 | controller |   "mtime": 1759159621.3842475,
2025-09-29 15:27:53.720940 | controller |   "nlink": 1,
2025-09-29 15:27:53.720957 | controller |   "path": "/home/zuul/ansible.log",
2025-09-29 15:27:53.720972 | controller |   "pw_name": "zuul",
2025-09-29 15:27:53.720986 | controller |   "rgrp": true,
2025-09-29 15:27:53.720999 | controller |   "roth": true,
2025-09-29 15:27:53.721020 | controller |   "rusr": true,
2025-09-29 15:27:53.721034 | controller |   "size": 6749,
2025-09-29 15:27:53.721047 | controller |   "uid": 1000,
2025-09-29 15:27:53.721062 | controller |   "wgrp": false,
2025-09-29 15:27:53.721088 | controller |   "woth": false,
2025-09-29 15:27:53.721105 | controller |   "wusr": true,
2025-09-29 15:27:53.721120 | controller |   "xgrp": false,
2025-09-29 15:27:53.721133 | controller |   "xoth": false,
2025-09-29 15:27:53.721147 | controller |   "xusr": false
2025-09-29 15:27:53.721160 | controller | }
2025-09-29 15:27:53.734414 | 
2025-09-29 15:27:53.734493 | TASK [Copy crio stats log file]
2025-09-29 15:27:53.748179 | controller | skipping: Conditional result was False
2025-09-29 15:27:53.753212 | 
2025-09-29 15:27:53.753271 | TASK [Get SELinux related data]
2025-09-29 15:27:53.947340 | controller | <no matches>
2025-09-29 15:27:54.277951 | controller | ERROR
2025-09-29 15:27:54.278096 | controller | {
2025-09-29 15:27:54.278128 | controller |   "delta": "0:00:00.007887",
2025-09-29 15:27:54.278150 | controller |   "end": "2025-09-29 15:27:53.947891",
2025-09-29 15:27:54.278169 | controller |   "msg": "non-zero return code",
2025-09-29 15:27:54.278186 | controller |   "rc": 1,
2025-09-29 15:27:54.278201 | controller |   "start": "2025-09-29 15:27:53.940004"
2025-09-29 15:27:54.278216 | controller | }
2025-09-29 15:27:54.278237 | controller | ERROR: Ignoring Errors
2025-09-29 15:27:54.282773 | 
2025-09-29 15:27:54.282837 | TASK [Create system configuration directory]
2025-09-29 15:27:54.453854 | controller | changed
2025-09-29 15:27:54.458317 | 
2025-09-29 15:27:54.458377 | TASK [Get some of the system configurations]
2025-09-29 15:27:54.982994 | controller | changed
2025-09-29 15:27:54.987356 | 
2025-09-29 15:27:54.987412 | TASK [Copy generated documentation if available]
2025-09-29 15:27:55.001271 | controller | skipping: Conditional result was False
2025-09-29 15:27:55.006727 | 
2025-09-29 15:27:55.006788 | TASK [Copy generated AsciiDoc documentation if available]
2025-09-29 15:27:55.020427 | controller | skipping: Conditional result was False
2025-09-29 15:27:55.026460 | 
2025-09-29 15:27:55.026518 | TASK [Compress logs bigger than 2MB]
2025-09-29 15:27:55.551953 | controller | changed
2025-09-29 15:27:55.557350 | 
2025-09-29 15:27:55.557412 | TASK [Copy files from workspace on node]
2025-09-29 15:27:55.575990 | controller | ok
2025-09-29 15:27:55.598466 | 
2025-09-29 15:27:55.598539 | TASK [fetch-output : Set log path for multiple nodes]
2025-09-29 15:27:55.622562 | controller | skipping: Conditional result was False
2025-09-29 15:27:55.627771 | 
2025-09-29 15:27:55.627838 | TASK [fetch-output : Set log path for single node]
2025-09-29 15:27:55.656311 | controller | ok
2025-09-29 15:27:55.662456 | 
2025-09-29 15:27:55.662521 | LOOP [fetch-output : Ensure local output dirs]
2025-09-29 15:27:55.857151 | controller -> localhost | ok: "/var/lib/zuul/builds/adabffc053744a5f9b11c86a495f04ae/work/logs"
2025-09-29 15:27:55.857426 | controller -> localhost | changed: All items complete
2025-09-29 15:27:55.857456 | 
2025-09-29 15:27:56.033124 | controller -> localhost | changed: "/var/lib/zuul/builds/adabffc053744a5f9b11c86a495f04ae/work/artifacts"
2025-09-29 15:27:56.209176 | controller -> localhost | changed: "/var/lib/zuul/builds/adabffc053744a5f9b11c86a495f04ae/work/docs"
2025-09-29 15:27:56.220506 | 
2025-09-29 15:27:56.220584 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-09-29 15:27:56.741513 | controller | changed:
2025-09-29 15:27:56.741713 | controller | .d..t...... ./
2025-09-29 15:27:56.741745 | controller | >f+++++++++ README.html
2025-09-29 15:27:56.741766 | controller | >f+++++++++ ansible-execution.log
2025-09-29 15:27:56.741788 | controller | >f+++++++++ ansible.log
2025-09-29 15:27:56.741805 | controller | >f+++++++++ dmesg.log
2025-09-29 15:27:56.741822 | controller | >f+++++++++ installed-pkgs.log
2025-09-29 15:27:56.741841 | controller | >f+++++++++ python.log
2025-09-29 15:27:56.741858 | controller | >f+++++++++ registries.conf
2025-09-29 15:27:56.741878 | controller | >f+++++++++ report.html
2025-09-29 15:27:56.741898 | controller | >f+++++++++ selinux-denials.log
2025-09-29 15:27:56.741938 | controller | >f+++++++++ selinux-listing.log
2025-09-29 15:27:56.741961 | controller | cd+++++++++ ci-framework-data/
2025-09-29 15:27:56.741978 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-09-29 15:27:56.741994 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-09-29 15:27:56.742010 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-09-29 15:27:56.742025 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-09-29 15:27:56.742040 | controller | cd+++++++++ ci-framework-data/logs/
2025-09-29 15:27:56.742055 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2025-09-29 15:27:56.742081 | controller | cd+++++++++ registries.conf.d/
2025-09-29 15:27:56.742098 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-09-29 15:27:56.742113 | controller | cd+++++++++ system-config/
2025-09-29 15:27:56.742129 | controller | cd+++++++++ system-config/libvirt/
2025-09-29 15:27:56.742145 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-09-29 15:27:56.742161 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-09-29 15:27:57.099972 | controller | changed: .d..t...... ./
2025-09-29 15:27:57.455635 | controller | changed: .d..t...... ./
2025-09-29 15:27:57.470445 | 
2025-09-29 15:27:57.470522 | TASK [Return artifact to Zuul]
2025-09-29 15:27:57.499308 | controller | ok
2025-09-29 15:27:57.518102 | 
2025-09-29 15:27:57.518158 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-09-29 15:27:57.518254 | 
2025-09-29 15:27:57.518280 | PLAY RECAP
2025-09-29 15:27:57.518314 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2025-09-29 15:27:57.518334 | 
2025-09-29 15:27:57.598483 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-09-29 15:27:57.599239 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-09-29 15:27:58.065336 | 
2025-09-29 15:27:58.065430 | PLAY [all]
2025-09-29 15:27:58.082226 | 
2025-09-29 15:27:58.082292 | TASK [include_role : fetch-output]
2025-09-29 15:27:58.110512 | controller | ok
2025-09-29 15:27:58.125488 | 
2025-09-29 15:27:58.125562 | TASK [fetch-output : Set log path for multiple nodes]
2025-09-29 15:27:58.169567 | controller | skipping: Conditional result was False
2025-09-29 15:27:58.174902 | 
2025-09-29 15:27:58.174988 | TASK [fetch-output : Set log path for single node]
2025-09-29 15:27:58.203241 | controller | ok
2025-09-29 15:27:58.208164 | 
2025-09-29 15:27:58.208236 | LOOP [fetch-output : Ensure local output dirs]
2025-09-29 15:27:58.519294 | controller -> localhost | ok: "/var/lib/zuul/builds/adabffc053744a5f9b11c86a495f04ae/work/logs"
2025-09-29 15:27:58.687831 | controller -> localhost | ok: "/var/lib/zuul/builds/adabffc053744a5f9b11c86a495f04ae/work/artifacts"
2025-09-29 15:27:58.860071 | controller -> localhost | ok: "/var/lib/zuul/builds/adabffc053744a5f9b11c86a495f04ae/work/docs"
2025-09-29 15:27:58.867979 | 
2025-09-29 15:27:58.868100 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-09-29 15:27:59.359445 | controller | ok
2025-09-29 15:27:59.359640 | controller | ok: All items complete
2025-09-29 15:27:59.359667 | 
2025-09-29 15:27:59.715982 | controller | ok
2025-09-29 15:28:00.070577 | controller | ok
2025-09-29 15:28:00.084884 | 
2025-09-29 15:28:00.085020 | TASK [include_role : fetch-output-openshift]
2025-09-29 15:28:00.098878 | controller | skipping: Conditional result was False
2025-09-29 15:28:00.105457 | 
2025-09-29 15:28:00.105527 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-09-29 15:28:00.436963 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.008115
2025-09-29 15:28:00.619149 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.005730
2025-09-29 15:28:00.643532 | 
2025-09-29 15:28:00.643604 | PLAY [all]
2025-09-29 15:28:00.656857 | 
2025-09-29 15:28:00.656941 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-09-29 15:28:01.031451 | controller | changed
2025-09-29 15:28:01.052669 | 
2025-09-29 15:28:01.052728 | PLAY RECAP
2025-09-29 15:28:01.052771 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-09-29 15:28:01.052794 | 
2025-09-29 15:28:01.125276 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-09-29 15:28:01.126089 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-09-29 15:28:01.653230 | 
2025-09-29 15:28:01.653341 | PLAY [localhost]
2025-09-29 15:28:01.669360 | 
2025-09-29 15:28:01.669429 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-09-29 15:28:01.960041 | localhost | changed
2025-09-29 15:28:01.964505 | 
2025-09-29 15:28:01.964585 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-09-29 15:28:01.992070 | localhost | ok
2025-09-29 15:28:01.999505 | 
2025-09-29 15:28:01.999568 | TASK [add-fileserver : Create SSH private key tempfile]
2025-09-29 15:28:02.279878 | localhost | changed
2025-09-29 15:28:02.285069 | 
2025-09-29 15:28:02.285136 | TASK [add-fileserver : Create SSH private key from secret]
2025-09-29 15:28:02.798204 | localhost | changed
2025-09-29 15:28:02.802882 | 
2025-09-29 15:28:02.802972 | TASK [add-fileserver : Add fileserver ssh key]
2025-09-29 15:28:03.113595 | localhost | Identity added: /var/lib/zuul/builds/adabffc053744a5f9b11c86a495f04ae/work/tmp/ansible.fk100sr_ (/var/lib/zuul/builds/adabffc053744a5f9b11c86a495f04ae/work/tmp/ansible.fk100sr_)
2025-09-29 15:28:03.113771 | localhost | ok: Runtime: 0:00:00.005259
2025-09-29 15:28:03.117619 | 
2025-09-29 15:28:03.117682 | TASK [add-fileserver : Remove SSH private key from disk]
2025-09-29 15:28:03.327931 | localhost | ok: Runtime: 0:00:00.003832
2025-09-29 15:28:03.332161 | 
2025-09-29 15:28:03.332223 | TASK [add-fileserver : Add fileserver to inventory]
2025-09-29 15:28:03.379747 | localhost | changed
2025-09-29 15:28:03.384555 | 
2025-09-29 15:28:03.384619 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-09-29 15:28:03.685013 | localhost | changed
2025-09-29 15:28:03.703486 | 
2025-09-29 15:28:03.703542 | PLAY [localhost]
2025-09-29 15:28:03.714517 | 
2025-09-29 15:28:03.714579 | TASK [Generate bulk log download script]
2025-09-29 15:28:03.733248 | localhost | ok
2025-09-29 15:28:03.744373 | 
2025-09-29 15:28:03.744442 | TASK [local-log-download : Check API endpoint is defined]
2025-09-29 15:28:03.772033 | localhost | ok: All assertions passed
2025-09-29 15:28:03.776249 | 
2025-09-29 15:28:03.776309 | TASK [local-log-download : Create download script]
2025-09-29 15:28:04.103374 | localhost -> localhost | changed
2025-09-29 15:28:04.111489 | 
2025-09-29 15:28:04.111551 | TASK [Register quick-download link]
2025-09-29 15:28:04.128872 | localhost | ok
2025-09-29 15:28:04.164848 | 
2025-09-29 15:28:04.164949 | PLAY [logserver.rdoproject.org]
2025-09-29 15:28:04.174094 | 
2025-09-29 15:28:04.174156 | TASK [Set zuul-log-path fact]
2025-09-29 15:28:04.190177 | logserver.rdoproject.org | ok
2025-09-29 15:28:04.198634 | 
2025-09-29 15:28:04.198695 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-09-29 15:28:04.224617 | logserver.rdoproject.org | ok
2025-09-29 15:28:04.229663 | 
2025-09-29 15:28:04.229726 | TASK [upload-logs : Create log directories]
2025-09-29 15:28:05.918375 | logserver.rdoproject.org | changed
2025-09-29 15:28:05.921242 | 
2025-09-29 15:28:05.921302 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-09-29 15:28:06.127826 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.004775
2025-09-29 15:28:06.132321 | 
2025-09-29 15:28:06.132381 | TASK [upload-logs : Upload logs to log server]
2025-09-29 15:28:07.614135 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-09-29 15:28:07.616841 | 
2025-09-29 15:28:07.616904 | LOOP [upload-logs : Compress console log and json output]
2025-09-29 15:28:07.652752 | logserver.rdoproject.org | skipping: Conditional result was False
2025-09-29 15:28:07.660771 | logserver.rdoproject.org | skipping: Conditional result was False
2025-09-29 15:28:07.664210 | 
2025-09-29 15:28:07.664270 | LOOP [upload-logs : Upload compressed console log and json output]
2025-09-29 15:28:07.697067 | logserver.rdoproject.org | skipping: Conditional result was False
2025-09-29 15:28:07.697263 | 
2025-09-29 15:28:07.700370 | logserver.rdoproject.org | skipping: Conditional result was False
2025-09-29 15:28:07.711301 | 
2025-09-29 15:28:07.711367 | LOOP [upload-logs : Upload console log and json output]