2025-11-24 10:17:56.113982 | Job console starting...
2025-11-24 10:17:56.388886 | Updating repositories
2025-11-24 10:17:57.109613 | Preparing job workspace
2025-11-24 10:18:05.062989 | Running Ansible setup...
2025-11-24 10:18:07.901625 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-11-24 10:18:08.476370 | 
2025-11-24 10:18:08.476488 | PLAY [localhost]
2025-11-24 10:18:08.485202 | 
2025-11-24 10:18:08.485274 | TASK [Gathering Facts]
2025-11-24 10:18:09.300903 | localhost | ok
2025-11-24 10:18:09.314670 | 
2025-11-24 10:18:09.314757 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-11-24 10:18:09.652622 | localhost -> localhost | changed
2025-11-24 10:18:09.658872 | 
2025-11-24 10:18:09.658972 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-11-24 10:18:10.342168 | localhost -> localhost | changed
2025-11-24 10:18:10.350142 | 
2025-11-24 10:18:10.350209 | TASK [Setup log path fact]
2025-11-24 10:18:10.367432 | localhost | ok
2025-11-24 10:18:10.378454 | 
2025-11-24 10:18:10.378522 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-11-24 10:18:10.395542 | localhost | ok
2025-11-24 10:18:10.402860 | 
2025-11-24 10:18:10.402945 | TASK [emit-job-header : Print job information]
2025-11-24 10:18:10.430010 | # Job Information
2025-11-24 10:18:10.430136 | Ansible Version: 2.15.12
2025-11-24 10:18:10.430166 | Job: cifmw-molecule-adoption_osp_deploy
2025-11-24 10:18:10.430188 | Pipeline: github-check
2025-11-24 10:18:10.430208 | Executor: ibm-bm4-ze.softwarefactory-project.io
2025-11-24 10:18:10.430226 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3397
2025-11-24 10:18:10.430245 | Log URL (when completed): https://logserver.rdoproject.org/769/rdoproject.org/7695e6fc106b4ea6b561eebe668c0bcc/
2025-11-24 10:18:10.430265 | Event ID: b4d141e0-c91e-11f0-9bec-c8277c7ee586
2025-11-24 10:18:10.433870 | 
2025-11-24 10:18:10.433949 | LOOP [emit-job-header : Print node information]
2025-11-24 10:18:10.519073 | localhost | ok:
2025-11-24 10:18:10.519251 | localhost | # Node Information
2025-11-24 10:18:10.519282 | localhost | Inventory Hostname: controller
2025-11-24 10:18:10.519308 | localhost | Hostname: np0005533312
2025-11-24 10:18:10.519330 | localhost | Username: zuul
2025-11-24 10:18:10.519352 | localhost | Distro: CentOS 9
2025-11-24 10:18:10.519371 | localhost | Provider: ibm-bm4-nodepool
2025-11-24 10:18:10.519391 | localhost | Region: regionOne
2025-11-24 10:18:10.519409 | localhost | Label: cloud-centos-9-stream-tripleo-ibm
2025-11-24 10:18:10.519427 | localhost | Product Name: OpenStack Compute
2025-11-24 10:18:10.519444 | localhost | Interface IP: 192.168.26.194
2025-11-24 10:18:10.547394 | 
2025-11-24 10:18:10.547478 | PLAY [all]
2025-11-24 10:18:10.553685 | 
2025-11-24 10:18:10.553747 | TASK [Gather network facts]
2025-11-24 10:18:10.887001 | controller | ok
2025-11-24 10:18:10.900754 | 
2025-11-24 10:18:10.900852 | TASK [include_role : start-zuul-console]
2025-11-24 10:18:10.918878 | controller | ok
2025-11-24 10:18:10.931061 | 
2025-11-24 10:18:10.931130 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-11-24 10:18:11.249398 | controller | ok
2025-11-24 10:18:11.257820 | 
2025-11-24 10:18:11.257886 | TASK [include_role : add-build-sshkey]
2025-11-24 10:18:11.276505 | controller | ok
2025-11-24 10:18:11.289386 | 
2025-11-24 10:18:11.289451 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-11-24 10:18:11.475104 | controller -> localhost | ok
2025-11-24 10:18:11.480539 | 
2025-11-24 10:18:11.480605 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-11-24 10:18:11.499661 | controller | ok
2025-11-24 10:18:11.521312 | controller | included: /var/lib/zuul/builds/7695e6fc106b4ea6b561eebe668c0bcc/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-11-24 10:18:11.527396 | 
2025-11-24 10:18:11.527462 | TASK [add-build-sshkey : Create Temp SSH key]
2025-11-24 10:18:12.110530 | controller -> localhost | Generating public/private rsa key pair.
2025-11-24 10:18:12.110736 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/7695e6fc106b4ea6b561eebe668c0bcc/work/7695e6fc106b4ea6b561eebe668c0bcc_id_rsa.
2025-11-24 10:18:12.110771 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/7695e6fc106b4ea6b561eebe668c0bcc/work/7695e6fc106b4ea6b561eebe668c0bcc_id_rsa.pub.
2025-11-24 10:18:12.110806 | controller -> localhost | The key fingerprint is:
2025-11-24 10:18:12.110832 | controller -> localhost | SHA256:xuTh6oCKPtFYm/NPGsjQOQ5gRdm0X+ApB0lraNh8vMs zuul-build-sshkey
2025-11-24 10:18:12.110855 | controller -> localhost | The key's randomart image is:
2025-11-24 10:18:12.110874 | controller -> localhost | +---[RSA 3072]----+
2025-11-24 10:18:12.110893 | controller -> localhost | |  .o=+..         |
2025-11-24 10:18:12.110933 | controller -> localhost | |  =.oo= o        |
2025-11-24 10:18:12.110957 | controller -> localhost | |.o = B +o.       |
2025-11-24 10:18:12.110978 | controller -> localhost | |o o.+ ==..       |
2025-11-24 10:18:12.110998 | controller -> localhost | | o++o. .S        |
2025-11-24 10:18:12.111016 | controller -> localhost | | o=*+ .o         |
2025-11-24 10:18:12.111033 | controller -> localhost | |  o++E..         |
2025-11-24 10:18:12.111053 | controller -> localhost | |.o   ++          |
2025-11-24 10:18:12.111071 | controller -> localhost | |+..  .o.         |
2025-11-24 10:18:12.111090 | controller -> localhost | +----[SHA256]-----+
2025-11-24 10:18:12.111132 | controller -> localhost | ok: Runtime: 0:00:00.254585
2025-11-24 10:18:12.116835 | 
2025-11-24 10:18:12.116900 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-11-24 10:18:12.146147 | controller | ok
2025-11-24 10:18:12.156400 | controller | included: /var/lib/zuul/builds/7695e6fc106b4ea6b561eebe668c0bcc/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-11-24 10:18:12.164049 | 
2025-11-24 10:18:12.164114 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-11-24 10:18:12.178328 | controller | skipping: Conditional result was False
2025-11-24 10:18:12.184207 | 
2025-11-24 10:18:12.184274 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-11-24 10:18:12.547832 | controller | changed
2025-11-24 10:18:12.552508 | 
2025-11-24 10:18:12.552575 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-11-24 10:18:12.733238 | controller | ok
2025-11-24 10:18:12.738059 | 
2025-11-24 10:18:12.738138 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-11-24 10:18:13.253247 | controller | changed
2025-11-24 10:18:13.258701 | 
2025-11-24 10:18:13.258947 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-11-24 10:18:13.775742 | controller | changed
2025-11-24 10:18:13.780602 | 
2025-11-24 10:18:13.780674 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-11-24 10:18:13.795456 | controller | skipping: Conditional result was False
2025-11-24 10:18:13.801492 | 
2025-11-24 10:18:13.801563 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-11-24 10:18:14.101264 | controller -> localhost | changed
2025-11-24 10:18:14.110376 | 
2025-11-24 10:18:14.110444 | TASK [add-build-sshkey : Add back temp key]
2025-11-24 10:18:14.347561 | controller -> localhost | Identity added: /var/lib/zuul/builds/7695e6fc106b4ea6b561eebe668c0bcc/work/7695e6fc106b4ea6b561eebe668c0bcc_id_rsa (zuul-build-sshkey)
2025-11-24 10:18:14.347734 | controller -> localhost | ok: Runtime: 0:00:00.007219
2025-11-24 10:18:14.353181 | 
2025-11-24 10:18:14.353244 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-11-24 10:18:14.634521 | controller | ok
2025-11-24 10:18:14.638895 | 
2025-11-24 10:18:14.638974 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-11-24 10:18:14.663281 | controller | skipping: Conditional result was False
2025-11-24 10:18:14.671867 | 
2025-11-24 10:18:14.671951 | TASK [include_role : validate-host]
2025-11-24 10:18:14.689899 | controller | ok
2025-11-24 10:18:14.709344 | 
2025-11-24 10:18:14.709413 | TASK [validate-host : Define zuul_info_dir fact]
2025-11-24 10:18:14.737478 | controller | ok
2025-11-24 10:18:14.741988 | 
2025-11-24 10:18:14.742052 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-11-24 10:18:14.947894 | controller -> localhost | ok
2025-11-24 10:18:14.953663 | 
2025-11-24 10:18:14.953733 | TASK [validate-host : Collect information about the host]
2025-11-24 10:18:15.519492 | controller | ok
2025-11-24 10:18:15.527425 | 
2025-11-24 10:18:15.527494 | TASK [validate-host : Sanitize hostname]
2025-11-24 10:18:15.597531 | controller | ok
2025-11-24 10:18:15.602253 | 
2025-11-24 10:18:15.602326 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-11-24 10:18:16.000377 | controller -> localhost | changed
2025-11-24 10:18:16.005363 | 
2025-11-24 10:18:16.005428 | TASK [validate-host : Collect information about zuul worker]
2025-11-24 10:18:16.307841 | controller | ok
2025-11-24 10:18:16.312666 | 
2025-11-24 10:18:16.312737 | TASK [validate-host : Write out all zuul information for each host]
2025-11-24 10:18:16.671078 | controller -> localhost | changed
2025-11-24 10:18:16.680371 | 
2025-11-24 10:18:16.680437 | TASK [include_role : prepare-workspace-openshift]
2025-11-24 10:18:16.694613 | controller | skipping: Conditional result was False
2025-11-24 10:18:16.699995 | 
2025-11-24 10:18:16.700060 | TASK [include_role : remove-zuul-sshkey]
2025-11-24 10:18:16.713419 | controller | skipping: Conditional result was False
2025-11-24 10:18:16.718564 | 
2025-11-24 10:18:16.718628 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-11-24 10:18:16.898866 | controller | ok: "logs"
2025-11-24 10:18:16.899088 | controller | ok: All items complete
2025-11-24 10:18:16.899119 | 
2025-11-24 10:18:17.056235 | controller | ok: "artifacts"
2025-11-24 10:18:17.213925 | controller | ok: "docs"
2025-11-24 10:18:17.220945 | 
2025-11-24 10:18:17.221017 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-11-24 10:18:17.406460 | controller | changed: "logs"
2025-11-24 10:18:17.565234 | controller | changed: "artifacts"
2025-11-24 10:18:17.723767 | controller | changed: "docs"
2025-11-24 10:18:17.753837 | 
2025-11-24 10:18:17.753895 | PLAY RECAP
2025-11-24 10:18:17.754004 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-11-24 10:18:17.754037 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-11-24 10:18:17.754059 | 
2025-11-24 10:18:17.838019 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-11-24 10:18:17.838900 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-11-24 10:18:18.409616 | 
2025-11-24 10:18:18.409732 | PLAY [all]
2025-11-24 10:18:18.430322 | 
2025-11-24 10:18:18.430459 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-11-24 10:18:18.479832 | controller | ok
2025-11-24 10:18:18.484717 | 
2025-11-24 10:18:18.484793 | TASK [mirror-info-fork : Create /etc/ci]
2025-11-24 10:18:18.808819 | controller | changed
2025-11-24 10:18:18.814327 | 
2025-11-24 10:18:18.814399 | TASK [mirror-info-fork : Install ci_mirror script]
2025-11-24 10:18:19.565604 | controller | changed
2025-11-24 10:18:19.580184 | 
2025-11-24 10:18:19.580259 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-11-24 10:18:19.937205 | controller | changed:
2025-11-24 10:18:19.937497 | controller | {
2025-11-24 10:18:19.937539 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-11-24 10:18:19.937589 | controller | }
2025-11-24 10:18:20.129368 | controller | changed:
2025-11-24 10:18:20.129513 | controller | {
2025-11-24 10:18:20.129543 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-11-24 10:18:20.129566 | controller | }
2025-11-24 10:18:20.329313 | controller | changed:
2025-11-24 10:18:20.329429 | controller | {
2025-11-24 10:18:20.329457 | 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-24 10:18:20.329481 | controller | }
2025-11-24 10:18:20.531335 | controller | changed:
2025-11-24 10:18:20.531434 | controller | {
2025-11-24 10:18:20.531461 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-11-24 10:18:20.531482 | controller | }
2025-11-24 10:18:20.729741 | controller | changed:
2025-11-24 10:18:20.731774 | controller | {
2025-11-24 10:18:20.731831 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-11-24 10:18:20.731857 | controller | }
2025-11-24 10:18:20.930998 | controller | changed:
2025-11-24 10:18:20.931120 | controller | {
2025-11-24 10:18:20.931147 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-11-24 10:18:20.931168 | controller | }
2025-11-24 10:18:21.124481 | controller | changed:
2025-11-24 10:18:21.124580 | controller | {
2025-11-24 10:18:21.124607 | 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-24 10:18:21.124628 | controller | }
2025-11-24 10:18:21.320496 | controller | changed:
2025-11-24 10:18:21.320560 | controller | {
2025-11-24 10:18:21.320586 | 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-24 10:18:21.320607 | controller | }
2025-11-24 10:18:21.515756 | controller | changed:
2025-11-24 10:18:21.515838 | controller | {
2025-11-24 10:18:21.515864 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-11-24 10:18:21.515884 | controller | }
2025-11-24 10:18:21.711617 | controller | changed:
2025-11-24 10:18:21.711709 | controller | {
2025-11-24 10:18:21.711735 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-11-24 10:18:21.711755 | controller | }
2025-11-24 10:18:21.907980 | controller | changed:
2025-11-24 10:18:21.908055 | controller | {
2025-11-24 10:18:21.908088 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-11-24 10:18:21.908114 | controller | }
2025-11-24 10:18:22.128849 | controller | changed:
2025-11-24 10:18:22.128974 | controller | {
2025-11-24 10:18:22.129002 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-11-24 10:18:22.129023 | controller | }
2025-11-24 10:18:22.306118 | controller | changed:
2025-11-24 10:18:22.306237 | controller | {
2025-11-24 10:18:22.306263 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-11-24 10:18:22.306282 | controller | }
2025-11-24 10:18:22.506425 | controller | changed:
2025-11-24 10:18:22.506537 | controller | {
2025-11-24 10:18:22.506563 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-11-24 10:18:22.506582 | controller | }
2025-11-24 10:18:22.706433 | controller | changed:
2025-11-24 10:18:22.706650 | controller | {
2025-11-24 10:18:22.706687 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-11-24 10:18:22.706710 | controller | }
2025-11-24 10:18:22.907308 | controller | changed:
2025-11-24 10:18:22.907408 | controller | {
2025-11-24 10:18:22.907434 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-11-24 10:18:22.907455 | controller | }
2025-11-24 10:18:23.106764 | controller | changed:
2025-11-24 10:18:23.106892 | controller | {
2025-11-24 10:18:23.106947 | 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-24 10:18:23.106980 | controller | }
2025-11-24 10:18:23.301136 | controller | changed:
2025-11-24 10:18:23.301360 | controller | {
2025-11-24 10:18:23.301396 | 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-24 10:18:23.301427 | controller | }
2025-11-24 10:18:23.498426 | controller | changed:
2025-11-24 10:18:23.498510 | controller | {
2025-11-24 10:18:23.498536 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-11-24 10:18:23.498554 | controller | }
2025-11-24 10:18:23.696633 | controller | changed:
2025-11-24 10:18:23.696768 | controller | {
2025-11-24 10:18:23.696813 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-11-24 10:18:23.696845 | controller | }
2025-11-24 10:18:23.898075 | controller | changed:
2025-11-24 10:18:23.898201 | controller | {
2025-11-24 10:18:23.898227 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-11-24 10:18:23.898247 | controller | }
2025-11-24 10:18:24.099761 | controller | changed:
2025-11-24 10:18:24.099883 | controller | {
2025-11-24 10:18:24.099927 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-11-24 10:18:24.099953 | controller | }
2025-11-24 10:18:24.301672 | controller | changed:
2025-11-24 10:18:24.301783 | controller | {
2025-11-24 10:18:24.301817 | 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-24 10:18:24.301839 | controller | }
2025-11-24 10:18:24.502007 | controller | changed:
2025-11-24 10:18:24.502103 | controller | {
2025-11-24 10:18:24.502129 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-11-24 10:18:24.502148 | controller | }
2025-11-24 10:18:24.706052 | controller | changed:
2025-11-24 10:18:24.706150 | controller | {
2025-11-24 10:18:24.706176 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-11-24 10:18:24.706195 | controller | }
2025-11-24 10:18:24.904416 | controller | changed:
2025-11-24 10:18:24.904518 | controller | {
2025-11-24 10:18:24.904543 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-11-24 10:18:24.904561 | controller | }
2025-11-24 10:18:24.924750 | 
2025-11-24 10:18:24.924869 | TASK [Set timezone to UTC]
2025-11-24 10:18:25.301045 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-11-24 10:18:25.305768 | 
2025-11-24 10:18:25.305841 | TASK [Create nodepool directory]
2025-11-24 10:18:25.496736 | controller | changed
2025-11-24 10:18:25.502134 | 
2025-11-24 10:18:25.502201 | TASK [Create nodepool sub_nodes file]
2025-11-24 10:18:25.976974 | controller | changed
2025-11-24 10:18:25.981771 | 
2025-11-24 10:18:25.981852 | TASK [Create nodepool sub_nodes_private file]
2025-11-24 10:18:26.477174 | controller | changed
2025-11-24 10:18:26.482626 | 
2025-11-24 10:18:26.482689 | LOOP [Populate nodepool sub_nodes file]
2025-11-24 10:18:26.513163 | 
2025-11-24 10:18:26.513307 | LOOP [Populate nodepool sub_nodes_private file]
2025-11-24 10:18:26.542418 | 
2025-11-24 10:18:26.542551 | TASK [Create nodepool primary file]
2025-11-24 10:18:26.566189 | controller | skipping: Conditional result was False
2025-11-24 10:18:26.571525 | 
2025-11-24 10:18:26.571599 | TASK [Create nodepool node_private for this node]
2025-11-24 10:18:27.086500 | controller | changed
2025-11-24 10:18:27.092070 | 
2025-11-24 10:18:27.092134 | LOOP [Copy ssh keys to nodepool directory]
2025-11-24 10:18:27.410112 | controller | ok: Item: id_rsa Runtime: 0:00:00.004308
2025-11-24 10:18:27.410334 | 
2025-11-24 10:18:27.576947 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.004116
2025-11-24 10:18:27.588808 | 
2025-11-24 10:18:27.588952 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-11-24 10:18:28.106075 | controller | changed
2025-11-24 10:18:28.110962 | 
2025-11-24 10:18:28.111030 | TASK [Validate sudoers config after edits]
2025-11-24 10:18:28.326965 | controller | /etc/sudoers: parsed OK
2025-11-24 10:18:28.327055 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-11-24 10:18:28.327066 | controller | /etc/sudoers.d/zuul: parsed OK
2025-11-24 10:18:28.327073 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-11-24 10:18:28.639132 | controller | ok: Runtime: 0:00:00.004776
2025-11-24 10:18:28.644617 | 
2025-11-24 10:18:28.644680 | TASK [Show the environment passed in to job shell scripts]
2025-11-24 10:18:28.847656 | controller | SHELL=/bin/bash
2025-11-24 10:18:28.847693 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-11-24 10:18:28.847703 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-11-24 10:18:28.847709 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/97/3397/c2e0832cfa81b09adfd9655abe13a10d1fb96d1e
2025-11-24 10:18:28.847714 | controller | PWD=/home/zuul
2025-11-24 10:18:28.847720 | controller | ZUUL_PIPELINE=github-check
2025-11-24 10:18:28.847725 | controller | LOGNAME=zuul
2025-11-24 10:18:28.847730 | controller | XDG_SESSION_TYPE=tty
2025-11-24 10:18:28.847736 | controller | _=/usr/bin/env
2025-11-24 10:18:28.847769 | controller | MOTD_SHOWN=pam
2025-11-24 10:18:28.847777 | controller | HOME=/home/zuul
2025-11-24 10:18:28.847782 | controller | LANG=en_US.UTF-8
2025-11-24 10:18:28.847788 | controller | SSH_CONNECTION=192.168.26.12 49160 192.168.26.194 22
2025-11-24 10:18:28.847793 | 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-24 10:18:28.847801 | controller | ZUUL_CHANGE_IDS=3397,c2e0832cfa81b09adfd9655abe13a10d1fb96d1e
2025-11-24 10:18:28.847806 | controller | WORKSPACE=/home/zuul/workspace
2025-11-24 10:18:28.847812 | controller | XDG_SESSION_CLASS=user
2025-11-24 10:18:28.847817 | controller | SELINUX_ROLE_REQUESTED=
2025-11-24 10:18:28.847822 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-11-24 10:18:28.847827 | controller | USER=zuul
2025-11-24 10:18:28.847832 | controller | ZUUL_VOTING=True
2025-11-24 10:18:28.847838 | controller | BUILD_TIMEOUT=1800000
2025-11-24 10:18:28.847843 | controller | SELINUX_USE_CURRENT_RANGE=
2025-11-24 10:18:28.847848 | controller | SHLVL=1
2025-11-24 10:18:28.847853 | controller | ZUUL_PATCHSET=c2e0832cfa81b09adfd9655abe13a10d1fb96d1e
2025-11-24 10:18:28.847858 | controller | XDG_SESSION_ID=1
2025-11-24 10:18:28.847863 | controller | ZUUL_BRANCH=main
2025-11-24 10:18:28.847869 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-11-24 10:18:28.847874 | controller | SSH_CLIENT=192.168.26.12 49160 22
2025-11-24 10:18:28.847879 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-11-24 10:18:28.847884 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-11-24 10:18:28.847889 | controller | which_declare=declare -f
2025-11-24 10:18:28.847895 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2025-11-24 10:18:28.847900 | controller | SELINUX_LEVEL_REQUESTED=
2025-11-24 10:18:28.847905 | controller | ZUUL_CHANGE=3397
2025-11-24 10:18:28.847910 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-11-24 10:18:28.847915 | controller | ZUUL_UUID=7695e6fc106b4ea6b561eebe668c0bcc
2025-11-24 10:18:28.847920 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-11-24 10:18:28.847926 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-11-24 10:18:28.847931 | controller | }
2025-11-24 10:18:29.167972 | controller | ok: Runtime: 0:00:00.005060
2025-11-24 10:18:29.173167 | 
2025-11-24 10:18:29.173236 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-11-24 10:18:29.187606 | controller | skipping: Conditional result was False
2025-11-24 10:18:29.193451 | 
2025-11-24 10:18:29.193515 | TASK [Symlink /home/zuul-worker/workspace]
2025-11-24 10:18:29.716829 | controller | skipping: Conditional result was False
2025-11-24 10:18:29.723511 | 
2025-11-24 10:18:29.723577 | TASK [Ensure legacy workspace directory]
2025-11-24 10:18:29.907733 | controller | changed
2025-11-24 10:18:29.925508 | 
2025-11-24 10:18:29.925562 | PLAY RECAP
2025-11-24 10:18:29.925607 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-11-24 10:18:29.925630 | 
2025-11-24 10:18:30.003672 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-11-24 10:18:30.004535 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-11-24 10:18:30.508659 | 
2025-11-24 10:18:30.508828 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-11-24 10:18:30.533621 | 
2025-11-24 10:18:30.534212 | TASK [Create zuul-output directory]
2025-11-24 10:18:30.848305 | controller | changed
2025-11-24 10:18:30.853362 | 
2025-11-24 10:18:30.853441 | TASK [Slurp Zuul inventory test]
2025-11-24 10:18:31.120448 | controller -> localhost | ok
2025-11-24 10:18:31.126757 | 
2025-11-24 10:18:31.126840 | TASK [Save zuul inventory]
2025-11-24 10:18:31.838674 | controller | changed
2025-11-24 10:18:31.843482 | 
2025-11-24 10:18:31.843549 | TASK [Save zuul vars without the change_message]
2025-11-24 10:18:32.388238 | controller | changed
2025-11-24 10:18:32.405718 | 
2025-11-24 10:18:32.405787 | PLAY RECAP
2025-11-24 10:18:32.405850 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-11-24 10:18:32.405875 | 
2025-11-24 10:18:32.477562 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-11-24 10:18:32.478339 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-11-24 10:18:33.033950 | 
2025-11-24 10:18:33.034062 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2025-11-24 10:18:33.055259 | 
2025-11-24 10:18:33.055338 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2025-11-24 10:18:33.074222 | controller | ok
2025-11-24 10:18:33.091878 | 
2025-11-24 10:18:33.091991 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-11-24 10:18:33.125997 | controller | skipping: Conditional result was False
2025-11-24 10:18:33.131749 | 
2025-11-24 10:18:33.131838 | TASK [mirror-info-fork : Create /etc/ci]
2025-11-24 10:18:33.443852 | controller | ok
2025-11-24 10:18:33.449291 | 
2025-11-24 10:18:33.449363 | TASK [mirror-info-fork : Install ci_mirror script]
2025-11-24 10:18:34.024475 | controller | ok
2025-11-24 10:18:34.034812 | 
2025-11-24 10:18:34.034879 | TASK [Prepare workspace]
2025-11-24 10:18:34.053427 | controller | ok
2025-11-24 10:18:34.070337 | 
2025-11-24 10:18:34.070428 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-11-24 10:18:34.382580 | controller | ok
2025-11-24 10:18:34.388227 | 
2025-11-24 10:18:34.388294 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-11-24 10:18:36.798125 | controller | Output suppressed because no_log was given
2025-11-24 10:18:36.807217 | 
2025-11-24 10:18:36.807285 | LOOP [Create zuul-output directory]
2025-11-24 10:18:36.984173 | controller | changed: "/home/zuul/ci-framework-data/logs"
2025-11-24 10:18:37.125428 | controller | ok: "/home/zuul/zuul-output/logs"
2025-11-24 10:18:37.141679 | 
2025-11-24 10:18:37.141812 | TASK [Install required packages]
2025-11-24 10:19:25.641264 | controller | changed
2025-11-24 10:19:25.646337 | 
2025-11-24 10:19:25.646420 | TASK [Install venv]
2025-11-24 10:20:25.082566 | controller | changed
2025-11-24 10:20:25.111080 | 
2025-11-24 10:20:25.111154 | PLAY RECAP
2025-11-24 10:20:25.111208 | controller | ok: 7 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-11-24 10:20:25.111238 | 
2025-11-24 10:20:25.178183 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-11-24 10:20:25.179015 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-11-24 10:20:25.681993 | 
2025-11-24 10:20:25.682095 | PLAY [Run ci/playbooks/molecule-test.yml]
2025-11-24 10:20:25.702346 | 
2025-11-24 10:20:25.702424 | TASK [Gather required facts]
2025-11-24 10:20:26.146535 | controller | ok
2025-11-24 10:20:26.151979 | 
2025-11-24 10:20:26.152055 | TASK [Load environment var if instructed to]
2025-11-24 10:20:26.175547 | controller | skipping: Conditional result was False
2025-11-24 10:20:26.180844 | 
2025-11-24 10:20:26.180927 | TASK [Ensure group_vars dir exists]
2025-11-24 10:20:26.472688 | controller | ok
2025-11-24 10:20:26.479684 | 
2025-11-24 10:20:26.479809 | TASK [Print related variables]
2025-11-24 10:20:26.507643 | controller | ok:
2025-11-24 10:20:26.507751 | controller | mol_config_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config_local.yml
2025-11-24 10:20:26.507789 | controller | roles_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/adoption_osp_deploy
2025-11-24 10:20:26.512477 | 
2025-11-24 10:20:26.512540 | TASK [Run molecule]
2025-11-24 10:20:27.408593 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2025-11-24 10:20:27.473215 | controller | WARNING  Driver podman does not provide a schema.
2025-11-24 10:20:27.475673 | controller | INFO     Performing prerun with role_name_check=0...
2025-11-24 10:20:44.132606 | 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-24 10:20:44.133123 | 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-24 10:20:44.133594 | 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-24 10:20:44.134041 | 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-24 10:20:44.134533 | controller | WARNING  Another version of 'cifmw.general' 1.0.0+d78282a3 was found installed in /home/zuul/.cache/ansible-compat/82d78a/collections/ansible_collections, only the first one will be used, 1.0.0+d78282a3 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-24 10:20:44.134980 | 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-24 10:20:44.135444 | 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-24 10:20:44.135930 | 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-24 10:20:44.136430 | 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-24 10:20:44.136908 | 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-24 10:20:44.137427 | 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-24 10:20:44.137908 | 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-24 10:20:44.138380 | 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-24 10:20:44.138832 | 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-24 10:20:44.139319 | 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-24 10:20:44.148910 | controller | INFO     Running default > destroy
2025-11-24 10:20:44.361127 | controller | INFO     Sanity checks: 'podman'
2025-11-24 10:20:45.187953 | controller |
2025-11-24 10:20:45.188185 | controller | PLAY [Destroy] *****************************************************************
2025-11-24 10:20:45.188343 | controller |
2025-11-24 10:20:45.188482 | controller | TASK [Get passwd entries for USER env] *****************************************
2025-11-24 10:20:45.188618 | controller | Monday 24 November 2025  10:20:45 +0000 (0:00:00.013)       0:00:00.013 *******
2025-11-24 10:20:45.463103 | controller | ok: [localhost]
2025-11-24 10:20:45.503841 | controller |
2025-11-24 10:20:45.503871 | controller | TASK [Get shell async_dir] *****************************************************
2025-11-24 10:20:45.503878 | controller | Monday 24 November 2025  10:20:45 +0000 (0:00:00.275)       0:00:00.289 *******
2025-11-24 10:20:45.503888 | controller | ok: [localhost]
2025-11-24 10:20:45.535859 | controller |
2025-11-24 10:20:45.535881 | controller | TASK [Set async_dir for HOME env] **********************************************
2025-11-24 10:20:45.535889 | controller | Monday 24 November 2025  10:20:45 +0000 (0:00:00.040)       0:00:00.330 *******
2025-11-24 10:20:45.535898 | controller | skipping: [localhost]
2025-11-24 10:20:45.903931 | controller |
2025-11-24 10:20:45.903958 | controller | TASK [Destroy molecule instance(s)] ********************************************
2025-11-24 10:20:45.903967 | controller | Monday 24 November 2025  10:20:45 +0000 (0:00:00.032)       0:00:00.362 *******
2025-11-24 10:20:45.903977 | 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-24 10:20:51.352238 | controller |
2025-11-24 10:20:51.352275 | controller | TASK [Wait for instance(s) deletion to complete] *******************************
2025-11-24 10:20:51.352283 | controller | Monday 24 November 2025  10:20:45 +0000 (0:00:00.367)       0:00:00.730 *******
2025-11-24 10:20:51.352293 | controller | FAILED - RETRYING: [localhost]: Wait for instance(s) deletion to complete (300 retries left).
2025-11-24 10:20:51.402163 | controller | changed: [localhost] => (item={'failed': 0, 'started': 1, 'finished': 0, 'ansible_job_id': 'j942819968754.29432', 'results_file': '/home/zuul/.ansible_async/j942819968754.29432', '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-24 10:20:51.402195 | controller |
2025-11-24 10:20:51.402203 | controller | TASK [Delete podman network dedicated to this scenario] ************************
2025-11-24 10:20:51.402217 | controller | Monday 24 November 2025  10:20:51 +0000 (0:00:05.448)       0:00:06.178 *******
2025-11-24 10:20:51.402229 | controller | skipping: [localhost] => (item=instance: None specified)
2025-11-24 10:20:51.402260 | controller | skipping: [localhost]
2025-11-24 10:20:51.402267 | controller |
2025-11-24 10:20:51.402273 | controller | PLAY RECAP *********************************************************************
2025-11-24 10:20:51.402278 | controller | localhost                  : ok=4    changed=2    unreachable=0    failed=0    skipped=2    rescued=0    ignored=0
2025-11-24 10:20:51.402284 | controller |
2025-11-24 10:20:51.402290 | controller | Monday 24 November 2025  10:20:51 +0000 (0:00:00.049)       0:00:06.228 *******
2025-11-24 10:20:51.402295 | controller | ===============================================================================
2025-11-24 10:20:51.402300 | controller | Wait for instance(s) deletion to complete ------------------------------- 5.45s
2025-11-24 10:20:51.402305 | controller | Destroy molecule instance(s) -------------------------------------------- 0.37s
2025-11-24 10:20:51.402311 | controller | Get passwd entries for USER env ----------------------------------------- 0.28s
2025-11-24 10:20:51.402316 | controller | Delete podman network dedicated to this scenario ------------------------ 0.05s
2025-11-24 10:20:51.402321 | controller | Get shell async_dir ----------------------------------------------------- 0.04s
2025-11-24 10:20:51.402339 | controller | Set async_dir for HOME env ---------------------------------------------- 0.03s
2025-11-24 10:20:51.450700 | controller | INFO     Running default > create
2025-11-24 10:20:51.849950 | controller |
2025-11-24 10:20:51.850109 | controller | PLAY [Create] ******************************************************************
2025-11-24 10:20:51.850414 | controller |
2025-11-24 10:20:51.850530 | controller | TASK [Get podman executable path] **********************************************
2025-11-24 10:20:51.850715 | controller | Monday 24 November 2025  10:20:51 +0000 (0:00:00.013)       0:00:00.013 *******
2025-11-24 10:20:52.131676 | controller | ok: [localhost]
2025-11-24 10:20:52.131754 | controller |
2025-11-24 10:20:52.131865 | controller | TASK [Register podman executable path] *****************************************
2025-11-24 10:20:52.131974 | controller | Monday 24 November 2025  10:20:52 +0000 (0:00:00.281)       0:00:00.295 *******
2025-11-24 10:20:52.171253 | controller | ok: [localhost]
2025-11-24 10:20:52.171382 | controller |
2025-11-24 10:20:52.171516 | controller | TASK [Get passwd entries for USER env] *****************************************
2025-11-24 10:20:52.171646 | controller | Monday 24 November 2025  10:20:52 +0000 (0:00:00.039)       0:00:00.334 *******
2025-11-24 10:20:52.449788 | controller | ok: [localhost]
2025-11-24 10:20:52.449840 | controller |
2025-11-24 10:20:52.449950 | controller | TASK [Get shell async_dir] *****************************************************
2025-11-24 10:20:52.450048 | controller | Monday 24 November 2025  10:20:52 +0000 (0:00:00.278)       0:00:00.613 *******
2025-11-24 10:20:52.490209 | controller | ok: [localhost]
2025-11-24 10:20:52.490307 | controller |
2025-11-24 10:20:52.490408 | controller | TASK [Set async_dir for HOME env] **********************************************
2025-11-24 10:20:52.490510 | controller | Monday 24 November 2025  10:20:52 +0000 (0:00:00.040)       0:00:00.653 *******
2025-11-24 10:20:52.523709 | controller | skipping: [localhost]
2025-11-24 10:20:52.523816 | controller |
2025-11-24 10:20:52.523933 | controller | TASK [Log into a container registry] *******************************************
2025-11-24 10:20:52.524031 | controller | Monday 24 November 2025  10:20:52 +0000 (0:00:00.033)       0:00:00.687 *******
2025-11-24 10:20:52.565552 | controller | skipping: [localhost] => (item="instance registry username: None specified")
2025-11-24 10:20:52.565677 | controller | skipping: [localhost]
2025-11-24 10:20:52.565789 | controller |
2025-11-24 10:20:52.565910 | controller | TASK [Check presence of custom Dockerfiles] ************************************
2025-11-24 10:20:52.566028 | controller | Monday 24 November 2025  10:20:52 +0000 (0:00:00.041)       0:00:00.729 *******
2025-11-24 10:20:52.860324 | controller | ok: [localhost] => (item=Dockerfile: containerfile)
2025-11-24 10:20:52.860381 | controller |
2025-11-24 10:20:52.860502 | controller | TASK [Create Dockerfiles from image names] *************************************
2025-11-24 10:20:52.860608 | controller | Monday 24 November 2025  10:20:52 +0000 (0:00:00.294)       0:00:01.023 *******
2025-11-24 10:20:52.899530 | controller | skipping: [localhost] => (item="Dockerfile: containerfile; Image: registry.access.redhat.com/ubi9/ubi-init")
2025-11-24 10:20:52.899649 | controller | skipping: [localhost]
2025-11-24 10:20:52.899759 | controller |
2025-11-24 10:20:52.899877 | controller | TASK [Discover local Podman images] ********************************************
2025-11-24 10:20:52.899991 | controller | Monday 24 November 2025  10:20:52 +0000 (0:00:00.039)       0:00:01.063 *******
2025-11-24 10:20:53.225394 | controller | ok: [localhost] => (item=instance)
2025-11-24 10:20:53.225468 | controller |
2025-11-24 10:20:53.225579 | controller | TASK [Build an Ansible compatible image] ***************************************
2025-11-24 10:20:53.225683 | controller | Monday 24 November 2025  10:20:53 +0000 (0:00:00.325)       0:00:01.388 *******
2025-11-24 10:20:53.264934 | controller | skipping: [localhost] => (item=registry.access.redhat.com/ubi9/ubi-init)
2025-11-24 10:20:53.265049 | controller | skipping: [localhost]
2025-11-24 10:20:53.265192 | controller |
2025-11-24 10:20:53.265334 | controller | TASK [Determine the CMD directives] ********************************************
2025-11-24 10:20:53.265442 | controller | Monday 24 November 2025  10:20:53 +0000 (0:00:00.039)       0:00:01.428 *******
2025-11-24 10:20:53.311817 | controller | ok: [localhost] => (item="instance command: /sbin/init")
2025-11-24 10:20:53.311935 | controller |
2025-11-24 10:20:53.312061 | controller | TASK [Remove possible pre-existing containers] *********************************
2025-11-24 10:20:53.312222 | controller | Monday 24 November 2025  10:20:53 +0000 (0:00:00.046)       0:00:01.475 *******
2025-11-24 10:20:53.541230 | controller | changed: [localhost]
2025-11-24 10:20:53.541537 | controller |
2025-11-24 10:20:53.583771 | controller | TASK [Discover local podman networks] ******************************************
2025-11-24 10:20:53.583794 | controller | Monday 24 November 2025  10:20:53 +0000 (0:00:00.229)       0:00:01.704 *******
2025-11-24 10:20:53.583807 | controller | skipping: [localhost] => (item=instance: None specified)
2025-11-24 10:20:53.617863 | controller | skipping: [localhost]
2025-11-24 10:20:53.617884 | controller |
2025-11-24 10:20:53.617893 | controller | TASK [Create podman network dedicated to this scenario] ************************
2025-11-24 10:20:53.617900 | controller | Monday 24 November 2025  10:20:53 +0000 (0:00:00.042)       0:00:01.746 *******
2025-11-24 10:20:53.617911 | controller | skipping: [localhost]
2025-11-24 10:20:53.936845 | controller |
2025-11-24 10:20:53.936882 | controller | TASK [Create molecule instance(s)] *********************************************
2025-11-24 10:20:53.936891 | controller | Monday 24 November 2025  10:20:53 +0000 (0:00:00.034)       0:00:01.781 *******
2025-11-24 10:20:53.936901 | controller | changed: [localhost] => (item=instance)
2025-11-24 10:20:59.388171 | controller |
2025-11-24 10:20:59.388201 | controller | TASK [Wait for instance(s) creation to complete] *******************************
2025-11-24 10:20:59.388209 | controller | Monday 24 November 2025  10:20:53 +0000 (0:00:00.317)       0:00:02.098 *******
2025-11-24 10:20:59.388219 | controller | FAILED - RETRYING: [localhost]: Wait for instance(s) creation to complete (300 retries left).
2025-11-24 10:20:59.388242 | controller | changed: [localhost] => (item=instance)
2025-11-24 10:20:59.388249 | controller |
2025-11-24 10:20:59.388254 | controller | PLAY RECAP *********************************************************************
2025-11-24 10:20:59.388260 | controller | localhost                  : ok=10   changed=3    unreachable=0    failed=0    skipped=6    rescued=0    ignored=0
2025-11-24 10:20:59.388265 | controller |
2025-11-24 10:20:59.388271 | controller | Monday 24 November 2025  10:20:59 +0000 (0:00:05.452)       0:00:07.550 *******
2025-11-24 10:20:59.388287 | controller | ===============================================================================
2025-11-24 10:20:59.388295 | controller | Wait for instance(s) creation to complete ------------------------------- 5.45s
2025-11-24 10:20:59.388316 | controller | Discover local Podman images -------------------------------------------- 0.33s
2025-11-24 10:20:59.389182 | controller | Create molecule instance(s) --------------------------------------------- 0.32s
2025-11-24 10:20:59.389664 | controller | Check presence of custom Dockerfiles ------------------------------------ 0.29s
2025-11-24 10:20:59.389691 | controller | Get podman executable path ---------------------------------------------- 0.28s
2025-11-24 10:20:59.389698 | controller | Get passwd entries for USER env ----------------------------------------- 0.28s
2025-11-24 10:20:59.389704 | controller | Remove possible pre-existing containers --------------------------------- 0.23s
2025-11-24 10:20:59.389709 | controller | Determine the CMD directives -------------------------------------------- 0.05s
2025-11-24 10:20:59.389715 | controller | Discover local podman networks ------------------------------------------ 0.04s
2025-11-24 10:20:59.389720 | controller | Log into a container registry ------------------------------------------- 0.04s
2025-11-24 10:20:59.389726 | controller | Get shell async_dir ----------------------------------------------------- 0.04s
2025-11-24 10:20:59.389736 | controller | Register podman executable path ----------------------------------------- 0.04s
2025-11-24 10:20:59.439198 | controller | Build an Ansible compatible image --------------------------------------- 0.04s
2025-11-24 10:20:59.439229 | controller | Create Dockerfiles from image names ------------------------------------- 0.04s
2025-11-24 10:20:59.439237 | controller | Create podman network dedicated to this scenario ------------------------ 0.03s
2025-11-24 10:20:59.439243 | controller | Set async_dir for HOME env ---------------------------------------------- 0.03s
2025-11-24 10:20:59.439253 | controller | INFO     Running default > prepare
2025-11-24 10:20:59.439749 | controller | WARNING  Skipping, prepare playbook not configured.
2025-11-24 10:20:59.447842 | controller | INFO     Running default > converge
2025-11-24 10:20:59.855245 | controller |
2025-11-24 10:20:59.856349 | controller | PLAY [Converge] ****************************************************************
2025-11-24 10:20:59.856556 | controller |
2025-11-24 10:20:59.856718 | controller | TASK [Gather stack nodes and facts] ********************************************
2025-11-24 10:20:59.856867 | controller | Monday 24 November 2025  10:20:59 +0000 (0:00:00.018)       0:00:00.018 *******
2025-11-24 10:20:59.886753 | 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-24 10:20:59.921399 | 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-24 10:20:59.921418 | 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-24 10:20:59.921425 | controller |
2025-11-24 10:20:59.921432 | controller | TASK [Get main facts for the overcloud stack] **********************************
2025-11-24 10:20:59.921437 | controller | Monday 24 November 2025  10:20:59 +0000 (0:00:00.032)       0:00:00.050 *******
2025-11-24 10:20:59.921446 | controller | ok: [instance]
2025-11-24 10:21:00.005099 | controller |
2025-11-24 10:21:00.005118 | controller | TASK [Gather other facts for stack overcloud"] *********************************
2025-11-24 10:21:00.005126 | controller | Monday 24 November 2025  10:20:59 +0000 (0:00:00.035)       0:00:00.085 *******
2025-11-24 10:21:00.005136 | controller | ok: [instance]
2025-11-24 10:21:00.066178 | controller |
2025-11-24 10:21:00.066207 | controller | TASK [Gather nodes for stack overcloud] ****************************************
2025-11-24 10:21:00.066218 | controller | Monday 24 November 2025  10:21:00 +0000 (0:00:00.083)       0:00:00.169 *******
2025-11-24 10:21:00.066231 | controller | skipping: [instance] => (item=cell1-osp-computes)
2025-11-24 10:21:00.066825 | controller | skipping: [instance] => (item=cell1-osp-controllers)
2025-11-24 10:21:00.066842 | controller | skipping: [instance] => (item=cell2-osp-computes)
2025-11-24 10:21:00.066849 | controller | skipping: [instance] => (item=cell2-osp-controllers)
2025-11-24 10:21:00.066857 | controller | skipping: [instance] => (item=controllers)
2025-11-24 10:21:00.096164 | controller | skipping: [instance] => (item=ocps)
2025-11-24 10:21:00.096189 | controller | ok: [instance] => (item=osp-controllers)
2025-11-24 10:21:00.096198 | controller | skipping: [instance] => (item=osp-underclouds)
2025-11-24 10:21:00.096206 | controller |
2025-11-24 10:21:00.096214 | controller | TASK [Get main facts for the overcloud stack] **********************************
2025-11-24 10:21:00.096221 | controller | Monday 24 November 2025  10:21:00 +0000 (0:00:00.060)       0:00:00.230 *******
2025-11-24 10:21:00.096233 | controller | ok: [instance]
2025-11-24 10:21:00.096265 | controller |
2025-11-24 10:21:00.096437 | controller | TASK [Gather other facts for stack cell1"] *************************************
2025-11-24 10:21:00.163481 | controller | Monday 24 November 2025  10:21:00 +0000 (0:00:00.030)       0:00:00.261 *******
2025-11-24 10:21:00.163503 | controller | ok: [instance]
2025-11-24 10:21:00.228193 | controller |
2025-11-24 10:21:00.228228 | controller | TASK [Gather nodes for stack cell1] ********************************************
2025-11-24 10:21:00.228241 | controller | Monday 24 November 2025  10:21:00 +0000 (0:00:00.067)       0:00:00.328 *******
2025-11-24 10:21:00.228258 | controller | ok: [instance] => (item=cell1-osp-computes)
2025-11-24 10:21:00.228311 | controller | ok: [instance] => (item=cell1-osp-controllers)
2025-11-24 10:21:00.228325 | controller | skipping: [instance] => (item=cell2-osp-computes)
2025-11-24 10:21:00.228334 | controller | skipping: [instance] => (item=cell2-osp-controllers)
2025-11-24 10:21:00.228354 | controller | skipping: [instance] => (item=controllers)
2025-11-24 10:21:00.228365 | controller | skipping: [instance] => (item=ocps)
2025-11-24 10:21:00.228375 | controller | skipping: [instance] => (item=osp-controllers)
2025-11-24 10:21:00.228388 | controller | skipping: [instance] => (item=osp-underclouds)
2025-11-24 10:21:00.228425 | controller |
2025-11-24 10:21:00.228577 | controller | TASK [Get main facts for the overcloud stack] **********************************
2025-11-24 10:21:00.257196 | controller | Monday 24 November 2025  10:21:00 +0000 (0:00:00.064)       0:00:00.392 *******
2025-11-24 10:21:00.257225 | controller | ok: [instance]
2025-11-24 10:21:00.324184 | controller |
2025-11-24 10:21:00.324209 | controller | TASK [Gather other facts for stack cell2"] *************************************
2025-11-24 10:21:00.324219 | controller | Monday 24 November 2025  10:21:00 +0000 (0:00:00.029)       0:00:00.421 *******
2025-11-24 10:21:00.324230 | controller | ok: [instance]
2025-11-24 10:21:00.324260 | controller |
2025-11-24 10:21:00.324465 | controller | TASK [Gather nodes for stack cell2] ********************************************
2025-11-24 10:21:00.388241 | controller | Monday 24 November 2025  10:21:00 +0000 (0:00:00.067)       0:00:00.489 *******
2025-11-24 10:21:00.388297 | controller | skipping: [instance] => (item=cell1-osp-computes)
2025-11-24 10:21:00.388342 | controller | skipping: [instance] => (item=cell1-osp-controllers)
2025-11-24 10:21:00.388354 | controller | ok: [instance] => (item=cell2-osp-computes)
2025-11-24 10:21:00.388367 | controller | ok: [instance] => (item=cell2-osp-controllers)
2025-11-24 10:21:00.389287 | controller | skipping: [instance] => (item=controllers)
2025-11-24 10:21:00.419405 | controller | skipping: [instance] => (item=ocps)
2025-11-24 10:21:00.419424 | controller | skipping: [instance] => (item=osp-controllers)
2025-11-24 10:21:00.419431 | controller | skipping: [instance] => (item=osp-underclouds)
2025-11-24 10:21:00.419437 | controller |
2025-11-24 10:21:00.419443 | controller | TASK [Store result for verification as persistent fact] ************************
2025-11-24 10:21:00.419449 | controller | Monday 24 November 2025  10:21:00 +0000 (0:00:00.063)       0:00:00.552 *******
2025-11-24 10:21:00.419458 | controller | ok: [instance]
2025-11-24 10:21:00.471267 | controller |
2025-11-24 10:21:00.471314 | controller | PLAY RECAP *********************************************************************
2025-11-24 10:21:00.471323 | controller | instance                   : ok=13   changed=0    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2025-11-24 10:21:00.471329 | controller |
2025-11-24 10:21:00.471335 | controller | Monday 24 November 2025  10:21:00 +0000 (0:00:00.029)       0:00:00.582 *******
2025-11-24 10:21:00.471341 | controller | ===============================================================================
2025-11-24 10:21:00.471347 | controller | Gather other facts for stack overcloud" --------------------------------- 0.08s
2025-11-24 10:21:00.471352 | controller | Gather other facts for stack cell2" ------------------------------------- 0.07s
2025-11-24 10:21:00.471362 | controller | Gather other facts for stack cell1" ------------------------------------- 0.07s
2025-11-24 10:21:00.471368 | controller | Gather nodes for stack cell1 -------------------------------------------- 0.06s
2025-11-24 10:21:00.471374 | controller | Gather nodes for stack cell2 -------------------------------------------- 0.06s
2025-11-24 10:21:00.471379 | controller | Gather nodes for stack overcloud ---------------------------------------- 0.06s
2025-11-24 10:21:00.471384 | controller | Get main facts for the overcloud stack ---------------------------------- 0.04s
2025-11-24 10:21:00.471390 | controller | Gather stack nodes and facts -------------------------------------------- 0.03s
2025-11-24 10:21:00.471395 | controller | Get main facts for the overcloud stack ---------------------------------- 0.03s
2025-11-24 10:21:00.471400 | controller | Store result for verification as persistent fact ------------------------ 0.03s
2025-11-24 10:21:00.471405 | controller | Get main facts for the overcloud stack ---------------------------------- 0.03s
2025-11-24 10:21:00.471415 | controller | INFO     Running default > verify
2025-11-24 10:21:00.471764 | controller | INFO     Running Ansible Verifier
2025-11-24 10:21:00.865314 | controller |
2025-11-24 10:21:00.886838 | controller | PLAY [Verify] ******************************************************************
2025-11-24 10:21:00.886854 | controller |
2025-11-24 10:21:00.886868 | controller | TASK [Set _tripleo_nodes_stack from persistent fact] ***************************
2025-11-24 10:21:00.886874 | controller | Monday 24 November 2025  10:21:00 +0000 (0:00:00.017)       0:00:00.017 *******
2025-11-24 10:21:00.886882 | controller | skipping: [instance]
2025-11-24 10:21:00.956164 | controller |
2025-11-24 10:21:00.956195 | controller | TASK [Assert gathered nodes for stacks] ****************************************
2025-11-24 10:21:00.956204 | controller | Monday 24 November 2025  10:21:00 +0000 (0:00:00.022)       0:00:00.040 *******
2025-11-24 10:21:00.956216 | 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-24 10:21:00.956336 | controller |   _stack:
2025-11-24 10:21:00.956345 | controller |     network_data_file: network_data.yaml.j2
2025-11-24 10:21:00.956351 | controller |     stack_nodes:
2025-11-24 10:21:00.956357 | controller |     - osp-controllers
2025-11-24 10:21:00.956363 | controller |     stackname: overcloud
2025-11-24 10:21:00.956368 | controller |     vips_data_file: vips_data_overcloud.yaml
2025-11-24 10:21:00.956374 | controller |   ansible_loop_var: _stack
2025-11-24 10:21:00.956379 | controller |   msg: Successfully verified gathered nodes for stack overcloud
2025-11-24 10:21:00.956384 | 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-24 10:21:00.956396 | controller |   _stack:
2025-11-24 10:21:00.956402 | controller |     network_data_file: network_data.yaml.j2
2025-11-24 10:21:00.956407 | controller |     stack_nodes:
2025-11-24 10:21:00.956412 | controller |     - cell1-osp-computes
2025-11-24 10:21:00.956418 | controller |     - cell1-osp-controllers
2025-11-24 10:21:00.956424 | controller |     stackname: cell1
2025-11-24 10:21:00.956429 | controller |     vips_data_file: vips_data_cell1.yaml
2025-11-24 10:21:00.956434 | controller |   ansible_loop_var: _stack
2025-11-24 10:21:00.956440 | controller |   msg: Successfully verified gathered nodes for stack cell1
2025-11-24 10:21:00.956445 | 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-24 10:21:00.956450 | controller |   _stack:
2025-11-24 10:21:00.956456 | controller |     network_data_file: network_data.yaml.j2
2025-11-24 10:21:00.956461 | controller |     stack_nodes:
2025-11-24 10:21:00.956467 | controller |     - cell2-osp-computes
2025-11-24 10:21:00.956472 | controller |     - cell2-osp-controllers
2025-11-24 10:21:00.956479 | controller |     stackname: cell2
2025-11-24 10:21:00.956485 | controller |     vips_data_file: vips_data_cell2.yaml
2025-11-24 10:21:00.956490 | controller |   ansible_loop_var: _stack
2025-11-24 10:21:00.956495 | controller |   msg: Successfully verified gathered nodes for stack cell2
2025-11-24 10:21:00.956501 | controller |
2025-11-24 10:21:00.956509 | controller | PLAY RECAP *********************************************************************
2025-11-24 10:21:00.956566 | controller | instance                   : ok=1    changed=0    unreachable=0    failed=0    skipped=1    rescued=0    ignored=0
2025-11-24 10:21:00.956579 | controller |
2025-11-24 10:21:00.956694 | controller | Monday 24 November 2025  10:21:00 +0000 (0:00:00.067)       0:00:00.107 *******
2025-11-24 10:21:00.956797 | controller | ===============================================================================
2025-11-24 10:21:00.956893 | controller | Assert gathered nodes for stacks ---------------------------------------- 0.07s
2025-11-24 10:21:00.956997 | controller | Set _tripleo_nodes_stack from persistent fact --------------------------- 0.02s
2025-11-24 10:21:00.991468 | controller | INFO     Verifier completed successfully.
2025-11-24 10:21:00.999605 | controller | INFO     Running default > destroy
2025-11-24 10:21:01.393938 | controller |
2025-11-24 10:21:01.678598 | controller | PLAY [Destroy] *****************************************************************
2025-11-24 10:21:01.678627 | controller |
2025-11-24 10:21:01.678635 | controller | TASK [Get passwd entries for USER env] *****************************************
2025-11-24 10:21:01.678641 | controller | Monday 24 November 2025  10:21:01 +0000 (0:00:00.014)       0:00:00.014 *******
2025-11-24 10:21:01.678651 | controller | ok: [localhost]
2025-11-24 10:21:01.719992 | controller |
2025-11-24 10:21:01.720016 | controller | TASK [Get shell async_dir] *****************************************************
2025-11-24 10:21:01.720026 | controller | Monday 24 November 2025  10:21:01 +0000 (0:00:00.284)       0:00:00.298 *******
2025-11-24 10:21:01.720038 | controller | ok: [localhost]
2025-11-24 10:21:01.751951 | controller |
2025-11-24 10:21:01.751974 | controller | TASK [Set async_dir for HOME env] **********************************************
2025-11-24 10:21:01.751983 | controller | Monday 24 November 2025  10:21:01 +0000 (0:00:00.041)       0:00:00.340 *******
2025-11-24 10:21:01.751995 | controller | skipping: [localhost]
2025-11-24 10:21:02.124209 | controller |
2025-11-24 10:21:02.124232 | controller | TASK [Destroy molecule instance(s)] ********************************************
2025-11-24 10:21:02.124240 | controller | Monday 24 November 2025  10:21:01 +0000 (0:00:00.031)       0:00:00.372 *******
2025-11-24 10:21:02.124249 | 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-24 10:21:07.569702 | controller |
2025-11-24 10:21:07.569731 | controller | TASK [Wait for instance(s) deletion to complete] *******************************
2025-11-24 10:21:07.569739 | controller | Monday 24 November 2025  10:21:02 +0000 (0:00:00.371)       0:00:00.744 *******
2025-11-24 10:21:07.569749 | controller | FAILED - RETRYING: [localhost]: Wait for instance(s) deletion to complete (300 retries left).
2025-11-24 10:21:07.620050 | controller | changed: [localhost] => (item={'failed': 0, 'started': 1, 'finished': 0, 'ansible_job_id': 'j587570141318.29883', 'results_file': '/home/zuul/.ansible_async/j587570141318.29883', '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-24 10:21:07.620069 | controller |
2025-11-24 10:21:07.620095 | controller | TASK [Delete podman network dedicated to this scenario] ************************
2025-11-24 10:21:07.620102 | controller | Monday 24 November 2025  10:21:07 +0000 (0:00:05.445)       0:00:06.190 *******
2025-11-24 10:21:07.620111 | controller | skipping: [localhost] => (item=instance: None specified)
2025-11-24 10:21:07.660809 | controller | skipping: [localhost]
2025-11-24 10:21:07.660828 | controller |
2025-11-24 10:21:07.660836 | controller | PLAY RECAP *********************************************************************
2025-11-24 10:21:07.660841 | controller | localhost                  : ok=4    changed=2    unreachable=0    failed=0    skipped=2    rescued=0    ignored=0
2025-11-24 10:21:07.660847 | controller |
2025-11-24 10:21:07.660853 | controller | Monday 24 November 2025  10:21:07 +0000 (0:00:00.050)       0:00:06.240 *******
2025-11-24 10:21:07.660858 | controller | ===============================================================================
2025-11-24 10:21:07.660864 | controller | Wait for instance(s) deletion to complete ------------------------------- 5.45s
2025-11-24 10:21:07.660869 | controller | Destroy molecule instance(s) -------------------------------------------- 0.37s
2025-11-24 10:21:07.660874 | controller | Get passwd entries for USER env ----------------------------------------- 0.28s
2025-11-24 10:21:07.660879 | controller | Delete podman network dedicated to this scenario ------------------------ 0.05s
2025-11-24 10:21:07.660885 | controller | Get shell async_dir ----------------------------------------------------- 0.04s
2025-11-24 10:21:07.660890 | controller | Set async_dir for HOME env ---------------------------------------------- 0.03s
2025-11-24 10:21:07.660899 | controller | INFO     Pruning extra files from scenario ephemeral directory
2025-11-24 10:21:07.662611 | controller | INFO     Writing /tmp/report.html report.
2025-11-24 10:21:08.087305 | controller | changed
2025-11-24 10:21:08.105598 | 
2025-11-24 10:21:08.105647 | PLAY RECAP
2025-11-24 10:21:08.105685 | controller | ok: 4 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-11-24 10:21:08.105707 | 
2025-11-24 10:21:08.162279 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-11-24 10:21:08.163096 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-11-24 10:21:08.690527 | 
2025-11-24 10:21:08.690628 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-11-24 10:21:08.710113 | 
2025-11-24 10:21:08.710183 | TASK [Filter out host if needed]
2025-11-24 10:21:08.718421 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-11-24 10:21:08.722787 | 
2025-11-24 10:21:08.722852 | TASK [Ensure file is present]
2025-11-24 10:21:09.014538 | controller | ok
2025-11-24 10:21:09.021468 | 
2025-11-24 10:21:09.021533 | TASK [Manage molecule report file]
2025-11-24 10:21:09.546009 | controller | changed
2025-11-24 10:21:09.551507 | 
2025-11-24 10:21:09.551575 | TASK [Check if we get ci-framework-data basedir]
2025-11-24 10:21:09.731972 | controller | ok
2025-11-24 10:21:09.737215 | 
2025-11-24 10:21:09.737291 | TASK [Create ci-framework-data log directory for zuul]
2025-11-24 10:21:10.028321 | controller | changed
2025-11-24 10:21:10.035020 | 
2025-11-24 10:21:10.035093 | TASK [Copy ci-framework interesting files]
2025-11-24 10:21:10.222617 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2025-11-24 10:21:10.562157 | controller | changed
2025-11-24 10:21:10.567114 | 
2025-11-24 10:21:10.567176 | TASK [Get SELinux listing]
2025-11-24 10:21:11.091262 | controller | changed
2025-11-24 10:21:11.096869 | 
2025-11-24 10:21:11.096952 | TASK [Generate log index]
2025-11-24 10:21:11.693492 | controller | changed
2025-11-24 10:21:11.698100 | 
2025-11-24 10:21:11.698160 | TASK [Get some env related data]
2025-11-24 10:21:12.213754 | controller | /home/zuul/.local/bin/ansible
2025-11-24 10:21:13.223583 | controller | changed
2025-11-24 10:21:13.228750 | 
2025-11-24 10:21:13.228841 | TASK [Generate list of logs to collect in home directory]
2025-11-24 10:21:13.500620 | controller | ok: All paths examined
2025-11-24 10:21:13.505486 | 
2025-11-24 10:21:13.505547 | LOOP [Copy logs from home directory]
2025-11-24 10:21:13.806474 | controller | changed:
2025-11-24 10:21:13.806576 | controller | {
2025-11-24 10:21:13.806602 | controller |   "atime": 1763979609.38346,
2025-11-24 10:21:13.806622 | controller |   "ctime": 1763979624.6796684,
2025-11-24 10:21:13.806638 | controller |   "dev": 64513,
2025-11-24 10:21:13.806653 | controller |   "gid": 1000,
2025-11-24 10:21:13.806668 | controller |   "gr_name": "zuul",
2025-11-24 10:21:13.806684 | controller |   "inode": 4329452,
2025-11-24 10:21:13.806701 | controller |   "isblk": false,
2025-11-24 10:21:13.806715 | controller |   "ischr": false,
2025-11-24 10:21:13.806729 | controller |   "isdir": false,
2025-11-24 10:21:13.806744 | controller |   "isfifo": false,
2025-11-24 10:21:13.806759 | controller |   "isgid": false,
2025-11-24 10:21:13.806773 | controller |   "islnk": false,
2025-11-24 10:21:13.806799 | controller |   "isreg": true,
2025-11-24 10:21:13.806815 | controller |   "issock": false,
2025-11-24 10:21:13.806829 | controller |   "isuid": false,
2025-11-24 10:21:13.806843 | controller |   "mode": "0644",
2025-11-24 10:21:13.806856 | controller |   "mtime": 1763979624.6796684,
2025-11-24 10:21:13.806870 | controller |   "nlink": 1,
2025-11-24 10:21:13.806883 | controller |   "path": "/home/zuul/ansible.log",
2025-11-24 10:21:13.806898 | controller |   "pw_name": "zuul",
2025-11-24 10:21:13.806933 | controller |   "rgrp": true,
2025-11-24 10:21:13.806953 | controller |   "roth": true,
2025-11-24 10:21:13.806968 | controller |   "rusr": true,
2025-11-24 10:21:13.806983 | controller |   "size": 6749,
2025-11-24 10:21:13.806997 | controller |   "uid": 1000,
2025-11-24 10:21:13.807011 | controller |   "wgrp": false,
2025-11-24 10:21:13.807024 | controller |   "woth": false,
2025-11-24 10:21:13.807041 | controller |   "wusr": true,
2025-11-24 10:21:13.807056 | controller |   "xgrp": false,
2025-11-24 10:21:13.807069 | controller |   "xoth": false,
2025-11-24 10:21:13.807083 | controller |   "xusr": false
2025-11-24 10:21:13.807096 | controller | }
2025-11-24 10:21:13.820459 | 
2025-11-24 10:21:13.820520 | TASK [Copy crio stats log file]
2025-11-24 10:21:13.833820 | controller | skipping: Conditional result was False
2025-11-24 10:21:13.838876 | 
2025-11-24 10:21:13.838957 | TASK [Get SELinux related data]
2025-11-24 10:21:14.040457 | controller | <no matches>
2025-11-24 10:21:14.365276 | controller | ERROR
2025-11-24 10:21:14.365453 | controller | {
2025-11-24 10:21:14.365490 | controller |   "delta": "0:00:00.009327",
2025-11-24 10:21:14.365515 | controller |   "end": "2025-11-24 10:21:14.041265",
2025-11-24 10:21:14.365535 | controller |   "msg": "non-zero return code",
2025-11-24 10:21:14.365556 | controller |   "rc": 1,
2025-11-24 10:21:14.365577 | controller |   "start": "2025-11-24 10:21:14.031938"
2025-11-24 10:21:14.365595 | controller | }
2025-11-24 10:21:14.365622 | controller | ERROR: Ignoring Errors
2025-11-24 10:21:14.370481 | 
2025-11-24 10:21:14.370541 | TASK [Create system configuration directory]
2025-11-24 10:21:14.550447 | controller | changed
2025-11-24 10:21:14.555157 | 
2025-11-24 10:21:14.555224 | TASK [Get some of the system configurations]
2025-11-24 10:21:15.081173 | controller | changed
2025-11-24 10:21:15.086216 | 
2025-11-24 10:21:15.086280 | TASK [Copy generated documentation if available]
2025-11-24 10:21:15.099836 | controller | skipping: Conditional result was False
2025-11-24 10:21:15.104980 | 
2025-11-24 10:21:15.105041 | TASK [Copy generated AsciiDoc documentation if available]
2025-11-24 10:21:15.117622 | controller | skipping: Conditional result was False
2025-11-24 10:21:15.123682 | 
2025-11-24 10:21:15.123743 | TASK [Compress logs bigger than 2MB]
2025-11-24 10:21:15.648666 | controller | changed
2025-11-24 10:21:15.654124 | 
2025-11-24 10:21:15.654187 | TASK [Copy files from workspace on node]
2025-11-24 10:21:15.671880 | controller | ok
2025-11-24 10:21:15.693216 | 
2025-11-24 10:21:15.693286 | TASK [fetch-output : Set log path for multiple nodes]
2025-11-24 10:21:15.706747 | controller | skipping: Conditional result was False
2025-11-24 10:21:15.711963 | 
2025-11-24 10:21:15.712030 | TASK [fetch-output : Set log path for single node]
2025-11-24 10:21:15.739763 | controller | ok
2025-11-24 10:21:15.745716 | 
2025-11-24 10:21:15.745789 | LOOP [fetch-output : Ensure local output dirs]
2025-11-24 10:21:15.935612 | controller -> localhost | ok: "/var/lib/zuul/builds/7695e6fc106b4ea6b561eebe668c0bcc/work/logs"
2025-11-24 10:21:15.935844 | controller -> localhost | changed: All items complete
2025-11-24 10:21:15.935877 | 
2025-11-24 10:21:16.114288 | controller -> localhost | changed: "/var/lib/zuul/builds/7695e6fc106b4ea6b561eebe668c0bcc/work/artifacts"
2025-11-24 10:21:16.288370 | controller -> localhost | changed: "/var/lib/zuul/builds/7695e6fc106b4ea6b561eebe668c0bcc/work/docs"
2025-11-24 10:21:16.301935 | 
2025-11-24 10:21:16.302004 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-11-24 10:21:16.799083 | controller | changed:
2025-11-24 10:21:16.799222 | controller | .d..t...... ./
2025-11-24 10:21:16.799251 | controller | >f+++++++++ README.html
2025-11-24 10:21:16.799272 | controller | >f+++++++++ ansible-execution.log
2025-11-24 10:21:16.799293 | controller | >f+++++++++ ansible.log
2025-11-24 10:21:16.799312 | controller | >f+++++++++ dmesg.log
2025-11-24 10:21:16.799328 | controller | >f+++++++++ installed-pkgs.log
2025-11-24 10:21:16.799347 | controller | >f+++++++++ python.log
2025-11-24 10:21:16.799364 | controller | >f+++++++++ registries.conf
2025-11-24 10:21:16.799380 | controller | >f+++++++++ report.html
2025-11-24 10:21:16.799398 | controller | >f+++++++++ selinux-denials.log
2025-11-24 10:21:16.799414 | controller | >f+++++++++ selinux-listing.log
2025-11-24 10:21:16.799429 | controller | cd+++++++++ ci-framework-data/
2025-11-24 10:21:16.799444 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-11-24 10:21:16.799459 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-11-24 10:21:16.799474 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-11-24 10:21:16.799489 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-11-24 10:21:16.799505 | controller | cd+++++++++ ci-framework-data/logs/
2025-11-24 10:21:16.799520 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2025-11-24 10:21:16.799535 | controller | cd+++++++++ registries.conf.d/
2025-11-24 10:21:16.799550 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-11-24 10:21:16.799564 | controller | cd+++++++++ system-config/
2025-11-24 10:21:16.799577 | controller | cd+++++++++ system-config/libvirt/
2025-11-24 10:21:16.799591 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-11-24 10:21:16.799607 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-11-24 10:21:17.150568 | controller | changed: .d..t...... ./
2025-11-24 10:21:17.523976 | controller | changed: .d..t...... ./
2025-11-24 10:21:17.544389 | 
2025-11-24 10:21:17.544502 | TASK [Return artifact to Zuul]
2025-11-24 10:21:17.586295 | controller | ok
2025-11-24 10:21:17.605952 | 
2025-11-24 10:21:17.606012 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-11-24 10:21:17.606121 | 
2025-11-24 10:21:17.606148 | PLAY RECAP
2025-11-24 10:21:17.606190 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2025-11-24 10:21:17.606210 | 
2025-11-24 10:21:17.695350 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-11-24 10:21:17.696157 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-11-24 10:21:18.210361 | 
2025-11-24 10:21:18.210470 | PLAY [all]
2025-11-24 10:21:18.228582 | 
2025-11-24 10:21:18.228656 | TASK [include_role : fetch-output]
2025-11-24 10:21:18.257867 | controller | ok
2025-11-24 10:21:18.274390 | 
2025-11-24 10:21:18.274483 | TASK [fetch-output : Set log path for multiple nodes]
2025-11-24 10:21:18.318883 | controller | skipping: Conditional result was False
2025-11-24 10:21:18.324437 | 
2025-11-24 10:21:18.324512 | TASK [fetch-output : Set log path for single node]
2025-11-24 10:21:18.353178 | controller | ok
2025-11-24 10:21:18.358070 | 
2025-11-24 10:21:18.358142 | LOOP [fetch-output : Ensure local output dirs]
2025-11-24 10:21:18.684077 | controller -> localhost | ok: "/var/lib/zuul/builds/7695e6fc106b4ea6b561eebe668c0bcc/work/logs"
2025-11-24 10:21:18.873406 | controller -> localhost | ok: "/var/lib/zuul/builds/7695e6fc106b4ea6b561eebe668c0bcc/work/artifacts"
2025-11-24 10:21:19.051189 | controller -> localhost | ok: "/var/lib/zuul/builds/7695e6fc106b4ea6b561eebe668c0bcc/work/docs"
2025-11-24 10:21:19.067024 | 
2025-11-24 10:21:19.067130 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-11-24 10:21:19.561369 | controller | ok
2025-11-24 10:21:19.561552 | controller | ok: All items complete
2025-11-24 10:21:19.561581 | 
2025-11-24 10:21:19.920518 | controller | ok
2025-11-24 10:21:20.272336 | controller | ok
2025-11-24 10:21:20.286543 | 
2025-11-24 10:21:20.286649 | TASK [include_role : fetch-output-openshift]
2025-11-24 10:21:20.300255 | controller | skipping: Conditional result was False
2025-11-24 10:21:20.305859 | 
2025-11-24 10:21:20.305941 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-11-24 10:21:20.643125 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.006601
2025-11-24 10:21:20.831673 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.006887
2025-11-24 10:21:20.860599 | 
2025-11-24 10:21:20.860695 | PLAY [all]
2025-11-24 10:21:20.874313 | 
2025-11-24 10:21:20.874381 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-11-24 10:21:21.257433 | controller | changed
2025-11-24 10:21:21.278719 | 
2025-11-24 10:21:21.278803 | PLAY RECAP
2025-11-24 10:21:21.278847 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-11-24 10:21:21.278871 | 
2025-11-24 10:21:21.344717 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-11-24 10:21:21.345472 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-11-24 10:21:21.880992 | 
2025-11-24 10:21:21.881092 | PLAY [localhost]
2025-11-24 10:21:21.898507 | 
2025-11-24 10:21:21.898587 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-11-24 10:21:22.199175 | localhost | changed
2025-11-24 10:21:22.204322 | 
2025-11-24 10:21:22.204398 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-11-24 10:21:22.222712 | localhost | ok
2025-11-24 10:21:22.230363 | 
2025-11-24 10:21:22.230430 | TASK [add-fileserver : Create SSH private key tempfile]
2025-11-24 10:21:22.510718 | localhost | changed
2025-11-24 10:21:22.516306 | 
2025-11-24 10:21:22.516374 | TASK [add-fileserver : Create SSH private key from secret]
2025-11-24 10:21:23.049662 | localhost | changed
2025-11-24 10:21:23.054615 | 
2025-11-24 10:21:23.054688 | TASK [add-fileserver : Add fileserver ssh key]
2025-11-24 10:21:23.376023 | localhost | Identity added: /var/lib/zuul/builds/7695e6fc106b4ea6b561eebe668c0bcc/work/tmp/ansible.8e1udly7 (/var/lib/zuul/builds/7695e6fc106b4ea6b561eebe668c0bcc/work/tmp/ansible.8e1udly7)
2025-11-24 10:21:23.376197 | localhost | ok: Runtime: 0:00:00.006047
2025-11-24 10:21:23.380526 | 
2025-11-24 10:21:23.380598 | TASK [add-fileserver : Remove SSH private key from disk]
2025-11-24 10:21:23.590663 | localhost | ok: Runtime: 0:00:00.004151
2025-11-24 10:21:23.595461 | 
2025-11-24 10:21:23.595550 | TASK [add-fileserver : Add fileserver to inventory]
2025-11-24 10:21:23.643826 | localhost | changed
2025-11-24 10:21:23.648772 | 
2025-11-24 10:21:23.648853 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-11-24 10:21:23.949958 | localhost | changed
2025-11-24 10:21:23.969574 | 
2025-11-24 10:21:23.969639 | PLAY [localhost]
2025-11-24 10:21:23.980770 | 
2025-11-24 10:21:23.980842 | TASK [Generate bulk log download script]
2025-11-24 10:21:23.998862 | localhost | ok
2025-11-24 10:21:24.009897 | 
2025-11-24 10:21:24.009988 | TASK [local-log-download : Check API endpoint is defined]
2025-11-24 10:21:24.037087 | localhost | ok: All assertions passed
2025-11-24 10:21:24.041381 | 
2025-11-24 10:21:24.041442 | TASK [local-log-download : Create download script]
2025-11-24 10:21:24.378871 | localhost -> localhost | changed
2025-11-24 10:21:24.387195 | 
2025-11-24 10:21:24.387264 | TASK [Register quick-download link]
2025-11-24 10:21:24.404439 | localhost | ok
2025-11-24 10:21:24.436243 | 
2025-11-24 10:21:24.436311 | PLAY [logserver.rdoproject.org]
2025-11-24 10:21:24.445522 | 
2025-11-24 10:21:24.445583 | TASK [Set zuul-log-path fact]
2025-11-24 10:21:24.461223 | logserver.rdoproject.org | ok
2025-11-24 10:21:24.469990 | 
2025-11-24 10:21:24.470058 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-11-24 10:21:24.485846 | logserver.rdoproject.org | ok
2025-11-24 10:21:24.491047 | 
2025-11-24 10:21:24.491111 | TASK [upload-logs : Create log directories]
2025-11-24 10:21:26.496130 | logserver.rdoproject.org | changed
2025-11-24 10:21:26.499279 | 
2025-11-24 10:21:26.499356 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-11-24 10:21:26.716210 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.004065
2025-11-24 10:21:26.720924 | 
2025-11-24 10:21:26.720990 | TASK [upload-logs : Upload logs to log server]
2025-11-24 10:21:28.379882 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-11-24 10:21:28.382732 | 
2025-11-24 10:21:28.382806 | LOOP [upload-logs : Compress console log and json output]
2025-11-24 10:21:28.420354 | logserver.rdoproject.org | skipping: Conditional result was False
2025-11-24 10:21:28.428530 | logserver.rdoproject.org | skipping: Conditional result was False
2025-11-24 10:21:28.440208 | 
2025-11-24 10:21:28.440297 | LOOP [upload-logs : Upload compressed console log and json output]
2025-11-24 10:21:28.474585 | logserver.rdoproject.org | skipping: Conditional result was False
2025-11-24 10:21:28.474826 | 
2025-11-24 10:21:28.478155 | logserver.rdoproject.org | skipping: Conditional result was False
2025-11-24 10:21:28.488938 | 
2025-11-24 10:21:28.489023 | LOOP [upload-logs : Upload console log and json output]