2026-05-20 19:07:07.034217 | Job console starting
2026-05-20 19:07:07.045671 | Updating git repos
2026-05-20 19:07:09.155046 | Cloning repos into workspace
2026-05-20 19:07:22.470520 | Restoring repo states
2026-05-20 19:07:34.482082 | Merging changes
2026-05-20 19:07:34.838576 | Checking out repos
2026-05-20 19:07:36.468484 | Preparing playbooks
2026-05-20 19:07:37.911088 | Running Ansible setup
2026-05-20 19:07:41.771253 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-05-20 19:07:42.371966 | 
2026-05-20 19:07:42.372101 | PLAY [localhost]
2026-05-20 19:07:42.380215 | 
2026-05-20 19:07:42.380309 | TASK [Gathering Facts]
2026-05-20 19:07:43.324690 | localhost | ok
2026-05-20 19:07:43.335448 | 
2026-05-20 19:07:43.335561 | TASK [Setup log path fact]
2026-05-20 19:07:43.353958 | localhost | ok
2026-05-20 19:07:43.365538 | 
2026-05-20 19:07:43.365653 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-05-20 19:07:43.397003 | localhost | ok
2026-05-20 19:07:43.405352 | 
2026-05-20 19:07:43.405503 | TASK [emit-job-header : Print job information]
2026-05-20 19:07:43.448191 | # Job Information
2026-05-20 19:07:43.448462 | Ansible Version: 2.16.18
2026-05-20 19:07:43.448530 | Job: atmosphere-common-molecule-glance-image
2026-05-20 19:07:43.448578 | Pipeline: check
2026-05-20 19:07:43.448622 | Executor: 2d72f0692154
2026-05-20 19:07:43.448666 | Triggered by: https://github.com/vexxhost/atmosphere.common/pull/111
2026-05-20 19:07:43.448718 | Event ID: fc5b9970-547e-11f1-82a8-b5b9872a1eb8
2026-05-20 19:07:43.454765 | 
2026-05-20 19:07:43.454863 | LOOP [emit-job-header : Print node information]
2026-05-20 19:07:43.552089 | localhost | ok:
2026-05-20 19:07:43.552546 | localhost | # Node Information
2026-05-20 19:07:43.552656 | localhost | Inventory Hostname: controller
2026-05-20 19:07:43.552714 | localhost | Hostname: np0000175443
2026-05-20 19:07:43.552760 | localhost | Username: zuul
2026-05-20 19:07:43.552812 | localhost | Distro: Ubuntu 24.04
2026-05-20 19:07:43.552857 | localhost | Provider: yul1
2026-05-20 19:07:43.552900 | localhost | Region: ca-ymq-1
2026-05-20 19:07:43.552942 | localhost | Label: ubuntu-noble
2026-05-20 19:07:43.553028 | localhost | Product Name: OpenStack Nova
2026-05-20 19:07:43.553072 | localhost | Interface IP: 199.204.45.169
2026-05-20 19:07:43.575976 | 
2026-05-20 19:07:43.576141 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-05-20 19:07:43.986271 | localhost -> localhost | changed
2026-05-20 19:07:43.993500 | 
2026-05-20 19:07:43.993601 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-05-20 19:07:44.911288 | localhost -> localhost | changed
2026-05-20 19:07:44.921258 | 
2026-05-20 19:07:44.921358 | PLAY [all]
2026-05-20 19:07:44.930298 | 
2026-05-20 19:07:44.930409 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-05-20 19:07:45.157398 | controller -> localhost | ok
2026-05-20 19:07:45.167649 | 
2026-05-20 19:07:45.167750 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-05-20 19:07:45.203655 | controller | ok
2026-05-20 19:07:45.218630 | controller | included: /var/lib/zuul/builds/472034a2a41740fcbef77c1b71702c03/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-05-20 19:07:45.224691 | 
2026-05-20 19:07:45.224754 | TASK [add-build-sshkey : Create Temp SSH key]
2026-05-20 19:07:45.953833 | controller -> localhost | Generating public/private rsa key pair.
2026-05-20 19:07:45.954031 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/472034a2a41740fcbef77c1b71702c03/work/472034a2a41740fcbef77c1b71702c03_id_rsa
2026-05-20 19:07:45.954062 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/472034a2a41740fcbef77c1b71702c03/work/472034a2a41740fcbef77c1b71702c03_id_rsa.pub
2026-05-20 19:07:45.954086 | controller -> localhost | The key fingerprint is:
2026-05-20 19:07:45.954108 | controller -> localhost | SHA256:idxf4sSKjVQJlc16nscli4u0YwREZZpq7FmJ2KrUAuQ zuul-build-sshkey
2026-05-20 19:07:45.954142 | controller -> localhost | The key's randomart image is:
2026-05-20 19:07:45.954164 | controller -> localhost | +---[RSA 3072]----+
2026-05-20 19:07:45.954189 | controller -> localhost | |     .oo++       |
2026-05-20 19:07:45.954211 | controller -> localhost | |      .=..o      |
2026-05-20 19:07:45.954232 | controller -> localhost | | .   .o o.       |
2026-05-20 19:07:45.954252 | controller -> localhost | |o  + +.=.o. . .  |
2026-05-20 19:07:45.954272 | controller -> localhost | |.E. * *.So++.+   |
2026-05-20 19:07:45.954291 | controller -> localhost | |. .+ + +o=+o+    |
2026-05-20 19:07:45.954310 | controller -> localhost | | o..o oooooo     |
2026-05-20 19:07:45.954330 | controller -> localhost | |...     = .      |
2026-05-20 19:07:45.954352 | controller -> localhost | |.      . .       |
2026-05-20 19:07:45.954372 | controller -> localhost | +----[SHA256]-----+
2026-05-20 19:07:45.954425 | controller -> localhost | ok: Runtime: 0:00:00.253280
2026-05-20 19:07:45.960296 | 
2026-05-20 19:07:45.960384 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-05-20 19:07:45.996390 | controller | ok
2026-05-20 19:07:46.006593 | controller | included: /var/lib/zuul/builds/472034a2a41740fcbef77c1b71702c03/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-05-20 19:07:46.014814 | 
2026-05-20 19:07:46.014879 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-05-20 19:07:46.040912 | controller | skipping: Conditional result was False
2026-05-20 19:07:46.053643 | 
2026-05-20 19:07:46.053748 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-05-20 19:07:46.636341 | controller | changed
2026-05-20 19:07:46.644522 | 
2026-05-20 19:07:46.644611 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-05-20 19:07:46.874222 | controller | ok
2026-05-20 19:07:46.881578 | 
2026-05-20 19:07:46.881648 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-05-20 19:07:47.433258 | controller | changed
2026-05-20 19:07:47.439535 | 
2026-05-20 19:07:47.439610 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-05-20 19:07:48.012994 | controller | changed
2026-05-20 19:07:48.019627 | 
2026-05-20 19:07:48.019696 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-05-20 19:07:48.045760 | controller | skipping: Conditional result was False
2026-05-20 19:07:48.058401 | 
2026-05-20 19:07:48.058526 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-05-20 19:07:48.448391 | controller -> localhost | changed
2026-05-20 19:07:48.464304 | 
2026-05-20 19:07:48.464418 | TASK [add-build-sshkey : Add back temp key]
2026-05-20 19:07:48.830604 | controller -> localhost | Identity added: /var/lib/zuul/builds/472034a2a41740fcbef77c1b71702c03/work/472034a2a41740fcbef77c1b71702c03_id_rsa (zuul-build-sshkey)
2026-05-20 19:07:48.830819 | controller -> localhost | ok: Runtime: 0:00:00.015225
2026-05-20 19:07:48.882082 | 
2026-05-20 19:07:48.882231 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-05-20 19:07:49.199384 | controller | ok
2026-05-20 19:07:49.206455 | 
2026-05-20 19:07:49.206544 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-05-20 19:07:49.232649 | controller | skipping: Conditional result was False
2026-05-20 19:07:49.248899 | 
2026-05-20 19:07:49.249051 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-05-20 19:07:49.583640 | controller | ok
2026-05-20 19:07:49.590044 | 
2026-05-20 19:07:49.590111 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-05-20 19:07:54.841774 | controller | Output suppressed because no_log was given
2026-05-20 19:07:54.854548 | 
2026-05-20 19:07:54.854722 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-05-20 19:07:55.083419 | controller | ok: "logs"
2026-05-20 19:07:55.083668 | controller | ok: All items complete
2026-05-20 19:07:55.083697 | 
2026-05-20 19:07:55.280405 | controller | ok: "artifacts"
2026-05-20 19:07:55.473418 | controller | ok: "docs"
2026-05-20 19:07:55.493065 | 
2026-05-20 19:07:55.493213 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-05-20 19:07:55.823227 | controller | changed: "logs"
2026-05-20 19:07:56.018583 | controller | changed: "artifacts"
2026-05-20 19:07:56.217886 | controller | changed: "docs"
2026-05-20 19:07:56.232304 | 
2026-05-20 19:07:56.232422 | PLAY RECAP
2026-05-20 19:07:56.232470 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-05-20 19:07:56.232516 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-05-20 19:07:56.232543 | 
2026-05-20 19:07:56.367844 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-05-20 19:07:56.374018 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-05-20 19:07:56.977085 | 
2026-05-20 19:07:56.977226 | PLAY [all]
2026-05-20 19:07:56.989424 | 
2026-05-20 19:07:56.989519 | TASK [setup-uv : Extract archive]
2026-05-20 19:07:59.448311 | controller | changed
2026-05-20 19:07:59.455434 | 
2026-05-20 19:07:59.455524 | TASK [setup-uv : Print version]
2026-05-20 19:07:59.957375 | controller | uv 0.8.13
2026-05-20 19:08:00.017562 | controller | ok: Runtime: 0:00:00.015419
2026-05-20 19:08:00.027947 | 
2026-05-20 19:08:00.028023 | TASK [Install Ansible collection siblings]
2026-05-20 19:08:06.575687 | controller | changed:
2026-05-20 19:08:06.737444 | controller | Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon
2026-05-20 19:08:06.737840 | controller | Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix
2026-05-20 19:08:06.737903 | controller | Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils
2026-05-20 19:08:06.737951 | controller | Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto
2026-05-20 19:08:06.738060 | controller | Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general
2026-05-20 19:08:06.738131 | controller | Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql
2026-05-20 19:08:06.738183 | controller | Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core
2026-05-20 19:08:06.738233 | controller | Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph
2026-05-20 19:08:06.738277 | controller | Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers
2026-05-20 19:08:06.738319 | controller | Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes
2026-05-20 19:08:06.738361 | controller | Sibling collection vexxhost.atmosphere at /home/zuul/src/github.com/vexxhost/atmosphere
2026-05-20 19:08:06.738403 | controller | Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack
2026-05-20 19:08:06.738443 | controller | Resolved kubernetes.core to 6.4.0 for >=2.4.0
2026-05-20 19:08:06.738484 | controller | Resolved openstack.cloud to 2.5.0 for >=2.0.0
2026-05-20 19:08:06.738524 | controller | Resolved vexxhost.kubernetes to v3.1.0 for >=2.5.0
2026-05-20 19:08:06.738566 | controller | Resolved ansible.posix to 2.2.0 for >=1.6.0
2026-05-20 19:08:06.738607 | controller | Resolved community.crypto to 3.2.1 for >=2.2.3
2026-05-20 19:08:06.738647 | controller | Resolved community.general to 13.0.0 for >=4.5.0
2026-05-20 19:08:06.738686 | controller | Resolved kubernetes.core to 6.4.0 for >=2.4.0,>=2.3.2
2026-05-20 19:08:06.738728 | controller | Resolved vexxhost.containers to v1.6.6 for >=1.6.0
2026-05-20 19:08:06.738767 | controller | Checked out ansible.posix to 2.2.0
2026-05-20 19:08:06.738849 | controller | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.posix
2026-05-20 19:08:06.738920 | controller | Starting galaxy collection install process
2026-05-20 19:08:06.738963 | controller | Process install dependency map
2026-05-20 19:08:06.739005 | controller | Starting collection install process
2026-05-20 19:08:06.739045 | controller | Installing 'ansible.posix:2.2.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-05-20 19:08:06.739086 | controller | Created collection for ansible.posix:2.2.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/posix
2026-05-20 19:08:06.739125 | controller | ansible.posix:2.2.0 was installed successfully
2026-05-20 19:08:06.739166 | controller | Using CPython 3.12.3 interpreter at: /usr/bin/python3
2026-05-20 19:08:06.739213 | controller | Creating virtual environment at: .venv
2026-05-20 19:08:06.739258 | controller | warning: No `requires-python` value found in the workspace. Defaulting to `>=3.12`.
2026-05-20 19:08:06.739304 | controller | Downloading kubernetes (1.9MiB)
2026-05-20 19:08:06.739350 | controller | Downloading cryptography (4.3MiB)
2026-05-20 19:08:06.739394 | controller | Downloading ansible-core (2.1MiB)
2026-05-20 19:08:06.739437 | controller | Downloading pydantic-core (2.0MiB)
2026-05-20 19:08:06.739481 | controller |  Downloading pydantic-core
2026-05-20 19:08:06.739524 | controller |  Downloading cryptography
2026-05-20 19:08:06.739566 | controller | Downloading pygments (1.2MiB)
2026-05-20 19:08:06.739609 | controller |  Downloading kubernetes
2026-05-20 19:08:06.739651 | controller |  Downloading pygments
2026-05-20 19:08:06.739694 | controller |  Downloading ansible-core
2026-05-20 19:08:06.739737 | controller | Installed 52 packages in 89ms
2026-05-20 19:08:06.739779 | controller | Checked out community.crypto to 3.2.1
2026-05-20 19:08:06.739823 | controller | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.crypto
2026-05-20 19:08:06.739868 | controller | Starting galaxy collection install process
2026-05-20 19:08:06.739918 | controller | Process install dependency map
2026-05-20 19:08:06.739963 | controller | Starting collection install process
2026-05-20 19:08:06.740007 | controller | Installing 'community.crypto:3.2.1' to '/home/zuul/.ansible/collections/ansible_collections/community/crypto'
2026-05-20 19:08:06.740052 | controller | Created collection for community.crypto:3.2.1 at /home/zuul/.ansible/collections/ansible_collections/community/crypto
2026-05-20 19:08:06.740097 | controller | community.crypto:3.2.1 was installed successfully
2026-05-20 19:08:06.740141 | controller | warning: No `requires-python` value found in the workspace. Defaulting to `>=3.12`.
2026-05-20 19:08:06.740185 | controller | Checked out community.general to 13.0.0
2026-05-20 19:08:06.740230 | controller | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.general
2026-05-20 19:08:06.740278 | controller | Starting galaxy collection install process
2026-05-20 19:08:06.740324 | controller | Process install dependency map
2026-05-20 19:08:06.740368 | controller | Starting collection install process
2026-05-20 19:08:06.740412 | controller | Installing 'community.general:13.0.0' to '/home/zuul/.ansible/collections/ansible_collections/community/general'
2026-05-20 19:08:06.740456 | controller | Created collection for community.general:13.0.0 at /home/zuul/.ansible/collections/ansible_collections/community/general
2026-05-20 19:08:06.740501 | controller | community.general:13.0.0 was installed successfully
2026-05-20 19:08:06.740544 | controller | warning: No `requires-python` value found in the workspace. Defaulting to `>=3.12`.
2026-05-20 19:08:06.740588 | controller | Checked out kubernetes.core to 6.4.0
2026-05-20 19:08:06.740648 | controller | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/kubernetes.core
2026-05-20 19:08:06.740694 | controller | Starting galaxy collection install process
2026-05-20 19:08:06.740739 | controller | Process install dependency map
2026-05-20 19:08:06.740783 | controller | Starting collection install process
2026-05-20 19:08:06.740827 | controller | Installing 'kubernetes.core:6.4.0' to '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core'
2026-05-20 19:08:06.740871 | controller | Created collection for kubernetes.core:6.4.0 at /home/zuul/.ansible/collections/ansible_collections/kubernetes/core
2026-05-20 19:08:06.740914 | controller | kubernetes.core:6.4.0 was installed successfully
2026-05-20 19:08:06.740991 | controller | warning: No `requires-python` value found in the workspace. Defaulting to `>=3.12`.
2026-05-20 19:08:06.741066 | controller | Checked out openstack.cloud to 2.5.0
2026-05-20 19:08:06.741176 | controller | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/opendev.org/openstack/ansible-collections-openstack
2026-05-20 19:08:06.741548 | controller | Starting galaxy collection install process
2026-05-20 19:08:06.741610 | controller | Process install dependency map
2026-05-20 19:08:06.741660 | controller | Starting collection install process
2026-05-20 19:08:06.741707 | controller | Installing 'openstack.cloud:2.5.0' to '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud'
2026-05-20 19:08:06.741753 | controller | Created collection for openstack.cloud:2.5.0 at /home/zuul/.ansible/collections/ansible_collections/openstack/cloud
2026-05-20 19:08:06.741798 | controller | openstack.cloud:2.5.0 was installed successfully
2026-05-20 19:08:06.741843 | controller | warning: No `requires-python` value found in the workspace. Defaulting to `>=3.12`.
2026-05-20 19:08:06.741888 | controller | Checked out vexxhost.containers to v1.6.6
2026-05-20 19:08:06.741934 | controller | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-containers
2026-05-20 19:08:06.742015 | controller | Starting galaxy collection install process
2026-05-20 19:08:06.742081 | controller | Process install dependency map
2026-05-20 19:08:06.742131 | controller | Starting collection install process
2026-05-20 19:08:06.742176 | controller | Installing 'vexxhost.containers:1.6.6' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers'
2026-05-20 19:08:06.742222 | controller | Created collection for vexxhost.containers:1.6.6 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers
2026-05-20 19:08:06.742269 | controller | vexxhost.containers:1.6.6 was installed successfully
2026-05-20 19:08:06.742315 | controller | warning: No `requires-python` value found in the workspace. Defaulting to `>=3.12`.
2026-05-20 19:08:06.742361 | controller | Checked out vexxhost.kubernetes to v3.1.0
2026-05-20 19:08:06.742407 | controller | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes
2026-05-20 19:08:06.742454 | controller | Starting galaxy collection install process
2026-05-20 19:08:06.742498 | controller | Process install dependency map
2026-05-20 19:08:06.742543 | controller | Starting collection install process
2026-05-20 19:08:06.742586 | controller | Installing 'vexxhost.kubernetes:3.1.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes'
2026-05-20 19:08:06.742632 | controller | Created collection for vexxhost.kubernetes:3.1.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes
2026-05-20 19:08:06.742676 | controller | vexxhost.kubernetes:3.1.0 was installed successfully
2026-05-20 19:08:06.742737 | controller | warning: No `requires-python` value found in the workspace. Defaulting to `>=3.12`.
2026-05-20 19:08:06.753914 | 
2026-05-20 19:08:06.754049 | PLAY RECAP
2026-05-20 19:08:06.754104 | controller | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-05-20 19:08:06.754127 | 
2026-05-20 19:08:06.905853 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-05-20 19:08:06.911948 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-05-20 19:08:07.501831 | 
2026-05-20 19:08:07.501974 | PLAY [all]
2026-05-20 19:08:07.514714 | 
2026-05-20 19:08:07.514854 | TASK [Copy inventory file for Zuul]
2026-05-20 19:08:08.533141 | controller | changed
2026-05-20 19:08:08.540988 | 
2026-05-20 19:08:08.541090 | TASK [Switch "ansible_host" to private IP]
2026-05-20 19:08:08.911058 | controller | changed: 1 replacements made
2026-05-20 19:08:08.919499 | 
2026-05-20 19:08:08.919573 | TASK [Run Molecule scenario]
2026-05-20 19:08:09.328620 | controller | warning: No `requires-python` value found in the workspace. Defaulting to `>=3.12`.
2026-05-20 19:08:10.040920 | controller | INFO     Collection 'atmosphere.common' detected.
2026-05-20 19:08:10.041030 | controller | INFO     Scenarios will be used from 'extensions/molecule'
2026-05-20 19:08:10.451185 | controller | INFO     [glance-image > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-05-20 19:08:10.451277 | controller | INFO     [glance-image > prerun] Performing prerun with role_name_check=0...
2026-05-20 19:08:13.175474 | controller | INFO     [glance-image > dependency] Executing
2026-05-20 19:08:45.048064 | controller | Starting galaxy collection install process
2026-05-20 19:08:45.048223 | controller | Process install dependency map
2026-05-20 19:08:45.048240 | controller | Starting collection install process
2026-05-20 19:08:45.048436 | controller | 'openstack.cloud:2.5.0' is already installed, skipping.
2026-05-20 19:08:45.048646 | controller | 'vexxhost.kubernetes:3.1.0' is already installed, skipping.
2026-05-20 19:08:45.048892 | controller | 'vexxhost.containers:1.6.6' is already installed, skipping.
2026-05-20 19:08:45.049126 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/vexxhost-atmosphere-7.6.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-1246fdqncpyk/tmph7cc7wk0/vexxhost-atmosphere-7.6.0-ubqgh9kl
2026-05-20 19:08:45.049359 | controller | Installing 'vexxhost.atmosphere:7.6.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/atmosphere'
2026-05-20 19:08:45.049537 | controller | vexxhost.atmosphere:7.6.0 was installed successfully
2026-05-20 19:08:45.049741 | controller | 'atmosphere.common:0.6.0' is already installed, skipping.
2026-05-20 19:08:45.050041 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/ansible-posix-1.6.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-1246fdqncpyk/tmph7cc7wk0/ansible-posix-1.6.0-df_fchr2
2026-05-20 19:08:45.050260 | controller | Installing 'ansible.posix:1.6.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-05-20 19:08:45.050464 | controller | ansible.posix:1.6.0 was installed successfully
2026-05-20 19:08:45.050765 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/community-general-7.3.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-1246fdqncpyk/tmph7cc7wk0/community-general-7.3.0-ks1971ka
2026-05-20 19:08:45.051018 | controller | Installing 'community.general:7.3.0' to '/home/zuul/.ansible/collections/ansible_collections/community/general'
2026-05-20 19:08:45.051221 | controller | community.general:7.3.0 was installed successfully
2026-05-20 19:08:45.051442 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/kubernetes-core-2.4.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-1246fdqncpyk/tmph7cc7wk0/kubernetes-core-2.4.0-tgtv_k_3
2026-05-20 19:08:45.051576 | controller | Installing 'kubernetes.core:2.4.0' to '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core'
2026-05-20 19:08:45.051693 | controller | kubernetes.core:2.4.0 was installed successfully
2026-05-20 19:08:45.051871 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/community-crypto-2.2.3.tar.gz to /home/zuul/.ansible/tmp/ansible-local-1246fdqncpyk/tmph7cc7wk0/community-crypto-2.2.3-ov6x9y4z
2026-05-20 19:08:45.051994 | controller | Installing 'community.crypto:2.2.3' to '/home/zuul/.ansible/collections/ansible_collections/community/crypto'
2026-05-20 19:08:45.052119 | controller | community.crypto:2.2.3 was installed successfully
2026-05-20 19:08:45.052259 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/community-mysql-3.6.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-1246fdqncpyk/tmph7cc7wk0/community-mysql-3.6.0-sr3kz0u3
2026-05-20 19:08:45.052391 | controller | Installing 'community.mysql:3.6.0' to '/home/zuul/.ansible/collections/ansible_collections/community/mysql'
2026-05-20 19:08:45.052518 | controller | community.mysql:3.6.0 was installed successfully
2026-05-20 19:08:45.052660 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/ansible-netcommon-1.2.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-1246fdqncpyk/tmph7cc7wk0/ansible-netcommon-1.2.0-8gq5nd_s
2026-05-20 19:08:45.052814 | controller | Installing 'ansible.netcommon:1.2.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-05-20 19:08:45.052946 | controller | ansible.netcommon:1.2.0 was installed successfully
2026-05-20 19:08:45.053099 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/vexxhost-ceph-4.0.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-1246fdqncpyk/tmph7cc7wk0/vexxhost-ceph-4.0.0-wgupwnz2
2026-05-20 19:08:45.053235 | controller | Installing 'vexxhost.ceph:4.0.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph'
2026-05-20 19:08:45.053361 | controller | vexxhost.ceph:4.0.0 was installed successfully
2026-05-20 19:08:45.053501 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/ansible-utils-6.0.2.tar.gz to /home/zuul/.ansible/tmp/ansible-local-1246fdqncpyk/tmph7cc7wk0/ansible-utils-6.0.2-4jfj05pf
2026-05-20 19:08:45.053633 | controller | Installing 'ansible.utils:6.0.2' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2026-05-20 19:08:45.053760 | controller | ansible.utils:6.0.2 was installed successfully
2026-05-20 19:08:45.131988 | controller | INFO     [glance-image > dependency] Dependency completed successfully.
2026-05-20 19:08:45.459476 | controller | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-05-20 19:08:45.459585 | controller | 2.18.17
2026-05-20 19:08:45.519998 | controller | Starting galaxy collection install process
2026-05-20 19:08:45.520096 | controller | Nothing to do. All requested collections are already installed. If you want to reinstall them, consider using `--force`.
2026-05-20 19:08:45.577281 | controller | INFO     [glance-image > dependency] Dependency completed successfully.
2026-05-20 19:08:45.577376 | controller | INFO     [glance-image > dependency] Executed: Successful
2026-05-20 19:08:45.588722 | controller | INFO     [glance-image > cleanup] Executing
2026-05-20 19:08:45.589053 | controller | WARNING  [glance-image > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-05-20 19:08:45.595109 | controller | INFO     [glance-image > destroy] Executing
2026-05-20 19:08:45.595146 | controller | WARNING  [glance-image > destroy] Skipping, '--destroy=never' requested.
2026-05-20 19:08:45.595160 | controller | INFO     [glance-image > destroy] Executed: Successful
2026-05-20 19:08:45.601706 | controller | INFO     [glance-image > syntax] Executing
2026-05-20 19:08:46.546585 | controller |
2026-05-20 19:08:46.546700 | controller | playbook: /home/zuul/src/github.com/vexxhost/atmosphere.common/extensions/molecule/glance-image/converge.yml
2026-05-20 19:08:46.588961 | controller | INFO     [glance-image > syntax] Executed: Successful
2026-05-20 19:08:46.599354 | controller | INFO     [glance-image > create] Executing
2026-05-20 19:08:46.601357 | controller | WARNING  [glance-image > create] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-05-20 19:08:46.607381 | controller | INFO     [glance-image > prepare] Executing
2026-05-20 19:08:48.038230 | controller |
2026-05-20 19:08:48.038351 | controller | PLAY [Prepare host packages] ***************************************************
2026-05-20 19:08:48.038363 | controller |
2026-05-20 19:08:48.038500 | controller | TASK [Gathering Facts] *********************************************************
2026-05-20 19:08:48.038628 | controller | Wednesday 20 May 2026  19:08:48 +0000 (0:00:00.033)       0:00:00.033 *********
2026-05-20 19:08:49.365677 | controller | [WARNING]: Platform linux on host controller is using the discovered Python
2026-05-20 19:08:49.365892 | controller | interpreter at /usr/bin/python3.12, but future installation of another Python
2026-05-20 19:08:49.366097 | controller | interpreter could change the meaning of that path. See
2026-05-20 19:08:49.366302 | controller | https://docs.ansible.com/ansible-
2026-05-20 19:08:49.366521 | controller | core/2.18/reference_appendices/interpreter_discovery.html for more information.
2026-05-20 19:08:49.371784 | controller | ok: [controller]
2026-05-20 19:08:49.371919 | controller |
2026-05-20 19:08:49.372055 | controller | TASK [Install Debian packages] *************************************************
2026-05-20 19:08:49.372193 | controller | Wednesday 20 May 2026  19:08:49 +0000 (0:00:01.334)       0:00:01.368 *********
2026-05-20 19:09:01.638871 | controller | changed: [controller]
2026-05-20 19:09:01.638929 | controller |
2026-05-20 19:09:01.638936 | controller | TASK [Install Python packages used by the test] ********************************
2026-05-20 19:09:01.638943 | controller | Wednesday 20 May 2026  19:09:01 +0000 (0:00:12.265)       0:00:13.634 *********
2026-05-20 19:09:08.871399 | controller | changed: [controller]
2026-05-20 19:09:08.871475 | controller |
2026-05-20 19:09:08.871573 | controller | PLAY [all] *********************************************************************
2026-05-20 19:09:08.871693 | controller |
2026-05-20 19:09:08.871824 | controller | TASK [Gathering Facts] *********************************************************
2026-05-20 19:09:08.871976 | controller | Wednesday 20 May 2026  19:09:08 +0000 (0:00:07.227)       0:00:20.862 *********
2026-05-20 19:09:09.687153 | controller | ok: [controller]
2026-05-20 19:09:09.687291 | controller |
2026-05-20 19:09:09.687505 | controller | TASK [Set a fact with the "atmosphere_images" for other plays] *****************
2026-05-20 19:09:09.687709 | controller | Wednesday 20 May 2026  19:09:09 +0000 (0:00:00.821)       0:00:21.683 *********
2026-05-20 19:09:09.833714 | controller | ok: [controller]
2026-05-20 19:09:09.833788 | controller |
2026-05-20 19:09:09.833939 | controller | PLAY [Configure Kubernetes VIP] ************************************************
2026-05-20 19:09:09.834072 | controller |
2026-05-20 19:09:09.834176 | controller | TASK [Gathering Facts] *********************************************************
2026-05-20 19:09:09.834289 | controller | Wednesday 20 May 2026  19:09:09 +0000 (0:00:00.146)       0:00:21.830 *********
2026-05-20 19:09:10.652521 | controller | ok: [controller]
2026-05-20 19:09:10.652666 | controller |
2026-05-20 19:09:10.652972 | controller | TASK [vexxhost.containers.directory : Create directory (/etc/kubernetes/manifests)] ***
2026-05-20 19:09:10.653186 | controller | Wednesday 20 May 2026  19:09:10 +0000 (0:00:00.818)       0:00:22.649 *********
2026-05-20 19:09:11.067828 | controller | changed: [controller]
2026-05-20 19:09:11.067986 | controller |
2026-05-20 19:09:11.068215 | controller | TASK [vexxhost.kubernetes.kube_vip : Uninstall legacy HA stack] ****************
2026-05-20 19:09:11.068427 | controller | Wednesday 20 May 2026  19:09:11 +0000 (0:00:00.415)       0:00:23.064 *********
2026-05-20 19:09:12.234297 | controller | ok: [controller] => (item=/etc/keepalived/keepalived.conf)
2026-05-20 19:09:12.234493 | controller | ok: [controller] => (item=/etc/keepalived/check_apiserver.sh)
2026-05-20 19:09:12.234714 | controller | ok: [controller] => (item=/etc/kubernetes/manifests/keepalived.yaml)
2026-05-20 19:09:12.234981 | controller | ok: [controller] => (item=/etc/haproxy/haproxy.cfg)
2026-05-20 19:09:12.235355 | controller | ok: [controller] => (item=/etc/kubernetes/manifests/haproxy.yaml)
2026-05-20 19:09:12.235557 | controller |
2026-05-20 19:09:12.235826 | controller | TASK [vexxhost.kubernetes.kube_vip : Switch API server to run on port 6443] ****
2026-05-20 19:09:12.236066 | controller | Wednesday 20 May 2026  19:09:12 +0000 (0:00:01.166)       0:00:24.230 *********
2026-05-20 19:09:12.614678 | controller | failed: [controller] (item=/etc/kubernetes/manifests/kube-apiserver.yaml) => {"ansible_loop_var": "item", "changed": false, "item": "/etc/kubernetes/manifests/kube-apiserver.yaml", "msg": "Path /etc/kubernetes/manifests/kube-apiserver.yaml does not exist !", "rc": 257}
2026-05-20 19:09:12.818015 | controller | failed: [controller] (item=/etc/kubernetes/controller-manager.conf) => {"ansible_loop_var": "item", "changed": false, "item": "/etc/kubernetes/controller-manager.conf", "msg": "Path /etc/kubernetes/controller-manager.conf does not exist !", "rc": 257}
2026-05-20 19:09:13.020189 | controller | failed: [controller] (item=/etc/kubernetes/scheduler.conf) => {"ansible_loop_var": "item", "changed": false, "item": "/etc/kubernetes/scheduler.conf", "msg": "Path /etc/kubernetes/scheduler.conf does not exist !", "rc": 257}
2026-05-20 19:09:13.030087 | controller | ...ignoring
2026-05-20 19:09:13.030208 | controller |
2026-05-20 19:09:13.030349 | controller | TASK [vexxhost.kubernetes.kube_vip : Check if super-admin.conf exists] *********
2026-05-20 19:09:13.030483 | controller | Wednesday 20 May 2026  19:09:13 +0000 (0:00:00.793)       0:00:25.023 *********
2026-05-20 19:09:13.403298 | controller | ok: [controller]
2026-05-20 19:09:13.403357 | controller |
2026-05-20 19:09:13.403443 | controller | TASK [vexxhost.kubernetes.kube_vip : Check if kubeadm has already run] *********
2026-05-20 19:09:13.403541 | controller | Wednesday 20 May 2026  19:09:13 +0000 (0:00:00.373)       0:00:25.397 *********
2026-05-20 19:09:13.633401 | controller | ok: [controller]
2026-05-20 19:09:13.633507 | controller |
2026-05-20 19:09:13.633558 | controller | TASK [vexxhost.kubernetes.kube_vip : Set fact with KUBECONFIG path] ************
2026-05-20 19:09:13.633746 | controller | Wednesday 20 May 2026  19:09:13 +0000 (0:00:00.232)       0:00:25.629 *********
2026-05-20 19:09:13.668421 | controller | ok: [controller]
2026-05-20 19:09:13.668480 | controller |
2026-05-20 19:09:13.668512 | controller | TASK [vexxhost.kubernetes.kube_vip : Set fact with KUBECONFIG path (with super-admin.conf)] ***
2026-05-20 19:09:13.668523 | controller | Wednesday 20 May 2026  19:09:13 +0000 (0:00:00.033)       0:00:25.663 *********
2026-05-20 19:09:13.709099 | controller | ok: [controller]
2026-05-20 19:09:13.709133 | controller |
2026-05-20 19:09:13.709144 | controller | TASK [vexxhost.kubernetes.kube_vip : Upload Kubernetes manifest] ***************
2026-05-20 19:09:13.709154 | controller | Wednesday 20 May 2026  19:09:13 +0000 (0:00:00.040)       0:00:25.704 *********
2026-05-20 19:09:14.595290 | controller | ok: [controller]
2026-05-20 19:09:14.595342 | controller |
2026-05-20 19:09:14.595353 | controller | TASK [vexxhost.kubernetes.kube_vip : Ensure kube-vip configuration file] *******
2026-05-20 19:09:14.595363 | controller | Wednesday 20 May 2026  19:09:14 +0000 (0:00:00.883)       0:00:26.588 *********
2026-05-20 19:09:14.940481 | controller | ok: [controller]
2026-05-20 19:09:14.940523 | controller |
2026-05-20 19:09:14.940530 | controller | TASK [vexxhost.kubernetes.kube_vip : Flush handlers] ***************************
2026-05-20 19:09:14.940536 | controller | Wednesday 20 May 2026  19:09:14 +0000 (0:00:00.249)       0:00:26.837 *********
2026-05-20 19:09:14.940542 | controller |
2026-05-20 19:09:14.940547 | controller | PLAY [Install Kubernetes] ******************************************************
2026-05-20 19:09:14.940553 | controller |
2026-05-20 19:09:14.940558 | controller | TASK [Gathering Facts] *********************************************************
2026-05-20 19:09:14.940564 | controller | Wednesday 20 May 2026  19:09:14 +0000 (0:00:00.099)       0:00:26.936 *********
2026-05-20 19:09:16.754225 | controller | ok: [controller]
2026-05-20 19:09:16.754273 | controller |
2026-05-20 19:09:16.754284 | controller | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-05-20 19:09:16.754293 | controller | Wednesday 20 May 2026  19:09:16 +0000 (0:00:01.813)       0:00:28.749 *********
2026-05-20 19:09:16.999110 | controller | ok: [controller]
2026-05-20 19:09:16.999147 | controller |
2026-05-20 19:09:16.999157 | controller | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-05-20 19:09:16.999169 | controller | Wednesday 20 May 2026  19:09:16 +0000 (0:00:00.245)       0:00:28.994 *********
2026-05-20 19:09:17.034871 | controller | skipping: [controller]
2026-05-20 19:09:17.034920 | controller |
2026-05-20 19:09:17.035151 | controller | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] ***
2026-05-20 19:09:17.035420 | controller | Wednesday 20 May 2026  19:09:17 +0000 (0:00:00.035)       0:00:29.030 *********
2026-05-20 19:09:17.284963 | controller | changed: [controller]
2026-05-20 19:09:17.285152 | controller |
2026-05-20 19:09:17.285455 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-05-20 19:09:17.285466 | controller | Wednesday 20 May 2026  19:09:17 +0000 (0:00:00.251)       0:00:29.281 *********
2026-05-20 19:09:17.355951 | controller | ok: [controller] => {
2026-05-20 19:09:17.356009 | controller |     "msg": "https://github.com/opencontainers/runc/releases/download/v1.4.2/runc.amd64"
2026-05-20 19:09:17.356020 | controller | }
2026-05-20 19:09:17.356036 | controller |
2026-05-20 19:09:17.356077 | controller | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-05-20 19:09:17.356199 | controller | Wednesday 20 May 2026  19:09:17 +0000 (0:00:00.070)       0:00:29.352 *********
2026-05-20 19:09:18.122011 | controller | changed: [controller]
2026-05-20 19:09:18.122130 | controller |
2026-05-20 19:09:18.122152 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-05-20 19:09:18.122218 | controller | Wednesday 20 May 2026  19:09:18 +0000 (0:00:00.765)       0:00:30.118 *********
2026-05-20 19:09:18.158633 | controller | skipping: [controller]
2026-05-20 19:09:18.158742 | controller |
2026-05-20 19:09:18.158758 | controller | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-05-20 19:09:18.158915 | controller | Wednesday 20 May 2026  19:09:18 +0000 (0:00:00.037)       0:00:30.155 *********
2026-05-20 19:09:18.387502 | controller | ok: [controller]
2026-05-20 19:09:18.387624 | controller |
2026-05-20 19:09:18.387875 | controller | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-05-20 19:09:18.388123 | controller | Wednesday 20 May 2026  19:09:18 +0000 (0:00:00.228)       0:00:30.384 *********
2026-05-20 19:09:20.378625 | controller | ok: [controller]
2026-05-20 19:09:20.378710 | controller |
2026-05-20 19:09:20.378720 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-05-20 19:09:20.378747 | controller | Wednesday 20 May 2026  19:09:20 +0000 (0:00:01.990)       0:00:32.374 *********
2026-05-20 19:09:20.442465 | controller | ok: [controller] => {
2026-05-20 19:09:20.442596 | controller |     "msg": "https://github.com/containerd/containerd/releases/download/v2.2.3/containerd-2.2.3-linux-amd64.tar.gz"
2026-05-20 19:09:20.442717 | controller | }
2026-05-20 19:09:20.442853 | controller |
2026-05-20 19:09:20.442991 | controller | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-05-20 19:09:20.443114 | controller | Wednesday 20 May 2026  19:09:20 +0000 (0:00:00.063)       0:00:32.437 *********
2026-05-20 19:09:21.280289 | controller | changed: [controller]
2026-05-20 19:09:21.280349 | controller |
2026-05-20 19:09:21.280360 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-05-20 19:09:21.280369 | controller | Wednesday 20 May 2026  19:09:21 +0000 (0:00:00.837)       0:00:33.275 *********
2026-05-20 19:09:24.229655 | controller | changed: [controller]
2026-05-20 19:09:24.229714 | controller |
2026-05-20 19:09:24.229724 | controller | TASK [vexxhost.containers.containerd : Install SELinux packages] ***************
2026-05-20 19:09:24.229734 | controller | Wednesday 20 May 2026  19:09:24 +0000 (0:00:02.946)       0:00:36.222 *********
2026-05-20 19:09:24.260770 | controller | skipping: [controller]
2026-05-20 19:09:24.260869 | controller |
2026-05-20 19:09:24.260881 | controller | TASK [vexxhost.containers.containerd : Set SELinux to permissive at runtime] ***
2026-05-20 19:09:24.260891 | controller | Wednesday 20 May 2026  19:09:24 +0000 (0:00:00.033)       0:00:36.256 *********
2026-05-20 19:09:24.296926 | controller | skipping: [controller]
2026-05-20 19:09:24.296953 | controller |
2026-05-20 19:09:24.297324 | controller | TASK [vexxhost.containers.containerd : Persist SELinux permissive mode] ********
2026-05-20 19:09:24.297372 | controller | Wednesday 20 May 2026  19:09:24 +0000 (0:00:00.036)       0:00:36.292 *********
2026-05-20 19:09:24.331296 | controller | skipping: [controller]
2026-05-20 19:09:24.331354 | controller |
2026-05-20 19:09:24.331365 | controller | TASK [vexxhost.containers.containerd : Install AppArmor packages] **************
2026-05-20 19:09:24.331375 | controller | Wednesday 20 May 2026  19:09:24 +0000 (0:00:00.034)       0:00:36.326 *********
2026-05-20 19:09:32.214857 | controller | changed: [controller]
2026-05-20 19:09:32.214913 | controller |
2026-05-20 19:09:32.215035 | controller | TASK [vexxhost.containers.containerd : Create systemd service file for containerd] ***
2026-05-20 19:09:32.215247 | controller | Wednesday 20 May 2026  19:09:32 +0000 (0:00:07.883)       0:00:44.210 *********
2026-05-20 19:09:32.806501 | controller | changed: [controller]
2026-05-20 19:09:32.806673 | controller |
2026-05-20 19:09:32.806938 | controller | TASK [vexxhost.containers.containerd : Create folders for configuration] *******
2026-05-20 19:09:32.807148 | controller | Wednesday 20 May 2026  19:09:32 +0000 (0:00:00.592)       0:00:44.803 *********
2026-05-20 19:09:33.954929 | controller | changed: [controller] => (item={'path': '/etc/containerd'})
2026-05-20 19:09:33.954990 | controller | changed: [controller] => (item={'path': '/var/lib/containerd', 'mode': '0o700'})
2026-05-20 19:09:33.955210 | controller | changed: [controller] => (item={'path': '/run/containerd', 'mode': '0o711'})
2026-05-20 19:09:33.955426 | controller | changed: [controller] => (item={'path': '/run/containerd/io.containerd.grpc.v1.cri', 'mode': '0o700'})
2026-05-20 19:09:33.955663 | controller | changed: [controller] => (item={'path': '/run/containerd/io.containerd.sandbox.controller.v1.shim', 'mode': '0o700'})
2026-05-20 19:09:33.955879 | controller |
2026-05-20 19:09:33.956095 | controller | TASK [vexxhost.containers.containerd : Create containerd config file] **********
2026-05-20 19:09:33.956288 | controller | Wednesday 20 May 2026  19:09:33 +0000 (0:00:01.147)       0:00:45.951 *********
2026-05-20 19:09:34.647494 | controller | changed: [controller]
2026-05-20 19:09:34.647661 | controller |
2026-05-20 19:09:34.647943 | controller | TASK [vexxhost.containers.containerd : Force any restarts if necessary] ********
2026-05-20 19:09:34.648159 | controller | Wednesday 20 May 2026  19:09:34 +0000 (0:00:00.685)       0:00:46.636 *********
2026-05-20 19:09:34.648345 | controller |
2026-05-20 19:09:34.648558 | controller | RUNNING HANDLER [vexxhost.containers.containerd : Restart containerd] **********
2026-05-20 19:09:34.648768 | controller | Wednesday 20 May 2026  19:09:34 +0000 (0:00:00.007)       0:00:46.643 *********
2026-05-20 19:09:35.448329 | controller | changed: [controller]
2026-05-20 19:09:35.448407 | controller |
2026-05-20 19:09:35.448419 | controller | RUNNING HANDLER [vexxhost.kubernetes.kubelet : Reload systemd] *****************
2026-05-20 19:09:35.448428 | controller | Wednesday 20 May 2026  19:09:35 +0000 (0:00:00.799)       0:00:47.443 *********
2026-05-20 19:09:36.374139 | controller | ok: [controller]
2026-05-20 19:09:36.374197 | controller |
2026-05-20 19:09:36.374207 | controller | TASK [vexxhost.containers.containerd : Enable and start service] ***************
2026-05-20 19:09:36.374217 | controller | Wednesday 20 May 2026  19:09:36 +0000 (0:00:00.926)       0:00:48.369 *********
2026-05-20 19:09:37.070966 | controller | changed: [controller]
2026-05-20 19:09:37.071032 | controller |
2026-05-20 19:09:37.071380 | controller | TASK [vexxhost.kubernetes.kubernetes_upgrade_check : Retrieve the "kubeadm-config" ConfigMap] ***
2026-05-20 19:09:37.071423 | controller | Wednesday 20 May 2026  19:09:37 +0000 (0:00:00.697)       0:00:49.066 *********
2026-05-20 19:09:38.073522 | controller | An exception occurred during task execution. To see the full traceback, use -vvv. The error was: ansible_collections.kubernetes.core.plugins.module_utils.k8s.exceptions.CoreException: Could not create API client: Invalid kube-config file. No configuration found.
2026-05-20 19:09:38.073745 | controller | fatal: [controller]: FAILED! => {"changed": false, "msg": "Could not create API client: Invalid kube-config file. No configuration found."}
2026-05-20 19:09:38.081261 | controller | ...ignoring
2026-05-20 19:09:38.081390 | controller |
2026-05-20 19:09:38.081533 | controller | TASK [vexxhost.kubernetes.kubernetes_upgrade_check : Parse the ClusterConfiguration] ***
2026-05-20 19:09:38.081664 | controller | Wednesday 20 May 2026  19:09:38 +0000 (0:00:01.011)       0:00:50.078 *********
2026-05-20 19:09:38.123939 | controller | skipping: [controller]
2026-05-20 19:09:38.124002 | controller |
2026-05-20 19:09:38.124024 | controller | TASK [vexxhost.kubernetes.kubernetes_upgrade_check : Retrieve the current Kubernetes version] ***
2026-05-20 19:09:38.124125 | controller | Wednesday 20 May 2026  19:09:38 +0000 (0:00:00.042)       0:00:50.120 *********
2026-05-20 19:09:38.166328 | controller | skipping: [controller]
2026-05-20 19:09:38.166416 | controller |
2026-05-20 19:09:38.166559 | controller | TASK [vexxhost.kubernetes.kubernetes_upgrade_check : Extract major, minor, and patch versions] ***
2026-05-20 19:09:38.166691 | controller | Wednesday 20 May 2026  19:09:38 +0000 (0:00:00.042)       0:00:50.163 *********
2026-05-20 19:09:38.210215 | controller | skipping: [controller]
2026-05-20 19:09:38.210272 | controller |
2026-05-20 19:09:38.210283 | controller | TASK [vexxhost.kubernetes.kubernetes_upgrade_check : Fail if we're jumping more than one minor version] ***
2026-05-20 19:09:38.210292 | controller | Wednesday 20 May 2026  19:09:38 +0000 (0:00:00.042)       0:00:50.205 *********
2026-05-20 19:09:38.265050 | controller | skipping: [controller]
2026-05-20 19:09:38.265135 | controller |
2026-05-20 19:09:38.265148 | controller | TASK [vexxhost.kubernetes.kubernetes_upgrade_check : Set fact if we need to upgrade] ***
2026-05-20 19:09:38.265165 | controller | Wednesday 20 May 2026  19:09:38 +0000 (0:00:00.038)       0:00:50.244 *********
2026-05-20 19:09:38.293162 | controller | skipping: [controller]
2026-05-20 19:09:38.293227 | controller |
2026-05-20 19:09:38.293238 | controller | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-05-20 19:09:38.293248 | controller | Wednesday 20 May 2026  19:09:38 +0000 (0:00:00.044)       0:00:50.289 *********
2026-05-20 19:09:38.546955 | controller | ok: [controller]
2026-05-20 19:09:38.547017 | controller |
2026-05-20 19:09:38.547028 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-05-20 19:09:38.547153 | controller | Wednesday 20 May 2026  19:09:38 +0000 (0:00:00.252)       0:00:50.541 *********
2026-05-20 19:09:38.594344 | controller | ok: [controller] => {
2026-05-20 19:09:38.594431 | controller |     "msg": "https://dl.k8s.io/release/v1.28.13/bin/linux/amd64/kubeadm"
2026-05-20 19:09:38.594519 | controller | }
2026-05-20 19:09:38.594604 | controller |
2026-05-20 19:09:38.594702 | controller | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-05-20 19:09:38.594831 | controller | Wednesday 20 May 2026  19:09:38 +0000 (0:00:00.048)       0:00:50.589 *********
2026-05-20 19:09:39.489267 | controller | changed: [controller]
2026-05-20 19:09:39.489335 | controller |
2026-05-20 19:09:39.489346 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-05-20 19:09:39.489371 | controller | Wednesday 20 May 2026  19:09:39 +0000 (0:00:00.894)       0:00:51.484 *********
2026-05-20 19:09:39.530077 | controller | skipping: [controller]
2026-05-20 19:09:39.530112 | controller |
2026-05-20 19:09:39.530123 | controller | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-05-20 19:09:39.530132 | controller | Wednesday 20 May 2026  19:09:39 +0000 (0:00:00.040)       0:00:51.525 *********
2026-05-20 19:09:39.790935 | controller | ok: [controller]
2026-05-20 19:09:39.790985 | controller |
2026-05-20 19:09:39.790992 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-05-20 19:09:39.790998 | controller | Wednesday 20 May 2026  19:09:39 +0000 (0:00:00.260)       0:00:51.785 *********
2026-05-20 19:09:39.835227 | controller | ok: [controller] => {
2026-05-20 19:09:39.835298 | controller |     "msg": "https://dl.k8s.io/release/v1.28.13/bin/linux/amd64/kubectl"
2026-05-20 19:09:39.835374 | controller | }
2026-05-20 19:09:39.835460 | controller |
2026-05-20 19:09:39.835560 | controller | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-05-20 19:09:39.835653 | controller | Wednesday 20 May 2026  19:09:39 +0000 (0:00:00.044)       0:00:51.830 *********
2026-05-20 19:09:40.799021 | controller | changed: [controller]
2026-05-20 19:09:40.799081 | controller |
2026-05-20 19:09:40.799092 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-05-20 19:09:40.799102 | controller | Wednesday 20 May 2026  19:09:40 +0000 (0:00:00.964)       0:00:52.795 *********
2026-05-20 19:09:40.846186 | controller | skipping: [controller]
2026-05-20 19:09:40.846249 | controller |
2026-05-20 19:09:40.846260 | controller | TASK [vexxhost.containers.containerd : Install SELinux packages] ***************
2026-05-20 19:09:40.846270 | controller | Wednesday 20 May 2026  19:09:40 +0000 (0:00:00.046)       0:00:52.841 *********
2026-05-20 19:09:40.878008 | controller | skipping: [controller]
2026-05-20 19:09:40.878037 | controller |
2026-05-20 19:09:40.878047 | controller | TASK [vexxhost.containers.containerd : Set SELinux to permissive at runtime] ***
2026-05-20 19:09:40.878056 | controller | Wednesday 20 May 2026  19:09:40 +0000 (0:00:00.031)       0:00:52.873 *********
2026-05-20 19:09:40.912306 | controller | skipping: [controller]
2026-05-20 19:09:40.912373 | controller |
2026-05-20 19:09:40.912671 | controller | TASK [vexxhost.containers.containerd : Persist SELinux permissive mode] ********
2026-05-20 19:09:40.912706 | controller | Wednesday 20 May 2026  19:09:40 +0000 (0:00:00.034)       0:00:52.908 *********
2026-05-20 19:09:40.947875 | controller | skipping: [controller]
2026-05-20 19:09:40.947911 | controller |
2026-05-20 19:09:40.947921 | controller | TASK [vexxhost.containers.containerd : Install AppArmor packages] **************
2026-05-20 19:09:40.947930 | controller | Wednesday 20 May 2026  19:09:40 +0000 (0:00:00.034)       0:00:52.943 *********
2026-05-20 19:09:42.116887 | controller | ok: [controller]
2026-05-20 19:09:42.116962 | controller |
2026-05-20 19:09:42.117432 | controller | TASK [vexxhost.containers.containerd : Create systemd service file for containerd] ***
2026-05-20 19:09:42.117522 | controller | Wednesday 20 May 2026  19:09:42 +0000 (0:00:01.169)       0:00:54.113 *********
2026-05-20 19:09:42.664436 | controller | ok: [controller]
2026-05-20 19:09:42.664551 | controller |
2026-05-20 19:09:42.664567 | controller | TASK [vexxhost.containers.containerd : Create folders for configuration] *******
2026-05-20 19:09:42.664749 | controller | Wednesday 20 May 2026  19:09:42 +0000 (0:00:00.545)       0:00:54.658 *********
2026-05-20 19:09:43.817300 | controller | ok: [controller] => (item={'path': '/etc/containerd'})
2026-05-20 19:09:43.817421 | controller | ok: [controller] => (item={'path': '/var/lib/containerd', 'mode': '0o700'})
2026-05-20 19:09:43.817553 | controller | ok: [controller] => (item={'path': '/run/containerd', 'mode': '0o711'})
2026-05-20 19:09:43.817705 | controller | ok: [controller] => (item={'path': '/run/containerd/io.containerd.grpc.v1.cri', 'mode': '0o700'})
2026-05-20 19:09:43.817881 | controller | ok: [controller] => (item={'path': '/run/containerd/io.containerd.sandbox.controller.v1.shim', 'mode': '0o700'})
2026-05-20 19:09:43.817998 | controller |
2026-05-20 19:09:43.818140 | controller | TASK [vexxhost.containers.containerd : Create containerd config file] **********
2026-05-20 19:09:43.818272 | controller | Wednesday 20 May 2026  19:09:43 +0000 (0:00:01.155)       0:00:55.814 *********
2026-05-20 19:09:44.463364 | controller | ok: [controller]
2026-05-20 19:09:44.463421 | controller |
2026-05-20 19:09:44.463524 | controller | TASK [vexxhost.containers.containerd : Force any restarts if necessary] ********
2026-05-20 19:09:44.463623 | controller | Wednesday 20 May 2026  19:09:44 +0000 (0:00:00.639)       0:00:56.453 *********
2026-05-20 19:09:44.463709 | controller |
2026-05-20 19:09:44.463836 | controller | TASK [vexxhost.containers.containerd : Enable and start service] ***************
2026-05-20 19:09:44.463933 | controller | Wednesday 20 May 2026  19:09:44 +0000 (0:00:00.004)       0:00:56.458 *********
2026-05-20 19:09:44.960228 | controller | ok: [controller]
2026-05-20 19:09:44.960307 | controller |
2026-05-20 19:09:44.960314 | controller | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-05-20 19:09:44.960321 | controller | Wednesday 20 May 2026  19:09:44 +0000 (0:00:00.496)       0:00:56.955 *********
2026-05-20 19:09:45.220262 | controller | ok: [controller]
2026-05-20 19:09:45.220365 | controller |
2026-05-20 19:09:45.220377 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-05-20 19:09:45.220443 | controller | Wednesday 20 May 2026  19:09:45 +0000 (0:00:00.258)       0:00:57.214 *********
2026-05-20 19:09:45.275415 | controller | ok: [controller] => {
2026-05-20 19:09:45.275507 | controller |     "msg": "https://github.com/kubernetes-sigs/cri-tools/releases/download/v1.35.0/crictl-v1.35.0-linux-amd64.tar.gz"
2026-05-20 19:09:45.275598 | controller | }
2026-05-20 19:09:45.275681 | controller |
2026-05-20 19:09:45.275769 | controller | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-05-20 19:09:45.276044 | controller | Wednesday 20 May 2026  19:09:45 +0000 (0:00:00.058)       0:00:57.272 *********
2026-05-20 19:09:46.008975 | controller | changed: [controller]
2026-05-20 19:09:46.009061 | controller |
2026-05-20 19:09:46.009473 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-05-20 19:09:46.009537 | controller | Wednesday 20 May 2026  19:09:46 +0000 (0:00:00.731)       0:00:58.004 *********
2026-05-20 19:09:47.629265 | controller | changed: [controller]
2026-05-20 19:09:47.629378 | controller |
2026-05-20 19:09:47.629394 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-05-20 19:09:47.629475 | controller | Wednesday 20 May 2026  19:09:47 +0000 (0:00:01.621)       0:00:59.625 *********
2026-05-20 19:09:47.687938 | controller | ok: [controller] => {
2026-05-20 19:09:47.687974 | controller |     "msg": "https://github.com/kubernetes-sigs/cri-tools/releases/download/v1.35.0/critest-v1.35.0-linux-amd64.tar.gz"
2026-05-20 19:09:47.687993 | controller | }
2026-05-20 19:09:47.688616 | controller |
2026-05-20 19:09:47.688678 | controller | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-05-20 19:09:47.688693 | controller | Wednesday 20 May 2026  19:09:47 +0000 (0:00:00.057)       0:00:59.683 *********
2026-05-20 19:09:48.419836 | controller | changed: [controller]
2026-05-20 19:09:48.419931 | controller |
2026-05-20 19:09:48.419946 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-05-20 19:09:48.419961 | controller | Wednesday 20 May 2026  19:09:48 +0000 (0:00:00.731)       0:01:00.414 *********
2026-05-20 19:09:50.047925 | controller | changed: [controller]
2026-05-20 19:09:50.047986 | controller |
2026-05-20 19:09:50.048495 | controller | TASK [vexxhost.containers.cri_tools : Create crictl config] ********************
2026-05-20 19:09:50.048566 | controller | Wednesday 20 May 2026  19:09:50 +0000 (0:00:01.629)       0:01:02.043 *********
2026-05-20 19:09:50.656819 | controller | changed: [controller]
2026-05-20 19:09:50.656866 | controller |
2026-05-20 19:09:50.656893 | controller | TASK [vexxhost.containers.directory : Create directory (/opt/cni/bin)] *********
2026-05-20 19:09:50.656901 | controller | Wednesday 20 May 2026  19:09:50 +0000 (0:00:00.609)       0:01:02.652 *********
2026-05-20 19:09:50.913241 | controller | changed: [controller]
2026-05-20 19:09:50.913296 | controller |
2026-05-20 19:09:50.913307 | controller | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-05-20 19:09:50.913316 | controller | Wednesday 20 May 2026  19:09:50 +0000 (0:00:00.255)       0:01:02.908 *********
2026-05-20 19:09:51.167895 | controller | ok: [controller]
2026-05-20 19:09:51.168028 | controller |
2026-05-20 19:09:51.168040 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-05-20 19:09:51.168050 | controller | Wednesday 20 May 2026  19:09:51 +0000 (0:00:00.254)       0:01:03.162 *********
2026-05-20 19:09:51.226540 | controller | ok: [controller] => {
2026-05-20 19:09:51.226632 | controller |     "msg": "https://github.com/containernetworking/plugins/releases/download/v1.9.1/cni-plugins-linux-amd64-v1.9.1.tgz"
2026-05-20 19:09:51.226685 | controller | }
2026-05-20 19:09:51.226816 | controller |
2026-05-20 19:09:51.226978 | controller | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-05-20 19:09:51.227107 | controller | Wednesday 20 May 2026  19:09:51 +0000 (0:00:00.059)       0:01:03.222 *********
2026-05-20 19:09:52.206923 | controller | changed: [controller]
2026-05-20 19:09:52.206989 | controller |
2026-05-20 19:09:52.206994 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-05-20 19:09:52.207000 | controller | Wednesday 20 May 2026  19:09:52 +0000 (0:00:00.980)       0:01:04.202 *********
2026-05-20 19:09:55.075908 | controller | changed: [controller]
2026-05-20 19:09:55.075973 | controller |
2026-05-20 19:09:55.076499 | controller | TASK [vexxhost.containers.cni_plugins : Gather variables for each operating system] ***
2026-05-20 19:09:55.076550 | controller | Wednesday 20 May 2026  19:09:55 +0000 (0:00:02.869)       0:01:07.071 *********
2026-05-20 19:09:55.128302 | controller | ok: [controller] => (item=/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers/roles/cni_plugins/vars/debian.yml)
2026-05-20 19:09:55.128358 | controller |
2026-05-20 19:09:55.131306 | controller | TASK [vexxhost.containers.cni_plugins : Install additional packages] ***********
2026-05-20 19:09:55.131332 | controller | Wednesday 20 May 2026  19:09:55 +0000 (0:00:00.052)       0:01:07.124 *********
2026-05-20 19:09:56.246713 | controller | ok: [controller]
2026-05-20 19:09:56.247107 | controller |
2026-05-20 19:09:56.247437 | controller | TASK [vexxhost.containers.cni_plugins : Ensure IPv6 is enabled] ****************
2026-05-20 19:09:56.247724 | controller | Wednesday 20 May 2026  19:09:56 +0000 (0:00:01.117)       0:01:08.242 *********
2026-05-20 19:09:56.606239 | controller | changed: [controller]
2026-05-20 19:09:56.606340 | controller |
2026-05-20 19:09:56.606359 | controller | TASK [vexxhost.containers.cni_plugins : Enable kernel modules on-boot] *********
2026-05-20 19:09:56.606491 | controller | Wednesday 20 May 2026  19:09:56 +0000 (0:00:00.360)       0:01:08.602 *********
2026-05-20 19:09:57.174285 | controller | changed: [controller]
2026-05-20 19:09:57.174371 | controller |
2026-05-20 19:09:57.174525 | controller | TASK [vexxhost.containers.cni_plugins : Enable kernel modules in runtime] ******
2026-05-20 19:09:57.174657 | controller | Wednesday 20 May 2026  19:09:57 +0000 (0:00:00.567)       0:01:09.170 *********
2026-05-20 19:09:58.247843 | controller | changed: [controller] => (item=br_netfilter)
2026-05-20 19:09:58.247900 | controller | ok: [controller] => (item=ip_tables)
2026-05-20 19:09:58.247913 | controller | changed: [controller] => (item=ip6_tables)
2026-05-20 19:09:58.247925 | controller | changed: [controller] => (item=nf_conntrack)
2026-05-20 19:09:58.247937 | controller |
2026-05-20 19:09:58.247950 | controller | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-05-20 19:09:58.247962 | controller | Wednesday 20 May 2026  19:09:58 +0000 (0:00:01.072)       0:01:10.243 *********
2026-05-20 19:09:58.500905 | controller | ok: [controller]
2026-05-20 19:09:58.500960 | controller |
2026-05-20 19:09:58.500970 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-05-20 19:09:58.500978 | controller | Wednesday 20 May 2026  19:09:58 +0000 (0:00:00.253)       0:01:10.497 *********
2026-05-20 19:09:58.544312 | controller | ok: [controller] => {
2026-05-20 19:09:58.544379 | controller |     "msg": "https://dl.k8s.io/release/v1.28.13/bin/linux/amd64/kubelet"
2026-05-20 19:09:58.544528 | controller | }
2026-05-20 19:09:58.544646 | controller |
2026-05-20 19:09:58.544809 | controller | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-05-20 19:09:58.544950 | controller | Wednesday 20 May 2026  19:09:58 +0000 (0:00:00.043)       0:01:10.540 *********
2026-05-20 19:09:59.904262 | controller | changed: [controller]
2026-05-20 19:09:59.904312 | controller |
2026-05-20 19:09:59.904338 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-05-20 19:09:59.904349 | controller | Wednesday 20 May 2026  19:09:59 +0000 (0:00:01.359)       0:01:11.899 *********
2026-05-20 19:09:59.943989 | controller | skipping: [controller]
2026-05-20 19:09:59.944061 | controller |
2026-05-20 19:09:59.944072 | controller | TASK [vexxhost.kubernetes.kubelet : Gather variables for each operating system] ***
2026-05-20 19:09:59.944082 | controller | Wednesday 20 May 2026  19:09:59 +0000 (0:00:00.039)       0:01:11.939 *********
2026-05-20 19:09:59.997129 | controller | ok: [controller] => (item=/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/kubelet/vars/debian.yml)
2026-05-20 19:09:59.997190 | controller |
2026-05-20 19:09:59.997243 | controller | TASK [vexxhost.kubernetes.kubelet : Install coreutils] *************************
2026-05-20 19:09:59.997351 | controller | Wednesday 20 May 2026  19:09:59 +0000 (0:00:00.053)       0:01:11.993 *********
2026-05-20 19:10:00.026153 | controller | skipping: [controller]
2026-05-20 19:10:00.026238 | controller |
2026-05-20 19:10:00.026389 | controller | TASK [vexxhost.kubernetes.kubelet : Install additional packages] ***************
2026-05-20 19:10:00.026521 | controller | Wednesday 20 May 2026  19:10:00 +0000 (0:00:00.029)       0:01:12.023 *********
2026-05-20 19:10:03.567294 | controller | changed: [controller]
2026-05-20 19:10:03.567357 | controller |
2026-05-20 19:10:03.567375 | controller | TASK [vexxhost.kubernetes.kubelet : Configure sysctl values] *******************
2026-05-20 19:10:03.567392 | controller | Wednesday 20 May 2026  19:10:03 +0000 (0:00:03.539)       0:01:15.562 *********
2026-05-20 19:10:05.156936 | controller | changed: [controller] => (item={'name': 'net.ipv4.ip_forward', 'value': 1})
2026-05-20 19:10:05.156998 | controller | changed: [controller] => (item={'name': 'net.bridge.bridge-nf-call-iptables', 'value': 1})
2026-05-20 19:10:05.157012 | controller | changed: [controller] => (item={'name': 'net.bridge.bridge-nf-call-ip6tables', 'value': 1})
2026-05-20 19:10:05.157023 | controller | changed: [controller] => (item={'name': 'net.ipv4.conf.all.rp_filter', 'value': 0})
2026-05-20 19:10:05.157035 | controller | changed: [controller] => (item={'name': 'fs.inotify.max_queued_events', 'value': 1048576})
2026-05-20 19:10:05.157047 | controller | changed: [controller] => (item={'name': 'fs.inotify.max_user_instances', 'value': 8192})
2026-05-20 19:10:05.157066 | controller | changed: [controller] => (item={'name': 'fs.inotify.max_user_watches', 'value': 1048576})
2026-05-20 19:10:05.157238 | controller |
2026-05-20 19:10:05.157456 | controller | TASK [vexxhost.kubernetes.kubelet : Create folders for kubernetes configuration] ***
2026-05-20 19:10:05.157686 | controller | Wednesday 20 May 2026  19:10:05 +0000 (0:00:01.589)       0:01:17.151 *********
2026-05-20 19:10:05.817507 | controller | changed: [controller] => (item=/etc/systemd/system/kubelet.service.d)
2026-05-20 19:10:05.817710 | controller | ok: [controller] => (item=/etc/kubernetes)
2026-05-20 19:10:05.818046 | controller | ok: [controller] => (item=/etc/kubernetes/manifests)
2026-05-20 19:10:05.818247 | controller |
2026-05-20 19:10:05.818534 | controller | TASK [vexxhost.kubernetes.kubelet : Add kubelet systemd service config] ********
2026-05-20 19:10:05.818733 | controller | Wednesday 20 May 2026  19:10:05 +0000 (0:00:00.662)       0:01:17.813 *********
2026-05-20 19:10:06.384831 | controller | changed: [controller]
2026-05-20 19:10:06.385012 | controller |
2026-05-20 19:10:06.385251 | controller | TASK [vexxhost.kubernetes.kubelet : Add kubeadm dropin for kubelet systemd service config] ***
2026-05-20 19:10:06.385468 | controller | Wednesday 20 May 2026  19:10:06 +0000 (0:00:00.567)       0:01:18.381 *********
2026-05-20 19:10:06.973659 | controller | changed: [controller]
2026-05-20 19:10:06.973768 | controller |
2026-05-20 19:10:06.974017 | controller | TASK [vexxhost.kubernetes.kubelet : Check swap status] *************************
2026-05-20 19:10:06.974221 | controller | Wednesday 20 May 2026  19:10:06 +0000 (0:00:00.588)       0:01:18.970 *********
2026-05-20 19:10:07.306157 | controller | ok: [controller]
2026-05-20 19:10:07.306269 | controller |
2026-05-20 19:10:07.306541 | controller | TASK [vexxhost.kubernetes.kubelet : Disable swap] ******************************
2026-05-20 19:10:07.306694 | controller | Wednesday 20 May 2026  19:10:07 +0000 (0:00:00.332)       0:01:19.302 *********
2026-05-20 19:10:07.329742 | controller | [DEPRECATION WARNING]: Conditional result at location /home/zuul/.ansible/colle
2026-05-20 19:10:07.329991 | controller | ctions/ansible_collections/vexxhost/kubernetes/roles/kubelet/tasks/main.yml
2026-05-20 19:10:07.330209 | controller | 91:11 was of type 'AnsibleUnsafeText'. Conditional results should only be True
2026-05-20 19:10:07.330374 | controller | or False. The result was interpreted as False. This feature will be removed in
2026-05-20 19:10:07.330628 | controller | version 2.19. Deprecation warnings can be disabled by setting
2026-05-20 19:10:07.330764 | controller | deprecation_warnings=False in ansible.cfg.
2026-05-20 19:10:07.343548 | controller | skipping: [controller]
2026-05-20 19:10:07.343693 | controller |
2026-05-20 19:10:07.343977 | controller | TASK [vexxhost.kubernetes.kubelet : Remove swapfile from /etc/fstab] ***********
2026-05-20 19:10:07.344237 | controller | Wednesday 20 May 2026  19:10:07 +0000 (0:00:00.037)       0:01:19.340 *********
2026-05-20 19:10:07.912470 | controller | ok: [controller] => (item=swap)
2026-05-20 19:10:07.912526 | controller | ok: [controller] => (item=none)
2026-05-20 19:10:07.912537 | controller |
2026-05-20 19:10:07.912546 | controller | TASK [vexxhost.kubernetes.kubelet : Create noswap systemd service config file] ***
2026-05-20 19:10:07.912555 | controller | Wednesday 20 May 2026  19:10:07 +0000 (0:00:00.567)       0:01:19.907 *********
2026-05-20 19:10:08.512150 | controller | changed: [controller]
2026-05-20 19:10:08.512208 | controller |
2026-05-20 19:10:08.512218 | controller | TASK [vexxhost.kubernetes.kubelet : Enable noswap service] *********************
2026-05-20 19:10:08.512229 | controller | Wednesday 20 May 2026  19:10:08 +0000 (0:00:00.597)       0:01:20.505 *********
2026-05-20 19:10:09.214970 | controller | changed: [controller]
2026-05-20 19:10:09.215051 | controller |
2026-05-20 19:10:09.215062 | controller | TASK [vexxhost.kubernetes.kubelet : Force any restarts if necessary] ***********
2026-05-20 19:10:09.215072 | controller | Wednesday 20 May 2026  19:10:09 +0000 (0:00:00.700)       0:01:21.206 *********
2026-05-20 19:10:09.215081 | controller |
2026-05-20 19:10:09.215097 | controller | RUNNING HANDLER [vexxhost.kubernetes.kubelet : Reload systemd] *****************
2026-05-20 19:10:09.215110 | controller | Wednesday 20 May 2026  19:10:09 +0000 (0:00:00.004)       0:01:21.210 *********
2026-05-20 19:10:10.155434 | controller | ok: [controller]
2026-05-20 19:10:10.155528 | controller |
2026-05-20 19:10:10.155542 | controller | TASK [vexxhost.kubernetes.kubelet : Enable and start kubelet service] **********
2026-05-20 19:10:10.155674 | controller | Wednesday 20 May 2026  19:10:10 +0000 (0:00:00.941)       0:01:22.151 *********
2026-05-20 19:10:10.904894 | controller | changed: [controller]
2026-05-20 19:10:10.904975 | controller |
2026-05-20 19:10:10.905352 | controller | TASK [vexxhost.kubernetes.kubelet : Ensure availability of dbus on Debian] *****
2026-05-20 19:10:10.905414 | controller | Wednesday 20 May 2026  19:10:10 +0000 (0:00:00.748)       0:01:22.900 *********
2026-05-20 19:10:12.138640 | controller | ok: [controller]
2026-05-20 19:10:12.138724 | controller |
2026-05-20 19:10:12.138736 | controller | TASK [vexxhost.kubernetes.kubelet : Configure short hostname] ******************
2026-05-20 19:10:12.138748 | controller | Wednesday 20 May 2026  19:10:12 +0000 (0:00:01.233)       0:01:24.133 *********
2026-05-20 19:10:13.005438 | controller | changed: [controller]
2026-05-20 19:10:13.005557 | controller |
2026-05-20 19:10:13.005567 | controller | TASK [vexxhost.kubernetes.kubelet : Ensure hostname inside hosts file] *********
2026-05-20 19:10:13.005724 | controller | Wednesday 20 May 2026  19:10:13 +0000 (0:00:00.866)       0:01:24.999 *********
2026-05-20 19:10:13.361209 | controller | changed: [controller]
2026-05-20 19:10:13.361314 | controller |
2026-05-20 19:10:13.361329 | controller | TASK [vexxhost.kubernetes.kubernetes : Remove kubernetes repository] ***********
2026-05-20 19:10:13.361428 | controller | Wednesday 20 May 2026  19:10:13 +0000 (0:00:00.358)       0:01:25.357 *********
2026-05-20 19:10:13.901890 | controller | ok: [controller]
2026-05-20 19:10:13.901947 | controller |
2026-05-20 19:10:13.901955 | controller | TASK [vexxhost.kubernetes.kubernetes : Setup control plane] ********************
2026-05-20 19:10:13.901981 | controller | Wednesday 20 May 2026  19:10:13 +0000 (0:00:00.538)       0:01:25.896 *********
2026-05-20 19:10:13.980447 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/kubernetes/tasks/control-plane.yml for controller
2026-05-20 19:10:13.980529 | controller |
2026-05-20 19:10:13.980541 | controller | TASK [vexxhost.kubernetes.kubernetes : Bootstrap cluster] **********************
2026-05-20 19:10:13.980551 | controller | Wednesday 20 May 2026  19:10:13 +0000 (0:00:00.079)       0:01:25.976 *********
2026-05-20 19:10:14.054948 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/kubernetes/tasks/bootstrap-cluster.yml for controller
2026-05-20 19:10:14.054981 | controller |
2026-05-20 19:10:14.054992 | controller | TASK [vexxhost.kubernetes.kubernetes : Check if any control plane is bootstrapped] ***
2026-05-20 19:10:14.055001 | controller | Wednesday 20 May 2026  19:10:14 +0000 (0:00:00.074)       0:01:26.050 *********
2026-05-20 19:10:14.310648 | controller | ok: [controller] => (item=controller)
2026-05-20 19:10:14.310700 | controller |
2026-05-20 19:10:14.310705 | controller | TASK [vexxhost.kubernetes.kubernetes : Pick node from pre-existing cluster] ****
2026-05-20 19:10:14.310710 | controller | Wednesday 20 May 2026  19:10:14 +0000 (0:00:00.255)       0:01:26.306 *********
2026-05-20 19:10:14.355786 | controller | skipping: [controller]
2026-05-20 19:10:14.356372 | controller |
2026-05-20 19:10:14.356385 | controller | TASK [vexxhost.kubernetes.kubernetes : Select first node to initialize cluster] ***
2026-05-20 19:10:14.356392 | controller | Wednesday 20 May 2026  19:10:14 +0000 (0:00:00.045)       0:01:26.351 *********
2026-05-20 19:10:14.413357 | controller | ok: [controller]
2026-05-20 19:10:14.413434 | controller |
2026-05-20 19:10:14.413446 | controller | TASK [vexxhost.kubernetes.kubernetes : Print selected bootstrap node] **********
2026-05-20 19:10:14.413456 | controller | Wednesday 20 May 2026  19:10:14 +0000 (0:00:00.056)       0:01:26.408 *********
2026-05-20 19:10:14.456942 | controller | ok: [controller] => {
2026-05-20 19:10:14.456989 | controller |     "msg": "controller"
2026-05-20 19:10:14.456997 | controller | }
2026-05-20 19:10:14.457721 | controller |
2026-05-20 19:10:14.457770 | controller | TASK [vexxhost.kubernetes.kubernetes : Upload cluster configuration for bootstrap node] ***
2026-05-20 19:10:14.457782 | controller | Wednesday 20 May 2026  19:10:14 +0000 (0:00:00.044)       0:01:26.452 *********
2026-05-20 19:10:15.159334 | controller | changed: [controller]
2026-05-20 19:10:15.159429 | controller |
2026-05-20 19:10:15.159536 | controller | TASK [vexxhost.kubernetes.kubernetes : Create pki folder] **********************
2026-05-20 19:10:15.159704 | controller | Wednesday 20 May 2026  19:10:15 +0000 (0:00:00.702)       0:01:27.155 *********
2026-05-20 19:10:15.200937 | controller | skipping: [controller]
2026-05-20 19:10:15.201000 | controller |
2026-05-20 19:10:15.201011 | controller | TASK [vexxhost.kubernetes.kubernetes : Create kubernetes ca key] ***************
2026-05-20 19:10:15.201020 | controller | Wednesday 20 May 2026  19:10:15 +0000 (0:00:00.041)       0:01:27.196 *********
2026-05-20 19:10:15.242683 | controller | skipping: [controller]
2026-05-20 19:10:15.242767 | controller |
2026-05-20 19:10:15.242778 | controller | TASK [vexxhost.kubernetes.kubernetes : Create kubernetes ca cert] **************
2026-05-20 19:10:15.242825 | controller | Wednesday 20 May 2026  19:10:15 +0000 (0:00:00.040)       0:01:27.237 *********
2026-05-20 19:10:15.280391 | controller | skipping: [controller]
2026-05-20 19:10:15.280431 | controller |
2026-05-20 19:10:15.280565 | controller | TASK [vexxhost.kubernetes.kubernetes : Create etcd-ca key] *********************
2026-05-20 19:10:15.280669 | controller | Wednesday 20 May 2026  19:10:15 +0000 (0:00:00.038)       0:01:27.275 *********
2026-05-20 19:10:15.322440 | controller | skipping: [controller]
2026-05-20 19:10:15.322524 | controller |
2026-05-20 19:10:15.322534 | controller | TASK [vexxhost.kubernetes.kubernetes : Create etcd-ca cert] ********************
2026-05-20 19:10:15.322545 | controller | Wednesday 20 May 2026  19:10:15 +0000 (0:00:00.040)       0:01:27.316 *********
2026-05-20 19:10:15.360660 | controller | skipping: [controller]
2026-05-20 19:10:15.360691 | controller |
2026-05-20 19:10:15.360919 | controller | TASK [vexxhost.kubernetes.kubernetes : Create front-proxy-ca key] **************
2026-05-20 19:10:15.361209 | controller | Wednesday 20 May 2026  19:10:15 +0000 (0:00:00.039)       0:01:27.356 *********
2026-05-20 19:10:15.399264 | controller | skipping: [controller]
2026-05-20 19:10:15.399534 | controller |
2026-05-20 19:10:15.399850 | controller | TASK [vexxhost.kubernetes.kubernetes : Create front-proxy-ca cert] *************
2026-05-20 19:10:15.400131 | controller | Wednesday 20 May 2026  19:10:15 +0000 (0:00:00.039)       0:01:27.395 *********
2026-05-20 19:10:15.441011 | controller | skipping: [controller]
2026-05-20 19:10:15.441092 | controller |
2026-05-20 19:10:15.441103 | controller | TASK [vexxhost.kubernetes.kubernetes : Initialize cluster] *********************
2026-05-20 19:10:15.441120 | controller | Wednesday 20 May 2026  19:10:15 +0000 (0:00:00.040)       0:01:27.436 *********
2026-05-20 19:10:45.246657 | controller | changed: [controller]
2026-05-20 19:10:45.246751 | controller |
2026-05-20 19:10:45.247142 | controller | TASK [vexxhost.kubernetes.kubernetes : Check if the node is already part of the cluster] ***
2026-05-20 19:10:45.247287 | controller | Wednesday 20 May 2026  19:10:45 +0000 (0:00:29.806)       0:01:57.243 *********
2026-05-20 19:10:45.504911 | controller | ok: [controller]
2026-05-20 19:10:45.505038 | controller |
2026-05-20 19:10:45.505542 | controller | TASK [vexxhost.kubernetes.kubernetes : Join cluster] ***************************
2026-05-20 19:10:45.505620 | controller | Wednesday 20 May 2026  19:10:45 +0000 (0:00:00.257)       0:01:57.500 *********
2026-05-20 19:10:45.544616 | controller | skipping: [controller]
2026-05-20 19:10:45.544709 | controller |
2026-05-20 19:10:45.544723 | controller | TASK [vexxhost.kubernetes.kubernetes : Create folder for admin configuration] ***
2026-05-20 19:10:45.544736 | controller | Wednesday 20 May 2026  19:10:45 +0000 (0:00:00.039)       0:01:57.539 *********
2026-05-20 19:10:45.804338 | controller | changed: [controller]
2026-05-20 19:10:45.804417 | controller |
2026-05-20 19:10:45.804432 | controller | TASK [vexxhost.kubernetes.kubernetes : Creating a symlink for admin configuration file] ***
2026-05-20 19:10:45.804446 | controller | Wednesday 20 May 2026  19:10:45 +0000 (0:00:00.259)       0:01:57.799 *********
2026-05-20 19:10:46.092203 | controller | changed: [controller]
2026-05-20 19:10:46.092322 | controller |
2026-05-20 19:10:46.092531 | controller | TASK [vexxhost.kubernetes.kubernetes : Add bash autocomplete for kubectl] ******
2026-05-20 19:10:46.092746 | controller | Wednesday 20 May 2026  19:10:46 +0000 (0:00:00.288)       0:01:58.088 *********
2026-05-20 19:10:46.359686 | controller | changed: [controller]
2026-05-20 19:10:46.360933 | controller |
2026-05-20 19:10:46.361027 | controller | TASK [vexxhost.kubernetes.kubernetes : Install PIP] ****************************
2026-05-20 19:10:46.361043 | controller | Wednesday 20 May 2026  19:10:46 +0000 (0:00:00.267)       0:01:58.355 *********
2026-05-20 19:10:46.405934 | controller | skipping: [controller]
2026-05-20 19:10:46.406032 | controller |
2026-05-20 19:10:46.406043 | controller | TASK [vexxhost.kubernetes.kubernetes : Install Kubernetes Python package using pip on supported systems] ***
2026-05-20 19:10:46.406051 | controller | Wednesday 20 May 2026  19:10:46 +0000 (0:00:00.045)       0:01:58.401 *********
2026-05-20 19:10:46.448410 | controller | skipping: [controller]
2026-05-20 19:10:46.448521 | controller |
2026-05-20 19:10:46.448757 | controller | TASK [vexxhost.kubernetes.kubernetes : Enable EPEL repository] *****************
2026-05-20 19:10:46.449002 | controller | Wednesday 20 May 2026  19:10:46 +0000 (0:00:00.043)       0:01:58.445 *********
2026-05-20 19:10:46.495292 | controller | skipping: [controller]
2026-05-20 19:10:46.495469 | controller |
2026-05-20 19:10:46.495719 | controller | TASK [vexxhost.kubernetes.kubernetes : Install Kubernetes Python package using package manager on supported systems] ***
2026-05-20 19:10:46.495983 | controller | Wednesday 20 May 2026  19:10:46 +0000 (0:00:00.046)       0:01:58.492 *********
2026-05-20 19:10:53.692948 | controller | changed: [controller]
2026-05-20 19:10:53.693019 | controller |
2026-05-20 19:10:53.693033 | controller | TASK [vexxhost.kubernetes.kubernetes : Allow workload on control plane node] ***
2026-05-20 19:10:53.693749 | controller | Wednesday 20 May 2026  19:10:53 +0000 (0:00:07.196)       0:02:05.688 *********
2026-05-20 19:10:54.519000 | controller | changed: [controller]
2026-05-20 19:10:54.519099 | controller |
2026-05-20 19:10:54.519114 | controller | TASK [vexxhost.kubernetes.kubernetes : Remove kube-proxy resources] ************
2026-05-20 19:10:54.519172 | controller | Wednesday 20 May 2026  19:10:54 +0000 (0:00:00.826)       0:02:06.515 *********
2026-05-20 19:10:54.565081 | controller | skipping: [controller] => (item=DaemonSet)
2026-05-20 19:10:54.565185 | controller | skipping: [controller] => (item=ConfigMap)
2026-05-20 19:10:54.565197 | controller | skipping: [controller]
2026-05-20 19:10:54.565259 | controller |
2026-05-20 19:10:54.565373 | controller | TASK [vexxhost.kubernetes.kubernetes : Start an upgrade] ***********************
2026-05-20 19:10:54.565460 | controller | Wednesday 20 May 2026  19:10:54 +0000 (0:00:00.044)       0:02:06.560 *********
2026-05-20 19:10:54.597016 | controller | skipping: [controller]
2026-05-20 19:10:54.597103 | controller |
2026-05-20 19:10:54.597250 | controller | TASK [vexxhost.kubernetes.kubernetes : Check if the Kubernetes API services is up to date] ***
2026-05-20 19:10:54.597864 | controller | Wednesday 20 May 2026  19:10:54 +0000 (0:00:00.033)       0:02:06.593 *********
2026-05-20 19:10:54.637177 | controller | skipping: [controller]
2026-05-20 19:10:54.637238 | controller |
2026-05-20 19:10:54.637270 | controller | TASK [vexxhost.kubernetes.kubernetes : Trigger an upgrade of the Kubernetes API services] ***
2026-05-20 19:10:54.637281 | controller | Wednesday 20 May 2026  19:10:54 +0000 (0:00:00.039)       0:02:06.633 *********
2026-05-20 19:10:54.676208 | controller | skipping: [controller]
2026-05-20 19:10:54.676265 | controller |
2026-05-20 19:10:54.676276 | controller | TASK [vexxhost.kubernetes.kubernetes : Setup nodes] ****************************
2026-05-20 19:10:54.676285 | controller | Wednesday 20 May 2026  19:10:54 +0000 (0:00:00.039)       0:02:06.672 *********
2026-05-20 19:10:54.713363 | controller | skipping: [controller]
2026-05-20 19:10:54.713420 | controller |
2026-05-20 19:10:54.713575 | controller | TASK [vexxhost.kubernetes.kubernetes : Upgrade & restart Kubelet node for upgrade (if needed)] ***
2026-05-20 19:10:54.713880 | controller | Wednesday 20 May 2026  19:10:54 +0000 (0:00:00.037)       0:02:06.709 *********
2026-05-20 19:10:55.207759 | controller | ok: [controller]
2026-05-20 19:10:55.207982 | controller |
2026-05-20 19:10:55.208341 | controller | TASK [vexxhost.kubernetes.kubernetes : Set node selector for CoreDNS components] ***
2026-05-20 19:10:55.208777 | controller | Wednesday 20 May 2026  19:10:55 +0000 (0:00:00.493)       0:02:07.203 *********
2026-05-20 19:10:56.291465 | controller | changed: [controller]
2026-05-20 19:10:56.291657 | controller |
2026-05-20 19:10:56.291930 | controller | PLAY [Install control-plane components] ****************************************
2026-05-20 19:10:56.292192 | controller |
2026-05-20 19:10:56.292416 | controller | TASK [Gathering Facts] *********************************************************
2026-05-20 19:10:56.292602 | controller | Wednesday 20 May 2026  19:10:56 +0000 (0:00:01.084)       0:02:08.288 *********
2026-05-20 19:10:57.154842 | controller | ok: [controller]
2026-05-20 19:10:57.154886 | controller |
2026-05-20 19:10:57.154892 | controller | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-05-20 19:10:57.154899 | controller | Wednesday 20 May 2026  19:10:57 +0000 (0:00:00.860)       0:02:09.149 *********
2026-05-20 19:10:57.403412 | controller | ok: [controller]
2026-05-20 19:10:57.403736 | controller |
2026-05-20 19:10:57.404121 | controller | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-05-20 19:10:57.404811 | controller | Wednesday 20 May 2026  19:10:57 +0000 (0:00:00.249)       0:02:09.399 *********
2026-05-20 19:10:58.590417 | controller | ok: [controller]
2026-05-20 19:10:58.590453 | controller |
2026-05-20 19:10:58.590457 | controller | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] ***
2026-05-20 19:10:58.590462 | controller | Wednesday 20 May 2026  19:10:58 +0000 (0:00:01.187)       0:02:10.586 *********
2026-05-20 19:10:58.844725 | controller | ok: [controller]
2026-05-20 19:10:58.845638 | controller |
2026-05-20 19:10:58.845670 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-05-20 19:10:58.845684 | controller | Wednesday 20 May 2026  19:10:58 +0000 (0:00:00.255)       0:02:10.841 *********
2026-05-20 19:10:58.884947 | controller | ok: [controller] => {
2026-05-20 19:10:58.885030 | controller |     "msg": "https://get.helm.sh/helm-v3.11.2-linux-amd64.tar.gz"
2026-05-20 19:10:58.885245 | controller | }
2026-05-20 19:10:58.885422 | controller |
2026-05-20 19:10:58.885638 | controller | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-05-20 19:10:58.885865 | controller | Wednesday 20 May 2026  19:10:58 +0000 (0:00:00.039)       0:02:10.881 *********
2026-05-20 19:10:59.765000 | controller | changed: [controller]
2026-05-20 19:10:59.765120 | controller |
2026-05-20 19:10:59.765292 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-05-20 19:10:59.765504 | controller | Wednesday 20 May 2026  19:10:59 +0000 (0:00:00.880)       0:02:11.761 *********
2026-05-20 19:11:01.459209 | controller | changed: [controller]
2026-05-20 19:11:01.459272 | controller |
2026-05-20 19:11:01.459371 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm repository] ***********************
2026-05-20 19:11:01.459465 | controller | Wednesday 20 May 2026  19:11:01 +0000 (0:00:01.692)       0:02:13.454 *********
2026-05-20 19:11:01.846057 | controller | ok: [controller]
2026-05-20 19:11:01.846269 | controller |
2026-05-20 19:11:01.846561 | controller | TASK [vexxhost.kubernetes.helm : Add bash autocomplete for helm] ***************
2026-05-20 19:11:01.846783 | controller | Wednesday 20 May 2026  19:11:01 +0000 (0:00:00.387)       0:02:13.842 *********
2026-05-20 19:11:02.089432 | controller | changed: [controller]
2026-05-20 19:11:02.089592 | controller |
2026-05-20 19:11:02.089817 | controller | TASK [vexxhost.kubernetes.helm : Get Helm plugins dir] *************************
2026-05-20 19:11:02.090054 | controller | Wednesday 20 May 2026  19:11:02 +0000 (0:00:00.243)       0:02:14.085 *********
2026-05-20 19:11:02.400359 | controller | ok: [controller]
2026-05-20 19:11:02.400519 | controller |
2026-05-20 19:11:02.400751 | controller | TASK [vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist] ***
2026-05-20 19:11:02.400992 | controller | Wednesday 20 May 2026  19:11:02 +0000 (0:00:00.311)       0:02:14.397 *********
2026-05-20 19:11:02.641306 | controller | changed: [controller]
2026-05-20 19:11:02.641394 | controller |
2026-05-20 19:11:02.641533 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin] ***
2026-05-20 19:11:02.641641 | controller | Wednesday 20 May 2026  19:11:02 +0000 (0:00:00.238)       0:02:14.635 *********
2026-05-20 19:11:02.883321 | controller | ok: [controller]
2026-05-20 19:11:02.883491 | controller |
2026-05-20 19:11:02.883716 | controller | TASK [Install plugin] **********************************************************
2026-05-20 19:11:02.883983 | controller | Wednesday 20 May 2026  19:11:02 +0000 (0:00:00.243)       0:02:14.879 *********
2026-05-20 19:11:02.933495 | controller | included: vexxhost.containers.download_artifact for controller
2026-05-20 19:11:02.933664 | controller |
2026-05-20 19:11:02.933929 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-05-20 19:11:02.934121 | controller | Wednesday 20 May 2026  19:11:02 +0000 (0:00:00.050)       0:02:14.930 *********
2026-05-20 19:11:02.977758 | controller | ok: [controller] => {
2026-05-20 19:11:02.978073 | controller |     "msg": "https://github.com/databus23/helm-diff/releases/download/v3.8.1/helm-diff-linux-amd64.tgz"
2026-05-20 19:11:02.978300 | controller | }
2026-05-20 19:11:02.978472 | controller |
2026-05-20 19:11:02.978682 | controller | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-05-20 19:11:02.978941 | controller | Wednesday 20 May 2026  19:11:02 +0000 (0:00:00.043)       0:02:14.974 *********
2026-05-20 19:11:03.764956 | controller | changed: [controller]
2026-05-20 19:11:03.765128 | controller |
2026-05-20 19:11:03.765343 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-05-20 19:11:03.765551 | controller | Wednesday 20 May 2026  19:11:03 +0000 (0:00:00.786)       0:02:15.761 *********
2026-05-20 19:11:05.838884 | controller | changed: [controller]
2026-05-20 19:11:05.839952 | controller |
2026-05-20 19:11:05.840327 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] ***
2026-05-20 19:11:05.840427 | controller | Wednesday 20 May 2026  19:11:05 +0000 (0:00:02.073)       0:02:17.834 *********
2026-05-20 19:11:05.879247 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/synchronize.yml for controller
2026-05-20 19:11:05.879323 | controller |
2026-05-20 19:11:05.879370 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload Helm chart] ***************
2026-05-20 19:11:05.879475 | controller | Wednesday 20 May 2026  19:11:05 +0000 (0:00:00.041)       0:02:17.875 *********
2026-05-20 19:11:06.532645 | controller | changed: [controller]
2026-05-20 19:11:06.532886 | controller |
2026-05-20 19:11:06.533136 | controller | TASK [vexxhost.kubernetes.cilium : Get current Kubernetes version] *************
2026-05-20 19:11:06.533356 | controller | Wednesday 20 May 2026  19:11:06 +0000 (0:00:00.653)       0:02:18.529 *********
2026-05-20 19:11:07.418096 | controller | ok: [controller]
2026-05-20 19:11:07.418285 | controller |
2026-05-20 19:11:07.419174 | controller | TASK [vexxhost.kubernetes.cilium : Deploy Helm chart] **************************
2026-05-20 19:11:07.419415 | controller | Wednesday 20 May 2026  19:11:07 +0000 (0:00:00.885)       0:02:19.414 *********
2026-05-20 19:11:09.659837 | controller | changed: [controller]
2026-05-20 19:11:09.660013 | controller |
2026-05-20 19:11:09.660083 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] ***
2026-05-20 19:11:09.660238 | controller | Wednesday 20 May 2026  19:11:09 +0000 (0:00:02.237)       0:02:21.652 *********
2026-05-20 19:11:09.693571 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/synchronize.yml for controller
2026-05-20 19:11:09.693670 | controller |
2026-05-20 19:11:09.693857 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload Helm chart] ***************
2026-05-20 19:11:09.694046 | controller | Wednesday 20 May 2026  19:11:09 +0000 (0:00:00.037)       0:02:21.690 *********
2026-05-20 19:11:10.231736 | controller | changed: [controller]
2026-05-20 19:11:10.231775 | controller |
2026-05-20 19:11:10.231780 | controller | TASK [vexxhost.kubernetes.envoy_gateway : Deploy Helm chart] *******************
2026-05-20 19:11:10.231784 | controller | Wednesday 20 May 2026  19:11:10 +0000 (0:00:00.537)       0:02:22.227 *********
2026-05-20 19:11:45.288787 | controller | changed: [controller]
2026-05-20 19:11:45.289430 | controller |
2026-05-20 19:11:45.289447 | controller | TASK [vexxhost.kubernetes.envoy_gateway : Create GatewayClass] *****************
2026-05-20 19:11:45.289455 | controller | Wednesday 20 May 2026  19:11:45 +0000 (0:00:35.057)       0:02:57.284 *********
2026-05-20 19:11:46.140936 | controller | changed: [controller]
2026-05-20 19:11:46.140993 | controller |
2026-05-20 19:11:46.141007 | controller | PLAY [all] *********************************************************************
2026-05-20 19:11:46.141027 | controller |
2026-05-20 19:11:46.141342 | controller | TASK [Gathering Facts] *********************************************************
2026-05-20 19:11:46.141552 | controller | Wednesday 20 May 2026  19:11:46 +0000 (0:00:00.852)       0:02:58.137 *********
2026-05-20 19:11:48.122949 | controller | ok: [controller]
2026-05-20 19:11:48.123155 | controller |
2026-05-20 19:11:48.123356 | controller | TASK [vexxhost.atmosphere.kubernetes_node_labels : Add labels to node] *********
2026-05-20 19:11:48.123570 | controller | Wednesday 20 May 2026  19:11:48 +0000 (0:00:01.982)       0:03:00.119 *********
2026-05-20 19:11:48.976414 | controller | changed: [controller]
2026-05-20 19:11:48.976770 | controller |
2026-05-20 19:11:48.977022 | controller | PLAY [all] *********************************************************************
2026-05-20 19:11:48.977218 | controller |
2026-05-20 19:11:48.977493 | controller | TASK [Uninstall unattended-upgrades] *******************************************
2026-05-20 19:11:48.977700 | controller | Wednesday 20 May 2026  19:11:48 +0000 (0:00:00.853)       0:03:00.973 *********
2026-05-20 19:11:49.687928 | controller | ok: [controller]
2026-05-20 19:11:49.688007 | controller |
2026-05-20 19:11:49.688152 | controller | PLAY [controllers] *************************************************************
2026-05-20 19:11:49.688365 | controller |
2026-05-20 19:11:49.688578 | controller | TASK [Gathering Facts] *********************************************************
2026-05-20 19:11:49.688820 | controller | Wednesday 20 May 2026  19:11:49 +0000 (0:00:00.711)       0:03:01.684 *********
2026-05-20 19:11:50.809129 | controller | ok: [controller]
2026-05-20 19:11:50.809301 | controller |
2026-05-20 19:11:50.809529 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] ***
2026-05-20 19:11:50.809736 | controller | Wednesday 20 May 2026  19:11:50 +0000 (0:00:01.121)       0:03:02.805 *********
2026-05-20 19:11:50.845936 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/synchronize.yml for controller
2026-05-20 19:11:50.846114 | controller |
2026-05-20 19:11:50.846339 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload Helm chart] ***************
2026-05-20 19:11:50.846545 | controller | Wednesday 20 May 2026  19:11:50 +0000 (0:00:00.037)       0:03:02.842 *********
2026-05-20 19:11:51.353197 | controller | changed: [controller]
2026-05-20 19:11:51.353379 | controller |
2026-05-20 19:11:51.353725 | controller | TASK [vexxhost.atmosphere.local_path_provisioner : Deploy Helm chart] **********
2026-05-20 19:11:51.353928 | controller | Wednesday 20 May 2026  19:11:51 +0000 (0:00:00.506)       0:03:03.349 *********
2026-05-20 19:11:53.460385 | controller | changed: [controller]
2026-05-20 19:11:53.460707 | controller |
2026-05-20 19:11:53.460869 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] ***
2026-05-20 19:11:53.461120 | controller | Wednesday 20 May 2026  19:11:53 +0000 (0:00:02.106)       0:03:05.456 *********
2026-05-20 19:11:53.496106 | controller | skipping: [controller]
2026-05-20 19:11:53.496227 | controller |
2026-05-20 19:11:53.496376 | controller | TASK [vexxhost.atmosphere.ceph_csi_rbd : Collect "ceph mon dump" output from a monitor] ***
2026-05-20 19:11:53.496593 | controller | Wednesday 20 May 2026  19:11:53 +0000 (0:00:00.035)       0:03:05.492 *********
2026-05-20 19:11:53.533212 | controller | skipping: [controller]
2026-05-20 19:11:53.533398 | controller |
2026-05-20 19:11:53.533643 | controller | TASK [vexxhost.atmosphere.ceph_csi_rbd : Generate fact with Ceph FSID] *********
2026-05-20 19:11:53.533891 | controller | Wednesday 20 May 2026  19:11:53 +0000 (0:00:00.037)       0:03:05.529 *********
2026-05-20 19:11:53.567257 | controller | skipping: [controller]
2026-05-20 19:11:53.567325 | controller |
2026-05-20 19:11:53.567584 | controller | TASK [vexxhost.atmosphere.ceph_csi_rbd : Generate fact with list of Ceph monitors] ***
2026-05-20 19:11:53.567700 | controller | Wednesday 20 May 2026  19:11:53 +0000 (0:00:00.033)       0:03:05.563 *********
2026-05-20 19:11:53.598775 | controller | skipping: [controller]
2026-05-20 19:11:53.598815 | controller |
2026-05-20 19:11:53.598820 | controller | TASK [vexxhost.atmosphere.ceph_csi_rbd : Create Ceph pool] *********************
2026-05-20 19:11:53.598825 | controller | Wednesday 20 May 2026  19:11:53 +0000 (0:00:00.030)       0:03:05.594 *********
2026-05-20 19:11:53.637898 | controller | skipping: [controller]
2026-05-20 19:11:53.637955 | controller |
2026-05-20 19:11:53.637962 | controller | TASK [vexxhost.atmosphere.ceph_csi_rbd : Create user client.kube] **************
2026-05-20 19:11:53.637969 | controller | Wednesday 20 May 2026  19:11:53 +0000 (0:00:00.038)       0:03:05.633 *********
2026-05-20 19:11:53.671920 | controller | skipping: [controller]
2026-05-20 19:11:53.671978 | controller |
2026-05-20 19:11:53.671989 | controller | TASK [vexxhost.atmosphere.ceph_csi_rbd : Retrieve keyring for client.kube] *****
2026-05-20 19:11:53.671999 | controller | Wednesday 20 May 2026  19:11:53 +0000 (0:00:00.034)       0:03:05.668 *********
2026-05-20 19:11:53.713699 | controller | skipping: [controller]
2026-05-20 19:11:53.713743 | controller |
2026-05-20 19:11:53.713747 | controller | TASK [vexxhost.atmosphere.ceph_csi_rbd : Store keyring inside fact] ************
2026-05-20 19:11:53.713752 | controller | Wednesday 20 May 2026  19:11:53 +0000 (0:00:00.041)       0:03:05.709 *********
2026-05-20 19:11:53.747911 | controller | skipping: [controller]
2026-05-20 19:11:53.747962 | controller |
2026-05-20 19:11:53.747974 | controller | TASK [vexxhost.atmosphere.ceph_csi_rbd : Deploy Helm chart] ********************
2026-05-20 19:11:53.748138 | controller | Wednesday 20 May 2026  19:11:53 +0000 (0:00:00.034)       0:03:05.744 *********
2026-05-20 19:11:53.787007 | controller | skipping: [controller]
2026-05-20 19:11:53.787168 | controller |
2026-05-20 19:11:53.787493 | controller | TASK [vexxhost.atmosphere.powerstore_csi : Clone PowerStore CSI from GitHub] ***
2026-05-20 19:11:53.787713 | controller | Wednesday 20 May 2026  19:11:53 +0000 (0:00:00.039)       0:03:05.783 *********
2026-05-20 19:11:53.823647 | controller | skipping: [controller]
2026-05-20 19:11:53.823745 | controller |
2026-05-20 19:11:53.823922 | controller | TASK [vexxhost.atmosphere.powerstore_csi : Create Secret] **********************
2026-05-20 19:11:53.824062 | controller | Wednesday 20 May 2026  19:11:53 +0000 (0:00:00.036)       0:03:05.820 *********
2026-05-20 19:11:53.857305 | controller | skipping: [controller]
2026-05-20 19:11:53.857482 | controller |
2026-05-20 19:11:53.857692 | controller | TASK [vexxhost.atmosphere.powerstore_csi : Create StorageClass] ****************
2026-05-20 19:11:53.857945 | controller | Wednesday 20 May 2026  19:11:53 +0000 (0:00:00.033)       0:03:05.853 *********
2026-05-20 19:11:53.889389 | controller | skipping: [controller]
2026-05-20 19:11:53.889567 | controller |
2026-05-20 19:11:53.889778 | controller | TASK [vexxhost.atmosphere.powerstore_csi : Deploy Helm chart] ******************
2026-05-20 19:11:53.890034 | controller | Wednesday 20 May 2026  19:11:53 +0000 (0:00:00.032)       0:03:05.886 *********
2026-05-20 19:11:53.927895 | controller | skipping: [controller]
2026-05-20 19:11:53.927931 | controller |
2026-05-20 19:11:53.928132 | controller | TASK [vexxhost.atmosphere.multipathd : Add backports PPA] **********************
2026-05-20 19:11:53.928453 | controller | Wednesday 20 May 2026  19:11:53 +0000 (0:00:00.038)       0:03:05.924 *********
2026-05-20 19:11:53.962656 | controller | skipping: [controller]
2026-05-20 19:11:53.963051 | controller |
2026-05-20 19:11:53.963381 | controller | TASK [vexxhost.atmosphere.multipathd : Install the multipathd package] *********
2026-05-20 19:11:53.963640 | controller | Wednesday 20 May 2026  19:11:53 +0000 (0:00:00.034)       0:03:05.959 *********
2026-05-20 19:11:53.991962 | controller | skipping: [controller]
2026-05-20 19:11:53.992130 | controller |
2026-05-20 19:11:53.992334 | controller | TASK [vexxhost.atmosphere.multipathd : Install the configuration file] *********
2026-05-20 19:11:53.992544 | controller | Wednesday 20 May 2026  19:11:53 +0000 (0:00:00.029)       0:03:05.988 *********
2026-05-20 19:11:54.023620 | controller | skipping: [controller]
2026-05-20 19:11:54.023827 | controller |
2026-05-20 19:11:54.024047 | controller | TASK [vexxhost.atmosphere.portworx : Detect if InitiatorName is set] ***********
2026-05-20 19:11:54.024250 | controller | Wednesday 20 May 2026  19:11:54 +0000 (0:00:00.031)       0:03:06.020 *********
2026-05-20 19:11:54.055692 | controller | skipping: [controller]
2026-05-20 19:11:54.055918 | controller |
2026-05-20 19:11:54.056166 | controller | TASK [vexxhost.atmosphere.portworx : Generate a new InitiatorName] *************
2026-05-20 19:11:54.056370 | controller | Wednesday 20 May 2026  19:11:54 +0000 (0:00:00.031)       0:03:06.052 *********
2026-05-20 19:11:54.085360 | controller | skipping: [controller]
2026-05-20 19:11:54.085534 | controller |
2026-05-20 19:11:54.085740 | controller | TASK [vexxhost.atmosphere.portworx : Write the new InitiatorName] **************
2026-05-20 19:11:54.085967 | controller | Wednesday 20 May 2026  19:11:54 +0000 (0:00:00.030)       0:03:06.082 *********
2026-05-20 19:11:54.114997 | controller | skipping: [controller]
2026-05-20 19:11:54.115207 | controller |
2026-05-20 19:11:54.115500 | controller | TASK [vexxhost.atmosphere.portworx : Create namespace] *************************
2026-05-20 19:11:54.115767 | controller | Wednesday 20 May 2026  19:11:54 +0000 (0:00:00.029)       0:03:06.111 *********
2026-05-20 19:11:54.143831 | controller | skipping: [controller]
2026-05-20 19:11:54.144039 | controller |
2026-05-20 19:11:54.144247 | controller | TASK [vexxhost.atmosphere.portworx : Install Portworx] *************************
2026-05-20 19:11:54.144449 | controller | Wednesday 20 May 2026  19:11:54 +0000 (0:00:00.028)       0:03:06.140 *********
2026-05-20 19:11:54.171333 | controller | skipping: [controller]
2026-05-20 19:11:54.171505 | controller |
2026-05-20 19:11:54.171712 | controller | TASK [vexxhost.atmosphere.portworx : Wait till the CRDs are created] ***********
2026-05-20 19:11:54.172038 | controller | Wednesday 20 May 2026  19:11:54 +0000 (0:00:00.027)       0:03:06.168 *********
2026-05-20 19:11:54.203837 | controller | skipping: [controller]
2026-05-20 19:11:54.204021 | controller |
2026-05-20 19:11:54.204227 | controller | TASK [vexxhost.atmosphere.portworx : Create Portworx Storage Cluster] **********
2026-05-20 19:11:54.204434 | controller | Wednesday 20 May 2026  19:11:54 +0000 (0:00:00.032)       0:03:06.200 *********
2026-05-20 19:11:54.241948 | controller | skipping: [controller]
2026-05-20 19:11:54.242121 | controller |
2026-05-20 19:11:54.242332 | controller | TASK [vexxhost.atmosphere.storpool_csi : Deploy CSI RBAC] **********************
2026-05-20 19:11:54.242535 | controller | Wednesday 20 May 2026  19:11:54 +0000 (0:00:00.038)       0:03:06.238 *********
2026-05-20 19:11:54.281222 | controller | skipping: [controller] => (item={'name': 'controllerplugin'})
2026-05-20 19:11:54.281308 | controller | skipping: [controller] => (item={'name': 'nodeplugin'})
2026-05-20 19:11:54.281452 | controller | skipping: [controller]
2026-05-20 19:11:54.281668 | controller |
2026-05-20 19:11:54.281898 | controller | TASK [vexxhost.atmosphere.storpool_csi : Deploy CSI] ***************************
2026-05-20 19:11:54.282093 | controller | Wednesday 20 May 2026  19:11:54 +0000 (0:00:00.039)       0:03:06.278 *********
2026-05-20 19:11:54.314656 | controller | skipping: [controller]
2026-05-20 19:11:54.314828 | controller |
2026-05-20 19:11:54.315148 | controller | TASK [vexxhost.atmosphere.storpool_csi : Create StorageClass] ******************
2026-05-20 19:11:54.315435 | controller | Wednesday 20 May 2026  19:11:54 +0000 (0:00:00.032)       0:03:06.310 *********
2026-05-20 19:11:54.352287 | controller | skipping: [controller]
2026-05-20 19:11:54.352406 | controller |
2026-05-20 19:11:54.352662 | controller | TASK [vexxhost.atmosphere.ibm_block_csi_driver : Deploy CSI] *******************
2026-05-20 19:11:54.352821 | controller | Wednesday 20 May 2026  19:11:54 +0000 (0:00:00.038)       0:03:06.349 *********
2026-05-20 19:11:54.377994 | controller | skipping: [controller]
2026-05-20 19:11:54.378242 | controller |
2026-05-20 19:11:54.378448 | controller | TASK [vexxhost.atmosphere.ibm_block_csi_driver : Create Secret] ****************
2026-05-20 19:11:54.378651 | controller | Wednesday 20 May 2026  19:11:54 +0000 (0:00:00.025)       0:03:06.374 *********
2026-05-20 19:11:54.407360 | controller | skipping: [controller]
2026-05-20 19:11:54.407530 | controller |
2026-05-20 19:11:54.407741 | controller | TASK [vexxhost.atmosphere.ibm_block_csi_driver : Create StorageClass] **********
2026-05-20 19:11:54.407971 | controller | Wednesday 20 May 2026  19:11:54 +0000 (0:00:00.029)       0:03:06.404 *********
2026-05-20 19:11:54.475626 | controller | skipping: [controller]
2026-05-20 19:11:54.475831 | controller |
2026-05-20 19:11:54.476112 | controller | PLAY [Deploy minimal OpenStack services for Glance] ****************************
2026-05-20 19:11:54.476306 | controller |
2026-05-20 19:11:54.476502 | controller | TASK [Gathering Facts] *********************************************************
2026-05-20 19:11:54.476690 | controller | Wednesday 20 May 2026  19:11:54 +0000 (0:00:00.068)       0:03:06.472 *********
2026-05-20 19:11:56.640355 | controller | ok: [controller]
2026-05-20 19:11:56.640487 | controller |
2026-05-20 19:11:56.640610 | controller | TASK [Expose OS family for Atmosphere roles] ***********************************
2026-05-20 19:11:56.640737 | controller | Wednesday 20 May 2026  19:11:56 +0000 (0:00:02.164)       0:03:08.637 *********
2026-05-20 19:11:56.679388 | controller | ok: [controller]
2026-05-20 19:11:56.679500 | controller |
2026-05-20 19:11:56.679636 | controller | TASK [Deploy Helm chart] *******************************************************
2026-05-20 19:11:56.679752 | controller | Wednesday 20 May 2026  19:11:56 +0000 (0:00:00.039)       0:03:08.676 *********
2026-05-20 19:11:56.726495 | controller | included: vexxhost.kubernetes.cert_manager for controller
2026-05-20 19:11:56.726650 | controller |
2026-05-20 19:11:56.726920 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] ***
2026-05-20 19:11:56.727126 | controller | Wednesday 20 May 2026  19:11:56 +0000 (0:00:00.046)       0:03:08.723 *********
2026-05-20 19:11:56.762311 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/synchronize.yml for controller
2026-05-20 19:11:56.762466 | controller |
2026-05-20 19:11:56.762707 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload Helm chart] ***************
2026-05-20 19:11:56.762949 | controller | Wednesday 20 May 2026  19:11:56 +0000 (0:00:00.035)       0:03:08.759 *********
2026-05-20 19:11:57.256979 | controller | changed: [controller]
2026-05-20 19:11:57.257092 | controller |
2026-05-20 19:11:57.257300 | controller | TASK [vexxhost.kubernetes.cert_manager : Deploy Helm chart] ********************
2026-05-20 19:11:57.257503 | controller | Wednesday 20 May 2026  19:11:57 +0000 (0:00:00.494)       0:03:09.253 *********
2026-05-20 19:12:13.834423 | controller | changed: [controller]
2026-05-20 19:12:13.834549 | controller |
2026-05-20 19:12:13.834822 | controller | TASK [vexxhost.atmosphere.cluster_issuer : Create self-signed cluster issuer] ***
2026-05-20 19:12:13.835027 | controller | Wednesday 20 May 2026  19:12:13 +0000 (0:00:16.577)       0:03:25.830 *********
2026-05-20 19:12:14.691392 | controller | changed: [controller]
2026-05-20 19:12:14.691609 | controller |
2026-05-20 19:12:14.691896 | controller | TASK [vexxhost.atmosphere.cluster_issuer : Import tasks for ClusterIssuer type] ***
2026-05-20 19:12:14.692124 | controller | Wednesday 20 May 2026  19:12:14 +0000 (0:00:00.857)       0:03:26.687 *********
2026-05-20 19:12:14.726447 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/atmosphere/roles/cluster_issuer/tasks/type/self-signed/main.yml for controller
2026-05-20 19:12:14.726614 | controller |
2026-05-20 19:12:14.726923 | controller | TASK [vexxhost.atmosphere.cluster_issuer : Create ClusterIssuer] ***************
2026-05-20 19:12:14.727133 | controller | Wednesday 20 May 2026  19:12:14 +0000 (0:00:00.035)       0:03:26.723 *********
2026-05-20 19:12:15.548594 | controller | changed: [controller]
2026-05-20 19:12:15.548722 | controller |
2026-05-20 19:12:15.548836 | controller | TASK [vexxhost.atmosphere.cluster_issuer : Wait till the secret is created] ****
2026-05-20 19:12:15.548989 | controller | Wednesday 20 May 2026  19:12:15 +0000 (0:00:00.821)       0:03:27.545 *********
2026-05-20 19:12:16.330639 | controller | ok: [controller]
2026-05-20 19:12:16.330922 | controller |
2026-05-20 19:12:16.331199 | controller | TASK [vexxhost.atmosphere.cluster_issuer : Copy CA certificate on host] ********
2026-05-20 19:12:16.331435 | controller | Wednesday 20 May 2026  19:12:16 +0000 (0:00:00.781)       0:03:28.327 *********
2026-05-20 19:12:16.910509 | controller | changed: [controller]
2026-05-20 19:12:16.910580 | controller |
2026-05-20 19:12:16.910655 | controller | TASK [vexxhost.atmosphere.cluster_issuer : Flush all handlers] *****************
2026-05-20 19:12:16.910750 | controller | Wednesday 20 May 2026  19:12:16 +0000 (0:00:00.575)       0:03:28.903 *********
2026-05-20 19:12:16.910870 | controller |
2026-05-20 19:12:16.910974 | controller | RUNNING HANDLER [vexxhost.atmosphere.cluster_issuer : Install openssl-perl package] ***
2026-05-20 19:12:16.911060 | controller | Wednesday 20 May 2026  19:12:16 +0000 (0:00:00.004)       0:03:28.907 *********
2026-05-20 19:12:16.944413 | controller | skipping: [controller]
2026-05-20 19:12:16.944476 | controller |
2026-05-20 19:12:16.944584 | controller | RUNNING HANDLER [vexxhost.atmosphere.cluster_issuer : Update CA certificates] ***
2026-05-20 19:12:16.944680 | controller | Wednesday 20 May 2026  19:12:16 +0000 (0:00:00.034)       0:03:28.941 *********
2026-05-20 19:12:19.314487 | controller | changed: [controller]
2026-05-20 19:12:19.314668 | controller |
2026-05-20 19:12:19.314985 | controller | RUNNING HANDLER [vexxhost.atmosphere.cluster_issuer : Rehash CA certificates] ***
2026-05-20 19:12:19.315197 | controller | Wednesday 20 May 2026  19:12:19 +0000 (0:00:02.369)       0:03:31.311 *********
2026-05-20 19:12:29.424383 | controller | changed: [controller]
2026-05-20 19:12:29.424555 | controller |
2026-05-20 19:12:29.424876 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] ***
2026-05-20 19:12:29.425083 | controller | Wednesday 20 May 2026  19:12:29 +0000 (0:00:10.109)       0:03:41.421 *********
2026-05-20 19:12:29.460934 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/synchronize.yml for controller
2026-05-20 19:12:29.461124 | controller |
2026-05-20 19:12:29.461339 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload Helm chart] ***************
2026-05-20 19:12:29.461539 | controller | Wednesday 20 May 2026  19:12:29 +0000 (0:00:00.036)       0:03:41.457 *********
2026-05-20 19:12:30.041188 | controller | changed: [controller]
2026-05-20 19:12:30.041362 | controller |
2026-05-20 19:12:30.041583 | controller | TASK [vexxhost.atmosphere.ingress_nginx : Deploy Helm chart] *******************
2026-05-20 19:12:30.041824 | controller | Wednesday 20 May 2026  19:12:30 +0000 (0:00:00.579)       0:03:42.037 *********
2026-05-20 19:12:45.908662 | controller | changed: [controller]
2026-05-20 19:12:45.908825 | controller |
2026-05-20 19:12:45.909120 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] ***
2026-05-20 19:12:45.909329 | controller | Wednesday 20 May 2026  19:12:45 +0000 (0:00:15.867)       0:03:57.905 *********
2026-05-20 19:12:45.945841 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/synchronize.yml for controller
2026-05-20 19:12:45.946043 | controller |
2026-05-20 19:12:45.946258 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload Helm chart] ***************
2026-05-20 19:12:45.946484 | controller | Wednesday 20 May 2026  19:12:45 +0000 (0:00:00.037)       0:03:57.942 *********
2026-05-20 19:12:46.491952 | controller | changed: [controller]
2026-05-20 19:12:46.492055 | controller |
2026-05-20 19:12:46.492070 | controller | TASK [vexxhost.atmosphere.rabbitmq_cluster_operator : Install all CRDs] ********
2026-05-20 19:12:46.492200 | controller | Wednesday 20 May 2026  19:12:46 +0000 (0:00:00.545)       0:03:58.488 *********
2026-05-20 19:12:49.513941 | controller | ok: [controller] => (item=messaging-topology-operator)
2026-05-20 19:12:49.513999 | controller | ok: [controller] => (item=rabbitmq-cluster)
2026-05-20 19:12:49.514008 | controller |
2026-05-20 19:12:49.514016 | controller | TASK [vexxhost.atmosphere.rabbitmq_cluster_operator : Deploy Helm chart] *******
2026-05-20 19:12:49.514132 | controller | Wednesday 20 May 2026  19:12:49 +0000 (0:00:03.021)       0:04:01.509 *********
2026-05-20 19:12:52.913006 | controller | changed: [controller]
2026-05-20 19:12:52.913243 | controller |
2026-05-20 19:12:52.913519 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] ***
2026-05-20 19:12:52.913739 | controller | Wednesday 20 May 2026  19:12:52 +0000 (0:00:03.399)       0:04:04.909 *********
2026-05-20 19:12:52.949396 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/synchronize.yml for controller
2026-05-20 19:12:52.949591 | controller |
2026-05-20 19:12:52.949843 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload Helm chart] ***************
2026-05-20 19:12:52.950057 | controller | Wednesday 20 May 2026  19:12:52 +0000 (0:00:00.036)       0:04:04.946 *********
2026-05-20 19:12:53.453635 | controller | changed: [controller]
2026-05-20 19:12:53.453884 | controller |
2026-05-20 19:12:53.454143 | controller | TASK [vexxhost.atmosphere.percona_xtradb_cluster_operator : Install all CRDs] ***
2026-05-20 19:12:53.454382 | controller | Wednesday 20 May 2026  19:12:53 +0000 (0:00:00.504)       0:04:05.450 *********
2026-05-20 19:12:56.391189 | controller | ok: [controller]
2026-05-20 19:12:56.391359 | controller |
2026-05-20 19:12:56.391589 | controller | TASK [vexxhost.atmosphere.percona_xtradb_cluster_operator : Deploy Helm chart] ***
2026-05-20 19:12:56.391837 | controller | Wednesday 20 May 2026  19:12:56 +0000 (0:00:02.937)       0:04:08.387 *********
2026-05-20 19:12:58.881294 | controller | changed: [controller]
2026-05-20 19:12:58.881339 | controller |
2026-05-20 19:12:58.881348 | controller | TASK [vexxhost.atmosphere.percona_xtradb_cluster : Check if the Percona XtraDB cluster secret exists] ***
2026-05-20 19:12:58.881356 | controller | Wednesday 20 May 2026  19:12:58 +0000 (0:00:02.489)       0:04:10.877 *********
2026-05-20 19:12:59.657030 | controller | ok: [controller]
2026-05-20 19:12:59.657252 | controller |
2026-05-20 19:12:59.657400 | controller | TASK [vexxhost.atmosphere.percona_xtradb_cluster : Create a secret] ************
2026-05-20 19:12:59.657544 | controller | Wednesday 20 May 2026  19:12:59 +0000 (0:00:00.776)       0:04:11.653 *********
2026-05-20 19:13:00.447398 | controller | changed: [controller]
2026-05-20 19:13:00.447593 | controller |
2026-05-20 19:13:00.447954 | controller | TASK [vexxhost.atmosphere.percona_xtradb_cluster : Check if the Percona XtraDB cluster exists] ***
2026-05-20 19:13:00.448186 | controller | Wednesday 20 May 2026  19:13:00 +0000 (0:00:00.790)       0:04:12.443 *********
2026-05-20 19:13:01.635670 | controller | ok: [controller]
2026-05-20 19:13:01.635925 | controller |
2026-05-20 19:13:01.636186 | controller | TASK [vexxhost.atmosphere.percona_xtradb_cluster : Get current status of the cluster] ***
2026-05-20 19:13:01.636428 | controller | Wednesday 20 May 2026  19:13:01 +0000 (0:00:01.188)       0:04:13.632 *********
2026-05-20 19:13:01.683089 | controller | skipping: [controller]
2026-05-20 19:13:01.683282 | controller |
2026-05-20 19:13:01.683515 | controller | TASK [vexxhost.atmosphere.percona_xtradb_cluster : Assert that the cluster is healthy before upgrade] ***
2026-05-20 19:13:01.683730 | controller | Wednesday 20 May 2026  19:13:01 +0000 (0:00:00.046)       0:04:13.678 *********
2026-05-20 19:13:01.738506 | controller | skipping: [controller]
2026-05-20 19:13:01.738722 | controller |
2026-05-20 19:13:01.739070 | controller | TASK [vexxhost.atmosphere.percona_xtradb_cluster : Stop PXC-operator] **********
2026-05-20 19:13:01.739429 | controller | Wednesday 20 May 2026  19:13:01 +0000 (0:00:00.055)       0:04:13.734 *********
2026-05-20 19:13:01.784654 | controller | skipping: [controller]
2026-05-20 19:13:01.784868 | controller |
2026-05-20 19:13:01.785118 | controller | TASK [vexxhost.atmosphere.percona_xtradb_cluster : Change the cluster Statefulset image to 8.0] ***
2026-05-20 19:13:01.785353 | controller | Wednesday 20 May 2026  19:13:01 +0000 (0:00:00.046)       0:04:13.780 *********
2026-05-20 19:13:01.824598 | controller | skipping: [controller]
2026-05-20 19:13:01.824778 | controller |
2026-05-20 19:13:01.825044 | controller | TASK [vexxhost.atmosphere.percona_xtradb_cluster : Wait until the cluster Statefulset rollout] ***
2026-05-20 19:13:01.825269 | controller | Wednesday 20 May 2026  19:13:01 +0000 (0:00:00.040)       0:04:13.821 *********
2026-05-20 19:13:01.862262 | controller | skipping: [controller]
2026-05-20 19:13:01.862427 | controller |
2026-05-20 19:13:01.862637 | controller | TASK [vexxhost.atmosphere.percona_xtradb_cluster : Update pxc cluster spec] ****
2026-05-20 19:13:01.862822 | controller | Wednesday 20 May 2026  19:13:01 +0000 (0:00:00.037)       0:04:13.858 *********
2026-05-20 19:13:01.903210 | controller | skipping: [controller]
2026-05-20 19:13:01.903401 | controller |
2026-05-20 19:13:01.903614 | controller | TASK [vexxhost.atmosphere.percona_xtradb_cluster : Start PXC-operator] *********
2026-05-20 19:13:01.903821 | controller | Wednesday 20 May 2026  19:13:01 +0000 (0:00:00.041)       0:04:13.899 *********
2026-05-20 19:13:01.943276 | controller | skipping: [controller]
2026-05-20 19:13:01.943453 | controller |
2026-05-20 19:13:01.943679 | controller | TASK [vexxhost.atmosphere.percona_xtradb_cluster : Apply Percona XtraDB cluster] ***
2026-05-20 19:13:01.943967 | controller | Wednesday 20 May 2026  19:13:01 +0000 (0:00:00.039)       0:04:13.939 *********
2026-05-20 19:14:45.825650 | controller | changed: [controller]
2026-05-20 19:14:45.825718 | controller |
2026-05-20 19:14:45.825741 | controller | TASK [vexxhost.atmosphere.percona_xtradb_cluster : Create percona haproxy metric service] ***
2026-05-20 19:14:45.825951 | controller | Wednesday 20 May 2026  19:14:45 +0000 (0:01:43.882)       0:05:57.821 *********
2026-05-20 19:14:46.607231 | controller | changed: [controller]
2026-05-20 19:14:46.607336 | controller |
2026-05-20 19:14:46.607394 | controller | TASK [vexxhost.atmosphere.openstack_helm_endpoints : Retrieve list of all the needed endpoints] ***
2026-05-20 19:14:46.607487 | controller | Wednesday 20 May 2026  19:14:46 +0000 (0:00:00.781)       0:05:58.603 *********
2026-05-20 19:14:46.656511 | controller | ok: [controller]
2026-05-20 19:14:46.657307 | controller |
2026-05-20 19:14:46.657338 | controller | TASK [Create RabbitMQ cluster] *************************************************
2026-05-20 19:14:46.657352 | controller | Wednesday 20 May 2026  19:14:46 +0000 (0:00:00.048)       0:05:58.652 *********
2026-05-20 19:14:46.688970 | controller | skipping: [controller]
2026-05-20 19:14:46.689041 | controller |
2026-05-20 19:14:46.689054 | controller | TASK [vexxhost.atmosphere.openstack_helm_endpoints : Grab RabbitMQ cluster secret] ***
2026-05-20 19:14:46.689067 | controller | Wednesday 20 May 2026  19:14:46 +0000 (0:00:00.032)       0:05:58.685 *********
2026-05-20 19:14:46.717777 | controller | skipping: [controller]
2026-05-20 19:14:46.717931 | controller |
2026-05-20 19:14:46.718059 | controller | TASK [vexxhost.atmosphere.openstack_helm_endpoints : Cache fact with RabbitMQ cluster credentials] ***
2026-05-20 19:14:46.718197 | controller | Wednesday 20 May 2026  19:14:46 +0000 (0:00:00.029)       0:05:58.714 *********
2026-05-20 19:14:46.751512 | controller | skipping: [controller]
2026-05-20 19:14:46.751731 | controller |
2026-05-20 19:14:46.752019 | controller | TASK [vexxhost.atmosphere.openstack_helm_endpoints : Grab Percona XtraDB cluster secret] ***
2026-05-20 19:14:46.752065 | controller | Wednesday 20 May 2026  19:14:46 +0000 (0:00:00.032)       0:05:58.747 *********
2026-05-20 19:14:46.781480 | controller | skipping: [controller]
2026-05-20 19:14:46.781742 | controller |
2026-05-20 19:14:46.782925 | controller | TASK [vexxhost.atmosphere.openstack_helm_endpoints : Cache fact with Percona XtraDB password] ***
2026-05-20 19:14:46.782984 | controller | Wednesday 20 May 2026  19:14:46 +0000 (0:00:00.031)       0:05:58.778 *********
2026-05-20 19:14:46.812589 | controller | skipping: [controller]
2026-05-20 19:14:46.812720 | controller |
2026-05-20 19:14:46.812967 | controller | TASK [vexxhost.atmosphere.openstack_helm_endpoints : Reset value for OpenStack_Helm endpoints] ***
2026-05-20 19:14:46.813144 | controller | Wednesday 20 May 2026  19:14:46 +0000 (0:00:00.031)       0:05:58.809 *********
2026-05-20 19:14:46.846567 | controller | ok: [controller]
2026-05-20 19:14:46.846757 | controller |
2026-05-20 19:14:46.847018 | controller | TASK [vexxhost.atmosphere.openstack_helm_endpoints : Generate OpenStack-Helm endpoints] ***
2026-05-20 19:14:46.847228 | controller | Wednesday 20 May 2026  19:14:46 +0000 (0:00:00.033)       0:05:58.843 *********
2026-05-20 19:14:46.897181 | controller | ok: [controller] => (item=oslo_cache)
2026-05-20 19:14:46.897329 | controller |
2026-05-20 19:14:46.897550 | controller | TASK [vexxhost.atmosphere.openstack_helm_endpoints : Clean-up facts] ***********
2026-05-20 19:14:46.897753 | controller | Wednesday 20 May 2026  19:14:46 +0000 (0:00:00.050)       0:05:58.894 *********
2026-05-20 19:14:46.946995 | controller | ok: [controller]
2026-05-20 19:14:46.947138 | controller |
2026-05-20 19:14:46.947372 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] ***
2026-05-20 19:14:46.947591 | controller | Wednesday 20 May 2026  19:14:46 +0000 (0:00:00.048)       0:05:58.943 *********
2026-05-20 19:14:46.982557 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/synchronize.yml for controller
2026-05-20 19:14:46.982751 | controller |
2026-05-20 19:14:46.983026 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload Helm chart] ***************
2026-05-20 19:14:46.983326 | controller | Wednesday 20 May 2026  19:14:46 +0000 (0:00:00.036)       0:05:58.979 *********
2026-05-20 19:14:47.528141 | controller | changed: [controller]
2026-05-20 19:14:47.528313 | controller |
2026-05-20 19:14:47.528532 | controller | TASK [vexxhost.atmosphere.memcached : Deploy Helm chart] ***********************
2026-05-20 19:14:47.528753 | controller | Wednesday 20 May 2026  19:14:47 +0000 (0:00:00.545)       0:05:59.524 *********
2026-05-20 19:14:48.958211 | controller | changed: [controller]
2026-05-20 19:14:48.958393 | controller |
2026-05-20 19:14:48.958634 | controller | TASK [vexxhost.atmosphere.memcached : Apply manifests for monitoring] **********
2026-05-20 19:14:48.958899 | controller | Wednesday 20 May 2026  19:14:48 +0000 (0:00:01.429)       0:06:00.954 *********
2026-05-20 19:14:49.755042 | controller | changed: [controller]
2026-05-20 19:14:49.755101 | controller |
2026-05-20 19:14:49.756421 | controller | TASK [vexxhost.atmosphere.openstack_helm_endpoints : Retrieve list of all the needed endpoints] ***
2026-05-20 19:14:49.756486 | controller | Wednesday 20 May 2026  19:14:49 +0000 (0:00:00.795)       0:06:01.749 *********
2026-05-20 19:14:49.788366 | controller | skipping: [controller]
2026-05-20 19:14:49.788492 | controller |
2026-05-20 19:14:49.788636 | controller | TASK [Create RabbitMQ cluster] *************************************************
2026-05-20 19:14:49.788779 | controller | Wednesday 20 May 2026  19:14:49 +0000 (0:00:00.035)       0:06:01.785 *********
2026-05-20 19:14:49.818388 | controller | skipping: [controller]
2026-05-20 19:14:49.818477 | controller |
2026-05-20 19:14:49.818660 | controller | TASK [vexxhost.atmosphere.openstack_helm_endpoints : Grab RabbitMQ cluster secret] ***
2026-05-20 19:14:49.818782 | controller | Wednesday 20 May 2026  19:14:49 +0000 (0:00:00.030)       0:06:01.815 *********
2026-05-20 19:14:49.852573 | controller | skipping: [controller]
2026-05-20 19:14:49.852809 | controller |
2026-05-20 19:14:49.853017 | controller | TASK [vexxhost.atmosphere.openstack_helm_endpoints : Cache fact with RabbitMQ cluster credentials] ***
2026-05-20 19:14:49.853263 | controller | Wednesday 20 May 2026  19:14:49 +0000 (0:00:00.033)       0:06:01.849 *********
2026-05-20 19:14:49.880921 | controller | skipping: [controller]
2026-05-20 19:14:49.881096 | controller |
2026-05-20 19:14:49.881314 | controller | TASK [vexxhost.atmosphere.openstack_helm_endpoints : Grab Percona XtraDB cluster secret] ***
2026-05-20 19:14:49.881534 | controller | Wednesday 20 May 2026  19:14:49 +0000 (0:00:00.028)       0:06:01.877 *********
2026-05-20 19:14:50.642919 | controller | ok: [controller]
2026-05-20 19:14:50.642975 | controller |
2026-05-20 19:14:50.642985 | controller | TASK [vexxhost.atmosphere.openstack_helm_endpoints : Cache fact with Percona XtraDB password] ***
2026-05-20 19:14:50.642995 | controller | Wednesday 20 May 2026  19:14:50 +0000 (0:00:00.759)       0:06:02.637 *********
2026-05-20 19:14:50.685876 | controller | ok: [controller]
2026-05-20 19:14:50.685938 | controller |
2026-05-20 19:14:50.685982 | controller | TASK [vexxhost.atmosphere.openstack_helm_endpoints : Reset value for OpenStack_Helm endpoints] ***
2026-05-20 19:14:50.686089 | controller | Wednesday 20 May 2026  19:14:50 +0000 (0:00:00.044)       0:06:02.682 *********
2026-05-20 19:14:50.724091 | controller | ok: [controller]
2026-05-20 19:14:50.813091 | controller |
2026-05-20 19:14:50.813143 | controller | TASK [vexxhost.atmosphere.openstack_helm_endpoints : Generate OpenStack-Helm endpoints] ***
2026-05-20 19:14:50.813157 | controller | Wednesday 20 May 2026  19:14:50 +0000 (0:00:00.038)       0:06:02.720 *********
2026-05-20 19:14:50.841225 | controller | ok: [controller] => (item=oslo_db)
2026-05-20 19:14:50.841411 | controller |
2026-05-20 19:14:50.841635 | controller | TASK [vexxhost.atmosphere.openstack_helm_endpoints : Clean-up facts] ***********
2026-05-20 19:14:50.841863 | controller | Wednesday 20 May 2026  19:14:50 +0000 (0:00:00.117)       0:06:02.838 *********
2026-05-20 19:14:50.881530 | controller | ok: [controller]
2026-05-20 19:14:50.881775 | controller |
2026-05-20 19:14:50.881985 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] ***
2026-05-20 19:14:50.882199 | controller | Wednesday 20 May 2026  19:14:50 +0000 (0:00:00.040)       0:06:02.878 *********
2026-05-20 19:14:50.921185 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/synchronize.yml for controller
2026-05-20 19:14:50.921358 | controller |
2026-05-20 19:14:50.921595 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload Helm chart] ***************
2026-05-20 19:14:50.921804 | controller | Wednesday 20 May 2026  19:14:50 +0000 (0:00:00.039)       0:06:02.918 *********
2026-05-20 19:14:51.468438 | controller | changed: [controller]
2026-05-20 19:14:51.468619 | controller |
2026-05-20 19:14:51.468882 | controller | TASK [vexxhost.atmosphere.keycloak : Get the Kuberentes service for Percona XtraDB Cluster] ***
2026-05-20 19:14:51.469096 | controller | Wednesday 20 May 2026  19:14:51 +0000 (0:00:00.546)       0:06:03.464 *********
2026-05-20 19:14:52.229912 | controller | ok: [controller]
2026-05-20 19:14:52.229963 | controller |
2026-05-20 19:14:52.229975 | controller | TASK [vexxhost.atmosphere.keycloak : Install MySQL python package] *************
2026-05-20 19:14:52.229984 | controller | Wednesday 20 May 2026  19:14:52 +0000 (0:00:00.760)       0:06:04.225 *********
2026-05-20 19:14:53.463844 | controller | changed: [controller]
2026-05-20 19:14:53.463887 | controller |
2026-05-20 19:14:53.463894 | controller | TASK [vexxhost.atmosphere.keycloak : Check MySQL ready] ************************
2026-05-20 19:14:53.464009 | controller | Wednesday 20 May 2026  19:14:53 +0000 (0:00:01.234)       0:06:05.459 *********
2026-05-20 19:14:53.897528 | controller | ok: [controller]
2026-05-20 19:14:53.897730 | controller |
2026-05-20 19:14:53.898016 | controller | TASK [vexxhost.atmosphere.keycloak : Create Keycloak database] *****************
2026-05-20 19:14:53.898231 | controller | Wednesday 20 May 2026  19:14:53 +0000 (0:00:00.433)       0:06:05.892 *********
2026-05-20 19:14:54.351173 | controller | changed: [controller]
2026-05-20 19:14:54.351339 | controller |
2026-05-20 19:14:54.351555 | controller | TASK [vexxhost.atmosphere.keycloak : Create a Keycloak user] *******************
2026-05-20 19:14:54.351820 | controller | Wednesday 20 May 2026  19:14:54 +0000 (0:00:00.454)       0:06:06.347 *********
2026-05-20 19:14:54.833354 | controller | changed: [controller]
2026-05-20 19:14:54.833533 | controller |
2026-05-20 19:14:54.833901 | controller | TASK [vexxhost.atmosphere.keycloak : Disable pxc strict mode] ******************
2026-05-20 19:14:54.834127 | controller | Wednesday 20 May 2026  19:14:54 +0000 (0:00:00.482)       0:06:06.829 *********
2026-05-20 19:14:55.221901 | controller | ok: [controller]
2026-05-20 19:14:55.222066 | controller |
2026-05-20 19:14:55.222283 | controller | TASK [vexxhost.atmosphere.keycloak : Deploy Helm chart] ************************
2026-05-20 19:14:55.222488 | controller | Wednesday 20 May 2026  19:14:55 +0000 (0:00:00.388)       0:06:07.218 *********
2026-05-20 19:17:18.720851 | controller | changed: [controller]
2026-05-20 19:17:18.720890 | controller |
2026-05-20 19:17:18.720897 | controller | TASK [vexxhost.atmosphere.keycloak : Wait until keycloak ready] ****************
2026-05-20 19:17:18.720903 | controller | Wednesday 20 May 2026  19:17:18 +0000 (0:02:23.497)       0:08:30.716 *********
2026-05-20 19:17:19.523266 | controller | ok: [controller]
2026-05-20 19:17:19.523339 | controller |
2026-05-20 19:17:19.523483 | controller | TASK [Create Keycloak Ingress] *************************************************
2026-05-20 19:17:19.523615 | controller | Wednesday 20 May 2026  19:17:19 +0000 (0:00:00.803)       0:08:31.520 *********
2026-05-20 19:17:19.568664 | controller | included: ingress for controller
2026-05-20 19:17:19.569343 | controller |
2026-05-20 19:17:19.569366 | controller | TASK [vexxhost.atmosphere.ingress : Create Ingress keycloak] *******************
2026-05-20 19:17:19.569379 | controller | Wednesday 20 May 2026  19:17:19 +0000 (0:00:00.045)       0:08:31.565 *********
2026-05-20 19:17:20.601071 | controller | changed: [controller]
2026-05-20 19:17:20.601287 | controller |
2026-05-20 19:17:20.601519 | controller | TASK [vexxhost.atmosphere.keycloak : Enable pxc strict mode] *******************
2026-05-20 19:17:20.601728 | controller | Wednesday 20 May 2026  19:17:20 +0000 (0:00:01.032)       0:08:32.597 *********
2026-05-20 19:17:20.948235 | controller | ok: [controller]
2026-05-20 19:17:20.948303 | controller |
2026-05-20 19:17:20.948388 | controller | TASK [vexxhost.atmosphere.openstacksdk : Install openstacksdk] *****************
2026-05-20 19:17:20.948483 | controller | Wednesday 20 May 2026  19:17:20 +0000 (0:00:00.345)       0:08:32.943 *********
2026-05-20 19:17:22.131867 | controller | ok: [controller]
2026-05-20 19:17:22.131957 | controller |
2026-05-20 19:17:22.131997 | controller | TASK [vexxhost.atmosphere.openstacksdk : Create openstack config directory] ****
2026-05-20 19:17:22.132118 | controller | Wednesday 20 May 2026  19:17:22 +0000 (0:00:01.185)       0:08:34.128 *********
2026-05-20 19:17:22.382927 | controller | changed: [controller]
2026-05-20 19:17:22.382980 | controller |
2026-05-20 19:17:22.382997 | controller | TASK [vexxhost.atmosphere.openstacksdk : Generate cloud config file] ***********
2026-05-20 19:17:22.383191 | controller | Wednesday 20 May 2026  19:17:22 +0000 (0:00:00.249)       0:08:34.378 *********
2026-05-20 19:17:22.991256 | controller | changed: [controller]
2026-05-20 19:17:22.991322 | controller |
2026-05-20 19:17:22.991382 | controller | TASK [vexxhost.atmosphere.openstack_helm_endpoints : Retrieve list of all the needed endpoints] ***
2026-05-20 19:17:22.991502 | controller | Wednesday 20 May 2026  19:17:22 +0000 (0:00:00.609)       0:08:34.987 *********
2026-05-20 19:17:23.064107 | controller | ok: [controller]
2026-05-20 19:17:23.064158 | controller |
2026-05-20 19:17:23.064267 | controller | TASK [Create RabbitMQ cluster] *************************************************
2026-05-20 19:17:23.064358 | controller | Wednesday 20 May 2026  19:17:23 +0000 (0:00:00.071)       0:08:35.059 *********
2026-05-20 19:17:23.118363 | controller | included: rabbitmq for controller
2026-05-20 19:17:23.118412 | controller |
2026-05-20 19:17:23.118538 | controller | TASK [vexxhost.atmosphere.rabbitmq : Verify state of current RabbitMQ cluster] ***
2026-05-20 19:17:23.118615 | controller | Wednesday 20 May 2026  19:17:23 +0000 (0:00:00.055)       0:08:35.115 *********
2026-05-20 19:17:24.000611 | controller | ok: [controller]
2026-05-20 19:17:24.000681 | controller |
2026-05-20 19:17:24.000744 | controller | TASK [vexxhost.atmosphere.rabbitmq : Print diff of RabbitMQ cluster spec] ******
2026-05-20 19:17:24.000878 | controller | Wednesday 20 May 2026  19:17:23 +0000 (0:00:00.881)       0:08:35.997 *********
2026-05-20 19:17:24.042977 | controller | skipping: [controller]
2026-05-20 19:17:24.043015 | controller |
2026-05-20 19:17:24.043029 | controller | TASK [vexxhost.atmosphere.rabbitmq : Wait for confirmation of RabbitMQ cluster spec] ***
2026-05-20 19:17:24.043047 | controller | Wednesday 20 May 2026  19:17:24 +0000 (0:00:00.041)       0:08:36.038 *********
2026-05-20 19:17:24.083274 | controller | skipping: [controller]
2026-05-20 19:17:24.083399 | controller |
2026-05-20 19:17:24.083569 | controller | TASK [vexxhost.atmosphere.rabbitmq : Enable all feature flags for upgrade] *****
2026-05-20 19:17:24.083705 | controller | Wednesday 20 May 2026  19:17:24 +0000 (0:00:00.040)       0:08:36.079 *********
2026-05-20 19:17:24.118920 | controller | skipping: [controller]
2026-05-20 19:17:24.119009 | controller |
2026-05-20 19:17:24.119025 | controller | TASK [vexxhost.atmosphere.rabbitmq : Deploy cluster] ***************************
2026-05-20 19:17:24.119159 | controller | Wednesday 20 May 2026  19:17:24 +0000 (0:00:00.035)       0:08:36.115 *********
2026-05-20 19:18:05.057537 | controller | changed: [controller]
2026-05-20 19:18:05.057602 | controller |
2026-05-20 19:18:05.057616 | controller | TASK [vexxhost.atmosphere.openstack_helm_endpoints : Grab RabbitMQ cluster secret] ***
2026-05-20 19:18:05.057628 | controller | Wednesday 20 May 2026  19:18:05 +0000 (0:00:40.937)       0:09:17.052 *********
2026-05-20 19:18:05.829858 | controller | ok: [controller]
2026-05-20 19:18:05.829917 | controller |
2026-05-20 19:18:05.829931 | controller | TASK [vexxhost.atmosphere.openstack_helm_endpoints : Cache fact with RabbitMQ cluster credentials] ***
2026-05-20 19:18:05.829943 | controller | Wednesday 20 May 2026  19:18:05 +0000 (0:00:00.773)       0:09:17.825 *********
2026-05-20 19:18:05.869851 | controller | ok: [controller]
2026-05-20 19:18:05.869885 | controller |
2026-05-20 19:18:05.869898 | controller | TASK [vexxhost.atmosphere.openstack_helm_endpoints : Grab Percona XtraDB cluster secret] ***
2026-05-20 19:18:05.869910 | controller | Wednesday 20 May 2026  19:18:05 +0000 (0:00:00.040)       0:09:17.865 *********
2026-05-20 19:18:05.903091 | controller | skipping: [controller]
2026-05-20 19:18:05.903123 | controller |
2026-05-20 19:18:05.903136 | controller | TASK [vexxhost.atmosphere.openstack_helm_endpoints : Cache fact with Percona XtraDB password] ***
2026-05-20 19:18:05.903148 | controller | Wednesday 20 May 2026  19:18:05 +0000 (0:00:00.033)       0:09:17.899 *********
2026-05-20 19:18:05.944014 | controller | skipping: [controller]
2026-05-20 19:18:05.944047 | controller |
2026-05-20 19:18:05.944085 | controller | TASK [vexxhost.atmosphere.openstack_helm_endpoints : Reset value for OpenStack_Helm endpoints] ***
2026-05-20 19:18:05.944288 | controller | Wednesday 20 May 2026  19:18:05 +0000 (0:00:00.041)       0:09:17.940 *********
2026-05-20 19:18:05.978618 | controller | ok: [controller]
2026-05-20 19:18:05.978816 | controller |
2026-05-20 19:18:05.979006 | controller | TASK [vexxhost.atmosphere.openstack_helm_endpoints : Generate OpenStack-Helm endpoints] ***
2026-05-20 19:18:05.979177 | controller | Wednesday 20 May 2026  19:18:05 +0000 (0:00:00.034)       0:09:17.975 *********
2026-05-20 19:18:06.050357 | controller | ok: [controller] => (item=identity)
2026-05-20 19:18:06.050587 | controller | ok: [controller] => (item=oslo_cache)
2026-05-20 19:18:06.050809 | controller | ok: [controller] => (item=oslo_db)
2026-05-20 19:18:06.051027 | controller | ok: [controller] => (item=oslo_messaging)
2026-05-20 19:18:06.051209 | controller |
2026-05-20 19:18:06.051408 | controller | TASK [vexxhost.atmosphere.openstack_helm_endpoints : Clean-up facts] ***********
2026-05-20 19:18:06.051619 | controller | Wednesday 20 May 2026  19:18:06 +0000 (0:00:00.072)       0:09:18.047 *********
2026-05-20 19:18:06.091355 | controller | ok: [controller]
2026-05-20 19:18:06.091520 | controller |
2026-05-20 19:18:06.092371 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] ***
2026-05-20 19:18:06.092996 | controller | Wednesday 20 May 2026  19:18:06 +0000 (0:00:00.039)       0:09:18.087 *********
2026-05-20 19:18:06.127972 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/synchronize.yml for controller
2026-05-20 19:18:06.128037 | controller |
2026-05-20 19:18:06.128055 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload Helm chart] ***************
2026-05-20 19:18:06.128297 | controller | Wednesday 20 May 2026  19:18:06 +0000 (0:00:00.037)       0:09:18.124 *********
2026-05-20 19:18:06.673433 | controller | changed: [controller]
2026-05-20 19:18:06.673735 | controller |
2026-05-20 19:18:06.674018 | controller | TASK [vexxhost.atmosphere.keystone : Create Keycloak realms] *******************
2026-05-20 19:18:06.674279 | controller | Wednesday 20 May 2026  19:18:06 +0000 (0:00:00.545)       0:09:18.669 *********
2026-05-20 19:18:15.913279 | controller | ok: [controller] => (item=None)
2026-05-20 19:18:15.913390 | controller | ok: [controller]
2026-05-20 19:18:15.913400 | controller |
2026-05-20 19:18:15.913544 | controller | TASK [vexxhost.atmosphere.keystone : Setup Keycloak Authentication Required Actions (MFA)] ***
2026-05-20 19:18:15.913673 | controller | Wednesday 20 May 2026  19:18:15 +0000 (0:00:09.237)       0:09:27.907 *********
2026-05-20 19:18:17.116932 | controller | ok: [controller] => (item=atmosphere)
2026-05-20 19:18:17.117118 | controller |
2026-05-20 19:18:17.117384 | controller | TASK [vexxhost.atmosphere.keystone : Create ConfigMap with all OpenID connect configurations] ***
2026-05-20 19:18:17.117652 | controller | Wednesday 20 May 2026  19:18:17 +0000 (0:00:01.205)       0:09:29.112 *********
2026-05-20 19:18:18.269750 | controller | changed: [controller]
2026-05-20 19:18:18.269879 | controller |
2026-05-20 19:18:18.269971 | controller | TASK [vexxhost.atmosphere.keystone : Create Keycloak clients] ******************
2026-05-20 19:18:18.270101 | controller | Wednesday 20 May 2026  19:18:18 +0000 (0:00:01.151)       0:09:30.264 *********
2026-05-20 19:18:19.739857 | controller | changed: [controller] => (item=None)
2026-05-20 19:18:19.739928 | controller | changed: [controller]
2026-05-20 19:18:19.739939 | controller |
2026-05-20 19:18:19.739947 | controller | TASK [vexxhost.atmosphere.keystone : Assign realm-management roles to service account] ***
2026-05-20 19:18:19.739955 | controller | Wednesday 20 May 2026  19:18:19 +0000 (0:00:01.470)       0:09:31.734 *********
2026-05-20 19:18:21.605625 | controller | changed: [controller] => (item=None)
2026-05-20 19:18:21.605706 | controller | changed: [controller]
2026-05-20 19:18:21.605923 | controller |
2026-05-20 19:18:21.606156 | controller | TASK [vexxhost.atmosphere.keystone : Deploy Helm chart] ************************
2026-05-20 19:18:21.606378 | controller | Wednesday 20 May 2026  19:18:21 +0000 (0:00:01.866)       0:09:33.601 *********
2026-05-20 19:21:05.781491 | controller | changed: [controller]
2026-05-20 19:21:05.781586 | controller |
2026-05-20 19:21:05.781601 | controller | TASK [Create Ingress] **********************************************************
2026-05-20 19:21:05.781662 | controller | Wednesday 20 May 2026  19:21:05 +0000 (0:02:44.176)       0:12:17.777 *********
2026-05-20 19:21:05.848641 | controller | included: openstack_helm_ingress for controller
2026-05-20 19:21:05.848704 | controller |
2026-05-20 19:21:05.848757 | controller | TASK [vexxhost.atmosphere.openstack_helm_ingress : Create certificate] *********
2026-05-20 19:21:05.848883 | controller | Wednesday 20 May 2026  19:21:05 +0000 (0:00:00.067)       0:12:17.845 *********
2026-05-20 19:21:05.894760 | controller | skipping: [controller]
2026-05-20 19:21:05.894843 | controller |
2026-05-20 19:21:05.894945 | controller | TASK [vexxhost.atmosphere.openstack_helm_ingress : Set fact with wildcard certificate] ***
2026-05-20 19:21:05.894995 | controller | Wednesday 20 May 2026  19:21:05 +0000 (0:00:00.046)       0:12:17.891 *********
2026-05-20 19:21:05.942878 | controller | skipping: [controller]
2026-05-20 19:21:05.942932 | controller |
2026-05-20 19:21:05.942942 | controller | TASK [vexxhost.atmosphere.openstack_helm_ingress : Add ClusterIssuer annotations] ***
2026-05-20 19:21:05.942952 | controller | Wednesday 20 May 2026  19:21:05 +0000 (0:00:00.046)       0:12:17.937 *********
2026-05-20 19:21:05.999191 | controller | ok: [controller]
2026-05-20 19:21:05.999249 | controller |
2026-05-20 19:21:05.999260 | controller | TASK [Create Ingress identity] *************************************************
2026-05-20 19:21:05.999270 | controller | Wednesday 20 May 2026  19:21:05 +0000 (0:00:00.057)       0:12:17.995 *********
2026-05-20 19:21:06.048203 | controller | included: ingress for controller
2026-05-20 19:21:06.048255 | controller |
2026-05-20 19:21:06.048338 | controller | TASK [vexxhost.atmosphere.ingress : Create Ingress identity] *******************
2026-05-20 19:21:06.048434 | controller | Wednesday 20 May 2026  19:21:06 +0000 (0:00:00.049)       0:12:18.045 *********
2026-05-20 19:21:07.091474 | controller | changed: [controller]
2026-05-20 19:21:07.091542 | controller |
2026-05-20 19:21:07.091587 | controller | TASK [vexxhost.atmosphere.keystone : Validate if ingress is reachable] *********
2026-05-20 19:21:07.091710 | controller | Wednesday 20 May 2026  19:21:07 +0000 (0:00:01.041)       0:12:19.086 *********
2026-05-20 19:21:10.474122 | controller | FAILED - RETRYING: [controller]: Validate if ingress is reachable (120 retries left).
2026-05-20 19:21:10.474307 | controller | FAILED - RETRYING: [controller]: Validate if ingress is reachable (119 retries left).
2026-05-20 19:21:10.474509 | controller | ok: [controller]
2026-05-20 19:21:10.474696 | controller |
2026-05-20 19:21:10.475057 | controller | TASK [vexxhost.atmosphere.keystone : Wait until identity service ready] ********
2026-05-20 19:21:10.475278 | controller | Wednesday 20 May 2026  19:21:10 +0000 (0:00:03.383)       0:12:22.469 *********
2026-05-20 19:21:11.388032 | controller | ok: [controller]
2026-05-20 19:21:11.388154 | controller |
2026-05-20 19:21:11.388644 | controller | TASK [vexxhost.atmosphere.keystone : Create Keystone domains] ******************
2026-05-20 19:21:11.388881 | controller | Wednesday 20 May 2026  19:21:11 +0000 (0:00:00.914)       0:12:23.384 *********
2026-05-20 19:21:12.642110 | controller | ok: [controller] => (item=atmosphere)
2026-05-20 19:21:12.642283 | controller |
2026-05-20 19:21:12.642507 | controller | TASK [vexxhost.atmosphere.keystone : Create Keystone identity providers] *******
2026-05-20 19:21:12.642720 | controller | Wednesday 20 May 2026  19:21:12 +0000 (0:00:01.253)       0:12:24.638 *********
2026-05-20 19:21:13.870224 | controller | changed: [controller] => (item=atmosphere)
2026-05-20 19:21:13.870391 | controller |
2026-05-20 19:21:13.870615 | controller | TASK [vexxhost.atmosphere.keystone : Create Keystone federation mappings] ******
2026-05-20 19:21:13.870859 | controller | Wednesday 20 May 2026  19:21:13 +0000 (0:00:01.228)       0:12:25.866 *********
2026-05-20 19:21:14.996129 | controller | changed: [controller] => (item=atmosphere)
2026-05-20 19:21:14.996302 | controller |
2026-05-20 19:21:14.996545 | controller | TASK [vexxhost.atmosphere.keystone : Create Keystone federation protocols] *****
2026-05-20 19:21:14.996755 | controller | Wednesday 20 May 2026  19:21:14 +0000 (0:00:01.126)       0:12:26.992 *********
2026-05-20 19:21:16.168826 | controller | changed: [controller] => (item=atmosphere)
2026-05-20 19:21:16.169288 | controller |
2026-05-20 19:21:16.169311 | controller | TASK [vexxhost.atmosphere.openstack_helm_endpoints : Retrieve list of all the needed endpoints] ***
2026-05-20 19:21:16.169559 | controller | Wednesday 20 May 2026  19:21:16 +0000 (0:00:01.172)       0:12:28.164 *********
2026-05-20 19:21:16.244091 | controller | ok: [controller]
2026-05-20 19:21:16.244254 | controller |
2026-05-20 19:21:16.244474 | controller | TASK [Create RabbitMQ cluster] *************************************************
2026-05-20 19:21:16.244686 | controller | Wednesday 20 May 2026  19:21:16 +0000 (0:00:00.075)       0:12:28.240 *********
2026-05-20 19:21:16.296276 | controller | included: rabbitmq for controller
2026-05-20 19:21:16.296414 | controller |
2026-05-20 19:21:16.296656 | controller | TASK [vexxhost.atmosphere.rabbitmq : Verify state of current RabbitMQ cluster] ***
2026-05-20 19:21:16.296895 | controller | Wednesday 20 May 2026  19:21:16 +0000 (0:00:00.052)       0:12:28.292 *********
2026-05-20 19:21:17.164619 | controller | ok: [controller]
2026-05-20 19:21:17.164786 | controller |
2026-05-20 19:21:17.165051 | controller | TASK [vexxhost.atmosphere.rabbitmq : Print diff of RabbitMQ cluster spec] ******
2026-05-20 19:21:17.165371 | controller | Wednesday 20 May 2026  19:21:17 +0000 (0:00:00.867)       0:12:29.160 *********
2026-05-20 19:21:17.210925 | controller | skipping: [controller]
2026-05-20 19:21:17.211063 | controller |
2026-05-20 19:21:17.211382 | controller | TASK [vexxhost.atmosphere.rabbitmq : Wait for confirmation of RabbitMQ cluster spec] ***
2026-05-20 19:21:17.211591 | controller | Wednesday 20 May 2026  19:21:17 +0000 (0:00:00.046)       0:12:29.207 *********
2026-05-20 19:21:17.256479 | controller | skipping: [controller]
2026-05-20 19:21:17.256814 | controller |
2026-05-20 19:21:17.257173 | controller | TASK [vexxhost.atmosphere.rabbitmq : Enable all feature flags for upgrade] *****
2026-05-20 19:21:17.257283 | controller | Wednesday 20 May 2026  19:21:17 +0000 (0:00:00.045)       0:12:29.253 *********
2026-05-20 19:21:17.300099 | controller | skipping: [controller]
2026-05-20 19:21:17.300149 | controller |
2026-05-20 19:21:17.300250 | controller | TASK [vexxhost.atmosphere.rabbitmq : Deploy cluster] ***************************
2026-05-20 19:21:17.300417 | controller | Wednesday 20 May 2026  19:21:17 +0000 (0:00:00.043)       0:12:29.297 *********
2026-05-20 19:21:48.306033 | controller | changed: [controller]
2026-05-20 19:21:48.306197 | controller |
2026-05-20 19:21:48.306471 | controller | TASK [vexxhost.atmosphere.openstack_helm_endpoints : Grab RabbitMQ cluster secret] ***
2026-05-20 19:21:48.306720 | controller | Wednesday 20 May 2026  19:21:48 +0000 (0:00:31.004)       0:13:00.302 *********
2026-05-20 19:21:49.150259 | controller | ok: [controller]
2026-05-20 19:21:49.150461 | controller |
2026-05-20 19:21:49.150762 | controller | TASK [vexxhost.atmosphere.openstack_helm_endpoints : Cache fact with RabbitMQ cluster credentials] ***
2026-05-20 19:21:49.151142 | controller | Wednesday 20 May 2026  19:21:49 +0000 (0:00:00.843)       0:13:01.145 *********
2026-05-20 19:21:49.199177 | controller | ok: [controller]
2026-05-20 19:21:49.199259 | controller |
2026-05-20 19:21:49.199382 | controller | TASK [vexxhost.atmosphere.openstack_helm_endpoints : Grab Percona XtraDB cluster secret] ***
2026-05-20 19:21:49.199515 | controller | Wednesday 20 May 2026  19:21:49 +0000 (0:00:00.050)       0:13:01.196 *********
2026-05-20 19:21:49.246087 | controller | skipping: [controller]
2026-05-20 19:21:49.246237 | controller |
2026-05-20 19:21:49.246482 | controller | TASK [vexxhost.atmosphere.openstack_helm_endpoints : Cache fact with Percona XtraDB password] ***
2026-05-20 19:21:49.246707 | controller | Wednesday 20 May 2026  19:21:49 +0000 (0:00:00.046)       0:13:01.242 *********
2026-05-20 19:21:49.289953 | controller | skipping: [controller]
2026-05-20 19:21:49.290109 | controller |
2026-05-20 19:21:49.290362 | controller | TASK [vexxhost.atmosphere.openstack_helm_endpoints : Reset value for OpenStack_Helm endpoints] ***
2026-05-20 19:21:49.290586 | controller | Wednesday 20 May 2026  19:21:49 +0000 (0:00:00.044)       0:13:01.286 *********
2026-05-20 19:21:49.330232 | controller | ok: [controller]
2026-05-20 19:21:49.330363 | controller |
2026-05-20 19:21:49.330617 | controller | TASK [vexxhost.atmosphere.openstack_helm_endpoints : Generate OpenStack-Helm endpoints] ***
2026-05-20 19:21:49.330902 | controller | Wednesday 20 May 2026  19:21:49 +0000 (0:00:00.040)       0:13:01.326 *********
2026-05-20 19:21:49.437877 | controller | ok: [controller] => (item=dashboard)
2026-05-20 19:21:49.438073 | controller | ok: [controller] => (item=identity)
2026-05-20 19:21:49.438282 | controller | ok: [controller] => (item=oslo_db)
2026-05-20 19:21:49.438486 | controller | ok: [controller] => (item=oslo_messaging)
2026-05-20 19:21:49.438702 | controller | ok: [controller] => (item=oslo_cache)
2026-05-20 19:21:49.438943 | controller | ok: [controller] => (item=image)
2026-05-20 19:21:49.439132 | controller |
2026-05-20 19:21:49.439391 | controller | TASK [vexxhost.atmosphere.openstack_helm_endpoints : Clean-up facts] ***********
2026-05-20 19:21:49.439602 | controller | Wednesday 20 May 2026  19:21:49 +0000 (0:00:00.106)       0:13:01.433 *********
2026-05-20 19:21:49.480730 | controller | ok: [controller]
2026-05-20 19:21:49.480943 | controller |
2026-05-20 19:21:49.481188 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] ***
2026-05-20 19:21:49.481413 | controller | Wednesday 20 May 2026  19:21:49 +0000 (0:00:00.044)       0:13:01.477 *********
2026-05-20 19:21:49.525460 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/synchronize.yml for controller
2026-05-20 19:21:49.525605 | controller |
2026-05-20 19:21:49.525889 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload Helm chart] ***************
2026-05-20 19:21:49.526148 | controller | Wednesday 20 May 2026  19:21:49 +0000 (0:00:00.044)       0:13:01.521 *********
2026-05-20 19:21:50.127206 | controller | changed: [controller]
2026-05-20 19:21:50.127304 | controller |
2026-05-20 19:21:50.127425 | controller | TASK [vexxhost.atmosphere.glance : Generate Helm values (storage)] *************
2026-05-20 19:21:50.127557 | controller | Wednesday 20 May 2026  19:21:50 +0000 (0:00:00.602)       0:13:02.123 *********
2026-05-20 19:21:50.370958 | controller | ok: [controller]
2026-05-20 19:21:50.371080 | controller |
2026-05-20 19:21:50.371272 | controller | TASK [vexxhost.atmosphere.glance : Deploy Helm chart] **************************
2026-05-20 19:21:50.371480 | controller | Wednesday 20 May 2026  19:21:50 +0000 (0:00:00.243)       0:13:02.367 *********
2026-05-20 19:24:03.197323 | controller | changed: [controller]
2026-05-20 19:24:03.197469 | controller |
2026-05-20 19:24:03.197677 | controller | TASK [Create Ingress] **********************************************************
2026-05-20 19:24:03.197920 | controller | Wednesday 20 May 2026  19:24:03 +0000 (0:02:12.826)       0:15:15.193 *********
2026-05-20 19:24:03.258025 | controller | included: openstack_helm_ingress for controller
2026-05-20 19:24:03.258126 | controller |
2026-05-20 19:24:03.258397 | controller | TASK [vexxhost.atmosphere.openstack_helm_ingress : Create certificate] *********
2026-05-20 19:24:03.258558 | controller | Wednesday 20 May 2026  19:24:03 +0000 (0:00:00.061)       0:15:15.254 *********
2026-05-20 19:24:03.302160 | controller | skipping: [controller]
2026-05-20 19:24:03.302443 | controller |
2026-05-20 19:24:03.302687 | controller | TASK [vexxhost.atmosphere.openstack_helm_ingress : Set fact with wildcard certificate] ***
2026-05-20 19:24:03.302983 | controller | Wednesday 20 May 2026  19:24:03 +0000 (0:00:00.043)       0:15:15.298 *********
2026-05-20 19:24:03.355971 | controller | skipping: [controller]
2026-05-20 19:24:03.356050 | controller |
2026-05-20 19:24:03.356072 | controller | TASK [vexxhost.atmosphere.openstack_helm_ingress : Add ClusterIssuer annotations] ***
2026-05-20 19:24:03.356231 | controller | Wednesday 20 May 2026  19:24:03 +0000 (0:00:00.051)       0:15:15.350 *********
2026-05-20 19:24:03.417232 | controller | ok: [controller]
2026-05-20 19:24:03.417401 | controller |
2026-05-20 19:24:03.417630 | controller | TASK [Create Ingress image] ****************************************************
2026-05-20 19:24:03.417889 | controller | Wednesday 20 May 2026  19:24:03 +0000 (0:00:00.063)       0:15:15.413 *********
2026-05-20 19:24:03.463006 | controller | included: ingress for controller
2026-05-20 19:24:03.463156 | controller |
2026-05-20 19:24:03.463394 | controller | TASK [vexxhost.atmosphere.ingress : Create Ingress image] **********************
2026-05-20 19:24:03.463655 | controller | Wednesday 20 May 2026  19:24:03 +0000 (0:00:00.045)       0:15:15.459 *********
2026-05-20 19:24:04.480039 | controller | changed: [controller]
2026-05-20 19:24:04.480209 | controller |
2026-05-20 19:24:04.480418 | controller | TASK [Create images] ***********************************************************
2026-05-20 19:24:04.480627 | controller | Wednesday 20 May 2026  19:24:04 +0000 (0:00:01.017)       0:15:16.476 *********
2026-05-20 19:24:04.519341 | controller | skipping: [controller]
2026-05-20 19:24:04.519445 | controller |
2026-05-20 19:24:04.519503 | controller | PLAY [Prepare local image source] **********************************************
2026-05-20 19:24:04.519593 | controller |
2026-05-20 19:24:04.521914 | controller | TASK [Gathering Facts] *********************************************************
2026-05-20 19:24:04.522124 | controller | Wednesday 20 May 2026  19:24:04 +0000 (0:00:00.039)       0:15:16.516 *********
2026-05-20 19:24:06.239872 | controller | ok: [controller]
2026-05-20 19:24:06.239984 | controller |
2026-05-20 19:24:06.240229 | controller | TASK [Create image source directory] *******************************************
2026-05-20 19:24:06.240472 | controller | Wednesday 20 May 2026  19:24:06 +0000 (0:00:01.719)       0:15:18.235 *********
2026-05-20 19:24:06.497772 | controller | changed: [controller]
2026-05-20 19:24:06.497997 | controller |
2026-05-20 19:24:06.498224 | controller | TASK [Create raw test image] ***************************************************
2026-05-20 19:24:06.498433 | controller | Wednesday 20 May 2026  19:24:06 +0000 (0:00:00.258)       0:15:18.494 *********
2026-05-20 19:24:06.780994 | controller | changed: [controller]
2026-05-20 19:24:06.781099 | controller |
2026-05-20 19:24:06.781117 | controller | TASK [Write initial image ETag] ************************************************
2026-05-20 19:24:06.781134 | controller | Wednesday 20 May 2026  19:24:06 +0000 (0:00:00.281)       0:15:18.775 *********
2026-05-20 19:24:07.376500 | controller | changed: [controller]
2026-05-20 19:24:07.376676 | controller |
2026-05-20 19:24:07.376951 | controller | TASK [Copy ETag-aware image server] ********************************************
2026-05-20 19:24:07.377219 | controller | Wednesday 20 May 2026  19:24:07 +0000 (0:00:00.597)       0:15:19.372 *********
2026-05-20 19:24:07.987163 | controller | changed: [controller]
2026-05-20 19:24:07.987289 | controller |
2026-05-20 19:24:07.987465 | controller | TASK [Check if image source is already listening] ******************************
2026-05-20 19:24:07.987636 | controller | Wednesday 20 May 2026  19:24:07 +0000 (0:00:00.610)       0:15:19.983 *********
2026-05-20 19:24:08.504518 | controller | ok: [controller]
2026-05-20 19:24:08.504591 | controller |
2026-05-20 19:24:08.504648 | controller | TASK [Start ETag-aware image server] *******************************************
2026-05-20 19:24:08.504744 | controller | Wednesday 20 May 2026  19:24:08 +0000 (0:00:00.515)       0:15:20.498 *********
2026-05-20 19:24:09.095289 | controller | ok: [controller]
2026-05-20 19:24:09.095360 | controller |
2026-05-20 19:24:09.095432 | controller | TASK [Wait for image source to answer HEAD requests] ***************************
2026-05-20 19:24:09.095529 | controller | Wednesday 20 May 2026  19:24:09 +0000 (0:00:00.587)       0:15:21.086 *********
2026-05-20 19:24:09.606545 | controller | ok: [controller]
2026-05-20 19:24:09.606600 | controller |
2026-05-20 19:24:09.606606 | controller | PLAY RECAP *********************************************************************
2026-05-20 19:24:09.606613 | controller | controller                 : ok=237  changed=104  unreachable=0    failed=0    skipped=96   rescued=0    ignored=2
2026-05-20 19:24:09.606618 | controller |
2026-05-20 19:24:09.606920 | controller | Wednesday 20 May 2026  19:24:09 +0000 (0:00:00.513)       0:15:21.600 *********
2026-05-20 19:24:09.608067 | controller | ===============================================================================
2026-05-20 19:24:09.608302 | controller | vexxhost.atmosphere.keystone : Deploy Helm chart ---------------------- 164.18s
2026-05-20 19:24:09.608400 | controller | vexxhost.atmosphere.keycloak : Deploy Helm chart ---------------------- 143.50s
2026-05-20 19:24:09.608498 | controller | vexxhost.atmosphere.glance : Deploy Helm chart ------------------------ 132.83s
2026-05-20 19:24:09.608635 | controller | vexxhost.atmosphere.percona_xtradb_cluster : Apply Percona XtraDB cluster - 103.88s
2026-05-20 19:24:09.609856 | controller | vexxhost.atmosphere.rabbitmq : Deploy cluster -------------------------- 40.94s
2026-05-20 19:24:09.610751 | controller | vexxhost.kubernetes.envoy_gateway : Deploy Helm chart ------------------ 35.06s
2026-05-20 19:24:09.610759 | controller | vexxhost.atmosphere.rabbitmq : Deploy cluster -------------------------- 31.00s
2026-05-20 19:24:09.610764 | controller | vexxhost.kubernetes.kubernetes : Initialize cluster -------------------- 29.81s
2026-05-20 19:24:09.610769 | controller | vexxhost.kubernetes.cert_manager : Deploy Helm chart ------------------- 16.58s
2026-05-20 19:24:09.610774 | controller | vexxhost.atmosphere.ingress_nginx : Deploy Helm chart ------------------ 15.87s
2026-05-20 19:24:09.610779 | controller | Install Debian packages ------------------------------------------------ 12.27s
2026-05-20 19:24:09.610783 | controller | vexxhost.atmosphere.cluster_issuer : Rehash CA certificates ------------ 10.11s
2026-05-20 19:24:09.610806 | controller | vexxhost.atmosphere.keystone : Create Keycloak realms ------------------- 9.24s
2026-05-20 19:24:09.610813 | controller | vexxhost.containers.containerd : Install AppArmor packages -------------- 7.88s
2026-05-20 19:24:09.610828 | controller | Install Python packages used by the test -------------------------------- 7.23s
2026-05-20 19:24:09.611945 | controller | vexxhost.kubernetes.kubernetes : Install Kubernetes Python package using package manager on supported systems --- 7.20s
2026-05-20 19:24:09.612048 | controller | vexxhost.kubernetes.kubelet : Install additional packages --------------- 3.54s
2026-05-20 19:24:09.612155 | controller | vexxhost.atmosphere.rabbitmq_cluster_operator : Deploy Helm chart ------- 3.40s
2026-05-20 19:24:09.612253 | controller | vexxhost.atmosphere.keystone : Validate if ingress is reachable --------- 3.38s
2026-05-20 19:24:09.612353 | controller | vexxhost.atmosphere.rabbitmq_cluster_operator : Install all CRDs -------- 3.02s
2026-05-20 19:24:09.795863 | controller | INFO     [glance-image > prepare] Executed: Successful
2026-05-20 19:24:09.800096 | controller | INFO     [glance-image > converge] Executing
2026-05-20 19:24:10.600843 | controller |
2026-05-20 19:24:10.600931 | controller | PLAY [Manage OpenStack Glance image] *******************************************
2026-05-20 19:24:10.600981 | controller |
2026-05-20 19:24:10.601077 | controller | TASK [Gathering Facts] *********************************************************
2026-05-20 19:24:10.601170 | controller | Wednesday 20 May 2026  19:24:10 +0000 (0:00:00.015)       0:00:00.015 *********
2026-05-20 19:24:12.317657 | controller | [WARNING]: Platform linux on host controller is using the discovered Python
2026-05-20 19:24:12.317732 | controller | interpreter at /usr/bin/python3.12, but future installation of another Python
2026-05-20 19:24:12.317738 | controller | interpreter could change the meaning of that path. See
2026-05-20 19:24:12.317742 | controller | https://docs.ansible.com/ansible-
2026-05-20 19:24:12.317747 | controller | core/2.18/reference_appendices/interpreter_discovery.html for more information.
2026-05-20 19:24:12.329841 | controller | ok: [controller]
2026-05-20 19:24:12.329930 | controller |
2026-05-20 19:24:12.330033 | controller | TASK [atmosphere.common.glance_image : Fetch source image metadata from URL] ***
2026-05-20 19:24:12.330152 | controller | Wednesday 20 May 2026  19:24:12 +0000 (0:00:01.728)       0:00:01.744 *********
2026-05-20 19:24:12.918744 | controller | ok: [controller]
2026-05-20 19:24:12.920817 | controller |
2026-05-20 19:24:12.920835 | controller | TASK [atmosphere.common.glance_image : Capture source ETag when available] *****
2026-05-20 19:24:12.920843 | controller | Wednesday 20 May 2026  19:24:12 +0000 (0:00:00.583)       0:00:02.328 *********
2026-05-20 19:24:12.980021 | controller | ok: [controller]
2026-05-20 19:24:12.980076 | controller |
2026-05-20 19:24:12.980082 | controller | TASK [atmosphere.common.glance_image : Check if image exists] ******************
2026-05-20 19:24:12.980088 | controller | Wednesday 20 May 2026  19:24:12 +0000 (0:00:00.060)       0:00:02.389 *********
2026-05-20 19:24:16.505092 | controller | FAILED - RETRYING: [controller]: Check if image exists (120 retries left).
2026-05-20 19:24:16.505167 | controller | ok: [controller]
2026-05-20 19:24:16.505263 | controller |
2026-05-20 19:24:16.505406 | controller | TASK [atmosphere.common.glance_image : Capture existing image and its Atmosphere metadata] ***
2026-05-20 19:24:16.505538 | controller | Wednesday 20 May 2026  19:24:16 +0000 (0:00:03.530)       0:00:05.919 *********
2026-05-20 19:24:16.544326 | controller | ok: [controller]
2026-05-20 19:24:16.544398 | controller |
2026-05-20 19:24:16.544413 | controller | TASK [atmosphere.common.glance_image : Decide whether image upload is required] ***
2026-05-20 19:24:16.544425 | controller | Wednesday 20 May 2026  19:24:16 +0000 (0:00:00.039)       0:00:05.958 *********
2026-05-20 19:24:16.594843 | controller | ok: [controller]
2026-05-20 19:24:16.594870 | controller |
2026-05-20 19:24:16.594879 | controller | TASK [atmosphere.common.glance_image : Generate temporary work directory] ******
2026-05-20 19:24:16.594890 | controller | Wednesday 20 May 2026  19:24:16 +0000 (0:00:00.049)       0:00:06.007 *********
2026-05-20 19:24:16.917519 | controller | changed: [controller]
2026-05-20 19:24:16.917679 | controller |
2026-05-20 19:24:16.917953 | controller | TASK [atmosphere.common.glance_image : Download image] *************************
2026-05-20 19:24:16.918162 | controller | Wednesday 20 May 2026  19:24:16 +0000 (0:00:00.323)       0:00:06.331 *********
2026-05-20 19:24:17.397843 | controller | changed: [controller]
2026-05-20 19:24:17.397881 | controller |
2026-05-20 19:24:17.397888 | controller | TASK [atmosphere.common.glance_image : Get image format] ***********************
2026-05-20 19:24:17.397910 | controller | Wednesday 20 May 2026  19:24:17 +0000 (0:00:00.479)       0:00:06.811 *********
2026-05-20 19:24:17.746717 | controller | ok: [controller]
2026-05-20 19:24:17.746910 | controller |
2026-05-20 19:24:17.747150 | controller | TASK [atmosphere.common.glance_image : Convert file to target disk format] *****
2026-05-20 19:24:17.747384 | controller | Wednesday 20 May 2026  19:24:17 +0000 (0:00:00.349)       0:00:07.160 *********
2026-05-20 19:24:17.779699 | controller | skipping: [controller]
2026-05-20 19:24:17.779933 | controller |
2026-05-20 19:24:17.780151 | controller | TASK [atmosphere.common.glance_image : Wait until image service ready] *********
2026-05-20 19:24:17.780360 | controller | Wednesday 20 May 2026  19:24:17 +0000 (0:00:00.033)       0:00:07.194 *********
2026-05-20 19:24:18.782054 | controller | ok: [controller]
2026-05-20 19:24:18.782223 | controller |
2026-05-20 19:24:18.782449 | controller | TASK [atmosphere.common.glance_image : Compute pending image name] *************
2026-05-20 19:24:18.782659 | controller | Wednesday 20 May 2026  19:24:18 +0000 (0:00:01.001)       0:00:08.196 *********
2026-05-20 19:24:18.828228 | controller | ok: [controller]
2026-05-20 19:24:18.828320 | controller |
2026-05-20 19:24:18.828539 | controller | TASK [atmosphere.common.glance_image : Upload image into Glance] ***************
2026-05-20 19:24:18.828741 | controller | Wednesday 20 May 2026  19:24:18 +0000 (0:00:00.046)       0:00:08.243 *********
2026-05-20 19:24:21.505620 | controller | changed: [controller]
2026-05-20 19:24:21.505834 | controller |
2026-05-20 19:24:21.506094 | controller | TASK [atmosphere.common.glance_image : Rename outdated image to preserve audit trail] ***
2026-05-20 19:24:21.506374 | controller | Wednesday 20 May 2026  19:24:21 +0000 (0:00:02.676)       0:00:10.919 *********
2026-05-20 19:24:21.540374 | controller | skipping: [controller]
2026-05-20 19:24:21.540500 | controller |
2026-05-20 19:24:21.540687 | controller | TASK [atmosphere.common.glance_image : Promote newly uploaded image to canonical name] ***
2026-05-20 19:24:21.540886 | controller | Wednesday 20 May 2026  19:24:21 +0000 (0:00:00.034)       0:00:10.954 *********
2026-05-20 19:24:21.573587 | controller | skipping: [controller]
2026-05-20 19:24:21.573777 | controller |
2026-05-20 19:24:21.574012 | controller | TASK [atmosphere.common.glance_image : Remove work directory] ******************
2026-05-20 19:24:21.574217 | controller | Wednesday 20 May 2026  19:24:21 +0000 (0:00:00.033)       0:00:10.987 *********
2026-05-20 19:24:21.923681 | controller | changed: [controller]
2026-05-20 19:24:21.923836 | controller |
2026-05-20 19:24:21.924054 | controller | PLAY RECAP *********************************************************************
2026-05-20 19:24:21.924311 | controller | controller                 : ok=13   changed=4    unreachable=0    failed=0    skipped=3    rescued=0    ignored=0
2026-05-20 19:24:21.924971 | controller |
2026-05-20 19:24:21.925001 | controller | Wednesday 20 May 2026  19:24:21 +0000 (0:00:00.350)       0:00:11.338 *********
2026-05-20 19:24:21.925841 | controller | ===============================================================================
2026-05-20 19:24:21.926043 | controller | atmosphere.common.glance_image : Check if image exists ------------------ 3.53s
2026-05-20 19:24:21.926988 | controller | atmosphere.common.glance_image : Upload image into Glance --------------- 2.68s
2026-05-20 19:24:21.927019 | controller | Gathering Facts --------------------------------------------------------- 1.73s
2026-05-20 19:24:21.927029 | controller | atmosphere.common.glance_image : Wait until image service ready --------- 1.00s
2026-05-20 19:24:21.927147 | controller | atmosphere.common.glance_image : Fetch source image metadata from URL --- 0.58s
2026-05-20 19:24:21.927319 | controller | atmosphere.common.glance_image : Download image ------------------------- 0.48s
2026-05-20 19:24:21.927409 | controller | atmosphere.common.glance_image : Remove work directory ------------------ 0.35s
2026-05-20 19:24:21.927502 | controller | atmosphere.common.glance_image : Get image format ----------------------- 0.35s
2026-05-20 19:24:21.927596 | controller | atmosphere.common.glance_image : Generate temporary work directory ------ 0.32s
2026-05-20 19:24:21.927688 | controller | atmosphere.common.glance_image : Capture source ETag when available ----- 0.06s
2026-05-20 19:24:21.927844 | controller | atmosphere.common.glance_image : Decide whether image upload is required --- 0.05s
2026-05-20 19:24:21.927999 | controller | atmosphere.common.glance_image : Compute pending image name ------------- 0.05s
2026-05-20 19:24:21.928101 | controller | atmosphere.common.glance_image : Capture existing image and its Atmosphere metadata --- 0.04s
2026-05-20 19:24:21.928228 | controller | atmosphere.common.glance_image : Rename outdated image to preserve audit trail --- 0.03s
2026-05-20 19:24:21.928331 | controller | atmosphere.common.glance_image : Convert file to target disk format ----- 0.03s
2026-05-20 19:24:21.928427 | controller | atmosphere.common.glance_image : Promote newly uploaded image to canonical name --- 0.03s
2026-05-20 19:24:22.012316 | controller | INFO     [glance-image > converge] Executed: Successful
2026-05-20 19:24:22.023700 | controller | INFO     [glance-image > idempotence] Executing
2026-05-20 19:24:22.847168 | controller |
2026-05-20 19:24:22.847259 | controller | PLAY [Manage OpenStack Glance image] *******************************************
2026-05-20 19:24:22.847270 | controller |
2026-05-20 19:24:22.847371 | controller | TASK [Gathering Facts] *********************************************************
2026-05-20 19:24:22.847465 | controller | Wednesday 20 May 2026  19:24:22 +0000 (0:00:00.017)       0:00:00.017 *********
2026-05-20 19:24:24.788897 | controller | [WARNING]: Platform linux on host controller is using the discovered Python
2026-05-20 19:24:24.789203 | controller | interpreter at /usr/bin/python3.12, but future installation of another Python
2026-05-20 19:24:24.789423 | controller | interpreter could change the meaning of that path. See
2026-05-20 19:24:24.789578 | controller | https://docs.ansible.com/ansible-
2026-05-20 19:24:24.789781 | controller | core/2.18/reference_appendices/interpreter_discovery.html for more information.
2026-05-20 19:24:24.798866 | controller | ok: [controller]
2026-05-20 19:24:24.799033 | controller |
2026-05-20 19:24:24.799288 | controller | TASK [atmosphere.common.glance_image : Fetch source image metadata from URL] ***
2026-05-20 19:24:24.799443 | controller | Wednesday 20 May 2026  19:24:24 +0000 (0:00:01.951)       0:00:01.969 *********
2026-05-20 19:24:25.373720 | controller | ok: [controller]
2026-05-20 19:24:25.373839 | controller |
2026-05-20 19:24:25.373935 | controller | TASK [atmosphere.common.glance_image : Capture source ETag when available] *****
2026-05-20 19:24:25.374064 | controller | Wednesday 20 May 2026  19:24:25 +0000 (0:00:00.574)       0:00:02.544 *********
2026-05-20 19:24:25.416139 | controller | ok: [controller]
2026-05-20 19:24:25.416241 | controller |
2026-05-20 19:24:25.416461 | controller | TASK [atmosphere.common.glance_image : Check if image exists] ******************
2026-05-20 19:24:25.416658 | controller | Wednesday 20 May 2026  19:24:25 +0000 (0:00:00.042)       0:00:02.586 *********
2026-05-20 19:24:26.803616 | controller | ok: [controller]
2026-05-20 19:24:26.803784 | controller |
2026-05-20 19:24:26.804088 | controller | TASK [atmosphere.common.glance_image : Capture existing image and its Atmosphere metadata] ***
2026-05-20 19:24:26.804314 | controller | Wednesday 20 May 2026  19:24:26 +0000 (0:00:01.387)       0:00:03.974 *********
2026-05-20 19:24:26.841216 | controller | ok: [controller]
2026-05-20 19:24:26.841384 | controller |
2026-05-20 19:24:26.841663 | controller | TASK [atmosphere.common.glance_image : Decide whether image upload is required] ***
2026-05-20 19:24:26.841920 | controller | Wednesday 20 May 2026  19:24:26 +0000 (0:00:00.038)       0:00:04.012 *********
2026-05-20 19:24:26.884899 | controller | ok: [controller]
2026-05-20 19:24:26.884962 | controller |
2026-05-20 19:24:26.885100 | controller | TASK [atmosphere.common.glance_image : Generate temporary work directory] ******
2026-05-20 19:24:26.885246 | controller | Wednesday 20 May 2026  19:24:26 +0000 (0:00:00.043)       0:00:04.055 *********
2026-05-20 19:24:26.912621 | controller | skipping: [controller]
2026-05-20 19:24:26.912711 | controller |
2026-05-20 19:24:26.913155 | controller | TASK [atmosphere.common.glance_image : Download image] *************************
2026-05-20 19:24:26.913172 | controller | Wednesday 20 May 2026  19:24:26 +0000 (0:00:00.027)       0:00:04.083 *********
2026-05-20 19:24:26.941058 | controller | skipping: [controller]
2026-05-20 19:24:26.941222 | controller |
2026-05-20 19:24:26.941399 | controller | TASK [atmosphere.common.glance_image : Get image format] ***********************
2026-05-20 19:24:26.941569 | controller | Wednesday 20 May 2026  19:24:26 +0000 (0:00:00.027)       0:00:04.111 *********
2026-05-20 19:24:26.966397 | controller | skipping: [controller]
2026-05-20 19:24:26.966582 | controller |
2026-05-20 19:24:26.966894 | controller | TASK [atmosphere.common.glance_image : Convert file to target disk format] *****
2026-05-20 19:24:26.967098 | controller | Wednesday 20 May 2026  19:24:26 +0000 (0:00:00.025)       0:00:04.137 *********
2026-05-20 19:24:26.990585 | controller | skipping: [controller]
2026-05-20 19:24:26.990752 | controller |
2026-05-20 19:24:26.990992 | controller | TASK [atmosphere.common.glance_image : Wait until image service ready] *********
2026-05-20 19:24:26.991191 | controller | Wednesday 20 May 2026  19:24:26 +0000 (0:00:00.024)       0:00:04.161 *********
2026-05-20 19:24:27.013242 | controller | skipping: [controller]
2026-05-20 19:24:27.013465 | controller |
2026-05-20 19:24:27.013890 | controller | TASK [atmosphere.common.glance_image : Compute pending image name] *************
2026-05-20 19:24:27.014146 | controller | Wednesday 20 May 2026  19:24:27 +0000 (0:00:00.022)       0:00:04.184 *********
2026-05-20 19:24:27.035198 | controller | skipping: [controller]
2026-05-20 19:24:27.035374 | controller |
2026-05-20 19:24:27.035593 | controller | TASK [atmosphere.common.glance_image : Upload image into Glance] ***************
2026-05-20 19:24:27.035780 | controller | Wednesday 20 May 2026  19:24:27 +0000 (0:00:00.021)       0:00:04.206 *********
2026-05-20 19:24:27.064255 | controller | skipping: [controller]
2026-05-20 19:24:27.064434 | controller |
2026-05-20 19:24:27.064654 | controller | TASK [atmosphere.common.glance_image : Rename outdated image to preserve audit trail] ***
2026-05-20 19:24:27.064900 | controller | Wednesday 20 May 2026  19:24:27 +0000 (0:00:00.029)       0:00:04.235 *********
2026-05-20 19:24:27.090545 | controller | skipping: [controller]
2026-05-20 19:24:27.090651 | controller |
2026-05-20 19:24:27.090822 | controller | TASK [atmosphere.common.glance_image : Promote newly uploaded image to canonical name] ***
2026-05-20 19:24:27.091032 | controller | Wednesday 20 May 2026  19:24:27 +0000 (0:00:00.025)       0:00:04.260 *********
2026-05-20 19:24:27.118382 | controller | skipping: [controller]
2026-05-20 19:24:27.118664 | controller |
2026-05-20 19:24:27.118945 | controller | TASK [atmosphere.common.glance_image : Remove work directory] ******************
2026-05-20 19:24:27.119118 | controller | Wednesday 20 May 2026  19:24:27 +0000 (0:00:00.028)       0:00:04.288 *********
2026-05-20 19:24:27.149688 | controller | skipping: [controller]
2026-05-20 19:24:27.149911 | controller |
2026-05-20 19:24:27.150115 | controller | PLAY RECAP *********************************************************************
2026-05-20 19:24:27.150360 | controller | controller                 : ok=6    changed=0    unreachable=0    failed=0    skipped=10   rescued=0    ignored=0
2026-05-20 19:24:27.150560 | controller |
2026-05-20 19:24:27.150764 | controller | Wednesday 20 May 2026  19:24:27 +0000 (0:00:00.031)       0:00:04.320 *********
2026-05-20 19:24:27.150982 | controller | ===============================================================================
2026-05-20 19:24:27.151223 | controller | Gathering Facts --------------------------------------------------------- 1.95s
2026-05-20 19:24:27.151429 | controller | atmosphere.common.glance_image : Check if image exists ------------------ 1.39s
2026-05-20 19:24:27.151630 | controller | atmosphere.common.glance_image : Fetch source image metadata from URL --- 0.57s
2026-05-20 19:24:27.151921 | controller | atmosphere.common.glance_image : Decide whether image upload is required --- 0.04s
2026-05-20 19:24:27.152239 | controller | atmosphere.common.glance_image : Capture source ETag when available ----- 0.04s
2026-05-20 19:24:27.152697 | controller | atmosphere.common.glance_image : Capture existing image and its Atmosphere metadata --- 0.04s
2026-05-20 19:24:27.153058 | controller | atmosphere.common.glance_image : Remove work directory ------------------ 0.03s
2026-05-20 19:24:27.153326 | controller | atmosphere.common.glance_image : Upload image into Glance --------------- 0.03s
2026-05-20 19:24:27.153996 | controller | atmosphere.common.glance_image : Promote newly uploaded image to canonical name --- 0.03s
2026-05-20 19:24:27.154538 | controller | atmosphere.common.glance_image : Download image ------------------------- 0.03s
2026-05-20 19:24:27.154781 | controller | atmosphere.common.glance_image : Generate temporary work directory ------ 0.03s
2026-05-20 19:24:27.155024 | controller | atmosphere.common.glance_image : Get image format ----------------------- 0.03s
2026-05-20 19:24:27.155498 | controller | atmosphere.common.glance_image : Rename outdated image to preserve audit trail --- 0.03s
2026-05-20 19:24:27.155812 | controller | atmosphere.common.glance_image : Convert file to target disk format ----- 0.02s
2026-05-20 19:24:27.156050 | controller | atmosphere.common.glance_image : Wait until image service ready --------- 0.02s
2026-05-20 19:24:27.156344 | controller | atmosphere.common.glance_image : Compute pending image name ------------- 0.02s
2026-05-20 19:24:27.235584 | controller | INFO     [glance-image > idempotence] Executed: Successful
2026-05-20 19:24:27.247864 | controller | INFO     [glance-image > side_effect] Executing
2026-05-20 19:24:27.248700 | controller | WARNING  [glance-image > side_effect] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-05-20 19:24:27.254701 | controller | INFO     [glance-image > verify] Executing
2026-05-20 19:24:28.026666 | controller |
2026-05-20 19:24:28.026900 | controller | PLAY [Verify Glance image lifecycle] *******************************************
2026-05-20 19:24:28.027082 | controller |
2026-05-20 19:24:28.027328 | controller | TASK [Gathering Facts] *********************************************************
2026-05-20 19:24:28.027631 | controller | Wednesday 20 May 2026  19:24:28 +0000 (0:00:00.008)       0:00:00.008 *********
2026-05-20 19:24:29.931177 | controller | [WARNING]: Platform linux on host controller is using the discovered Python
2026-05-20 19:24:29.931356 | controller | interpreter at /usr/bin/python3.12, but future installation of another Python
2026-05-20 19:24:29.931562 | controller | interpreter could change the meaning of that path. See
2026-05-20 19:24:29.931764 | controller | https://docs.ansible.com/ansible-
2026-05-20 19:24:29.932004 | controller | core/2.18/reference_appendices/interpreter_discovery.html for more information.
2026-05-20 19:24:29.942585 | controller | ok: [controller]
2026-05-20 19:24:29.942771 | controller |
2026-05-20 19:24:29.943016 | controller | TASK [Get initial image] *******************************************************
2026-05-20 19:24:29.943225 | controller | Wednesday 20 May 2026  19:24:29 +0000 (0:00:01.916)       0:00:01.924 *********
2026-05-20 19:24:31.324897 | controller | ok: [controller]
2026-05-20 19:24:31.324965 | controller |
2026-05-20 19:24:31.324979 | controller | TASK [Assert initial image properties] *****************************************
2026-05-20 19:24:31.324991 | controller | Wednesday 20 May 2026  19:24:31 +0000 (0:00:01.380)       0:00:03.304 *********
2026-05-20 19:24:31.370903 | controller | ok: [controller] => {
2026-05-20 19:24:31.370965 | controller |     "changed": false,
2026-05-20 19:24:31.370973 | controller |     "msg": "All assertions passed"
2026-05-20 19:24:31.370989 | controller | }
2026-05-20 19:24:31.371095 | controller |
2026-05-20 19:24:31.371304 | controller | TASK [Run role again with unchanged ETag] **************************************
2026-05-20 19:24:31.371510 | controller | Wednesday 20 May 2026  19:24:31 +0000 (0:00:00.047)       0:00:03.352 *********
2026-05-20 19:24:31.426394 | controller | included: atmosphere.common.glance_image for controller
2026-05-20 19:24:31.426565 | controller |
2026-05-20 19:24:31.426778 | controller | TASK [atmosphere.common.glance_image : Fetch source image metadata from URL] ***
2026-05-20 19:24:31.427024 | controller | Wednesday 20 May 2026  19:24:31 +0000 (0:00:00.056)       0:00:03.408 *********
2026-05-20 19:24:32.028548 | controller | ok: [controller]
2026-05-20 19:24:32.028765 | controller |
2026-05-20 19:24:32.029068 | controller | TASK [atmosphere.common.glance_image : Capture source ETag when available] *****
2026-05-20 19:24:32.029323 | controller | Wednesday 20 May 2026  19:24:32 +0000 (0:00:00.600)       0:00:04.008 *********
2026-05-20 19:24:32.075208 | controller | ok: [controller]
2026-05-20 19:24:32.075357 | controller |
2026-05-20 19:24:32.075574 | controller | TASK [atmosphere.common.glance_image : Check if image exists] ******************
2026-05-20 19:24:32.075817 | controller | Wednesday 20 May 2026  19:24:32 +0000 (0:00:00.048)       0:00:04.057 *********
2026-05-20 19:24:32.998954 | controller | ok: [controller]
2026-05-20 19:24:32.999029 | controller |
2026-05-20 19:24:32.999044 | controller | TASK [atmosphere.common.glance_image : Capture existing image and its Atmosphere metadata] ***
2026-05-20 19:24:32.999065 | controller | Wednesday 20 May 2026  19:24:32 +0000 (0:00:00.922)       0:00:04.979 *********
2026-05-20 19:24:33.045375 | controller | ok: [controller]
2026-05-20 19:24:33.045469 | controller |
2026-05-20 19:24:33.045626 | controller | TASK [atmosphere.common.glance_image : Decide whether image upload is required] ***
2026-05-20 19:24:33.045755 | controller | Wednesday 20 May 2026  19:24:33 +0000 (0:00:00.047)       0:00:05.027 *********
2026-05-20 19:24:33.091295 | controller | ok: [controller]
2026-05-20 19:24:33.091488 | controller |
2026-05-20 19:24:33.091709 | controller | TASK [atmosphere.common.glance_image : Generate temporary work directory] ******
2026-05-20 19:24:33.091964 | controller | Wednesday 20 May 2026  19:24:33 +0000 (0:00:00.046)       0:00:05.073 *********
2026-05-20 19:24:33.125219 | controller | skipping: [controller]
2026-05-20 19:24:33.125394 | controller |
2026-05-20 19:24:33.125625 | controller | TASK [atmosphere.common.glance_image : Download image] *************************
2026-05-20 19:24:33.125870 | controller | Wednesday 20 May 2026  19:24:33 +0000 (0:00:00.033)       0:00:05.106 *********
2026-05-20 19:24:33.156633 | controller | skipping: [controller]
2026-05-20 19:24:33.156774 | controller |
2026-05-20 19:24:33.157015 | controller | TASK [atmosphere.common.glance_image : Get image format] ***********************
2026-05-20 19:24:33.157219 | controller | Wednesday 20 May 2026  19:24:33 +0000 (0:00:00.031)       0:00:05.138 *********
2026-05-20 19:24:33.187123 | controller | skipping: [controller]
2026-05-20 19:24:33.187293 | controller |
2026-05-20 19:24:33.187517 | controller | TASK [atmosphere.common.glance_image : Convert file to target disk format] *****
2026-05-20 19:24:33.187765 | controller | Wednesday 20 May 2026  19:24:33 +0000 (0:00:00.030)       0:00:05.169 *********
2026-05-20 19:24:33.217897 | controller | skipping: [controller]
2026-05-20 19:24:33.218057 | controller |
2026-05-20 19:24:33.218286 | controller | TASK [atmosphere.common.glance_image : Wait until image service ready] *********
2026-05-20 19:24:33.218557 | controller | Wednesday 20 May 2026  19:24:33 +0000 (0:00:00.030)       0:00:05.199 *********
2026-05-20 19:24:33.249568 | controller | skipping: [controller]
2026-05-20 19:24:33.249671 | controller |
2026-05-20 19:24:33.249918 | controller | TASK [atmosphere.common.glance_image : Compute pending image name] *************
2026-05-20 19:24:33.250138 | controller | Wednesday 20 May 2026  19:24:33 +0000 (0:00:00.031)       0:00:05.231 *********
2026-05-20 19:24:33.280361 | controller | skipping: [controller]
2026-05-20 19:24:33.280584 | controller |
2026-05-20 19:24:33.280854 | controller | TASK [atmosphere.common.glance_image : Upload image into Glance] ***************
2026-05-20 19:24:33.281031 | controller | Wednesday 20 May 2026  19:24:33 +0000 (0:00:00.030)       0:00:05.262 *********
2026-05-20 19:24:33.312740 | controller | skipping: [controller]
2026-05-20 19:24:33.312953 | controller |
2026-05-20 19:24:33.313174 | controller | TASK [atmosphere.common.glance_image : Rename outdated image to preserve audit trail] ***
2026-05-20 19:24:33.313334 | controller | Wednesday 20 May 2026  19:24:33 +0000 (0:00:00.032)       0:00:05.294 *********
2026-05-20 19:24:33.343367 | controller | skipping: [controller]
2026-05-20 19:24:33.343556 | controller |
2026-05-20 19:24:33.343819 | controller | TASK [atmosphere.common.glance_image : Promote newly uploaded image to canonical name] ***
2026-05-20 19:24:33.344085 | controller | Wednesday 20 May 2026  19:24:33 +0000 (0:00:00.030)       0:00:05.325 *********
2026-05-20 19:24:33.371827 | controller | skipping: [controller]
2026-05-20 19:24:33.372038 | controller |
2026-05-20 19:24:33.372264 | controller | TASK [atmosphere.common.glance_image : Remove work directory] ******************
2026-05-20 19:24:33.372478 | controller | Wednesday 20 May 2026  19:24:33 +0000 (0:00:00.028)       0:00:05.353 *********
2026-05-20 19:24:33.408603 | controller | skipping: [controller]
2026-05-20 19:24:33.408784 | controller |
2026-05-20 19:24:33.409048 | controller | TASK [Get image after unchanged ETag run] **************************************
2026-05-20 19:24:33.409249 | controller | Wednesday 20 May 2026  19:24:33 +0000 (0:00:00.036)       0:00:05.390 *********
2026-05-20 19:24:34.342367 | controller | ok: [controller]
2026-05-20 19:24:34.342579 | controller |
2026-05-20 19:24:34.342835 | controller | TASK [Assert unchanged ETag does not re-upload] ********************************
2026-05-20 19:24:34.343073 | controller | Wednesday 20 May 2026  19:24:34 +0000 (0:00:00.932)       0:00:06.323 *********
2026-05-20 19:24:34.386311 | controller | ok: [controller] => {
2026-05-20 19:24:34.386386 | controller |     "changed": false,
2026-05-20 19:24:34.386487 | controller |     "msg": "All assertions passed"
2026-05-20 19:24:34.386593 | controller | }
2026-05-20 19:24:34.386660 | controller |
2026-05-20 19:24:34.386762 | controller | TASK [Change source ETag] ******************************************************
2026-05-20 19:24:34.386872 | controller | Wednesday 20 May 2026  19:24:34 +0000 (0:00:00.044)       0:00:06.367 *********
2026-05-20 19:24:35.189884 | controller | changed: [controller]
2026-05-20 19:24:35.190256 | controller |
2026-05-20 19:24:35.190589 | controller | TASK [Run role again with changed ETag] ****************************************
2026-05-20 19:24:35.190858 | controller | Wednesday 20 May 2026  19:24:35 +0000 (0:00:00.803)       0:00:07.171 *********
2026-05-20 19:24:35.252620 | controller | included: atmosphere.common.glance_image for controller
2026-05-20 19:24:35.252761 | controller |
2026-05-20 19:24:35.253016 | controller | TASK [atmosphere.common.glance_image : Fetch source image metadata from URL] ***
2026-05-20 19:24:35.253222 | controller | Wednesday 20 May 2026  19:24:35 +0000 (0:00:00.063)       0:00:07.234 *********
2026-05-20 19:24:35.728184 | controller | ok: [controller]
2026-05-20 19:24:35.728344 | controller |
2026-05-20 19:24:35.728589 | controller | TASK [atmosphere.common.glance_image : Capture source ETag when available] *****
2026-05-20 19:24:35.728772 | controller | Wednesday 20 May 2026  19:24:35 +0000 (0:00:00.474)       0:00:07.709 *********
2026-05-20 19:24:35.768528 | controller | ok: [controller]
2026-05-20 19:24:35.768707 | controller |
2026-05-20 19:24:35.768951 | controller | TASK [atmosphere.common.glance_image : Check if image exists] ******************
2026-05-20 19:24:35.769153 | controller | Wednesday 20 May 2026  19:24:35 +0000 (0:00:00.040)       0:00:07.750 *********
2026-05-20 19:24:37.045983 | controller | ok: [controller]
2026-05-20 19:24:37.046071 | controller |
2026-05-20 19:24:37.046083 | controller | TASK [atmosphere.common.glance_image : Capture existing image and its Atmosphere metadata] ***
2026-05-20 19:24:37.046112 | controller | Wednesday 20 May 2026  19:24:37 +0000 (0:00:01.276)       0:00:09.026 *********
2026-05-20 19:24:37.082076 | controller | ok: [controller]
2026-05-20 19:24:37.082116 | controller |
2026-05-20 19:24:37.082229 | controller | TASK [atmosphere.common.glance_image : Decide whether image upload is required] ***
2026-05-20 19:24:37.082323 | controller | Wednesday 20 May 2026  19:24:37 +0000 (0:00:00.036)       0:00:09.063 *********
2026-05-20 19:24:37.124655 | controller | ok: [controller]
2026-05-20 19:24:37.124719 | controller |
2026-05-20 19:24:37.124846 | controller | TASK [atmosphere.common.glance_image : Generate temporary work directory] ******
2026-05-20 19:24:37.124942 | controller | Wednesday 20 May 2026  19:24:37 +0000 (0:00:00.043)       0:00:09.106 *********
2026-05-20 19:24:37.446411 | controller | changed: [controller]
2026-05-20 19:24:37.446486 | controller |
2026-05-20 19:24:37.446684 | controller | TASK [atmosphere.common.glance_image : Download image] *************************
2026-05-20 19:24:37.446838 | controller | Wednesday 20 May 2026  19:24:37 +0000 (0:00:00.320)       0:00:09.427 *********
2026-05-20 19:24:37.943421 | controller | changed: [controller]
2026-05-20 19:24:37.943582 | controller |
2026-05-20 19:24:37.943825 | controller | TASK [atmosphere.common.glance_image : Get image format] ***********************
2026-05-20 19:24:37.944037 | controller | Wednesday 20 May 2026  19:24:37 +0000 (0:00:00.496)       0:00:09.924 *********
2026-05-20 19:24:38.303853 | controller | ok: [controller]
2026-05-20 19:24:38.303902 | controller |
2026-05-20 19:24:38.303909 | controller | TASK [atmosphere.common.glance_image : Convert file to target disk format] *****
2026-05-20 19:24:38.303915 | controller | Wednesday 20 May 2026  19:24:38 +0000 (0:00:00.359)       0:00:10.284 *********
2026-05-20 19:24:38.336695 | controller | skipping: [controller]
2026-05-20 19:24:38.337355 | controller |
2026-05-20 19:24:38.337594 | controller | TASK [atmosphere.common.glance_image : Wait until image service ready] *********
2026-05-20 19:24:38.337765 | controller | Wednesday 20 May 2026  19:24:38 +0000 (0:00:00.033)       0:00:10.318 *********
2026-05-20 19:24:39.344142 | controller | ok: [controller]
2026-05-20 19:24:39.344334 | controller |
2026-05-20 19:24:39.344579 | controller | TASK [atmosphere.common.glance_image : Compute pending image name] *************
2026-05-20 19:24:39.344883 | controller | Wednesday 20 May 2026  19:24:39 +0000 (0:00:01.006)       0:00:11.325 *********
2026-05-20 19:24:39.398089 | controller | ok: [controller]
2026-05-20 19:24:39.398266 | controller |
2026-05-20 19:24:39.398476 | controller | TASK [atmosphere.common.glance_image : Upload image into Glance] ***************
2026-05-20 19:24:39.398765 | controller | Wednesday 20 May 2026  19:24:39 +0000 (0:00:00.054)       0:00:11.380 *********
2026-05-20 19:24:40.868404 | controller | changed: [controller]
2026-05-20 19:24:40.868508 | controller |
2026-05-20 19:24:40.868520 | controller | TASK [atmosphere.common.glance_image : Rename outdated image to preserve audit trail] ***
2026-05-20 19:24:40.868622 | controller | Wednesday 20 May 2026  19:24:40 +0000 (0:00:01.467)       0:00:12.848 *********
2026-05-20 19:24:41.987584 | controller | changed: [controller]
2026-05-20 19:24:41.987759 | controller |
2026-05-20 19:24:41.988064 | controller | TASK [atmosphere.common.glance_image : Promote newly uploaded image to canonical name] ***
2026-05-20 19:24:41.988287 | controller | Wednesday 20 May 2026  19:24:41 +0000 (0:00:01.120)       0:00:13.968 *********
2026-05-20 19:24:43.072426 | controller | changed: [controller]
2026-05-20 19:24:43.072654 | controller |
2026-05-20 19:24:43.072826 | controller | TASK [atmosphere.common.glance_image : Remove work directory] ******************
2026-05-20 19:24:43.073067 | controller | Wednesday 20 May 2026  19:24:43 +0000 (0:00:01.084)       0:00:15.053 *********
2026-05-20 19:24:43.439625 | controller | changed: [controller]
2026-05-20 19:24:43.439966 | controller |
2026-05-20 19:24:43.440060 | controller | TASK [Get canonical image after ETag update] ***********************************
2026-05-20 19:24:43.440294 | controller | Wednesday 20 May 2026  19:24:43 +0000 (0:00:00.367)       0:00:15.420 *********
2026-05-20 19:24:44.409645 | controller | ok: [controller]
2026-05-20 19:24:44.410347 | controller |
2026-05-20 19:24:44.410376 | controller | TASK [Get obsolete image after ETag update] ************************************
2026-05-20 19:24:44.410389 | controller | Wednesday 20 May 2026  19:24:44 +0000 (0:00:00.970)       0:00:16.391 *********
2026-05-20 19:24:45.341285 | controller | ok: [controller]
2026-05-20 19:24:45.341442 | controller |
2026-05-20 19:24:45.341660 | controller | TASK [Assert image update behavior] ********************************************
2026-05-20 19:24:45.341902 | controller | Wednesday 20 May 2026  19:24:45 +0000 (0:00:00.931)       0:00:17.322 *********
2026-05-20 19:24:45.372196 | controller | fatal: [controller]: FAILED! => {
2026-05-20 19:24:45.372244 | controller |     "assertion": "_obsolete_image.images | length == 1",
2026-05-20 19:24:45.372254 | controller |     "changed": false,
2026-05-20 19:24:45.372396 | controller |     "evaluated_to": false,
2026-05-20 19:24:45.372624 | controller |     "msg": "Assertion failed"
2026-05-20 19:24:45.372852 | controller | }
2026-05-20 19:24:45.373243 | controller |
2026-05-20 19:24:45.373451 | controller | PLAY RECAP *********************************************************************
2026-05-20 19:24:45.373678 | controller | controller                 : ok=29   changed=7    unreachable=0    failed=1    skipped=11   rescued=0    ignored=0
2026-05-20 19:24:45.373896 | controller |
2026-05-20 19:24:45.374110 | controller | Wednesday 20 May 2026  19:24:45 +0000 (0:00:00.032)       0:00:17.355 *********
2026-05-20 19:24:45.374302 | controller | ===============================================================================
2026-05-20 19:24:45.374501 | controller | Gathering Facts --------------------------------------------------------- 1.92s
2026-05-20 19:24:45.374707 | controller | atmosphere.common.glance_image : Upload image into Glance --------------- 1.47s
2026-05-20 19:24:45.374984 | controller | Get initial image ------------------------------------------------------- 1.38s
2026-05-20 19:24:45.375192 | controller | atmosphere.common.glance_image : Check if image exists ------------------ 1.28s
2026-05-20 19:24:45.375404 | controller | atmosphere.common.glance_image : Rename outdated image to preserve audit trail --- 1.12s
2026-05-20 19:24:45.375618 | controller | atmosphere.common.glance_image : Promote newly uploaded image to canonical name --- 1.08s
2026-05-20 19:24:45.375850 | controller | atmosphere.common.glance_image : Wait until image service ready --------- 1.01s
2026-05-20 19:24:45.376062 | controller | Get canonical image after ETag update ----------------------------------- 0.97s
2026-05-20 19:24:45.376262 | controller | Get image after unchanged ETag run -------------------------------------- 0.93s
2026-05-20 19:24:45.376465 | controller | Get obsolete image after ETag update ------------------------------------ 0.93s
2026-05-20 19:24:45.376669 | controller | atmosphere.common.glance_image : Check if image exists ------------------ 0.92s
2026-05-20 19:24:45.376900 | controller | Change source ETag ------------------------------------------------------ 0.80s
2026-05-20 19:24:45.377108 | controller | atmosphere.common.glance_image : Fetch source image metadata from URL --- 0.60s
2026-05-20 19:24:45.377311 | controller | atmosphere.common.glance_image : Download image ------------------------- 0.50s
2026-05-20 19:24:45.377546 | controller | atmosphere.common.glance_image : Fetch source image metadata from URL --- 0.47s
2026-05-20 19:24:45.377748 | controller | atmosphere.common.glance_image : Remove work directory ------------------ 0.37s
2026-05-20 19:24:45.377986 | controller | atmosphere.common.glance_image : Get image format ----------------------- 0.36s
2026-05-20 19:24:45.378196 | controller | atmosphere.common.glance_image : Generate temporary work directory ------ 0.32s
2026-05-20 19:24:45.378401 | controller | Run role again with changed ETag ---------------------------------------- 0.06s
2026-05-20 19:24:45.378605 | controller | Run role again with unchanged ETag -------------------------------------- 0.06s
2026-05-20 19:24:45.461234 | controller | CRITICAL Ansible return code was 2, command was: ansible-playbook --inventory /home/zuul/.ansible/tmp/molecule.z7Jo.glance-image/inventory --skip-tags molecule-notest,notest --inventory=/home/zuul/src/github.com/vexxhost/atmosphere.common/inventory.yaml /home/zuul/src/github.com/vexxhost/atmosphere.common/extensions/molecule/glance-image/verify.yml
2026-05-20 19:24:45.461411 | controller | ERROR    [glance-image > verify] Executed: Failed
2026-05-20 19:24:45.461553 | controller | ERROR    Ansible return code was 2, command was: ansible-playbook --inventory /home/zuul/.ansible/tmp/molecule.z7Jo.glance-image/inventory --skip-tags molecule-notest,notest --inventory=/home/zuul/src/github.com/vexxhost/atmosphere.common/inventory.yaml /home/zuul/src/github.com/vexxhost/atmosphere.common/extensions/molecule/glance-image/verify.yml
2026-05-20 19:24:45.991632 | controller | ERROR
2026-05-20 19:24:45.992036 | controller | {
2026-05-20 19:24:45.992104 | controller |   "delta": "0:16:36.236787",
2026-05-20 19:24:45.992150 | controller |   "end": "2026-05-20 19:24:45.538160",
2026-05-20 19:24:45.992194 | controller |   "msg": "non-zero return code",
2026-05-20 19:24:45.992235 | controller |   "rc": 2,
2026-05-20 19:24:45.992280 | controller |   "start": "2026-05-20 19:08:09.301373"
2026-05-20 19:24:45.992320 | controller | }
failure
2026-05-20 19:24:46.016591 | 
2026-05-20 19:24:46.016726 | PLAY RECAP
2026-05-20 19:24:46.016785 | controller | ok: 2 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-05-20 19:24:46.016807 | 
2026-05-20 19:24:46.171136 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-05-20 19:24:46.177698 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-05-20 19:24:46.748410 | 
2026-05-20 19:24:46.748538 | PLAY [all]
2026-05-20 19:24:46.759994 | 
2026-05-20 19:24:46.760080 | TASK [fetch-output : Set log path for multiple nodes]
2026-05-20 19:24:46.804873 | controller | skipping: Conditional result was False
2026-05-20 19:24:46.814315 | 
2026-05-20 19:24:46.814423 | TASK [fetch-output : Set log path for single node]
2026-05-20 19:24:46.858944 | controller | ok
2026-05-20 19:24:46.864646 | 
2026-05-20 19:24:46.864727 | LOOP [fetch-output : Ensure local output dirs]
2026-05-20 19:24:47.249160 | controller -> localhost | ok: "/var/lib/zuul/builds/472034a2a41740fcbef77c1b71702c03/work/logs"
2026-05-20 19:24:47.436534 | controller -> localhost | changed: "/var/lib/zuul/builds/472034a2a41740fcbef77c1b71702c03/work/artifacts"
2026-05-20 19:24:47.642293 | controller -> localhost | changed: "/var/lib/zuul/builds/472034a2a41740fcbef77c1b71702c03/work/docs"
2026-05-20 19:24:47.662611 | 
2026-05-20 19:24:47.662782 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-05-20 19:24:48.299773 | controller | changed: .d..t...... ./
2026-05-20 19:24:48.300012 | controller | changed: All items complete
2026-05-20 19:24:48.300050 | 
2026-05-20 19:24:48.768500 | controller | changed: .d..t...... ./
2026-05-20 19:24:49.242195 | controller | changed: .d..t...... ./
2026-05-20 19:24:49.261640 | 
2026-05-20 19:24:49.261784 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-05-20 19:24:49.659612 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.009026
2026-05-20 19:24:49.879291 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.007472
2026-05-20 19:24:49.898483 | 
2026-05-20 19:24:49.898595 | PLAY [all]
2026-05-20 19:24:49.904845 | 
2026-05-20 19:24:49.904906 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-05-20 19:24:50.375523 | controller | changed
2026-05-20 19:24:50.382919 | 
2026-05-20 19:24:50.382971 | PLAY RECAP
2026-05-20 19:24:50.383018 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-05-20 19:24:50.383042 | 
2026-05-20 19:24:50.502876 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-05-20 19:24:50.509053 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-05-20 19:24:51.128488 | 
2026-05-20 19:24:51.128628 | PLAY [localhost]
2026-05-20 19:24:51.138584 | 
2026-05-20 19:24:51.138662 | TASK [Generate Zuul manifest]
2026-05-20 19:24:51.171129 | localhost | ok
2026-05-20 19:24:51.186635 | 
2026-05-20 19:24:51.186712 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-05-20 19:24:51.533950 | localhost | changed
2026-05-20 19:24:51.544482 | 
2026-05-20 19:24:51.544555 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-05-20 19:24:51.575598 | localhost | ok
2026-05-20 19:24:51.584329 | 
2026-05-20 19:24:51.584413 | TASK [Upload logs]
2026-05-20 19:24:51.606275 | localhost | ok
2026-05-20 19:24:51.663195 | 
2026-05-20 19:24:51.663307 | TASK [Set zuul-log-path fact]
2026-05-20 19:24:51.685901 | localhost | ok
2026-05-20 19:24:51.698842 | 
2026-05-20 19:24:51.698915 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-05-20 19:24:51.731092 | localhost | ok
2026-05-20 19:24:51.740064 | 
2026-05-20 19:24:51.740148 | TASK [upload-logs : Create log directories]
2026-05-20 19:24:52.130288 | localhost | changed
2026-05-20 19:24:52.175688 | 
2026-05-20 19:24:52.175823 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-05-20 19:24:52.549831 | localhost -> localhost | ok: Runtime: 0:00:00.004835
2026-05-20 19:24:52.555166 | 
2026-05-20 19:24:52.555237 | TASK [upload-logs : Upload logs to log server]
2026-05-20 19:24:52.975634 | localhost | Output suppressed because no_log was given
2026-05-20 19:24:52.980672 | 
2026-05-20 19:24:52.980742 | LOOP [upload-logs : Compress console log and json output]
2026-05-20 19:24:53.033010 | localhost | skipping: Conditional result was False
2026-05-20 19:24:53.039895 | localhost | skipping: Conditional result was False
2026-05-20 19:24:53.055071 | 
2026-05-20 19:24:53.055195 | LOOP [upload-logs : Upload compressed console log and json output]
2026-05-20 19:24:53.102236 | localhost | skipping: Conditional result was False
2026-05-20 19:24:53.102854 | 
2026-05-20 19:24:53.106007 | localhost | skipping: Conditional result was False
2026-05-20 19:24:53.121449 | 
2026-05-20 19:24:53.121616 | LOOP [upload-logs : Upload console log and json output]