2025-11-25 09:07:02.692315 | Job console starting...
2025-11-25 09:07:02.898338 | Updating repositories
2025-11-25 09:07:03.651880 | Preparing job workspace
2025-11-25 09:07:10.569897 | Running Ansible setup...
2025-11-25 09:07:13.215525 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-11-25 09:07:13.688338 | 
2025-11-25 09:07:13.688451 | PLAY [localhost]
2025-11-25 09:07:13.696132 | 
2025-11-25 09:07:13.696198 | TASK [Gathering Facts]
2025-11-25 09:07:14.462453 | localhost | ok
2025-11-25 09:07:14.479616 | 
2025-11-25 09:07:14.479734 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-11-25 09:07:14.785917 | localhost -> localhost | changed
2025-11-25 09:07:14.790768 | 
2025-11-25 09:07:14.790847 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-11-25 09:07:15.420879 | localhost -> localhost | changed
2025-11-25 09:07:15.428262 | 
2025-11-25 09:07:15.428327 | TASK [Setup log path fact]
2025-11-25 09:07:15.445293 | localhost | ok
2025-11-25 09:07:15.455132 | 
2025-11-25 09:07:15.455196 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-11-25 09:07:15.472106 | localhost | ok
2025-11-25 09:07:15.478733 | 
2025-11-25 09:07:15.478795 | TASK [emit-job-header : Print job information]
2025-11-25 09:07:15.505275 | # Job Information
2025-11-25 09:07:15.505378 | Ansible Version: 2.15.12
2025-11-25 09:07:15.505439 | Job: cifmw-molecule-adoption_osp_deploy
2025-11-25 09:07:15.505462 | Pipeline: github-check
2025-11-25 09:07:15.505481 | Executor: ibm-bm3-ze.softwarefactory-project.io
2025-11-25 09:07:15.505498 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3397
2025-11-25 09:07:15.505516 | Log URL (when completed): https://logserver.rdoproject.org/a00/rdoproject.org/a00f5baf14c84f2a8f3d4b41490166f7/
2025-11-25 09:07:15.505533 | Event ID: f8f0ee60-c9dd-11f0-8c1b-57ef65c2fc06
2025-11-25 09:07:15.508792 | 
2025-11-25 09:07:15.508855 | LOOP [emit-job-header : Print node information]
2025-11-25 09:07:15.587192 | localhost | ok:
2025-11-25 09:07:15.587322 | localhost | # Node Information
2025-11-25 09:07:15.587350 | localhost | Inventory Hostname: controller
2025-11-25 09:07:15.587372 | localhost | Hostname: np0005534710
2025-11-25 09:07:15.587411 | localhost | Username: zuul
2025-11-25 09:07:15.587432 | localhost | Distro: CentOS 9
2025-11-25 09:07:15.587448 | localhost | Provider: ibm-bm3-nodepool
2025-11-25 09:07:15.587464 | localhost | Region: regionOne
2025-11-25 09:07:15.587478 | localhost | Label: cloud-centos-9-stream-tripleo-ibm
2025-11-25 09:07:15.587492 | localhost | Product Name: OpenStack Compute
2025-11-25 09:07:15.587506 | localhost | Interface IP: 192.168.25.26
2025-11-25 09:07:15.609633 | 
2025-11-25 09:07:15.609683 | PLAY [all]
2025-11-25 09:07:15.615549 | 
2025-11-25 09:07:15.615605 | TASK [Gather network facts]
2025-11-25 09:07:15.938029 | controller | ok
2025-11-25 09:07:15.950517 | 
2025-11-25 09:07:15.950577 | TASK [include_role : start-zuul-console]
2025-11-25 09:07:15.966861 | controller | ok
2025-11-25 09:07:15.977798 | 
2025-11-25 09:07:15.977855 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-11-25 09:07:16.247571 | controller | ok
2025-11-25 09:07:16.255981 | 
2025-11-25 09:07:16.256045 | TASK [include_role : add-build-sshkey]
2025-11-25 09:07:16.273983 | controller | ok
2025-11-25 09:07:16.285285 | 
2025-11-25 09:07:16.285347 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-11-25 09:07:16.459925 | controller -> localhost | ok
2025-11-25 09:07:16.464708 | 
2025-11-25 09:07:16.464769 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-11-25 09:07:16.481320 | controller | ok
2025-11-25 09:07:16.493481 | controller | included: /var/lib/zuul/builds/a00f5baf14c84f2a8f3d4b41490166f7/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-11-25 09:07:16.498398 | 
2025-11-25 09:07:16.498458 | TASK [add-build-sshkey : Create Temp SSH key]
2025-11-25 09:07:17.158717 | controller -> localhost | Generating public/private rsa key pair.
2025-11-25 09:07:17.158898 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/a00f5baf14c84f2a8f3d4b41490166f7/work/a00f5baf14c84f2a8f3d4b41490166f7_id_rsa.
2025-11-25 09:07:17.158930 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/a00f5baf14c84f2a8f3d4b41490166f7/work/a00f5baf14c84f2a8f3d4b41490166f7_id_rsa.pub.
2025-11-25 09:07:17.158954 | controller -> localhost | The key fingerprint is:
2025-11-25 09:07:17.158975 | controller -> localhost | SHA256:LLhZN1Wb/7c109hEMoYrKmDg1/TXQkRHGE8ruU0L/Uk zuul-build-sshkey
2025-11-25 09:07:17.158994 | controller -> localhost | The key's randomart image is:
2025-11-25 09:07:17.159012 | controller -> localhost | +---[RSA 3072]----+
2025-11-25 09:07:17.159028 | controller -> localhost | |         .++=    |
2025-11-25 09:07:17.159045 | controller -> localhost | |         ..B =   |
2025-11-25 09:07:17.159061 | controller -> localhost | |  .   .   * O E .|
2025-11-25 09:07:17.159079 | controller -> localhost | | . . + o o B B = |
2025-11-25 09:07:17.159096 | controller -> localhost | |  . = + S = = + .|
2025-11-25 09:07:17.159127 | controller -> localhost | |   o = o + o   =.|
2025-11-25 09:07:17.159146 | controller -> localhost | |    o . .     .o*|
2025-11-25 09:07:17.159164 | controller -> localhost | |       .        *|
2025-11-25 09:07:17.159181 | controller -> localhost | |               . |
2025-11-25 09:07:17.159198 | controller -> localhost | +----[SHA256]-----+
2025-11-25 09:07:17.159242 | controller -> localhost | ok: Runtime: 0:00:00.362771
2025-11-25 09:07:17.164346 | 
2025-11-25 09:07:17.164427 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-11-25 09:07:17.181400 | controller | ok
2025-11-25 09:07:17.189824 | controller | included: /var/lib/zuul/builds/a00f5baf14c84f2a8f3d4b41490166f7/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-11-25 09:07:17.196696 | 
2025-11-25 09:07:17.196754 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-11-25 09:07:17.211339 | controller | skipping: Conditional result was False
2025-11-25 09:07:17.216159 | 
2025-11-25 09:07:17.216220 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-11-25 09:07:17.537720 | controller | changed
2025-11-25 09:07:17.542434 | 
2025-11-25 09:07:17.542499 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-11-25 09:07:17.711733 | controller | ok
2025-11-25 09:07:17.716298 | 
2025-11-25 09:07:17.716361 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-11-25 09:07:18.230461 | controller | changed
2025-11-25 09:07:18.234735 | 
2025-11-25 09:07:18.234797 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-11-25 09:07:18.738997 | controller | changed
2025-11-25 09:07:18.743263 | 
2025-11-25 09:07:18.743322 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-11-25 09:07:18.756171 | controller | skipping: Conditional result was False
2025-11-25 09:07:18.761057 | 
2025-11-25 09:07:18.761127 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-11-25 09:07:19.042052 | controller -> localhost | changed
2025-11-25 09:07:19.051319 | 
2025-11-25 09:07:19.051396 | TASK [add-build-sshkey : Add back temp key]
2025-11-25 09:07:19.266740 | controller -> localhost | Identity added: /var/lib/zuul/builds/a00f5baf14c84f2a8f3d4b41490166f7/work/a00f5baf14c84f2a8f3d4b41490166f7_id_rsa (zuul-build-sshkey)
2025-11-25 09:07:19.266929 | controller -> localhost | ok: Runtime: 0:00:00.006832
2025-11-25 09:07:19.272459 | 
2025-11-25 09:07:19.272523 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-11-25 09:07:19.542755 | controller | ok
2025-11-25 09:07:19.547073 | 
2025-11-25 09:07:19.547146 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-11-25 09:07:19.570697 | controller | skipping: Conditional result was False
2025-11-25 09:07:19.578974 | 
2025-11-25 09:07:19.579034 | TASK [include_role : validate-host]
2025-11-25 09:07:19.595812 | controller | ok
2025-11-25 09:07:19.614131 | 
2025-11-25 09:07:19.614196 | TASK [validate-host : Define zuul_info_dir fact]
2025-11-25 09:07:19.641217 | controller | ok
2025-11-25 09:07:19.645418 | 
2025-11-25 09:07:19.645481 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-11-25 09:07:19.841539 | controller -> localhost | ok
2025-11-25 09:07:19.846821 | 
2025-11-25 09:07:19.846885 | TASK [validate-host : Collect information about the host]
2025-11-25 09:07:20.423583 | controller | ok
2025-11-25 09:07:20.431259 | 
2025-11-25 09:07:20.431322 | TASK [validate-host : Sanitize hostname]
2025-11-25 09:07:20.492841 | controller | ok
2025-11-25 09:07:20.497598 | 
2025-11-25 09:07:20.497698 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-11-25 09:07:20.876654 | controller -> localhost | changed
2025-11-25 09:07:20.904503 | 
2025-11-25 09:07:20.904595 | TASK [validate-host : Collect information about zuul worker]
2025-11-25 09:07:21.206744 | controller | ok
2025-11-25 09:07:21.211145 | 
2025-11-25 09:07:21.211210 | TASK [validate-host : Write out all zuul information for each host]
2025-11-25 09:07:21.559412 | controller -> localhost | changed
2025-11-25 09:07:21.568174 | 
2025-11-25 09:07:21.568250 | TASK [include_role : prepare-workspace-openshift]
2025-11-25 09:07:21.581260 | controller | skipping: Conditional result was False
2025-11-25 09:07:21.585961 | 
2025-11-25 09:07:21.586027 | TASK [include_role : remove-zuul-sshkey]
2025-11-25 09:07:21.598725 | controller | skipping: Conditional result was False
2025-11-25 09:07:21.603638 | 
2025-11-25 09:07:21.603704 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-11-25 09:07:21.772120 | controller | ok: "logs"
2025-11-25 09:07:21.772336 | controller | ok: All items complete
2025-11-25 09:07:21.772366 | 
2025-11-25 09:07:21.920819 | controller | ok: "artifacts"
2025-11-25 09:07:22.067747 | controller | ok: "docs"
2025-11-25 09:07:22.077809 | 
2025-11-25 09:07:22.077876 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-11-25 09:07:22.250518 | controller | changed: "logs"
2025-11-25 09:07:22.401786 | controller | changed: "artifacts"
2025-11-25 09:07:22.554480 | controller | changed: "docs"
2025-11-25 09:07:22.575621 | 
2025-11-25 09:07:22.575684 | PLAY RECAP
2025-11-25 09:07:22.575727 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-11-25 09:07:22.575753 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-11-25 09:07:22.575770 | 
2025-11-25 09:07:22.648051 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-11-25 09:07:22.648749 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-11-25 09:07:23.146816 | 
2025-11-25 09:07:23.146921 | PLAY [all]
2025-11-25 09:07:23.164699 | 
2025-11-25 09:07:23.164768 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-11-25 09:07:23.213206 | controller | ok
2025-11-25 09:07:23.217660 | 
2025-11-25 09:07:23.217737 | TASK [mirror-info-fork : Create /etc/ci]
2025-11-25 09:07:23.519280 | controller | changed
2025-11-25 09:07:23.524091 | 
2025-11-25 09:07:23.524164 | TASK [mirror-info-fork : Install ci_mirror script]
2025-11-25 09:07:24.230743 | controller | changed
2025-11-25 09:07:24.239564 | 
2025-11-25 09:07:24.239662 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-11-25 09:07:24.567272 | controller | changed:
2025-11-25 09:07:24.567543 | controller | {
2025-11-25 09:07:24.567579 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-11-25 09:07:24.567612 | controller | }
2025-11-25 09:07:24.746344 | controller | changed:
2025-11-25 09:07:24.746458 | controller | {
2025-11-25 09:07:24.746490 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-11-25 09:07:24.746513 | controller | }
2025-11-25 09:07:24.929012 | controller | changed:
2025-11-25 09:07:24.929088 | controller | {
2025-11-25 09:07:24.929124 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com"
2025-11-25 09:07:24.929148 | controller | }
2025-11-25 09:07:25.118951 | controller | changed:
2025-11-25 09:07:25.119330 | controller | {
2025-11-25 09:07:25.119368 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-11-25 09:07:25.119412 | controller | }
2025-11-25 09:07:25.306003 | controller | changed:
2025-11-25 09:07:25.306060 | controller | {
2025-11-25 09:07:25.306086 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-11-25 09:07:25.306107 | controller | }
2025-11-25 09:07:25.488216 | controller | changed:
2025-11-25 09:07:25.488273 | controller | {
2025-11-25 09:07:25.488300 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-11-25 09:07:25.488322 | controller | }
2025-11-25 09:07:25.669235 | controller | changed:
2025-11-25 09:07:25.669286 | controller | {
2025-11-25 09:07:25.669310 | 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-11-25 09:07:25.669330 | controller | }
2025-11-25 09:07:25.856853 | controller | changed:
2025-11-25 09:07:25.856943 | controller | {
2025-11-25 09:07:25.856970 | 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-11-25 09:07:25.856992 | controller | }
2025-11-25 09:07:26.047091 | controller | changed:
2025-11-25 09:07:26.047191 | controller | {
2025-11-25 09:07:26.047217 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-11-25 09:07:26.047235 | controller | }
2025-11-25 09:07:26.233915 | controller | changed:
2025-11-25 09:07:26.234001 | controller | {
2025-11-25 09:07:26.234028 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-11-25 09:07:26.234048 | controller | }
2025-11-25 09:07:26.420081 | controller | changed:
2025-11-25 09:07:26.420149 | controller | {
2025-11-25 09:07:26.420174 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-11-25 09:07:26.420193 | controller | }
2025-11-25 09:07:26.623903 | controller | changed:
2025-11-25 09:07:26.624053 | controller | {
2025-11-25 09:07:26.624087 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-11-25 09:07:26.624122 | controller | }
2025-11-25 09:07:26.787653 | controller | changed:
2025-11-25 09:07:26.787703 | controller | {
2025-11-25 09:07:26.787727 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-11-25 09:07:26.787746 | controller | }
2025-11-25 09:07:26.969529 | controller | changed:
2025-11-25 09:07:26.969640 | controller | {
2025-11-25 09:07:26.969671 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-11-25 09:07:26.969691 | controller | }
2025-11-25 09:07:27.151425 | controller | changed:
2025-11-25 09:07:27.151476 | controller | {
2025-11-25 09:07:27.151502 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-11-25 09:07:27.151521 | controller | }
2025-11-25 09:07:27.337904 | controller | changed:
2025-11-25 09:07:27.337993 | controller | {
2025-11-25 09:07:27.338019 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-11-25 09:07:27.338037 | controller | }
2025-11-25 09:07:27.525448 | controller | changed:
2025-11-25 09:07:27.525536 | controller | {
2025-11-25 09:07:27.525563 | 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-11-25 09:07:27.525584 | controller | }
2025-11-25 09:07:27.716761 | controller | changed:
2025-11-25 09:07:27.716819 | controller | {
2025-11-25 09:07:27.716846 | 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-11-25 09:07:27.716874 | controller | }
2025-11-25 09:07:27.907821 | controller | changed:
2025-11-25 09:07:27.907887 | controller | {
2025-11-25 09:07:27.907913 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-11-25 09:07:27.908106 | controller | }
2025-11-25 09:07:28.094076 | controller | changed:
2025-11-25 09:07:28.094151 | controller | {
2025-11-25 09:07:28.094185 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-11-25 09:07:28.094212 | controller | }
2025-11-25 09:07:28.280374 | controller | changed:
2025-11-25 09:07:28.280435 | controller | {
2025-11-25 09:07:28.280459 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-11-25 09:07:28.280486 | controller | }
2025-11-25 09:07:28.470696 | controller | changed:
2025-11-25 09:07:28.470780 | controller | {
2025-11-25 09:07:28.470805 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-11-25 09:07:28.470823 | controller | }
2025-11-25 09:07:28.658023 | controller | changed:
2025-11-25 09:07:28.658141 | controller | {
2025-11-25 09:07:28.658171 | 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-11-25 09:07:28.658192 | controller | }
2025-11-25 09:07:28.846605 | controller | changed:
2025-11-25 09:07:28.846685 | controller | {
2025-11-25 09:07:28.846709 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-11-25 09:07:28.846727 | controller | }
2025-11-25 09:07:29.035508 | controller | changed:
2025-11-25 09:07:29.035591 | controller | {
2025-11-25 09:07:29.035616 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-11-25 09:07:29.035634 | controller | }
2025-11-25 09:07:29.227338 | controller | changed:
2025-11-25 09:07:29.227431 | controller | {
2025-11-25 09:07:29.227459 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-11-25 09:07:29.227480 | controller | }
2025-11-25 09:07:29.244078 | 
2025-11-25 09:07:29.244161 | TASK [Set timezone to UTC]
2025-11-25 09:07:29.616925 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-11-25 09:07:29.621582 | 
2025-11-25 09:07:29.621647 | TASK [Create nodepool directory]
2025-11-25 09:07:29.811849 | controller | changed
2025-11-25 09:07:29.816906 | 
2025-11-25 09:07:29.816971 | TASK [Create nodepool sub_nodes file]
2025-11-25 09:07:30.281969 | controller | changed
2025-11-25 09:07:30.286217 | 
2025-11-25 09:07:30.286279 | TASK [Create nodepool sub_nodes_private file]
2025-11-25 09:07:30.750277 | controller | changed
2025-11-25 09:07:30.754970 | 
2025-11-25 09:07:30.755030 | LOOP [Populate nodepool sub_nodes file]
2025-11-25 09:07:30.783523 | 
2025-11-25 09:07:30.783643 | LOOP [Populate nodepool sub_nodes_private file]
2025-11-25 09:07:30.811117 | 
2025-11-25 09:07:30.811237 | TASK [Create nodepool primary file]
2025-11-25 09:07:30.834099 | controller | skipping: Conditional result was False
2025-11-25 09:07:30.839274 | 
2025-11-25 09:07:30.839346 | TASK [Create nodepool node_private for this node]
2025-11-25 09:07:31.343057 | controller | changed
2025-11-25 09:07:31.348086 | 
2025-11-25 09:07:31.348155 | LOOP [Copy ssh keys to nodepool directory]
2025-11-25 09:07:31.647959 | controller | ok: Item: id_rsa Runtime: 0:00:00.004168
2025-11-25 09:07:31.648153 | 
2025-11-25 09:07:31.797014 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.004075
2025-11-25 09:07:31.803249 | 
2025-11-25 09:07:31.803337 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-11-25 09:07:32.298553 | controller | changed
2025-11-25 09:07:32.303300 | 
2025-11-25 09:07:32.303358 | TASK [Validate sudoers config after edits]
2025-11-25 09:07:32.506244 | controller | /etc/sudoers: parsed OK
2025-11-25 09:07:32.506330 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-11-25 09:07:32.506340 | controller | /etc/sudoers.d/zuul: parsed OK
2025-11-25 09:07:32.506346 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-11-25 09:07:32.829369 | controller | ok: Runtime: 0:00:00.004965
2025-11-25 09:07:32.835150 | 
2025-11-25 09:07:32.835234 | TASK [Show the environment passed in to job shell scripts]
2025-11-25 09:07:33.026600 | controller | SHELL=/bin/bash
2025-11-25 09:07:33.026635 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-11-25 09:07:33.026645 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-11-25 09:07:33.026651 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/97/3397/a34a0d1eb4ccd96195238ccc2d2eb38eb493dd1c
2025-11-25 09:07:33.026657 | controller | PWD=/home/zuul
2025-11-25 09:07:33.026662 | controller | ZUUL_PIPELINE=github-check
2025-11-25 09:07:33.026668 | controller | LOGNAME=zuul
2025-11-25 09:07:33.026673 | controller | XDG_SESSION_TYPE=tty
2025-11-25 09:07:33.026678 | controller | _=/usr/bin/env
2025-11-25 09:07:33.026721 | controller | MOTD_SHOWN=pam
2025-11-25 09:07:33.026730 | controller | HOME=/home/zuul
2025-11-25 09:07:33.026735 | controller | LANG=en_US.UTF-8
2025-11-25 09:07:33.026740 | controller | SSH_CONNECTION=192.168.25.12 37750 192.168.25.26 22
2025-11-25 09:07:33.026746 | 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-11-25 09:07:33.026754 | controller | ZUUL_CHANGE_IDS=3397,a34a0d1eb4ccd96195238ccc2d2eb38eb493dd1c
2025-11-25 09:07:33.026760 | controller | WORKSPACE=/home/zuul/workspace
2025-11-25 09:07:33.026765 | controller | XDG_SESSION_CLASS=user
2025-11-25 09:07:33.026770 | controller | SELINUX_ROLE_REQUESTED=
2025-11-25 09:07:33.026776 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-11-25 09:07:33.026781 | controller | USER=zuul
2025-11-25 09:07:33.026786 | controller | ZUUL_VOTING=True
2025-11-25 09:07:33.026792 | controller | BUILD_TIMEOUT=1800000
2025-11-25 09:07:33.026797 | controller | SELINUX_USE_CURRENT_RANGE=
2025-11-25 09:07:33.026802 | controller | SHLVL=1
2025-11-25 09:07:33.026807 | controller | ZUUL_PATCHSET=a34a0d1eb4ccd96195238ccc2d2eb38eb493dd1c
2025-11-25 09:07:33.026813 | controller | XDG_SESSION_ID=1
2025-11-25 09:07:33.026818 | controller | ZUUL_BRANCH=main
2025-11-25 09:07:33.026823 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-11-25 09:07:33.026828 | controller | SSH_CLIENT=192.168.25.12 37750 22
2025-11-25 09:07:33.026835 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-11-25 09:07:33.026841 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-11-25 09:07:33.026846 | controller | which_declare=declare -f
2025-11-25 09:07:33.026852 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2025-11-25 09:07:33.026857 | controller | SELINUX_LEVEL_REQUESTED=
2025-11-25 09:07:33.026862 | controller | ZUUL_CHANGE=3397
2025-11-25 09:07:33.026867 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-11-25 09:07:33.026873 | controller | ZUUL_UUID=a00f5baf14c84f2a8f3d4b41490166f7
2025-11-25 09:07:33.026878 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-11-25 09:07:33.026883 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-11-25 09:07:33.026889 | controller | }
2025-11-25 09:07:33.358808 | controller | ok: Runtime: 0:00:00.005462
2025-11-25 09:07:33.363640 | 
2025-11-25 09:07:33.363697 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-11-25 09:07:33.377088 | controller | skipping: Conditional result was False
2025-11-25 09:07:33.382729 | 
2025-11-25 09:07:33.382794 | TASK [Symlink /home/zuul-worker/workspace]
2025-11-25 09:07:33.907517 | controller | skipping: Conditional result was False
2025-11-25 09:07:33.912909 | 
2025-11-25 09:07:33.912972 | TASK [Ensure legacy workspace directory]
2025-11-25 09:07:34.092866 | controller | changed
2025-11-25 09:07:34.109225 | 
2025-11-25 09:07:34.109275 | PLAY RECAP
2025-11-25 09:07:34.109317 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-11-25 09:07:34.109338 | 
2025-11-25 09:07:34.178836 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-11-25 09:07:34.179533 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-11-25 09:07:34.651691 | 
2025-11-25 09:07:34.651788 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-11-25 09:07:34.670232 | 
2025-11-25 09:07:34.670302 | TASK [Create zuul-output directory]
2025-11-25 09:07:34.952761 | controller | changed
2025-11-25 09:07:34.957528 | 
2025-11-25 09:07:34.957599 | TASK [Slurp Zuul inventory test]
2025-11-25 09:07:35.214731 | controller -> localhost | ok
2025-11-25 09:07:35.221356 | 
2025-11-25 09:07:35.221453 | TASK [Save zuul inventory]
2025-11-25 09:07:35.909949 | controller | changed
2025-11-25 09:07:35.914927 | 
2025-11-25 09:07:35.914992 | TASK [Save zuul vars without the change_message]
2025-11-25 09:07:36.459630 | controller | changed
2025-11-25 09:07:36.475790 | 
2025-11-25 09:07:36.475845 | PLAY RECAP
2025-11-25 09:07:36.475886 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-11-25 09:07:36.475908 | 
2025-11-25 09:07:36.537627 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-11-25 09:07:36.538409 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-11-25 09:07:37.045350 | 
2025-11-25 09:07:37.045500 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2025-11-25 09:07:37.064528 | 
2025-11-25 09:07:37.064601 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2025-11-25 09:07:37.082965 | controller | ok
2025-11-25 09:07:37.098997 | 
2025-11-25 09:07:37.099078 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-11-25 09:07:37.122606 | controller | skipping: Conditional result was False
2025-11-25 09:07:37.127763 | 
2025-11-25 09:07:37.127835 | TASK [mirror-info-fork : Create /etc/ci]
2025-11-25 09:07:37.407765 | controller | ok
2025-11-25 09:07:37.412809 | 
2025-11-25 09:07:37.412876 | TASK [mirror-info-fork : Install ci_mirror script]
2025-11-25 09:07:37.967094 | controller | ok
2025-11-25 09:07:37.976887 | 
2025-11-25 09:07:37.976951 | TASK [Prepare workspace]
2025-11-25 09:07:37.994937 | controller | ok
2025-11-25 09:07:38.010046 | 
2025-11-25 09:07:38.010106 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-11-25 09:07:38.291274 | controller | ok
2025-11-25 09:07:38.296691 | 
2025-11-25 09:07:38.296750 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-11-25 09:07:40.603954 | controller | Output suppressed because no_log was given
2025-11-25 09:07:40.612216 | 
2025-11-25 09:07:40.612275 | LOOP [Create zuul-output directory]
2025-11-25 09:07:40.785879 | controller | changed: "/home/zuul/ci-framework-data/logs"
2025-11-25 09:07:40.928814 | controller | ok: "/home/zuul/zuul-output/logs"
2025-11-25 09:07:40.935640 | 
2025-11-25 09:07:40.935700 | TASK [Install required packages]
2025-11-25 09:08:29.283761 | controller | changed
2025-11-25 09:08:29.288657 | 
2025-11-25 09:08:29.288719 | TASK [Install venv]
2025-11-25 09:09:27.441588 | controller | changed
2025-11-25 09:09:27.466478 | 
2025-11-25 09:09:27.466601 | PLAY RECAP
2025-11-25 09:09:27.466655 | controller | ok: 7 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-11-25 09:09:27.466680 | 
2025-11-25 09:09:27.531954 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-11-25 09:09:27.532908 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-11-25 09:09:28.011223 | 
2025-11-25 09:09:28.011319 | PLAY [Run ci/playbooks/molecule-test.yml]
2025-11-25 09:09:28.029606 | 
2025-11-25 09:09:28.029676 | TASK [Gather required facts]
2025-11-25 09:09:28.474118 | controller | ok
2025-11-25 09:09:28.479002 | 
2025-11-25 09:09:28.479070 | TASK [Load environment var if instructed to]
2025-11-25 09:09:28.502893 | controller | skipping: Conditional result was False
2025-11-25 09:09:28.507772 | 
2025-11-25 09:09:28.507833 | TASK [Ensure group_vars dir exists]
2025-11-25 09:09:28.798519 | controller | ok
2025-11-25 09:09:28.804022 | 
2025-11-25 09:09:28.804095 | TASK [Print related variables]
2025-11-25 09:09:28.831586 | controller | ok:
2025-11-25 09:09:28.831696 | controller | mol_config_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config_local.yml
2025-11-25 09:09:28.831723 | controller | roles_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/adoption_osp_deploy
2025-11-25 09:09:28.836009 | 
2025-11-25 09:09:28.836068 | TASK [Run molecule]
2025-11-25 09:09:29.695733 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2025-11-25 09:09:29.761392 | controller | WARNING  Driver podman does not provide a schema.
2025-11-25 09:09:29.763814 | controller | INFO     Performing prerun with role_name_check=0...
2025-11-25 09:09:45.595624 | 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-11-25 09:09:45.596043 | 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-11-25 09:09:45.596474 | 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-11-25 09:09:45.596897 | 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-11-25 09:09:45.597343 | controller | WARNING  Another version of 'cifmw.general' 1.0.0+a34a0d1e was found installed in /home/zuul/.cache/ansible-compat/82d78a/collections/ansible_collections, only the first one will be used, 1.0.0+a34a0d1e (/home/zuul/.ansible/collections/ansible_collections).
2025-11-25 09:09:45.597767 | 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-11-25 09:09:45.598185 | 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-11-25 09:09:45.598598 | 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-11-25 09:09:45.599002 | 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-11-25 09:09:45.599416 | 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-11-25 09:09:45.599825 | 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-11-25 09:09:45.600248 | 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-11-25 09:09:45.600658 | 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-11-25 09:09:45.601076 | 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-11-25 09:09:45.601506 | 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-11-25 09:09:45.610290 | controller | INFO     Running default > destroy
2025-11-25 09:09:45.831441 | controller | INFO     Sanity checks: 'podman'
2025-11-25 09:09:46.636543 | controller |
2025-11-25 09:09:46.636641 | controller | PLAY [Destroy] *****************************************************************
2025-11-25 09:09:46.636735 | controller |
2025-11-25 09:09:46.636850 | controller | TASK [Get passwd entries for USER env] *****************************************
2025-11-25 09:09:46.636951 | controller | Tuesday 25 November 2025  09:09:46 +0000 (0:00:00.013)       0:00:00.013 ******
2025-11-25 09:09:46.901327 | controller | ok: [localhost]
2025-11-25 09:09:46.901411 | controller |
2025-11-25 09:09:46.901506 | controller | TASK [Get shell async_dir] *****************************************************
2025-11-25 09:09:46.901602 | controller | Tuesday 25 November 2025  09:09:46 +0000 (0:00:00.265)       0:00:00.279 ******
2025-11-25 09:09:46.942230 | controller | ok: [localhost]
2025-11-25 09:09:46.942349 | controller |
2025-11-25 09:09:46.942478 | controller | TASK [Set async_dir for HOME env] **********************************************
2025-11-25 09:09:46.942598 | controller | Tuesday 25 November 2025  09:09:46 +0000 (0:00:00.040)       0:00:00.320 ******
2025-11-25 09:09:46.975257 | controller | skipping: [localhost]
2025-11-25 09:09:46.975376 | controller |
2025-11-25 09:09:46.975504 | controller | TASK [Destroy molecule instance(s)] ********************************************
2025-11-25 09:09:46.975629 | controller | Tuesday 25 November 2025  09:09:46 +0000 (0:00:00.032)       0:00:00.352 ******
2025-11-25 09:09:47.334675 | controller | changed: [localhost] => (item={'command': '/sbin/init', 'dockerfile': 'containerfile', 'image': 'registry.access.redhat.com/ubi9/ubi-init', 'name': 'instance', 'pre_build_image': True})
2025-11-25 09:09:47.334751 | controller |
2025-11-25 09:09:47.334865 | controller | TASK [Wait for instance(s) deletion to complete] *******************************
2025-11-25 09:09:47.334963 | controller | Tuesday 25 November 2025  09:09:47 +0000 (0:00:00.358)       0:00:00.711 ******
2025-11-25 09:09:52.771291 | controller | FAILED - RETRYING: [localhost]: Wait for instance(s) deletion to complete (300 retries left).
2025-11-25 09:09:52.771388 | controller | changed: [localhost] => (item={'failed': 0, 'started': 1, 'finished': 0, 'ansible_job_id': 'j452721917095.29430', 'results_file': '/home/zuul/.ansible_async/j452721917095.29430', '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-11-25 09:09:52.771485 | controller |
2025-11-25 09:09:52.771586 | controller | TASK [Delete podman network dedicated to this scenario] ************************
2025-11-25 09:09:52.771686 | controller | Tuesday 25 November 2025  09:09:52 +0000 (0:00:05.437)       0:00:06.148 ******
2025-11-25 09:09:52.817950 | controller | skipping: [localhost] => (item=instance: None specified)
2025-11-25 09:09:52.818069 | controller | skipping: [localhost]
2025-11-25 09:09:52.818195 | controller |
2025-11-25 09:09:52.818284 | controller | PLAY RECAP *********************************************************************
2025-11-25 09:09:52.818382 | controller | localhost                  : ok=4    changed=2    unreachable=0    failed=0    skipped=2    rescued=0    ignored=0
2025-11-25 09:09:52.818465 | controller |
2025-11-25 09:09:52.818559 | controller | Tuesday 25 November 2025  09:09:52 +0000 (0:00:00.047)       0:00:06.196 ******
2025-11-25 09:09:52.818642 | controller | ===============================================================================
2025-11-25 09:09:52.818727 | controller | Wait for instance(s) deletion to complete ------------------------------- 5.44s
2025-11-25 09:09:52.818825 | controller | Destroy molecule instance(s) -------------------------------------------- 0.36s
2025-11-25 09:09:52.818910 | controller | Get passwd entries for USER env ----------------------------------------- 0.27s
2025-11-25 09:09:52.818996 | controller | Delete podman network dedicated to this scenario ------------------------ 0.05s
2025-11-25 09:09:52.819081 | controller | Get shell async_dir ----------------------------------------------------- 0.04s
2025-11-25 09:09:52.819190 | controller | Set async_dir for HOME env ---------------------------------------------- 0.03s
2025-11-25 09:09:52.868281 | controller | INFO     Running default > create
2025-11-25 09:09:53.251474 | controller |
2025-11-25 09:09:53.251596 | controller | PLAY [Create] ******************************************************************
2025-11-25 09:09:53.251704 | controller |
2025-11-25 09:09:53.251820 | controller | TASK [Get podman executable path] **********************************************
2025-11-25 09:09:53.251898 | controller | Tuesday 25 November 2025  09:09:53 +0000 (0:00:00.012)       0:00:00.012 ******
2025-11-25 09:09:53.518787 | controller | ok: [localhost]
2025-11-25 09:09:53.518870 | controller |
2025-11-25 09:09:53.518972 | controller | TASK [Register podman executable path] *****************************************
2025-11-25 09:09:53.519076 | controller | Tuesday 25 November 2025  09:09:53 +0000 (0:00:00.267)       0:00:00.280 ******
2025-11-25 09:09:53.555094 | controller | ok: [localhost]
2025-11-25 09:09:53.555221 | controller |
2025-11-25 09:09:53.555340 | controller | TASK [Get passwd entries for USER env] *****************************************
2025-11-25 09:09:53.555456 | controller | Tuesday 25 November 2025  09:09:53 +0000 (0:00:00.036)       0:00:00.317 ******
2025-11-25 09:09:53.817255 | controller | ok: [localhost]
2025-11-25 09:09:53.817336 | controller |
2025-11-25 09:09:53.817442 | controller | TASK [Get shell async_dir] *****************************************************
2025-11-25 09:09:53.817541 | controller | Tuesday 25 November 2025  09:09:53 +0000 (0:00:00.261)       0:00:00.579 ******
2025-11-25 09:09:53.856567 | controller | ok: [localhost]
2025-11-25 09:09:53.856676 | controller |
2025-11-25 09:09:53.856809 | controller | TASK [Set async_dir for HOME env] **********************************************
2025-11-25 09:09:53.856931 | controller | Tuesday 25 November 2025  09:09:53 +0000 (0:00:00.039)       0:00:00.618 ******
2025-11-25 09:09:53.887002 | controller | skipping: [localhost]
2025-11-25 09:09:53.887111 | controller |
2025-11-25 09:09:53.887250 | controller | TASK [Log into a container registry] *******************************************
2025-11-25 09:09:53.887344 | controller | Tuesday 25 November 2025  09:09:53 +0000 (0:00:00.030)       0:00:00.649 ******
2025-11-25 09:09:53.926161 | controller | skipping: [localhost] => (item="instance registry username: None specified")
2025-11-25 09:09:53.926263 | controller | skipping: [localhost]
2025-11-25 09:09:53.926348 | controller |
2025-11-25 09:09:53.926441 | controller | TASK [Check presence of custom Dockerfiles] ************************************
2025-11-25 09:09:53.926530 | controller | Tuesday 25 November 2025  09:09:53 +0000 (0:00:00.039)       0:00:00.688 ******
2025-11-25 09:09:54.216947 | controller | ok: [localhost] => (item=Dockerfile: containerfile)
2025-11-25 09:09:54.217010 | controller |
2025-11-25 09:09:54.217113 | controller | TASK [Create Dockerfiles from image names] *************************************
2025-11-25 09:09:54.217230 | controller | Tuesday 25 November 2025  09:09:54 +0000 (0:00:00.290)       0:00:00.979 ******
2025-11-25 09:09:54.254304 | controller | skipping: [localhost] => (item="Dockerfile: containerfile; Image: registry.access.redhat.com/ubi9/ubi-init")
2025-11-25 09:09:54.254396 | controller | skipping: [localhost]
2025-11-25 09:09:54.254485 | controller |
2025-11-25 09:09:54.254577 | controller | TASK [Discover local Podman images] ********************************************
2025-11-25 09:09:54.254666 | controller | Tuesday 25 November 2025  09:09:54 +0000 (0:00:00.037)       0:00:01.016 ******
2025-11-25 09:09:54.560723 | controller | ok: [localhost] => (item=instance)
2025-11-25 09:09:54.560816 | controller |
2025-11-25 09:09:54.560917 | controller | TASK [Build an Ansible compatible image] ***************************************
2025-11-25 09:09:54.561018 | controller | Tuesday 25 November 2025  09:09:54 +0000 (0:00:00.306)       0:00:01.322 ******
2025-11-25 09:09:54.598089 | controller | skipping: [localhost] => (item=registry.access.redhat.com/ubi9/ubi-init)
2025-11-25 09:09:54.598213 | controller | skipping: [localhost]
2025-11-25 09:09:54.598314 | controller |
2025-11-25 09:09:54.598417 | controller | TASK [Determine the CMD directives] ********************************************
2025-11-25 09:09:54.598527 | controller | Tuesday 25 November 2025  09:09:54 +0000 (0:00:00.037)       0:00:01.360 ******
2025-11-25 09:09:54.644429 | controller | ok: [localhost] => (item="instance command: /sbin/init")
2025-11-25 09:09:54.644553 | controller |
2025-11-25 09:09:54.644665 | controller | TASK [Remove possible pre-existing containers] *********************************
2025-11-25 09:09:54.644778 | controller | Tuesday 25 November 2025  09:09:54 +0000 (0:00:00.046)       0:00:01.406 ******
2025-11-25 09:09:54.866552 | controller | changed: [localhost]
2025-11-25 09:09:54.866598 | controller |
2025-11-25 09:09:54.866834 | controller | TASK [Discover local podman networks] ******************************************
2025-11-25 09:09:54.907850 | controller | Tuesday 25 November 2025  09:09:54 +0000 (0:00:00.221)       0:00:01.628 ******
2025-11-25 09:09:54.907871 | controller | skipping: [localhost] => (item=instance: None specified)
2025-11-25 09:09:54.942319 | controller | skipping: [localhost]
2025-11-25 09:09:54.942339 | controller |
2025-11-25 09:09:54.942347 | controller | TASK [Create podman network dedicated to this scenario] ************************
2025-11-25 09:09:54.942353 | controller | Tuesday 25 November 2025  09:09:54 +0000 (0:00:00.041)       0:00:01.669 ******
2025-11-25 09:09:54.942362 | controller | skipping: [localhost]
2025-11-25 09:09:55.262829 | controller |
2025-11-25 09:09:55.262859 | controller | TASK [Create molecule instance(s)] *********************************************
2025-11-25 09:09:55.262867 | controller | Tuesday 25 November 2025  09:09:54 +0000 (0:00:00.034)       0:00:01.704 ******
2025-11-25 09:09:55.262878 | controller | changed: [localhost] => (item=instance)
2025-11-25 09:10:00.721381 | controller |
2025-11-25 09:10:00.721420 | controller | TASK [Wait for instance(s) creation to complete] *******************************
2025-11-25 09:10:00.721431 | controller | Tuesday 25 November 2025  09:09:55 +0000 (0:00:00.318)       0:00:02.022 ******
2025-11-25 09:10:00.721445 | controller | FAILED - RETRYING: [localhost]: Wait for instance(s) creation to complete (300 retries left).
2025-11-25 09:10:00.721476 | controller | changed: [localhost] => (item=instance)
2025-11-25 09:10:00.721485 | controller |
2025-11-25 09:10:00.721493 | controller | PLAY RECAP *********************************************************************
2025-11-25 09:10:00.721502 | controller | localhost                  : ok=10   changed=3    unreachable=0    failed=0    skipped=6    rescued=0    ignored=0
2025-11-25 09:10:00.721531 | controller |
2025-11-25 09:10:00.723127 | controller | Tuesday 25 November 2025  09:10:00 +0000 (0:00:05.460)       0:00:07.483 ******
2025-11-25 09:10:00.769463 | controller | ===============================================================================
2025-11-25 09:10:00.769482 | controller | Wait for instance(s) creation to complete ------------------------------- 5.46s
2025-11-25 09:10:00.769489 | controller | Create molecule instance(s) --------------------------------------------- 0.32s
2025-11-25 09:10:00.769495 | controller | Discover local Podman images -------------------------------------------- 0.31s
2025-11-25 09:10:00.769501 | controller | Check presence of custom Dockerfiles ------------------------------------ 0.29s
2025-11-25 09:10:00.769507 | controller | Get podman executable path ---------------------------------------------- 0.27s
2025-11-25 09:10:00.769512 | controller | Get passwd entries for USER env ----------------------------------------- 0.26s
2025-11-25 09:10:00.769517 | controller | Remove possible pre-existing containers --------------------------------- 0.22s
2025-11-25 09:10:00.769522 | controller | Determine the CMD directives -------------------------------------------- 0.05s
2025-11-25 09:10:00.769527 | controller | Discover local podman networks ------------------------------------------ 0.04s
2025-11-25 09:10:00.769532 | controller | Get shell async_dir ----------------------------------------------------- 0.04s
2025-11-25 09:10:00.769537 | controller | Log into a container registry ------------------------------------------- 0.04s
2025-11-25 09:10:00.769542 | controller | Build an Ansible compatible image --------------------------------------- 0.04s
2025-11-25 09:10:00.769547 | controller | Create Dockerfiles from image names ------------------------------------- 0.04s
2025-11-25 09:10:00.769556 | controller | Register podman executable path ----------------------------------------- 0.04s
2025-11-25 09:10:00.769561 | controller | Create podman network dedicated to this scenario ------------------------ 0.03s
2025-11-25 09:10:00.769566 | controller | Set async_dir for HOME env ---------------------------------------------- 0.03s
2025-11-25 09:10:00.769575 | controller | INFO     Running default > prepare
2025-11-25 09:10:00.770014 | controller | WARNING  Skipping, prepare playbook not configured.
2025-11-25 09:10:00.777258 | controller | INFO     Running default > converge
2025-11-25 09:10:01.163610 | controller |
2025-11-25 09:10:01.163803 | controller | PLAY [Converge] ****************************************************************
2025-11-25 09:10:01.163943 | controller |
2025-11-25 09:10:01.164091 | controller | TASK [Gather stack nodes and facts] ********************************************
2025-11-25 09:10:01.164254 | controller | Tuesday 25 November 2025  09:10:01 +0000 (0:00:00.018)       0:00:00.018 ******
2025-11-25 09:10:01.193268 | 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-11-25 09:10:01.193327 | 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-11-25 09:10:01.193342 | 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-11-25 09:10:01.193710 | controller |
2025-11-25 09:10:01.226752 | controller | TASK [Get main facts for the overcloud stack] **********************************
2025-11-25 09:10:01.226772 | controller | Tuesday 25 November 2025  09:10:01 +0000 (0:00:00.030)       0:00:00.049 ******
2025-11-25 09:10:01.226783 | controller | ok: [instance]
2025-11-25 09:10:01.308050 | controller |
2025-11-25 09:10:01.308069 | controller | TASK [Gather other facts for stack overcloud"] *********************************
2025-11-25 09:10:01.308076 | controller | Tuesday 25 November 2025  09:10:01 +0000 (0:00:00.033)       0:00:00.082 ******
2025-11-25 09:10:01.308087 | controller | ok: [instance]
2025-11-25 09:10:01.369878 | controller |
2025-11-25 09:10:01.369898 | controller | TASK [Gather nodes for stack overcloud] ****************************************
2025-11-25 09:10:01.369905 | controller | Tuesday 25 November 2025  09:10:01 +0000 (0:00:00.081)       0:00:00.164 ******
2025-11-25 09:10:01.369914 | controller | skipping: [instance] => (item=cell1-osp-computes)
2025-11-25 09:10:01.399208 | controller | skipping: [instance] => (item=cell1-osp-controllers)
2025-11-25 09:10:01.399232 | controller | skipping: [instance] => (item=cell2-osp-computes)
2025-11-25 09:10:01.399240 | controller | skipping: [instance] => (item=cell2-osp-controllers)
2025-11-25 09:10:01.399245 | controller | skipping: [instance] => (item=controllers)
2025-11-25 09:10:01.399251 | controller | skipping: [instance] => (item=ocps)
2025-11-25 09:10:01.399257 | controller | ok: [instance] => (item=osp-controllers)
2025-11-25 09:10:01.399262 | controller | skipping: [instance] => (item=osp-underclouds)
2025-11-25 09:10:01.399268 | controller |
2025-11-25 09:10:01.399273 | controller | TASK [Get main facts for the overcloud stack] **********************************
2025-11-25 09:10:01.399279 | controller | Tuesday 25 November 2025  09:10:01 +0000 (0:00:00.060)       0:00:00.224 ******
2025-11-25 09:10:01.399288 | controller | ok: [instance]
2025-11-25 09:10:01.399313 | controller |
2025-11-25 09:10:01.399321 | controller | TASK [Gather other facts for stack cell1"] *************************************
2025-11-25 09:10:01.399438 | controller | Tuesday 25 November 2025  09:10:01 +0000 (0:00:00.030)       0:00:00.255 ******
2025-11-25 09:10:01.463766 | controller | ok: [instance]
2025-11-25 09:10:01.526231 | controller |
2025-11-25 09:10:01.526263 | controller | TASK [Gather nodes for stack cell1] ********************************************
2025-11-25 09:10:01.526271 | controller | Tuesday 25 November 2025  09:10:01 +0000 (0:00:00.064)       0:00:00.319 ******
2025-11-25 09:10:01.526282 | controller | ok: [instance] => (item=cell1-osp-computes)
2025-11-25 09:10:01.526307 | controller | ok: [instance] => (item=cell1-osp-controllers)
2025-11-25 09:10:01.526314 | controller | skipping: [instance] => (item=cell2-osp-computes)
2025-11-25 09:10:01.526319 | controller | skipping: [instance] => (item=cell2-osp-controllers)
2025-11-25 09:10:01.526331 | controller | skipping: [instance] => (item=controllers)
2025-11-25 09:10:01.526338 | controller | skipping: [instance] => (item=ocps)
2025-11-25 09:10:01.526343 | controller | skipping: [instance] => (item=osp-controllers)
2025-11-25 09:10:01.526349 | controller | skipping: [instance] => (item=osp-underclouds)
2025-11-25 09:10:01.526356 | controller |
2025-11-25 09:10:01.526379 | controller | TASK [Get main facts for the overcloud stack] **********************************
2025-11-25 09:10:01.526505 | controller | Tuesday 25 November 2025  09:10:01 +0000 (0:00:00.061)       0:00:00.381 ******
2025-11-25 09:10:01.554709 | controller | ok: [instance]
2025-11-25 09:10:01.618052 | controller |
2025-11-25 09:10:01.618070 | controller | TASK [Gather other facts for stack cell2"] *************************************
2025-11-25 09:10:01.618077 | controller | Tuesday 25 November 2025  09:10:01 +0000 (0:00:00.028)       0:00:00.410 ******
2025-11-25 09:10:01.618085 | controller | ok: [instance]
2025-11-25 09:10:01.680209 | controller |
2025-11-25 09:10:01.680230 | controller | TASK [Gather nodes for stack cell2] ********************************************
2025-11-25 09:10:01.680237 | controller | Tuesday 25 November 2025  09:10:01 +0000 (0:00:00.063)       0:00:00.473 ******
2025-11-25 09:10:01.680245 | controller | skipping: [instance] => (item=cell1-osp-computes)
2025-11-25 09:10:01.681237 | controller | skipping: [instance] => (item=cell1-osp-controllers)
2025-11-25 09:10:01.681265 | controller | ok: [instance] => (item=cell2-osp-computes)
2025-11-25 09:10:01.681277 | controller | ok: [instance] => (item=cell2-osp-controllers)
2025-11-25 09:10:01.681294 | controller | skipping: [instance] => (item=controllers)
2025-11-25 09:10:01.681300 | controller | skipping: [instance] => (item=ocps)
2025-11-25 09:10:01.681305 | controller | skipping: [instance] => (item=osp-controllers)
2025-11-25 09:10:01.681310 | controller | skipping: [instance] => (item=osp-underclouds)
2025-11-25 09:10:01.681316 | controller |
2025-11-25 09:10:01.681321 | controller | TASK [Store result for verification as persistent fact] ************************
2025-11-25 09:10:01.681328 | controller | Tuesday 25 November 2025  09:10:01 +0000 (0:00:00.062)       0:00:00.536 ******
2025-11-25 09:10:01.708240 | controller | ok: [instance]
2025-11-25 09:10:01.708850 | controller |
2025-11-25 09:10:01.708867 | controller | PLAY RECAP *********************************************************************
2025-11-25 09:10:01.708874 | controller | instance                   : ok=13   changed=0    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2025-11-25 09:10:01.708879 | controller |
2025-11-25 09:10:01.708885 | controller | Tuesday 25 November 2025  09:10:01 +0000 (0:00:00.027)       0:00:00.563 ******
2025-11-25 09:10:01.708890 | controller | ===============================================================================
2025-11-25 09:10:01.708895 | controller | Gather other facts for stack overcloud" --------------------------------- 0.08s
2025-11-25 09:10:01.708900 | controller | Gather other facts for stack cell1" ------------------------------------- 0.06s
2025-11-25 09:10:01.708911 | controller | Gather other facts for stack cell2" ------------------------------------- 0.06s
2025-11-25 09:10:01.708916 | controller | Gather nodes for stack cell2 -------------------------------------------- 0.06s
2025-11-25 09:10:01.708923 | controller | Gather nodes for stack cell1 -------------------------------------------- 0.06s
2025-11-25 09:10:01.756960 | controller | Gather nodes for stack overcloud ---------------------------------------- 0.06s
2025-11-25 09:10:01.756980 | controller | Get main facts for the overcloud stack ---------------------------------- 0.03s
2025-11-25 09:10:01.756987 | controller | Gather stack nodes and facts -------------------------------------------- 0.03s
2025-11-25 09:10:01.756992 | controller | Get main facts for the overcloud stack ---------------------------------- 0.03s
2025-11-25 09:10:01.756997 | controller | Get main facts for the overcloud stack ---------------------------------- 0.03s
2025-11-25 09:10:01.757002 | controller | Store result for verification as persistent fact ------------------------ 0.03s
2025-11-25 09:10:01.757010 | controller | INFO     Running default > verify
2025-11-25 09:10:01.757329 | controller | INFO     Running Ansible Verifier
2025-11-25 09:10:02.162127 | controller |
2025-11-25 09:10:02.184219 | controller | PLAY [Verify] ******************************************************************
2025-11-25 09:10:02.184243 | controller |
2025-11-25 09:10:02.184256 | controller | TASK [Set _tripleo_nodes_stack from persistent fact] ***************************
2025-11-25 09:10:02.184262 | controller | Tuesday 25 November 2025  09:10:02 +0000 (0:00:00.019)       0:00:00.019 ******
2025-11-25 09:10:02.184271 | controller | skipping: [instance]
2025-11-25 09:10:02.184297 | controller |
2025-11-25 09:10:02.184715 | controller | TASK [Assert gathered nodes for stacks] ****************************************
2025-11-25 09:10:02.256279 | controller | Tuesday 25 November 2025  09:10:02 +0000 (0:00:00.022)       0:00:00.041 ******
2025-11-25 09:10:02.256315 | controller | ok: [instance] => (item={'stackname': 'overcloud', 'network_data_file': 'network_data.yaml.j2', 'vips_data_file': 'vips_data_overcloud.yaml', 'stack_nodes': ['osp-controllers']}) => changed=false
2025-11-25 09:10:02.256342 | controller |   _stack:
2025-11-25 09:10:02.256349 | controller |     network_data_file: network_data.yaml.j2
2025-11-25 09:10:02.256355 | controller |     stack_nodes:
2025-11-25 09:10:02.256360 | controller |     - osp-controllers
2025-11-25 09:10:02.256366 | controller |     stackname: overcloud
2025-11-25 09:10:02.256371 | controller |     vips_data_file: vips_data_overcloud.yaml
2025-11-25 09:10:02.256377 | controller |   ansible_loop_var: _stack
2025-11-25 09:10:02.256386 | controller |   msg: Successfully verified gathered nodes for stack overcloud
2025-11-25 09:10:02.256392 | controller | ok: [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']}) => changed=false
2025-11-25 09:10:02.256402 | controller |   _stack:
2025-11-25 09:10:02.256407 | controller |     network_data_file: network_data.yaml.j2
2025-11-25 09:10:02.256413 | controller |     stack_nodes:
2025-11-25 09:10:02.256418 | controller |     - cell1-osp-computes
2025-11-25 09:10:02.256424 | controller |     - cell1-osp-controllers
2025-11-25 09:10:02.256429 | controller |     stackname: cell1
2025-11-25 09:10:02.256435 | controller |     vips_data_file: vips_data_cell1.yaml
2025-11-25 09:10:02.256440 | controller |   ansible_loop_var: _stack
2025-11-25 09:10:02.256445 | controller |   msg: Successfully verified gathered nodes for stack cell1
2025-11-25 09:10:02.256451 | controller | ok: [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']}) => changed=false
2025-11-25 09:10:02.256456 | controller |   _stack:
2025-11-25 09:10:02.256462 | controller |     network_data_file: network_data.yaml.j2
2025-11-25 09:10:02.256467 | controller |     stack_nodes:
2025-11-25 09:10:02.256474 | controller |     - cell2-osp-computes
2025-11-25 09:10:02.256479 | controller |     - cell2-osp-controllers
2025-11-25 09:10:02.256484 | controller |     stackname: cell2
2025-11-25 09:10:02.256490 | controller |     vips_data_file: vips_data_cell2.yaml
2025-11-25 09:10:02.256495 | controller |   ansible_loop_var: _stack
2025-11-25 09:10:02.256500 | controller |   msg: Successfully verified gathered nodes for stack cell2
2025-11-25 09:10:02.256507 | controller |
2025-11-25 09:10:02.256530 | controller | PLAY RECAP *********************************************************************
2025-11-25 09:10:02.256602 | controller | instance                   : ok=1    changed=0    unreachable=0    failed=0    skipped=1    rescued=0    ignored=0
2025-11-25 09:10:02.256613 | controller |
2025-11-25 09:10:02.256732 | controller | Tuesday 25 November 2025  09:10:02 +0000 (0:00:00.070)       0:00:00.112 ******
2025-11-25 09:10:02.256849 | controller | ===============================================================================
2025-11-25 09:10:02.256963 | controller | Assert gathered nodes for stacks ---------------------------------------- 0.07s
2025-11-25 09:10:02.257083 | controller | Set _tripleo_nodes_stack from persistent fact --------------------------- 0.02s
2025-11-25 09:10:02.300530 | controller | INFO     Verifier completed successfully.
2025-11-25 09:10:02.308820 | controller | INFO     Running default > destroy
2025-11-25 09:10:02.705615 | controller |
2025-11-25 09:10:02.981024 | controller | PLAY [Destroy] *****************************************************************
2025-11-25 09:10:02.981054 | controller |
2025-11-25 09:10:02.981061 | controller | TASK [Get passwd entries for USER env] *****************************************
2025-11-25 09:10:02.981067 | controller | Tuesday 25 November 2025  09:10:02 +0000 (0:00:00.013)       0:00:00.013 ******
2025-11-25 09:10:02.981077 | controller | ok: [localhost]
2025-11-25 09:10:03.020243 | controller |
2025-11-25 09:10:03.020272 | controller | TASK [Get shell async_dir] *****************************************************
2025-11-25 09:10:03.020282 | controller | Tuesday 25 November 2025  09:10:02 +0000 (0:00:00.275)       0:00:00.288 ******
2025-11-25 09:10:03.020294 | controller | ok: [localhost]
2025-11-25 09:10:03.020514 | controller |
2025-11-25 09:10:03.020536 | controller | TASK [Set async_dir for HOME env] **********************************************
2025-11-25 09:10:03.052123 | controller | Tuesday 25 November 2025  09:10:03 +0000 (0:00:00.039)       0:00:00.328 ******
2025-11-25 09:10:03.052167 | controller | skipping: [localhost]
2025-11-25 09:10:03.425164 | controller |
2025-11-25 09:10:03.425194 | controller | TASK [Destroy molecule instance(s)] ********************************************
2025-11-25 09:10:03.425202 | controller | Tuesday 25 November 2025  09:10:03 +0000 (0:00:00.031)       0:00:00.360 ******
2025-11-25 09:10:03.425211 | controller | changed: [localhost] => (item={'command': '/sbin/init', 'dockerfile': 'containerfile', 'image': 'registry.access.redhat.com/ubi9/ubi-init', 'name': 'instance', 'pre_build_image': True})
2025-11-25 09:10:08.847069 | controller |
2025-11-25 09:10:08.847101 | controller | TASK [Wait for instance(s) deletion to complete] *******************************
2025-11-25 09:10:08.847108 | controller | Tuesday 25 November 2025  09:10:03 +0000 (0:00:00.372)       0:00:00.732 ******
2025-11-25 09:10:08.847118 | controller | FAILED - RETRYING: [localhost]: Wait for instance(s) deletion to complete (300 retries left).
2025-11-25 09:10:08.893236 | controller | changed: [localhost] => (item={'failed': 0, 'started': 1, 'finished': 0, 'ansible_job_id': 'j541154278605.29884', 'results_file': '/home/zuul/.ansible_async/j541154278605.29884', '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-11-25 09:10:08.893271 | controller |
2025-11-25 09:10:08.893279 | controller | TASK [Delete podman network dedicated to this scenario] ************************
2025-11-25 09:10:08.893285 | controller | Tuesday 25 November 2025  09:10:08 +0000 (0:00:05.421)       0:00:06.154 ******
2025-11-25 09:10:08.893296 | controller | skipping: [localhost] => (item=instance: None specified)
2025-11-25 09:10:08.894409 | controller | skipping: [localhost]
2025-11-25 09:10:08.894431 | controller |
2025-11-25 09:10:08.894443 | controller | PLAY RECAP *********************************************************************
2025-11-25 09:10:08.934911 | controller | localhost                  : ok=4    changed=2    unreachable=0    failed=0    skipped=2    rescued=0    ignored=0
2025-11-25 09:10:08.934931 | controller |
2025-11-25 09:10:08.934938 | controller | Tuesday 25 November 2025  09:10:08 +0000 (0:00:00.046)       0:00:06.201 ******
2025-11-25 09:10:08.934943 | controller | ===============================================================================
2025-11-25 09:10:08.934948 | controller | Wait for instance(s) deletion to complete ------------------------------- 5.42s
2025-11-25 09:10:08.934953 | controller | Destroy molecule instance(s) -------------------------------------------- 0.37s
2025-11-25 09:10:08.934958 | controller | Get passwd entries for USER env ----------------------------------------- 0.28s
2025-11-25 09:10:08.934963 | controller | Delete podman network dedicated to this scenario ------------------------ 0.05s
2025-11-25 09:10:08.934968 | controller | Get shell async_dir ----------------------------------------------------- 0.04s
2025-11-25 09:10:08.934973 | controller | Set async_dir for HOME env ---------------------------------------------- 0.03s
2025-11-25 09:10:08.934981 | controller | INFO     Pruning extra files from scenario ephemeral directory
2025-11-25 09:10:08.936587 | controller | INFO     Writing /tmp/report.html report.
2025-11-25 09:10:09.403358 | controller | changed
2025-11-25 09:10:09.420262 | 
2025-11-25 09:10:09.420307 | PLAY RECAP
2025-11-25 09:10:09.420342 | controller | ok: 4 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-11-25 09:10:09.420361 | 
2025-11-25 09:10:09.474802 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-11-25 09:10:09.475532 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-11-25 09:10:09.955921 | 
2025-11-25 09:10:09.956014 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-11-25 09:10:09.974947 | 
2025-11-25 09:10:09.975017 | TASK [Filter out host if needed]
2025-11-25 09:10:09.982659 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-11-25 09:10:09.986617 | 
2025-11-25 09:10:09.986679 | TASK [Ensure file is present]
2025-11-25 09:10:10.267537 | controller | ok
2025-11-25 09:10:10.273776 | 
2025-11-25 09:10:10.273845 | TASK [Manage molecule report file]
2025-11-25 09:10:10.797679 | controller | changed
2025-11-25 09:10:10.802345 | 
2025-11-25 09:10:10.802418 | TASK [Check if we get ci-framework-data basedir]
2025-11-25 09:10:10.973188 | controller | ok
2025-11-25 09:10:10.978038 | 
2025-11-25 09:10:10.978100 | TASK [Create ci-framework-data log directory for zuul]
2025-11-25 09:10:11.251127 | controller | changed
2025-11-25 09:10:11.255794 | 
2025-11-25 09:10:11.255856 | TASK [Copy ci-framework interesting files]
2025-11-25 09:10:11.429982 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2025-11-25 09:10:11.778216 | controller | changed
2025-11-25 09:10:11.783090 | 
2025-11-25 09:10:11.783157 | TASK [Get SELinux listing]
2025-11-25 09:10:12.303341 | controller | changed
2025-11-25 09:10:12.309484 | 
2025-11-25 09:10:12.309558 | TASK [Generate log index]
2025-11-25 09:10:12.939433 | controller | changed
2025-11-25 09:10:12.943941 | 
2025-11-25 09:10:12.944007 | TASK [Get some env related data]
2025-11-25 09:10:13.449525 | controller | /home/zuul/.local/bin/ansible
2025-11-25 09:10:14.472211 | controller | changed
2025-11-25 09:10:14.484784 | 
2025-11-25 09:10:14.484936 | TASK [Generate list of logs to collect in home directory]
2025-11-25 09:10:14.771644 | controller | ok: All paths examined
2025-11-25 09:10:14.777072 | 
2025-11-25 09:10:14.777158 | LOOP [Copy logs from home directory]
2025-11-25 09:10:15.071626 | controller | changed:
2025-11-25 09:10:15.071839 | controller | {
2025-11-25 09:10:15.071867 | controller |   "atime": 1764061751.7752762,
2025-11-25 09:10:15.071886 | controller |   "ctime": 1764061767.0373216,
2025-11-25 09:10:15.071904 | controller |   "dev": 64513,
2025-11-25 09:10:15.071922 | controller |   "gid": 1000,
2025-11-25 09:10:15.071938 | controller |   "gr_name": "zuul",
2025-11-25 09:10:15.071954 | controller |   "inode": 4958531,
2025-11-25 09:10:15.071971 | controller |   "isblk": false,
2025-11-25 09:10:15.071986 | controller |   "ischr": false,
2025-11-25 09:10:15.072001 | controller |   "isdir": false,
2025-11-25 09:10:15.072015 | controller |   "isfifo": false,
2025-11-25 09:10:15.072029 | controller |   "isgid": false,
2025-11-25 09:10:15.072042 | controller |   "islnk": false,
2025-11-25 09:10:15.072056 | controller |   "isreg": true,
2025-11-25 09:10:15.072070 | controller |   "issock": false,
2025-11-25 09:10:15.072083 | controller |   "isuid": false,
2025-11-25 09:10:15.072096 | controller |   "mode": "0644",
2025-11-25 09:10:15.072123 | controller |   "mtime": 1764061767.0373216,
2025-11-25 09:10:15.072140 | controller |   "nlink": 1,
2025-11-25 09:10:15.072154 | controller |   "path": "/home/zuul/ansible.log",
2025-11-25 09:10:15.072175 | controller |   "pw_name": "zuul",
2025-11-25 09:10:15.072190 | controller |   "rgrp": true,
2025-11-25 09:10:15.072204 | controller |   "roth": true,
2025-11-25 09:10:15.072218 | controller |   "rusr": true,
2025-11-25 09:10:15.072232 | controller |   "size": 6749,
2025-11-25 09:10:15.072246 | controller |   "uid": 1000,
2025-11-25 09:10:15.072260 | controller |   "wgrp": false,
2025-11-25 09:10:15.072273 | controller |   "woth": false,
2025-11-25 09:10:15.072289 | controller |   "wusr": true,
2025-11-25 09:10:15.072304 | controller |   "xgrp": false,
2025-11-25 09:10:15.072317 | controller |   "xoth": false,
2025-11-25 09:10:15.072331 | controller |   "xusr": false
2025-11-25 09:10:15.072344 | controller | }
2025-11-25 09:10:15.120945 | 
2025-11-25 09:10:15.121104 | TASK [Copy crio stats log file]
2025-11-25 09:10:15.148506 | controller | skipping: Conditional result was False
2025-11-25 09:10:15.154142 | 
2025-11-25 09:10:15.154211 | TASK [Get SELinux related data]
2025-11-25 09:10:15.350040 | controller | <no matches>
2025-11-25 09:10:15.678870 | controller | ERROR
2025-11-25 09:10:15.679324 | controller | {
2025-11-25 09:10:15.679360 | controller |   "delta": "0:00:00.008216",
2025-11-25 09:10:15.679413 | controller |   "end": "2025-11-25 09:10:15.350668",
2025-11-25 09:10:15.679442 | controller |   "msg": "non-zero return code",
2025-11-25 09:10:15.679459 | controller |   "rc": 1,
2025-11-25 09:10:15.679476 | controller |   "start": "2025-11-25 09:10:15.342452"
2025-11-25 09:10:15.679490 | controller | }
2025-11-25 09:10:15.679515 | controller | ERROR: Ignoring Errors
2025-11-25 09:10:15.686233 | 
2025-11-25 09:10:15.686311 | TASK [Create system configuration directory]
2025-11-25 09:10:15.869272 | controller | changed
2025-11-25 09:10:15.874168 | 
2025-11-25 09:10:15.874235 | TASK [Get some of the system configurations]
2025-11-25 09:10:16.402527 | controller | changed
2025-11-25 09:10:16.407474 | 
2025-11-25 09:10:16.407537 | TASK [Copy generated documentation if available]
2025-11-25 09:10:16.424518 | controller | skipping: Conditional result was False
2025-11-25 09:10:16.431049 | 
2025-11-25 09:10:16.431121 | TASK [Copy generated AsciiDoc documentation if available]
2025-11-25 09:10:16.456848 | controller | skipping: Conditional result was False
2025-11-25 09:10:16.462266 | 
2025-11-25 09:10:16.462328 | TASK [Compress logs bigger than 2MB]
2025-11-25 09:10:16.989674 | controller | changed
2025-11-25 09:10:16.994571 | 
2025-11-25 09:10:16.994631 | TASK [Copy files from workspace on node]
2025-11-25 09:10:17.015314 | controller | ok
2025-11-25 09:10:17.037190 | 
2025-11-25 09:10:17.037255 | TASK [fetch-output : Set log path for multiple nodes]
2025-11-25 09:10:17.053850 | controller | skipping: Conditional result was False
2025-11-25 09:10:17.060415 | 
2025-11-25 09:10:17.060480 | TASK [fetch-output : Set log path for single node]
2025-11-25 09:10:17.091209 | controller | ok
2025-11-25 09:10:17.095863 | 
2025-11-25 09:10:17.095924 | LOOP [fetch-output : Ensure local output dirs]
2025-11-25 09:10:17.281910 | controller -> localhost | ok: "/var/lib/zuul/builds/a00f5baf14c84f2a8f3d4b41490166f7/work/logs"
2025-11-25 09:10:17.282483 | controller -> localhost | changed: All items complete
2025-11-25 09:10:17.282516 | 
2025-11-25 09:10:17.445039 | controller -> localhost | changed: "/var/lib/zuul/builds/a00f5baf14c84f2a8f3d4b41490166f7/work/artifacts"
2025-11-25 09:10:17.606869 | controller -> localhost | changed: "/var/lib/zuul/builds/a00f5baf14c84f2a8f3d4b41490166f7/work/docs"
2025-11-25 09:10:17.620178 | 
2025-11-25 09:10:17.620371 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-11-25 09:10:18.153754 | controller | changed:
2025-11-25 09:10:18.153951 | controller | .d..t...... ./
2025-11-25 09:10:18.153988 | controller | >f+++++++++ README.html
2025-11-25 09:10:18.154185 | controller | >f+++++++++ ansible-execution.log
2025-11-25 09:10:18.154224 | controller | >f+++++++++ ansible.log
2025-11-25 09:10:18.154247 | controller | >f+++++++++ dmesg.log
2025-11-25 09:10:18.154265 | controller | >f+++++++++ installed-pkgs.log
2025-11-25 09:10:18.154284 | controller | >f+++++++++ python.log
2025-11-25 09:10:18.154302 | controller | >f+++++++++ registries.conf
2025-11-25 09:10:18.154318 | controller | >f+++++++++ report.html
2025-11-25 09:10:18.154336 | controller | >f+++++++++ selinux-denials.log
2025-11-25 09:10:18.154352 | controller | >f+++++++++ selinux-listing.log
2025-11-25 09:10:18.154366 | controller | cd+++++++++ ci-framework-data/
2025-11-25 09:10:18.154393 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-11-25 09:10:18.154417 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-11-25 09:10:18.154436 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-11-25 09:10:18.154452 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-11-25 09:10:18.154466 | controller | cd+++++++++ ci-framework-data/logs/
2025-11-25 09:10:18.154480 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2025-11-25 09:10:18.154494 | controller | cd+++++++++ registries.conf.d/
2025-11-25 09:10:18.154507 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-11-25 09:10:18.154520 | controller | cd+++++++++ system-config/
2025-11-25 09:10:18.154533 | controller | cd+++++++++ system-config/libvirt/
2025-11-25 09:10:18.154547 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-11-25 09:10:18.154561 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-11-25 09:10:18.521631 | controller | changed: .d..t...... ./
2025-11-25 09:10:18.880214 | controller | changed: .d..t...... ./
2025-11-25 09:10:18.891870 | 
2025-11-25 09:10:18.891946 | TASK [Return artifact to Zuul]
2025-11-25 09:10:18.925118 | controller | ok
2025-11-25 09:10:18.945024 | 
2025-11-25 09:10:18.945086 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-11-25 09:10:18.945226 | 
2025-11-25 09:10:18.945254 | PLAY RECAP
2025-11-25 09:10:18.945299 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2025-11-25 09:10:18.945319 | 
2025-11-25 09:10:19.058431 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-11-25 09:10:19.059126 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-11-25 09:10:19.590763 | 
2025-11-25 09:10:19.590904 | PLAY [all]
2025-11-25 09:10:19.610265 | 
2025-11-25 09:10:19.610339 | TASK [include_role : fetch-output]
2025-11-25 09:10:19.641980 | controller | ok
2025-11-25 09:10:19.659094 | 
2025-11-25 09:10:19.659185 | TASK [fetch-output : Set log path for multiple nodes]
2025-11-25 09:10:19.705211 | controller | skipping: Conditional result was False
2025-11-25 09:10:19.710965 | 
2025-11-25 09:10:19.711042 | TASK [fetch-output : Set log path for single node]
2025-11-25 09:10:19.741470 | controller | ok
2025-11-25 09:10:19.746021 | 
2025-11-25 09:10:19.746086 | LOOP [fetch-output : Ensure local output dirs]
2025-11-25 09:10:20.072933 | controller -> localhost | ok: "/var/lib/zuul/builds/a00f5baf14c84f2a8f3d4b41490166f7/work/logs"
2025-11-25 09:10:20.253579 | controller -> localhost | ok: "/var/lib/zuul/builds/a00f5baf14c84f2a8f3d4b41490166f7/work/artifacts"
2025-11-25 09:10:20.436294 | controller -> localhost | ok: "/var/lib/zuul/builds/a00f5baf14c84f2a8f3d4b41490166f7/work/docs"
2025-11-25 09:10:20.448904 | 
2025-11-25 09:10:20.449083 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-11-25 09:10:20.959747 | controller | ok
2025-11-25 09:10:20.959999 | controller | ok: All items complete
2025-11-25 09:10:20.960028 | 
2025-11-25 09:10:21.317966 | controller | ok
2025-11-25 09:10:21.681016 | controller | ok
2025-11-25 09:10:21.695232 | 
2025-11-25 09:10:21.695439 | TASK [include_role : fetch-output-openshift]
2025-11-25 09:10:21.711460 | controller | skipping: Conditional result was False
2025-11-25 09:10:21.717775 | 
2025-11-25 09:10:21.717842 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-11-25 09:10:22.042757 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.005809
2025-11-25 09:10:22.231878 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.007829
2025-11-25 09:10:22.265136 | 
2025-11-25 09:10:22.265250 | PLAY [all]
2025-11-25 09:10:22.279146 | 
2025-11-25 09:10:22.279240 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-11-25 09:10:22.656503 | controller | changed
2025-11-25 09:10:22.676427 | 
2025-11-25 09:10:22.676480 | PLAY RECAP
2025-11-25 09:10:22.676523 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-11-25 09:10:22.676543 | 
2025-11-25 09:10:22.747940 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-11-25 09:10:22.748954 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-11-25 09:10:23.272784 | 
2025-11-25 09:10:23.272944 | PLAY [localhost]
2025-11-25 09:10:23.289356 | 
2025-11-25 09:10:23.289449 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-11-25 09:10:23.622336 | localhost | changed
2025-11-25 09:10:23.626992 | 
2025-11-25 09:10:23.627070 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-11-25 09:10:23.656002 | localhost | ok
2025-11-25 09:10:23.663155 | 
2025-11-25 09:10:23.663221 | TASK [add-fileserver : Create SSH private key tempfile]
2025-11-25 09:10:23.955973 | localhost | changed
2025-11-25 09:10:23.962293 | 
2025-11-25 09:10:23.962364 | TASK [add-fileserver : Create SSH private key from secret]
2025-11-25 09:10:24.486906 | localhost | changed
2025-11-25 09:10:24.491961 | 
2025-11-25 09:10:24.492032 | TASK [add-fileserver : Add fileserver ssh key]
2025-11-25 09:10:24.813536 | localhost | Identity added: /var/lib/zuul/builds/a00f5baf14c84f2a8f3d4b41490166f7/work/tmp/ansible.btnki2gk (/var/lib/zuul/builds/a00f5baf14c84f2a8f3d4b41490166f7/work/tmp/ansible.btnki2gk)
2025-11-25 09:10:24.813728 | localhost | ok: Runtime: 0:00:00.008201
2025-11-25 09:10:24.817988 | 
2025-11-25 09:10:24.818058 | TASK [add-fileserver : Remove SSH private key from disk]
2025-11-25 09:10:25.028446 | localhost | ok: Runtime: 0:00:00.004169
2025-11-25 09:10:25.033089 | 
2025-11-25 09:10:25.033167 | TASK [add-fileserver : Add fileserver to inventory]
2025-11-25 09:10:25.081247 | localhost | changed
2025-11-25 09:10:25.086076 | 
2025-11-25 09:10:25.086151 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-11-25 09:10:25.396798 | localhost | changed
2025-11-25 09:10:25.414511 | 
2025-11-25 09:10:25.414565 | PLAY [localhost]
2025-11-25 09:10:25.424797 | 
2025-11-25 09:10:25.424857 | TASK [Generate bulk log download script]
2025-11-25 09:10:25.443631 | localhost | ok
2025-11-25 09:10:25.454654 | 
2025-11-25 09:10:25.454718 | TASK [local-log-download : Check API endpoint is defined]
2025-11-25 09:10:25.483774 | localhost | ok: All assertions passed
2025-11-25 09:10:25.487998 | 
2025-11-25 09:10:25.488061 | TASK [local-log-download : Create download script]
2025-11-25 09:10:25.856846 | localhost -> localhost | changed
2025-11-25 09:10:25.865958 | 
2025-11-25 09:10:25.866025 | TASK [Register quick-download link]
2025-11-25 09:10:25.894745 | localhost | ok
2025-11-25 09:10:25.932482 | 
2025-11-25 09:10:25.932558 | PLAY [logserver.rdoproject.org]
2025-11-25 09:10:25.942129 | 
2025-11-25 09:10:25.942191 | TASK [Set zuul-log-path fact]
2025-11-25 09:10:25.958913 | logserver.rdoproject.org | ok
2025-11-25 09:10:25.968649 | 
2025-11-25 09:10:25.968746 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-11-25 09:10:25.995246 | logserver.rdoproject.org | ok
2025-11-25 09:10:26.000983 | 
2025-11-25 09:10:26.001072 | TASK [upload-logs : Create log directories]
2025-11-25 09:10:27.692006 | logserver.rdoproject.org | changed
2025-11-25 09:10:27.695075 | 
2025-11-25 09:10:27.695147 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-11-25 09:10:27.911934 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.004775
2025-11-25 09:10:27.916653 | 
2025-11-25 09:10:27.916715 | TASK [upload-logs : Upload logs to log server]
2025-11-25 09:10:29.292703 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-11-25 09:10:29.295533 | 
2025-11-25 09:10:29.295596 | LOOP [upload-logs : Compress console log and json output]
2025-11-25 09:10:29.345662 | logserver.rdoproject.org | skipping: Conditional result was False
2025-11-25 09:10:29.357045 | logserver.rdoproject.org | skipping: Conditional result was False
2025-11-25 09:10:29.364371 | 
2025-11-25 09:10:29.364516 | LOOP [upload-logs : Upload compressed console log and json output]
2025-11-25 09:10:29.407676 | logserver.rdoproject.org | skipping: Conditional result was False
2025-11-25 09:10:29.407985 | 
2025-11-25 09:10:29.413422 | logserver.rdoproject.org | skipping: Conditional result was False
2025-11-25 09:10:29.422704 | 
2025-11-25 09:10:29.422782 | LOOP [upload-logs : Upload console log and json output]