2026-03-26 08:40:05.625564 | Job console starting 2026-03-26 08:40:05.651328 | Updating git repos 2026-03-26 08:40:05.707262 | Cloning repos into workspace 2026-03-26 08:40:05.836986 | Restoring repo states 2026-03-26 08:40:05.873918 | Merging changes 2026-03-26 08:40:06.761299 | Checking out repos 2026-03-26 08:40:07.338803 | Preparing playbooks 2026-03-26 08:40:13.308119 | Running Ansible setup 2026-03-26 08:40:17.140189 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-03-26 08:40:17.767455 | 2026-03-26 08:40:17.767819 | PLAY [localhost] 2026-03-26 08:40:17.775697 | 2026-03-26 08:40:17.775775 | TASK [Gathering Facts] 2026-03-26 08:40:18.728313 | localhost | ok 2026-03-26 08:40:18.739346 | 2026-03-26 08:40:18.739419 | TASK [Setup log path fact] 2026-03-26 08:40:18.760466 | localhost | ok 2026-03-26 08:40:18.771921 | 2026-03-26 08:40:18.772006 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-26 08:40:18.799022 | localhost | ok 2026-03-26 08:40:18.804722 | 2026-03-26 08:40:18.804786 | TASK [emit-job-header : Print job information] 2026-03-26 08:40:18.846367 | # Job Information 2026-03-26 08:40:18.846643 | Ansible Version: 2.16.16 2026-03-26 08:40:18.846713 | Job: atmosphere-molecule-aio-ovn 2026-03-26 08:40:18.846761 | Pipeline: check 2026-03-26 08:40:18.846805 | Executor: 0a8996d2b663 2026-03-26 08:40:18.846848 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3772 2026-03-26 08:40:18.846898 | Event ID: 3f310cf0-28ef-11f1-84ff-e0e0c6971770 2026-03-26 08:40:18.851671 | 2026-03-26 08:40:18.851740 | LOOP [emit-job-header : Print node information] 2026-03-26 08:40:18.945967 | localhost | ok: 2026-03-26 08:40:18.946690 | localhost | # Node Information 2026-03-26 08:40:18.946755 | localhost | Inventory Hostname: instance 2026-03-26 08:40:18.946803 | localhost | Hostname: np0000161037 2026-03-26 08:40:18.946847 | localhost | Username: zuul 2026-03-26 08:40:18.946897 | localhost | Distro: Ubuntu 22.04 2026-03-26 08:40:18.946941 | localhost | Provider: yul1 2026-03-26 08:40:18.946985 | localhost | Region: ca-ymq-1 2026-03-26 08:40:18.947026 | localhost | Label: ubuntu-jammy-16 2026-03-26 08:40:18.947067 | localhost | Product Name: OpenStack Nova 2026-03-26 08:40:18.947108 | localhost | Interface IP: 162.253.55.43 2026-03-26 08:40:18.957759 | 2026-03-26 08:40:18.957926 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-03-26 08:40:19.359152 | localhost -> localhost | changed 2026-03-26 08:40:19.369299 | 2026-03-26 08:40:19.369463 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-03-26 08:40:20.215675 | localhost -> localhost | changed 2026-03-26 08:40:20.224253 | 2026-03-26 08:40:20.224333 | PLAY [all] 2026-03-26 08:40:20.235217 | 2026-03-26 08:40:20.235302 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-03-26 08:40:20.473664 | instance -> localhost | ok 2026-03-26 08:40:20.483226 | 2026-03-26 08:40:20.483322 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-03-26 08:40:20.518691 | instance | ok 2026-03-26 08:40:20.532201 | instance | included: /var/lib/zuul/builds/e9c927c3ad5147d8992e9cee46a43bac/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-03-26 08:40:20.538003 | 2026-03-26 08:40:20.538070 | TASK [add-build-sshkey : Create Temp SSH key] 2026-03-26 08:40:22.112569 | instance -> localhost | Generating public/private rsa key pair. 2026-03-26 08:40:22.112797 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/e9c927c3ad5147d8992e9cee46a43bac/work/e9c927c3ad5147d8992e9cee46a43bac_id_rsa 2026-03-26 08:40:22.112835 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/e9c927c3ad5147d8992e9cee46a43bac/work/e9c927c3ad5147d8992e9cee46a43bac_id_rsa.pub 2026-03-26 08:40:22.112859 | instance -> localhost | The key fingerprint is: 2026-03-26 08:40:22.112880 | instance -> localhost | SHA256:DAfznmKjToJM0AN/o+0g5pp3PlTVy0vHukbg/nTXuVs zuul-build-sshkey 2026-03-26 08:40:22.112920 | instance -> localhost | The key's randomart image is: 2026-03-26 08:40:22.112941 | instance -> localhost | +---[RSA 3072]----+ 2026-03-26 08:40:22.112993 | instance -> localhost | |. o . | 2026-03-26 08:40:22.113018 | instance -> localhost | | + +. . | 2026-03-26 08:40:22.113047 | instance -> localhost | |. + o ..o. o | 2026-03-26 08:40:22.113074 | instance -> localhost | |. = ..=..+ o | 2026-03-26 08:40:22.113094 | instance -> localhost | |.oo ..+.So + | 2026-03-26 08:40:22.113113 | instance -> localhost | |=o o.o o. + ..| 2026-03-26 08:40:22.113133 | instance -> localhost | |.o..+ . .... ..E| 2026-03-26 08:40:22.113155 | instance -> localhost | |...+o ..o. . o| 2026-03-26 08:40:22.113187 | instance -> localhost | |o. oo. o. o.| 2026-03-26 08:40:22.113213 | instance -> localhost | +----[SHA256]-----+ 2026-03-26 08:40:22.113286 | instance -> localhost | ok: Runtime: 0:00:01.177587 2026-03-26 08:40:22.119557 | 2026-03-26 08:40:22.119731 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-03-26 08:40:22.155407 | instance | ok 2026-03-26 08:40:22.166139 | instance | included: /var/lib/zuul/builds/e9c927c3ad5147d8992e9cee46a43bac/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-03-26 08:40:22.175275 | 2026-03-26 08:40:22.175374 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-03-26 08:40:22.200608 | instance | skipping: Conditional result was False 2026-03-26 08:40:22.209172 | 2026-03-26 08:40:22.209276 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-03-26 08:40:22.623414 | instance | changed 2026-03-26 08:40:22.628540 | 2026-03-26 08:40:22.628603 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-03-26 08:40:22.836379 | instance | ok 2026-03-26 08:40:22.843463 | 2026-03-26 08:40:22.843542 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-03-26 08:40:23.302724 | instance | changed 2026-03-26 08:40:23.309914 | 2026-03-26 08:40:23.310013 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-03-26 08:40:23.771725 | instance | changed 2026-03-26 08:40:23.778197 | 2026-03-26 08:40:23.778260 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-03-26 08:40:23.803575 | instance | skipping: Conditional result was False 2026-03-26 08:40:23.814428 | 2026-03-26 08:40:23.814555 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-03-26 08:40:24.175524 | instance -> localhost | changed 2026-03-26 08:40:24.253442 | 2026-03-26 08:40:24.253576 | TASK [add-build-sshkey : Add back temp key] 2026-03-26 08:40:24.529352 | instance -> localhost | Identity added: /var/lib/zuul/builds/e9c927c3ad5147d8992e9cee46a43bac/work/e9c927c3ad5147d8992e9cee46a43bac_id_rsa (zuul-build-sshkey) 2026-03-26 08:40:24.529587 | instance -> localhost | ok: Runtime: 0:00:00.012804 2026-03-26 08:40:24.536455 | 2026-03-26 08:40:24.536565 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-03-26 08:40:24.795709 | instance | ok 2026-03-26 08:40:24.802550 | 2026-03-26 08:40:24.802629 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-03-26 08:40:24.826364 | instance | skipping: Conditional result was False 2026-03-26 08:40:24.840848 | 2026-03-26 08:40:24.840924 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-03-26 08:40:25.125710 | instance | ok 2026-03-26 08:40:25.132846 | 2026-03-26 08:40:25.132921 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-03-26 08:40:26.640740 | instance | Output suppressed because no_log was given 2026-03-26 08:40:26.652261 | 2026-03-26 08:40:26.652323 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-03-26 08:40:26.824712 | instance | ok: "logs" 2026-03-26 08:40:26.825003 | instance | ok: All items complete 2026-03-26 08:40:26.825034 | 2026-03-26 08:40:26.980734 | instance | ok: "artifacts" 2026-03-26 08:40:27.169603 | instance | ok: "docs" 2026-03-26 08:40:27.184701 | 2026-03-26 08:40:27.184853 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-03-26 08:40:27.367978 | instance | changed: "logs" 2026-03-26 08:40:27.520472 | instance | changed: "artifacts" 2026-03-26 08:40:27.674664 | instance | changed: "docs" 2026-03-26 08:40:27.686068 | 2026-03-26 08:40:27.686251 | PLAY RECAP 2026-03-26 08:40:27.686349 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-03-26 08:40:27.686410 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-26 08:40:27.686451 | 2026-03-26 08:40:27.882634 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-03-26 08:40:27.893470 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-03-26 08:40:28.525890 | 2026-03-26 08:40:28.526073 | PLAY [all] 2026-03-26 08:40:28.539065 | 2026-03-26 08:40:28.539190 | TASK [setup-uv : Extract archive] 2026-03-26 08:40:30.752762 | instance | changed 2026-03-26 08:40:30.760113 | 2026-03-26 08:40:30.760221 | TASK [setup-uv : Print version] 2026-03-26 08:40:29.904795 | instance | uv 0.8.13 2026-03-26 08:40:31.307169 | instance | ok: Runtime: 0:00:00.011314 2026-03-26 08:40:31.313418 | 2026-03-26 08:40:31.313472 | PLAY RECAP 2026-03-26 08:40:31.313518 | instance | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-26 08:40:31.313544 | 2026-03-26 08:40:31.554058 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-03-26 08:40:31.564875 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/pre.yml@main] 2026-03-26 08:40:32.154885 | 2026-03-26 08:40:32.155028 | PLAY [all] 2026-03-26 08:40:32.166603 | 2026-03-26 08:40:32.166685 | TASK [Install "jq" for log collection] 2026-03-26 08:47:54.175349 | instance | changed 2026-03-26 08:47:54.178023 | 2026-03-26 08:47:54.178100 | PLAY RECAP 2026-03-26 08:47:54.178164 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-26 08:47:54.178230 | 2026-03-26 08:47:54.281984 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/pre.yml@main] 2026-03-26 08:47:54.291245 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-03-26 08:47:54.902671 | 2026-03-26 08:47:54.902820 | PLAY [all] 2026-03-26 08:47:54.914342 | 2026-03-26 08:47:54.914450 | TASK [Copy inventory file for Zuul] 2026-03-26 08:47:55.752474 | instance | changed 2026-03-26 08:47:56.819370 | 2026-03-26 08:47:56.819546 | TASK [Switch "ansible_host" to private IP] 2026-03-26 08:47:57.137877 | instance | changed: 1 replacements made 2026-03-26 08:47:57.143120 | 2026-03-26 08:47:57.143192 | TASK [Run Molecule scenario] 2026-03-26 08:47:57.527043 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3 2026-03-26 08:47:57.527193 | instance | Creating virtual environment at: .venv 2026-03-26 08:47:57.552323 | instance | Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere 2026-03-26 08:47:57.575230 | instance | Downloading pydantic-core (2.0MiB) 2026-03-26 08:47:57.575717 | instance | Downloading setuptools (1.1MiB) 2026-03-26 08:47:57.575918 | instance | Downloading openstacksdk (1.7MiB) 2026-03-26 08:47:57.576277 | instance | Downloading cryptography (4.2MiB) 2026-03-26 08:47:57.577177 | instance | Downloading kubernetes (1.9MiB) 2026-03-26 08:47:57.579565 | instance | Downloading ansible-core (2.1MiB) 2026-03-26 08:47:57.579953 | instance | Downloading rjsonnet (1.2MiB) 2026-03-26 08:47:57.580377 | instance | Downloading pygments (1.2MiB) 2026-03-26 08:47:57.580597 | instance | Downloading netaddr (2.2MiB) 2026-03-26 08:47:57.862785 | instance | Building pyperclip==1.9.0 2026-03-26 08:47:57.882834 | instance | Downloading rjsonnet 2026-03-26 08:47:57.975549 | instance | Downloading pydantic-core 2026-03-26 08:47:58.019006 | instance | Downloading netaddr 2026-03-26 08:47:58.030168 | instance | Downloading pygments 2026-03-26 08:47:58.042606 | instance | Downloading cryptography 2026-03-26 08:47:58.078704 | instance | Downloading setuptools 2026-03-26 08:47:58.138397 | instance | Downloading kubernetes 2026-03-26 08:47:58.169423 | instance | Downloading ansible-core 2026-03-26 08:47:58.200175 | instance | Downloading openstacksdk 2026-03-26 08:47:58.554017 | instance | Built pyperclip==1.9.0 2026-03-26 08:47:58.724309 | instance | Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere 2026-03-26 08:47:58.764207 | instance | Installed 83 packages in 37ms 2026-03-26 08:47:59.396582 | instance | WARNING Molecule scenarios should migrate to 'extensions/molecule' 2026-03-26 08:47:59.973357 | instance | INFO [aio > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-03-26 08:47:59.973434 | instance | INFO [aio > prerun] Performing prerun with role_name_check=0... 2026-03-26 08:48:40.473586 | instance | INFO [aio > dependency] Executing 2026-03-26 08:48:40.473665 | instance | WARNING [aio > dependency] Missing roles requirements file: requirements.yml 2026-03-26 08:48:40.473882 | instance | WARNING [aio > dependency] Missing collections requirements file: collections.yml 2026-03-26 08:48:40.473994 | instance | WARNING [aio > dependency] Executed: 2 missing (Remove from test_sequence to suppress) 2026-03-26 08:48:40.481622 | instance | INFO [aio > cleanup] Executing 2026-03-26 08:48:40.481908 | instance | WARNING [aio > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-03-26 08:48:40.489453 | instance | INFO [aio > destroy] Executing 2026-03-26 08:48:40.489490 | instance | WARNING [aio > destroy] Skipping, '--destroy=never' requested. 2026-03-26 08:48:40.489576 | instance | INFO [aio > destroy] Executed: Successful 2026-03-26 08:48:40.497496 | instance | INFO [aio > syntax] Executing 2026-03-26 08:48:42.269248 | instance | ERROR! We were unable to read either as JSON nor YAML, these are the errors we got from each: 2026-03-26 08:48:42.269420 | instance | JSON: Expecting value: line 1 column 1 (char 0) 2026-03-26 08:48:42.269627 | instance | 2026-03-26 08:48:42.269861 | instance | Syntax Error while loading YAML. 2026-03-26 08:48:42.270095 | instance | found character that cannot start any token. while scanning for the next token 2026-03-26 08:48:42.270325 | instance | found character that cannot start any token 2026-03-26 08:48:42.270594 | instance | in "", line 30, column 2 2026-03-26 08:48:42.270873 | instance | 2026-03-26 08:48:42.271118 | instance | The error appears to be in '/home/zuul/.ansible/collections/ansible_collections/vexxhost/atmosphere/roles/smartctl_exporter/tasks/main.yml': line 29, column 42, but may 2026-03-26 08:48:42.271348 | instance | be elsewhere in the file depending on the exact syntax problem. 2026-03-26 08:48:42.271565 | instance | 2026-03-26 08:48:42.271792 | instance | The offending line appears to be: 2026-03-26 08:48:42.272012 | instance | 2026-03-26 08:48:42.272321 | instance | - --smartctl.interval=60s 2026-03-26 08:48:42.272460 | instance | ^ here 2026-03-26 08:48:42.272659 | instance | 2026-03-26 08:48:42.272891 | instance | There appears to be both 'k=v' shorthand syntax and YAML in this task. Only one syntax may be used. 2026-03-26 08:48:42.335856 | instance | CRITICAL Ansible return code was 4, command was: ansible-playbook --inventory /home/zuul/.ansible/tmp/molecule.v9Wo.aio/inventory --skip-tags molecule-notest,notest --syntax-check --inventory=/home/zuul/src/github.com/vexxhost/atmosphere/inventory.yaml /home/zuul/src/github.com/vexxhost/atmosphere/molecule/aio/converge.yml 2026-03-26 08:48:42.336005 | instance | ERROR [aio > syntax] Executed: Failed 2026-03-26 08:48:42.336113 | instance | ERROR Ansible return code was 4, command was: ansible-playbook --inventory /home/zuul/.ansible/tmp/molecule.v9Wo.aio/inventory --skip-tags molecule-notest,notest --syntax-check --inventory=/home/zuul/src/github.com/vexxhost/atmosphere/inventory.yaml /home/zuul/src/github.com/vexxhost/atmosphere/molecule/aio/converge.yml 2026-03-26 08:48:42.742581 | instance | ERROR 2026-03-26 08:48:42.742904 | instance | { 2026-03-26 08:48:42.742972 | instance | "delta": "0:00:44.950532", 2026-03-26 08:48:42.743017 | instance | "end": "2026-03-26 08:48:42.415927", 2026-03-26 08:48:42.743058 | instance | "msg": "non-zero return code", 2026-03-26 08:48:42.743098 | instance | "rc": 4, 2026-03-26 08:48:42.743142 | instance | "start": "2026-03-26 08:47:57.465395" 2026-03-26 08:48:42.743181 | instance | } failure 2026-03-26 08:48:42.745467 | 2026-03-26 08:48:42.745515 | PLAY RECAP 2026-03-26 08:48:42.745559 | instance | ok: 2 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-03-26 08:48:42.745581 | 2026-03-26 08:48:42.878928 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-03-26 08:48:42.891217 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@main] 2026-03-26 08:48:43.533958 | 2026-03-26 08:48:43.534078 | PLAY [all] 2026-03-26 08:48:43.548145 | 2026-03-26 08:48:43.548226 | TASK [gather-host-logs : creating directory for system status] 2026-03-26 08:48:43.896212 | instance | changed 2026-03-26 08:48:43.901615 | 2026-03-26 08:48:43.901692 | TASK [gather-host-logs : Get logs for each host] 2026-03-26 08:48:44.227932 | instance | + systemd-cgls --full --all --no-pager 2026-03-26 08:48:44.239450 | instance | + ip addr 2026-03-26 08:48:44.240607 | instance | + ip route 2026-03-26 08:48:44.241604 | instance | + lsblk 2026-03-26 08:48:44.243450 | instance | + mount 2026-03-26 08:48:44.244469 | instance | + docker images 2026-03-26 08:48:44.244703 | instance | /bin/bash: line 7: docker: command not found 2026-03-26 08:48:44.244824 | instance | + brctl show 2026-03-26 08:48:44.245068 | instance | /bin/bash: line 8: brctl: command not found 2026-03-26 08:48:44.245226 | instance | + ps aux --sort=-%mem 2026-03-26 08:48:44.254750 | instance | + dpkg -l 2026-03-26 08:48:44.262253 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul)) 2026-03-26 08:48:44.262837 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul 2026-03-26 08:48:44.262931 | instance | /bin/bash: line 11: docker: command not found 2026-03-26 08:48:44.263270 | instance | + '[' '!' -z '' ']' 2026-03-26 08:48:44.441367 | instance | ok: Runtime: 0:00:00.040162 2026-03-26 08:48:44.451824 | 2026-03-26 08:48:44.451892 | TASK [gather-host-logs : Downloads logs to executor] 2026-03-26 08:48:45.072733 | instance | changed: 2026-03-26 08:48:45.072973 | instance | created directory /var/lib/zuul/builds/e9c927c3ad5147d8992e9cee46a43bac/work/logs/instance 2026-03-26 08:48:45.073029 | instance | cd+++++++++ system/ 2026-03-26 08:48:45.073062 | instance | >f+++++++++ system/brctl-show.txt 2026-03-26 08:48:45.073091 | instance | >f+++++++++ system/docker-images.txt 2026-03-26 08:48:45.073118 | instance | >f+++++++++ system/ip-addr.txt 2026-03-26 08:48:45.073149 | instance | >f+++++++++ system/ip-route.txt 2026-03-26 08:48:45.073178 | instance | >f+++++++++ system/lsblk.txt 2026-03-26 08:48:45.073205 | instance | >f+++++++++ system/mount.txt 2026-03-26 08:48:45.073235 | instance | >f+++++++++ system/packages.txt 2026-03-26 08:48:45.073262 | instance | >f+++++++++ system/ps.txt 2026-03-26 08:48:45.073289 | instance | >f+++++++++ system/systemd-cgls.txt 2026-03-26 08:48:45.089322 | 2026-03-26 08:48:45.089394 | LOOP [helm-release-status : creating directory for helm release status] 2026-03-26 08:48:45.281237 | instance | changed: "values" 2026-03-26 08:48:45.444765 | instance | changed: "releases" 2026-03-26 08:48:45.463829 | 2026-03-26 08:48:45.464016 | TASK [helm-release-status : Gather get release status for helm charts] 2026-03-26 08:48:45.677074 | instance | /bin/bash: line 3: kubectl: command not found 2026-03-26 08:48:46.003321 | instance | ok: Runtime: 0:00:00.004455 2026-03-26 08:48:46.010161 | 2026-03-26 08:48:46.010248 | TASK [helm-release-status : Downloads logs to executor] 2026-03-26 08:48:46.499564 | instance | changed: 2026-03-26 08:48:46.499729 | instance | cd+++++++++ helm/ 2026-03-26 08:48:46.499758 | instance | cd+++++++++ helm/releases/ 2026-03-26 08:48:46.499779 | instance | cd+++++++++ helm/values/ 2026-03-26 08:48:46.508789 | 2026-03-26 08:48:46.508854 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects] 2026-03-26 08:48:46.726134 | instance | changed 2026-03-26 08:48:46.759309 | 2026-03-26 08:48:46.759446 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects] 2026-03-26 08:48:46.960591 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-03-26 08:48:46.960813 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-03-26 08:48:46.965511 | instance | environment: line 1: kubectl: command not found 2026-03-26 08:48:46.967261 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-03-26 08:48:46.968203 | instance | environment: line 1: kubectl: command not found 2026-03-26 08:48:46.969655 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-03-26 08:48:46.970899 | instance | environment: line 1: kubectl: command not found 2026-03-26 08:48:46.972133 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-03-26 08:48:46.972934 | instance | environment: line 1: kubectl: command not found 2026-03-26 08:48:46.974120 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-03-26 08:48:46.975334 | instance | environment: line 1: kubectl: command not found 2026-03-26 08:48:46.976609 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-03-26 08:48:47.295185 | instance | ok: Runtime: 0:00:00.026188 2026-03-26 08:48:47.301613 | 2026-03-26 08:48:47.301675 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects] 2026-03-26 08:48:47.502640 | instance | changed 2026-03-26 08:48:47.509382 | 2026-03-26 08:48:47.509452 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects] 2026-03-26 08:48:47.708369 | instance | environment: line 5: kubectl: command not found 2026-03-26 08:48:47.708722 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-03-26 08:48:47.709193 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-03-26 08:48:47.709516 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-03-26 08:48:48.043389 | instance | ok: Runtime: 0:00:00.008823 2026-03-26 08:48:48.050855 | 2026-03-26 08:48:48.050942 | TASK [describe-kubernetes-objects : Downloads logs to executor] 2026-03-26 08:48:48.531367 | instance | changed: 2026-03-26 08:48:48.531569 | instance | cd+++++++++ objects/ 2026-03-26 08:48:48.531597 | instance | cd+++++++++ objects/cluster/ 2026-03-26 08:48:48.531619 | instance | cd+++++++++ objects/namespaced/ 2026-03-26 08:48:48.540562 | 2026-03-26 08:48:48.540627 | TASK [gather-pod-logs : creating directory for pod logs] 2026-03-26 08:48:48.736540 | instance | changed 2026-03-26 08:48:48.741675 | 2026-03-26 08:48:48.741738 | TASK [gather-pod-logs : creating directory for failed pod logs] 2026-03-26 08:48:48.935527 | instance | changed 2026-03-26 08:48:48.940742 | 2026-03-26 08:48:48.940809 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)] 2026-03-26 08:48:49.153279 | instance | environment: line 3: kubectl: command not found 2026-03-26 08:48:49.477881 | instance | ok: Runtime: 0:00:00.008581 2026-03-26 08:48:49.484705 | 2026-03-26 08:48:49.484790 | TASK [gather-pod-logs : Downloads pod logs to executor] 2026-03-26 08:48:49.988114 | instance | changed: 2026-03-26 08:48:49.988359 | instance | cd+++++++++ pod-logs/ 2026-03-26 08:48:49.988415 | instance | cd+++++++++ pod-logs/failed-pods/ 2026-03-26 08:48:50.000574 | 2026-03-26 08:48:50.000640 | TASK [gather-prom-metrics : creating directory for helm release descriptions] 2026-03-26 08:48:50.197787 | instance | changed 2026-03-26 08:48:50.206553 | 2026-03-26 08:48:50.206670 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces] 2026-03-26 08:48:50.416396 | instance | /bin/bash: line 2: kubectl: command not found 2026-03-26 08:48:50.747609 | instance | ok: Runtime: 0:00:00.040089 2026-03-26 08:48:50.753697 | 2026-03-26 08:48:50.753776 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr] 2026-03-26 08:48:50.951970 | instance | /bin/bash: line 2: kubectl: command not found 2026-03-26 08:48:50.981975 | instance | ceph-mgr endpoints: 2026-03-26 08:48:51.290869 | instance | ok: Runtime: 0:00:00.036859 2026-03-26 08:48:51.297940 | 2026-03-26 08:48:51.298031 | TASK [gather-prom-metrics : Get metrics from fluentd pods] 2026-03-26 08:48:51.502803 | instance | /bin/bash: line 4: kubectl: command not found 2026-03-26 08:48:51.834348 | instance | ok: Runtime: 0:00:00.032761 2026-03-26 08:48:51.841439 | 2026-03-26 08:48:51.841526 | TASK [gather-prom-metrics : Downloads logs to executor] 2026-03-26 08:48:52.380303 | instance | changed: cd+++++++++ prometheus/ 2026-03-26 08:48:52.389858 | 2026-03-26 08:48:52.389927 | TASK [gather-selenium-data : creating directory for helm release descriptions] 2026-03-26 08:48:52.626688 | instance | changed 2026-03-26 08:48:52.631953 | 2026-03-26 08:48:52.632032 | TASK [gather-selenium-data : Get selenium data] 2026-03-26 08:48:52.825892 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/. 2026-03-26 08:48:52.827546 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory 2026-03-26 08:48:53.168063 | instance | ERROR 2026-03-26 08:48:53.168324 | instance | { 2026-03-26 08:48:53.168365 | instance | "delta": "0:00:00.006367", 2026-03-26 08:48:53.168387 | instance | "end": "2026-03-26 08:48:52.827760", 2026-03-26 08:48:53.168406 | instance | "msg": "non-zero return code", 2026-03-26 08:48:53.168424 | instance | "rc": 1, 2026-03-26 08:48:53.168442 | instance | "start": "2026-03-26 08:48:52.821393" 2026-03-26 08:48:53.168459 | instance | } 2026-03-26 08:48:53.168485 | instance | ERROR: Ignoring Errors 2026-03-26 08:48:53.173822 | 2026-03-26 08:48:53.173887 | TASK [gather-selenium-data : Downloads logs to executor] 2026-03-26 08:48:53.662939 | instance | changed: cd+++++++++ selenium/ 2026-03-26 08:48:53.670686 | 2026-03-26 08:48:53.670751 | PLAY RECAP 2026-03-26 08:48:53.670808 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1 2026-03-26 08:48:53.670840 | 2026-03-26 08:48:53.827790 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@main] 2026-03-26 08:48:54.271013 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-03-26 08:48:54.868227 | 2026-03-26 08:48:54.868372 | PLAY [all] 2026-03-26 08:48:54.879522 | 2026-03-26 08:48:54.879615 | TASK [fetch-output : Set log path for multiple nodes] 2026-03-26 08:48:54.924700 | instance | skipping: Conditional result was False 2026-03-26 08:48:54.937152 | 2026-03-26 08:48:54.937251 | TASK [fetch-output : Set log path for single node] 2026-03-26 08:48:54.981821 | instance | ok 2026-03-26 08:48:54.988034 | 2026-03-26 08:48:54.988107 | LOOP [fetch-output : Ensure local output dirs] 2026-03-26 08:48:55.388398 | instance -> localhost | ok: "/var/lib/zuul/builds/e9c927c3ad5147d8992e9cee46a43bac/work/logs" 2026-03-26 08:48:55.594798 | instance -> localhost | changed: "/var/lib/zuul/builds/e9c927c3ad5147d8992e9cee46a43bac/work/artifacts" 2026-03-26 08:48:55.804308 | instance -> localhost | changed: "/var/lib/zuul/builds/e9c927c3ad5147d8992e9cee46a43bac/work/docs" 2026-03-26 08:48:55.829285 | 2026-03-26 08:48:55.829470 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-03-26 08:48:56.438341 | instance | changed: .d..t...... ./ 2026-03-26 08:48:56.438695 | instance | changed: All items complete 2026-03-26 08:48:56.438757 | 2026-03-26 08:48:56.886206 | instance | changed: .d..t...... ./ 2026-03-26 08:48:57.318296 | instance | changed: .d..t...... ./ 2026-03-26 08:48:57.342033 | 2026-03-26 08:48:57.342168 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-03-26 08:48:57.772120 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.006045 2026-03-26 08:48:58.018300 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.009980 2026-03-26 08:48:58.038960 | 2026-03-26 08:48:58.039096 | PLAY [all] 2026-03-26 08:48:58.046618 | 2026-03-26 08:48:58.046680 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-03-26 08:48:58.452950 | instance | changed 2026-03-26 08:48:58.460393 | 2026-03-26 08:48:58.460464 | PLAY RECAP 2026-03-26 08:48:58.460524 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-03-26 08:48:58.460551 | 2026-03-26 08:48:58.583910 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-03-26 08:48:58.596155 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-03-26 08:48:59.179043 | 2026-03-26 08:48:59.179163 | PLAY [localhost] 2026-03-26 08:48:59.188737 | 2026-03-26 08:48:59.188808 | TASK [Generate Zuul manifest] 2026-03-26 08:48:59.220441 | localhost | ok 2026-03-26 08:48:59.235987 | 2026-03-26 08:48:59.236064 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-03-26 08:48:59.593804 | localhost | changed 2026-03-26 08:48:59.604893 | 2026-03-26 08:48:59.604984 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-03-26 08:48:59.636376 | localhost | ok 2026-03-26 08:48:59.645041 | 2026-03-26 08:48:59.645124 | TASK [Upload logs] 2026-03-26 08:48:59.667439 | localhost | ok 2026-03-26 08:48:59.770014 | 2026-03-26 08:48:59.770159 | TASK [Set zuul-log-path fact] 2026-03-26 08:48:59.801786 | localhost | ok 2026-03-26 08:48:59.816004 | 2026-03-26 08:48:59.816070 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-26 08:48:59.847976 | localhost | ok 2026-03-26 08:48:59.858503 | 2026-03-26 08:48:59.858565 | TASK [upload-logs : Create log directories] 2026-03-26 08:49:00.276590 | localhost | changed 2026-03-26 08:49:00.282782 | 2026-03-26 08:49:00.282867 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-03-26 08:49:00.639577 | localhost -> localhost | ok: Runtime: 0:00:00.004900 2026-03-26 08:49:00.644971 | 2026-03-26 08:49:00.645035 | TASK [upload-logs : Upload logs to log server] 2026-03-26 08:49:01.080373 | localhost | Output suppressed because no_log was given 2026-03-26 08:49:01.085159 | 2026-03-26 08:49:01.085246 | LOOP [upload-logs : Compress console log and json output] 2026-03-26 08:49:01.135451 | localhost | skipping: Conditional result was False 2026-03-26 08:49:01.143056 | localhost | skipping: Conditional result was False 2026-03-26 08:49:01.161163 | 2026-03-26 08:49:01.161445 | LOOP [upload-logs : Upload compressed console log and json output] 2026-03-26 08:49:01.213071 | localhost | skipping: Conditional result was False 2026-03-26 08:49:01.213496 | 2026-03-26 08:49:01.217427 | localhost | skipping: Conditional result was False 2026-03-26 08:49:01.227634 | 2026-03-26 08:49:01.227796 | LOOP [upload-logs : Upload console log and json output]