2026-04-13 03:12:28.787276 | Job console starting
2026-04-13 03:12:28.795745 | Updating git repos
2026-04-13 03:12:28.859052 | Cloning repos into workspace
2026-04-13 03:12:28.918474 | Restoring repo states
2026-04-13 03:12:28.937553 | Merging changes
2026-04-13 03:12:29.974176 | Checking out repos
2026-04-13 03:12:30.323228 | Preparing playbooks
2026-04-13 03:12:32.189651 | Running Ansible setup
2026-04-13 03:12:35.502472 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-13 03:12:36.058076 | 
2026-04-13 03:12:36.058203 | PLAY [localhost]
2026-04-13 03:12:36.067108 | 
2026-04-13 03:12:36.067232 | TASK [Gathering Facts]
2026-04-13 03:12:37.015555 | localhost | ok
2026-04-13 03:12:37.024026 | 
2026-04-13 03:12:37.024130 | TASK [Setup log path fact]
2026-04-13 03:12:37.046813 | localhost | ok
2026-04-13 03:12:37.062545 | 
2026-04-13 03:12:37.062718 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-13 03:12:37.093872 | localhost | ok
2026-04-13 03:12:37.103209 | 
2026-04-13 03:12:37.103310 | TASK [emit-job-header : Print job information]
2026-04-13 03:12:37.146368 | # Job Information
2026-04-13 03:12:37.146659 | Ansible Version: 2.16.16
2026-04-13 03:12:37.146739 | Job: atmosphere-molecule-csi-local-path-provisioner
2026-04-13 03:12:37.146794 | Pipeline: check
2026-04-13 03:12:37.146845 | Executor: 0a8996d2b663
2026-04-13 03:12:37.146894 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3818
2026-04-13 03:12:37.146947 | Event ID: 888d0fc0-36e6-11f1-8cdc-f1d19df9aa9a
2026-04-13 03:12:37.151302 | 
2026-04-13 03:12:37.151373 | LOOP [emit-job-header : Print node information]
2026-04-13 03:12:37.246038 | localhost | ok:
2026-04-13 03:12:37.246250 | localhost | # Node Information
2026-04-13 03:12:37.246279 | localhost | Inventory Hostname: instance
2026-04-13 03:12:37.246301 | localhost | Hostname: np0000165427
2026-04-13 03:12:37.246321 | localhost | Username: zuul
2026-04-13 03:12:37.246345 | localhost | Distro: Ubuntu 22.04
2026-04-13 03:12:37.246366 | localhost | Provider: yul1
2026-04-13 03:12:37.246385 | localhost | Region: ca-ymq-1
2026-04-13 03:12:37.246405 | localhost | Label: ubuntu-jammy
2026-04-13 03:12:37.246423 | localhost | Product Name: OpenStack Nova
2026-04-13 03:12:37.246442 | localhost | Interface IP: 162.253.55.110
2026-04-13 03:12:37.257720 | 
2026-04-13 03:12:37.257909 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-04-13 03:12:37.699061 | localhost -> localhost | changed
2026-04-13 03:12:37.709213 | 
2026-04-13 03:12:37.709371 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-04-13 03:12:39.059499 | localhost -> localhost | changed
2026-04-13 03:12:39.068311 | 
2026-04-13 03:12:39.068393 | PLAY [all]
2026-04-13 03:12:39.077660 | 
2026-04-13 03:12:39.077728 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-04-13 03:12:39.415246 | instance -> localhost | ok
2026-04-13 03:12:39.425862 | 
2026-04-13 03:12:39.426037 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-04-13 03:12:39.456507 | instance | ok
2026-04-13 03:12:39.472721 | instance | included: /var/lib/zuul/builds/32da2fb992c545d78d2b8220cf6a9d4c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-04-13 03:12:39.479260 | 
2026-04-13 03:12:39.479419 | TASK [add-build-sshkey : Create Temp SSH key]
2026-04-13 03:12:40.869736 | instance -> localhost | Generating public/private rsa key pair.
2026-04-13 03:12:40.869921 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/32da2fb992c545d78d2b8220cf6a9d4c/work/32da2fb992c545d78d2b8220cf6a9d4c_id_rsa
2026-04-13 03:12:40.869965 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/32da2fb992c545d78d2b8220cf6a9d4c/work/32da2fb992c545d78d2b8220cf6a9d4c_id_rsa.pub
2026-04-13 03:12:40.869998 | instance -> localhost | The key fingerprint is:
2026-04-13 03:12:40.870030 | instance -> localhost | SHA256:Mbif36yecqUAfjrBDXWSBv4Jw6fCeZyOJ7rnH7HcR7c zuul-build-sshkey
2026-04-13 03:12:40.870073 | instance -> localhost | The key's randomart image is:
2026-04-13 03:12:40.870105 | instance -> localhost | +---[RSA 3072]----+
2026-04-13 03:12:40.870141 | instance -> localhost | |      .. .       |
2026-04-13 03:12:40.870174 | instance -> localhost | |     o .= .      |
2026-04-13 03:12:40.870203 | instance -> localhost | |      *o+o       |
2026-04-13 03:12:40.870233 | instance -> localhost | |   . ooO +       |
2026-04-13 03:12:40.870262 | instance -> localhost | |    +oB+S . .    |
2026-04-13 03:12:40.870291 | instance -> localhost | |     *+=+o ...   |
2026-04-13 03:12:40.870320 | instance -> localhost | |    o *++..oE    |
2026-04-13 03:12:40.870349 | instance -> localhost | |   ..oo..oo+     |
2026-04-13 03:12:40.870382 | instance -> localhost | |  o+.... +=.o    |
2026-04-13 03:12:40.870412 | instance -> localhost | +----[SHA256]-----+
2026-04-13 03:12:40.870481 | instance -> localhost | ok: Runtime: 0:00:00.963576
2026-04-13 03:12:40.878568 | 
2026-04-13 03:12:40.878732 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-04-13 03:12:40.910373 | instance | ok
2026-04-13 03:12:40.921069 | instance | included: /var/lib/zuul/builds/32da2fb992c545d78d2b8220cf6a9d4c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-04-13 03:12:40.929096 | 
2026-04-13 03:12:40.929165 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-04-13 03:12:40.953454 | instance | skipping: Conditional result was False
2026-04-13 03:12:40.962129 | 
2026-04-13 03:12:40.962233 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-04-13 03:12:41.464145 | instance | changed
2026-04-13 03:12:41.469848 | 
2026-04-13 03:12:41.469915 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-04-13 03:12:41.680246 | instance | ok
2026-04-13 03:12:41.700101 | 
2026-04-13 03:12:41.700538 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-04-13 03:12:42.284862 | instance | changed
2026-04-13 03:12:42.291376 | 
2026-04-13 03:12:42.291441 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-04-13 03:12:42.766463 | instance | changed
2026-04-13 03:12:42.773076 | 
2026-04-13 03:12:42.773161 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-04-13 03:12:42.798021 | instance | skipping: Conditional result was False
2026-04-13 03:12:42.808401 | 
2026-04-13 03:12:42.808489 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-04-13 03:12:43.178730 | instance -> localhost | changed
2026-04-13 03:12:43.192405 | 
2026-04-13 03:12:43.192470 | TASK [add-build-sshkey : Add back temp key]
2026-04-13 03:12:43.607396 | instance -> localhost | Identity added: /var/lib/zuul/builds/32da2fb992c545d78d2b8220cf6a9d4c/work/32da2fb992c545d78d2b8220cf6a9d4c_id_rsa (zuul-build-sshkey)
2026-04-13 03:12:43.607622 | instance -> localhost | ok: Runtime: 0:00:00.013258
2026-04-13 03:12:43.614711 | 
2026-04-13 03:12:43.616680 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-04-13 03:12:43.923644 | instance | ok
2026-04-13 03:12:43.929911 | 
2026-04-13 03:12:43.929999 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-04-13 03:12:43.954207 | instance | skipping: Conditional result was False
2026-04-13 03:12:43.968676 | 
2026-04-13 03:12:43.968774 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-04-13 03:12:44.343160 | instance | ok
2026-04-13 03:12:44.350373 | 
2026-04-13 03:12:44.350444 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-04-13 03:12:46.010995 | instance | Output suppressed because no_log was given
2026-04-13 03:12:46.022803 | 
2026-04-13 03:12:46.022891 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-04-13 03:12:46.214706 | instance | ok: "logs"
2026-04-13 03:12:46.214920 | instance | ok: All items complete
2026-04-13 03:12:46.214949 | 
2026-04-13 03:12:46.368649 | instance | ok: "artifacts"
2026-04-13 03:12:46.527421 | instance | ok: "docs"
2026-04-13 03:12:46.538872 | 
2026-04-13 03:12:46.539010 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-04-13 03:12:46.731655 | instance | changed: "logs"
2026-04-13 03:12:46.892837 | instance | changed: "artifacts"
2026-04-13 03:12:47.053337 | instance | changed: "docs"
2026-04-13 03:12:47.061700 | 
2026-04-13 03:12:47.061745 | PLAY RECAP
2026-04-13 03:12:47.061789 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-04-13 03:12:47.061818 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-13 03:12:47.061838 | 
2026-04-13 03:12:47.162878 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-13 03:12:47.165259 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-13 03:12:47.797967 | 
2026-04-13 03:12:47.798083 | PLAY [all]
2026-04-13 03:12:47.809470 | 
2026-04-13 03:12:47.809548 | TASK [setup-uv : Extract archive]
2026-04-13 03:12:50.055257 | instance | changed
2026-04-13 03:12:50.064716 | 
2026-04-13 03:12:50.084514 | TASK [setup-uv : Print version]
2026-04-13 03:12:51.043449 | instance | uv 0.8.13
2026-04-13 03:12:50.628611 | instance | ok: Runtime: 0:00:00.013281
2026-04-13 03:12:50.637772 | 
2026-04-13 03:12:50.637841 | PLAY RECAP
2026-04-13 03:12:50.637885 | instance | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-13 03:12:50.637912 | 
2026-04-13 03:12:50.741252 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-13 03:12:50.744355 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/pre.yml@main]
2026-04-13 03:12:51.416863 | 
2026-04-13 03:12:51.417337 | PLAY [all]
2026-04-13 03:12:51.430636 | 
2026-04-13 03:12:51.430765 | TASK [Install "jq" for log collection]
2026-04-13 03:13:01.571618 | instance | changed
2026-04-13 03:13:01.574840 | 
2026-04-13 03:13:01.574922 | PLAY [all]
2026-04-13 03:13:01.582039 | 
2026-04-13 03:13:01.582126 | TASK [ensure-go : Check installed go version]
2026-04-13 03:13:02.121488 | instance | ok: ERROR (ignored)
2026-04-13 03:13:02.121801 | instance | {
2026-04-13 03:13:02.121834 | instance |   "failed_when_result": false,
2026-04-13 03:13:02.121858 | instance |   "msg": "[Errno 2] No such file or directory: b'go'",
2026-04-13 03:13:02.121878 | instance |   "rc": 2
2026-04-13 03:13:02.121897 | instance | }
2026-04-13 03:13:02.127405 | 
2026-04-13 03:13:02.127479 | TASK [ensure-go : Skip if correct version of go is installed]
2026-04-13 03:13:02.195053 | instance | ok
2026-04-13 03:13:02.207304 | instance | included: /var/lib/zuul/builds/32da2fb992c545d78d2b8220cf6a9d4c/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/ensure-go/tasks/install-go.yaml
2026-04-13 03:13:02.212658 | 
2026-04-13 03:13:02.212739 | TASK [ensure-go : Create temp directory]
2026-04-13 03:13:02.521364 | instance | changed
2026-04-13 03:13:02.529707 | 
2026-04-13 03:13:02.529877 | TASK [ensure-go : Get archive checksum]
2026-04-13 03:13:03.185761 | instance | ok: OK (64 bytes)
2026-04-13 03:13:03.191925 | 
2026-04-13 03:13:03.192028 | TASK [ensure-go : Download go archive]
2026-04-13 03:13:04.690769 | instance | changed: OK (78559214 bytes)
2026-04-13 03:13:04.700014 | 
2026-04-13 03:13:04.700140 | TASK [ensure-go : Install go]
2026-04-13 03:13:11.636568 | instance | changed
2026-04-13 03:13:11.642171 | 
2026-04-13 03:13:11.642243 | PLAY RECAP
2026-04-13 03:13:11.642288 | instance | ok: 7 changed: 4 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-13 03:13:11.642313 | 
2026-04-13 03:13:11.760001 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/pre.yml@main]
2026-04-13 03:13:11.764627 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-04-13 03:13:12.303770 | 
2026-04-13 03:13:12.303882 | PLAY [all]
2026-04-13 03:13:12.314015 | 
2026-04-13 03:13:12.314103 | TASK [Copy inventory file for Zuul]
2026-04-13 03:13:13.137128 | instance | changed
2026-04-13 03:13:13.142125 | 
2026-04-13 03:13:13.142224 | TASK [Switch "ansible_host" to private IP]
2026-04-13 03:13:13.439927 | instance | changed: 1 replacements made
2026-04-13 03:13:13.445193 | 
2026-04-13 03:13:13.445263 | TASK [Run Molecule scenario]
2026-04-13 03:13:13.835808 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3
2026-04-13 03:13:13.836021 | instance | Creating virtual environment at: .venv
2026-04-13 03:13:13.861929 | instance |    Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-04-13 03:13:13.891854 | instance | Downloading netaddr (2.2MiB)
2026-04-13 03:13:13.892137 | instance | Downloading openstacksdk (1.7MiB)
2026-04-13 03:13:13.892331 | instance | Downloading setuptools (1.1MiB)
2026-04-13 03:13:13.892586 | instance | Downloading rjsonnet (1.2MiB)
2026-04-13 03:13:13.892956 | instance | Downloading kubernetes (1.9MiB)
2026-04-13 03:13:13.893313 | instance | Downloading cryptography (4.2MiB)
2026-04-13 03:13:13.893524 | instance | Downloading pydantic-core (2.0MiB)
2026-04-13 03:13:13.893979 | instance | Downloading ansible-core (2.1MiB)
2026-04-13 03:13:13.894191 | instance | Downloading pygments (1.2MiB)
2026-04-13 03:13:14.234859 | instance |    Building pyperclip==1.9.0
2026-04-13 03:13:14.247747 | instance |  Downloading rjsonnet
2026-04-13 03:13:14.352529 | instance |  Downloading pydantic-core
2026-04-13 03:13:14.395474 | instance |  Downloading netaddr
2026-04-13 03:13:14.407168 | instance |  Downloading pygments
2026-04-13 03:13:14.420464 | instance |  Downloading cryptography
2026-04-13 03:13:14.451078 | instance |  Downloading setuptools
2026-04-13 03:13:14.505894 | instance |  Downloading kubernetes
2026-04-13 03:13:14.534394 | instance |  Downloading ansible-core
2026-04-13 03:13:14.559592 | instance |  Downloading openstacksdk
2026-04-13 03:13:14.973386 | instance |       Built pyperclip==1.9.0
2026-04-13 03:13:15.125906 | instance |       Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-04-13 03:13:15.177004 | instance | Installed 83 packages in 48ms
2026-04-13 03:13:15.849938 | instance | WARNING  Molecule scenarios should migrate to 'extensions/molecule'
2026-04-13 03:13:16.488260 | instance | INFO     [csi > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-04-13 03:13:16.488387 | instance | INFO     [csi > prerun] Performing prerun with role_name_check=0...
2026-04-13 03:14:00.235340 | instance | INFO     [csi > dependency] Executing
2026-04-13 03:14:00.235524 | instance | WARNING  [csi > dependency] Missing roles requirements file: requirements.yml
2026-04-13 03:14:00.235768 | instance | WARNING  [csi > dependency] Missing collections requirements file: collections.yml
2026-04-13 03:14:00.235930 | instance | WARNING  [csi > dependency] Executed: 2 missing (Remove from test_sequence to suppress)
2026-04-13 03:14:00.243888 | instance | INFO     [csi > cleanup] Executing
2026-04-13 03:14:00.244278 | instance | WARNING  [csi > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-04-13 03:14:00.252000 | instance | INFO     [csi > destroy] Executing
2026-04-13 03:14:00.252053 | instance | WARNING  [csi > destroy] Skipping, '--destroy=never' requested.
2026-04-13 03:14:00.252189 | instance | INFO     [csi > destroy] Executed: Successful
2026-04-13 03:14:00.259859 | instance | INFO     [csi > syntax] Executing
2026-04-13 03:14:01.087889 | instance |
2026-04-13 03:14:01.088148 | instance | playbook: /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml
2026-04-13 03:14:01.149510 | instance | INFO     [csi > syntax] Executed: Successful
2026-04-13 03:14:01.164829 | instance | INFO     [csi > create] Executing
2026-04-13 03:14:01.166962 | instance | WARNING  [csi > create] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-04-13 03:14:01.175325 | instance | INFO     [csi > prepare] Executing
2026-04-13 03:14:02.055703 | instance |
2026-04-13 03:14:02.055854 | instance | PLAY [Prepare] *****************************************************************
2026-04-13 03:14:02.056126 | instance |
2026-04-13 03:14:02.056388 | instance | TASK [Gathering Facts] *********************************************************
2026-04-13 03:14:02.056662 | instance | Monday 13 April 2026  03:14:02 +0000 (0:00:00.030)       0:00:00.030 **********
2026-04-13 03:14:03.197532 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-04-13 03:14:03.197804 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-04-13 03:14:03.198093 | instance | interpreter could change the meaning of that path. See
2026-04-13 03:14:03.198377 | instance | https://docs.ansible.com/ansible-
2026-04-13 03:14:03.198687 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-04-13 03:14:03.206774 | instance | ok: [instance]
2026-04-13 03:14:03.207028 | instance |
2026-04-13 03:14:03.207303 | instance | TASK [Configure short hostname] ************************************************
2026-04-13 03:14:03.207571 | instance | Monday 13 April 2026  03:14:03 +0000 (0:00:01.153)       0:00:01.183 **********
2026-04-13 03:14:03.929412 | instance | changed: [instance]
2026-04-13 03:14:03.929542 | instance |
2026-04-13 03:14:03.929728 | instance | TASK [Ensure hostname inside hosts file] ***************************************
2026-04-13 03:14:03.929904 | instance | Monday 13 April 2026  03:14:03 +0000 (0:00:00.722)       0:00:01.905 **********
2026-04-13 03:14:04.223205 | instance | [WARNING]: Module remote_tmp /root/.ansible/tmp did not exist and was created
2026-04-13 03:14:04.223486 | instance | with a mode of 0700, this may cause issues when running as another user. To
2026-04-13 03:14:04.223767 | instance | avoid this, create the remote_tmp dir with the correct permissions manually
2026-04-13 03:14:04.232410 | instance | changed: [instance]
2026-04-13 03:14:04.232682 | instance |
2026-04-13 03:14:04.232957 | instance | TASK [Purge "snapd" package] ***************************************************
2026-04-13 03:14:04.233220 | instance | Monday 13 April 2026  03:14:04 +0000 (0:00:00.303)       0:00:02.209 **********
2026-04-13 03:14:05.034869 | instance | ok: [instance]
2026-04-13 03:14:05.035081 | instance |
2026-04-13 03:14:05.035353 | instance | PLAY [Create devices for Ceph] *************************************************
2026-04-13 03:14:05.035795 | instance |
2026-04-13 03:14:05.036157 | instance | TASK [Gathering Facts] *********************************************************
2026-04-13 03:14:05.036427 | instance | Monday 13 April 2026  03:14:05 +0000 (0:00:00.801)       0:00:03.011 **********
2026-04-13 03:14:05.744475 | instance | ok: [instance]
2026-04-13 03:14:05.744715 | instance |
2026-04-13 03:14:05.744999 | instance | TASK [Install depedencies] *****************************************************
2026-04-13 03:14:05.745321 | instance | Monday 13 April 2026  03:14:05 +0000 (0:00:00.709)       0:00:03.720 **********
2026-04-13 03:14:25.872452 | instance | changed: [instance]
2026-04-13 03:14:25.872599 | instance |
2026-04-13 03:14:25.872920 | instance | TASK [Start up service] ********************************************************
2026-04-13 03:14:25.873231 | instance | Monday 13 April 2026  03:14:25 +0000 (0:00:20.128)       0:00:23.849 **********
2026-04-13 03:14:26.427565 | instance | ok: [instance]
2026-04-13 03:14:26.427840 | instance |
2026-04-13 03:14:26.428066 | instance | TASK [Generate lvm.conf] *******************************************************
2026-04-13 03:14:26.428329 | instance | Monday 13 April 2026  03:14:26 +0000 (0:00:00.555)       0:00:24.404 **********
2026-04-13 03:14:26.719525 | instance | ok: [instance]
2026-04-13 03:14:26.719782 | instance |
2026-04-13 03:14:26.720054 | instance | TASK [Write /etc/lvm/lvm.conf] *************************************************
2026-04-13 03:14:26.720324 | instance | Monday 13 April 2026  03:14:26 +0000 (0:00:00.291)       0:00:24.696 **********
2026-04-13 03:14:27.387372 | instance | changed: [instance]
2026-04-13 03:14:27.387499 | instance |
2026-04-13 03:14:27.387681 | instance | TASK [Get list of all loopback devices] ****************************************
2026-04-13 03:14:27.387859 | instance | Monday 13 April 2026  03:14:27 +0000 (0:00:00.668)       0:00:25.364 **********
2026-04-13 03:14:27.573779 | instance | ok: [instance]
2026-04-13 03:14:27.574017 | instance |
2026-04-13 03:14:27.574295 | instance | TASK [Fail if there is any existing loopback devices] **************************
2026-04-13 03:14:27.574573 | instance | Monday 13 April 2026  03:14:27 +0000 (0:00:00.186)       0:00:25.550 **********
2026-04-13 03:14:27.596616 | instance | skipping: [instance]
2026-04-13 03:14:27.596908 | instance |
2026-04-13 03:14:27.597204 | instance | TASK [Create devices for Ceph] *************************************************
2026-04-13 03:14:27.597548 | instance | Monday 13 April 2026  03:14:27 +0000 (0:00:00.023)       0:00:25.573 **********
2026-04-13 03:14:28.132990 | instance | changed: [instance] => (item=osd0)
2026-04-13 03:14:28.133300 | instance | changed: [instance] => (item=osd1)
2026-04-13 03:14:28.133588 | instance | changed: [instance] => (item=osd2)
2026-04-13 03:14:28.133843 | instance |
2026-04-13 03:14:28.134125 | instance | TASK [Set permissions on loopback devices] *************************************
2026-04-13 03:14:28.134396 | instance | Monday 13 April 2026  03:14:28 +0000 (0:00:00.535)       0:00:26.109 **********
2026-04-13 03:14:28.748303 | instance | changed: [instance] => (item=osd0)
2026-04-13 03:14:28.748558 | instance | changed: [instance] => (item=osd1)
2026-04-13 03:14:28.748834 | instance | changed: [instance] => (item=osd2)
2026-04-13 03:14:28.749091 | instance |
2026-04-13 03:14:28.749412 | instance | TASK [Start loop devices] ******************************************************
2026-04-13 03:14:28.749684 | instance | Monday 13 April 2026  03:14:28 +0000 (0:00:00.615)       0:00:26.724 **********
2026-04-13 03:14:29.417420 | instance | changed: [instance] => (item=osd0)
2026-04-13 03:14:29.417581 | instance | changed: [instance] => (item=osd1)
2026-04-13 03:14:29.417860 | instance | changed: [instance] => (item=osd2)
2026-04-13 03:14:29.418155 | instance |
2026-04-13 03:14:29.418425 | instance | TASK [Create a volume group for each loop device] ******************************
2026-04-13 03:14:29.418692 | instance | Monday 13 April 2026  03:14:29 +0000 (0:00:00.669)       0:00:27.394 **********
2026-04-13 03:14:32.422247 | instance | changed: [instance] => (item=osd0)
2026-04-13 03:14:32.422498 | instance | changed: [instance] => (item=osd1)
2026-04-13 03:14:32.422773 | instance | changed: [instance] => (item=osd2)
2026-04-13 03:14:32.423046 | instance |
2026-04-13 03:14:32.423363 | instance | TASK [Create a logical volume for each loop device] ****************************
2026-04-13 03:14:32.423658 | instance | Monday 13 April 2026  03:14:32 +0000 (0:00:03.004)       0:00:30.398 **********
2026-04-13 03:14:34.229520 | instance | changed: [instance] => (item=ceph-instance-osd0)
2026-04-13 03:14:34.229783 | instance | changed: [instance] => (item=ceph-instance-osd1)
2026-04-13 03:14:34.230107 | instance | changed: [instance] => (item=ceph-instance-osd2)
2026-04-13 03:14:34.230373 | instance |
2026-04-13 03:14:34.230646 | instance | PLAY RECAP *********************************************************************
2026-04-13 03:14:34.230946 | instance | instance                   : ok=15   changed=9    unreachable=0    failed=0    skipped=1    rescued=0    ignored=0
2026-04-13 03:14:34.231198 | instance |
2026-04-13 03:14:34.231471 | instance | Monday 13 April 2026  03:14:34 +0000 (0:00:01.807)       0:00:32.206 **********
2026-04-13 03:14:34.231788 | instance | ===============================================================================
2026-04-13 03:14:34.232076 | instance | Install depedencies ---------------------------------------------------- 20.13s
2026-04-13 03:14:34.232347 | instance | Create a volume group for each loop device ------------------------------ 3.00s
2026-04-13 03:14:34.232619 | instance | Create a logical volume for each loop device ---------------------------- 1.81s
2026-04-13 03:14:34.232887 | instance | Gathering Facts --------------------------------------------------------- 1.15s
2026-04-13 03:14:34.233158 | instance | Purge "snapd" package --------------------------------------------------- 0.80s
2026-04-13 03:14:34.233480 | instance | Configure short hostname ------------------------------------------------ 0.72s
2026-04-13 03:14:34.233747 | instance | Gathering Facts --------------------------------------------------------- 0.71s
2026-04-13 03:14:34.234046 | instance | Start loop devices ------------------------------------------------------ 0.67s
2026-04-13 03:14:34.234284 | instance | Write /etc/lvm/lvm.conf ------------------------------------------------- 0.67s
2026-04-13 03:14:34.234543 | instance | Set permissions on loopback devices ------------------------------------- 0.62s
2026-04-13 03:14:34.234804 | instance | Start up service -------------------------------------------------------- 0.56s
2026-04-13 03:14:34.235072 | instance | Create devices for Ceph ------------------------------------------------- 0.54s
2026-04-13 03:14:34.235327 | instance | Ensure hostname inside hosts file --------------------------------------- 0.30s
2026-04-13 03:14:34.235593 | instance | Generate lvm.conf ------------------------------------------------------- 0.29s
2026-04-13 03:14:34.235895 | instance | Get list of all loopback devices ---------------------------------------- 0.19s
2026-04-13 03:14:34.236164 | instance | Fail if there is any existing loopback devices -------------------------- 0.02s
2026-04-13 03:14:34.311851 | instance | INFO     [csi > prepare] Executed: Successful
2026-04-13 03:14:34.323043 | instance | INFO     [csi > converge] Executing
2026-04-13 03:14:35.110285 | instance |
2026-04-13 03:14:35.110421 | instance | PLAY [Debug CSI driver value] **************************************************
2026-04-13 03:14:35.110435 | instance |
2026-04-13 03:14:35.110444 | instance | TASK [Gathering Facts] *********************************************************
2026-04-13 03:14:35.110454 | instance | Monday 13 April 2026  03:14:35 +0000 (0:00:00.008)       0:00:00.008 **********
2026-04-13 03:14:36.028355 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-04-13 03:14:36.028452 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-04-13 03:14:36.028465 | instance | interpreter could change the meaning of that path. See
2026-04-13 03:14:36.028475 | instance | https://docs.ansible.com/ansible-
2026-04-13 03:14:36.028485 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-04-13 03:14:36.039433 | instance | ok: [instance]
2026-04-13 03:14:36.039524 | instance |
2026-04-13 03:14:36.039648 | instance | TASK [Display CSI driver value and environment variable] ***********************
2026-04-13 03:14:36.039774 | instance | Monday 13 April 2026  03:14:36 +0000 (0:00:00.929)       0:00:00.937 **********
2026-04-13 03:14:36.071631 | instance | ok: [instance] => {
2026-04-13 03:14:36.071784 | instance |     "msg": "csi_driver=local-path-provisioner, MOLECULE_CSI_DRIVER="
2026-04-13 03:14:36.071945 | instance | }
2026-04-13 03:14:36.072154 | instance |
2026-04-13 03:14:36.072372 | instance | PLAY [localhost] ***************************************************************
2026-04-13 03:14:36.072517 | instance |
2026-04-13 03:14:36.072706 | instance | TASK [Build atmosphere binary] *************************************************
2026-04-13 03:14:36.072895 | instance | Monday 13 April 2026  03:14:36 +0000 (0:00:00.032)       0:00:00.970 **********
2026-04-13 03:14:43.271454 | instance | changed: [localhost]
2026-04-13 03:14:43.271653 | instance |
2026-04-13 03:14:43.271710 | instance | TASK [Deploy with parallel orchestrator (with ceph)] ***************************
2026-04-13 03:14:43.271848 | instance | Monday 13 April 2026  03:14:43 +0000 (0:00:07.196)       0:00:08.167 **********
2026-04-13 03:14:43.297627 | instance | skipping: [localhost]
2026-04-13 03:14:43.297900 | instance |
2026-04-13 03:14:43.298190 | instance | TASK [Deploy with parallel orchestrator (without ceph)] ************************
2026-04-13 03:14:43.298557 | instance | Monday 13 April 2026  03:14:43 +0000 (0:00:00.028)       0:00:08.196 **********
2026-04-13 03:14:43.322367 | instance | fatal: [localhost]: FAILED! => {"msg": "The task includes an option with an undefined variable.. 'inventory_file' is undefined\n\nThe error appears to be in '/home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml': line 48, column 7, but may\nbe elsewhere in the file depending on the exact syntax problem.\n\nThe offending line appears to be:\n\n\n    - name: Deploy with parallel orchestrator (without ceph)\n      ^ here\n"}
2026-04-13 03:14:43.323294 | instance |
2026-04-13 03:14:43.323594 | instance | PLAY RECAP *********************************************************************
2026-04-13 03:14:43.323887 | instance | instance                   : ok=2    changed=0    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-04-13 03:14:43.324171 | instance | localhost                  : ok=1    changed=1    unreachable=0    failed=1    skipped=1    rescued=0    ignored=0
2026-04-13 03:14:43.324421 | instance |
2026-04-13 03:14:43.324689 | instance | Monday 13 April 2026  03:14:43 +0000 (0:00:00.026)       0:00:08.223 **********
2026-04-13 03:14:43.325019 | instance | ===============================================================================
2026-04-13 03:14:43.325217 | instance | Build atmosphere binary ------------------------------------------------- 7.20s
2026-04-13 03:14:43.325503 | instance | Gathering Facts --------------------------------------------------------- 0.93s
2026-04-13 03:14:43.325763 | instance | Display CSI driver value and environment variable ----------------------- 0.03s
2026-04-13 03:14:43.326024 | instance | Deploy with parallel orchestrator (with ceph) --------------------------- 0.03s
2026-04-13 03:14:43.326304 | instance | Deploy with parallel orchestrator (without ceph) ------------------------ 0.03s
2026-04-13 03:14:43.402487 | 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-04-13 03:14:43.402705 | instance | ERROR    [csi > converge] Executed: Failed
2026-04-13 03:14:43.402911 | 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-04-13 03:14:43.605614 | instance | ERROR
2026-04-13 03:14:43.606157 | instance | {
2026-04-13 03:14:43.606235 | instance |   "delta": "0:01:29.702975",
2026-04-13 03:14:43.606351 | instance |   "end": "2026-04-13 03:14:43.477460",
2026-04-13 03:14:43.606489 | instance |   "msg": "non-zero return code",
2026-04-13 03:14:43.606540 | instance |   "rc": 2,
2026-04-13 03:14:43.606585 | instance |   "start": "2026-04-13 03:13:13.774485"
2026-04-13 03:14:43.606609 | instance | }
failure
2026-04-13 03:14:43.609833 | 
2026-04-13 03:14:43.609887 | PLAY RECAP
2026-04-13 03:14:43.609936 | instance | ok: 2 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-04-13 03:14:43.609959 | 
2026-04-13 03:14:43.775543 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-04-13 03:14:43.780466 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@main]
2026-04-13 03:14:44.397936 | 
2026-04-13 03:14:44.398123 | PLAY [all]
2026-04-13 03:14:44.413429 | 
2026-04-13 03:14:44.413549 | TASK [gather-host-logs : creating directory for system status]
2026-04-13 03:14:44.782859 | instance | changed
2026-04-13 03:14:44.790183 | 
2026-04-13 03:14:44.790291 | TASK [gather-host-logs : Get logs for each host]
2026-04-13 03:14:45.161121 | instance | + systemd-cgls --full --all --no-pager
2026-04-13 03:14:45.169586 | instance | + ip addr
2026-04-13 03:14:45.171011 | instance | + ip route
2026-04-13 03:14:45.172225 | instance | + lsblk
2026-04-13 03:14:45.175258 | instance | + mount
2026-04-13 03:14:45.176590 | instance | + docker images
2026-04-13 03:14:45.176882 | instance | /bin/bash: line 7: docker: command not found
2026-04-13 03:14:45.177032 | instance | + brctl show
2026-04-13 03:14:45.177306 | instance | /bin/bash: line 8: brctl: command not found
2026-04-13 03:14:45.177437 | instance | + ps aux --sort=-%mem
2026-04-13 03:14:45.188040 | instance | + dpkg -l
2026-04-13 03:14:45.195529 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul))
2026-04-13 03:14:45.195906 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul
2026-04-13 03:14:45.195957 | instance | /bin/bash: line 11: docker: command not found
2026-04-13 03:14:45.196300 | instance | + '[' '!' -z '' ']'
2026-04-13 03:14:45.329891 | instance | ok: Runtime: 0:00:00.040883
2026-04-13 03:14:45.336299 | 
2026-04-13 03:14:45.336378 | TASK [gather-host-logs : Downloads logs to executor]
2026-04-13 03:14:45.946605 | instance | changed:
2026-04-13 03:14:45.946747 | instance | created directory /var/lib/zuul/builds/32da2fb992c545d78d2b8220cf6a9d4c/work/logs/instance
2026-04-13 03:14:45.946775 | instance | cd+++++++++ system/
2026-04-13 03:14:45.946797 | instance | >f+++++++++ system/brctl-show.txt
2026-04-13 03:14:45.946817 | instance | >f+++++++++ system/docker-images.txt
2026-04-13 03:14:45.946836 | instance | >f+++++++++ system/ip-addr.txt
2026-04-13 03:14:45.946859 | instance | >f+++++++++ system/ip-route.txt
2026-04-13 03:14:45.946880 | instance | >f+++++++++ system/lsblk.txt
2026-04-13 03:14:45.946899 | instance | >f+++++++++ system/mount.txt
2026-04-13 03:14:45.946920 | instance | >f+++++++++ system/packages.txt
2026-04-13 03:14:45.946939 | instance | >f+++++++++ system/ps.txt
2026-04-13 03:14:45.946958 | instance | >f+++++++++ system/systemd-cgls.txt
2026-04-13 03:14:45.955743 | 
2026-04-13 03:14:45.955818 | LOOP [helm-release-status : creating directory for helm release status]
2026-04-13 03:14:46.141133 | instance | changed: "values"
2026-04-13 03:14:46.312226 | instance | changed: "releases"
2026-04-13 03:14:46.325149 | 
2026-04-13 03:14:46.325380 | TASK [helm-release-status : Gather get release status for helm charts]
2026-04-13 03:14:46.539651 | instance | /bin/bash: line 3: kubectl: command not found
2026-04-13 03:14:47.588012 | instance | ok: Runtime: 0:00:00.005620
2026-04-13 03:14:47.594927 | 
2026-04-13 03:14:47.595013 | TASK [helm-release-status : Downloads logs to executor]
2026-04-13 03:14:48.621528 | instance | changed:
2026-04-13 03:14:48.621767 | instance | cd+++++++++ helm/
2026-04-13 03:14:48.621814 | instance | cd+++++++++ helm/releases/
2026-04-13 03:14:48.621853 | instance | cd+++++++++ helm/values/
2026-04-13 03:14:48.634202 | 
2026-04-13 03:14:48.634283 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-04-13 03:14:48.832253 | instance | changed
2026-04-13 03:14:48.847907 | 
2026-04-13 03:14:48.848001 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-04-13 03:14:49.047280 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-04-13 03:14:49.047747 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-04-13 03:14:49.053600 | instance | environment: line 1: kubectl: command not found
2026-04-13 03:14:49.054904 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-04-13 03:14:49.055791 | instance | environment: line 1: kubectl: command not found
2026-04-13 03:14:49.056954 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-04-13 03:14:49.057959 | instance | environment: line 1: kubectl: command not found
2026-04-13 03:14:49.058862 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-04-13 03:14:49.060113 | instance | environment: line 1: kubectl: command not found
2026-04-13 03:14:49.061019 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-04-13 03:14:49.061279 | instance | environment: line 1: kubectl: command not found
2026-04-13 03:14:49.062176 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-04-13 03:14:49.378045 | instance | ok: Runtime: 0:00:00.022695
2026-04-13 03:14:49.383051 | 
2026-04-13 03:14:49.383134 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-04-13 03:14:49.568185 | instance | changed
2026-04-13 03:14:49.573249 | 
2026-04-13 03:14:49.573323 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-04-13 03:14:49.797180 | instance | environment: line 5: kubectl: command not found
2026-04-13 03:14:49.798161 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-04-13 03:14:49.798234 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-04-13 03:14:49.798332 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-04-13 03:14:50.137604 | instance | ok: Runtime: 0:00:00.009232
2026-04-13 03:14:50.143599 | 
2026-04-13 03:14:50.143666 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-04-13 03:14:50.643139 | instance | changed:
2026-04-13 03:14:50.643291 | instance | cd+++++++++ objects/
2026-04-13 03:14:50.643318 | instance | cd+++++++++ objects/cluster/
2026-04-13 03:14:50.643340 | instance | cd+++++++++ objects/namespaced/
2026-04-13 03:14:50.652476 | 
2026-04-13 03:14:50.652556 | TASK [gather-pod-logs : creating directory for pod logs]
2026-04-13 03:14:50.842618 | instance | changed
2026-04-13 03:14:50.849372 | 
2026-04-13 03:14:50.849467 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-04-13 03:14:51.047691 | instance | changed
2026-04-13 03:14:51.077014 | 
2026-04-13 03:14:51.077112 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-04-13 03:14:51.277674 | instance | environment: line 3: kubectl: command not found
2026-04-13 03:14:51.743918 | instance | ok: Runtime: 0:00:00.008261
2026-04-13 03:14:51.750634 | 
2026-04-13 03:14:51.750721 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-04-13 03:14:52.321417 | instance | changed:
2026-04-13 03:14:52.321614 | instance | cd+++++++++ pod-logs/
2026-04-13 03:14:52.321642 | instance | cd+++++++++ pod-logs/failed-pods/
2026-04-13 03:14:52.332262 | 
2026-04-13 03:14:52.332326 | TASK [gather-prom-metrics : creating directory for helm release descriptions]
2026-04-13 03:14:52.537236 | instance | changed
2026-04-13 03:14:52.544455 | 
2026-04-13 03:14:52.544550 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces]
2026-04-13 03:14:52.750563 | instance | /bin/bash: line 2: kubectl: command not found
2026-04-13 03:14:53.078634 | instance | ok: Runtime: 0:00:00.033283
2026-04-13 03:14:53.083473 | 
2026-04-13 03:14:53.083540 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr]
2026-04-13 03:14:53.278423 | instance | /bin/bash: line 2: kubectl: command not found
2026-04-13 03:14:53.305885 | instance | ceph-mgr endpoints:
2026-04-13 03:14:53.622196 | instance | ok: Runtime: 0:00:00.032533
2026-04-13 03:14:53.630105 | 
2026-04-13 03:14:53.630207 | TASK [gather-prom-metrics : Get metrics from fluentd pods]
2026-04-13 03:14:53.828538 | instance | /bin/bash: line 4: kubectl: command not found
2026-04-13 03:14:54.217126 | instance | ok: Runtime: 0:00:00.034547
2026-04-13 03:14:54.228293 | 
2026-04-13 03:14:54.228397 | TASK [gather-prom-metrics : Downloads logs to executor]
2026-04-13 03:14:54.708670 | instance | changed: cd+++++++++ prometheus/
2026-04-13 03:14:54.718426 | 
2026-04-13 03:14:54.718495 | TASK [gather-selenium-data : creating directory for helm release descriptions]
2026-04-13 03:14:55.201753 | instance | changed
2026-04-13 03:14:55.206552 | 
2026-04-13 03:14:55.206623 | TASK [gather-selenium-data : Get selenium data]
2026-04-13 03:14:55.626918 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/.
2026-04-13 03:14:55.628504 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory
2026-04-13 03:14:56.117694 | instance | ERROR
2026-04-13 03:14:56.117993 | instance | {
2026-04-13 03:14:56.118040 | instance |   "delta": "0:00:00.006945",
2026-04-13 03:14:56.118072 | instance |   "end": "2026-04-13 03:14:55.628943",
2026-04-13 03:14:56.118101 | instance |   "msg": "non-zero return code",
2026-04-13 03:14:56.118129 | instance |   "rc": 1,
2026-04-13 03:14:56.118156 | instance |   "start": "2026-04-13 03:14:55.621998"
2026-04-13 03:14:56.118182 | instance | }
2026-04-13 03:14:56.118216 | instance | ERROR: Ignoring Errors
2026-04-13 03:14:56.124081 | 
2026-04-13 03:14:56.124156 | TASK [gather-selenium-data : Downloads logs to executor]
2026-04-13 03:14:56.616603 | instance | changed: cd+++++++++ selenium/
2026-04-13 03:14:56.624815 | 
2026-04-13 03:14:56.624884 | PLAY RECAP
2026-04-13 03:14:56.624944 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1
2026-04-13 03:14:56.624994 | 
2026-04-13 03:14:56.752367 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@main]
2026-04-13 03:14:56.755029 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-13 03:14:57.382964 | 
2026-04-13 03:14:57.383209 | PLAY [all]
2026-04-13 03:14:57.398263 | 
2026-04-13 03:14:57.398376 | TASK [fetch-output : Set log path for multiple nodes]
2026-04-13 03:14:57.444664 | instance | skipping: Conditional result was False
2026-04-13 03:14:57.450710 | 
2026-04-13 03:14:57.450804 | TASK [fetch-output : Set log path for single node]
2026-04-13 03:14:57.490934 | instance | ok
2026-04-13 03:14:57.496527 | 
2026-04-13 03:14:57.496599 | LOOP [fetch-output : Ensure local output dirs]
2026-04-13 03:14:57.929017 | instance -> localhost | ok: "/var/lib/zuul/builds/32da2fb992c545d78d2b8220cf6a9d4c/work/logs"
2026-04-13 03:14:58.139136 | instance -> localhost | changed: "/var/lib/zuul/builds/32da2fb992c545d78d2b8220cf6a9d4c/work/artifacts"
2026-04-13 03:14:58.373489 | instance -> localhost | changed: "/var/lib/zuul/builds/32da2fb992c545d78d2b8220cf6a9d4c/work/docs"
2026-04-13 03:14:58.391412 | 
2026-04-13 03:14:58.391673 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-04-13 03:14:59.088809 | instance | changed: .d..t...... ./
2026-04-13 03:14:59.089160 | instance | changed: All items complete
2026-04-13 03:14:59.089190 | 
2026-04-13 03:14:59.524142 | instance | changed: .d..t...... ./
2026-04-13 03:14:59.984474 | instance | changed: .d..t...... ./
2026-04-13 03:14:59.997788 | 
2026-04-13 03:14:59.997981 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-04-13 03:15:00.466085 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.008918
2026-04-13 03:15:00.773739 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.009960
2026-04-13 03:15:00.785093 | 
2026-04-13 03:15:00.785209 | PLAY [all]
2026-04-13 03:15:00.791080 | 
2026-04-13 03:15:00.791154 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-04-13 03:15:01.220901 | instance | changed
2026-04-13 03:15:01.230374 | 
2026-04-13 03:15:01.230475 | PLAY RECAP
2026-04-13 03:15:01.230525 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-04-13 03:15:01.230548 | 
2026-04-13 03:15:01.350008 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-13 03:15:01.354546 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-04-13 03:15:01.947682 | 
2026-04-13 03:15:01.948256 | PLAY [localhost]
2026-04-13 03:15:01.958956 | 
2026-04-13 03:15:01.959036 | TASK [Generate Zuul manifest]
2026-04-13 03:15:01.983455 | localhost | ok
2026-04-13 03:15:01.999236 | 
2026-04-13 03:15:01.999332 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-04-13 03:15:02.317390 | localhost | changed
2026-04-13 03:15:02.329638 | 
2026-04-13 03:15:02.329724 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-04-13 03:15:02.362395 | localhost | ok
2026-04-13 03:15:02.371711 | 
2026-04-13 03:15:02.371812 | TASK [Upload logs]
2026-04-13 03:15:02.403915 | localhost | ok
2026-04-13 03:15:02.461637 | 
2026-04-13 03:15:02.461805 | TASK [Set zuul-log-path fact]
2026-04-13 03:15:02.483407 | localhost | ok
2026-04-13 03:15:02.498843 | 
2026-04-13 03:15:02.498927 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-13 03:15:02.530653 | localhost | ok
2026-04-13 03:15:02.540826 | 
2026-04-13 03:15:02.540904 | TASK [upload-logs : Create log directories]
2026-04-13 03:15:03.035036 | localhost | changed
2026-04-13 03:15:03.042543 | 
2026-04-13 03:15:03.042631 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-04-13 03:15:03.483600 | localhost -> localhost | ok: Runtime: 0:00:00.006788
2026-04-13 03:15:03.490821 | 
2026-04-13 03:15:03.490951 | TASK [upload-logs : Upload logs to log server]
2026-04-13 03:15:04.036492 | localhost | Output suppressed because no_log was given
2026-04-13 03:15:04.041595 | 
2026-04-13 03:15:04.041696 | LOOP [upload-logs : Compress console log and json output]
2026-04-13 03:15:04.090739 | localhost | skipping: Conditional result was False
2026-04-13 03:15:04.095439 | localhost | skipping: Conditional result was False
2026-04-13 03:15:04.106300 | 
2026-04-13 03:15:04.106462 | LOOP [upload-logs : Upload compressed console log and json output]
2026-04-13 03:15:04.147357 | localhost | skipping: Conditional result was False
2026-04-13 03:15:04.148002 | 
2026-04-13 03:15:04.150592 | localhost | skipping: Conditional result was False
2026-04-13 03:15:04.161043 | 
2026-04-13 03:15:04.161235 | LOOP [upload-logs : Upload console log and json output]