2026-09-12 03:54:21.651802 | Job console starting
2026-09-12 03:54:21.681102 | Updating git repos
2026-09-12 03:54:25.300385 | Cloning repos into workspace
2026-09-12 03:54:41.261027 | Restoring repo states
2026-09-12 03:54:50.303321 | Merging changes
2026-09-12 03:54:51.446013 | Checking out repos
2026-09-12 03:54:53.033059 | Preparing playbooks
2026-09-12 03:54:55.553272 | Running Ansible setup
2026-09-12 03:54:59.115956 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-12 03:54:59.743607 | 
2026-09-12 03:54:59.743766 | PLAY [localhost]
2026-09-12 03:54:59.752349 | 
2026-09-12 03:54:59.752644 | TASK [Gathering Facts]
2026-09-12 03:55:00.758794 | localhost | ok
2026-09-12 03:55:00.771431 | 
2026-09-12 03:55:00.771578 | TASK [Setup log path fact]
2026-09-12 03:55:00.795010 | localhost | ok
2026-09-12 03:55:00.815219 | 
2026-09-12 03:55:00.815321 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-12 03:55:00.856442 | localhost | ok
2026-09-12 03:55:00.868074 | 
2026-09-12 03:55:00.868161 | TASK [emit-job-header : Print job information]
2026-09-12 03:55:00.919844 | # Job Information
2026-09-12 03:55:00.920071 | Ansible Version: 2.16.18
2026-09-12 03:55:00.920134 | Job: atmosphere-molecule-csi-rbd
2026-09-12 03:55:00.920178 | Pipeline: check
2026-09-12 03:55:00.920217 | Executor: 2d72f0692154
2026-09-12 03:55:00.920255 | Triggered by: https://github.com/vexxhost/atmosphere/pull/4365
2026-09-12 03:55:00.920300 | Event ID: d864e070-ae5c-11f1-99bc-ab36f7e6b742
2026-09-12 03:55:00.926106 | 
2026-09-12 03:55:00.926231 | LOOP [emit-job-header : Print node information]
2026-09-12 03:55:01.087814 | localhost | ok:
2026-09-12 03:55:01.088336 | localhost | # Node Information
2026-09-12 03:55:01.088431 | localhost | Inventory Hostname: instance
2026-09-12 03:55:01.088478 | localhost | Hostname: np0000192682
2026-09-12 03:55:01.088554 | localhost | Username: zuul
2026-09-12 03:55:01.088613 | localhost | Distro: Ubuntu 22.04
2026-09-12 03:55:01.088673 | localhost | Provider: yul1
2026-09-12 03:55:01.088724 | localhost | Region: ca-ymq-1
2026-09-12 03:55:01.088783 | localhost | Label: ubuntu-jammy
2026-09-12 03:55:01.088824 | localhost | Product Name: OpenStack Nova
2026-09-12 03:55:01.088863 | localhost | Interface IP: 162.253.55.72
2026-09-12 03:55:01.112049 | 
2026-09-12 03:55:01.112231 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-09-12 03:55:01.559582 | localhost -> localhost | changed
2026-09-12 03:55:01.568801 | 
2026-09-12 03:55:01.568933 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-09-12 03:55:02.542598 | localhost -> localhost | changed
2026-09-12 03:55:02.549875 | 
2026-09-12 03:55:02.549949 | PLAY [all]
2026-09-12 03:55:02.559301 | 
2026-09-12 03:55:02.559367 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-09-12 03:55:02.938291 | instance -> localhost | ok
2026-09-12 03:55:02.943995 | 
2026-09-12 03:55:02.944058 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-09-12 03:55:02.978449 | instance | ok
2026-09-12 03:55:03.001277 | instance | included: /var/lib/zuul/builds/9ea711e316f347ad95c86c894d916ec6/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-09-12 03:55:03.007957 | 
2026-09-12 03:55:03.008033 | TASK [add-build-sshkey : Create Temp SSH key]
2026-09-12 03:55:03.909282 | instance -> localhost | Generating public/private rsa key pair.
2026-09-12 03:55:03.909534 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/9ea711e316f347ad95c86c894d916ec6/work/9ea711e316f347ad95c86c894d916ec6_id_rsa
2026-09-12 03:55:03.909590 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/9ea711e316f347ad95c86c894d916ec6/work/9ea711e316f347ad95c86c894d916ec6_id_rsa.pub
2026-09-12 03:55:03.909632 | instance -> localhost | The key fingerprint is:
2026-09-12 03:55:03.909673 | instance -> localhost | SHA256:J1ArzhW9WGLcCGf6myp5c8hGQKmbfa1cJ5vDGgaBCYA zuul-build-sshkey
2026-09-12 03:55:03.909726 | instance -> localhost | The key's randomart image is:
2026-09-12 03:55:03.909784 | instance -> localhost | +---[RSA 3072]----+
2026-09-12 03:55:03.909831 | instance -> localhost | |=    ..o=+       |
2026-09-12 03:55:03.909872 | instance -> localhost | |E. oo  ==o+      |
2026-09-12 03:55:03.909909 | instance -> localhost | |  oo. +.o+ .     |
2026-09-12 03:55:03.909947 | instance -> localhost | |  . .+ =. .      |
2026-09-12 03:55:03.909985 | instance -> localhost | |   +..o.S .      |
2026-09-12 03:55:03.910022 | instance -> localhost | |  o ..o +=.      |
2026-09-12 03:55:03.910057 | instance -> localhost | |     *o=o=       |
2026-09-12 03:55:03.910094 | instance -> localhost | |    o.Oo*        |
2026-09-12 03:55:03.910136 | instance -> localhost | |     +o+ .       |
2026-09-12 03:55:03.910174 | instance -> localhost | +----[SHA256]-----+
2026-09-12 03:55:03.910260 | instance -> localhost | ok: Runtime: 0:00:00.447046
2026-09-12 03:55:03.923662 | 
2026-09-12 03:55:03.923878 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-09-12 03:55:03.963391 | instance | ok
2026-09-12 03:55:03.976736 | instance | included: /var/lib/zuul/builds/9ea711e316f347ad95c86c894d916ec6/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-09-12 03:55:03.990280 | 
2026-09-12 03:55:03.990609 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-09-12 03:55:04.016372 | instance | skipping: Conditional result was False
2026-09-12 03:55:04.024585 | 
2026-09-12 03:55:04.024680 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-09-12 03:55:04.530147 | instance | changed
2026-09-12 03:55:04.537384 | 
2026-09-12 03:55:04.537462 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-09-12 03:55:04.731304 | instance | ok
2026-09-12 03:55:04.738531 | 
2026-09-12 03:55:04.738609 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-09-12 03:55:05.355607 | instance | changed
2026-09-12 03:55:05.365650 | 
2026-09-12 03:55:05.366068 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-09-12 03:55:05.874597 | instance | changed
2026-09-12 03:55:05.894039 | 
2026-09-12 03:55:05.894252 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-09-12 03:55:05.923816 | instance | skipping: Conditional result was False
2026-09-12 03:55:05.935682 | 
2026-09-12 03:55:05.935881 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-09-12 03:55:06.321145 | instance -> localhost | changed
2026-09-12 03:55:06.335166 | 
2026-09-12 03:55:06.335317 | TASK [add-build-sshkey : Add back temp key]
2026-09-12 03:55:06.643996 | instance -> localhost | Identity added: /var/lib/zuul/builds/9ea711e316f347ad95c86c894d916ec6/work/9ea711e316f347ad95c86c894d916ec6_id_rsa (zuul-build-sshkey)
2026-09-12 03:55:06.644299 | instance -> localhost | ok: Runtime: 0:00:00.014838
2026-09-12 03:55:06.657699 | 
2026-09-12 03:55:06.657853 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-09-12 03:55:07.036671 | instance | ok
2026-09-12 03:55:07.046149 | 
2026-09-12 03:55:07.046287 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-09-12 03:55:07.088267 | instance | skipping: Conditional result was False
2026-09-12 03:55:07.100096 | 
2026-09-12 03:55:07.100246 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-09-12 03:55:07.479357 | instance | ok
2026-09-12 03:55:07.490491 | 
2026-09-12 03:55:07.490614 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-09-12 03:55:17.460393 | instance | Output suppressed because no_log was given
2026-09-12 03:55:17.470967 | 
2026-09-12 03:55:17.471099 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-09-12 03:55:17.701774 | instance | ok: "logs"
2026-09-12 03:55:17.702179 | instance | ok: All items complete
2026-09-12 03:55:17.702239 | 
2026-09-12 03:55:17.874613 | instance | ok: "artifacts"
2026-09-12 03:55:18.055219 | instance | ok: "docs"
2026-09-12 03:55:18.067946 | 
2026-09-12 03:55:18.068152 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-09-12 03:55:18.261789 | instance | changed: "logs"
2026-09-12 03:55:18.468109 | instance | changed: "artifacts"
2026-09-12 03:55:18.657635 | instance | changed: "docs"
2026-09-12 03:55:18.679457 | 
2026-09-12 03:55:18.679595 | PLAY RECAP
2026-09-12 03:55:18.679656 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-09-12 03:55:18.679693 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-12 03:55:18.679716 | 
2026-09-12 03:55:18.849871 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-12 03:55:18.851778 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-12 03:55:19.458450 | 
2026-09-12 03:55:19.458659 | PLAY [all]
2026-09-12 03:55:19.470899 | 
2026-09-12 03:55:19.471121 | TASK [setup-uv : Extract archive]
2026-09-12 03:55:22.001552 | instance | changed
2026-09-12 03:55:22.012718 | 
2026-09-12 03:55:22.012954 | TASK [setup-uv : Print version]
2026-09-12 03:55:22.402197 | instance | uv 0.8.13
2026-09-12 03:55:22.576318 | instance | ok: Runtime: 0:00:00.012004
2026-09-12 03:55:22.608212 | 
2026-09-12 03:55:22.608419 | TASK [Install Ansible collection siblings]
2026-09-12 03:55:31.859817 | instance | changed:
2026-09-12 03:55:31.860096 | instance | Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon
2026-09-12 03:55:31.860153 | instance | Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix
2026-09-12 03:55:31.860196 | instance | Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils
2026-09-12 03:55:31.860234 | instance | Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto
2026-09-12 03:55:31.860272 | instance | Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general
2026-09-12 03:55:31.860316 | instance | Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql
2026-09-12 03:55:31.860357 | instance | Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core
2026-09-12 03:55:31.860397 | instance | Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph
2026-09-12 03:55:31.860435 | instance | Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers
2026-09-12 03:55:31.860472 | instance | Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes
2026-09-12 03:55:31.860541 | instance | Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common
2026-09-12 03:55:31.860580 | instance | Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack
2026-09-12 03:55:31.860616 | instance | Resolved ansible.netcommon to v8.6.2 for >=1.2.0
2026-09-12 03:55:31.860651 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0
2026-09-12 03:55:31.860686 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=3.0.0
2026-09-12 03:55:31.860722 | instance | Resolved community.crypto to 2.2.3 for 2.2.3
2026-09-12 03:55:31.860777 | instance | Resolved community.general to 7.3.0 for 7.3.0
2026-09-12 03:55:31.860816 | instance | Resolved community.mysql to 3.6.0 for 3.6.0
2026-09-12 03:55:31.860853 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0
2026-09-12 03:55:31.860888 | instance | Resolved openstack.cloud to 1.7.0 for 1.7.0
2026-09-12 03:55:31.860923 | instance | Resolved atmosphere.common to v0.6.0 for >=0.6.0,<0.7.0
2026-09-12 03:55:31.860958 | instance | Resolved vexxhost.ceph to v4.2.0 for >=3.1.2
2026-09-12 03:55:31.860996 | instance | Resolved vexxhost.kubernetes to v3.4.0 for >=3.3.0,>=2.5.0
2026-09-12 03:55:31.861058 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=3.0.0,>=6.0.0
2026-09-12 03:55:31.861096 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-09-12 03:55:31.861131 | instance | Resolved vexxhost.kubernetes to v3.4.0 for >=3.3.0,>=2.5.0
2026-09-12 03:55:31.861166 | instance | Resolved community.general to 7.3.0 for 7.3.0,>=4.5.0
2026-09-12 03:55:31.861201 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=3.0.0,>=6.0.0
2026-09-12 03:55:31.861235 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-09-12 03:55:31.861269 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0,>=1.6.0
2026-09-12 03:55:31.861304 | instance | Resolved community.crypto to 2.2.3 for 2.2.3,>=2.2.3
2026-09-12 03:55:31.861340 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-09-12 03:55:31.861376 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-09-12 03:55:31.861412 | instance | Checked out ansible.netcommon to v8.6.2
2026-09-12 03:55:31.861446 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.netcommon
2026-09-12 03:55:31.861492 | instance | Starting galaxy collection install process
2026-09-12 03:55:31.861528 | instance | Process install dependency map
2026-09-12 03:55:31.861562 | instance | Starting collection install process
2026-09-12 03:55:31.861596 | instance | Installing 'ansible.netcommon:8.6.2' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-09-12 03:55:31.861630 | instance | Created collection for ansible.netcommon:8.6.2 at /home/zuul/.ansible/collections/ansible_collections/ansible/netcommon
2026-09-12 03:55:31.861664 | instance | ansible.netcommon:8.6.2 was installed successfully
2026-09-12 03:55:31.861698 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3
2026-09-12 03:55:31.861732 | instance | Creating virtual environment at: .venv
2026-09-12 03:55:31.861786 | instance |    Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-09-12 03:55:31.861822 | instance | Downloading setuptools (1.1MiB)
2026-09-12 03:55:31.861862 | instance | Downloading pygments (1.2MiB)
2026-09-12 03:55:31.861896 | instance | Downloading kubernetes (1.9MiB)
2026-09-12 03:55:31.861930 | instance | Downloading rjsonnet (1.2MiB)
2026-09-12 03:55:31.861964 | instance | Downloading ansible-core (2.1MiB)
2026-09-12 03:55:31.861999 | instance | Downloading cryptography (4.2MiB)
2026-09-12 03:55:31.862033 | instance | Downloading netaddr (2.2MiB)
2026-09-12 03:55:31.862068 | instance | Downloading openstacksdk (1.7MiB)
2026-09-12 03:55:31.862102 | instance |    Building pyperclip==1.9.0
2026-09-12 03:55:31.862139 | instance |  Downloading rjsonnet
2026-09-12 03:55:31.862174 | instance |  Downloading netaddr
2026-09-12 03:55:31.862209 | instance |  Downloading pygments
2026-09-12 03:55:31.862243 | instance |  Downloading cryptography
2026-09-12 03:55:31.862277 | instance |  Downloading setuptools
2026-09-12 03:55:31.862311 | instance |  Downloading kubernetes
2026-09-12 03:55:31.862344 | instance |  Downloading ansible-core
2026-09-12 03:55:31.862378 | instance |  Downloading openstacksdk
2026-09-12 03:55:31.862412 | instance |       Built pyperclip==1.9.0
2026-09-12 03:55:31.862446 | instance |       Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-09-12 03:55:31.862481 | instance | Installed 79 packages in 59ms
2026-09-12 03:55:31.862515 | instance | Checked out ansible.posix to 1.6.0
2026-09-12 03:55:31.862550 | instance | 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-09-12 03:55:31.862585 | instance | Starting galaxy collection install process
2026-09-12 03:55:31.862620 | instance | Process install dependency map
2026-09-12 03:55:31.862669 | instance | Starting collection install process
2026-09-12 03:55:31.862706 | instance | Installing 'ansible.posix:1.6.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-09-12 03:55:31.862740 | instance | Created collection for ansible.posix:1.6.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/posix
2026-09-12 03:55:31.862793 | instance | ansible.posix:1.6.0 was installed successfully
2026-09-12 03:55:31.862829 | instance | Checked out ansible.utils to v6.1.0
2026-09-12 03:55:31.862864 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.utils
2026-09-12 03:55:31.862899 | instance | Starting galaxy collection install process
2026-09-12 03:55:31.862933 | instance | Process install dependency map
2026-09-12 03:55:31.862975 | instance | Starting collection install process
2026-09-12 03:55:31.863043 | instance | Installing 'ansible.utils:6.1.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2026-09-12 03:55:31.863136 | instance | Created collection for ansible.utils:6.1.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/utils
2026-09-12 03:55:31.863182 | instance | ansible.utils:6.1.0 was installed successfully
2026-09-12 03:55:31.863222 | instance | Checked out atmosphere.common to v0.6.0
2026-09-12 03:55:31.863262 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/atmosphere.common
2026-09-12 03:55:31.863301 | instance | Starting galaxy collection install process
2026-09-12 03:55:31.863338 | instance | Process install dependency map
2026-09-12 03:55:31.863377 | instance | Starting collection install process
2026-09-12 03:55:31.863414 | instance | Installing 'atmosphere.common:0.6.0' to '/home/zuul/.ansible/collections/ansible_collections/atmosphere/common'
2026-09-12 03:55:31.863452 | instance | Created collection for atmosphere.common:0.6.0 at /home/zuul/.ansible/collections/ansible_collections/atmosphere/common
2026-09-12 03:55:31.863488 | instance | atmosphere.common:0.6.0 was installed successfully
2026-09-12 03:55:31.863524 | instance | Checked out community.crypto to 2.2.3
2026-09-12 03:55:31.863560 | instance | 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-09-12 03:55:31.863598 | instance | Starting galaxy collection install process
2026-09-12 03:55:31.863635 | instance | Process install dependency map
2026-09-12 03:55:31.863671 | instance | Starting collection install process
2026-09-12 03:55:31.863707 | instance | Installing 'community.crypto:2.2.3' to '/home/zuul/.ansible/collections/ansible_collections/community/crypto'
2026-09-12 03:55:31.863742 | instance | Created collection for community.crypto:2.2.3 at /home/zuul/.ansible/collections/ansible_collections/community/crypto
2026-09-12 03:55:31.863808 | instance | community.crypto:2.2.3 was installed successfully
2026-09-12 03:55:31.863850 | instance | Checked out community.general to 7.3.0
2026-09-12 03:55:31.863885 | instance | 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-09-12 03:55:31.863935 | instance | Starting galaxy collection install process
2026-09-12 03:55:31.863988 | instance | Process install dependency map
2026-09-12 03:55:31.864027 | instance | Starting collection install process
2026-09-12 03:55:31.864061 | instance | Installing 'community.general:7.3.0' to '/home/zuul/.ansible/collections/ansible_collections/community/general'
2026-09-12 03:55:31.864102 | instance | Created collection for community.general:7.3.0 at /home/zuul/.ansible/collections/ansible_collections/community/general
2026-09-12 03:55:31.864157 | instance | community.general:7.3.0 was installed successfully
2026-09-12 03:55:31.864225 | instance | Checked out community.mysql to 3.6.0
2026-09-12 03:55:31.864285 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.mysql
2026-09-12 03:55:31.864333 | instance | Starting galaxy collection install process
2026-09-12 03:55:31.864372 | instance | Process install dependency map
2026-09-12 03:55:31.864409 | instance | Starting collection install process
2026-09-12 03:55:31.864444 | instance | Installing 'community.mysql:3.6.0' to '/home/zuul/.ansible/collections/ansible_collections/community/mysql'
2026-09-12 03:55:31.864481 | instance | Created collection for community.mysql:3.6.0 at /home/zuul/.ansible/collections/ansible_collections/community/mysql
2026-09-12 03:55:31.864555 | instance | community.mysql:3.6.0 was installed successfully
2026-09-12 03:55:31.864594 | instance | Checked out kubernetes.core to 2.4.0
2026-09-12 03:55:31.864632 | instance | 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-09-12 03:55:31.864669 | instance | Starting galaxy collection install process
2026-09-12 03:55:31.864706 | instance | Process install dependency map
2026-09-12 03:55:31.864748 | instance | Starting collection install process
2026-09-12 03:55:31.864976 | instance | Installing 'kubernetes.core:2.4.0' to '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core'
2026-09-12 03:55:31.865046 | instance | Created collection for kubernetes.core:2.4.0 at /home/zuul/.ansible/collections/ansible_collections/kubernetes/core
2026-09-12 03:55:31.865086 | instance | kubernetes.core:2.4.0 was installed successfully
2026-09-12 03:55:31.865124 | instance | Checked out openstack.cloud to 1.7.0
2026-09-12 03:55:31.865159 | instance | 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-09-12 03:55:31.865195 | instance | Starting galaxy collection install process
2026-09-12 03:55:31.865230 | instance | Process install dependency map
2026-09-12 03:55:31.865264 | instance | Starting collection install process
2026-09-12 03:55:31.865298 | instance | Installing 'openstack.cloud:1.7.0' to '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud'
2026-09-12 03:55:31.865332 | instance | Created collection for openstack.cloud:1.7.0 at /home/zuul/.ansible/collections/ansible_collections/openstack/cloud
2026-09-12 03:55:31.865365 | instance | openstack.cloud:1.7.0 was installed successfully
2026-09-12 03:55:31.865399 | instance | Checked out vexxhost.ceph to v4.2.0
2026-09-12 03:55:31.865434 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-ceph
2026-09-12 03:55:31.865468 | instance | Starting galaxy collection install process
2026-09-12 03:55:31.865502 | instance | Process install dependency map
2026-09-12 03:55:31.865537 | instance | Starting collection install process
2026-09-12 03:55:31.865571 | instance | Installing 'vexxhost.ceph:4.2.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph'
2026-09-12 03:55:31.865605 | instance | Created collection for vexxhost.ceph:4.2.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph
2026-09-12 03:55:31.865638 | instance | vexxhost.ceph:4.2.0 was installed successfully
2026-09-12 03:55:31.865672 | instance | Checked out vexxhost.containers to v1.6.8
2026-09-12 03:55:31.865706 | instance | 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-09-12 03:55:31.865742 | instance | Starting galaxy collection install process
2026-09-12 03:55:31.865815 | instance | Process install dependency map
2026-09-12 03:55:31.865853 | instance | Starting collection install process
2026-09-12 03:55:31.865887 | instance | Installing 'vexxhost.containers:1.6.8' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers'
2026-09-12 03:55:31.865921 | instance | Created collection for vexxhost.containers:1.6.8 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers
2026-09-12 03:55:31.865955 | instance | vexxhost.containers:1.6.8 was installed successfully
2026-09-12 03:55:31.865989 | instance | Checked out vexxhost.kubernetes to v3.4.0
2026-09-12 03:55:31.866023 | instance | 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-09-12 03:55:31.866056 | instance | Starting galaxy collection install process
2026-09-12 03:55:31.866090 | instance | Process install dependency map
2026-09-12 03:55:31.866123 | instance | Starting collection install process
2026-09-12 03:55:31.866156 | instance | Installing 'vexxhost.kubernetes:3.4.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes'
2026-09-12 03:55:31.866189 | instance | Created collection for vexxhost.kubernetes:3.4.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes
2026-09-12 03:55:31.866223 | instance | vexxhost.kubernetes:3.4.0 was installed successfully
2026-09-12 03:55:31.876636 | 
2026-09-12 03:55:31.876729 | PLAY RECAP
2026-09-12 03:55:31.876839 | instance | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-12 03:55:31.876881 | 
2026-09-12 03:55:32.003670 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-12 03:55:32.007359 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-09-12 03:55:32.591656 | 
2026-09-12 03:55:32.591809 | PLAY [all]
2026-09-12 03:55:32.602670 | 
2026-09-12 03:55:32.602744 | TASK [Install "jq" for log collection]
2026-09-12 03:55:42.044237 | instance | changed
2026-09-12 03:55:42.047444 | 
2026-09-12 03:55:42.048131 | PLAY RECAP
2026-09-12 03:55:42.048252 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-12 03:55:42.048354 | 
2026-09-12 03:55:42.179819 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-09-12 03:55:42.183638 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-12 03:55:42.787333 | 
2026-09-12 03:55:42.787506 | PLAY [all]
2026-09-12 03:55:42.798487 | 
2026-09-12 03:55:42.798570 | LOOP [Check inventory directories]
2026-09-12 03:55:43.162440 | instance | ok: "src/github.com/vexxhost/atmosphere"
2026-09-12 03:55:43.339072 | instance | ok: "src/github.com/vexxhost/atmosphere/molecule/csi/inventory"
2026-09-12 03:55:43.520399 | instance | ok: "src/github.com/vexxhost/atmosphere/extensions/molecule/csi/inventory"
2026-09-12 03:55:43.534655 | 
2026-09-12 03:55:43.534972 | LOOP [Copy inventory file for Zuul]
2026-09-12 03:55:44.399091 | instance | changed:
2026-09-12 03:55:44.399473 | instance | {
2026-09-12 03:55:44.399536 | instance |   "ansible_loop_var": "item",
2026-09-12 03:55:44.399584 | instance |   "changed": false,
2026-09-12 03:55:44.399623 | instance |   "failed": false,
2026-09-12 03:55:44.399667 | instance |   "invocation": {
2026-09-12 03:55:44.399703 | instance |     "module_args": {
2026-09-12 03:55:44.399738 | instance |       "checksum_algorithm": "sha1",
2026-09-12 03:55:44.399801 | instance |       "follow": true,
2026-09-12 03:55:44.399840 | instance |       "get_attributes": true,
2026-09-12 03:55:44.399901 | instance |       "get_checksum": true,
2026-09-12 03:55:44.399936 | instance |       "get_mime": true,
2026-09-12 03:55:44.399970 | instance |       "path": "src/github.com/vexxhost/atmosphere"
2026-09-12 03:55:44.400004 | instance |     }
2026-09-12 03:55:44.400061 | instance |   },
2026-09-12 03:55:44.400098 | instance |   "item": "src/github.com/vexxhost/atmosphere",
2026-09-12 03:55:44.400143 | instance |   "stat": {
2026-09-12 03:55:44.400193 | instance |     "atime": 1789185324.7843337,
2026-09-12 03:55:44.400243 | instance |     "attr_flags": "e",
2026-09-12 03:55:44.400279 | instance |     "attributes": [
2026-09-12 03:55:44.400313 | instance |       "extents"
2026-09-12 03:55:44.400347 | instance |     ],
2026-09-12 03:55:44.400380 | instance |     "block_size": 4096,
2026-09-12 03:55:44.400412 | instance |     "blocks": 8,
2026-09-12 03:55:44.400446 | instance |     "charset": "unknown",
2026-09-12 03:55:44.400482 | instance |     "ctime": 1789185323.5763361,
2026-09-12 03:55:44.400559 | instance |     "dev": 64513,
2026-09-12 03:55:44.400595 | instance |     "device_type": 0,
2026-09-12 03:55:44.400628 | instance |     "executable": true,
2026-09-12 03:55:44.400661 | instance |     "exists": true,
2026-09-12 03:55:44.400693 | instance |     "gid": 1000,
2026-09-12 03:55:44.400727 | instance |     "gr_name": "zuul",
2026-09-12 03:55:44.400759 | instance |     "inode": 1048559,
2026-09-12 03:55:44.400815 | instance |     "isblk": false,
2026-09-12 03:55:44.400849 | instance |     "ischr": false,
2026-09-12 03:55:44.400883 | instance |     "isdir": true,
2026-09-12 03:55:44.400915 | instance |     "isfifo": false,
2026-09-12 03:55:44.400948 | instance |     "isgid": false,
2026-09-12 03:55:44.400980 | instance |     "islnk": false,
2026-09-12 03:55:44.401013 | instance |     "isreg": false,
2026-09-12 03:55:44.401045 | instance |     "issock": false,
2026-09-12 03:55:44.401077 | instance |     "isuid": false,
2026-09-12 03:55:44.401108 | instance |     "mimetype": "unknown",
2026-09-12 03:55:44.401140 | instance |     "mode": "0755",
2026-09-12 03:55:44.401172 | instance |     "mtime": 1789185323.5763361,
2026-09-12 03:55:44.401204 | instance |     "nlink": 22,
2026-09-12 03:55:44.401235 | instance |     "path": "src/github.com/vexxhost/atmosphere",
2026-09-12 03:55:44.401269 | instance |     "pw_name": "zuul",
2026-09-12 03:55:44.401310 | instance |     "readable": true,
2026-09-12 03:55:44.401344 | instance |     "rgrp": true,
2026-09-12 03:55:44.401378 | instance |     "roth": true,
2026-09-12 03:55:44.401412 | instance |     "rusr": true,
2026-09-12 03:55:44.401444 | instance |     "size": 4096,
2026-09-12 03:55:44.401477 | instance |     "uid": 1000,
2026-09-12 03:55:44.401510 | instance |     "version": "442704734",
2026-09-12 03:55:44.401544 | instance |     "wgrp": false,
2026-09-12 03:55:44.401579 | instance |     "woth": false,
2026-09-12 03:55:44.401613 | instance |     "writeable": true,
2026-09-12 03:55:44.401646 | instance |     "wusr": true,
2026-09-12 03:55:44.401679 | instance |     "xgrp": true,
2026-09-12 03:55:44.401712 | instance |     "xoth": true,
2026-09-12 03:55:44.401744 | instance |     "xusr": true
2026-09-12 03:55:44.401797 | instance |   }
2026-09-12 03:55:44.401832 | instance | }
2026-09-12 03:55:44.401901 | instance | ok: All items complete
2026-09-12 03:55:44.401942 | 
2026-09-12 03:55:44.404593 | instance | skipping: Conditional result was False
2026-09-12 03:55:44.406157 | instance | skipping: Conditional result was False
2026-09-12 03:55:44.417496 | 
2026-09-12 03:55:44.417999 | LOOP [Switch "ansible_host" to private IP]
2026-09-12 03:55:44.809982 | instance | changed: 1 replacements made
2026-09-12 03:55:44.813172 | instance | skipping: Conditional result was False
2026-09-12 03:55:44.823903 | instance | skipping: Conditional result was False
2026-09-12 03:55:44.839789 | 
2026-09-12 03:55:44.839942 | TASK [Run Molecule scenario]
2026-09-12 03:55:45.786347 | instance | WARNING  Molecule scenarios should migrate to 'extensions/molecule'
2026-09-12 03:55:46.182158 | instance | INFO     [csi > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-09-12 03:55:46.182264 | instance | INFO     [csi > prerun] Performing prerun with role_name_check=0...
2026-09-12 03:55:57.340727 | instance | INFO     [csi > dependency] Executing
2026-09-12 03:55:57.341043 | instance | WARNING  [csi > dependency] Missing roles requirements file: requirements.yml
2026-09-12 03:55:57.341357 | instance | WARNING  [csi > dependency] Missing collections requirements file: collections.yml
2026-09-12 03:55:57.341519 | instance | WARNING  [csi > dependency] Executed: 2 missing (Remove from test_sequence to suppress)
2026-09-12 03:55:57.362646 | instance | INFO     [csi > cleanup] Executing
2026-09-12 03:55:57.364236 | instance | WARNING  [csi > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-09-12 03:55:57.375819 | instance | INFO     [csi > destroy] Executing
2026-09-12 03:55:57.375910 | instance | WARNING  [csi > destroy] Skipping, '--destroy=never' requested.
2026-09-12 03:55:57.376015 | instance | INFO     [csi > destroy] Executed: Successful
2026-09-12 03:55:57.383416 | instance | INFO     [csi > syntax] Executing
2026-09-12 03:55:58.862978 | instance |
2026-09-12 03:55:58.863089 | instance | playbook: /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml
2026-09-12 03:55:58.939281 | instance | INFO     [csi > syntax] Executed: Successful
2026-09-12 03:55:58.954031 | instance | INFO     [csi > create] Executing
2026-09-12 03:55:58.957583 | instance | WARNING  [csi > create] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-09-12 03:55:58.971604 | instance | INFO     [csi > prepare] Executing
2026-09-12 03:55:59.735610 | instance |
2026-09-12 03:55:59.735783 | instance | PLAY [Prepare] *****************************************************************
2026-09-12 03:55:59.736001 | instance |
2026-09-12 03:55:59.736318 | instance | TASK [Gathering Facts] *********************************************************
2026-09-12 03:55:59.736674 | instance | Saturday 12 September 2026  03:55:59 +0000 (0:00:00.024)       0:00:00.024 ****
2026-09-12 03:56:01.035098 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-09-12 03:56:01.035327 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-09-12 03:56:01.035577 | instance | interpreter could change the meaning of that path. See
2026-09-12 03:56:01.035850 | instance | https://docs.ansible.com/ansible-
2026-09-12 03:56:01.036092 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-09-12 03:56:01.046024 | instance | ok: [instance]
2026-09-12 03:56:01.046246 | instance |
2026-09-12 03:56:01.046483 | instance | TASK [Configure short hostname] ************************************************
2026-09-12 03:56:01.046735 | instance | Saturday 12 September 2026  03:56:01 +0000 (0:00:01.311)       0:00:01.335 ****
2026-09-12 03:56:01.816177 | instance | changed: [instance]
2026-09-12 03:56:01.816398 | instance |
2026-09-12 03:56:01.816611 | instance | TASK [Ensure hostname inside hosts file] ***************************************
2026-09-12 03:56:01.816912 | instance | Saturday 12 September 2026  03:56:01 +0000 (0:00:00.769)       0:00:02.105 ****
2026-09-12 03:56:02.105257 | instance | [WARNING]: Module remote_tmp /root/.ansible/tmp did not exist and was created
2026-09-12 03:56:02.105310 | instance | with a mode of 0700, this may cause issues when running as another user. To
2026-09-12 03:56:02.105319 | instance | avoid this, create the remote_tmp dir with the correct permissions manually
2026-09-12 03:56:02.110454 | instance | changed: [instance]
2026-09-12 03:56:02.110482 | instance |
2026-09-12 03:56:02.110491 | instance | TASK [Purge "snapd" package] ***************************************************
2026-09-12 03:56:02.110499 | instance | Saturday 12 September 2026  03:56:02 +0000 (0:00:00.294)       0:00:02.400 ****
2026-09-12 03:56:02.942397 | instance | ok: [instance]
2026-09-12 03:56:02.942794 | instance |
2026-09-12 03:56:02.943109 | instance | PLAY [Create devices for Ceph] *************************************************
2026-09-12 03:56:02.943393 | instance |
2026-09-12 03:56:02.943690 | instance | TASK [Gathering Facts] *********************************************************
2026-09-12 03:56:02.944055 | instance | Saturday 12 September 2026  03:56:02 +0000 (0:00:00.831)       0:00:03.231 ****
2026-09-12 03:56:03.709932 | instance | ok: [instance]
2026-09-12 03:56:03.710131 | instance |
2026-09-12 03:56:03.710274 | instance | TASK [Install depedencies] *****************************************************
2026-09-12 03:56:03.710414 | instance | Saturday 12 September 2026  03:56:03 +0000 (0:00:00.767)       0:00:03.999 ****
2026-09-12 03:56:27.651046 | instance | changed: [instance]
2026-09-12 03:56:27.651112 | instance |
2026-09-12 03:56:27.651125 | instance | TASK [Start up service] ********************************************************
2026-09-12 03:56:27.651137 | instance | Saturday 12 September 2026  03:56:27 +0000 (0:00:23.939)       0:00:27.939 ****
2026-09-12 03:56:28.244157 | instance | ok: [instance]
2026-09-12 03:56:28.244226 | instance |
2026-09-12 03:56:28.244237 | instance | TASK [Generate lvm.conf] *******************************************************
2026-09-12 03:56:28.244246 | instance | Saturday 12 September 2026  03:56:28 +0000 (0:00:00.593)       0:00:28.532 ****
2026-09-12 03:56:28.587202 | instance | ok: [instance]
2026-09-12 03:56:28.587276 | instance |
2026-09-12 03:56:28.587288 | instance | TASK [Write /etc/lvm/lvm.conf] *************************************************
2026-09-12 03:56:28.587299 | instance | Saturday 12 September 2026  03:56:28 +0000 (0:00:00.343)       0:00:28.875 ****
2026-09-12 03:56:29.389497 | instance | changed: [instance]
2026-09-12 03:56:29.389699 | instance |
2026-09-12 03:56:29.389846 | instance | TASK [Get list of all loopback devices] ****************************************
2026-09-12 03:56:29.389987 | instance | Saturday 12 September 2026  03:56:29 +0000 (0:00:00.803)       0:00:29.679 ****
2026-09-12 03:56:29.601242 | instance | ok: [instance]
2026-09-12 03:56:29.601316 | instance |
2026-09-12 03:56:29.601416 | instance | TASK [Fail if there is any existing loopback devices] **************************
2026-09-12 03:56:29.601609 | instance | Saturday 12 September 2026  03:56:29 +0000 (0:00:00.211)       0:00:29.891 ****
2026-09-12 03:56:29.629350 | instance | skipping: [instance]
2026-09-12 03:56:29.629609 | instance |
2026-09-12 03:56:29.629938 | instance | TASK [Create devices for Ceph] *************************************************
2026-09-12 03:56:29.630238 | instance | Saturday 12 September 2026  03:56:29 +0000 (0:00:00.027)       0:00:29.918 ****
2026-09-12 03:56:30.269159 | instance | changed: [instance] => (item=osd0)
2026-09-12 03:56:30.269710 | instance | changed: [instance] => (item=osd1)
2026-09-12 03:56:30.270013 | instance | changed: [instance] => (item=osd2)
2026-09-12 03:56:30.270294 | instance |
2026-09-12 03:56:30.270608 | instance | TASK [Set permissions on loopback devices] *************************************
2026-09-12 03:56:30.270902 | instance | Saturday 12 September 2026  03:56:30 +0000 (0:00:00.637)       0:00:30.556 ****
2026-09-12 03:56:30.980970 | instance | changed: [instance] => (item=osd0)
2026-09-12 03:56:30.981483 | instance | changed: [instance] => (item=osd1)
2026-09-12 03:56:30.981964 | instance | changed: [instance] => (item=osd2)
2026-09-12 03:56:30.982437 | instance |
2026-09-12 03:56:30.982937 | instance | TASK [Start loop devices] ******************************************************
2026-09-12 03:56:30.983287 | instance | Saturday 12 September 2026  03:56:30 +0000 (0:00:00.713)       0:00:31.269 ****
2026-09-12 03:56:31.748161 | instance | changed: [instance] => (item=osd0)
2026-09-12 03:56:31.748239 | instance | changed: [instance] => (item=osd1)
2026-09-12 03:56:31.748251 | instance | changed: [instance] => (item=osd2)
2026-09-12 03:56:31.748262 | instance |
2026-09-12 03:56:31.748272 | instance | TASK [Create a volume group for each loop device] ******************************
2026-09-12 03:56:31.748283 | instance | Saturday 12 September 2026  03:56:31 +0000 (0:00:00.765)       0:00:32.035 ****
2026-09-12 03:56:35.238479 | instance | changed: [instance] => (item=osd0)
2026-09-12 03:56:35.238558 | instance | changed: [instance] => (item=osd1)
2026-09-12 03:56:35.238568 | instance | changed: [instance] => (item=osd2)
2026-09-12 03:56:35.238578 | instance |
2026-09-12 03:56:35.238588 | instance | TASK [Create a logical volume for each loop device] ****************************
2026-09-12 03:56:35.238599 | instance | Saturday 12 September 2026  03:56:35 +0000 (0:00:03.490)       0:00:35.525 ****
2026-09-12 03:56:37.218550 | instance | changed: [instance] => (item=ceph-instance-osd0)
2026-09-12 03:56:37.218663 | instance | changed: [instance] => (item=ceph-instance-osd1)
2026-09-12 03:56:37.220796 | instance | changed: [instance] => (item=ceph-instance-osd2)
2026-09-12 03:56:37.220831 | instance |
2026-09-12 03:56:37.220843 | instance | PLAY RECAP *********************************************************************
2026-09-12 03:56:37.220854 | instance | instance                   : ok=15   changed=9    unreachable=0    failed=0    skipped=1    rescued=0    ignored=0
2026-09-12 03:56:37.220863 | instance |
2026-09-12 03:56:37.220872 | instance | Saturday 12 September 2026  03:56:37 +0000 (0:00:01.982)       0:00:37.508 ****
2026-09-12 03:56:37.220881 | instance | ===============================================================================
2026-09-12 03:56:37.224443 | instance | Install depedencies ---------------------------------------------------- 23.94s
2026-09-12 03:56:37.224522 | instance | Create a volume group for each loop device ------------------------------ 3.49s
2026-09-12 03:56:37.224533 | instance | Create a logical volume for each loop device ---------------------------- 1.98s
2026-09-12 03:56:37.224544 | instance | Gathering Facts --------------------------------------------------------- 1.31s
2026-09-12 03:56:37.224553 | instance | Purge "snapd" package --------------------------------------------------- 0.83s
2026-09-12 03:56:37.224562 | instance | Write /etc/lvm/lvm.conf ------------------------------------------------- 0.80s
2026-09-12 03:56:37.224570 | instance | Configure short hostname ------------------------------------------------ 0.77s
2026-09-12 03:56:37.224579 | instance | Gathering Facts --------------------------------------------------------- 0.77s
2026-09-12 03:56:37.224588 | instance | Start loop devices ------------------------------------------------------ 0.77s
2026-09-12 03:56:37.224597 | instance | Set permissions on loopback devices ------------------------------------- 0.71s
2026-09-12 03:56:37.224606 | instance | Create devices for Ceph ------------------------------------------------- 0.64s
2026-09-12 03:56:37.224615 | instance | Start up service -------------------------------------------------------- 0.59s
2026-09-12 03:56:37.224623 | instance | Generate lvm.conf ------------------------------------------------------- 0.34s
2026-09-12 03:56:37.224632 | instance | Ensure hostname inside hosts file --------------------------------------- 0.29s
2026-09-12 03:56:37.224661 | instance | Get list of all loopback devices ---------------------------------------- 0.21s
2026-09-12 03:56:37.224670 | instance | Fail if there is any existing loopback devices -------------------------- 0.03s
2026-09-12 03:56:37.315447 | instance | INFO     [csi > prepare] Executed: Successful
2026-09-12 03:56:37.332844 | instance | INFO     [csi > converge] Executing
2026-09-12 03:56:38.646232 | instance |
2026-09-12 03:56:38.646736 | instance | PLAY [Debug CSI driver value] **************************************************
2026-09-12 03:56:38.647096 | instance |
2026-09-12 03:56:38.647463 | instance | TASK [Gathering Facts] *********************************************************
2026-09-12 03:56:38.647862 | instance | Saturday 12 September 2026  03:56:38 +0000 (0:00:00.006)       0:00:00.006 ****
2026-09-12 03:56:39.650422 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-09-12 03:56:39.650492 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-09-12 03:56:39.650504 | instance | interpreter could change the meaning of that path. See
2026-09-12 03:56:39.650514 | instance | https://docs.ansible.com/ansible-
2026-09-12 03:56:39.650523 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-09-12 03:56:39.663500 | instance | ok: [instance]
2026-09-12 03:56:39.664305 | instance |
2026-09-12 03:56:39.664368 | instance | TASK [Display CSI driver value and environment variable] ***********************
2026-09-12 03:56:39.664381 | instance | Saturday 12 September 2026  03:56:39 +0000 (0:00:01.016)       0:00:01.023 ****
2026-09-12 03:56:39.731162 | instance | ok: [instance] => {
2026-09-12 03:56:39.731408 | instance |     "msg": "csi_driver=rbd, MOLECULE_CSI_DRIVER="
2026-09-12 03:56:39.731829 | instance | }
2026-09-12 03:56:39.732135 | instance |
2026-09-12 03:56:39.732439 | instance | PLAY [all] *********************************************************************
2026-09-12 03:56:39.732795 | instance |
2026-09-12 03:56:39.733159 | instance | TASK [Gathering Facts] *********************************************************
2026-09-12 03:56:39.733739 | instance | Saturday 12 September 2026  03:56:39 +0000 (0:00:00.067)       0:00:01.091 ****
2026-09-12 03:56:40.723698 | instance | ok: [instance]
2026-09-12 03:56:40.723793 | instance |
2026-09-12 03:56:40.723979 | instance | TASK [Set a fact with the "atmosphere_images" for other plays] *****************
2026-09-12 03:56:40.724212 | instance | Saturday 12 September 2026  03:56:40 +0000 (0:00:00.992)       0:00:02.083 ****
2026-09-12 03:56:40.937458 | instance | ok: [instance]
2026-09-12 03:56:40.937658 | instance |
2026-09-12 03:56:40.937823 | instance | PLAY [Deploy Ceph monitors & managers] *****************************************
2026-09-12 03:56:40.937952 | instance |
2026-09-12 03:56:40.938086 | instance | TASK [Gathering Facts] *********************************************************
2026-09-12 03:56:40.938242 | instance | Saturday 12 September 2026  03:56:40 +0000 (0:00:00.213)       0:00:02.297 ****
2026-09-12 03:56:41.869567 | instance | ok: [instance]
2026-09-12 03:56:41.869782 | instance |
2026-09-12 03:56:41.870133 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-12 03:56:41.870434 | instance | Saturday 12 September 2026  03:56:41 +0000 (0:00:00.931)       0:00:03.228 ****
2026-09-12 03:56:42.208973 | instance | ok: [instance]
2026-09-12 03:56:42.209467 | instance |
2026-09-12 03:56:42.209982 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-12 03:56:42.210523 | instance | Saturday 12 September 2026  03:56:42 +0000 (0:00:00.337)       0:00:03.566 ****
2026-09-12 03:56:42.265382 | instance | skipping: [instance]
2026-09-12 03:56:42.265429 | instance |
2026-09-12 03:56:42.265436 | instance | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] ***
2026-09-12 03:56:42.265443 | instance | Saturday 12 September 2026  03:56:42 +0000 (0:00:00.059)       0:00:03.625 ****
2026-09-12 03:56:42.606177 | instance | changed: [instance]
2026-09-12 03:56:42.606237 | instance |
2026-09-12 03:56:42.606249 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-12 03:56:42.606260 | instance | Saturday 12 September 2026  03:56:42 +0000 (0:00:00.340)       0:00:03.965 ****
2026-09-12 03:56:42.703892 | instance | ok: [instance] => {
2026-09-12 03:56:42.703933 | instance |     "msg": "https://github.com/opencontainers/runc/releases/download/v1.4.2/runc.amd64"
2026-09-12 03:56:42.703945 | instance | }
2026-09-12 03:56:42.703955 | instance |
2026-09-12 03:56:42.703965 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-12 03:56:42.703974 | instance | Saturday 12 September 2026  03:56:42 +0000 (0:00:00.097)       0:00:04.063 ****
2026-09-12 03:56:43.463819 | instance | changed: [instance]
2026-09-12 03:56:43.463896 | instance |
2026-09-12 03:56:43.463910 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-12 03:56:43.463924 | instance | Saturday 12 September 2026  03:56:43 +0000 (0:00:00.759)       0:00:04.822 ****
2026-09-12 03:56:43.528819 | instance | skipping: [instance]
2026-09-12 03:56:43.528894 | instance |
2026-09-12 03:56:43.529044 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-12 03:56:43.529208 | instance | Saturday 12 September 2026  03:56:43 +0000 (0:00:00.066)       0:00:04.889 ****
2026-09-12 03:56:43.599262 | instance | skipping: [instance]
2026-09-12 03:56:43.599471 | instance |
2026-09-12 03:56:43.599781 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-12 03:56:43.599988 | instance | Saturday 12 September 2026  03:56:43 +0000 (0:00:00.070)       0:00:04.959 ****
2026-09-12 03:56:43.871776 | instance | ok: [instance]
2026-09-12 03:56:43.871999 | instance |
2026-09-12 03:56:43.872254 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-12 03:56:43.872497 | instance | Saturday 12 September 2026  03:56:43 +0000 (0:00:00.272)       0:00:05.232 ****
2026-09-12 03:56:45.270578 | instance | ok: [instance]
2026-09-12 03:56:45.270998 | instance |
2026-09-12 03:56:45.271338 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-12 03:56:45.271670 | instance | Saturday 12 September 2026  03:56:45 +0000 (0:00:01.398)       0:00:06.630 ****
2026-09-12 03:56:45.362676 | instance | ok: [instance] => {
2026-09-12 03:56:45.362922 | instance |     "msg": "https://github.com/containerd/containerd/releases/download/v2.3.2/containerd-2.3.2-linux-amd64.tar.gz"
2026-09-12 03:56:45.363461 | instance | }
2026-09-12 03:56:45.363771 | instance |
2026-09-12 03:56:45.364073 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-12 03:56:45.364382 | instance | Saturday 12 September 2026  03:56:45 +0000 (0:00:00.092)       0:00:06.722 ****
2026-09-12 03:56:46.097386 | instance | changed: [instance]
2026-09-12 03:56:46.097592 | instance |
2026-09-12 03:56:46.097911 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-12 03:56:46.098070 | instance | Saturday 12 September 2026  03:56:46 +0000 (0:00:00.733)       0:00:07.456 ****
2026-09-12 03:56:50.049473 | instance | changed: [instance]
2026-09-12 03:56:50.049568 | instance |
2026-09-12 03:56:50.049821 | instance | TASK [vexxhost.containers.containerd : Install SELinux packages] ***************
2026-09-12 03:56:50.050093 | instance | Saturday 12 September 2026  03:56:50 +0000 (0:00:03.951)       0:00:11.407 ****
2026-09-12 03:56:50.097308 | instance | skipping: [instance]
2026-09-12 03:56:50.097730 | instance |
2026-09-12 03:56:50.098056 | instance | TASK [vexxhost.containers.containerd : Set SELinux to permissive at runtime] ***
2026-09-12 03:56:50.098362 | instance | Saturday 12 September 2026  03:56:50 +0000 (0:00:00.046)       0:00:11.453 ****
2026-09-12 03:56:50.137036 | instance | skipping: [instance]
2026-09-12 03:56:50.137716 | instance |
2026-09-12 03:56:50.138066 | instance | TASK [vexxhost.containers.containerd : Persist SELinux permissive mode] ********
2026-09-12 03:56:50.138392 | instance | Saturday 12 September 2026  03:56:50 +0000 (0:00:00.043)       0:00:11.497 ****
2026-09-12 03:56:50.180199 | instance | skipping: [instance]
2026-09-12 03:56:50.180520 | instance |
2026-09-12 03:56:50.180895 | instance | TASK [vexxhost.containers.containerd : Install AppArmor packages] **************
2026-09-12 03:56:50.181216 | instance | Saturday 12 September 2026  03:56:50 +0000 (0:00:00.042)       0:00:11.540 ****
2026-09-12 03:56:56.089669 | instance | changed: [instance]
2026-09-12 03:56:56.089772 | instance |
2026-09-12 03:56:56.089783 | instance | TASK [vexxhost.containers.containerd : Create systemd service file for containerd] ***
2026-09-12 03:56:56.089794 | instance | Saturday 12 September 2026  03:56:56 +0000 (0:00:05.908)       0:00:17.449 ****
2026-09-12 03:56:56.756056 | instance | changed: [instance]
2026-09-12 03:56:56.756120 | instance |
2026-09-12 03:56:56.756132 | instance | TASK [vexxhost.containers.containerd : Create folders for configuration] *******
2026-09-12 03:56:56.756142 | instance | Saturday 12 September 2026  03:56:56 +0000 (0:00:00.666)       0:00:18.115 ****
2026-09-12 03:56:57.770430 | instance | changed: [instance] => (item={'path': '/etc/containerd'})
2026-09-12 03:56:57.770492 | instance | changed: [instance] => (item={'path': '/var/lib/containerd', 'mode': '0o700'})
2026-09-12 03:56:57.770500 | instance | changed: [instance] => (item={'path': '/run/containerd', 'mode': '0o711'})
2026-09-12 03:56:57.770506 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.grpc.v1.cri', 'mode': '0o700'})
2026-09-12 03:56:57.770513 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.sandbox.controller.v1.shim', 'mode': '0o700'})
2026-09-12 03:56:57.770519 | instance |
2026-09-12 03:56:57.770547 | instance | TASK [vexxhost.containers.containerd : Create containerd config file] **********
2026-09-12 03:56:57.770887 | instance | Saturday 12 September 2026  03:56:57 +0000 (0:00:01.013)       0:00:19.129 ****
2026-09-12 03:56:58.397518 | instance | changed: [instance]
2026-09-12 03:56:58.397580 | instance |
2026-09-12 03:56:58.398122 | instance | TASK [vexxhost.containers.containerd : Force any restarts if necessary] ********
2026-09-12 03:56:58.398138 | instance | Saturday 12 September 2026  03:56:58 +0000 (0:00:00.610)       0:00:19.740 ****
2026-09-12 03:56:58.398143 | instance |
2026-09-12 03:56:58.398147 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] **************
2026-09-12 03:56:58.398151 | instance | Saturday 12 September 2026  03:56:58 +0000 (0:00:00.018)       0:00:19.758 ****
2026-09-12 03:56:59.370849 | instance | ok: [instance]
2026-09-12 03:56:59.370903 | instance |
2026-09-12 03:56:59.370914 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Restart containerd] **********
2026-09-12 03:56:59.370921 | instance | Saturday 12 September 2026  03:56:59 +0000 (0:00:00.971)       0:00:20.730 ****
2026-09-12 03:56:59.891582 | instance | changed: [instance]
2026-09-12 03:56:59.892473 | instance |
2026-09-12 03:56:59.892541 | instance | TASK [vexxhost.containers.containerd : Enable and start service] ***************
2026-09-12 03:56:59.892554 | instance | Saturday 12 September 2026  03:56:59 +0000 (0:00:00.521)       0:00:21.251 ****
2026-09-12 03:57:00.531262 | instance | changed: [instance]
2026-09-12 03:57:00.531351 | instance |
2026-09-12 03:57:00.531714 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-12 03:57:00.531967 | instance | Saturday 12 September 2026  03:57:00 +0000 (0:00:00.640)       0:00:21.891 ****
2026-09-12 03:57:00.762650 | instance | ok: [instance]
2026-09-12 03:57:00.762784 | instance |
2026-09-12 03:57:00.762796 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-12 03:57:00.762945 | instance | Saturday 12 September 2026  03:57:00 +0000 (0:00:00.231)       0:00:22.123 ****
2026-09-12 03:57:00.839703 | instance | ok: [instance] => {
2026-09-12 03:57:00.839787 | instance |     "msg": "https://download.docker.com/linux/static/stable/x86_64/docker-24.0.9.tgz"
2026-09-12 03:57:00.840226 | instance | }
2026-09-12 03:57:00.840284 | instance |
2026-09-12 03:57:00.840290 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-12 03:57:00.840296 | instance | Saturday 12 September 2026  03:57:00 +0000 (0:00:00.076)       0:00:22.200 ****
2026-09-12 03:57:04.649333 | instance | changed: [instance]
2026-09-12 03:57:04.650118 | instance |
2026-09-12 03:57:04.650192 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-12 03:57:04.650206 | instance | Saturday 12 September 2026  03:57:04 +0000 (0:00:03.808)       0:00:26.009 ****
2026-09-12 03:57:09.139754 | instance | changed: [instance]
2026-09-12 03:57:09.139833 | instance |
2026-09-12 03:57:09.140069 | instance | TASK [vexxhost.containers.docker : Install AppArmor packages] ******************
2026-09-12 03:57:09.140150 | instance | Saturday 12 September 2026  03:57:09 +0000 (0:00:04.491)       0:00:30.500 ****
2026-09-12 03:57:10.552521 | instance | ok: [instance]
2026-09-12 03:57:10.552853 | instance |
2026-09-12 03:57:10.553137 | instance | TASK [vexxhost.containers.docker : Ensure group "docker" exists] ***************
2026-09-12 03:57:10.553397 | instance | Saturday 12 September 2026  03:57:10 +0000 (0:00:01.411)       0:00:31.912 ****
2026-09-12 03:57:10.940095 | instance | changed: [instance]
2026-09-12 03:57:10.940382 | instance |
2026-09-12 03:57:10.940828 | instance | TASK [vexxhost.containers.docker : Create systemd service file for docker] *****
2026-09-12 03:57:10.941118 | instance | Saturday 12 September 2026  03:57:10 +0000 (0:00:00.388)       0:00:32.300 ****
2026-09-12 03:57:11.441019 | instance | changed: [instance]
2026-09-12 03:57:11.441306 | instance |
2026-09-12 03:57:11.441665 | instance | TASK [vexxhost.containers.docker : Create folders for configuration] ***********
2026-09-12 03:57:11.442018 | instance | Saturday 12 September 2026  03:57:11 +0000 (0:00:00.500)       0:00:32.801 ****
2026-09-12 03:57:12.090883 | instance | changed: [instance] => (item={'path': '/etc/docker'})
2026-09-12 03:57:12.091087 | instance | changed: [instance] => (item={'path': '/var/lib/docker', 'mode': '0o710'})
2026-09-12 03:57:12.091324 | instance | changed: [instance] => (item={'path': '/run/docker', 'mode': '0o711'})
2026-09-12 03:57:12.091551 | instance |
2026-09-12 03:57:12.091862 | instance | TASK [vexxhost.containers.docker : Create systemd socket file for docker] ******
2026-09-12 03:57:12.092108 | instance | Saturday 12 September 2026  03:57:12 +0000 (0:00:00.649)       0:00:33.450 ****
2026-09-12 03:57:12.589153 | instance | changed: [instance]
2026-09-12 03:57:12.589360 | instance |
2026-09-12 03:57:12.589629 | instance | TASK [vexxhost.containers.docker : Create docker daemon config file] ***********
2026-09-12 03:57:12.589892 | instance | Saturday 12 September 2026  03:57:12 +0000 (0:00:00.498)       0:00:33.949 ****
2026-09-12 03:57:13.145155 | instance | changed: [instance]
2026-09-12 03:57:13.145526 | instance |
2026-09-12 03:57:13.145724 | instance | TASK [vexxhost.containers.docker : Force any restarts if necessary] ************
2026-09-12 03:57:13.145959 | instance | Saturday 12 September 2026  03:57:13 +0000 (0:00:00.520)       0:00:34.469 ****
2026-09-12 03:57:13.146169 | instance |
2026-09-12 03:57:13.146401 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] **************
2026-09-12 03:57:13.146652 | instance | Saturday 12 September 2026  03:57:13 +0000 (0:00:00.035)       0:00:34.505 ****
2026-09-12 03:57:13.903779 | instance | ok: [instance]
2026-09-12 03:57:13.903978 | instance |
2026-09-12 03:57:13.904243 | instance | RUNNING HANDLER [vexxhost.containers.docker : Restart docker] ******************
2026-09-12 03:57:13.904488 | instance | Saturday 12 September 2026  03:57:13 +0000 (0:00:00.758)       0:00:35.263 ****
2026-09-12 03:57:14.794753 | instance | changed: [instance]
2026-09-12 03:57:14.794941 | instance |
2026-09-12 03:57:14.795185 | instance | TASK [vexxhost.containers.docker : Enable and start service] *******************
2026-09-12 03:57:14.795409 | instance | Saturday 12 September 2026  03:57:14 +0000 (0:00:00.890)       0:00:36.154 ****
2026-09-12 03:57:15.436255 | instance | changed: [instance]
2026-09-12 03:57:15.436482 | instance |
2026-09-12 03:57:15.436879 | instance | TASK [vexxhost.ceph.cephadm : Gather variables for each operating system] ******
2026-09-12 03:57:15.437202 | instance | Saturday 12 September 2026  03:57:15 +0000 (0:00:00.641)       0:00:36.796 ****
2026-09-12 03:57:15.509995 | instance | ok: [instance] => (item=/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/cephadm/vars/ubuntu-22.04.yml)
2026-09-12 03:57:15.510191 | instance |
2026-09-12 03:57:15.510434 | instance | TASK [vexxhost.ceph.cephadm : Install packages] ********************************
2026-09-12 03:57:15.510683 | instance | Saturday 12 September 2026  03:57:15 +0000 (0:00:00.074)       0:00:36.870 ****
2026-09-12 03:57:21.206096 | instance | changed: [instance]
2026-09-12 03:57:21.206264 | instance |
2026-09-12 03:57:21.206573 | instance | TASK [vexxhost.ceph.cephadm : Ensure services are started] *********************
2026-09-12 03:57:21.206812 | instance | Saturday 12 September 2026  03:57:21 +0000 (0:00:05.695)       0:00:42.566 ****
2026-09-12 03:57:21.895006 | instance | ok: [instance] => (item=chronyd)
2026-09-12 03:57:21.895309 | instance | ok: [instance] => (item=sshd)
2026-09-12 03:57:21.895631 | instance |
2026-09-12 03:57:21.895945 | instance | TASK [vexxhost.ceph.cephadm : Download "cephadm"] ******************************
2026-09-12 03:57:21.896245 | instance | Saturday 12 September 2026  03:57:21 +0000 (0:00:00.688)       0:00:43.255 ****
2026-09-12 03:57:40.660461 | instance | changed: [instance]
2026-09-12 03:57:40.660548 | instance |
2026-09-12 03:57:40.660563 | instance | TASK [vexxhost.ceph.cephadm : Remove cephadm from old path] ********************
2026-09-12 03:57:40.660578 | instance | Saturday 12 September 2026  03:57:40 +0000 (0:00:18.764)       0:01:02.019 ****
2026-09-12 03:57:40.905858 | instance | ok: [instance]
2026-09-12 03:57:40.905925 | instance |
2026-09-12 03:57:40.905937 | instance | TASK [vexxhost.ceph.cephadm : Ensure "cephadm" user is present] ****************
2026-09-12 03:57:40.905947 | instance | Saturday 12 September 2026  03:57:40 +0000 (0:00:00.245)       0:01:02.265 ****
2026-09-12 03:57:41.391110 | instance | changed: [instance]
2026-09-12 03:57:41.391652 | instance |
2026-09-12 03:57:41.391696 | instance | TASK [vexxhost.ceph.cephadm : Allow "cephadm" user to have passwordless sudo] ***
2026-09-12 03:57:41.391936 | instance | Saturday 12 September 2026  03:57:41 +0000 (0:00:00.486)       0:01:02.751 ****
2026-09-12 03:57:41.738008 | instance | changed: [instance]
2026-09-12 03:57:41.738119 | instance |
2026-09-12 03:57:41.738478 | instance | TASK [vexxhost.ceph.mon : Set Ceph Monitor IP address] *************************
2026-09-12 03:57:41.738499 | instance | Saturday 12 September 2026  03:57:41 +0000 (0:00:00.347)       0:01:03.098 ****
2026-09-12 03:57:41.855007 | instance | ok: [instance]
2026-09-12 03:57:41.855795 | instance |
2026-09-12 03:57:41.855827 | instance | TASK [vexxhost.ceph.mon : Check if any node is bootstrapped] *******************
2026-09-12 03:57:41.855839 | instance | Saturday 12 September 2026  03:57:41 +0000 (0:00:00.116)       0:01:03.215 ****
2026-09-12 03:57:42.118362 | instance | ok: [instance] => (item=instance)
2026-09-12 03:57:42.118748 | instance |
2026-09-12 03:57:42.119002 | instance | TASK [vexxhost.ceph.mon : Select pre-existing bootstrap node if exists] ********
2026-09-12 03:57:42.119016 | instance | Saturday 12 September 2026  03:57:42 +0000 (0:00:00.263)       0:01:03.478 ****
2026-09-12 03:57:42.194071 | instance | ok: [instance]
2026-09-12 03:57:42.194137 | instance |
2026-09-12 03:57:42.194149 | instance | TASK [vexxhost.ceph.mon : Bootstrap cluster] ***********************************
2026-09-12 03:57:42.194159 | instance | Saturday 12 September 2026  03:57:42 +0000 (0:00:00.074)       0:01:03.553 ****
2026-09-12 03:57:42.279191 | instance | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/mon/tasks/bootstrap-ceph.yml for instance
2026-09-12 03:57:42.279275 | instance |
2026-09-12 03:57:42.279549 | instance | TASK [vexxhost.ceph.mon : Generate temporary file for "ceph.conf"] *************
2026-09-12 03:57:42.279613 | instance | Saturday 12 September 2026  03:57:42 +0000 (0:00:00.086)       0:01:03.639 ****
2026-09-12 03:57:42.619541 | instance | changed: [instance]
2026-09-12 03:57:42.619643 | instance |
2026-09-12 03:57:42.619794 | instance | TASK [vexxhost.ceph.mon : Include extra configuration values] ******************
2026-09-12 03:57:42.619953 | instance | Saturday 12 September 2026  03:57:42 +0000 (0:00:00.340)       0:01:03.980 ****
2026-09-12 03:57:43.402929 | instance | changed: [instance] => (item={'option': 'mon allow pool size one', 'section': 'global', 'value': True})
2026-09-12 03:57:43.403187 | instance | changed: [instance] => (item={'option': 'osd crush chooseleaf type', 'section': 'global', 'value': 0})
2026-09-12 03:57:43.403374 | instance | changed: [instance] => (item={'option': 'auth allow insecure global id reclaim', 'section': 'mon', 'value': False})
2026-09-12 03:57:43.403586 | instance |
2026-09-12 03:57:43.403817 | instance | TASK [vexxhost.ceph.mon : Run Bootstrap command] *******************************
2026-09-12 03:57:43.404052 | instance | Saturday 12 September 2026  03:57:43 +0000 (0:00:00.783)       0:01:04.763 ****
2026-09-12 03:59:20.056885 | instance | ok: [instance]
2026-09-12 03:59:20.057005 | instance |
2026-09-12 03:59:20.057020 | instance | TASK [vexxhost.ceph.mon : Remove temporary file for "ceph.conf"] ***************
2026-09-12 03:59:20.057031 | instance | Saturday 12 September 2026  03:59:20 +0000 (0:01:36.652)       0:02:41.416 ****
2026-09-12 03:59:20.304274 | instance | changed: [instance]
2026-09-12 03:59:20.304468 | instance |
2026-09-12 03:59:20.304800 | instance | TASK [vexxhost.ceph.mon : Set bootstrap node] **********************************
2026-09-12 03:59:20.305065 | instance | Saturday 12 September 2026  03:59:20 +0000 (0:00:00.248)       0:02:41.664 ****
2026-09-12 03:59:20.341177 | instance | ok: [instance]
2026-09-12 03:59:20.341224 | instance |
2026-09-12 03:59:20.341494 | instance | TASK [Install Ceph host] *******************************************************
2026-09-12 03:59:20.341569 | instance | Saturday 12 September 2026  03:59:20 +0000 (0:00:00.037)       0:02:41.702 ****
2026-09-12 03:59:20.408071 | instance | included: vexxhost.ceph.cephadm_host for instance
2026-09-12 03:59:20.408447 | instance |
2026-09-12 03:59:20.408493 | instance | TASK [vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user] ******
2026-09-12 03:59:20.408499 | instance | Saturday 12 September 2026  03:59:20 +0000 (0:00:00.066)       0:02:41.769 ****
2026-09-12 03:59:22.106863 | instance | ok: [instance]
2026-09-12 03:59:22.106927 | instance |
2026-09-12 03:59:22.106939 | instance | TASK [vexxhost.ceph.cephadm_host : Set fact with public SSH key for "cephadm" user] ***
2026-09-12 03:59:22.106949 | instance | Saturday 12 September 2026  03:59:22 +0000 (0:00:01.697)       0:02:43.466 ****
2026-09-12 03:59:22.158065 | instance | ok: [instance] => (item=instance)
2026-09-12 03:59:22.158114 | instance |
2026-09-12 03:59:22.158121 | instance | TASK [vexxhost.ceph.cephadm_host : Set authorized key for "cephadm"] ***********
2026-09-12 03:59:22.158127 | instance | Saturday 12 September 2026  03:59:22 +0000 (0:00:00.051)       0:02:43.518 ****
2026-09-12 03:59:22.536970 | instance | ok: [instance]
2026-09-12 03:59:22.537119 | instance |
2026-09-12 03:59:22.537317 | instance | TASK [vexxhost.ceph.cephadm_host : Add new host to Ceph] ***********************
2026-09-12 03:59:22.537495 | instance | Saturday 12 September 2026  03:59:22 +0000 (0:00:00.379)       0:02:43.897 ****
2026-09-12 03:59:25.827803 | instance | ok: [instance]
2026-09-12 03:59:25.827989 | instance |
2026-09-12 03:59:25.828242 | instance | TASK [vexxhost.ceph.mon : Configure "mon" label for monitors] ******************
2026-09-12 03:59:25.828471 | instance | Saturday 12 September 2026  03:59:25 +0000 (0:00:03.290)       0:02:47.188 ****
2026-09-12 04:00:18.657314 | instance | An exception occurred during task execution. To see the full traceback, use -vvv. The error was: [errno 5] RADOS I/O error (error connecting to the cluster)
2026-09-12 04:00:18.657743 | instance | fatal: [instance]: FAILED! => {"changed": false, "module_stderr": "Traceback (most recent call last):\n  File \"<stdin>\", line 107, in <module>\n  File \"<stdin>\", line 99, in _ansiballz_main\n  File \"<stdin>\", line 47, in invoke_module\n  File \"/usr/lib/python3.10/runpy.py\", line 224, in run_module\n    return _run_module_code(code, init_globals, run_name, mod_spec)\n  File \"/usr/lib/python3.10/runpy.py\", line 96, in _run_module_code\n    _run_code(code, mod_globals, init_globals,\n  File \"/usr/lib/python3.10/runpy.py\", line 86, in _run_code\n    exec(code, run_globals)\n  File \"/tmp/ansible_vexxhost.ceph.orch_apply_payload_6tmp80ns/ansible_vexxhost.ceph.orch_apply_payload.zip/ansible_collections/vexxhost/ceph/plugins/modules/orch_apply.py\", line 202, in <module>\n  File \"/tmp/ansible_vexxhost.ceph.orch_apply_payload_6tmp80ns/ansible_vexxhost.ceph.orch_apply_payload.zip/ansible_collections/vexxhost/ceph/plugins/modules/orch_apply.py\", line 198, in main\n  File \"/tmp/ansible_vexxhost.ceph.orch_apply_payload_6tmp80ns/ansible_vexxhost.ceph.orch_apply_payload.zip/ansible_collections/vexxhost/ceph/plugins/modules/orch_apply.py\", line 177, in run_module\n  File \"/tmp/ansible_vexxhost.ceph.orch_apply_payload_6tmp80ns/ansible_vexxhost.ceph.orch_apply_payload.zip/ansible_collections/vexxhost/ceph/plugins/modules/orch_apply.py\", line 120, in apply_spec\nRuntimeError: Inferring config /var/lib/ceph/4837cbf8-4f90-4300-b3f6-726c9b9f89b4/mon.instance/config\n2026-09-12T04:00:18.473+0000 7fee17fff640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgDuzaRqJao7NCAAePIyP5lArhq3hyA/+3hkReNpJCaFBO7D6zF6/d5SzWk=: Malformed input [buffer:3]\n2026-09-12T04:00:18.473+0000 7fee17fff640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-12T04:00:18.477+0000 7fee17fff640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgDuzaRqJao7NCAAePIyP5lArhq3hyA/+3hkReNpJCaFBO7D6zF6/d5SzWk=: Malformed input [buffer:3]\n2026-09-12T04:00:18.477+0000 7fee17fff640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-12T04:00:18.477+0000 7fee17fff640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgDuzaRqJao7NCAAePIyP5lArhq3hyA/+3hkReNpJCaFBO7D6zF6/d5SzWk=: Malformed input [buffer:3]\n2026-09-12T04:00:18.477+0000 7fee17fff640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-12T04:00:18.477+0000 7fee17fff640 -1 monclient: keyring not found\n[errno 5] RADOS I/O error (error connecting to the cluster)\n\n", "module_stdout": "", "msg": "MODULE FAILURE\nSee stdout/stderr for the exact error", "rc": 1}
2026-09-12 04:00:18.658069 | instance |
2026-09-12 04:00:18.658309 | instance | PLAY RECAP *********************************************************************
2026-09-12 04:00:18.658564 | instance | instance                   : ok=55   changed=26   unreachable=0    failed=1    skipped=6    rescued=0    ignored=0
2026-09-12 04:00:18.658814 | instance |
2026-09-12 04:00:18.659045 | instance | Saturday 12 September 2026  04:00:18 +0000 (0:00:52.830)       0:03:40.018 ****
2026-09-12 04:00:18.659264 | instance | ===============================================================================
2026-09-12 04:00:18.659489 | instance | vexxhost.ceph.mon : Run Bootstrap command ------------------------------ 96.65s
2026-09-12 04:00:18.659737 | instance | vexxhost.ceph.mon : Configure "mon" label for monitors ----------------- 52.83s
2026-09-12 04:00:18.659994 | instance | vexxhost.ceph.cephadm : Download "cephadm" ----------------------------- 18.76s
2026-09-12 04:00:18.660221 | instance | vexxhost.containers.containerd : Install AppArmor packages -------------- 5.91s
2026-09-12 04:00:18.660447 | instance | vexxhost.ceph.cephadm : Install packages -------------------------------- 5.70s
2026-09-12 04:00:18.660770 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 4.49s
2026-09-12 04:00:18.661069 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 3.95s
2026-09-12 04:00:18.661365 | instance | vexxhost.containers.download_artifact : Download item ------------------- 3.81s
2026-09-12 04:00:18.661618 | instance | vexxhost.ceph.cephadm_host : Add new host to Ceph ----------------------- 3.29s
2026-09-12 04:00:18.661925 | instance | vexxhost.containers.containerd : Reload systemd ------------------------- 1.73s
2026-09-12 04:00:18.662151 | instance | vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user ------ 1.70s
2026-09-12 04:00:18.662373 | instance | vexxhost.containers.docker : Install AppArmor packages ------------------ 1.41s
2026-09-12 04:00:18.662617 | instance | vexxhost.containers.package : Update state for tar ---------------------- 1.40s
2026-09-12 04:00:18.662938 | instance | Gathering Facts --------------------------------------------------------- 1.02s
2026-09-12 04:00:18.663255 | instance | vexxhost.containers.containerd : Create folders for configuration ------- 1.01s
2026-09-12 04:00:18.663552 | instance | Gathering Facts --------------------------------------------------------- 0.99s
2026-09-12 04:00:18.664004 | instance | Gathering Facts --------------------------------------------------------- 0.93s
2026-09-12 04:00:18.664320 | instance | vexxhost.containers.docker : Restart docker ----------------------------- 0.89s
2026-09-12 04:00:18.664664 | instance | vexxhost.ceph.mon : Include extra configuration values ------------------ 0.78s
2026-09-12 04:00:18.665134 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.76s
2026-09-12 04:00:18.739717 | instance | CRITICAL Ansible return code was 2, command was: ansible-playbook --inventory /home/zuul/.ansible/tmp/molecule.v9Wo.csi/inventory --skip-tags molecule-notest,notest --inventory=/home/zuul/src/github.com/vexxhost/atmosphere/inventory.yaml /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml
2026-09-12 04:00:18.739863 | instance | ERROR    [csi > converge] Executed: Failed
2026-09-12 04:00:18.739989 | instance | ERROR    Ansible return code was 2, command was: ansible-playbook --inventory /home/zuul/.ansible/tmp/molecule.v9Wo.csi/inventory --skip-tags molecule-notest,notest --inventory=/home/zuul/src/github.com/vexxhost/atmosphere/inventory.yaml /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml
2026-09-12 04:00:19.250072 | instance | ERROR
2026-09-12 04:00:19.250384 | instance | {
2026-09-12 04:00:19.250441 | instance |   "delta": "0:04:33.582892",
2026-09-12 04:00:19.250480 | instance |   "end": "2026-09-12 04:00:18.813500",
2026-09-12 04:00:19.250516 | instance |   "msg": "non-zero return code",
2026-09-12 04:00:19.251242 | instance |   "rc": 2,
2026-09-12 04:00:19.251301 | instance |   "start": "2026-09-12 03:55:45.230608"
2026-09-12 04:00:19.251339 | instance | }
failure
2026-09-12 04:00:19.262381 | 
2026-09-12 04:00:19.262484 | PLAY RECAP
2026-09-12 04:00:19.262564 | instance | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-09-12 04:00:19.262607 | 
2026-09-12 04:00:19.413546 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-12 04:00:19.422454 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-09-12 04:00:20.284163 | 
2026-09-12 04:00:20.284349 | PLAY [all]
2026-09-12 04:00:20.352518 | 
2026-09-12 04:00:20.352744 | TASK [gather-host-logs : creating directory for system status]
2026-09-12 04:00:20.755188 | instance | changed
2026-09-12 04:00:20.766229 | 
2026-09-12 04:00:20.766415 | TASK [gather-host-logs : Get logs for each host]
2026-09-12 04:00:21.198310 | instance | + systemd-cgls --full --all --no-pager
2026-09-12 04:00:21.212859 | instance | + ip addr
2026-09-12 04:00:21.216113 | instance | + ip route
2026-09-12 04:00:21.219209 | instance | + lsblk
2026-09-12 04:00:21.225117 | instance | + mount
2026-09-12 04:00:21.228099 | instance | + docker images
2026-09-12 04:00:21.253752 | instance | + brctl show
2026-09-12 04:00:21.254432 | instance | /bin/bash: line 8: brctl: command not found
2026-09-12 04:00:21.254738 | instance | + ip neigh
2026-09-12 04:00:21.257453 | instance | + bridge fdb show
2026-09-12 04:00:21.260895 | instance | + ps aux --sort=-%mem
2026-09-12 04:00:21.281097 | instance | + dpkg -l
2026-09-12 04:00:21.292547 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul))
2026-09-12 04:00:21.293075 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul
2026-09-12 04:00:21.316259 | instance | + '[' '!' -z '' ']'
2026-09-12 04:00:21.812037 | instance | ok: Runtime: 0:00:00.122953
2026-09-12 04:00:21.824175 | 
2026-09-12 04:00:21.824288 | TASK [gather-host-logs : Downloads logs to executor]
2026-09-12 04:00:22.557782 | instance | changed:
2026-09-12 04:00:22.558145 | instance | created directory /var/lib/zuul/builds/9ea711e316f347ad95c86c894d916ec6/work/logs/instance
2026-09-12 04:00:22.558186 | instance | cd+++++++++ system/
2026-09-12 04:00:22.558209 | instance | >f+++++++++ system/brctl-show.txt
2026-09-12 04:00:22.558227 | instance | >f+++++++++ system/bridge-fdb.txt
2026-09-12 04:00:22.558257 | instance | >f+++++++++ system/docker-images.txt
2026-09-12 04:00:22.558277 | instance | >f+++++++++ system/ip-addr.txt
2026-09-12 04:00:22.558296 | instance | >f+++++++++ system/ip-neigh.txt
2026-09-12 04:00:22.558314 | instance | >f+++++++++ system/ip-route.txt
2026-09-12 04:00:22.558335 | instance | >f+++++++++ system/lsblk.txt
2026-09-12 04:00:22.558364 | instance | >f+++++++++ system/mount.txt
2026-09-12 04:00:22.558382 | instance | >f+++++++++ system/packages.txt
2026-09-12 04:00:22.558401 | instance | >f+++++++++ system/ps.txt
2026-09-12 04:00:22.558418 | instance | >f+++++++++ system/systemd-cgls.txt
2026-09-12 04:00:22.567671 | 
2026-09-12 04:00:22.567736 | LOOP [helm-release-status : creating directory for helm release status]
2026-09-12 04:00:22.827085 | instance | changed: "values"
2026-09-12 04:00:23.002217 | instance | changed: "releases"
2026-09-12 04:00:23.033695 | 
2026-09-12 04:00:23.033918 | TASK [helm-release-status : Gather get release status for helm charts]
2026-09-12 04:00:23.310478 | instance | /bin/bash: line 3: kubectl: command not found
2026-09-12 04:00:23.579688 | instance | ok: Runtime: 0:00:00.004536
2026-09-12 04:00:23.590151 | 
2026-09-12 04:00:23.590284 | TASK [helm-release-status : Downloads logs to executor]
2026-09-12 04:00:24.189992 | instance | changed:
2026-09-12 04:00:24.190181 | instance | cd+++++++++ helm/
2026-09-12 04:00:24.190217 | instance | cd+++++++++ helm/releases/
2026-09-12 04:00:24.190244 | instance | cd+++++++++ helm/values/
2026-09-12 04:00:24.203399 | 
2026-09-12 04:00:24.203570 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-09-12 04:00:24.430779 | instance | changed
2026-09-12 04:00:24.444340 | 
2026-09-12 04:00:24.444532 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-09-12 04:00:24.765807 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-12 04:00:24.766240 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-12 04:00:24.771235 | instance | environment: line 1: kubectl: command not found
2026-09-12 04:00:24.772871 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-12 04:00:24.773834 | instance | environment: line 1: kubectl: command not found
2026-09-12 04:00:24.774862 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-12 04:00:24.776295 | instance | environment: line 1: kubectl: command not found
2026-09-12 04:00:24.777972 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-12 04:00:24.779061 | instance | environment: line 1: kubectl: command not found
2026-09-12 04:00:24.780779 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-12 04:00:24.782681 | instance | environment: line 1: kubectl: command not found
2026-09-12 04:00:24.783479 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-12 04:00:24.993009 | instance | ok: Runtime: 0:00:00.025100
2026-09-12 04:00:25.003166 | 
2026-09-12 04:00:25.003289 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-09-12 04:00:25.228974 | instance | changed
2026-09-12 04:00:25.235716 | 
2026-09-12 04:00:25.235821 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-09-12 04:00:25.480542 | instance | environment: line 5: kubectl: command not found
2026-09-12 04:00:25.480863 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-12 04:00:25.481902 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-12 04:00:25.482109 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-12 04:00:25.769589 | instance | ok: Runtime: 0:00:00.009720
2026-09-12 04:00:25.781125 | 
2026-09-12 04:00:25.781269 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-09-12 04:00:26.323090 | instance | changed:
2026-09-12 04:00:26.323272 | instance | cd+++++++++ objects/
2026-09-12 04:00:26.323307 | instance | cd+++++++++ objects/cluster/
2026-09-12 04:00:26.323334 | instance | cd+++++++++ objects/namespaced/
2026-09-12 04:00:26.337698 | 
2026-09-12 04:00:26.337787 | TASK [gather-pod-logs : creating directory for pod logs]
2026-09-12 04:00:26.621044 | instance | changed
2026-09-12 04:00:26.625451 | 
2026-09-12 04:00:26.625512 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-09-12 04:00:26.846926 | instance | changed
2026-09-12 04:00:26.851604 | 
2026-09-12 04:00:26.851698 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-09-12 04:00:27.112774 | instance | environment: line 3: kubectl: command not found
2026-09-12 04:00:27.381912 | instance | ok: Runtime: 0:00:00.008643
2026-09-12 04:00:27.389221 | 
2026-09-12 04:00:27.389317 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-09-12 04:00:27.928469 | instance | changed:
2026-09-12 04:00:27.928700 | instance | cd+++++++++ pod-logs/
2026-09-12 04:00:27.928742 | instance | cd+++++++++ pod-logs/failed-pods/
2026-09-12 04:00:27.951929 | 
2026-09-12 04:00:27.952108 | TASK [gather-prom-metrics : creating directory for helm release descriptions]
2026-09-12 04:00:28.167783 | instance | changed
2026-09-12 04:00:28.177732 | 
2026-09-12 04:00:28.177880 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces]
2026-09-12 04:00:28.422900 | instance | /bin/bash: line 2: kubectl: command not found
2026-09-12 04:00:28.732987 | instance | ok: Runtime: 0:00:00.049684
2026-09-12 04:00:28.742656 | 
2026-09-12 04:00:28.742791 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr]
2026-09-12 04:00:29.004494 | instance | /bin/bash: line 2: kubectl: command not found
2026-09-12 04:00:29.048814 | instance | ceph-mgr endpoints:
2026-09-12 04:00:29.292733 | instance | ok: Runtime: 0:00:00.050023
2026-09-12 04:00:29.302649 | 
2026-09-12 04:00:29.302784 | TASK [gather-prom-metrics : Get metrics from fluentd pods]
2026-09-12 04:00:29.542220 | instance | /bin/bash: line 4: kubectl: command not found
2026-09-12 04:00:29.849270 | instance | ok: Runtime: 0:00:00.050628
2026-09-12 04:00:29.858492 | 
2026-09-12 04:00:29.858993 | TASK [gather-prom-metrics : Downloads logs to executor]
2026-09-12 04:00:30.373854 | instance | changed: cd+++++++++ prometheus/
2026-09-12 04:00:30.382096 | 
2026-09-12 04:00:30.382213 | TASK [gather-selenium-data : creating directory for helm release descriptions]
2026-09-12 04:00:30.616970 | instance | changed
2026-09-12 04:00:30.628549 | 
2026-09-12 04:00:30.628691 | TASK [gather-selenium-data : Get selenium data]
2026-09-12 04:00:30.917855 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/.
2026-09-12 04:00:30.919283 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory
2026-09-12 04:00:31.186987 | instance | ERROR
2026-09-12 04:00:31.187376 | instance | {
2026-09-12 04:00:31.187479 | instance |   "delta": "0:00:00.005420",
2026-09-12 04:00:31.187551 | instance |   "end": "2026-09-12 04:00:30.919619",
2026-09-12 04:00:31.187616 | instance |   "msg": "non-zero return code",
2026-09-12 04:00:31.187681 | instance |   "rc": 1,
2026-09-12 04:00:31.187743 | instance |   "start": "2026-09-12 04:00:30.914199"
2026-09-12 04:00:31.187808 | instance | }
2026-09-12 04:00:31.187917 | instance | ERROR: Ignoring Errors
2026-09-12 04:00:31.199081 | 
2026-09-12 04:00:31.199219 | TASK [gather-selenium-data : Downloads logs to executor]
2026-09-12 04:00:31.697172 | instance | changed: cd+++++++++ selenium/
2026-09-12 04:00:31.708710 | 
2026-09-12 04:00:31.708859 | PLAY RECAP
2026-09-12 04:00:31.708945 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1
2026-09-12 04:00:31.708987 | 
2026-09-12 04:00:31.844437 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-09-12 04:00:31.847047 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-12 04:00:32.449257 | 
2026-09-12 04:00:32.449400 | PLAY [all]
2026-09-12 04:00:32.460407 | 
2026-09-12 04:00:32.460483 | TASK [fetch-output : Set log path for multiple nodes]
2026-09-12 04:00:32.524660 | instance | skipping: Conditional result was False
2026-09-12 04:00:32.531162 | 
2026-09-12 04:00:32.531262 | TASK [fetch-output : Set log path for single node]
2026-09-12 04:00:32.573304 | instance | ok
2026-09-12 04:00:32.581007 | 
2026-09-12 04:00:32.581093 | LOOP [fetch-output : Ensure local output dirs]
2026-09-12 04:00:33.001614 | instance -> localhost | ok: "/var/lib/zuul/builds/9ea711e316f347ad95c86c894d916ec6/work/logs"
2026-09-12 04:00:33.264022 | instance -> localhost | changed: "/var/lib/zuul/builds/9ea711e316f347ad95c86c894d916ec6/work/artifacts"
2026-09-12 04:00:33.509540 | instance -> localhost | changed: "/var/lib/zuul/builds/9ea711e316f347ad95c86c894d916ec6/work/docs"
2026-09-12 04:00:33.524600 | 
2026-09-12 04:00:33.524734 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-09-12 04:00:34.196212 | instance | changed: .d..t...... ./
2026-09-12 04:00:34.196540 | instance | changed: All items complete
2026-09-12 04:00:34.196587 | 
2026-09-12 04:00:34.716857 | instance | changed: .d..t...... ./
2026-09-12 04:00:35.265810 | instance | changed: .d..t...... ./
2026-09-12 04:00:35.298461 | 
2026-09-12 04:00:35.298677 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-09-12 04:00:35.846288 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.048750
2026-09-12 04:00:36.122282 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.006192
2026-09-12 04:00:36.143311 | 
2026-09-12 04:00:36.143445 | PLAY [all]
2026-09-12 04:00:36.156109 | 
2026-09-12 04:00:36.156237 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-09-12 04:00:36.602597 | instance | changed
2026-09-12 04:00:36.613078 | 
2026-09-12 04:00:36.613176 | PLAY RECAP
2026-09-12 04:00:36.613255 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-09-12 04:00:36.613297 | 
2026-09-12 04:00:36.800547 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-12 04:00:36.804373 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-09-12 04:00:37.378291 | 
2026-09-12 04:00:37.378422 | PLAY [localhost]
2026-09-12 04:00:37.387640 | 
2026-09-12 04:00:37.387730 | TASK [Generate Zuul manifest]
2026-09-12 04:00:37.403900 | localhost | ok
2026-09-12 04:00:37.419966 | 
2026-09-12 04:00:37.420075 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-09-12 04:00:37.795100 | localhost | changed
2026-09-12 04:00:37.862219 | 
2026-09-12 04:00:37.862506 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-09-12 04:00:37.907799 | localhost | ok
2026-09-12 04:00:37.923294 | 
2026-09-12 04:00:37.923493 | TASK [Upload logs]
2026-09-12 04:00:37.945191 | localhost | ok
2026-09-12 04:00:38.027579 | 
2026-09-12 04:00:38.027708 | TASK [Set zuul-log-path fact]
2026-09-12 04:00:38.043839 | localhost | ok
2026-09-12 04:00:38.063409 | 
2026-09-12 04:00:38.063499 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-12 04:00:38.092342 | localhost | ok
2026-09-12 04:00:38.102265 | 
2026-09-12 04:00:38.102454 | TASK [upload-logs : Create log directories]
2026-09-12 04:00:38.546804 | localhost | changed
2026-09-12 04:00:38.552697 | 
2026-09-12 04:00:38.552780 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-09-12 04:00:38.984190 | localhost -> localhost | ok: Runtime: 0:00:00.004346
2026-09-12 04:00:38.988819 | 
2026-09-12 04:00:38.988895 | TASK [upload-logs : Upload logs to log server]
2026-09-12 04:00:39.732359 | localhost | Output suppressed because no_log was given
2026-09-12 04:00:39.785340 | 
2026-09-12 04:00:39.785503 | LOOP [upload-logs : Compress console log and json output]
2026-09-12 04:00:39.850905 | localhost | skipping: Conditional result was False
2026-09-12 04:00:39.871342 | localhost | skipping: Conditional result was False
2026-09-12 04:00:39.889826 | 
2026-09-12 04:00:39.890099 | LOOP [upload-logs : Upload compressed console log and json output]
2026-09-12 04:00:39.953170 | localhost | skipping: Conditional result was False
2026-09-12 04:00:39.953662 | 
2026-09-12 04:00:39.958777 | localhost | skipping: Conditional result was False
2026-09-12 04:00:39.975412 | 
2026-09-12 04:00:39.975589 | LOOP [upload-logs : Upload console log and json output]