2026-04-13 20:46:58.982985 | Job console starting 2026-04-13 20:46:58.994026 | Updating git repos 2026-04-13 20:46:59.073065 | Cloning repos into workspace 2026-04-13 20:46:59.144006 | Restoring repo states 2026-04-13 20:46:59.165474 | Merging changes 2026-04-13 20:47:00.708502 | Checking out repos 2026-04-13 20:47:00.808263 | Preparing playbooks 2026-04-13 20:47:04.199700 | Running Ansible setup 2026-04-13 20:47:09.866276 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-13 20:47:10.576844 | 2026-04-13 20:47:10.576994 | PLAY [localhost] 2026-04-13 20:47:10.585488 | 2026-04-13 20:47:10.585576 | TASK [Gathering Facts] 2026-04-13 20:47:12.512182 | localhost | ok 2026-04-13 20:47:12.522363 | 2026-04-13 20:47:12.522522 | TASK [Setup log path fact] 2026-04-13 20:47:12.544696 | localhost | ok 2026-04-13 20:47:12.558853 | 2026-04-13 20:47:12.559037 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-13 20:47:12.587878 | localhost | ok 2026-04-13 20:47:12.597770 | 2026-04-13 20:47:12.598030 | TASK [emit-job-header : Print job information] 2026-04-13 20:47:12.638845 | # Job Information 2026-04-13 20:47:12.639101 | Ansible Version: 2.16.16 2026-04-13 20:47:12.639156 | Job: atmosphere-molecule-csi-rbd 2026-04-13 20:47:12.639192 | Pipeline: check 2026-04-13 20:47:12.639224 | Executor: 0a8996d2b663 2026-04-13 20:47:12.639253 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3818 2026-04-13 20:47:12.639286 | Event ID: c9a01c80-3779-11f1-8db7-82665fdb899d 2026-04-13 20:47:12.644211 | 2026-04-13 20:47:12.644341 | LOOP [emit-job-header : Print node information] 2026-04-13 20:47:12.758116 | localhost | ok: 2026-04-13 20:47:12.758352 | localhost | # Node Information 2026-04-13 20:47:12.758392 | localhost | Inventory Hostname: instance 2026-04-13 20:47:12.758422 | localhost | Hostname: np0000165764 2026-04-13 20:47:12.758452 | localhost | Username: zuul 2026-04-13 20:47:12.758486 | localhost | Distro: Ubuntu 22.04 2026-04-13 20:47:12.758517 | localhost | Provider: yul1 2026-04-13 20:47:12.758547 | localhost | Region: ca-ymq-1 2026-04-13 20:47:12.758578 | localhost | Label: ubuntu-jammy 2026-04-13 20:47:12.758608 | localhost | Product Name: OpenStack Nova 2026-04-13 20:47:12.758636 | localhost | Interface IP: 199.19.213.195 2026-04-13 20:47:12.770740 | 2026-04-13 20:47:12.770897 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-04-13 20:47:13.233936 | localhost -> localhost | changed 2026-04-13 20:47:13.243554 | 2026-04-13 20:47:13.243752 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-04-13 20:47:14.134466 | localhost -> localhost | changed 2026-04-13 20:47:14.140855 | 2026-04-13 20:47:14.140933 | PLAY [all] 2026-04-13 20:47:14.150662 | 2026-04-13 20:47:14.150729 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-04-13 20:47:14.438389 | instance -> localhost | ok 2026-04-13 20:47:14.447420 | 2026-04-13 20:47:14.447514 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-04-13 20:47:14.478786 | instance | ok 2026-04-13 20:47:14.494105 | instance | included: /var/lib/zuul/builds/d3c504fa37bb480d88f91e8a6acd4f0d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-04-13 20:47:14.500149 | 2026-04-13 20:47:14.500216 | TASK [add-build-sshkey : Create Temp SSH key] 2026-04-13 20:47:15.274353 | instance -> localhost | Generating public/private rsa key pair. 2026-04-13 20:47:15.274588 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/d3c504fa37bb480d88f91e8a6acd4f0d/work/d3c504fa37bb480d88f91e8a6acd4f0d_id_rsa 2026-04-13 20:47:15.274636 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/d3c504fa37bb480d88f91e8a6acd4f0d/work/d3c504fa37bb480d88f91e8a6acd4f0d_id_rsa.pub 2026-04-13 20:47:15.274676 | instance -> localhost | The key fingerprint is: 2026-04-13 20:47:15.274713 | instance -> localhost | SHA256:uQOvexZaeb90EKbVCuFmaR0ux0RxTaVNirR6vvWwGWQ zuul-build-sshkey 2026-04-13 20:47:15.274776 | instance -> localhost | The key's randomart image is: 2026-04-13 20:47:15.274815 | instance -> localhost | +---[RSA 3072]----+ 2026-04-13 20:47:15.274859 | instance -> localhost | | ..*..o=| 2026-04-13 20:47:15.274895 | instance -> localhost | | . O * =.| 2026-04-13 20:47:15.274928 | instance -> localhost | | O @ + .| 2026-04-13 20:47:15.274958 | instance -> localhost | | = O o | 2026-04-13 20:47:15.274987 | instance -> localhost | | . S.o + E | 2026-04-13 20:47:15.275019 | instance -> localhost | | o+..o + | 2026-04-13 20:47:15.275050 | instance -> localhost | | o+o .o = | 2026-04-13 20:47:15.275080 | instance -> localhost | | ..o. ..+ * | 2026-04-13 20:47:15.275116 | instance -> localhost | | o+ o.o .| 2026-04-13 20:47:15.275152 | instance -> localhost | +----[SHA256]-----+ 2026-04-13 20:47:15.275232 | instance -> localhost | ok: Runtime: 0:00:00.269404 2026-04-13 20:47:15.283795 | 2026-04-13 20:47:15.283947 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-04-13 20:47:15.315955 | instance | ok 2026-04-13 20:47:15.633465 | instance | included: /var/lib/zuul/builds/d3c504fa37bb480d88f91e8a6acd4f0d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-04-13 20:47:15.641622 | 2026-04-13 20:47:15.641709 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-04-13 20:47:15.668753 | instance | skipping: Conditional result was False 2026-04-13 20:47:15.678409 | 2026-04-13 20:47:15.678500 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-04-13 20:47:16.152119 | instance | changed 2026-04-13 20:47:16.158976 | 2026-04-13 20:47:16.159064 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-04-13 20:47:16.346421 | instance | ok 2026-04-13 20:47:16.352355 | 2026-04-13 20:47:16.352422 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-04-13 20:47:16.846726 | instance | changed 2026-04-13 20:47:16.855837 | 2026-04-13 20:47:16.856130 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-04-13 20:47:17.383910 | instance | changed 2026-04-13 20:47:17.389962 | 2026-04-13 20:47:17.390242 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-04-13 20:47:17.414339 | instance | skipping: Conditional result was False 2026-04-13 20:47:17.425338 | 2026-04-13 20:47:17.425480 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-04-13 20:47:17.873014 | instance -> localhost | changed 2026-04-13 20:47:17.889562 | 2026-04-13 20:47:17.889723 | TASK [add-build-sshkey : Add back temp key] 2026-04-13 20:47:18.201950 | instance -> localhost | Identity added: /var/lib/zuul/builds/d3c504fa37bb480d88f91e8a6acd4f0d/work/d3c504fa37bb480d88f91e8a6acd4f0d_id_rsa (zuul-build-sshkey) 2026-04-13 20:47:18.202124 | instance -> localhost | ok: Runtime: 0:00:00.014638 2026-04-13 20:47:18.209926 | 2026-04-13 20:47:18.209988 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-04-13 20:47:18.534981 | instance | ok 2026-04-13 20:47:18.544208 | 2026-04-13 20:47:18.544381 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-04-13 20:47:18.568582 | instance | skipping: Conditional result was False 2026-04-13 20:47:18.584341 | 2026-04-13 20:47:18.584472 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-04-13 20:47:18.942331 | instance | ok 2026-04-13 20:47:18.950011 | 2026-04-13 20:47:18.950080 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-04-13 20:47:20.659620 | instance | Output suppressed because no_log was given 2026-04-13 20:47:20.674820 | 2026-04-13 20:47:20.675016 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-04-13 20:47:20.861812 | instance | ok: "logs" 2026-04-13 20:47:20.862158 | instance | ok: All items complete 2026-04-13 20:47:20.862232 | 2026-04-13 20:47:21.010848 | instance | ok: "artifacts" 2026-04-13 20:47:21.171278 | instance | ok: "docs" 2026-04-13 20:47:21.186083 | 2026-04-13 20:47:21.186336 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-04-13 20:47:21.371673 | instance | changed: "logs" 2026-04-13 20:47:21.511500 | instance | changed: "artifacts" 2026-04-13 20:47:21.649862 | instance | changed: "docs" 2026-04-13 20:47:21.670496 | 2026-04-13 20:47:21.670617 | PLAY RECAP 2026-04-13 20:47:21.670682 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-04-13 20:47:21.670726 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-13 20:47:21.670757 | 2026-04-13 20:47:21.863351 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-13 20:47:21.866344 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-13 20:47:22.555274 | 2026-04-13 20:47:22.555437 | PLAY [all] 2026-04-13 20:47:22.571360 | 2026-04-13 20:47:22.571545 | TASK [setup-uv : Extract archive] 2026-04-13 20:47:24.746569 | instance | changed 2026-04-13 20:47:24.753386 | 2026-04-13 20:47:24.753511 | TASK [setup-uv : Print version] 2026-04-13 20:47:25.151825 | instance | uv 0.8.13 2026-04-13 20:47:25.334173 | instance | ok: Runtime: 0:00:00.012030 2026-04-13 20:47:25.341316 | 2026-04-13 20:47:25.341536 | PLAY RECAP 2026-04-13 20:47:25.341601 | instance | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-13 20:47:25.341627 | 2026-04-13 20:47:25.504557 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-13 20:47:25.509605 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/pre.yml@main] 2026-04-13 20:47:26.238678 | 2026-04-13 20:47:26.238845 | PLAY [all] 2026-04-13 20:47:26.251783 | 2026-04-13 20:47:26.251925 | TASK [Install "jq" for log collection] 2026-04-13 20:47:36.038890 | instance | changed 2026-04-13 20:47:36.044557 | 2026-04-13 20:47:36.044780 | TASK [Install pip3 for Python package management] 2026-04-13 20:47:40.678403 | instance | changed 2026-04-13 20:47:40.687081 | 2026-04-13 20:47:40.687276 | TASK [Install Python "kubernetes" library for kubernetes.core modules] 2026-04-13 20:47:43.927164 | instance | changed 2026-04-13 20:47:43.930972 | 2026-04-13 20:47:43.931096 | PLAY [all] 2026-04-13 20:47:43.948234 | 2026-04-13 20:47:43.948435 | TASK [ensure-go : Check installed go version] 2026-04-13 20:47:44.488832 | instance | ok: ERROR (ignored) 2026-04-13 20:47:44.489253 | instance | { 2026-04-13 20:47:44.489345 | instance | "failed_when_result": false, 2026-04-13 20:47:44.489404 | instance | "msg": "[Errno 2] No such file or directory: b'go'", 2026-04-13 20:47:44.489456 | instance | "rc": 2 2026-04-13 20:47:44.489517 | instance | } 2026-04-13 20:47:44.495479 | 2026-04-13 20:47:44.495545 | TASK [ensure-go : Skip if correct version of go is installed] 2026-04-13 20:47:44.547889 | instance | ok 2026-04-13 20:47:44.558491 | instance | included: /var/lib/zuul/builds/d3c504fa37bb480d88f91e8a6acd4f0d/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/ensure-go/tasks/install-go.yaml 2026-04-13 20:47:44.564535 | 2026-04-13 20:47:44.564657 | TASK [ensure-go : Create temp directory] 2026-04-13 20:47:44.865596 | instance | changed 2026-04-13 20:47:44.871708 | 2026-04-13 20:47:44.871975 | TASK [ensure-go : Get archive checksum] 2026-04-13 20:47:45.483309 | instance | ok: OK (64 bytes) 2026-04-13 20:47:45.489250 | 2026-04-13 20:47:45.489360 | TASK [ensure-go : Download go archive] 2026-04-13 20:47:47.246537 | instance | changed: OK (78559214 bytes) 2026-04-13 20:47:47.257201 | 2026-04-13 20:47:47.257373 | TASK [ensure-go : Install go] 2026-04-13 20:47:53.073530 | instance | changed 2026-04-13 20:47:53.080872 | 2026-04-13 20:47:53.080985 | PLAY RECAP 2026-04-13 20:47:53.081035 | instance | ok: 9 changed: 6 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-13 20:47:53.081060 | 2026-04-13 20:47:53.200844 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/pre.yml@main] 2026-04-13 20:47:53.203892 | RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/run.yml@main] 2026-04-13 20:47:54.389287 | ERROR! 'molecule_scenario' is undefined. 'molecule_scenario' is undefined 2026-04-13 20:47:54.414749 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/run.yml@main] 2026-04-13 20:47:54.419049 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@main] 2026-04-13 20:47:55.189659 | 2026-04-13 20:47:55.189902 | PLAY [all] 2026-04-13 20:47:55.209522 | 2026-04-13 20:47:55.209745 | TASK [gather-host-logs : creating directory for system status] 2026-04-13 20:47:55.559945 | instance | changed 2026-04-13 20:47:55.577349 | 2026-04-13 20:47:55.577536 | TASK [gather-host-logs : Get logs for each host] 2026-04-13 20:47:55.954886 | instance | + systemd-cgls --full --all --no-pager 2026-04-13 20:47:55.966390 | instance | + ip addr 2026-04-13 20:47:55.968832 | instance | + ip route 2026-04-13 20:47:55.970410 | instance | + lsblk 2026-04-13 20:47:55.972398 | instance | + mount 2026-04-13 20:47:55.973508 | instance | + docker images 2026-04-13 20:47:55.973816 | instance | /bin/bash: line 7: docker: command not found 2026-04-13 20:47:55.973942 | instance | + brctl show 2026-04-13 20:47:55.974203 | instance | /bin/bash: line 8: brctl: command not found 2026-04-13 20:47:55.974334 | instance | + ps aux --sort=-%mem 2026-04-13 20:47:55.984665 | instance | + dpkg -l 2026-04-13 20:47:55.990409 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul)) 2026-04-13 20:47:55.990640 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul 2026-04-13 20:47:55.990694 | instance | /bin/bash: line 11: docker: command not found 2026-04-13 20:47:55.990865 | instance | + '[' '!' -z '' ']' 2026-04-13 20:47:56.119724 | instance | ok: Runtime: 0:00:00.041115 2026-04-13 20:47:56.128719 | 2026-04-13 20:47:56.128813 | TASK [gather-host-logs : Downloads logs to executor] 2026-04-13 20:47:56.873873 | instance | changed: 2026-04-13 20:47:56.874035 | instance | created directory /var/lib/zuul/builds/d3c504fa37bb480d88f91e8a6acd4f0d/work/logs/instance 2026-04-13 20:47:56.874065 | instance | cd+++++++++ system/ 2026-04-13 20:47:56.874088 | instance | >f+++++++++ system/brctl-show.txt 2026-04-13 20:47:56.874161 | instance | >f+++++++++ system/docker-images.txt 2026-04-13 20:47:56.874202 | instance | >f+++++++++ system/ip-addr.txt 2026-04-13 20:47:56.874243 | instance | >f+++++++++ system/ip-route.txt 2026-04-13 20:47:56.874267 | instance | >f+++++++++ system/lsblk.txt 2026-04-13 20:47:56.874287 | instance | >f+++++++++ system/mount.txt 2026-04-13 20:47:56.874309 | instance | >f+++++++++ system/packages.txt 2026-04-13 20:47:56.874328 | instance | >f+++++++++ system/ps.txt 2026-04-13 20:47:56.874346 | instance | >f+++++++++ system/systemd-cgls.txt 2026-04-13 20:47:56.884474 | 2026-04-13 20:47:56.884582 | LOOP [helm-release-status : creating directory for helm release status] 2026-04-13 20:47:57.074980 | instance | changed: "values" 2026-04-13 20:47:57.285603 | instance | changed: "releases" 2026-04-13 20:47:57.301858 | 2026-04-13 20:47:57.302035 | TASK [helm-release-status : Gather get release status for helm charts] 2026-04-13 20:47:57.503601 | instance | /bin/bash: line 3: kubectl: command not found 2026-04-13 20:47:57.837046 | instance | ok: Runtime: 0:00:00.005014 2026-04-13 20:47:57.842898 | 2026-04-13 20:47:57.843001 | TASK [helm-release-status : Downloads logs to executor] 2026-04-13 20:47:58.341940 | instance | changed: 2026-04-13 20:47:58.342295 | instance | cd+++++++++ helm/ 2026-04-13 20:47:58.342332 | instance | cd+++++++++ helm/releases/ 2026-04-13 20:47:58.342361 | instance | cd+++++++++ helm/values/ 2026-04-13 20:47:58.353332 | 2026-04-13 20:47:58.353420 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects] 2026-04-13 20:47:58.543268 | instance | changed 2026-04-13 20:47:58.548590 | 2026-04-13 20:47:58.548671 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects] 2026-04-13 20:47:58.772260 | instance | xargs: xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args valuewarning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-13 20:47:58.772474 | instance | 2026-04-13 20:47:58.778549 | instance | environment: line 1: kubectl: command not found 2026-04-13 20:47:58.779473 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-13 20:47:58.781146 | instance | environment: line 1: kubectl: command not found 2026-04-13 20:47:58.782143 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-13 20:47:58.783069 | instance | environment: line 1: kubectl: command not found 2026-04-13 20:47:58.784497 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-13 20:47:58.784895 | instance | environment: line 1: kubectl: command not found 2026-04-13 20:47:58.786553 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-13 20:47:58.787242 | instance | environment: line 1: kubectl: command not found 2026-04-13 20:47:58.788263 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-13 20:47:59.083741 | instance | ok: Runtime: 0:00:00.026027 2026-04-13 20:47:59.090393 | 2026-04-13 20:47:59.090529 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects] 2026-04-13 20:47:59.300609 | instance | changed 2026-04-13 20:47:59.382460 | 2026-04-13 20:47:59.382661 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects] 2026-04-13 20:47:59.643759 | instance | environment: line 5: kubectl: command not found 2026-04-13 20:47:59.644541 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-13 20:47:59.644838 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-13 20:47:59.644889 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-13 20:47:59.982395 | instance | ok: Runtime: 0:00:00.008006 2026-04-13 20:47:59.988778 | 2026-04-13 20:47:59.988878 | TASK [describe-kubernetes-objects : Downloads logs to executor] 2026-04-13 20:48:00.484555 | instance | changed: 2026-04-13 20:48:00.484863 | instance | cd+++++++++ objects/ 2026-04-13 20:48:00.484912 | instance | cd+++++++++ objects/cluster/ 2026-04-13 20:48:00.484946 | instance | cd+++++++++ objects/namespaced/ 2026-04-13 20:48:00.497293 | 2026-04-13 20:48:00.497428 | TASK [gather-pod-logs : creating directory for pod logs] 2026-04-13 20:48:00.696019 | instance | changed 2026-04-13 20:48:00.703790 | 2026-04-13 20:48:00.703943 | TASK [gather-pod-logs : creating directory for failed pod logs] 2026-04-13 20:48:00.892526 | instance | changed 2026-04-13 20:48:00.898259 | 2026-04-13 20:48:00.898407 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)] 2026-04-13 20:48:01.103077 | instance | environment: line 3: kubectl: command not found 2026-04-13 20:48:01.439163 | instance | ok: Runtime: 0:00:00.007465 2026-04-13 20:48:01.445586 | 2026-04-13 20:48:01.445704 | TASK [gather-pod-logs : Downloads pod logs to executor] 2026-04-13 20:48:01.914649 | instance | changed: 2026-04-13 20:48:01.914835 | instance | cd+++++++++ pod-logs/ 2026-04-13 20:48:01.914874 | instance | cd+++++++++ pod-logs/failed-pods/ 2026-04-13 20:48:01.926856 | 2026-04-13 20:48:01.926952 | TASK [gather-prom-metrics : creating directory for helm release descriptions] 2026-04-13 20:48:02.123152 | instance | changed 2026-04-13 20:48:02.129302 | 2026-04-13 20:48:02.129444 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces] 2026-04-13 20:48:02.344227 | instance | /bin/bash: line 2: kubectl: command not found 2026-04-13 20:48:02.669324 | instance | ok: Runtime: 0:00:00.035537 2026-04-13 20:48:02.676251 | 2026-04-13 20:48:02.676319 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr] 2026-04-13 20:48:02.877621 | instance | /bin/bash: line 2: kubectl: command not found 2026-04-13 20:48:02.906967 | instance | ceph-mgr endpoints: 2026-04-13 20:48:03.214231 | instance | ok: Runtime: 0:00:00.035787 2026-04-13 20:48:03.221391 | 2026-04-13 20:48:03.221557 | TASK [gather-prom-metrics : Get metrics from fluentd pods] 2026-04-13 20:48:03.430059 | instance | /bin/bash: line 4: kubectl: command not found 2026-04-13 20:48:03.758517 | instance | ok: Runtime: 0:00:00.036401 2026-04-13 20:48:03.766129 | 2026-04-13 20:48:03.766268 | TASK [gather-prom-metrics : Downloads logs to executor] 2026-04-13 20:48:04.450074 | instance | changed: cd+++++++++ prometheus/ 2026-04-13 20:48:04.460694 | 2026-04-13 20:48:04.460810 | TASK [gather-selenium-data : creating directory for helm release descriptions] 2026-04-13 20:48:04.672500 | instance | changed 2026-04-13 20:48:04.680564 | 2026-04-13 20:48:04.680758 | TASK [gather-selenium-data : Get selenium data] 2026-04-13 20:48:04.887259 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/. 2026-04-13 20:48:04.888764 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory 2026-04-13 20:48:05.569619 | instance | ERROR 2026-04-13 20:48:05.569846 | instance | { 2026-04-13 20:48:05.569890 | instance | "delta": "0:00:00.006232", 2026-04-13 20:48:05.569920 | instance | "end": "2026-04-13 20:48:04.889286", 2026-04-13 20:48:05.569946 | instance | "msg": "non-zero return code", 2026-04-13 20:48:05.569971 | instance | "rc": 1, 2026-04-13 20:48:05.569997 | instance | "start": "2026-04-13 20:48:04.883054" 2026-04-13 20:48:05.570021 | instance | } 2026-04-13 20:48:05.570056 | instance | ERROR: Ignoring Errors 2026-04-13 20:48:05.576295 | 2026-04-13 20:48:05.576404 | TASK [gather-selenium-data : Downloads logs to executor] 2026-04-13 20:48:06.115264 | instance | changed: cd+++++++++ selenium/ 2026-04-13 20:48:06.122514 | 2026-04-13 20:48:06.122566 | PLAY RECAP 2026-04-13 20:48:06.122619 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1 2026-04-13 20:48:06.122645 | 2026-04-13 20:48:06.244778 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@main] 2026-04-13 20:48:06.247520 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-13 20:48:06.912269 | 2026-04-13 20:48:06.912407 | PLAY [all] 2026-04-13 20:48:06.967702 | 2026-04-13 20:48:06.967849 | TASK [fetch-output : Set log path for multiple nodes] 2026-04-13 20:48:07.006692 | instance | skipping: Conditional result was False 2026-04-13 20:48:07.016180 | 2026-04-13 20:48:07.016324 | TASK [fetch-output : Set log path for single node] 2026-04-13 20:48:07.059980 | instance | ok 2026-04-13 20:48:07.064784 | 2026-04-13 20:48:07.064857 | LOOP [fetch-output : Ensure local output dirs] 2026-04-13 20:48:07.568585 | instance -> localhost | ok: "/var/lib/zuul/builds/d3c504fa37bb480d88f91e8a6acd4f0d/work/logs" 2026-04-13 20:48:07.798806 | instance -> localhost | changed: "/var/lib/zuul/builds/d3c504fa37bb480d88f91e8a6acd4f0d/work/artifacts" 2026-04-13 20:48:08.046445 | instance -> localhost | changed: "/var/lib/zuul/builds/d3c504fa37bb480d88f91e8a6acd4f0d/work/docs" 2026-04-13 20:48:08.074258 | 2026-04-13 20:48:08.074454 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-04-13 20:48:08.824958 | instance | changed: .d..t...... ./ 2026-04-13 20:48:08.825216 | instance | changed: All items complete 2026-04-13 20:48:08.825256 | 2026-04-13 20:48:09.295815 | instance | changed: .d..t...... ./ 2026-04-13 20:48:09.796818 | instance | changed: .d..t...... ./ 2026-04-13 20:48:09.825200 | 2026-04-13 20:48:09.825387 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-04-13 20:48:10.370436 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.006467 2026-04-13 20:48:10.641540 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.009026 2026-04-13 20:48:10.657019 | 2026-04-13 20:48:10.657133 | PLAY [all] 2026-04-13 20:48:10.664644 | 2026-04-13 20:48:10.664751 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-04-13 20:48:11.104892 | instance | changed 2026-04-13 20:48:11.112365 | 2026-04-13 20:48:11.112418 | PLAY RECAP 2026-04-13 20:48:11.112464 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-04-13 20:48:11.112488 | 2026-04-13 20:48:11.231227 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-13 20:48:11.235851 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-04-13 20:48:11.856256 | 2026-04-13 20:48:11.856379 | PLAY [localhost] 2026-04-13 20:48:11.866671 | 2026-04-13 20:48:11.866830 | TASK [Generate Zuul manifest] 2026-04-13 20:48:11.888667 | localhost | ok 2026-04-13 20:48:11.904330 | 2026-04-13 20:48:11.904450 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-04-13 20:48:12.289241 | localhost | changed 2026-04-13 20:48:12.302467 | 2026-04-13 20:48:12.302580 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-04-13 20:48:12.332748 | localhost | ok 2026-04-13 20:48:12.341484 | 2026-04-13 20:48:12.341563 | TASK [Upload logs] 2026-04-13 20:48:12.364503 | localhost | ok 2026-04-13 20:48:12.426977 | 2026-04-13 20:48:12.427080 | TASK [Set zuul-log-path fact] 2026-04-13 20:48:12.448537 | localhost | ok 2026-04-13 20:48:12.459534 | 2026-04-13 20:48:12.459621 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-13 20:48:12.490303 | localhost | ok 2026-04-13 20:48:12.500038 | 2026-04-13 20:48:12.500161 | TASK [upload-logs : Create log directories] 2026-04-13 20:48:12.914619 | localhost | changed 2026-04-13 20:48:12.922330 | 2026-04-13 20:48:12.922469 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-04-13 20:48:13.412287 | localhost -> localhost | ok: Runtime: 0:00:00.006527 2026-04-13 20:48:13.421092 | 2026-04-13 20:48:13.421213 | TASK [upload-logs : Upload logs to log server] 2026-04-13 20:48:13.994962 | localhost | Output suppressed because no_log was given 2026-04-13 20:48:14.000916 | 2026-04-13 20:48:14.001120 | LOOP [upload-logs : Compress console log and json output] 2026-04-13 20:48:14.061256 | localhost | skipping: Conditional result was False 2026-04-13 20:48:14.075394 | localhost | skipping: Conditional result was False 2026-04-13 20:48:14.085882 | 2026-04-13 20:48:14.086528 | LOOP [upload-logs : Upload compressed console log and json output] 2026-04-13 20:48:14.132822 | localhost | skipping: Conditional result was False 2026-04-13 20:48:14.133242 | 2026-04-13 20:48:14.138101 | localhost | skipping: Conditional result was False 2026-04-13 20:48:14.152109 | 2026-04-13 20:48:14.152295 | LOOP [upload-logs : Upload console log and json output]