2026-04-30 15:33:28.843416 | Job console starting 2026-04-30 15:33:28.853432 | Updating git repos 2026-04-30 15:33:28.916744 | Cloning repos into workspace 2026-04-30 15:33:28.999216 | Restoring repo states 2026-04-30 15:33:29.027145 | Merging changes 2026-04-30 15:33:30.206681 | Checking out repos 2026-04-30 15:33:30.341266 | Preparing playbooks 2026-04-30 15:33:32.909519 | Running Ansible setup 2026-04-30 15:33:36.513748 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-30 15:33:37.178467 | 2026-04-30 15:33:37.178826 | PLAY [localhost] 2026-04-30 15:33:37.188258 | 2026-04-30 15:33:37.188401 | TASK [Gathering Facts] 2026-04-30 15:33:38.061073 | localhost | ok 2026-04-30 15:33:38.068382 | 2026-04-30 15:33:38.069522 | TASK [Setup log path fact] 2026-04-30 15:33:38.092282 | localhost | ok 2026-04-30 15:33:38.106483 | 2026-04-30 15:33:38.106563 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-30 15:33:38.140758 | localhost | ok 2026-04-30 15:33:38.149389 | 2026-04-30 15:33:38.149512 | TASK [emit-job-header : Print job information] 2026-04-30 15:33:38.191961 | # Job Information 2026-04-30 15:33:38.192216 | Ansible Version: 2.16.18 2026-04-30 15:33:38.192290 | Job: atmosphere-molecule-aio-ovn 2026-04-30 15:33:38.192343 | Pipeline: check 2026-04-30 15:33:38.192389 | Executor: 2d72f0692154 2026-04-30 15:33:38.192435 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3895 2026-04-30 15:33:38.192491 | Event ID: d9b4c2a0-44a9-11f1-9c97-96221c3abe13 2026-04-30 15:33:38.196740 | 2026-04-30 15:33:38.196859 | LOOP [emit-job-header : Print node information] 2026-04-30 15:33:38.297855 | localhost | ok: 2026-04-30 15:33:38.298058 | localhost | # Node Information 2026-04-30 15:33:38.298089 | localhost | Inventory Hostname: instance 2026-04-30 15:33:38.298111 | localhost | Hostname: np0000171383 2026-04-30 15:33:38.298132 | localhost | Username: zuul 2026-04-30 15:33:38.298157 | localhost | Distro: Ubuntu 22.04 2026-04-30 15:33:38.298178 | localhost | Provider: yul1 2026-04-30 15:33:38.298199 | localhost | Region: ca-ymq-1 2026-04-30 15:33:38.298218 | localhost | Label: ubuntu-jammy-16 2026-04-30 15:33:38.298237 | localhost | Product Name: OpenStack Nova 2026-04-30 15:33:38.298256 | localhost | Interface IP: 199.19.213.225 2026-04-30 15:33:38.313976 | 2026-04-30 15:33:38.314186 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-04-30 15:33:38.724468 | localhost -> localhost | changed 2026-04-30 15:33:38.731949 | 2026-04-30 15:33:38.732070 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-04-30 15:33:39.700923 | localhost -> localhost | changed 2026-04-30 15:33:39.712089 | 2026-04-30 15:33:39.712199 | PLAY [all] 2026-04-30 15:33:39.722005 | 2026-04-30 15:33:39.722101 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-04-30 15:33:39.972213 | instance -> localhost | ok 2026-04-30 15:33:39.980572 | 2026-04-30 15:33:39.980675 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-04-30 15:33:40.015173 | instance | ok 2026-04-30 15:33:40.029676 | instance | included: /var/lib/zuul/builds/a765ac8960204fd68bcc029730b0da34/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-04-30 15:33:40.035872 | 2026-04-30 15:33:40.035958 | TASK [add-build-sshkey : Create Temp SSH key] 2026-04-30 15:33:42.151177 | instance -> localhost | Generating public/private rsa key pair. 2026-04-30 15:33:42.151744 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/a765ac8960204fd68bcc029730b0da34/work/a765ac8960204fd68bcc029730b0da34_id_rsa 2026-04-30 15:33:42.151790 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/a765ac8960204fd68bcc029730b0da34/work/a765ac8960204fd68bcc029730b0da34_id_rsa.pub 2026-04-30 15:33:42.151824 | instance -> localhost | The key fingerprint is: 2026-04-30 15:33:42.151855 | instance -> localhost | SHA256:03BV3WqlpLe8KWckt2An31padFXEA1RDNIdzuHmx3VE zuul-build-sshkey 2026-04-30 15:33:42.151901 | instance -> localhost | The key's randomart image is: 2026-04-30 15:33:42.151931 | instance -> localhost | +---[RSA 3072]----+ 2026-04-30 15:33:42.151967 | instance -> localhost | | o+*%E| 2026-04-30 15:33:42.151997 | instance -> localhost | | . =+@| 2026-04-30 15:33:42.152026 | instance -> localhost | | . . o OO| 2026-04-30 15:33:42.152067 | instance -> localhost | | + . B.=| 2026-04-30 15:33:42.152099 | instance -> localhost | | S . + oo| 2026-04-30 15:33:42.152128 | instance -> localhost | | . = *..| 2026-04-30 15:33:42.152155 | instance -> localhost | | . O =o| 2026-04-30 15:33:42.152183 | instance -> localhost | | . B+.| 2026-04-30 15:33:42.152217 | instance -> localhost | | +o. | 2026-04-30 15:33:42.152259 | instance -> localhost | +----[SHA256]-----+ 2026-04-30 15:33:42.152348 | instance -> localhost | ok: Runtime: 0:00:01.603508 2026-04-30 15:33:42.158704 | 2026-04-30 15:33:42.180194 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-04-30 15:33:42.214927 | instance | ok 2026-04-30 15:33:42.229157 | instance | included: /var/lib/zuul/builds/a765ac8960204fd68bcc029730b0da34/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-04-30 15:33:42.259262 | 2026-04-30 15:33:42.259386 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-04-30 15:33:42.285414 | instance | skipping: Conditional result was False 2026-04-30 15:33:42.312038 | 2026-04-30 15:33:42.312214 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-04-30 15:33:42.806594 | instance | changed 2026-04-30 15:33:42.916634 | 2026-04-30 15:33:42.916751 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-04-30 15:33:43.101008 | instance | ok 2026-04-30 15:33:43.111172 | 2026-04-30 15:33:43.111280 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-04-30 15:33:43.640630 | instance | changed 2026-04-30 15:33:43.645533 | 2026-04-30 15:33:43.645597 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-04-30 15:33:44.144545 | instance | changed 2026-04-30 15:33:44.150397 | 2026-04-30 15:33:44.150506 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-04-30 15:33:44.176924 | instance | skipping: Conditional result was False 2026-04-30 15:33:44.188563 | 2026-04-30 15:33:44.188712 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-04-30 15:33:44.571155 | instance -> localhost | changed 2026-04-30 15:33:44.593672 | 2026-04-30 15:33:44.625367 | TASK [add-build-sshkey : Add back temp key] 2026-04-30 15:33:44.948886 | instance -> localhost | Identity added: /var/lib/zuul/builds/a765ac8960204fd68bcc029730b0da34/work/a765ac8960204fd68bcc029730b0da34_id_rsa (zuul-build-sshkey) 2026-04-30 15:33:44.949412 | instance -> localhost | ok: Runtime: 0:00:00.013395 2026-04-30 15:33:44.954518 | 2026-04-30 15:33:44.954582 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-04-30 15:33:45.222925 | instance | ok 2026-04-30 15:33:45.229915 | 2026-04-30 15:33:45.230469 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-04-30 15:33:45.255394 | instance | skipping: Conditional result was False 2026-04-30 15:33:45.278640 | 2026-04-30 15:33:45.297026 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-04-30 15:33:45.598136 | instance | ok 2026-04-30 15:33:45.605345 | 2026-04-30 15:33:45.605423 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-04-30 15:33:47.323170 | instance | Output suppressed because no_log was given 2026-04-30 15:33:47.336561 | 2026-04-30 15:33:47.336738 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-04-30 15:33:47.523907 | instance | ok: "logs" 2026-04-30 15:33:47.524145 | instance | ok: All items complete 2026-04-30 15:33:47.524182 | 2026-04-30 15:33:47.678045 | instance | ok: "artifacts" 2026-04-30 15:33:47.853151 | instance | ok: "docs" 2026-04-30 15:33:47.868598 | 2026-04-30 15:33:47.868810 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-04-30 15:33:48.062872 | instance | changed: "logs" 2026-04-30 15:33:48.226086 | instance | changed: "artifacts" 2026-04-30 15:33:48.394080 | instance | changed: "docs" 2026-04-30 15:33:48.410243 | 2026-04-30 15:33:48.410376 | PLAY RECAP 2026-04-30 15:33:48.410430 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-04-30 15:33:48.410458 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-30 15:33:48.410482 | 2026-04-30 15:33:48.639740 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-30 15:33:48.650368 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-30 15:33:49.306941 | 2026-04-30 15:33:49.307085 | PLAY [all] 2026-04-30 15:33:49.318692 | 2026-04-30 15:33:49.318781 | TASK [setup-uv : Extract archive] 2026-04-30 15:33:51.926824 | instance | changed 2026-04-30 15:33:51.937745 | 2026-04-30 15:33:51.937856 | TASK [setup-uv : Print version] 2026-04-30 15:33:52.529154 | instance | uv 0.8.13 2026-04-30 15:33:52.481327 | instance | ok: Runtime: 0:00:00.012992 2026-04-30 15:33:52.487339 | 2026-04-30 15:33:52.487395 | PLAY RECAP 2026-04-30 15:33:52.487441 | instance | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-30 15:33:52.487465 | 2026-04-30 15:33:52.682329 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-30 15:33:52.689132 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/pre.yml@main] 2026-04-30 15:33:53.359117 | 2026-04-30 15:33:53.359263 | PLAY [all] 2026-04-30 15:33:53.370580 | 2026-04-30 15:33:53.370677 | TASK [Install "jq" for log collection] 2026-04-30 15:34:04.187585 | instance | changed 2026-04-30 15:34:04.190283 | 2026-04-30 15:34:04.190404 | PLAY RECAP 2026-04-30 15:34:04.190482 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-30 15:34:04.190551 | 2026-04-30 15:34:04.319755 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/pre.yml@main] 2026-04-30 15:34:04.330127 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-30 15:34:05.070448 | 2026-04-30 15:34:05.070583 | PLAY [all] 2026-04-30 15:34:05.082658 | 2026-04-30 15:34:05.082742 | TASK [Copy inventory file for Zuul] 2026-04-30 15:34:05.961034 | instance | changed 2026-04-30 15:34:05.967813 | 2026-04-30 15:34:05.967951 | TASK [Switch "ansible_host" to private IP] 2026-04-30 15:34:06.317899 | instance | changed: 1 replacements made 2026-04-30 15:34:06.323540 | 2026-04-30 15:34:06.323610 | TASK [Run Molecule scenario] 2026-04-30 15:34:06.784438 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3 2026-04-30 15:34:06.784689 | instance | Creating virtual environment at: .venv 2026-04-30 15:34:06.813979 | instance | Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere 2026-04-30 15:34:06.848101 | instance | Downloading openstacksdk (1.7MiB) 2026-04-30 15:34:06.848874 | instance | Downloading kubernetes (1.9MiB) 2026-04-30 15:34:06.849054 | instance | Downloading ansible-core (2.1MiB) 2026-04-30 15:34:06.849624 | instance | Downloading rjsonnet (1.2MiB) 2026-04-30 15:34:06.849779 | instance | Downloading pydantic-core (2.0MiB) 2026-04-30 15:34:06.850720 | instance | Downloading cryptography (4.2MiB) 2026-04-30 15:34:06.850899 | instance | Downloading pygments (1.2MiB) 2026-04-30 15:34:06.855207 | instance | Downloading setuptools (1.1MiB) 2026-04-30 15:34:06.855430 | instance | Downloading netaddr (2.2MiB) 2026-04-30 15:34:07.722952 | instance | Building pyperclip==1.9.0 2026-04-30 15:34:08.188006 | instance | Downloading pygments 2026-04-30 15:34:08.191822 | instance | Downloading setuptools 2026-04-30 15:34:08.194275 | instance | Downloading rjsonnet 2026-04-30 15:34:08.293061 | instance | Downloading pydantic-core 2026-04-30 15:34:08.335045 | instance | Downloading netaddr 2026-04-30 15:34:08.336562 | instance | Downloading kubernetes 2026-04-30 15:34:08.345337 | instance | Downloading openstacksdk 2026-04-30 15:34:08.354355 | instance | Downloading cryptography 2026-04-30 15:34:08.367705 | instance | Downloading ansible-core 2026-04-30 15:34:08.677267 | instance | Built pyperclip==1.9.0 2026-04-30 15:34:08.879220 | instance | Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere 2026-04-30 15:34:08.921874 | instance | Installed 83 packages in 40ms 2026-04-30 15:34:09.587475 | instance | WARNING Molecule scenarios should migrate to 'extensions/molecule' 2026-04-30 15:34:10.274533 | instance | INFO [aio > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-04-30 15:34:10.274635 | instance | INFO [aio > prerun] Performing prerun with role_name_check=0... 2026-04-30 15:34:56.635746 | instance | INFO [aio > dependency] Executing 2026-04-30 15:34:56.635996 | instance | WARNING [aio > dependency] Missing roles requirements file: requirements.yml 2026-04-30 15:34:56.636015 | instance | WARNING [aio > dependency] Missing collections requirements file: collections.yml 2026-04-30 15:34:56.636228 | instance | WARNING [aio > dependency] Executed: 2 missing (Remove from test_sequence to suppress) 2026-04-30 15:34:56.644078 | instance | INFO [aio > cleanup] Executing 2026-04-30 15:34:56.644439 | instance | WARNING [aio > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-04-30 15:34:56.651994 | instance | INFO [aio > destroy] Executing 2026-04-30 15:34:56.652053 | instance | WARNING [aio > destroy] Skipping, '--destroy=never' requested. 2026-04-30 15:34:56.652206 | instance | INFO [aio > destroy] Executed: Successful 2026-04-30 15:34:56.659600 | instance | INFO [aio > syntax] Executing 2026-04-30 15:34:58.876196 | instance | ERROR! We were unable to read either as JSON nor YAML, these are the errors we got from each: 2026-04-30 15:34:58.876340 | instance | JSON: Expecting value: line 1 column 1 (char 0) 2026-04-30 15:34:58.876558 | instance | 2026-04-30 15:34:58.876840 | instance | Syntax Error while loading YAML. 2026-04-30 15:34:58.877162 | instance | mapping values are not allowed in this context. mapping values are not allowed in this context 2026-04-30 15:34:58.877459 | instance | in "", line 17, column 15 2026-04-30 15:34:58.877738 | instance | 2026-04-30 15:34:58.878040 | instance | The error appears to be in '/home/zuul/.ansible/collections/ansible_collections/vexxhost/atmosphere/roles/iscsi/tasks/main.yml': line 17, column 15, but may 2026-04-30 15:34:58.878305 | instance | be elsewhere in the file depending on the exact syntax problem. 2026-04-30 15:34:58.878559 | instance | 2026-04-30 15:34:58.878835 | instance | The offending line appears to be: 2026-04-30 15:34:58.879085 | instance | 2026-04-30 15:34:58.879357 | instance | path: /etc/lvm/lvm.conf 2026-04-30 15:34:58.879624 | instance | regexp: '^\s*global_filter\s*=' 2026-04-30 15:34:58.879891 | instance | ^ here 2026-04-30 15:34:58.976871 | 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-04-30 15:34:58.977049 | instance | ERROR [aio > syntax] Executed: Failed 2026-04-30 15:34:58.977216 | 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-04-30 15:34:59.492873 | instance | ERROR 2026-04-30 15:34:59.493144 | instance | { 2026-04-30 15:34:59.493197 | instance | "delta": "0:00:52.336251", 2026-04-30 15:34:59.493231 | instance | "end": "2026-04-30 15:34:59.055017", 2026-04-30 15:34:59.493262 | instance | "msg": "non-zero return code", 2026-04-30 15:34:59.493292 | instance | "rc": 4, 2026-04-30 15:34:59.493327 | instance | "start": "2026-04-30 15:34:06.718766" 2026-04-30 15:34:59.493357 | instance | } failure 2026-04-30 15:34:59.495912 | 2026-04-30 15:34:59.495973 | PLAY RECAP 2026-04-30 15:34:59.496032 | instance | ok: 2 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-04-30 15:34:59.496066 | 2026-04-30 15:34:59.638299 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-30 15:34:59.648423 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@main] 2026-04-30 15:35:00.374943 | 2026-04-30 15:35:00.375190 | PLAY [all] 2026-04-30 15:35:00.392210 | 2026-04-30 15:35:00.392403 | TASK [gather-host-logs : creating directory for system status] 2026-04-30 15:35:00.752499 | instance | changed 2026-04-30 15:35:00.757942 | 2026-04-30 15:35:00.758025 | TASK [gather-host-logs : Get logs for each host] 2026-04-30 15:35:01.110313 | instance | + systemd-cgls --full --all --no-pager 2026-04-30 15:35:01.120998 | instance | + ip addr 2026-04-30 15:35:01.122931 | instance | + ip route 2026-04-30 15:35:01.124480 | instance | + lsblk 2026-04-30 15:35:01.127301 | instance | + mount 2026-04-30 15:35:01.128561 | instance | + docker images 2026-04-30 15:35:01.128954 | instance | /bin/bash: line 7: docker: command not found 2026-04-30 15:35:01.129158 | instance | + brctl show 2026-04-30 15:35:01.129574 | instance | /bin/bash: line 8: brctl: command not found 2026-04-30 15:35:01.129829 | instance | + ps aux --sort=-%mem 2026-04-30 15:35:01.142801 | instance | + dpkg -l 2026-04-30 15:35:01.155503 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul)) 2026-04-30 15:35:01.155922 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul 2026-04-30 15:35:01.155986 | instance | /bin/bash: line 11: docker: command not found 2026-04-30 15:35:01.156247 | instance | + '[' '!' -z '' ']' 2026-04-30 15:35:01.294841 | instance | ok: Runtime: 0:00:00.049668 2026-04-30 15:35:01.301157 | 2026-04-30 15:35:01.301227 | TASK [gather-host-logs : Downloads logs to executor] 2026-04-30 15:35:02.030749 | instance | changed: 2026-04-30 15:35:02.030963 | instance | created directory /var/lib/zuul/builds/a765ac8960204fd68bcc029730b0da34/work/logs/instance 2026-04-30 15:35:02.031004 | instance | cd+++++++++ system/ 2026-04-30 15:35:02.031034 | instance | >f+++++++++ system/brctl-show.txt 2026-04-30 15:35:02.031062 | instance | >f+++++++++ system/docker-images.txt 2026-04-30 15:35:02.031090 | instance | >f+++++++++ system/ip-addr.txt 2026-04-30 15:35:02.031120 | instance | >f+++++++++ system/ip-route.txt 2026-04-30 15:35:02.031149 | instance | >f+++++++++ system/lsblk.txt 2026-04-30 15:35:02.031177 | instance | >f+++++++++ system/mount.txt 2026-04-30 15:35:02.031210 | instance | >f+++++++++ system/packages.txt 2026-04-30 15:35:02.031238 | instance | >f+++++++++ system/ps.txt 2026-04-30 15:35:02.031266 | instance | >f+++++++++ system/systemd-cgls.txt 2026-04-30 15:35:02.042565 | 2026-04-30 15:35:02.042671 | LOOP [helm-release-status : creating directory for helm release status] 2026-04-30 15:35:02.253352 | instance | changed: "values" 2026-04-30 15:35:02.430178 | instance | changed: "releases" 2026-04-30 15:35:02.449444 | 2026-04-30 15:35:02.449635 | TASK [helm-release-status : Gather get release status for helm charts] 2026-04-30 15:35:02.672374 | instance | /bin/bash: line 3: kubectl: command not found 2026-04-30 15:35:02.991367 | instance | ok: Runtime: 0:00:00.007220 2026-04-30 15:35:03.033890 | 2026-04-30 15:35:03.034053 | TASK [helm-release-status : Downloads logs to executor] 2026-04-30 15:35:03.546440 | instance | changed: 2026-04-30 15:35:03.546662 | instance | cd+++++++++ helm/ 2026-04-30 15:35:03.546702 | instance | cd+++++++++ helm/releases/ 2026-04-30 15:35:03.546732 | instance | cd+++++++++ helm/values/ 2026-04-30 15:35:03.558005 | 2026-04-30 15:35:03.558080 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects] 2026-04-30 15:35:03.782097 | instance | changed 2026-04-30 15:35:03.787948 | 2026-04-30 15:35:03.788022 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects] 2026-04-30 15:35:04.018046 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-30 15:35:04.018337 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-30 15:35:04.024542 | instance | environment: line 1: kubectl: command not found 2026-04-30 15:35:04.025843 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-30 15:35:04.026305 | instance | environment: line 1: kubectl: command not found 2026-04-30 15:35:04.027744 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-30 15:35:04.029936 | instance | environment: line 1: kubectl: command not found 2026-04-30 15:35:04.030202 | instance | environment: line 1: kubectl: command not found 2026-04-30 15:35:04.030935 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-30 15:35:04.032297 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-30 15:35:04.033312 | instance | environment: line 1: kubectl: command not found 2026-04-30 15:35:04.034933 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-30 15:35:04.324501 | instance | ok: Runtime: 0:00:00.029246 2026-04-30 15:35:04.329746 | 2026-04-30 15:35:04.329813 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects] 2026-04-30 15:35:04.538826 | instance | changed 2026-04-30 15:35:04.545962 | 2026-04-30 15:35:04.546065 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects] 2026-04-30 15:35:04.767169 | instance | environment: line 5: kubectl: command not found 2026-04-30 15:35:04.768300 | instance | xargs: xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-30 15:35:04.768324 | instance | warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-30 15:35:04.768774 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-30 15:35:05.081886 | instance | ok: Runtime: 0:00:00.011611 2026-04-30 15:35:05.090191 | 2026-04-30 15:35:05.090281 | TASK [describe-kubernetes-objects : Downloads logs to executor] 2026-04-30 15:35:05.587393 | instance | changed: 2026-04-30 15:35:05.587600 | instance | cd+++++++++ objects/ 2026-04-30 15:35:05.587629 | instance | cd+++++++++ objects/cluster/ 2026-04-30 15:35:05.587650 | instance | cd+++++++++ objects/namespaced/ 2026-04-30 15:35:05.606443 | 2026-04-30 15:35:05.606628 | TASK [gather-pod-logs : creating directory for pod logs] 2026-04-30 15:35:05.823308 | instance | changed 2026-04-30 15:35:05.829944 | 2026-04-30 15:35:05.830016 | TASK [gather-pod-logs : creating directory for failed pod logs] 2026-04-30 15:35:06.040447 | instance | changed 2026-04-30 15:35:06.047894 | 2026-04-30 15:35:06.047997 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)] 2026-04-30 15:35:06.264698 | instance | environment: line 3: kubectl: command not found 2026-04-30 15:35:06.585817 | instance | ok: Runtime: 0:00:00.011675 2026-04-30 15:35:06.592096 | 2026-04-30 15:35:06.592162 | TASK [gather-pod-logs : Downloads pod logs to executor] 2026-04-30 15:35:07.072185 | instance | changed: 2026-04-30 15:35:07.072358 | instance | cd+++++++++ pod-logs/ 2026-04-30 15:35:07.072398 | instance | cd+++++++++ pod-logs/failed-pods/ 2026-04-30 15:35:07.081971 | 2026-04-30 15:35:07.082044 | TASK [gather-prom-metrics : creating directory for helm release descriptions] 2026-04-30 15:35:07.290816 | instance | changed 2026-04-30 15:35:07.296545 | 2026-04-30 15:35:07.296627 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces] 2026-04-30 15:35:07.507768 | instance | /bin/bash: line 2: kubectl: command not found 2026-04-30 15:35:07.834204 | instance | ok: Runtime: 0:00:00.038754 2026-04-30 15:35:07.842759 | 2026-04-30 15:35:07.842929 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr] 2026-04-30 15:35:08.065102 | instance | /bin/bash: line 2: kubectl: command not found 2026-04-30 15:35:08.102761 | instance | ceph-mgr endpoints: 2026-04-30 15:35:08.378557 | instance | ok: Runtime: 0:00:00.044730 2026-04-30 15:35:08.388038 | 2026-04-30 15:35:08.388464 | TASK [gather-prom-metrics : Get metrics from fluentd pods] 2026-04-30 15:35:08.608988 | instance | /bin/bash: line 4: kubectl: command not found 2026-04-30 15:35:08.943971 | instance | ok: Runtime: 0:00:00.041296 2026-04-30 15:35:08.989939 | 2026-04-30 15:35:08.990090 | TASK [gather-prom-metrics : Downloads logs to executor] 2026-04-30 15:35:09.470610 | instance | changed: cd+++++++++ prometheus/ 2026-04-30 15:35:09.481077 | 2026-04-30 15:35:09.481160 | TASK [gather-selenium-data : creating directory for helm release descriptions] 2026-04-30 15:35:09.695639 | instance | changed 2026-04-30 15:35:09.703291 | 2026-04-30 15:35:09.703383 | TASK [gather-selenium-data : Get selenium data] 2026-04-30 15:35:09.915381 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/. 2026-04-30 15:35:09.917062 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory 2026-04-30 15:35:10.250681 | instance | ERROR 2026-04-30 15:35:10.250973 | instance | { 2026-04-30 15:35:10.251023 | instance | "delta": "0:00:00.007839", 2026-04-30 15:35:10.251054 | instance | "end": "2026-04-30 15:35:09.917546", 2026-04-30 15:35:10.251090 | instance | "msg": "non-zero return code", 2026-04-30 15:35:10.251132 | instance | "rc": 1, 2026-04-30 15:35:10.251173 | instance | "start": "2026-04-30 15:35:09.909707" 2026-04-30 15:35:10.251202 | instance | } 2026-04-30 15:35:10.251237 | instance | ERROR: Ignoring Errors 2026-04-30 15:35:10.259070 | 2026-04-30 15:35:10.259208 | TASK [gather-selenium-data : Downloads logs to executor] 2026-04-30 15:35:10.762378 | instance | changed: cd+++++++++ selenium/ 2026-04-30 15:35:10.771177 | 2026-04-30 15:35:10.771290 | PLAY RECAP 2026-04-30 15:35:10.771364 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1 2026-04-30 15:35:10.771402 | 2026-04-30 15:35:10.916148 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@main] 2026-04-30 15:35:10.924409 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-30 15:35:11.571981 | 2026-04-30 15:35:11.572154 | PLAY [all] 2026-04-30 15:35:11.584295 | 2026-04-30 15:35:11.584436 | TASK [fetch-output : Set log path for multiple nodes] 2026-04-30 15:35:11.630084 | instance | skipping: Conditional result was False 2026-04-30 15:35:11.644360 | 2026-04-30 15:35:11.644639 | TASK [fetch-output : Set log path for single node] 2026-04-30 15:35:11.688555 | instance | ok 2026-04-30 15:35:11.695651 | 2026-04-30 15:35:11.695733 | LOOP [fetch-output : Ensure local output dirs] 2026-04-30 15:35:12.106009 | instance -> localhost | ok: "/var/lib/zuul/builds/a765ac8960204fd68bcc029730b0da34/work/logs" 2026-04-30 15:35:12.313674 | instance -> localhost | changed: "/var/lib/zuul/builds/a765ac8960204fd68bcc029730b0da34/work/artifacts" 2026-04-30 15:35:12.571698 | instance -> localhost | changed: "/var/lib/zuul/builds/a765ac8960204fd68bcc029730b0da34/work/docs" 2026-04-30 15:35:12.584501 | 2026-04-30 15:35:12.585138 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-04-30 15:35:13.197475 | instance | changed: .d..t...... ./ 2026-04-30 15:35:13.197804 | instance | changed: All items complete 2026-04-30 15:35:13.197864 | 2026-04-30 15:35:13.652390 | instance | changed: .d..t...... ./ 2026-04-30 15:35:14.111969 | instance | changed: .d..t...... ./ 2026-04-30 15:35:14.137284 | 2026-04-30 15:35:14.137451 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-04-30 15:35:14.635554 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.007086 2026-04-30 15:35:14.882399 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.006353 2026-04-30 15:35:14.904347 | 2026-04-30 15:35:14.904493 | PLAY [all] 2026-04-30 15:35:14.912535 | 2026-04-30 15:35:14.912625 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-04-30 15:35:15.357442 | instance | changed 2026-04-30 15:35:15.368290 | 2026-04-30 15:35:15.368402 | PLAY RECAP 2026-04-30 15:35:15.368455 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-04-30 15:35:15.368478 | 2026-04-30 15:35:15.534153 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-30 15:35:15.546440 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-04-30 15:35:16.195453 | 2026-04-30 15:35:16.195599 | PLAY [localhost] 2026-04-30 15:35:16.206011 | 2026-04-30 15:35:16.206089 | TASK [Generate Zuul manifest] 2026-04-30 15:35:16.228145 | localhost | ok 2026-04-30 15:35:16.244277 | 2026-04-30 15:35:16.244357 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-04-30 15:35:16.624451 | localhost | changed 2026-04-30 15:35:16.637199 | 2026-04-30 15:35:16.637275 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-04-30 15:35:16.663387 | localhost | ok 2026-04-30 15:35:16.673443 | 2026-04-30 15:35:16.673526 | TASK [Upload logs] 2026-04-30 15:35:16.703213 | localhost | ok 2026-04-30 15:35:16.810875 | 2026-04-30 15:35:16.811024 | TASK [Set zuul-log-path fact] 2026-04-30 15:35:16.831906 | localhost | ok 2026-04-30 15:35:16.845408 | 2026-04-30 15:35:16.845474 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-30 15:35:16.878250 | localhost | ok 2026-04-30 15:35:16.886797 | 2026-04-30 15:35:16.886862 | TASK [upload-logs : Create log directories] 2026-04-30 15:35:17.276820 | localhost | changed 2026-04-30 15:35:17.286621 | 2026-04-30 15:35:17.286755 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-04-30 15:35:17.647057 | localhost -> localhost | ok: Runtime: 0:00:00.006263 2026-04-30 15:35:17.655706 | 2026-04-30 15:35:17.655891 | TASK [upload-logs : Upload logs to log server] 2026-04-30 15:35:18.103945 | localhost | Output suppressed because no_log was given 2026-04-30 15:35:18.109496 | 2026-04-30 15:35:18.109589 | LOOP [upload-logs : Compress console log and json output] 2026-04-30 15:35:18.157260 | localhost | skipping: Conditional result was False 2026-04-30 15:35:18.166480 | localhost | skipping: Conditional result was False 2026-04-30 15:35:18.176106 | 2026-04-30 15:35:18.176280 | LOOP [upload-logs : Upload compressed console log and json output] 2026-04-30 15:35:18.218101 | localhost | skipping: Conditional result was False 2026-04-30 15:35:18.218548 | 2026-04-30 15:35:18.222677 | localhost | skipping: Conditional result was False 2026-04-30 15:35:18.240602 | 2026-04-30 15:35:18.240777 | LOOP [upload-logs : Upload console log and json output]