2026-04-13 20:40:10.833856 | Job console starting 2026-04-13 20:40:10.842823 | Updating git repos 2026-04-13 20:40:10.906888 | Cloning repos into workspace 2026-04-13 20:40:24.635636 | Restoring repo states 2026-04-13 20:40:24.655750 | Merging changes 2026-04-13 20:40:26.344467 | Checking out repos 2026-04-13 20:40:34.451551 | Preparing playbooks 2026-04-13 20:40:56.219378 | Running Ansible setup 2026-04-13 20:40:59.876236 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-13 20:41:00.486400 | 2026-04-13 20:41:00.486538 | PLAY [localhost] 2026-04-13 20:41:00.495693 | 2026-04-13 20:41:00.495813 | TASK [Gathering Facts] 2026-04-13 20:41:01.383137 | localhost | ok 2026-04-13 20:41:01.393967 | 2026-04-13 20:41:01.394101 | TASK [Setup log path fact] 2026-04-13 20:41:01.413672 | localhost | ok 2026-04-13 20:41:01.426164 | 2026-04-13 20:41:01.426291 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-13 20:41:01.455517 | localhost | ok 2026-04-13 20:41:01.464859 | 2026-04-13 20:41:01.465023 | TASK [emit-job-header : Print job information] 2026-04-13 20:41:01.505502 | # Job Information 2026-04-13 20:41:01.505743 | Ansible Version: 2.16.16 2026-04-13 20:41:01.505811 | Job: atmosphere-molecule-csi-rbd 2026-04-13 20:41:01.505859 | Pipeline: check 2026-04-13 20:41:01.505903 | Executor: 0a8996d2b663 2026-04-13 20:41:01.505946 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3818 2026-04-13 20:41:01.505995 | Event ID: af35ae10-3778-11f1-8810-704e89e38670 2026-04-13 20:41:01.510078 | 2026-04-13 20:41:01.510182 | LOOP [emit-job-header : Print node information] 2026-04-13 20:41:01.603640 | localhost | ok: 2026-04-13 20:41:01.603825 | localhost | # Node Information 2026-04-13 20:41:01.603889 | localhost | Inventory Hostname: instance 2026-04-13 20:41:01.603947 | localhost | Hostname: np0000165702 2026-04-13 20:41:01.603987 | localhost | Username: zuul 2026-04-13 20:41:01.604031 | localhost | Distro: Ubuntu 22.04 2026-04-13 20:41:01.604060 | localhost | Provider: yul1 2026-04-13 20:41:01.604086 | localhost | Region: ca-ymq-1 2026-04-13 20:41:01.604112 | localhost | Label: ubuntu-jammy 2026-04-13 20:41:01.604137 | localhost | Product Name: OpenStack Nova 2026-04-13 20:41:01.604162 | localhost | Interface IP: 199.204.45.57 2026-04-13 20:41:01.614956 | 2026-04-13 20:41:01.615107 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-04-13 20:41:01.964077 | localhost -> localhost | changed 2026-04-13 20:41:01.973772 | 2026-04-13 20:41:01.973955 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-04-13 20:41:02.819183 | localhost -> localhost | changed 2026-04-13 20:41:02.828483 | 2026-04-13 20:41:02.828624 | PLAY [all] 2026-04-13 20:41:02.839051 | 2026-04-13 20:41:02.839159 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-04-13 20:41:03.075952 | instance -> localhost | ok 2026-04-13 20:41:03.085390 | 2026-04-13 20:41:03.085516 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-04-13 20:41:03.117814 | instance | ok 2026-04-13 20:41:03.133207 | instance | included: /var/lib/zuul/builds/3a56ff007dee492696f313f507c23547/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-04-13 20:41:03.138922 | 2026-04-13 20:41:03.139015 | TASK [add-build-sshkey : Create Temp SSH key] 2026-04-13 20:41:04.026070 | instance -> localhost | Generating public/private rsa key pair. 2026-04-13 20:41:04.026276 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/3a56ff007dee492696f313f507c23547/work/3a56ff007dee492696f313f507c23547_id_rsa 2026-04-13 20:41:04.026305 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/3a56ff007dee492696f313f507c23547/work/3a56ff007dee492696f313f507c23547_id_rsa.pub 2026-04-13 20:41:04.026328 | instance -> localhost | The key fingerprint is: 2026-04-13 20:41:04.026348 | instance -> localhost | SHA256:fiUGfRH4oS+SCrkoCMcl4Gwi1Z1bDR3vkdfdKLGEKmg zuul-build-sshkey 2026-04-13 20:41:04.026382 | instance -> localhost | The key's randomart image is: 2026-04-13 20:41:04.026403 | instance -> localhost | +---[RSA 3072]----+ 2026-04-13 20:41:04.026567 | instance -> localhost | | . . ..+.+=. | 2026-04-13 20:41:04.026602 | instance -> localhost | |. . . o ..=o.= oo| 2026-04-13 20:41:04.026624 | instance -> localhost | |oo . o...oB.o +| 2026-04-13 20:41:04.026644 | instance -> localhost | |o+. E o ...o.+ | 2026-04-13 20:41:04.026664 | instance -> localhost | |+. + . .S.o.o | 2026-04-13 20:41:04.026683 | instance -> localhost | |. o o .o..o. | 2026-04-13 20:41:04.026703 | instance -> localhost | |o. . o ..... | 2026-04-13 20:41:04.026722 | instance -> localhost | |o . . . . | 2026-04-13 20:41:04.026745 | instance -> localhost | | . | 2026-04-13 20:41:04.026914 | instance -> localhost | +----[SHA256]-----+ 2026-04-13 20:41:04.026983 | instance -> localhost | ok: Runtime: 0:00:00.418179 2026-04-13 20:41:04.034726 | 2026-04-13 20:41:04.034868 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-04-13 20:41:04.071222 | instance | ok 2026-04-13 20:41:04.088903 | instance | included: /var/lib/zuul/builds/3a56ff007dee492696f313f507c23547/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-04-13 20:41:04.098398 | 2026-04-13 20:41:04.098543 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-04-13 20:41:04.125339 | instance | skipping: Conditional result was False 2026-04-13 20:41:04.137334 | 2026-04-13 20:41:04.137535 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-04-13 20:41:04.567833 | instance | changed 2026-04-13 20:41:04.572778 | 2026-04-13 20:41:04.572889 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-04-13 20:41:04.767437 | instance | ok 2026-04-13 20:41:04.772411 | 2026-04-13 20:41:04.772489 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-04-13 20:41:05.276200 | instance | changed 2026-04-13 20:41:05.283298 | 2026-04-13 20:41:05.283409 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-04-13 20:41:05.761764 | instance | changed 2026-04-13 20:41:05.768228 | 2026-04-13 20:41:05.768320 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-04-13 20:41:05.791990 | instance | skipping: Conditional result was False 2026-04-13 20:41:05.802629 | 2026-04-13 20:41:05.802790 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-04-13 20:41:06.212232 | instance -> localhost | changed 2026-04-13 20:41:06.232259 | 2026-04-13 20:41:06.232556 | TASK [add-build-sshkey : Add back temp key] 2026-04-13 20:41:06.531204 | instance -> localhost | Identity added: /var/lib/zuul/builds/3a56ff007dee492696f313f507c23547/work/3a56ff007dee492696f313f507c23547_id_rsa (zuul-build-sshkey) 2026-04-13 20:41:06.531531 | instance -> localhost | ok: Runtime: 0:00:00.014320 2026-04-13 20:41:06.543040 | 2026-04-13 20:41:06.543298 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-04-13 20:41:06.875830 | instance | ok 2026-04-13 20:41:06.884148 | 2026-04-13 20:41:06.884364 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-04-13 20:41:06.909097 | instance | skipping: Conditional result was False 2026-04-13 20:41:06.927646 | 2026-04-13 20:41:06.927775 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-04-13 20:41:07.261192 | instance | ok 2026-04-13 20:41:07.269944 | 2026-04-13 20:41:07.270041 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-04-13 20:41:08.959046 | instance | Output suppressed because no_log was given 2026-04-13 20:41:08.971257 | 2026-04-13 20:41:08.971363 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-04-13 20:41:09.173056 | instance | ok: "logs" 2026-04-13 20:41:09.173615 | instance | ok: All items complete 2026-04-13 20:41:09.173659 | 2026-04-13 20:41:09.330663 | instance | ok: "artifacts" 2026-04-13 20:41:09.494719 | instance | ok: "docs" 2026-04-13 20:41:09.511469 | 2026-04-13 20:41:09.511827 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-04-13 20:41:09.708416 | instance | changed: "logs" 2026-04-13 20:41:09.868495 | instance | changed: "artifacts" 2026-04-13 20:41:10.039837 | instance | changed: "docs" 2026-04-13 20:41:10.052229 | 2026-04-13 20:41:10.052547 | PLAY RECAP 2026-04-13 20:41:10.052610 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-04-13 20:41:10.052650 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-13 20:41:10.052679 | 2026-04-13 20:41:10.169599 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-13 20:41:10.172155 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-13 20:41:10.840739 | 2026-04-13 20:41:11.889680 | PLAY [all] 2026-04-13 20:41:11.908002 | 2026-04-13 20:41:11.908158 | TASK [setup-uv : Extract archive] 2026-04-13 20:41:14.233998 | instance | changed 2026-04-13 20:41:14.270637 | 2026-04-13 20:41:14.271009 | TASK [setup-uv : Print version] 2026-04-13 20:41:14.684550 | instance | uv 0.8.13 2026-04-13 20:41:14.807797 | instance | ok: Runtime: 0:00:00.010509 2026-04-13 20:41:14.816602 | 2026-04-13 20:41:14.816719 | PLAY RECAP 2026-04-13 20:41:14.816773 | instance | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-13 20:41:14.816798 | 2026-04-13 20:41:14.936423 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-13 20:41:14.939091 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/pre.yml@main] 2026-04-13 20:41:15.556352 | 2026-04-13 20:41:15.556492 | PLAY [all] 2026-04-13 20:41:15.567677 | 2026-04-13 20:41:15.567761 | TASK [Install "jq" for log collection] 2026-04-13 20:41:30.359208 | instance | changed 2026-04-13 20:41:30.367710 | 2026-04-13 20:41:30.367850 | TASK [Install pip3 for Python package management] 2026-04-13 20:41:36.926086 | instance | changed 2026-04-13 20:41:36.931569 | 2026-04-13 20:41:36.931674 | TASK [Install Python "kubernetes" library for kubernetes.core modules] 2026-04-13 20:41:39.934385 | instance | changed 2026-04-13 20:41:39.937810 | 2026-04-13 20:41:39.937893 | PLAY [all] 2026-04-13 20:41:39.950693 | 2026-04-13 20:41:39.954227 | TASK [ensure-go : Check installed go version] 2026-04-13 20:41:40.495584 | instance | ok: ERROR (ignored) 2026-04-13 20:41:40.515738 | instance | { 2026-04-13 20:41:40.515915 | instance | "failed_when_result": false, 2026-04-13 20:41:40.515946 | instance | "msg": "[Errno 2] No such file or directory: b'go'", 2026-04-13 20:41:40.515969 | instance | "rc": 2 2026-04-13 20:41:40.515994 | instance | } 2026-04-13 20:41:40.521635 | 2026-04-13 20:41:40.521725 | TASK [ensure-go : Skip if correct version of go is installed] 2026-04-13 20:41:40.584579 | instance | ok 2026-04-13 20:41:40.594237 | instance | included: /var/lib/zuul/builds/3a56ff007dee492696f313f507c23547/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/ensure-go/tasks/install-go.yaml 2026-04-13 20:41:40.600136 | 2026-04-13 20:41:40.600242 | TASK [ensure-go : Create temp directory] 2026-04-13 20:41:40.954517 | instance | changed 2026-04-13 20:41:40.960149 | 2026-04-13 20:41:40.960264 | TASK [ensure-go : Get archive checksum] 2026-04-13 20:41:41.593270 | instance | ok: OK (64 bytes) 2026-04-13 20:41:41.598637 | 2026-04-13 20:41:41.598702 | TASK [ensure-go : Download go archive] 2026-04-13 20:41:43.126081 | instance | changed: OK (78559214 bytes) 2026-04-13 20:41:43.132889 | 2026-04-13 20:41:43.133001 | TASK [ensure-go : Install go] 2026-04-13 20:41:49.075966 | instance | changed 2026-04-13 20:41:49.085047 | 2026-04-13 20:41:49.085216 | PLAY [all] 2026-04-13 20:41:49.090464 | 2026-04-13 20:41:49.090542 | TASK [Build atmosphere binary] 2026-04-13 20:41:49.363852 | instance -> localhost | ERROR 2026-04-13 20:41:49.364039 | instance -> localhost | { 2026-04-13 20:41:49.364079 | instance -> localhost | "delta": null, 2026-04-13 20:41:49.364102 | instance -> localhost | "end": null, 2026-04-13 20:41:49.364123 | 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:41:49.364143 | instance -> localhost | "rc": null, 2026-04-13 20:41:49.364162 | instance -> localhost | "start": null 2026-04-13 20:41:49.364179 | instance -> localhost | } failure 2026-04-13 20:41:49.365696 | 2026-04-13 20:41:49.365743 | PLAY RECAP 2026-04-13 20:41:49.365789 | instance | ok: 9 changed: 6 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-04-13 20:41:49.365812 | 2026-04-13 20:41:49.473188 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/pre.yml@main] 2026-04-13 20:41:49.475211 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@main] 2026-04-13 20:41:50.185790 | 2026-04-13 20:41:50.185910 | PLAY [all] 2026-04-13 20:41:50.199633 | 2026-04-13 20:41:50.199719 | TASK [gather-host-logs : creating directory for system status] 2026-04-13 20:41:50.539629 | instance | changed 2026-04-13 20:41:50.546965 | 2026-04-13 20:41:50.547144 | TASK [gather-host-logs : Get logs for each host] 2026-04-13 20:41:50.879919 | instance | + systemd-cgls --full --all --no-pager 2026-04-13 20:41:50.892108 | instance | + ip addr 2026-04-13 20:41:50.894012 | instance | + ip route 2026-04-13 20:41:50.895320 | instance | + lsblk 2026-04-13 20:41:50.898961 | instance | + mount 2026-04-13 20:41:50.901196 | instance | + docker images 2026-04-13 20:41:50.901623 | instance | /bin/bash: line 7: docker: command not found 2026-04-13 20:41:50.901860 | instance | + brctl show 2026-04-13 20:41:50.902247 | instance | /bin/bash: line 8: brctl: command not found 2026-04-13 20:41:50.902489 | instance | + ps aux --sort=-%mem 2026-04-13 20:41:50.912620 | instance | + dpkg -l 2026-04-13 20:41:50.918008 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul)) 2026-04-13 20:41:50.918371 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul 2026-04-13 20:41:50.918386 | instance | /bin/bash: line 11: docker: command not found 2026-04-13 20:41:50.918633 | instance | + '[' '!' -z '' ']' 2026-04-13 20:41:51.095128 | instance | ok: Runtime: 0:00:00.043681 2026-04-13 20:41:51.101831 | 2026-04-13 20:41:51.101912 | TASK [gather-host-logs : Downloads logs to executor] 2026-04-13 20:41:51.810716 | instance | changed: 2026-04-13 20:41:51.810907 | instance | created directory /var/lib/zuul/builds/3a56ff007dee492696f313f507c23547/work/logs/instance 2026-04-13 20:41:51.810937 | instance | cd+++++++++ system/ 2026-04-13 20:41:51.810960 | instance | >f+++++++++ system/brctl-show.txt 2026-04-13 20:41:51.810981 | instance | >f+++++++++ system/docker-images.txt 2026-04-13 20:41:51.811001 | instance | >f+++++++++ system/ip-addr.txt 2026-04-13 20:41:51.811028 | instance | >f+++++++++ system/ip-route.txt 2026-04-13 20:41:51.811059 | instance | >f+++++++++ system/lsblk.txt 2026-04-13 20:41:51.811084 | instance | >f+++++++++ system/mount.txt 2026-04-13 20:41:51.811107 | instance | >f+++++++++ system/packages.txt 2026-04-13 20:41:51.811127 | instance | >f+++++++++ system/ps.txt 2026-04-13 20:41:51.811146 | instance | >f+++++++++ system/systemd-cgls.txt 2026-04-13 20:41:51.821366 | 2026-04-13 20:41:51.821509 | LOOP [helm-release-status : creating directory for helm release status] 2026-04-13 20:41:52.029498 | instance | changed: "values" 2026-04-13 20:41:52.214419 | instance | changed: "releases" 2026-04-13 20:41:52.234081 | 2026-04-13 20:41:52.234213 | TASK [helm-release-status : Gather get release status for helm charts] 2026-04-13 20:41:52.436142 | instance | /bin/bash: line 3: kubectl: command not found 2026-04-13 20:41:52.826274 | instance | ok: Runtime: 0:00:00.005799 2026-04-13 20:41:52.834755 | 2026-04-13 20:41:52.834866 | TASK [helm-release-status : Downloads logs to executor] 2026-04-13 20:41:53.336011 | instance | changed: 2026-04-13 20:41:53.336623 | instance | cd+++++++++ helm/ 2026-04-13 20:41:53.336692 | instance | cd+++++++++ helm/releases/ 2026-04-13 20:41:53.336740 | instance | cd+++++++++ helm/values/ 2026-04-13 20:41:53.349970 | 2026-04-13 20:41:53.350051 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects] 2026-04-13 20:41:53.556864 | instance | changed 2026-04-13 20:41:53.563992 | 2026-04-13 20:41:53.564101 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects] 2026-04-13 20:41:53.787040 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-13 20:41:53.787241 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-13 20:41:53.791182 | instance | environment: line 1: kubectl: command not found 2026-04-13 20:41:53.791631 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-13 20:41:53.794284 | instance | environment: line 1: kubectl: command not found 2026-04-13 20:41:53.796045 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-13 20:41:53.797601 | instance | environment: line 1: kubectl: command not found 2026-04-13 20:41:53.798338 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-13 20:41:53.799607 | instance | environment: line 1: kubectl: command not found 2026-04-13 20:41:53.800198 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-13 20:41:53.801865 | instance | environment: line 1: kubectl: command not found 2026-04-13 20:41:53.802928 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-13 20:41:54.103141 | instance | ok: Runtime: 0:00:00.026997 2026-04-13 20:41:54.113156 | 2026-04-13 20:41:54.113257 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects] 2026-04-13 20:41:54.308997 | instance | changed 2026-04-13 20:41:54.315608 | 2026-04-13 20:41:54.315690 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects] 2026-04-13 20:41:54.553312 | instance | environment: line 5: kubectl: command not found 2026-04-13 20:41:54.553769 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-13 20:41:54.554230 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-13 20:41:54.554544 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-13 20:41:54.902590 | instance | ok: Runtime: 0:00:00.010134 2026-04-13 20:41:54.909868 | 2026-04-13 20:41:54.909974 | TASK [describe-kubernetes-objects : Downloads logs to executor] 2026-04-13 20:41:55.413064 | instance | changed: 2026-04-13 20:41:55.413235 | instance | cd+++++++++ objects/ 2026-04-13 20:41:55.413265 | instance | cd+++++++++ objects/cluster/ 2026-04-13 20:41:55.413287 | instance | cd+++++++++ objects/namespaced/ 2026-04-13 20:41:55.423243 | 2026-04-13 20:41:55.423367 | TASK [gather-pod-logs : creating directory for pod logs] 2026-04-13 20:41:55.632508 | instance | changed 2026-04-13 20:41:55.637391 | 2026-04-13 20:41:55.637459 | TASK [gather-pod-logs : creating directory for failed pod logs] 2026-04-13 20:41:55.842523 | instance | changed 2026-04-13 20:41:55.847995 | 2026-04-13 20:41:55.848066 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)] 2026-04-13 20:41:56.060322 | instance | environment: line 3: kubectl: command not found 2026-04-13 20:41:56.394289 | instance | ok: Runtime: 0:00:00.009237 2026-04-13 20:41:56.401666 | 2026-04-13 20:41:56.401850 | TASK [gather-pod-logs : Downloads pod logs to executor] 2026-04-13 20:41:56.931284 | instance | changed: 2026-04-13 20:41:56.931512 | instance | cd+++++++++ pod-logs/ 2026-04-13 20:41:56.931554 | instance | cd+++++++++ pod-logs/failed-pods/ 2026-04-13 20:41:56.943814 | 2026-04-13 20:41:56.943935 | TASK [gather-prom-metrics : creating directory for helm release descriptions] 2026-04-13 20:41:57.151753 | instance | changed 2026-04-13 20:41:57.157921 | 2026-04-13 20:41:57.158025 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces] 2026-04-13 20:41:57.364772 | instance | /bin/bash: line 2: kubectl: command not found 2026-04-13 20:41:57.694782 | instance | ok: Runtime: 0:00:00.038846 2026-04-13 20:41:57.700197 | 2026-04-13 20:41:57.700285 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr] 2026-04-13 20:41:57.909548 | instance | /bin/bash: line 2: kubectl: command not found 2026-04-13 20:41:57.939891 | instance | ceph-mgr endpoints: 2026-04-13 20:41:58.233759 | instance | ok: Runtime: 0:00:00.034920 2026-04-13 20:41:58.240663 | 2026-04-13 20:41:58.240753 | TASK [gather-prom-metrics : Get metrics from fluentd pods] 2026-04-13 20:41:58.485322 | instance | /bin/bash: line 4: kubectl: command not found 2026-04-13 20:41:59.131462 | instance | ok: Runtime: 0:00:00.035015 2026-04-13 20:41:59.139378 | 2026-04-13 20:41:59.139475 | TASK [gather-prom-metrics : Downloads logs to executor] 2026-04-13 20:41:59.805504 | instance | changed: cd+++++++++ prometheus/ 2026-04-13 20:41:59.819317 | 2026-04-13 20:41:59.819428 | TASK [gather-selenium-data : creating directory for helm release descriptions] 2026-04-13 20:42:00.027079 | instance | changed 2026-04-13 20:42:00.426676 | 2026-04-13 20:42:00.426788 | TASK [gather-selenium-data : Get selenium data] 2026-04-13 20:42:00.641573 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/. 2026-04-13 20:42:00.643083 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory 2026-04-13 20:42:00.976534 | instance | ERROR 2026-04-13 20:42:00.976837 | instance | { 2026-04-13 20:42:00.976983 | instance | "delta": "0:00:00.006615", 2026-04-13 20:42:00.977035 | instance | "end": "2026-04-13 20:42:00.643450", 2026-04-13 20:42:00.977065 | instance | "msg": "non-zero return code", 2026-04-13 20:42:00.977093 | instance | "rc": 1, 2026-04-13 20:42:00.977118 | instance | "start": "2026-04-13 20:42:00.636835" 2026-04-13 20:42:00.977143 | instance | } 2026-04-13 20:42:00.977177 | instance | ERROR: Ignoring Errors 2026-04-13 20:42:00.987642 | 2026-04-13 20:42:00.987717 | TASK [gather-selenium-data : Downloads logs to executor] 2026-04-13 20:42:01.631221 | instance | changed: cd+++++++++ selenium/ 2026-04-13 20:42:01.640291 | 2026-04-13 20:42:01.640430 | PLAY RECAP 2026-04-13 20:42:01.640489 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1 2026-04-13 20:42:01.640523 | 2026-04-13 20:42:01.794297 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@main] 2026-04-13 20:42:01.799369 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-13 20:42:02.373039 | 2026-04-13 20:42:02.373469 | PLAY [all] 2026-04-13 20:42:02.386650 | 2026-04-13 20:42:02.386772 | TASK [fetch-output : Set log path for multiple nodes] 2026-04-13 20:42:02.438809 | instance | skipping: Conditional result was False 2026-04-13 20:42:02.449000 | 2026-04-13 20:42:02.449141 | TASK [fetch-output : Set log path for single node] 2026-04-13 20:42:02.495439 | instance | ok 2026-04-13 20:42:02.507844 | 2026-04-13 20:42:02.508017 | LOOP [fetch-output : Ensure local output dirs] 2026-04-13 20:42:02.959167 | instance -> localhost | ok: "/var/lib/zuul/builds/3a56ff007dee492696f313f507c23547/work/logs" 2026-04-13 20:42:03.202232 | instance -> localhost | changed: "/var/lib/zuul/builds/3a56ff007dee492696f313f507c23547/work/artifacts" 2026-04-13 20:42:03.469121 | instance -> localhost | changed: "/var/lib/zuul/builds/3a56ff007dee492696f313f507c23547/work/docs" 2026-04-13 20:42:03.485858 | 2026-04-13 20:42:03.486012 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-04-13 20:42:04.242325 | instance | changed: .d..t...... ./ 2026-04-13 20:42:04.242603 | instance | changed: All items complete 2026-04-13 20:42:04.242654 | 2026-04-13 20:42:04.923858 | instance | changed: .d..t...... ./ 2026-04-13 20:42:05.412674 | instance | changed: .d..t...... ./ 2026-04-13 20:42:05.431122 | 2026-04-13 20:42:05.431235 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-04-13 20:42:06.818396 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.005612 2026-04-13 20:42:07.042551 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.007531 2026-04-13 20:42:07.064754 | 2026-04-13 20:42:07.064856 | PLAY [all] 2026-04-13 20:42:07.070887 | 2026-04-13 20:42:07.070951 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-04-13 20:42:07.561122 | instance | changed 2026-04-13 20:42:07.809701 | 2026-04-13 20:42:07.809814 | PLAY RECAP 2026-04-13 20:42:07.809878 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-04-13 20:42:07.809910 | 2026-04-13 20:42:07.928429 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-13 20:42:07.931628 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-04-13 20:42:08.558587 | 2026-04-13 20:42:09.130059 | PLAY [localhost] 2026-04-13 20:42:09.149534 | 2026-04-13 20:42:09.149694 | TASK [Generate Zuul manifest] 2026-04-13 20:42:09.174798 | localhost | ok 2026-04-13 20:42:09.202570 | 2026-04-13 20:42:09.202753 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-04-13 20:42:09.546135 | localhost | changed 2026-04-13 20:42:09.557414 | 2026-04-13 20:42:09.557579 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-04-13 20:42:09.851653 | localhost | ok 2026-04-13 20:42:09.858025 | 2026-04-13 20:42:09.858150 | TASK [Upload logs] 2026-04-13 20:42:09.880479 | localhost | ok 2026-04-13 20:42:09.943102 | 2026-04-13 20:42:09.943258 | TASK [Set zuul-log-path fact] 2026-04-13 20:42:09.964037 | localhost | ok 2026-04-13 20:42:09.976831 | 2026-04-13 20:42:09.976998 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-13 20:42:10.005725 | localhost | ok 2026-04-13 20:42:10.015447 | 2026-04-13 20:42:10.015589 | TASK [upload-logs : Create log directories] 2026-04-13 20:42:10.519669 | localhost | changed 2026-04-13 20:42:10.526614 | 2026-04-13 20:42:10.526772 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-04-13 20:42:11.306947 | localhost -> localhost | ok: Runtime: 0:00:00.004527 2026-04-13 20:42:11.313493 | 2026-04-13 20:42:11.313562 | TASK [upload-logs : Upload logs to log server] 2026-04-13 20:42:11.846367 | localhost | Output suppressed because no_log was given 2026-04-13 20:42:11.852034 | 2026-04-13 20:42:11.852144 | LOOP [upload-logs : Compress console log and json output] 2026-04-13 20:42:11.896536 | localhost | skipping: Conditional result was False 2026-04-13 20:42:11.905075 | localhost | skipping: Conditional result was False 2026-04-13 20:42:11.918911 | 2026-04-13 20:42:11.919059 | LOOP [upload-logs : Upload compressed console log and json output] 2026-04-13 20:42:11.955404 | localhost | skipping: Conditional result was False 2026-04-13 20:42:11.955681 | 2026-04-13 20:42:11.959776 | localhost | skipping: Conditional result was False 2026-04-13 20:42:11.972546 | 2026-04-13 20:42:11.972684 | LOOP [upload-logs : Upload console log and json output]