2026-04-13 20:36:31.133099 | Job console starting 2026-04-13 20:36:31.144002 | Updating git repos 2026-04-13 20:36:31.211080 | Cloning repos into workspace 2026-04-13 20:36:31.284409 | Restoring repo states 2026-04-13 20:36:31.305357 | Merging changes 2026-04-13 20:36:32.563691 | Checking out repos 2026-04-13 20:36:32.668694 | Preparing playbooks 2026-04-13 20:37:02.159575 | Running Ansible setup 2026-04-13 20:37:06.399323 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-13 20:37:07.154271 | 2026-04-13 20:37:07.154458 | PLAY [localhost] 2026-04-13 20:37:07.163700 | 2026-04-13 20:37:07.163807 | TASK [Gathering Facts] 2026-04-13 20:37:08.123614 | localhost | ok 2026-04-13 20:37:08.132597 | 2026-04-13 20:37:08.132722 | TASK [Setup log path fact] 2026-04-13 20:37:08.154504 | localhost | ok 2026-04-13 20:37:08.174154 | 2026-04-13 20:37:08.174277 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-13 20:37:08.207982 | localhost | ok 2026-04-13 20:37:08.218396 | 2026-04-13 20:37:08.218549 | TASK [emit-job-header : Print job information] 2026-04-13 20:37:08.246868 | # Job Information 2026-04-13 20:37:08.247030 | Ansible Version: 2.16.16 2026-04-13 20:37:08.247064 | Job: atmosphere-molecule-csi-rbd 2026-04-13 20:37:08.247089 | Pipeline: check 2026-04-13 20:37:08.247111 | Executor: 0a8996d2b663 2026-04-13 20:37:08.247132 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3818 2026-04-13 20:37:08.247157 | Event ID: 5e803620-3778-11f1-8282-f22f713c42c2 2026-04-13 20:37:08.250272 | 2026-04-13 20:37:08.250342 | LOOP [emit-job-header : Print node information] 2026-04-13 20:37:08.354501 | localhost | ok: 2026-04-13 20:37:08.354779 | localhost | # Node Information 2026-04-13 20:37:08.354836 | localhost | Inventory Hostname: instance 2026-04-13 20:37:08.354879 | localhost | Hostname: np0000165700 2026-04-13 20:37:08.354917 | localhost | Username: zuul 2026-04-13 20:37:08.354959 | localhost | Distro: Ubuntu 22.04 2026-04-13 20:37:08.355012 | localhost | Provider: yul1 2026-04-13 20:37:08.355059 | localhost | Region: ca-ymq-1 2026-04-13 20:37:08.355102 | localhost | Label: ubuntu-jammy 2026-04-13 20:37:08.355146 | localhost | Product Name: OpenStack Nova 2026-04-13 20:37:08.355188 | localhost | Interface IP: 199.19.213.163 2026-04-13 20:37:08.366253 | 2026-04-13 20:37:08.366416 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-04-13 20:37:08.796840 | localhost -> localhost | changed 2026-04-13 20:37:08.805582 | 2026-04-13 20:37:08.811675 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-04-13 20:37:09.800861 | localhost -> localhost | changed 2026-04-13 20:37:09.812616 | 2026-04-13 20:37:09.812850 | PLAY [all] 2026-04-13 20:37:09.829154 | 2026-04-13 20:37:09.829440 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-04-13 20:37:10.098284 | instance -> localhost | ok 2026-04-13 20:37:10.108124 | 2026-04-13 20:37:10.108267 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-04-13 20:37:10.141843 | instance | ok 2026-04-13 20:37:10.159439 | instance | included: /var/lib/zuul/builds/873f43b1faf045d19a8f83afa030966c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-04-13 20:37:10.165343 | 2026-04-13 20:37:10.165441 | TASK [add-build-sshkey : Create Temp SSH key] 2026-04-13 20:37:11.134967 | instance -> localhost | Generating public/private rsa key pair. 2026-04-13 20:37:11.135122 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/873f43b1faf045d19a8f83afa030966c/work/873f43b1faf045d19a8f83afa030966c_id_rsa 2026-04-13 20:37:11.135153 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/873f43b1faf045d19a8f83afa030966c/work/873f43b1faf045d19a8f83afa030966c_id_rsa.pub 2026-04-13 20:37:11.135177 | instance -> localhost | The key fingerprint is: 2026-04-13 20:37:11.135199 | instance -> localhost | SHA256:FWcYK97f1B77H2V/3VSMCvX/RBeVUoul4KjKU5i/TQ8 zuul-build-sshkey 2026-04-13 20:37:11.135238 | instance -> localhost | The key's randomart image is: 2026-04-13 20:37:11.135261 | instance -> localhost | +---[RSA 3072]----+ 2026-04-13 20:37:11.135288 | instance -> localhost | | o++ .++| 2026-04-13 20:37:11.135310 | instance -> localhost | | +*.o++o| 2026-04-13 20:37:11.135332 | instance -> localhost | | ..+. o+.=| 2026-04-13 20:37:11.135352 | instance -> localhost | | o..+ . . +o| 2026-04-13 20:37:11.135372 | instance -> localhost | | o oS . . ..B| 2026-04-13 20:37:11.135393 | instance -> localhost | | . + . o =O| 2026-04-13 20:37:11.135414 | instance -> localhost | | + . E . .oB| 2026-04-13 20:37:11.135433 | instance -> localhost | | . + o +| 2026-04-13 20:37:11.135457 | instance -> localhost | | . . . +| 2026-04-13 20:37:11.135477 | instance -> localhost | +----[SHA256]-----+ 2026-04-13 20:37:11.135524 | instance -> localhost | ok: Runtime: 0:00:00.560792 2026-04-13 20:37:11.140452 | 2026-04-13 20:37:11.140515 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-04-13 20:37:11.174235 | instance | ok 2026-04-13 20:37:11.186132 | instance | included: /var/lib/zuul/builds/873f43b1faf045d19a8f83afa030966c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-04-13 20:37:11.194607 | 2026-04-13 20:37:11.194691 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-04-13 20:37:11.238504 | instance | skipping: Conditional result was False 2026-04-13 20:37:11.248547 | 2026-04-13 20:37:11.248640 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-04-13 20:37:11.692400 | instance | changed 2026-04-13 20:37:11.701563 | 2026-04-13 20:37:11.701683 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-04-13 20:37:11.876930 | instance | ok 2026-04-13 20:37:11.897824 | 2026-04-13 20:37:11.897958 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-04-13 20:37:12.381187 | instance | changed 2026-04-13 20:37:12.402149 | 2026-04-13 20:37:12.402273 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-04-13 20:37:12.853718 | instance | changed 2026-04-13 20:37:12.861872 | 2026-04-13 20:37:12.862055 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-04-13 20:37:12.886500 | instance | skipping: Conditional result was False 2026-04-13 20:37:12.898025 | 2026-04-13 20:37:12.898174 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-04-13 20:37:13.550956 | instance -> localhost | changed 2026-04-13 20:37:13.562629 | 2026-04-13 20:37:13.562713 | TASK [add-build-sshkey : Add back temp key] 2026-04-13 20:37:13.883372 | instance -> localhost | Identity added: /var/lib/zuul/builds/873f43b1faf045d19a8f83afa030966c/work/873f43b1faf045d19a8f83afa030966c_id_rsa (zuul-build-sshkey) 2026-04-13 20:37:13.883665 | instance -> localhost | ok: Runtime: 0:00:00.013757 2026-04-13 20:37:13.892703 | 2026-04-13 20:37:13.892814 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-04-13 20:37:14.252163 | instance | ok 2026-04-13 20:37:14.258343 | 2026-04-13 20:37:14.258431 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-04-13 20:37:14.282633 | instance | skipping: Conditional result was False 2026-04-13 20:37:14.316253 | 2026-04-13 20:37:14.317985 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-04-13 20:37:14.669724 | instance | ok 2026-04-13 20:37:14.681917 | 2026-04-13 20:37:14.682038 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-04-13 20:37:16.555313 | instance | Output suppressed because no_log was given 2026-04-13 20:37:16.569813 | 2026-04-13 20:37:16.570965 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-04-13 20:37:16.798575 | instance | ok: "logs" 2026-04-13 20:37:17.067008 | instance | ok: All items complete 2026-04-13 20:37:17.067143 | 2026-04-13 20:37:17.074422 | instance | ok: "artifacts" 2026-04-13 20:37:17.126789 | instance | ok: "docs" 2026-04-13 20:37:17.148093 | 2026-04-13 20:37:17.148257 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-04-13 20:37:17.346092 | instance | changed: "logs" 2026-04-13 20:37:17.509572 | instance | changed: "artifacts" 2026-04-13 20:37:17.661926 | instance | changed: "docs" 2026-04-13 20:37:17.675751 | 2026-04-13 20:37:17.675886 | PLAY RECAP 2026-04-13 20:37:17.675952 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-04-13 20:37:17.675995 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-13 20:37:17.676032 | 2026-04-13 20:37:17.819684 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-13 20:37:17.821621 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-13 20:37:18.468582 | 2026-04-13 20:37:18.468700 | PLAY [all] 2026-04-13 20:37:18.487848 | 2026-04-13 20:37:18.488078 | TASK [setup-uv : Extract archive] 2026-04-13 20:37:20.736672 | instance | changed 2026-04-13 20:37:20.746164 | 2026-04-13 20:37:20.746287 | TASK [setup-uv : Print version] 2026-04-13 20:37:21.136652 | instance | uv 0.8.13 2026-04-13 20:37:21.370865 | instance | ok: Runtime: 0:00:00.013024 2026-04-13 20:37:21.386809 | 2026-04-13 20:37:21.387666 | PLAY RECAP 2026-04-13 20:37:21.388022 | instance | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-13 20:37:21.388159 | 2026-04-13 20:37:21.542895 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-13 20:37:21.545678 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/pre.yml@main] 2026-04-13 20:37:22.265418 | 2026-04-13 20:37:22.265676 | PLAY [all] 2026-04-13 20:37:22.281217 | 2026-04-13 20:37:22.281452 | TASK [Install "jq" for log collection] 2026-04-13 20:37:33.209206 | instance | changed 2026-04-13 20:37:33.217616 | 2026-04-13 20:37:33.217771 | TASK [Install pip3 for Python package management] 2026-04-13 20:37:37.430189 | instance | changed 2026-04-13 20:37:37.438281 | 2026-04-13 20:37:37.438408 | TASK [Install Python "kubernetes" library for kubernetes.core modules] 2026-04-13 20:37:40.523057 | instance | changed 2026-04-13 20:37:40.527476 | 2026-04-13 20:37:40.527605 | PLAY [all] 2026-04-13 20:37:40.536379 | 2026-04-13 20:37:40.536469 | TASK [ensure-go : Check installed go version] 2026-04-13 20:37:41.070102 | instance | ok: ERROR (ignored) 2026-04-13 20:37:41.070478 | instance | { 2026-04-13 20:37:41.070554 | instance | "failed_when_result": false, 2026-04-13 20:37:41.070606 | instance | "msg": "[Errno 2] No such file or directory: b'go'", 2026-04-13 20:37:41.070657 | instance | "rc": 2 2026-04-13 20:37:41.070710 | instance | } 2026-04-13 20:37:41.075528 | 2026-04-13 20:37:41.075598 | TASK [ensure-go : Skip if correct version of go is installed] 2026-04-13 20:37:41.127868 | instance | ok 2026-04-13 20:37:41.135448 | instance | included: /var/lib/zuul/builds/873f43b1faf045d19a8f83afa030966c/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/ensure-go/tasks/install-go.yaml 2026-04-13 20:37:41.140084 | 2026-04-13 20:37:41.140150 | TASK [ensure-go : Create temp directory] 2026-04-13 20:37:41.442205 | instance | changed 2026-04-13 20:37:41.450588 | 2026-04-13 20:37:41.450728 | TASK [ensure-go : Get archive checksum] 2026-04-13 20:37:42.127317 | instance | ok: OK (64 bytes) 2026-04-13 20:37:42.135140 | 2026-04-13 20:37:42.135229 | TASK [ensure-go : Download go archive] 2026-04-13 20:37:43.858303 | instance | changed: OK (78559214 bytes) 2026-04-13 20:37:43.863965 | 2026-04-13 20:37:43.864058 | TASK [ensure-go : Install go] 2026-04-13 20:37:49.706251 | instance | changed 2026-04-13 20:37:49.715820 | 2026-04-13 20:37:49.715925 | PLAY [all] 2026-04-13 20:37:49.721381 | 2026-04-13 20:37:49.721443 | TASK [Build atmosphere binary] 2026-04-13 20:37:49.979582 | instance -> localhost | ERROR 2026-04-13 20:37:49.979844 | instance -> localhost | { 2026-04-13 20:37:49.979895 | instance -> localhost | "delta": null, 2026-04-13 20:37:49.979917 | instance -> localhost | "end": null, 2026-04-13 20:37:49.979936 | instance -> localhost | "msg": "Unable to change directory before execution: [Errno 2] No such file or directory: b'src/github.com/vexxhost/atmosphere'", 2026-04-13 20:37:49.979954 | instance -> localhost | "rc": null, 2026-04-13 20:37:49.979972 | instance -> localhost | "start": null 2026-04-13 20:37:49.979989 | instance -> localhost | } failure 2026-04-13 20:37:49.981335 | 2026-04-13 20:37:49.981382 | PLAY RECAP 2026-04-13 20:37:49.981426 | instance | ok: 9 changed: 6 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-04-13 20:37:49.981447 | 2026-04-13 20:37:50.097600 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/pre.yml@main] 2026-04-13 20:37:50.099716 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@main] 2026-04-13 20:37:50.700787 | 2026-04-13 20:37:50.700922 | PLAY [all] 2026-04-13 20:37:50.715417 | 2026-04-13 20:37:50.715537 | TASK [gather-host-logs : creating directory for system status] 2026-04-13 20:37:51.088894 | instance | changed 2026-04-13 20:37:51.098618 | 2026-04-13 20:37:51.099067 | TASK [gather-host-logs : Get logs for each host] 2026-04-13 20:37:51.456768 | instance | + systemd-cgls --full --all --no-pager 2026-04-13 20:37:51.467943 | instance | + ip addr 2026-04-13 20:37:51.470141 | instance | + ip route 2026-04-13 20:37:51.472013 | instance | + lsblk 2026-04-13 20:37:51.475520 | instance | + mount 2026-04-13 20:37:51.477150 | instance | + docker images 2026-04-13 20:37:51.477401 | instance | /bin/bash: line 7: docker: command not found 2026-04-13 20:37:51.477527 | instance | + brctl show 2026-04-13 20:37:51.477776 | instance | /bin/bash: line 8: brctl: command not found 2026-04-13 20:37:51.477900 | instance | + ps aux --sort=-%mem 2026-04-13 20:37:51.489153 | instance | + dpkg -l 2026-04-13 20:37:51.500493 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul)) 2026-04-13 20:37:51.500745 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul 2026-04-13 20:37:51.500792 | instance | /bin/bash: line 11: docker: command not found 2026-04-13 20:37:51.500968 | instance | + '[' '!' -z '' ']' 2026-04-13 20:37:51.645759 | instance | ok: Runtime: 0:00:00.048064 2026-04-13 20:37:51.654201 | 2026-04-13 20:37:51.654310 | TASK [gather-host-logs : Downloads logs to executor] 2026-04-13 20:37:52.256540 | instance | changed: 2026-04-13 20:37:52.256721 | instance | created directory /var/lib/zuul/builds/873f43b1faf045d19a8f83afa030966c/work/logs/instance 2026-04-13 20:37:52.256763 | instance | cd+++++++++ system/ 2026-04-13 20:37:52.256795 | instance | >f+++++++++ system/brctl-show.txt 2026-04-13 20:37:52.256825 | instance | >f+++++++++ system/docker-images.txt 2026-04-13 20:37:52.256856 | instance | >f+++++++++ system/ip-addr.txt 2026-04-13 20:37:52.256888 | instance | >f+++++++++ system/ip-route.txt 2026-04-13 20:37:52.256919 | instance | >f+++++++++ system/lsblk.txt 2026-04-13 20:37:52.256949 | instance | >f+++++++++ system/mount.txt 2026-04-13 20:37:52.257006 | instance | >f+++++++++ system/packages.txt 2026-04-13 20:37:52.257038 | instance | >f+++++++++ system/ps.txt 2026-04-13 20:37:52.257067 | instance | >f+++++++++ system/systemd-cgls.txt 2026-04-13 20:37:52.269815 | 2026-04-13 20:37:52.269923 | LOOP [helm-release-status : creating directory for helm release status] 2026-04-13 20:37:52.463963 | instance | changed: "values" 2026-04-13 20:37:52.626593 | instance | changed: "releases" 2026-04-13 20:37:52.640604 | 2026-04-13 20:37:52.640778 | TASK [helm-release-status : Gather get release status for helm charts] 2026-04-13 20:37:52.838281 | instance | /bin/bash: line 3: kubectl: command not found 2026-04-13 20:37:53.174526 | instance | ok: Runtime: 0:00:00.004333 2026-04-13 20:37:53.182808 | 2026-04-13 20:37:53.182984 | TASK [helm-release-status : Downloads logs to executor] 2026-04-13 20:37:53.693904 | instance | changed: 2026-04-13 20:37:53.694094 | instance | cd+++++++++ helm/ 2026-04-13 20:37:53.694133 | instance | cd+++++++++ helm/releases/ 2026-04-13 20:37:53.694163 | instance | cd+++++++++ helm/values/ 2026-04-13 20:37:53.706766 | 2026-04-13 20:37:53.706902 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects] 2026-04-13 20:37:53.921410 | instance | changed 2026-04-13 20:37:53.928603 | 2026-04-13 20:37:53.928703 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects] 2026-04-13 20:37:54.138433 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-13 20:37:54.138956 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-13 20:37:54.143306 | instance | environment: line 1: kubectl: command not found 2026-04-13 20:37:54.144064 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-13 20:37:54.145530 | instance | environment: line 1: kubectl: command not found 2026-04-13 20:37:54.146762 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-13 20:37:54.148013 | instance | environment: line 1: kubectl: command not found 2026-04-13 20:37:54.149149 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-13 20:37:54.150713 | instance | environment: line 1: kubectl: command not found 2026-04-13 20:37:54.151433 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-13 20:37:54.153118 | instance | environment: line 1: kubectl: command not found 2026-04-13 20:37:54.153813 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-13 20:37:54.465957 | instance | ok: Runtime: 0:00:00.023639 2026-04-13 20:37:54.473273 | 2026-04-13 20:37:54.473390 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects] 2026-04-13 20:37:54.680195 | instance | changed 2026-04-13 20:37:54.687560 | 2026-04-13 20:37:54.687682 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects] 2026-04-13 20:37:54.938418 | instance | environment: line 5: kubectl: command not found 2026-04-13 20:37:54.939550 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-13 20:37:54.939972 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-13 20:37:54.940010 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-13 20:37:55.256242 | instance | ok: Runtime: 0:00:00.009190 2026-04-13 20:37:55.262413 | 2026-04-13 20:37:55.262481 | TASK [describe-kubernetes-objects : Downloads logs to executor] 2026-04-13 20:37:55.781187 | instance | changed: 2026-04-13 20:37:55.781400 | instance | cd+++++++++ objects/ 2026-04-13 20:37:55.781440 | instance | cd+++++++++ objects/cluster/ 2026-04-13 20:37:55.781469 | instance | cd+++++++++ objects/namespaced/ 2026-04-13 20:37:55.793085 | 2026-04-13 20:37:55.793196 | TASK [gather-pod-logs : creating directory for pod logs] 2026-04-13 20:37:55.989013 | instance | changed 2026-04-13 20:37:55.997102 | 2026-04-13 20:37:55.997178 | TASK [gather-pod-logs : creating directory for failed pod logs] 2026-04-13 20:37:56.206031 | instance | changed 2026-04-13 20:37:56.213941 | 2026-04-13 20:37:56.214088 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)] 2026-04-13 20:37:56.407696 | instance | environment: line 3: kubectl: command not found 2026-04-13 20:37:56.749300 | instance | ok: Runtime: 0:00:00.008194 2026-04-13 20:37:56.757433 | 2026-04-13 20:37:56.757557 | TASK [gather-pod-logs : Downloads pod logs to executor] 2026-04-13 20:37:57.248760 | instance | changed: 2026-04-13 20:37:57.249134 | instance | cd+++++++++ pod-logs/ 2026-04-13 20:37:57.249203 | instance | cd+++++++++ pod-logs/failed-pods/ 2026-04-13 20:37:57.260642 | 2026-04-13 20:37:57.260780 | TASK [gather-prom-metrics : creating directory for helm release descriptions] 2026-04-13 20:37:57.468068 | instance | changed 2026-04-13 20:37:57.477085 | 2026-04-13 20:37:57.477230 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces] 2026-04-13 20:37:57.689013 | instance | /bin/bash: line 2: kubectl: command not found 2026-04-13 20:37:58.018405 | instance | ok: Runtime: 0:00:00.036831 2026-04-13 20:37:58.026050 | 2026-04-13 20:37:58.026197 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr] 2026-04-13 20:37:58.230672 | instance | /bin/bash: line 2: kubectl: command not found 2026-04-13 20:37:58.263557 | instance | ceph-mgr endpoints: 2026-04-13 20:37:58.560825 | instance | ok: Runtime: 0:00:00.041720 2026-04-13 20:37:58.568876 | 2026-04-13 20:37:58.569005 | TASK [gather-prom-metrics : Get metrics from fluentd pods] 2026-04-13 20:37:58.790910 | instance | /bin/bash: line 4: kubectl: command not found 2026-04-13 20:37:59.105437 | instance | ok: Runtime: 0:00:00.035517 2026-04-13 20:37:59.112573 | 2026-04-13 20:37:59.112673 | TASK [gather-prom-metrics : Downloads logs to executor] 2026-04-13 20:37:59.603120 | instance | changed: cd+++++++++ prometheus/ 2026-04-13 20:37:59.612763 | 2026-04-13 20:37:59.612826 | TASK [gather-selenium-data : creating directory for helm release descriptions] 2026-04-13 20:37:59.829926 | instance | changed 2026-04-13 20:37:59.837482 | 2026-04-13 20:37:59.837611 | TASK [gather-selenium-data : Get selenium data] 2026-04-13 20:38:00.048054 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/. 2026-04-13 20:38:00.049569 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory 2026-04-13 20:38:00.375897 | instance | ERROR 2026-04-13 20:38:00.376193 | instance | { 2026-04-13 20:38:00.376264 | instance | "delta": "0:00:00.007031", 2026-04-13 20:38:00.376313 | instance | "end": "2026-04-13 20:38:00.049964", 2026-04-13 20:38:00.376352 | instance | "msg": "non-zero return code", 2026-04-13 20:38:00.376375 | instance | "rc": 1, 2026-04-13 20:38:00.376393 | instance | "start": "2026-04-13 20:38:00.042933" 2026-04-13 20:38:00.376411 | instance | } 2026-04-13 20:38:00.376434 | instance | ERROR: Ignoring Errors 2026-04-13 20:38:00.381527 | 2026-04-13 20:38:00.381613 | TASK [gather-selenium-data : Downloads logs to executor] 2026-04-13 20:38:00.894188 | instance | changed: cd+++++++++ selenium/ 2026-04-13 20:38:00.903271 | 2026-04-13 20:38:00.903388 | PLAY RECAP 2026-04-13 20:38:00.903442 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1 2026-04-13 20:38:00.903472 | 2026-04-13 20:38:01.027861 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@main] 2026-04-13 20:38:01.031570 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-13 20:38:01.643852 | 2026-04-13 20:38:01.643987 | PLAY [all] 2026-04-13 20:38:01.655469 | 2026-04-13 20:38:01.655561 | TASK [fetch-output : Set log path for multiple nodes] 2026-04-13 20:38:01.710138 | instance | skipping: Conditional result was False 2026-04-13 20:38:01.719591 | 2026-04-13 20:38:01.719703 | TASK [fetch-output : Set log path for single node] 2026-04-13 20:38:01.763376 | instance | ok 2026-04-13 20:38:01.771354 | 2026-04-13 20:38:01.771436 | LOOP [fetch-output : Ensure local output dirs] 2026-04-13 20:38:02.151992 | instance -> localhost | ok: "/var/lib/zuul/builds/873f43b1faf045d19a8f83afa030966c/work/logs" 2026-04-13 20:38:02.380084 | instance -> localhost | changed: "/var/lib/zuul/builds/873f43b1faf045d19a8f83afa030966c/work/artifacts" 2026-04-13 20:38:02.610430 | instance -> localhost | changed: "/var/lib/zuul/builds/873f43b1faf045d19a8f83afa030966c/work/docs" 2026-04-13 20:38:02.622687 | 2026-04-13 20:38:02.622863 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-04-13 20:38:03.267970 | instance | changed: .d..t...... ./ 2026-04-13 20:38:03.268179 | instance | changed: All items complete 2026-04-13 20:38:03.268210 | 2026-04-13 20:38:03.730242 | instance | changed: .d..t...... ./ 2026-04-13 20:38:04.170970 | instance | changed: .d..t...... ./ 2026-04-13 20:38:04.198464 | 2026-04-13 20:38:04.198601 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-04-13 20:38:04.607334 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.006516 2026-04-13 20:38:04.854149 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.006461 2026-04-13 20:38:04.869446 | 2026-04-13 20:38:04.869552 | PLAY [all] 2026-04-13 20:38:04.875461 | 2026-04-13 20:38:04.875531 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-04-13 20:38:05.337549 | instance | changed 2026-04-13 20:38:05.346672 | 2026-04-13 20:38:05.346740 | PLAY RECAP 2026-04-13 20:38:05.346870 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-04-13 20:38:05.346917 | 2026-04-13 20:38:05.452507 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-13 20:38:05.456474 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-04-13 20:38:06.067148 | 2026-04-13 20:38:06.067273 | PLAY [localhost] 2026-04-13 20:38:06.077467 | 2026-04-13 20:38:06.077544 | TASK [Generate Zuul manifest] 2026-04-13 20:38:06.100019 | localhost | ok 2026-04-13 20:38:06.113753 | 2026-04-13 20:38:06.113850 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-04-13 20:38:06.440335 | localhost | changed 2026-04-13 20:38:06.451926 | 2026-04-13 20:38:06.452056 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-04-13 20:38:06.482129 | localhost | ok 2026-04-13 20:38:06.493549 | 2026-04-13 20:38:06.493649 | TASK [Upload logs] 2026-04-13 20:38:06.528413 | localhost | ok 2026-04-13 20:38:06.589825 | 2026-04-13 20:38:06.589953 | TASK [Set zuul-log-path fact] 2026-04-13 20:38:06.609365 | localhost | ok 2026-04-13 20:38:06.622579 | 2026-04-13 20:38:06.622659 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-13 20:38:06.653661 | localhost | ok 2026-04-13 20:38:06.661064 | 2026-04-13 20:38:06.661129 | TASK [upload-logs : Create log directories] 2026-04-13 20:38:07.085940 | localhost | changed 2026-04-13 20:38:07.091944 | 2026-04-13 20:38:07.092046 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-04-13 20:38:07.496325 | localhost -> localhost | ok: Runtime: 0:00:00.004027 2026-04-13 20:38:07.502702 | 2026-04-13 20:38:07.502794 | TASK [upload-logs : Upload logs to log server] 2026-04-13 20:38:07.968046 | localhost | Output suppressed because no_log was given 2026-04-13 20:38:07.973578 | 2026-04-13 20:38:07.973736 | LOOP [upload-logs : Compress console log and json output] 2026-04-13 20:38:08.021500 | localhost | skipping: Conditional result was False 2026-04-13 20:38:08.029344 | localhost | skipping: Conditional result was False 2026-04-13 20:38:08.044146 | 2026-04-13 20:38:08.044303 | LOOP [upload-logs : Upload compressed console log and json output] 2026-04-13 20:38:08.091299 | localhost | skipping: Conditional result was False 2026-04-13 20:38:08.091778 | 2026-04-13 20:38:08.098296 | localhost | skipping: Conditional result was False 2026-04-13 20:38:08.103955 | 2026-04-13 20:38:08.104075 | LOOP [upload-logs : Upload console log and json output]