2026-01-29 15:12:14.411522 | Job console starting...
2026-01-29 15:12:14.626714 | Updating repositories
2026-01-29 15:12:15.374831 | Preparing job workspace
2026-01-29 15:12:22.283030 | Running Ansible setup...
2026-01-29 15:12:25.022128 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2026-01-29 15:12:25.491780 | 
2026-01-29 15:12:25.491904 | PLAY [localhost]
2026-01-29 15:12:25.500220 | 
2026-01-29 15:12:25.500290 | TASK [Gathering Facts]
2026-01-29 15:12:26.262690 | localhost | ok
2026-01-29 15:12:26.275112 | 
2026-01-29 15:12:26.275206 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-01-29 15:12:26.573033 | localhost -> localhost | changed
2026-01-29 15:12:26.578269 | 
2026-01-29 15:12:26.578366 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-01-29 15:12:27.219555 | localhost -> localhost | changed
2026-01-29 15:12:27.227515 | 
2026-01-29 15:12:27.227584 | TASK [Setup log path fact]
2026-01-29 15:12:27.244136 | localhost | ok
2026-01-29 15:12:27.254436 | 
2026-01-29 15:12:27.254499 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-01-29 15:12:27.271171 | localhost | ok
2026-01-29 15:12:27.277927 | 
2026-01-29 15:12:27.277993 | TASK [emit-job-header : Print job information]
2026-01-29 15:12:27.304814 | # Job Information
2026-01-29 15:12:27.304930 | Ansible Version: 2.15.12
2026-01-29 15:12:27.304959 | Job: cifmw-molecule-adoption_osp_deploy
2026-01-29 15:12:27.304982 | Pipeline: github-check
2026-01-29 15:12:27.305002 | Executor: ibm-bm4-ze.softwarefactory-project.io
2026-01-29 15:12:27.305020 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3631
2026-01-29 15:12:27.305040 | Log URL (when completed): https://logserver.rdoproject.org/9dd/rdoproject.org/9ddf609f605b44a098ce282deb9ff662/
2026-01-29 15:12:27.305059 | Event ID: c050dbe0-fd24-11f0-9e91-9117dc54a151
2026-01-29 15:12:27.308586 | 
2026-01-29 15:12:27.308656 | LOOP [emit-job-header : Print node information]
2026-01-29 15:12:27.387554 | localhost | ok:
2026-01-29 15:12:27.387747 | localhost | # Node Information
2026-01-29 15:12:27.387780 | localhost | Inventory Hostname: controller
2026-01-29 15:12:27.387805 | localhost | Hostname: np0005601063
2026-01-29 15:12:27.387826 | localhost | Username: zuul
2026-01-29 15:12:27.387847 | localhost | Distro: CentOS 9
2026-01-29 15:12:27.387866 | localhost | Provider: ibm-bm4-nodepool
2026-01-29 15:12:27.387884 | localhost | Region: regionOne
2026-01-29 15:12:27.387902 | localhost | Label: cloud-centos-9-stream-tripleo-ibm
2026-01-29 15:12:27.387921 | localhost | Product Name: OpenStack Compute
2026-01-29 15:12:27.387938 | localhost | Interface IP: 192.168.26.39
2026-01-29 15:12:27.410462 | 
2026-01-29 15:12:27.410526 | PLAY [all]
2026-01-29 15:12:27.416086 | 
2026-01-29 15:12:27.416143 | TASK [Gather network facts]
2026-01-29 15:12:27.769320 | controller | ok
2026-01-29 15:12:27.782613 | 
2026-01-29 15:12:27.782676 | TASK [include_role : start-zuul-console]
2026-01-29 15:12:27.799898 | controller | ok
2026-01-29 15:12:27.811541 | 
2026-01-29 15:12:27.811608 | TASK [start-zuul-console : Start zuul_console daemon.]
2026-01-29 15:12:28.102678 | controller | ok
2026-01-29 15:12:28.110643 | 
2026-01-29 15:12:28.110703 | TASK [include_role : add-build-sshkey]
2026-01-29 15:12:28.127738 | controller | ok
2026-01-29 15:12:28.140227 | 
2026-01-29 15:12:28.140316 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-01-29 15:12:28.325437 | controller -> localhost | ok
2026-01-29 15:12:28.330571 | 
2026-01-29 15:12:28.330635 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-01-29 15:12:28.347677 | controller | ok
2026-01-29 15:12:28.360191 | controller | included: /var/lib/zuul/builds/9ddf609f605b44a098ce282deb9ff662/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-01-29 15:12:28.365701 | 
2026-01-29 15:12:28.365763 | TASK [add-build-sshkey : Create Temp SSH key]
2026-01-29 15:12:28.712925 | controller -> localhost | Generating public/private rsa key pair.
2026-01-29 15:12:28.713117 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/9ddf609f605b44a098ce282deb9ff662/work/9ddf609f605b44a098ce282deb9ff662_id_rsa.
2026-01-29 15:12:28.713152 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/9ddf609f605b44a098ce282deb9ff662/work/9ddf609f605b44a098ce282deb9ff662_id_rsa.pub.
2026-01-29 15:12:28.713177 | controller -> localhost | The key fingerprint is:
2026-01-29 15:12:28.713197 | controller -> localhost | SHA256:jfKY3OPEE+/LGrOZbbEz7W6G2LtptPfAsfGMUYEZm/0 zuul-build-sshkey
2026-01-29 15:12:28.713219 | controller -> localhost | The key's randomart image is:
2026-01-29 15:12:28.713239 | controller -> localhost | +---[RSA 3072]----+
2026-01-29 15:12:28.713258 | controller -> localhost | |            .+.  |
2026-01-29 15:12:28.713278 | controller -> localhost | |            o+ . |
2026-01-29 15:12:28.713313 | controller -> localhost | |            o o  |
2026-01-29 15:12:28.713339 | controller -> localhost | |         o   . . |
2026-01-29 15:12:28.713359 | controller -> localhost | |      . S . +   E|
2026-01-29 15:12:28.713378 | controller -> localhost | |     . B oo. O   |
2026-01-29 15:12:28.713395 | controller -> localhost | |      + X+.*= o  |
2026-01-29 15:12:28.713427 | controller -> localhost | |       o.%X.=.   |
2026-01-29 15:12:28.713447 | controller -> localhost | |        *+O%o..  |
2026-01-29 15:12:28.713466 | controller -> localhost | +----[SHA256]-----+
2026-01-29 15:12:28.713510 | controller -> localhost | ok: Runtime: 0:00:00.042344
2026-01-29 15:12:28.718931 | 
2026-01-29 15:12:28.719001 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-01-29 15:12:28.736558 | controller | ok
2026-01-29 15:12:28.745634 | controller | included: /var/lib/zuul/builds/9ddf609f605b44a098ce282deb9ff662/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-01-29 15:12:28.753065 | 
2026-01-29 15:12:28.753130 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-01-29 15:12:28.766422 | controller | skipping: Conditional result was False
2026-01-29 15:12:28.771567 | 
2026-01-29 15:12:28.771635 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-01-29 15:12:29.114780 | controller | changed
2026-01-29 15:12:29.120394 | 
2026-01-29 15:12:29.120469 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-01-29 15:12:29.301485 | controller | ok
2026-01-29 15:12:29.306035 | 
2026-01-29 15:12:29.306103 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-01-29 15:12:29.840277 | controller | changed
2026-01-29 15:12:29.845014 | 
2026-01-29 15:12:29.845080 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-01-29 15:12:30.378858 | controller | changed
2026-01-29 15:12:30.383586 | 
2026-01-29 15:12:30.383661 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-01-29 15:12:30.396362 | controller | skipping: Conditional result was False
2026-01-29 15:12:30.401510 | 
2026-01-29 15:12:30.401585 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-01-29 15:12:30.687507 | controller -> localhost | changed
2026-01-29 15:12:30.697836 | 
2026-01-29 15:12:30.697914 | TASK [add-build-sshkey : Add back temp key]
2026-01-29 15:12:30.913015 | controller -> localhost | Identity added: /var/lib/zuul/builds/9ddf609f605b44a098ce282deb9ff662/work/9ddf609f605b44a098ce282deb9ff662_id_rsa (zuul-build-sshkey)
2026-01-29 15:12:30.913185 | controller -> localhost | ok: Runtime: 0:00:00.007043
2026-01-29 15:12:30.918436 | 
2026-01-29 15:12:30.918498 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-01-29 15:12:31.179022 | controller | ok
2026-01-29 15:12:31.183414 | 
2026-01-29 15:12:31.183480 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-01-29 15:12:31.206573 | controller | skipping: Conditional result was False
2026-01-29 15:12:31.214714 | 
2026-01-29 15:12:31.214776 | TASK [include_role : validate-host]
2026-01-29 15:12:31.231038 | controller | ok
2026-01-29 15:12:31.247043 | 
2026-01-29 15:12:31.247109 | TASK [validate-host : Define zuul_info_dir fact]
2026-01-29 15:12:31.274401 | controller | ok
2026-01-29 15:12:31.278611 | 
2026-01-29 15:12:31.278674 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2026-01-29 15:12:31.463573 | controller -> localhost | ok
2026-01-29 15:12:31.469021 | 
2026-01-29 15:12:31.469103 | TASK [validate-host : Collect information about the host]
2026-01-29 15:12:32.057177 | controller | ok
2026-01-29 15:12:32.064946 | 
2026-01-29 15:12:32.065012 | TASK [validate-host : Sanitize hostname]
2026-01-29 15:12:32.102009 | controller | ok
2026-01-29 15:12:32.106204 | 
2026-01-29 15:12:32.106265 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2026-01-29 15:12:32.462848 | controller -> localhost | changed
2026-01-29 15:12:32.469212 | 
2026-01-29 15:12:32.469309 | TASK [validate-host : Collect information about zuul worker]
2026-01-29 15:12:32.778711 | controller | ok
2026-01-29 15:12:32.782757 | 
2026-01-29 15:12:32.782819 | TASK [validate-host : Write out all zuul information for each host]
2026-01-29 15:12:33.109879 | controller -> localhost | changed
2026-01-29 15:12:33.118117 | 
2026-01-29 15:12:33.118176 | TASK [include_role : prepare-workspace-openshift]
2026-01-29 15:12:33.130567 | controller | skipping: Conditional result was False
2026-01-29 15:12:33.134758 | 
2026-01-29 15:12:33.134816 | TASK [include_role : remove-zuul-sshkey]
2026-01-29 15:12:33.147104 | controller | skipping: Conditional result was False
2026-01-29 15:12:33.151526 | 
2026-01-29 15:12:33.151588 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-01-29 15:12:33.329207 | controller | ok: "logs"
2026-01-29 15:12:33.329452 | controller | ok: All items complete
2026-01-29 15:12:33.329481 | 
2026-01-29 15:12:33.482969 | controller | ok: "artifacts"
2026-01-29 15:12:33.638369 | controller | ok: "docs"
2026-01-29 15:12:33.644912 | 
2026-01-29 15:12:33.645023 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-01-29 15:12:33.825625 | controller | changed: "logs"
2026-01-29 15:12:33.983851 | controller | changed: "artifacts"
2026-01-29 15:12:34.142614 | controller | changed: "docs"
2026-01-29 15:12:34.165576 | 
2026-01-29 15:12:34.165628 | PLAY RECAP
2026-01-29 15:12:34.165669 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2026-01-29 15:12:34.165697 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-01-29 15:12:34.165715 | 
2026-01-29 15:12:34.230643 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2026-01-29 15:12:34.231314 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2026-01-29 15:12:34.726728 | 
2026-01-29 15:12:34.726836 | PLAY [all]
2026-01-29 15:12:34.745065 | 
2026-01-29 15:12:34.745134 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2026-01-29 15:12:34.794806 | controller | ok
2026-01-29 15:12:34.800067 | 
2026-01-29 15:12:34.800152 | TASK [mirror-info-fork : Create /etc/ci]
2026-01-29 15:12:35.112894 | controller | changed
2026-01-29 15:12:35.118327 | 
2026-01-29 15:12:35.118411 | TASK [mirror-info-fork : Install ci_mirror script]
2026-01-29 15:12:35.846258 | controller | changed
2026-01-29 15:12:35.855527 | 
2026-01-29 15:12:35.855595 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2026-01-29 15:12:36.183043 | controller | changed:
2026-01-29 15:12:36.183263 | controller | {
2026-01-29 15:12:36.183310 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2026-01-29 15:12:36.183347 | controller | }
2026-01-29 15:12:36.368762 | controller | changed:
2026-01-29 15:12:36.368859 | controller | {
2026-01-29 15:12:36.368890 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2026-01-29 15:12:36.368912 | controller | }
2026-01-29 15:12:36.565816 | controller | changed:
2026-01-29 15:12:36.565873 | controller | {
2026-01-29 15:12:36.565899 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com"
2026-01-29 15:12:36.565919 | controller | }
2026-01-29 15:12:36.765061 | controller | changed:
2026-01-29 15:12:36.765132 | controller | {
2026-01-29 15:12:36.765166 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2026-01-29 15:12:36.765192 | controller | }
2026-01-29 15:12:36.967957 | controller | changed:
2026-01-29 15:12:36.968022 | controller | {
2026-01-29 15:12:36.968066 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2026-01-29 15:12:36.968092 | controller | }
2026-01-29 15:12:37.161938 | controller | changed:
2026-01-29 15:12:37.161993 | controller | {
2026-01-29 15:12:37.162019 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2026-01-29 15:12:37.162039 | controller | }
2026-01-29 15:12:37.350109 | controller | changed:
2026-01-29 15:12:37.350158 | controller | {
2026-01-29 15:12:37.350184 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDLOQd4ZLtkZXQGY6UwAr/06ppWQK4fDO3HaqxPk98csyOCBXsliSKK39Bso828+5srIXiW7aI6aC9P5mwi4mUZlGPfJlQbfrcGvY+b/SocuvaGK+1RrHLoJCT52LBhwgrzlXio2jeksZeein8iaTrhsPrOAs7KggIL/rB9hEiB3NaOPWhhoCP4vlW6MEMExGcqB/1FVxXFBPnLkEyW0Lk7ycVflZl2ocRxbfjZi0+tI1Wlinp8PvSQSc/WVrAcDgKjc/mB4ODPOyYy3G8FHgfMsrXSDEyjBKgLKMsdCrAUcqJQWjkqXleXSYOV4q3pzL+9umK+q/e3P/bIoSFQzmJKTU1eDfuvPXmow9F5H54fii/Da7ezlMJ+wPGHJrRAkmzvMbALy7xwswLhZMkOGNtRcPqaKYRmIBKpw3o6bCTtcNUHOtOQnzwY8JzrM2eBWJBXAANYw+9/ho80JIiwhg29CFNpVBuHbql2YxJQNrnl90guN65rYNpDxdIluweyUf8= anbanerj@kaermorhen"
2026-01-29 15:12:37.350205 | controller | }
2026-01-29 15:12:37.541899 | controller | changed:
2026-01-29 15:12:37.541950 | controller | {
2026-01-29 15:12:37.541977 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQC3VwV8Im9kRm49lt3tM36hj4Zv27FxGo4C1Q/0jqhzFmHY7RHbmeRr8ObhwWoHjXSozKWg8FL5ER0z3hTwL0W6lez3sL7hUaCmSuZmG5Hnl3x4vTSxDI9JZ/Y65rtYiiWQo2fC5xJhU/4+0e5e/pseCm8cKRSu+SaxhO+sd6FDojA2x1BzOzKiQRDy/1zWGp/cZkxcEuB1wHI5LMzN03c67vmbu+fhZRAUO4dQkvcnj2LrhQtpa+ytvnSjr8icMDosf1OsbSffwZFyHB/hfWGAfe0eIeSA2XPraxiPknXxiPKx2MJsaUTYbsZcm3EjFdHBBMumw5rBI74zLrMRvCO9GwBEmGT4rFng1nP+yw5DB8sn2zqpOsPg1LYRwCPOUveC13P6pgsZZPh812e8v5EKnETct+5XI3dVpdw6CnNiLwAyVAF15DJvBGT/u1k0Myg/bQn+Gv9k2MSj6LvQmf6WbZu2Wgjm30z3FyCneBqTL7mLF19YXzeC0ufHz5pnO1E= dasm@fedora"
2026-01-29 15:12:37.541999 | controller | }
2026-01-29 15:12:37.732152 | controller | changed:
2026-01-29 15:12:37.732210 | controller | {
2026-01-29 15:12:37.732236 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2026-01-29 15:12:37.732256 | controller | }
2026-01-29 15:12:37.921479 | controller | changed:
2026-01-29 15:12:37.921548 | controller | {
2026-01-29 15:12:37.921582 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2026-01-29 15:12:37.921602 | controller | }
2026-01-29 15:12:38.111641 | controller | changed:
2026-01-29 15:12:38.111692 | controller | {
2026-01-29 15:12:38.111718 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2026-01-29 15:12:38.111737 | controller | }
2026-01-29 15:12:38.318057 | controller | changed:
2026-01-29 15:12:38.318128 | controller | {
2026-01-29 15:12:38.318155 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2026-01-29 15:12:38.318176 | controller | }
2026-01-29 15:12:38.489718 | controller | changed:
2026-01-29 15:12:38.489773 | controller | {
2026-01-29 15:12:38.489799 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2026-01-29 15:12:38.489818 | controller | }
2026-01-29 15:12:38.681048 | controller | changed:
2026-01-29 15:12:38.681108 | controller | {
2026-01-29 15:12:38.681135 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2026-01-29 15:12:38.681155 | controller | }
2026-01-29 15:12:38.868212 | controller | changed:
2026-01-29 15:12:38.868260 | controller | {
2026-01-29 15:12:38.868286 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2026-01-29 15:12:38.868327 | controller | }
2026-01-29 15:12:39.055007 | controller | changed:
2026-01-29 15:12:39.055055 | controller | {
2026-01-29 15:12:39.055080 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2026-01-29 15:12:39.055099 | controller | }
2026-01-29 15:12:39.247582 | controller | changed:
2026-01-29 15:12:39.247631 | controller | {
2026-01-29 15:12:39.247655 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQCfNtF7NvKl915TGsGGoseUb06Hj8L/S4toWf0hExeY+F00woL6NvBlJD0nDct+P5a22I4EhvoQCRQ8reaPCm1lybR3uiRIJsj+8zkVvLwby9LXzfZorlNG9ofjd00FEmB09uW/YvTl6Q9XwwwX6tInzIOv3TMqTHHGOL74ibbj8J/FJR0cFEyj0z4WQRvtkh32xAHl83gbuINryMt0sqRI+clj2381NKL55DRLQrVw0gsfqqxiHAnXg21qWmc4J+b9e9kiuAFQjcjwTVkwJCcg3xbPwC/qokYRby/Y5S40UUd7/jEARGXT7RZgpzTuDd1oZiCVrnrqJNPaMNdVv5MLeFdf1B7iIe5aa/fGouX7AO4SdKhZUdnJmCFAGvjC6S3JMZ2wAcUl+OHnssfmdj7XL50cLo27vjuzMtLAgSqi6N99m92WCF2s8J9aVzszX7Xz9OKZCeGsiVJp3/NdABKzSEAyM9xBD/5Vho894Sav+otpySHe3p6RUTgbB5Zu8VyZRZ/UtB3ueXxyo764yrc6qWIDqrehm84Xm9g+/jpIBzGPl07NUNJpdt/6Sgf9RIKXw/7XypO5yZfUcuFNGTxLfqjTNrtgLZNcjfav6sSdVXVcMPL//XNuRdKmVFaO76eV/oGMQGr1fGcCD+N+CpI7+Q+fCNB6VFWG4nZFuI/Iuw== averdagu@redhat.com"
2026-01-29 15:12:39.247675 | controller | }
2026-01-29 15:12:39.462099 | controller | changed:
2026-01-29 15:12:39.462151 | controller | {
2026-01-29 15:12:39.462176 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDq8l27xI+QlQVdS4djp9ogSoyrNE2+Ox6vKPdhSNL1J3PE5w+WCSvMz9A5gnNuH810zwbekEApbxTze/gLQJwBHA52CChfURpXrFaxY7ePXRElwKAL3mJfzBWY/c5jnNL9TCVmFJTGZkFZP3Nh+BMgZvL6xBkt3WKm6Uq18qzd9XeKcZusrA+O+uLv1fVeQnadY9RIqOCyeFYCzLWrUfTyE8x/XG0hAWIM7qpnF2cALQS2h9n4hW5ybiUN790H08wf9hFwEf5nxY9Z9dVkPFQiTSGKNBzmnCXU9skxS/xhpFjJ5duGSZdtAHe9O+nGZm9c67hxgtf8e5PDuqAdXEv2cf6e3VBAt+Bz8EKI3yosTj0oZHfwr42Yzb1l/SKy14Rggsrc9KAQlrGXan6+u2jcQqqx7l+SWmnpFiWTV9u5cWj2IgOhApOitmRBPYqk9rE2usfO0hLn/Pj/R/Nau4803e1/EikdLE7Ps95s9mX5jRDjAoUa2JwFF5RsVFyL910= ashigupt@ashigupt.remote.csb"
2026-01-29 15:12:39.462204 | controller | }
2026-01-29 15:12:39.657004 | controller | changed:
2026-01-29 15:12:39.657092 | controller | {
2026-01-29 15:12:39.657118 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2026-01-29 15:12:39.657137 | controller | }
2026-01-29 15:12:39.853613 | controller | changed:
2026-01-29 15:12:39.853663 | controller | {
2026-01-29 15:12:39.853689 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2026-01-29 15:12:39.853709 | controller | }
2026-01-29 15:12:40.056211 | controller | changed:
2026-01-29 15:12:40.056270 | controller | {
2026-01-29 15:12:40.056312 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2026-01-29 15:12:40.056338 | controller | }
2026-01-29 15:12:40.253946 | controller | changed:
2026-01-29 15:12:40.253997 | controller | {
2026-01-29 15:12:40.254022 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2026-01-29 15:12:40.254041 | controller | }
2026-01-29 15:12:40.447086 | controller | changed:
2026-01-29 15:12:40.447144 | controller | {
2026-01-29 15:12:40.447178 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQDZdI7t1cxYx65heVI24HTV4F7oQLW1zyfxHreL2TIJKxjyrUUKIFEUmTutcBlJRLNT2Eoix6x1sOw9YrchloCLcn//SGfTElr9mSc5jbjb7QXEU+zJMhtxyEJ1Po3CUGnj7ckiIXw7wcawZtrEOAQ9pH3ExYCJcEMiyNjRQZCxT3tPK+S4B95EWh5Fsrz9CkwpjNRPPH7LigCeQTM3Wc7r97utAslBUUvYceDSLA7rMgkitJE38b7rZBeYzsGQ8YYUBjTCtehqQXxCRjizbHWaaZkBU+N3zkKB6n/iCNGIO690NK7A/qb6msTijiz1PeuM8ThOsi9qXnbX5v0PoTpcFSojV7NHAQ71f0XXuS43FhZctT+Dcx44dT8Fb5vJu2cJGrk+qF8ZgJYNpRS7gPg0EG2EqjK7JMf9ULdjSu0r+KlqIAyLvtzT4eOnQipoKlb/WG5D/0ohKv7OMQ352ggfkBFIQsRXyyTCT98Ft9juqPuahi3CAQmP4H9dyE+7+Kz437PEtsxLmfm6naNmWi7Ee1DqWPwS8rEajsm4sNM4wW9gdBboJQtc0uZw0DfLj1I9r3Mc8Ol0jYtz0yNQDSzVLrGCaJlC311trU70tZ+ZkAVV6Mn8lOhSbj1cK0lvSr6ZK4dgqGl3I1eTZJJhbLNdg7UOVaiRx9543+C/p/As7w== brjackma@redhat.com"
2026-01-29 15:12:40.447205 | controller | }
2026-01-29 15:12:40.642383 | controller | changed:
2026-01-29 15:12:40.642433 | controller | {
2026-01-29 15:12:40.642458 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2026-01-29 15:12:40.642477 | controller | }
2026-01-29 15:12:40.837071 | controller | changed:
2026-01-29 15:12:40.837162 | controller | {
2026-01-29 15:12:40.837190 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2026-01-29 15:12:40.837210 | controller | }
2026-01-29 15:12:41.030394 | controller | changed:
2026-01-29 15:12:41.030454 | controller | {
2026-01-29 15:12:41.030479 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2026-01-29 15:12:41.030498 | controller | }
2026-01-29 15:12:41.049042 | 
2026-01-29 15:12:41.049154 | TASK [Set timezone to UTC]
2026-01-29 15:12:41.421992 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2026-01-29 15:12:41.426758 | 
2026-01-29 15:12:41.426831 | TASK [Create nodepool directory]
2026-01-29 15:12:41.628074 | controller | changed
2026-01-29 15:12:41.633371 | 
2026-01-29 15:12:41.633471 | TASK [Create nodepool sub_nodes file]
2026-01-29 15:12:42.117427 | controller | changed
2026-01-29 15:12:42.122195 | 
2026-01-29 15:12:42.122258 | TASK [Create nodepool sub_nodes_private file]
2026-01-29 15:12:42.597452 | controller | changed
2026-01-29 15:12:42.602866 | 
2026-01-29 15:12:42.602936 | LOOP [Populate nodepool sub_nodes file]
2026-01-29 15:12:42.631988 | 
2026-01-29 15:12:42.632112 | LOOP [Populate nodepool sub_nodes_private file]
2026-01-29 15:12:42.660999 | 
2026-01-29 15:12:42.661125 | TASK [Create nodepool primary file]
2026-01-29 15:12:42.684622 | controller | skipping: Conditional result was False
2026-01-29 15:12:42.689650 | 
2026-01-29 15:12:42.689718 | TASK [Create nodepool node_private for this node]
2026-01-29 15:12:43.213843 | controller | changed
2026-01-29 15:12:43.219088 | 
2026-01-29 15:12:43.219149 | LOOP [Copy ssh keys to nodepool directory]
2026-01-29 15:12:43.532368 | controller | ok: Item: id_rsa Runtime: 0:00:00.004752
2026-01-29 15:12:43.532587 | 
2026-01-29 15:12:43.685276 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.004104
2026-01-29 15:12:43.692472 | 
2026-01-29 15:12:43.692560 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2026-01-29 15:12:44.198413 | controller | changed
2026-01-29 15:12:44.203229 | 
2026-01-29 15:12:44.203393 | TASK [Validate sudoers config after edits]
2026-01-29 15:12:44.410694 | controller | /etc/sudoers: parsed OK
2026-01-29 15:12:44.410735 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2026-01-29 15:12:44.410743 | controller | /etc/sudoers.d/zuul: parsed OK
2026-01-29 15:12:44.410749 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2026-01-29 15:12:44.728941 | controller | ok: Runtime: 0:00:00.005329
2026-01-29 15:12:44.734614 | 
2026-01-29 15:12:44.734678 | TASK [Show the environment passed in to job shell scripts]
2026-01-29 15:12:44.926271 | controller | SHELL=/bin/bash
2026-01-29 15:12:44.926306 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2026-01-29 15:12:44.926316 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2026-01-29 15:12:44.926322 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/31/3631/e607c8c2eddb1497e083950ac717270349d67b76
2026-01-29 15:12:44.926328 | controller | PWD=/home/zuul
2026-01-29 15:12:44.926333 | controller | ZUUL_PIPELINE=github-check
2026-01-29 15:12:44.926353 | controller | LOGNAME=zuul
2026-01-29 15:12:44.926391 | controller | XDG_SESSION_TYPE=tty
2026-01-29 15:12:44.926399 | controller | _=/usr/bin/env
2026-01-29 15:12:44.926405 | controller | MOTD_SHOWN=pam
2026-01-29 15:12:44.926410 | controller | HOME=/home/zuul
2026-01-29 15:12:44.926415 | controller | LANG=en_US.UTF-8
2026-01-29 15:12:44.926421 | controller | SSH_CONNECTION=192.168.26.12 51188 192.168.26.39 22
2026-01-29 15:12:44.926426 | controller | TOX_TESTENV_PASSENV=ZUUL_UUID ZUUL_PROJECT ZUUL_SHORT_PROJECT_NAME ZUUL_PIPELINE ZUUL_VOTING WORKSPACE BUILD_TIMEOUT ZUUL_BRANCH ZUUL_CHANGES ZUUL_CHANGE_IDS ZUUL_CHANGE ZUUL_PATCHSET
2026-01-29 15:12:44.926434 | controller | ZUUL_CHANGE_IDS=3631,e607c8c2eddb1497e083950ac717270349d67b76
2026-01-29 15:12:44.926439 | controller | WORKSPACE=/home/zuul/workspace
2026-01-29 15:12:44.926445 | controller | XDG_SESSION_CLASS=user
2026-01-29 15:12:44.926450 | controller | SELINUX_ROLE_REQUESTED=
2026-01-29 15:12:44.926456 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2026-01-29 15:12:44.926461 | controller | USER=zuul
2026-01-29 15:12:44.926466 | controller | ZUUL_VOTING=True
2026-01-29 15:12:44.926471 | controller | BUILD_TIMEOUT=1800000
2026-01-29 15:12:44.926476 | controller | SELINUX_USE_CURRENT_RANGE=
2026-01-29 15:12:44.926482 | controller | SHLVL=1
2026-01-29 15:12:44.926487 | controller | ZUUL_PATCHSET=e607c8c2eddb1497e083950ac717270349d67b76
2026-01-29 15:12:44.926492 | controller | XDG_SESSION_ID=1
2026-01-29 15:12:44.926498 | controller | ZUUL_BRANCH=main
2026-01-29 15:12:44.926503 | controller | XDG_RUNTIME_DIR=/run/user/1000
2026-01-29 15:12:44.926508 | controller | SSH_CLIENT=192.168.26.12 51188 22
2026-01-29 15:12:44.926515 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2026-01-29 15:12:44.926520 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2026-01-29 15:12:44.926527 | controller | which_declare=declare -f
2026-01-29 15:12:44.926533 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2026-01-29 15:12:44.926538 | controller | SELINUX_LEVEL_REQUESTED=
2026-01-29 15:12:44.926543 | controller | ZUUL_CHANGE=3631
2026-01-29 15:12:44.926549 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2026-01-29 15:12:44.926554 | controller | ZUUL_UUID=9ddf609f605b44a098ce282deb9ff662
2026-01-29 15:12:44.926559 | controller | BASH_FUNC_which%%=() {  ( alias;
2026-01-29 15:12:44.926565 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2026-01-29 15:12:44.926570 | controller | }
2026-01-29 15:12:45.259469 | controller | ok: Runtime: 0:00:00.004857
2026-01-29 15:12:45.264530 | 
2026-01-29 15:12:45.264586 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2026-01-29 15:12:45.277843 | controller | skipping: Conditional result was False
2026-01-29 15:12:45.282833 | 
2026-01-29 15:12:45.282897 | TASK [Symlink /home/zuul-worker/workspace]
2026-01-29 15:12:45.806682 | controller | skipping: Conditional result was False
2026-01-29 15:12:45.812108 | 
2026-01-29 15:12:45.812172 | TASK [Ensure legacy workspace directory]
2026-01-29 15:12:45.992620 | controller | changed
2026-01-29 15:12:46.009835 | 
2026-01-29 15:12:46.009891 | PLAY RECAP
2026-01-29 15:12:46.009935 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2026-01-29 15:12:46.009958 | 
2026-01-29 15:12:46.096807 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2026-01-29 15:12:46.097560 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2026-01-29 15:12:46.589071 | 
2026-01-29 15:12:46.589164 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2026-01-29 15:12:46.608802 | 
2026-01-29 15:12:46.608876 | TASK [Create zuul-output directory]
2026-01-29 15:12:46.910819 | controller | changed
2026-01-29 15:12:46.915671 | 
2026-01-29 15:12:46.915751 | TASK [Slurp Zuul inventory test]
2026-01-29 15:12:47.171851 | controller -> localhost | ok
2026-01-29 15:12:47.177547 | 
2026-01-29 15:12:47.177618 | TASK [Save zuul inventory]
2026-01-29 15:12:47.885026 | controller | changed
2026-01-29 15:12:47.889762 | 
2026-01-29 15:12:47.889830 | TASK [Save zuul vars without the change_message]
2026-01-29 15:12:48.494656 | controller | changed
2026-01-29 15:12:48.511865 | 
2026-01-29 15:12:48.511935 | PLAY RECAP
2026-01-29 15:12:48.511984 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-01-29 15:12:48.512014 | 
2026-01-29 15:12:48.575679 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2026-01-29 15:12:48.576424 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2026-01-29 15:12:49.101190 | 
2026-01-29 15:12:49.101322 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2026-01-29 15:12:49.122420 | 
2026-01-29 15:12:49.122501 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2026-01-29 15:12:49.140895 | controller | ok
2026-01-29 15:12:49.158449 | 
2026-01-29 15:12:49.158537 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2026-01-29 15:12:49.182387 | controller | skipping: Conditional result was False
2026-01-29 15:12:49.188622 | 
2026-01-29 15:12:49.188703 | TASK [mirror-info-fork : Create /etc/ci]
2026-01-29 15:12:49.550446 | controller | ok
2026-01-29 15:12:49.556144 | 
2026-01-29 15:12:49.556215 | TASK [mirror-info-fork : Install ci_mirror script]
2026-01-29 15:12:50.152424 | controller | ok
2026-01-29 15:12:50.162116 | 
2026-01-29 15:12:50.162179 | TASK [Prepare workspace]
2026-01-29 15:12:50.179732 | controller | ok
2026-01-29 15:12:50.195543 | 
2026-01-29 15:12:50.195611 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-01-29 15:12:50.476596 | controller | ok
2026-01-29 15:12:50.482871 | 
2026-01-29 15:12:50.482938 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-01-29 15:12:52.997401 | controller | Output suppressed because no_log was given
2026-01-29 15:12:53.006597 | 
2026-01-29 15:12:53.006661 | LOOP [Create zuul-output directory]
2026-01-29 15:12:53.189859 | controller | changed: "/home/zuul/ci-framework-data/logs"
2026-01-29 15:12:53.341507 | controller | ok: "/home/zuul/zuul-output/logs"
2026-01-29 15:12:53.353055 | 
2026-01-29 15:12:53.353141 | TASK [Install required packages]
2026-01-29 15:13:26.297563 | controller | changed
2026-01-29 15:13:26.302423 | 
2026-01-29 15:13:26.302485 | TASK [Install venv]
2026-01-29 15:14:30.273018 | controller | changed
2026-01-29 15:14:30.296143 | 
2026-01-29 15:14:30.296216 | PLAY RECAP
2026-01-29 15:14:30.296262 | controller | ok: 7 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-01-29 15:14:30.296284 | 
2026-01-29 15:14:30.360235 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2026-01-29 15:14:30.361073 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2026-01-29 15:14:30.849843 | 
2026-01-29 15:14:30.849940 | PLAY [Run ci/playbooks/molecule-test.yml]
2026-01-29 15:14:30.869106 | 
2026-01-29 15:14:30.869187 | TASK [Gather required facts]
2026-01-29 15:14:31.313829 | controller | ok
2026-01-29 15:14:31.319050 | 
2026-01-29 15:14:31.319127 | TASK [Load environment var if instructed to]
2026-01-29 15:14:31.342435 | controller | skipping: Conditional result was False
2026-01-29 15:14:31.347514 | 
2026-01-29 15:14:31.347577 | TASK [Ensure group_vars dir exists]
2026-01-29 15:14:31.638874 | controller | ok
2026-01-29 15:14:31.644555 | 
2026-01-29 15:14:31.644621 | TASK [Print related variables]
2026-01-29 15:14:31.672718 | controller | ok:
2026-01-29 15:14:31.672826 | controller | mol_config_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config_local.yml
2026-01-29 15:14:31.672855 | controller | roles_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/adoption_osp_deploy
2026-01-29 15:14:31.677413 | 
2026-01-29 15:14:31.677475 | TASK [Run molecule]
2026-01-29 15:14:32.567833 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2026-01-29 15:14:32.632088 | controller | WARNING  Driver podman does not provide a schema.
2026-01-29 15:14:32.634478 | controller | INFO     Performing prerun with role_name_check=0...
2026-01-29 15:14:49.460671 | 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).
2026-01-29 15:14:49.461063 | 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).
2026-01-29 15:14:49.461482 | 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).
2026-01-29 15:14:49.461893 | 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).
2026-01-29 15:14:49.462304 | controller | WARNING  Another version of 'cifmw.general' 1.0.0+7ff569c0 was found installed in /home/zuul/.cache/ansible-compat/82d78a/collections/ansible_collections, only the first one will be used, 1.0.0+7ff569c0 (/home/zuul/.ansible/collections/ansible_collections).
2026-01-29 15:14:49.462714 | 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).
2026-01-29 15:14:49.463107 | 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).
2026-01-29 15:14:49.463501 | 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).
2026-01-29 15:14:49.463889 | 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).
2026-01-29 15:14:49.464272 | 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).
2026-01-29 15:14:49.464674 | 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).
2026-01-29 15:14:49.465072 | 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).
2026-01-29 15:14:49.465473 | 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).
2026-01-29 15:14:49.465884 | 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).
2026-01-29 15:14:49.466266 | 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).
2026-01-29 15:14:49.475204 | controller | INFO     Running default > destroy
2026-01-29 15:14:49.688336 | controller | INFO     Sanity checks: 'podman'
2026-01-29 15:14:50.495993 | controller |
2026-01-29 15:14:50.496071 | controller | PLAY [Destroy] *****************************************************************
2026-01-29 15:14:50.496159 | controller |
2026-01-29 15:14:50.496252 | controller | TASK [Get passwd entries for USER env] *****************************************
2026-01-29 15:14:50.496352 | controller | Thursday 29 January 2026  15:14:50 +0000 (0:00:00.013)       0:00:00.013 ******
2026-01-29 15:14:50.784211 | controller | ok: [localhost]
2026-01-29 15:14:50.784272 | controller |
2026-01-29 15:14:50.784427 | controller | TASK [Get shell async_dir] *****************************************************
2026-01-29 15:14:50.784524 | controller | Thursday 29 January 2026  15:14:50 +0000 (0:00:00.288)       0:00:00.302 ******
2026-01-29 15:14:50.825354 | controller | ok: [localhost]
2026-01-29 15:14:50.825484 | controller |
2026-01-29 15:14:50.825611 | controller | TASK [Set async_dir for HOME env] **********************************************
2026-01-29 15:14:50.825745 | controller | Thursday 29 January 2026  15:14:50 +0000 (0:00:00.041)       0:00:00.343 ******
2026-01-29 15:14:50.856165 | controller | skipping: [localhost]
2026-01-29 15:14:50.856254 | controller |
2026-01-29 15:14:50.856380 | controller | TASK [Destroy molecule instance(s)] ********************************************
2026-01-29 15:14:50.856490 | controller | Thursday 29 January 2026  15:14:50 +0000 (0:00:00.030)       0:00:00.374 ******
2026-01-29 15:14:51.211943 | controller | changed: [localhost] => (item={'command': '/sbin/init', 'dockerfile': 'containerfile', 'image': 'registry.access.redhat.com/ubi9/ubi-init', 'name': 'instance', 'pre_build_image': True})
2026-01-29 15:14:51.211990 | controller |
2026-01-29 15:14:51.212087 | controller | TASK [Wait for instance(s) deletion to complete] *******************************
2026-01-29 15:14:51.217046 | controller | Thursday 29 January 2026  15:14:51 +0000 (0:00:00.355)       0:00:00.729 ******
2026-01-29 15:14:56.656562 | controller | FAILED - RETRYING: [localhost]: Wait for instance(s) deletion to complete (300 retries left).
2026-01-29 15:14:56.656636 | controller | changed: [localhost] => (item={'failed': 0, 'started': 1, 'finished': 0, 'ansible_job_id': 'j287179358604.29457', 'results_file': '/home/zuul/.ansible_async/j287179358604.29457', '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'})
2026-01-29 15:14:56.656721 | controller |
2026-01-29 15:14:56.656830 | controller | TASK [Delete podman network dedicated to this scenario] ************************
2026-01-29 15:14:56.656924 | controller | Thursday 29 January 2026  15:14:56 +0000 (0:00:05.444)       0:00:06.174 ******
2026-01-29 15:14:56.706332 | controller | skipping: [localhost] => (item=instance: None specified)
2026-01-29 15:14:56.706452 | controller | skipping: [localhost]
2026-01-29 15:14:56.706539 | controller |
2026-01-29 15:14:56.706634 | controller | PLAY RECAP *********************************************************************
2026-01-29 15:14:56.706729 | controller | localhost                  : ok=4    changed=2    unreachable=0    failed=0    skipped=2    rescued=0    ignored=0
2026-01-29 15:14:56.706823 | controller |
2026-01-29 15:14:56.706919 | controller | Thursday 29 January 2026  15:14:56 +0000 (0:00:00.050)       0:00:06.224 ******
2026-01-29 15:14:56.706997 | controller | ===============================================================================
2026-01-29 15:14:56.707085 | controller | Wait for instance(s) deletion to complete ------------------------------- 5.44s
2026-01-29 15:14:56.707171 | controller | Destroy molecule instance(s) -------------------------------------------- 0.36s
2026-01-29 15:14:56.707255 | controller | Get passwd entries for USER env ----------------------------------------- 0.29s
2026-01-29 15:14:56.707354 | controller | Delete podman network dedicated to this scenario ------------------------ 0.05s
2026-01-29 15:14:56.707446 | controller | Get shell async_dir ----------------------------------------------------- 0.04s
2026-01-29 15:14:56.707535 | controller | Set async_dir for HOME env ---------------------------------------------- 0.03s
2026-01-29 15:14:56.758078 | controller | INFO     Running default > create
2026-01-29 15:14:57.164719 | controller |
2026-01-29 15:14:57.164786 | controller | PLAY [Create] ******************************************************************
2026-01-29 15:14:57.164872 | controller |
2026-01-29 15:14:57.164976 | controller | TASK [Get podman executable path] **********************************************
2026-01-29 15:14:57.165059 | controller | Thursday 29 January 2026  15:14:57 +0000 (0:00:00.014)       0:00:00.014 ******
2026-01-29 15:14:57.451034 | controller | ok: [localhost]
2026-01-29 15:14:57.451121 | controller |
2026-01-29 15:14:57.451224 | controller | TASK [Register podman executable path] *****************************************
2026-01-29 15:14:57.451327 | controller | Thursday 29 January 2026  15:14:57 +0000 (0:00:00.286)       0:00:00.300 ******
2026-01-29 15:14:57.490613 | controller | ok: [localhost]
2026-01-29 15:14:57.490700 | controller |
2026-01-29 15:14:57.490817 | controller | TASK [Get passwd entries for USER env] *****************************************
2026-01-29 15:14:57.490917 | controller | Thursday 29 January 2026  15:14:57 +0000 (0:00:00.039)       0:00:00.340 ******
2026-01-29 15:14:57.777953 | controller | ok: [localhost]
2026-01-29 15:14:57.778021 | controller |
2026-01-29 15:14:57.778128 | controller | TASK [Get shell async_dir] *****************************************************
2026-01-29 15:14:57.778226 | controller | Thursday 29 January 2026  15:14:57 +0000 (0:00:00.287)       0:00:00.627 ******
2026-01-29 15:14:57.819750 | controller | ok: [localhost]
2026-01-29 15:14:57.819839 | controller |
2026-01-29 15:14:57.819938 | controller | TASK [Set async_dir for HOME env] **********************************************
2026-01-29 15:14:57.820036 | controller | Thursday 29 January 2026  15:14:57 +0000 (0:00:00.041)       0:00:00.669 ******
2026-01-29 15:14:57.852949 | controller | skipping: [localhost]
2026-01-29 15:14:57.853021 | controller |
2026-01-29 15:14:57.853110 | controller | TASK [Log into a container registry] *******************************************
2026-01-29 15:14:57.853198 | controller | Thursday 29 January 2026  15:14:57 +0000 (0:00:00.033)       0:00:00.702 ******
2026-01-29 15:14:57.895435 | controller | skipping: [localhost] => (item="instance registry username: None specified")
2026-01-29 15:14:57.895524 | controller | skipping: [localhost]
2026-01-29 15:14:57.895606 | controller |
2026-01-29 15:14:57.895696 | controller | TASK [Check presence of custom Dockerfiles] ************************************
2026-01-29 15:14:57.895820 | controller | Thursday 29 January 2026  15:14:57 +0000 (0:00:00.042)       0:00:00.745 ******
2026-01-29 15:14:58.196819 | controller | ok: [localhost] => (item=Dockerfile: containerfile)
2026-01-29 15:14:58.196875 | controller |
2026-01-29 15:14:58.196988 | controller | TASK [Create Dockerfiles from image names] *************************************
2026-01-29 15:14:58.197081 | controller | Thursday 29 January 2026  15:14:58 +0000 (0:00:00.301)       0:00:01.046 ******
2026-01-29 15:14:58.237085 | controller | skipping: [localhost] => (item="Dockerfile: containerfile; Image: registry.access.redhat.com/ubi9/ubi-init")
2026-01-29 15:14:58.237180 | controller | skipping: [localhost]
2026-01-29 15:14:58.237271 | controller |
2026-01-29 15:14:58.237386 | controller | TASK [Discover local Podman images] ********************************************
2026-01-29 15:14:58.237485 | controller | Thursday 29 January 2026  15:14:58 +0000 (0:00:00.040)       0:00:01.086 ******
2026-01-29 15:14:58.575291 | controller | ok: [localhost] => (item=instance)
2026-01-29 15:14:58.575384 | controller |
2026-01-29 15:14:58.575494 | controller | TASK [Build an Ansible compatible image] ***************************************
2026-01-29 15:14:58.575604 | controller | Thursday 29 January 2026  15:14:58 +0000 (0:00:00.337)       0:00:01.424 ******
2026-01-29 15:14:58.616945 | controller | skipping: [localhost] => (item=registry.access.redhat.com/ubi9/ubi-init)
2026-01-29 15:14:58.617030 | controller | skipping: [localhost]
2026-01-29 15:14:58.617114 | controller |
2026-01-29 15:14:58.617208 | controller | TASK [Determine the CMD directives] ********************************************
2026-01-29 15:14:58.617296 | controller | Thursday 29 January 2026  15:14:58 +0000 (0:00:00.041)       0:00:01.466 ******
2026-01-29 15:14:58.664439 | controller | ok: [localhost] => (item="instance command: /sbin/init")
2026-01-29 15:14:58.664522 | controller |
2026-01-29 15:14:58.664626 | controller | TASK [Remove possible pre-existing containers] *********************************
2026-01-29 15:14:58.664723 | controller | Thursday 29 January 2026  15:14:58 +0000 (0:00:00.047)       0:00:01.514 ******
2026-01-29 15:14:58.908015 | controller | changed: [localhost]
2026-01-29 15:14:58.908083 | controller |
2026-01-29 15:14:58.908327 | controller | TASK [Discover local podman networks] ******************************************
2026-01-29 15:14:58.952983 | controller | Thursday 29 January 2026  15:14:58 +0000 (0:00:00.243)       0:00:01.757 ******
2026-01-29 15:14:58.953020 | controller | skipping: [localhost] => (item=instance: None specified)
2026-01-29 15:14:58.989127 | controller | skipping: [localhost]
2026-01-29 15:14:58.989149 | controller |
2026-01-29 15:14:58.989157 | controller | TASK [Create podman network dedicated to this scenario] ************************
2026-01-29 15:14:58.989163 | controller | Thursday 29 January 2026  15:14:58 +0000 (0:00:00.044)       0:00:01.802 ******
2026-01-29 15:14:58.989172 | controller | skipping: [localhost]
2026-01-29 15:14:59.302678 | controller |
2026-01-29 15:14:59.302701 | controller | TASK [Create molecule instance(s)] *********************************************
2026-01-29 15:14:59.302708 | controller | Thursday 29 January 2026  15:14:58 +0000 (0:00:00.036)       0:00:01.838 ******
2026-01-29 15:14:59.302718 | controller | changed: [localhost] => (item=instance)
2026-01-29 15:15:04.761051 | controller |
2026-01-29 15:15:04.761083 | controller | TASK [Wait for instance(s) creation to complete] *******************************
2026-01-29 15:15:04.761091 | controller | Thursday 29 January 2026  15:14:59 +0000 (0:00:00.313)       0:00:02.152 ******
2026-01-29 15:15:04.761101 | controller | FAILED - RETRYING: [localhost]: Wait for instance(s) creation to complete (300 retries left).
2026-01-29 15:15:04.811425 | controller | changed: [localhost] => (item=instance)
2026-01-29 15:15:04.811457 | controller |
2026-01-29 15:15:04.811464 | controller | PLAY RECAP *********************************************************************
2026-01-29 15:15:04.811471 | controller | localhost                  : ok=10   changed=3    unreachable=0    failed=0    skipped=6    rescued=0    ignored=0
2026-01-29 15:15:04.811477 | controller |
2026-01-29 15:15:04.811482 | controller | Thursday 29 January 2026  15:15:04 +0000 (0:00:05.456)       0:00:07.609 ******
2026-01-29 15:15:04.811487 | controller | ===============================================================================
2026-01-29 15:15:04.811492 | controller | Wait for instance(s) creation to complete ------------------------------- 5.46s
2026-01-29 15:15:04.811497 | controller | Discover local Podman images -------------------------------------------- 0.34s
2026-01-29 15:15:04.811502 | controller | Create molecule instance(s) --------------------------------------------- 0.31s
2026-01-29 15:15:04.811507 | controller | Check presence of custom Dockerfiles ------------------------------------ 0.30s
2026-01-29 15:15:04.811512 | controller | Get passwd entries for USER env ----------------------------------------- 0.29s
2026-01-29 15:15:04.811517 | controller | Get podman executable path ---------------------------------------------- 0.29s
2026-01-29 15:15:04.811522 | controller | Remove possible pre-existing containers --------------------------------- 0.24s
2026-01-29 15:15:04.811527 | controller | Determine the CMD directives -------------------------------------------- 0.05s
2026-01-29 15:15:04.811532 | controller | Discover local podman networks ------------------------------------------ 0.04s
2026-01-29 15:15:04.811537 | controller | Log into a container registry ------------------------------------------- 0.04s
2026-01-29 15:15:04.811542 | controller | Build an Ansible compatible image --------------------------------------- 0.04s
2026-01-29 15:15:04.811547 | controller | Get shell async_dir ----------------------------------------------------- 0.04s
2026-01-29 15:15:04.811553 | controller | Create Dockerfiles from image names ------------------------------------- 0.04s
2026-01-29 15:15:04.811558 | controller | Register podman executable path ----------------------------------------- 0.04s
2026-01-29 15:15:04.811562 | controller | Create podman network dedicated to this scenario ------------------------ 0.04s
2026-01-29 15:15:04.811567 | controller | Set async_dir for HOME env ---------------------------------------------- 0.03s
2026-01-29 15:15:04.811577 | controller | INFO     Running default > prepare
2026-01-29 15:15:04.811998 | controller | WARNING  Skipping, prepare playbook not configured.
2026-01-29 15:15:04.819304 | controller | INFO     Running default > converge
2026-01-29 15:15:05.229923 | controller |
2026-01-29 15:15:05.230144 | controller | PLAY [Converge] ****************************************************************
2026-01-29 15:15:05.230279 | controller |
2026-01-29 15:15:05.230446 | controller | TASK [Gather stack nodes and facts] ********************************************
2026-01-29 15:15:05.230586 | controller | Thursday 29 January 2026  15:15:05 +0000 (0:00:00.018)       0:00:00.018 ******
2026-01-29 15:15:05.260487 | 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']})
2026-01-29 15:15:05.260853 | 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']})
2026-01-29 15:15:05.260873 | 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']})
2026-01-29 15:15:05.293767 | controller |
2026-01-29 15:15:05.293809 | controller | TASK [Get main facts for the overcloud stack] **********************************
2026-01-29 15:15:05.293818 | controller | Thursday 29 January 2026  15:15:05 +0000 (0:00:00.030)       0:00:00.049 ******
2026-01-29 15:15:05.293827 | controller | ok: [instance]
2026-01-29 15:15:05.378238 | controller |
2026-01-29 15:15:05.378268 | controller | TASK [Gather other facts for stack overcloud"] *********************************
2026-01-29 15:15:05.378277 | controller | Thursday 29 January 2026  15:15:05 +0000 (0:00:00.033)       0:00:00.082 ******
2026-01-29 15:15:05.378287 | controller | ok: [instance]
2026-01-29 15:15:05.439326 | controller |
2026-01-29 15:15:05.439367 | controller | TASK [Gather nodes for stack overcloud] ****************************************
2026-01-29 15:15:05.439376 | controller | Thursday 29 January 2026  15:15:05 +0000 (0:00:00.084)       0:00:00.167 ******
2026-01-29 15:15:05.439387 | controller | skipping: [instance] => (item=cell1-osp-computes)
2026-01-29 15:15:05.469333 | controller | skipping: [instance] => (item=cell1-osp-controllers)
2026-01-29 15:15:05.469370 | controller | skipping: [instance] => (item=cell2-osp-computes)
2026-01-29 15:15:05.469378 | controller | skipping: [instance] => (item=cell2-osp-controllers)
2026-01-29 15:15:05.469384 | controller | skipping: [instance] => (item=controllers)
2026-01-29 15:15:05.469390 | controller | skipping: [instance] => (item=ocps)
2026-01-29 15:15:05.469395 | controller | ok: [instance] => (item=osp-controllers)
2026-01-29 15:15:05.469400 | controller | skipping: [instance] => (item=osp-underclouds)
2026-01-29 15:15:05.469406 | controller |
2026-01-29 15:15:05.469411 | controller | TASK [Get main facts for the overcloud stack] **********************************
2026-01-29 15:15:05.469417 | controller | Thursday 29 January 2026  15:15:05 +0000 (0:00:00.060)       0:00:00.227 ******
2026-01-29 15:15:05.469426 | controller | ok: [instance]
2026-01-29 15:15:05.538417 | controller |
2026-01-29 15:15:05.538448 | controller | TASK [Gather other facts for stack cell1"] *************************************
2026-01-29 15:15:05.538458 | controller | Thursday 29 January 2026  15:15:05 +0000 (0:00:00.030)       0:00:00.258 ******
2026-01-29 15:15:05.538471 | controller | ok: [instance]
2026-01-29 15:15:05.538580 | controller |
2026-01-29 15:15:05.538598 | controller | TASK [Gather nodes for stack cell1] ********************************************
2026-01-29 15:15:05.604141 | controller | Thursday 29 January 2026  15:15:05 +0000 (0:00:00.069)       0:00:00.327 ******
2026-01-29 15:15:05.604165 | controller | ok: [instance] => (item=cell1-osp-computes)
2026-01-29 15:15:05.634404 | controller | ok: [instance] => (item=cell1-osp-controllers)
2026-01-29 15:15:05.634424 | controller | skipping: [instance] => (item=cell2-osp-computes)
2026-01-29 15:15:05.634433 | controller | skipping: [instance] => (item=cell2-osp-controllers)
2026-01-29 15:15:05.634449 | controller | skipping: [instance] => (item=controllers)
2026-01-29 15:15:05.634456 | controller | skipping: [instance] => (item=ocps)
2026-01-29 15:15:05.634462 | controller | skipping: [instance] => (item=osp-controllers)
2026-01-29 15:15:05.634467 | controller | skipping: [instance] => (item=osp-underclouds)
2026-01-29 15:15:05.634473 | controller |
2026-01-29 15:15:05.634478 | controller | TASK [Get main facts for the overcloud stack] **********************************
2026-01-29 15:15:05.634484 | controller | Thursday 29 January 2026  15:15:05 +0000 (0:00:00.064)       0:00:00.392 ******
2026-01-29 15:15:05.634493 | controller | ok: [instance]
2026-01-29 15:15:05.634519 | controller |
2026-01-29 15:15:05.634633 | controller | TASK [Gather other facts for stack cell2"] *************************************
2026-01-29 15:15:05.634652 | controller | Thursday 29 January 2026  15:15:05 +0000 (0:00:00.031)       0:00:00.423 ******
2026-01-29 15:15:05.702885 | controller | ok: [instance]
2026-01-29 15:15:05.768427 | controller |
2026-01-29 15:15:05.768451 | controller | TASK [Gather nodes for stack cell2] ********************************************
2026-01-29 15:15:05.768460 | controller | Thursday 29 January 2026  15:15:05 +0000 (0:00:00.068)       0:00:00.492 ******
2026-01-29 15:15:05.768471 | controller | skipping: [instance] => (item=cell1-osp-computes)
2026-01-29 15:15:05.768496 | controller | skipping: [instance] => (item=cell1-osp-controllers)
2026-01-29 15:15:05.768503 | controller | ok: [instance] => (item=cell2-osp-computes)
2026-01-29 15:15:05.768509 | controller | ok: [instance] => (item=cell2-osp-controllers)
2026-01-29 15:15:05.768515 | controller | skipping: [instance] => (item=controllers)
2026-01-29 15:15:05.768521 | controller | skipping: [instance] => (item=ocps)
2026-01-29 15:15:05.768528 | controller | skipping: [instance] => (item=osp-controllers)
2026-01-29 15:15:05.768534 | controller | skipping: [instance] => (item=osp-underclouds)
2026-01-29 15:15:05.768540 | controller |
2026-01-29 15:15:05.768548 | controller | TASK [Store result for verification as persistent fact] ************************
2026-01-29 15:15:05.794408 | controller | Thursday 29 January 2026  15:15:05 +0000 (0:00:00.064)       0:00:00.557 ******
2026-01-29 15:15:05.794429 | controller | ok: [instance]
2026-01-29 15:15:05.795616 | controller |
2026-01-29 15:15:05.795633 | controller | PLAY RECAP *********************************************************************
2026-01-29 15:15:05.795643 | controller | instance                   : ok=13   changed=0    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-01-29 15:15:05.846823 | controller |
2026-01-29 15:15:05.846844 | controller | Thursday 29 January 2026  15:15:05 +0000 (0:00:00.026)       0:00:00.584 ******
2026-01-29 15:15:05.846852 | controller | ===============================================================================
2026-01-29 15:15:05.846858 | controller | Gather other facts for stack overcloud" --------------------------------- 0.08s
2026-01-29 15:15:05.846868 | controller | Gather other facts for stack cell1" ------------------------------------- 0.07s
2026-01-29 15:15:05.846874 | controller | Gather other facts for stack cell2" ------------------------------------- 0.07s
2026-01-29 15:15:05.846879 | controller | Gather nodes for stack cell1 -------------------------------------------- 0.07s
2026-01-29 15:15:05.846884 | controller | Gather nodes for stack cell2 -------------------------------------------- 0.06s
2026-01-29 15:15:05.846890 | controller | Gather nodes for stack overcloud ---------------------------------------- 0.06s
2026-01-29 15:15:05.846895 | controller | Get main facts for the overcloud stack ---------------------------------- 0.03s
2026-01-29 15:15:05.846900 | controller | Get main facts for the overcloud stack ---------------------------------- 0.03s
2026-01-29 15:15:05.846905 | controller | Gather stack nodes and facts -------------------------------------------- 0.03s
2026-01-29 15:15:05.846911 | controller | Get main facts for the overcloud stack ---------------------------------- 0.03s
2026-01-29 15:15:05.846916 | controller | Store result for verification as persistent fact ------------------------ 0.03s
2026-01-29 15:15:05.846925 | controller | INFO     Running default > verify
2026-01-29 15:15:05.847411 | controller | INFO     Running Ansible Verifier
2026-01-29 15:15:06.236134 | controller |
2026-01-29 15:15:06.257106 | controller | PLAY [Verify] ******************************************************************
2026-01-29 15:15:06.257131 | controller |
2026-01-29 15:15:06.257148 | controller | TASK [Set _tripleo_nodes_stack from persistent fact] ***************************
2026-01-29 15:15:06.257155 | controller | Thursday 29 January 2026  15:15:06 +0000 (0:00:00.018)       0:00:00.018 ******
2026-01-29 15:15:06.257164 | controller | skipping: [instance]
2026-01-29 15:15:06.326489 | controller |
2026-01-29 15:15:06.326528 | controller | TASK [Assert gathered nodes for stacks] ****************************************
2026-01-29 15:15:06.326539 | controller | Thursday 29 January 2026  15:15:06 +0000 (0:00:00.021)       0:00:00.040 ******
2026-01-29 15:15:06.326552 | 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
2026-01-29 15:15:06.326580 | controller |   _stack:
2026-01-29 15:15:06.326588 | controller |     network_data_file: network_data.yaml.j2
2026-01-29 15:15:06.326596 | controller |     stack_nodes:
2026-01-29 15:15:06.326603 | controller |     - osp-controllers
2026-01-29 15:15:06.326610 | controller |     stackname: overcloud
2026-01-29 15:15:06.326617 | controller |     vips_data_file: vips_data_overcloud.yaml
2026-01-29 15:15:06.326626 | controller |   ansible_loop_var: _stack
2026-01-29 15:15:06.326651 | controller |   msg: Successfully verified gathered nodes for stack overcloud
2026-01-29 15:15:06.328946 | 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
2026-01-29 15:15:06.367005 | controller |   _stack:
2026-01-29 15:15:06.367034 | controller |     network_data_file: network_data.yaml.j2
2026-01-29 15:15:06.367043 | controller |     stack_nodes:
2026-01-29 15:15:06.367049 | controller |     - cell1-osp-computes
2026-01-29 15:15:06.367056 | controller |     - cell1-osp-controllers
2026-01-29 15:15:06.367061 | controller |     stackname: cell1
2026-01-29 15:15:06.367067 | controller |     vips_data_file: vips_data_cell1.yaml
2026-01-29 15:15:06.367073 | controller |   ansible_loop_var: _stack
2026-01-29 15:15:06.367081 | controller |   msg: Successfully verified gathered nodes for stack cell1
2026-01-29 15:15:06.367087 | 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
2026-01-29 15:15:06.367093 | controller |   _stack:
2026-01-29 15:15:06.367098 | controller |     network_data_file: network_data.yaml.j2
2026-01-29 15:15:06.367104 | controller |     stack_nodes:
2026-01-29 15:15:06.367109 | controller |     - cell2-osp-computes
2026-01-29 15:15:06.367114 | controller |     - cell2-osp-controllers
2026-01-29 15:15:06.367120 | controller |     stackname: cell2
2026-01-29 15:15:06.367125 | controller |     vips_data_file: vips_data_cell2.yaml
2026-01-29 15:15:06.367130 | controller |   ansible_loop_var: _stack
2026-01-29 15:15:06.367136 | controller |   msg: Successfully verified gathered nodes for stack cell2
2026-01-29 15:15:06.367141 | controller |
2026-01-29 15:15:06.367146 | controller | PLAY RECAP *********************************************************************
2026-01-29 15:15:06.367152 | controller | instance                   : ok=1    changed=0    unreachable=0    failed=0    skipped=1    rescued=0    ignored=0
2026-01-29 15:15:06.367158 | controller |
2026-01-29 15:15:06.367163 | controller | Thursday 29 January 2026  15:15:06 +0000 (0:00:00.069)       0:00:00.109 ******
2026-01-29 15:15:06.367169 | controller | ===============================================================================
2026-01-29 15:15:06.367174 | controller | Assert gathered nodes for stacks ---------------------------------------- 0.07s
2026-01-29 15:15:06.367179 | controller | Set _tripleo_nodes_stack from persistent fact --------------------------- 0.02s
2026-01-29 15:15:06.367189 | controller | INFO     Verifier completed successfully.
2026-01-29 15:15:06.375051 | controller | INFO     Running default > destroy
2026-01-29 15:15:06.765399 | controller |
2026-01-29 15:15:07.043103 | controller | PLAY [Destroy] *****************************************************************
2026-01-29 15:15:07.043133 | controller |
2026-01-29 15:15:07.043140 | controller | TASK [Get passwd entries for USER env] *****************************************
2026-01-29 15:15:07.043146 | controller | Thursday 29 January 2026  15:15:06 +0000 (0:00:00.013)       0:00:00.013 ******
2026-01-29 15:15:07.043155 | controller | ok: [localhost]
2026-01-29 15:15:07.082249 | controller |
2026-01-29 15:15:07.082271 | controller | TASK [Get shell async_dir] *****************************************************
2026-01-29 15:15:07.082280 | controller | Thursday 29 January 2026  15:15:07 +0000 (0:00:00.277)       0:00:00.291 ******
2026-01-29 15:15:07.082290 | controller | ok: [localhost]
2026-01-29 15:15:07.115038 | controller |
2026-01-29 15:15:07.115059 | controller | TASK [Set async_dir for HOME env] **********************************************
2026-01-29 15:15:07.115067 | controller | Thursday 29 January 2026  15:15:07 +0000 (0:00:00.039)       0:00:00.330 ******
2026-01-29 15:15:07.115078 | controller | skipping: [localhost]
2026-01-29 15:15:07.492109 | controller |
2026-01-29 15:15:07.492140 | controller | TASK [Destroy molecule instance(s)] ********************************************
2026-01-29 15:15:07.492149 | controller | Thursday 29 January 2026  15:15:07 +0000 (0:00:00.032)       0:00:00.363 ******
2026-01-29 15:15:07.492159 | controller | changed: [localhost] => (item={'command': '/sbin/init', 'dockerfile': 'containerfile', 'image': 'registry.access.redhat.com/ubi9/ubi-init', 'name': 'instance', 'pre_build_image': True})
2026-01-29 15:15:12.947421 | controller |
2026-01-29 15:15:12.947456 | controller | TASK [Wait for instance(s) deletion to complete] *******************************
2026-01-29 15:15:12.947465 | controller | Thursday 29 January 2026  15:15:07 +0000 (0:00:00.376)       0:00:00.740 ******
2026-01-29 15:15:12.947475 | controller | FAILED - RETRYING: [localhost]: Wait for instance(s) deletion to complete (300 retries left).
2026-01-29 15:15:12.947504 | controller | changed: [localhost] => (item={'failed': 0, 'started': 1, 'finished': 0, 'ansible_job_id': 'j604001815404.29910', 'results_file': '/home/zuul/.ansible_async/j604001815404.29910', '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'})
2026-01-29 15:15:12.947890 | controller |
2026-01-29 15:15:12.997486 | controller | TASK [Delete podman network dedicated to this scenario] ************************
2026-01-29 15:15:12.997507 | controller | Thursday 29 January 2026  15:15:12 +0000 (0:00:05.455)       0:00:06.196 ******
2026-01-29 15:15:12.997518 | controller | skipping: [localhost] => (item=instance: None specified)
2026-01-29 15:15:13.043362 | controller | skipping: [localhost]
2026-01-29 15:15:13.043384 | controller |
2026-01-29 15:15:13.043392 | controller | PLAY RECAP *********************************************************************
2026-01-29 15:15:13.043399 | controller | localhost                  : ok=4    changed=2    unreachable=0    failed=0    skipped=2    rescued=0    ignored=0
2026-01-29 15:15:13.043404 | controller |
2026-01-29 15:15:13.043410 | controller | Thursday 29 January 2026  15:15:12 +0000 (0:00:00.049)       0:00:06.245 ******
2026-01-29 15:15:13.043415 | controller | ===============================================================================
2026-01-29 15:15:13.043420 | controller | Wait for instance(s) deletion to complete ------------------------------- 5.46s
2026-01-29 15:15:13.043426 | controller | Destroy molecule instance(s) -------------------------------------------- 0.38s
2026-01-29 15:15:13.043431 | controller | Get passwd entries for USER env ----------------------------------------- 0.28s
2026-01-29 15:15:13.043436 | controller | Delete podman network dedicated to this scenario ------------------------ 0.05s
2026-01-29 15:15:13.043441 | controller | Get shell async_dir ----------------------------------------------------- 0.04s
2026-01-29 15:15:13.043447 | controller | Set async_dir for HOME env ---------------------------------------------- 0.03s
2026-01-29 15:15:13.043456 | controller | INFO     Pruning extra files from scenario ephemeral directory
2026-01-29 15:15:13.045211 | controller | INFO     Writing /tmp/report.html report.
2026-01-29 15:15:13.250682 | controller | changed
2026-01-29 15:15:13.268775 | 
2026-01-29 15:15:13.268837 | PLAY RECAP
2026-01-29 15:15:13.268883 | controller | ok: 4 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-01-29 15:15:13.268910 | 
2026-01-29 15:15:13.324351 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2026-01-29 15:15:13.325150 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2026-01-29 15:15:13.820952 | 
2026-01-29 15:15:13.821049 | PLAY [Run ci/playbooks/collect-logs.yml]
2026-01-29 15:15:13.842406 | 
2026-01-29 15:15:13.842479 | TASK [Filter out host if needed]
2026-01-29 15:15:13.850736 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2026-01-29 15:15:13.854831 | 
2026-01-29 15:15:13.854894 | TASK [Ensure file is present]
2026-01-29 15:15:14.136179 | controller | ok
2026-01-29 15:15:14.142906 | 
2026-01-29 15:15:14.142979 | TASK [Manage molecule report file]
2026-01-29 15:15:14.665557 | controller | changed
2026-01-29 15:15:14.670253 | 
2026-01-29 15:15:14.670324 | TASK [Check if we get ci-framework-data basedir]
2026-01-29 15:15:14.850579 | controller | ok
2026-01-29 15:15:14.855583 | 
2026-01-29 15:15:14.855645 | TASK [Create ci-framework-data log directory for zuul]
2026-01-29 15:15:15.147657 | controller | changed
2026-01-29 15:15:15.152984 | 
2026-01-29 15:15:15.153051 | TASK [Copy ci-framework interesting files]
2026-01-29 15:15:15.339248 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2026-01-29 15:15:15.679198 | controller | changed
2026-01-29 15:15:15.684321 | 
2026-01-29 15:15:15.684395 | TASK [Get SELinux listing]
2026-01-29 15:15:16.208737 | controller | changed
2026-01-29 15:15:16.214246 | 
2026-01-29 15:15:16.214332 | TASK [Generate log index]
2026-01-29 15:15:16.810461 | controller | changed
2026-01-29 15:15:16.815190 | 
2026-01-29 15:15:16.815253 | TASK [Get some env related data]
2026-01-29 15:15:17.336727 | controller | /home/zuul/.local/bin/ansible
2026-01-29 15:15:18.339016 | controller | changed
2026-01-29 15:15:18.344067 | 
2026-01-29 15:15:18.344172 | TASK [Generate list of logs to collect in home directory]
2026-01-29 15:15:18.625711 | controller | ok: All paths examined
2026-01-29 15:15:18.630603 | 
2026-01-29 15:15:18.630664 | LOOP [Copy logs from home directory]
2026-01-29 15:15:18.933927 | controller | changed:
2026-01-29 15:15:18.934039 | controller | {
2026-01-29 15:15:18.934066 | controller |   "atime": 1769699653.767539,
2026-01-29 15:15:18.934099 | controller |   "ctime": 1769699669.859744,
2026-01-29 15:15:18.934118 | controller |   "dev": 64513,
2026-01-29 15:15:18.934136 | controller |   "gid": 1000,
2026-01-29 15:15:18.934152 | controller |   "gr_name": "zuul",
2026-01-29 15:15:18.934170 | controller |   "inode": 1268828,
2026-01-29 15:15:18.934186 | controller |   "isblk": false,
2026-01-29 15:15:18.934201 | controller |   "ischr": false,
2026-01-29 15:15:18.934217 | controller |   "isdir": false,
2026-01-29 15:15:18.934231 | controller |   "isfifo": false,
2026-01-29 15:15:18.934247 | controller |   "isgid": false,
2026-01-29 15:15:18.934262 | controller |   "islnk": false,
2026-01-29 15:15:18.934276 | controller |   "isreg": true,
2026-01-29 15:15:18.934290 | controller |   "issock": false,
2026-01-29 15:15:18.934330 | controller |   "isuid": false,
2026-01-29 15:15:18.934347 | controller |   "mode": "0644",
2026-01-29 15:15:18.934362 | controller |   "mtime": 1769699669.859744,
2026-01-29 15:15:18.934377 | controller |   "nlink": 1,
2026-01-29 15:15:18.934391 | controller |   "path": "/home/zuul/ansible.log",
2026-01-29 15:15:18.934407 | controller |   "pw_name": "zuul",
2026-01-29 15:15:18.934423 | controller |   "rgrp": true,
2026-01-29 15:15:18.934439 | controller |   "roth": true,
2026-01-29 15:15:18.934454 | controller |   "rusr": true,
2026-01-29 15:15:18.934469 | controller |   "size": 6749,
2026-01-29 15:15:18.934484 | controller |   "uid": 1000,
2026-01-29 15:15:18.934499 | controller |   "wgrp": false,
2026-01-29 15:15:18.934513 | controller |   "woth": false,
2026-01-29 15:15:18.934530 | controller |   "wusr": true,
2026-01-29 15:15:18.934545 | controller |   "xgrp": false,
2026-01-29 15:15:18.934561 | controller |   "xoth": false,
2026-01-29 15:15:18.934576 | controller |   "xusr": false
2026-01-29 15:15:18.934591 | controller | }
2026-01-29 15:15:18.947576 | 
2026-01-29 15:15:18.947679 | TASK [Copy crio stats log file]
2026-01-29 15:15:18.961563 | controller | skipping: Conditional result was False
2026-01-29 15:15:18.966826 | 
2026-01-29 15:15:18.966889 | TASK [Get SELinux related data]
2026-01-29 15:15:19.164496 | controller | <no matches>
2026-01-29 15:15:19.490564 | controller | ERROR
2026-01-29 15:15:19.490667 | controller | {
2026-01-29 15:15:19.490697 | controller |   "delta": "0:00:00.007750",
2026-01-29 15:15:19.490718 | controller |   "end": "2026-01-29 15:15:19.165114",
2026-01-29 15:15:19.490735 | controller |   "msg": "non-zero return code",
2026-01-29 15:15:19.490751 | controller |   "rc": 1,
2026-01-29 15:15:19.490767 | controller |   "start": "2026-01-29 15:15:19.157364"
2026-01-29 15:15:19.490784 | controller | }
2026-01-29 15:15:19.490805 | controller | ERROR: Ignoring Errors
2026-01-29 15:15:19.495216 | 
2026-01-29 15:15:19.495271 | TASK [Create system configuration directory]
2026-01-29 15:15:19.675684 | controller | changed
2026-01-29 15:15:19.680166 | 
2026-01-29 15:15:19.680227 | TASK [Get some of the system configurations]
2026-01-29 15:15:20.201867 | controller | changed
2026-01-29 15:15:20.206473 | 
2026-01-29 15:15:20.206531 | TASK [Copy generated documentation if available]
2026-01-29 15:15:20.219906 | controller | skipping: Conditional result was False
2026-01-29 15:15:20.225354 | 
2026-01-29 15:15:20.225417 | TASK [Copy generated AsciiDoc documentation if available]
2026-01-29 15:15:20.238708 | controller | skipping: Conditional result was False
2026-01-29 15:15:20.244415 | 
2026-01-29 15:15:20.244475 | TASK [Compress logs bigger than 2MB]
2026-01-29 15:15:20.767877 | controller | changed
2026-01-29 15:15:20.772756 | 
2026-01-29 15:15:20.772819 | TASK [Copy files from workspace on node]
2026-01-29 15:15:20.790712 | controller | ok
2026-01-29 15:15:20.811904 | 
2026-01-29 15:15:20.811968 | TASK [fetch-output : Set log path for multiple nodes]
2026-01-29 15:15:20.825262 | controller | skipping: Conditional result was False
2026-01-29 15:15:20.830597 | 
2026-01-29 15:15:20.830664 | TASK [fetch-output : Set log path for single node]
2026-01-29 15:15:20.858652 | controller | ok
2026-01-29 15:15:20.864362 | 
2026-01-29 15:15:20.864423 | LOOP [fetch-output : Ensure local output dirs]
2026-01-29 15:15:21.055000 | controller -> localhost | ok: "/var/lib/zuul/builds/9ddf609f605b44a098ce282deb9ff662/work/logs"
2026-01-29 15:15:21.055261 | controller -> localhost | changed: All items complete
2026-01-29 15:15:21.055323 | 
2026-01-29 15:15:21.227114 | controller -> localhost | changed: "/var/lib/zuul/builds/9ddf609f605b44a098ce282deb9ff662/work/artifacts"
2026-01-29 15:15:21.401492 | controller -> localhost | changed: "/var/lib/zuul/builds/9ddf609f605b44a098ce282deb9ff662/work/docs"
2026-01-29 15:15:21.412334 | 
2026-01-29 15:15:21.412440 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-01-29 15:15:21.928276 | controller | changed:
2026-01-29 15:15:21.928473 | controller | .d..t...... ./
2026-01-29 15:15:21.928506 | controller | >f+++++++++ README.html
2026-01-29 15:15:21.928529 | controller | >f+++++++++ ansible-execution.log
2026-01-29 15:15:21.928548 | controller | >f+++++++++ ansible.log
2026-01-29 15:15:21.928565 | controller | >f+++++++++ dmesg.log
2026-01-29 15:15:21.928582 | controller | >f+++++++++ installed-pkgs.log
2026-01-29 15:15:21.928601 | controller | >f+++++++++ python.log
2026-01-29 15:15:21.928619 | controller | >f+++++++++ registries.conf
2026-01-29 15:15:21.928636 | controller | >f+++++++++ report.html
2026-01-29 15:15:21.928655 | controller | >f+++++++++ selinux-denials.log
2026-01-29 15:15:21.928672 | controller | >f+++++++++ selinux-listing.log
2026-01-29 15:15:21.928689 | controller | cd+++++++++ ci-framework-data/
2026-01-29 15:15:21.928705 | controller | cd+++++++++ ci-framework-data/artifacts/
2026-01-29 15:15:21.928721 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2026-01-29 15:15:21.928738 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2026-01-29 15:15:21.928755 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2026-01-29 15:15:21.928771 | controller | cd+++++++++ ci-framework-data/logs/
2026-01-29 15:15:21.928786 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2026-01-29 15:15:21.928801 | controller | cd+++++++++ registries.conf.d/
2026-01-29 15:15:21.928816 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2026-01-29 15:15:21.928830 | controller | cd+++++++++ system-config/
2026-01-29 15:15:21.928844 | controller | cd+++++++++ system-config/libvirt/
2026-01-29 15:15:21.928859 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2026-01-29 15:15:21.928875 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2026-01-29 15:15:22.279479 | controller | changed: .d..t...... ./
2026-01-29 15:15:22.635893 | controller | changed: .d..t...... ./
2026-01-29 15:15:22.656021 | 
2026-01-29 15:15:22.656130 | TASK [Return artifact to Zuul]
2026-01-29 15:15:22.684634 | controller | ok
2026-01-29 15:15:22.703679 | 
2026-01-29 15:15:22.703738 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2026-01-29 15:15:22.703835 | 
2026-01-29 15:15:22.703861 | PLAY RECAP
2026-01-29 15:15:22.703899 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2026-01-29 15:15:22.703920 | 
2026-01-29 15:15:22.776975 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2026-01-29 15:15:22.777677 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2026-01-29 15:15:23.258539 | 
2026-01-29 15:15:23.258628 | PLAY [all]
2026-01-29 15:15:23.276964 | 
2026-01-29 15:15:23.277035 | TASK [include_role : fetch-output]
2026-01-29 15:15:23.304792 | controller | ok
2026-01-29 15:15:23.320512 | 
2026-01-29 15:15:23.320590 | TASK [fetch-output : Set log path for multiple nodes]
2026-01-29 15:15:23.364522 | controller | skipping: Conditional result was False
2026-01-29 15:15:23.369992 | 
2026-01-29 15:15:23.370066 | TASK [fetch-output : Set log path for single node]
2026-01-29 15:15:23.398503 | controller | ok
2026-01-29 15:15:23.403252 | 
2026-01-29 15:15:23.403335 | LOOP [fetch-output : Ensure local output dirs]
2026-01-29 15:15:23.709454 | controller -> localhost | ok: "/var/lib/zuul/builds/9ddf609f605b44a098ce282deb9ff662/work/logs"
2026-01-29 15:15:23.884471 | controller -> localhost | ok: "/var/lib/zuul/builds/9ddf609f605b44a098ce282deb9ff662/work/artifacts"
2026-01-29 15:15:24.072185 | controller -> localhost | ok: "/var/lib/zuul/builds/9ddf609f605b44a098ce282deb9ff662/work/docs"
2026-01-29 15:15:24.081189 | 
2026-01-29 15:15:24.081258 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-01-29 15:15:24.599837 | controller | ok
2026-01-29 15:15:24.599992 | controller | ok: All items complete
2026-01-29 15:15:24.600020 | 
2026-01-29 15:15:24.966202 | controller | ok
2026-01-29 15:15:25.333862 | controller | ok
2026-01-29 15:15:25.353832 | 
2026-01-29 15:15:25.353901 | TASK [include_role : fetch-output-openshift]
2026-01-29 15:15:25.377820 | controller | skipping: Conditional result was False
2026-01-29 15:15:25.383680 | 
2026-01-29 15:15:25.383757 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-01-29 15:15:25.781944 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.011213
2026-01-29 15:15:25.968811 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.007609
2026-01-29 15:15:25.999922 | 
2026-01-29 15:15:26.000045 | PLAY [all]
2026-01-29 15:15:26.013622 | 
2026-01-29 15:15:26.013688 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-01-29 15:15:26.430379 | controller | changed
2026-01-29 15:15:26.451456 | 
2026-01-29 15:15:26.451510 | PLAY RECAP
2026-01-29 15:15:26.451557 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2026-01-29 15:15:26.451579 | 
2026-01-29 15:15:26.533488 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2026-01-29 15:15:26.534205 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2026-01-29 15:15:27.056493 | 
2026-01-29 15:15:27.056592 | PLAY [localhost]
2026-01-29 15:15:27.072702 | 
2026-01-29 15:15:27.072776 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-01-29 15:15:27.363870 | localhost | changed
2026-01-29 15:15:27.368201 | 
2026-01-29 15:15:27.368280 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-01-29 15:15:27.395769 | localhost | ok
2026-01-29 15:15:27.403323 | 
2026-01-29 15:15:27.403392 | TASK [add-fileserver : Create SSH private key tempfile]
2026-01-29 15:15:27.694811 | localhost | changed
2026-01-29 15:15:27.701532 | 
2026-01-29 15:15:27.701608 | TASK [add-fileserver : Create SSH private key from secret]
2026-01-29 15:15:28.258574 | localhost | changed
2026-01-29 15:15:28.263965 | 
2026-01-29 15:15:28.264049 | TASK [add-fileserver : Add fileserver ssh key]
2026-01-29 15:15:28.609178 | localhost | Identity added: /var/lib/zuul/builds/9ddf609f605b44a098ce282deb9ff662/work/tmp/ansible.5swg_d1l (/var/lib/zuul/builds/9ddf609f605b44a098ce282deb9ff662/work/tmp/ansible.5swg_d1l)
2026-01-29 15:15:28.609486 | localhost | ok: Runtime: 0:00:00.008955
2026-01-29 15:15:28.614244 | 
2026-01-29 15:15:28.614345 | TASK [add-fileserver : Remove SSH private key from disk]
2026-01-29 15:15:28.837182 | localhost | ok: Runtime: 0:00:00.004381
2026-01-29 15:15:28.842162 | 
2026-01-29 15:15:28.842231 | TASK [add-fileserver : Add fileserver to inventory]
2026-01-29 15:15:28.893923 | localhost | changed
2026-01-29 15:15:28.899271 | 
2026-01-29 15:15:28.899362 | TASK [add-fileserver : Add fileserver server to known hosts]
2026-01-29 15:15:29.223117 | localhost | changed
2026-01-29 15:15:29.242101 | 
2026-01-29 15:15:29.242204 | PLAY [localhost]
2026-01-29 15:15:29.252986 | 
2026-01-29 15:15:29.253050 | TASK [Generate bulk log download script]
2026-01-29 15:15:29.272832 | localhost | ok
2026-01-29 15:15:29.283268 | 
2026-01-29 15:15:29.283361 | TASK [local-log-download : Check API endpoint is defined]
2026-01-29 15:15:29.310608 | localhost | ok: All assertions passed
2026-01-29 15:15:29.314757 | 
2026-01-29 15:15:29.314822 | TASK [local-log-download : Create download script]
2026-01-29 15:15:29.663230 | localhost -> localhost | changed
2026-01-29 15:15:29.672000 | 
2026-01-29 15:15:29.672078 | TASK [Register quick-download link]
2026-01-29 15:15:29.700453 | localhost | ok
2026-01-29 15:15:29.744633 | 
2026-01-29 15:15:29.744723 | PLAY [logserver.rdoproject.org]
2026-01-29 15:15:29.753191 | 
2026-01-29 15:15:29.753249 | TASK [Set zuul-log-path fact]
2026-01-29 15:15:29.770171 | logserver.rdoproject.org | ok
2026-01-29 15:15:29.779521 | 
2026-01-29 15:15:29.779600 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-01-29 15:15:29.807287 | logserver.rdoproject.org | ok
2026-01-29 15:15:29.812630 | 
2026-01-29 15:15:29.812692 | TASK [upload-logs : Create log directories]
2026-01-29 15:15:31.386911 | logserver.rdoproject.org | changed
2026-01-29 15:15:31.390113 | 
2026-01-29 15:15:31.390178 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-01-29 15:15:31.609579 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.007027
2026-01-29 15:15:31.614618 | 
2026-01-29 15:15:31.614685 | TASK [upload-logs : Upload logs to log server]
2026-01-29 15:15:33.060398 | logserver.rdoproject.org | Output suppressed because no_log was given
2026-01-29 15:15:33.063468 | 
2026-01-29 15:15:33.063541 | LOOP [upload-logs : Compress console log and json output]
2026-01-29 15:15:33.103813 | logserver.rdoproject.org | skipping: Conditional result was False
2026-01-29 15:15:33.111580 | logserver.rdoproject.org | skipping: Conditional result was False
2026-01-29 15:15:33.123455 | 
2026-01-29 15:15:33.123569 | LOOP [upload-logs : Upload compressed console log and json output]
2026-01-29 15:15:33.162045 | logserver.rdoproject.org | skipping: Conditional result was False
2026-01-29 15:15:33.162317 | 
2026-01-29 15:15:33.165315 | logserver.rdoproject.org | skipping: Conditional result was False
2026-01-29 15:15:33.173801 | 
2026-01-29 15:15:33.173914 | LOOP [upload-logs : Upload console log and json output]