2026-04-13 20:40:32.667578 | Job console starting 2026-04-13 20:40:32.677679 | Updating git repos 2026-04-13 20:40:32.732837 | Cloning repos into workspace 2026-04-13 20:40:39.886620 | Restoring repo states 2026-04-13 20:40:39.910819 | Merging changes 2026-04-13 20:40:41.124047 | Checking out repos 2026-04-13 20:40:43.023567 | Preparing playbooks 2026-04-13 20:40:58.799340 | Running Ansible setup 2026-04-13 20:41:02.395790 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-13 20:41:03.002927 | 2026-04-13 20:41:03.003060 | PLAY [localhost] 2026-04-13 20:41:03.011515 | 2026-04-13 20:41:03.011600 | TASK [Gathering Facts] 2026-04-13 20:41:03.907928 | localhost | ok 2026-04-13 20:41:03.918372 | 2026-04-13 20:41:03.918527 | TASK [Setup log path fact] 2026-04-13 20:41:03.937389 | localhost | ok 2026-04-13 20:41:03.949289 | 2026-04-13 20:41:03.949438 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-13 20:41:03.976309 | localhost | ok 2026-04-13 20:41:03.983766 | 2026-04-13 20:41:03.983920 | TASK [emit-job-header : Print job information] 2026-04-13 20:41:04.025697 | # Job Information 2026-04-13 20:41:04.025902 | Ansible Version: 2.16.16 2026-04-13 20:41:04.025949 | Job: atmosphere-molecule-csi-local-path-provisioner 2026-04-13 20:41:04.025983 | Pipeline: check 2026-04-13 20:41:04.026013 | Executor: 0a8996d2b663 2026-04-13 20:41:04.026041 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3818 2026-04-13 20:41:04.026073 | Event ID: af35ae10-3778-11f1-8810-704e89e38670 2026-04-13 20:41:04.030090 | 2026-04-13 20:41:04.030182 | LOOP [emit-job-header : Print node information] 2026-04-13 20:41:04.125594 | localhost | ok: 2026-04-13 20:41:04.125845 | localhost | # Node Information 2026-04-13 20:41:04.125884 | localhost | Inventory Hostname: instance 2026-04-13 20:41:04.125913 | localhost | Hostname: np0000165703 2026-04-13 20:41:04.125940 | localhost | Username: zuul 2026-04-13 20:41:04.125970 | localhost | Distro: Ubuntu 22.04 2026-04-13 20:41:04.125997 | localhost | Provider: yul1 2026-04-13 20:41:04.126023 | localhost | Region: ca-ymq-1 2026-04-13 20:41:04.126049 | localhost | Label: ubuntu-jammy 2026-04-13 20:41:04.126087 | localhost | Product Name: OpenStack Nova 2026-04-13 20:41:04.126121 | localhost | Interface IP: 199.204.45.53 2026-04-13 20:41:04.137680 | 2026-04-13 20:41:04.137887 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-04-13 20:41:04.506890 | localhost -> localhost | changed 2026-04-13 20:41:04.513120 | 2026-04-13 20:41:04.513240 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-04-13 20:41:05.435747 | localhost -> localhost | changed 2026-04-13 20:41:05.446373 | 2026-04-13 20:41:05.446514 | PLAY [all] 2026-04-13 20:41:05.456335 | 2026-04-13 20:41:05.456429 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-04-13 20:41:05.703977 | instance -> localhost | ok 2026-04-13 20:41:05.713802 | 2026-04-13 20:41:05.713980 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-04-13 20:41:05.748045 | instance | ok 2026-04-13 20:41:05.761374 | instance | included: /var/lib/zuul/builds/1def5d2c3cac443cb20131220d0b49b3/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-04-13 20:41:05.766794 | 2026-04-13 20:41:05.766862 | TASK [add-build-sshkey : Create Temp SSH key] 2026-04-13 20:41:07.932089 | instance -> localhost | Generating public/private rsa key pair. 2026-04-13 20:41:07.932338 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/1def5d2c3cac443cb20131220d0b49b3/work/1def5d2c3cac443cb20131220d0b49b3_id_rsa 2026-04-13 20:41:07.932384 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/1def5d2c3cac443cb20131220d0b49b3/work/1def5d2c3cac443cb20131220d0b49b3_id_rsa.pub 2026-04-13 20:41:07.932419 | instance -> localhost | The key fingerprint is: 2026-04-13 20:41:07.932453 | instance -> localhost | SHA256:KoFFGF7GLFvgPmEPCW5nc43FA06ze88eB8DqiNSMJQ8 zuul-build-sshkey 2026-04-13 20:41:07.932507 | instance -> localhost | The key's randomart image is: 2026-04-13 20:41:07.932544 | instance -> localhost | +---[RSA 3072]----+ 2026-04-13 20:41:07.932588 | instance -> localhost | |. o*++o. | 2026-04-13 20:41:07.932626 | instance -> localhost | |.+o=* Bo | 2026-04-13 20:41:07.932660 | instance -> localhost | | EBO.= +. | 2026-04-13 20:41:07.932691 | instance -> localhost | |.o%*o o . | 2026-04-13 20:41:07.932720 | instance -> localhost | | o++oo .S. | 2026-04-13 20:41:07.932751 | instance -> localhost | |. ..o...o . | 2026-04-13 20:41:07.932781 | instance -> localhost | | . .... + . | 2026-04-13 20:41:07.932812 | instance -> localhost | | . . o | 2026-04-13 20:41:07.932859 | instance -> localhost | | . | 2026-04-13 20:41:07.932901 | instance -> localhost | +----[SHA256]-----+ 2026-04-13 20:41:07.932996 | instance -> localhost | ok: Runtime: 0:00:01.719507 2026-04-13 20:41:07.939890 | 2026-04-13 20:41:07.939974 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-04-13 20:41:07.971567 | instance | ok 2026-04-13 20:41:07.982950 | instance | included: /var/lib/zuul/builds/1def5d2c3cac443cb20131220d0b49b3/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-04-13 20:41:07.991406 | 2026-04-13 20:41:07.991496 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-04-13 20:41:08.015144 | instance | skipping: Conditional result was False 2026-04-13 20:41:08.024585 | 2026-04-13 20:41:08.024708 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-04-13 20:41:08.530490 | instance | changed 2026-04-13 20:41:08.539194 | 2026-04-13 20:41:08.539291 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-04-13 20:41:08.734891 | instance | ok 2026-04-13 20:41:08.741987 | 2026-04-13 20:41:08.742140 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-04-13 20:41:09.194153 | instance | changed 2026-04-13 20:41:09.202072 | 2026-04-13 20:41:09.202190 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-04-13 20:41:09.653002 | instance | changed 2026-04-13 20:41:09.658164 | 2026-04-13 20:41:09.658277 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-04-13 20:41:09.681636 | instance | skipping: Conditional result was False 2026-04-13 20:41:09.695027 | 2026-04-13 20:41:09.695193 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-04-13 20:41:10.111278 | instance -> localhost | changed 2026-04-13 20:41:10.124673 | 2026-04-13 20:41:10.124803 | TASK [add-build-sshkey : Add back temp key] 2026-04-13 20:41:10.404033 | instance -> localhost | Identity added: /var/lib/zuul/builds/1def5d2c3cac443cb20131220d0b49b3/work/1def5d2c3cac443cb20131220d0b49b3_id_rsa (zuul-build-sshkey) 2026-04-13 20:41:10.404229 | instance -> localhost | ok: Runtime: 0:00:00.010914 2026-04-13 20:41:10.412395 | 2026-04-13 20:41:10.412483 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-04-13 20:41:10.748849 | instance | ok 2026-04-13 20:41:11.897023 | 2026-04-13 20:41:11.897139 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-04-13 20:41:11.921258 | instance | skipping: Conditional result was False 2026-04-13 20:41:11.937435 | 2026-04-13 20:41:11.937592 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-04-13 20:41:12.286604 | instance | ok 2026-04-13 20:41:12.295086 | 2026-04-13 20:41:12.295228 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-04-13 20:41:14.072693 | instance | Output suppressed because no_log was given 2026-04-13 20:41:14.086166 | 2026-04-13 20:41:14.086259 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-04-13 20:41:14.293459 | instance | ok: "logs" 2026-04-13 20:41:14.293826 | instance | ok: All items complete 2026-04-13 20:41:14.293891 | 2026-04-13 20:41:14.439562 | instance | ok: "artifacts" 2026-04-13 20:41:14.591382 | instance | ok: "docs" 2026-04-13 20:41:14.606218 | 2026-04-13 20:41:14.606345 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-04-13 20:41:14.787515 | instance | changed: "logs" 2026-04-13 20:41:14.952119 | instance | changed: "artifacts" 2026-04-13 20:41:15.127654 | instance | changed: "docs" 2026-04-13 20:41:15.141106 | 2026-04-13 20:41:15.141246 | PLAY RECAP 2026-04-13 20:41:15.141323 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-04-13 20:41:15.141373 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-13 20:41:15.141413 | 2026-04-13 20:41:15.292741 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-13 20:41:15.296768 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-13 20:41:15.895887 | 2026-04-13 20:41:16.342148 | PLAY [all] 2026-04-13 20:41:16.360045 | 2026-04-13 20:41:16.360169 | TASK [setup-uv : Extract archive] 2026-04-13 20:41:18.537577 | instance | changed 2026-04-13 20:41:18.546138 | 2026-04-13 20:41:18.546275 | TASK [setup-uv : Print version] 2026-04-13 20:41:18.885089 | instance | uv 0.8.13 2026-04-13 20:41:19.086192 | instance | ok: Runtime: 0:00:00.012551 2026-04-13 20:41:19.092605 | 2026-04-13 20:41:19.092657 | PLAY RECAP 2026-04-13 20:41:19.092705 | instance | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-13 20:41:19.092728 | 2026-04-13 20:41:19.533722 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-13 20:41:19.537364 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/pre.yml@main] 2026-04-13 20:41:20.197767 | 2026-04-13 20:41:20.256493 | PLAY [all] 2026-04-13 20:41:20.271376 | 2026-04-13 20:41:20.271511 | TASK [Install "jq" for log collection] 2026-04-13 20:41:31.811520 | instance | changed 2026-04-13 20:41:31.819129 | 2026-04-13 20:41:31.819243 | TASK [Install pip3 for Python package management] 2026-04-13 20:41:36.836982 | instance | changed 2026-04-13 20:41:36.842522 | 2026-04-13 20:41:36.842607 | TASK [Install Python "kubernetes" library for kubernetes.core modules] 2026-04-13 20:41:40.048743 | instance | changed 2026-04-13 20:41:40.052219 | 2026-04-13 20:41:40.052282 | PLAY [all] 2026-04-13 20:41:40.060638 | 2026-04-13 20:41:40.104511 | TASK [ensure-go : Check installed go version] 2026-04-13 20:41:40.644722 | instance | ok: ERROR (ignored) 2026-04-13 20:41:40.645218 | instance | { 2026-04-13 20:41:40.645307 | instance | "failed_when_result": false, 2026-04-13 20:41:40.645359 | instance | "msg": "[Errno 2] No such file or directory: b'go'", 2026-04-13 20:41:40.645410 | instance | "rc": 2 2026-04-13 20:41:40.645460 | instance | } 2026-04-13 20:41:40.650805 | 2026-04-13 20:41:40.650892 | TASK [ensure-go : Skip if correct version of go is installed] 2026-04-13 20:41:40.716053 | instance | ok 2026-04-13 20:41:40.725430 | instance | included: /var/lib/zuul/builds/1def5d2c3cac443cb20131220d0b49b3/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/ensure-go/tasks/install-go.yaml 2026-04-13 20:41:40.730742 | 2026-04-13 20:41:40.730879 | TASK [ensure-go : Create temp directory] 2026-04-13 20:41:41.043380 | instance | changed 2026-04-13 20:41:41.049302 | 2026-04-13 20:41:41.049383 | TASK [ensure-go : Get archive checksum] 2026-04-13 20:41:41.668088 | instance | ok: OK (64 bytes) 2026-04-13 20:41:41.675772 | 2026-04-13 20:41:41.675942 | TASK [ensure-go : Download go archive] 2026-04-13 20:41:43.082600 | instance | changed: OK (78559214 bytes) 2026-04-13 20:41:43.089137 | 2026-04-13 20:41:43.089211 | TASK [ensure-go : Install go] 2026-04-13 20:41:48.979373 | instance | changed 2026-04-13 20:41:48.988100 | 2026-04-13 20:41:48.988168 | PLAY [all] 2026-04-13 20:41:48.993902 | 2026-04-13 20:41:48.993974 | TASK [Build atmosphere binary] 2026-04-13 20:41:49.242528 | instance -> localhost | ERROR 2026-04-13 20:41:49.242719 | instance -> localhost | { 2026-04-13 20:41:49.242764 | instance -> localhost | "delta": null, 2026-04-13 20:41:49.242787 | instance -> localhost | "end": null, 2026-04-13 20:41:49.242806 | 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.242825 | instance -> localhost | "rc": null, 2026-04-13 20:41:49.242843 | instance -> localhost | "start": null 2026-04-13 20:41:49.242863 | instance -> localhost | } failure 2026-04-13 20:41:49.244099 | 2026-04-13 20:41:49.244141 | PLAY RECAP 2026-04-13 20:41:49.244184 | instance | ok: 9 changed: 6 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-04-13 20:41:49.244206 | 2026-04-13 20:41:49.359782 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/pre.yml@main] 2026-04-13 20:41:49.363336 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@main] 2026-04-13 20:41:49.993270 | 2026-04-13 20:41:49.993446 | PLAY [all] 2026-04-13 20:41:50.009810 | 2026-04-13 20:41:50.009976 | TASK [gather-host-logs : creating directory for system status] 2026-04-13 20:41:50.396184 | instance | changed 2026-04-13 20:41:50.401545 | 2026-04-13 20:41:50.401624 | TASK [gather-host-logs : Get logs for each host] 2026-04-13 20:41:50.751482 | instance | + systemd-cgls --full --all --no-pager 2026-04-13 20:41:50.762252 | instance | + ip addr 2026-04-13 20:41:50.765206 | instance | + ip route 2026-04-13 20:41:50.766882 | instance | + lsblk 2026-04-13 20:41:50.769339 | instance | + mount 2026-04-13 20:41:50.770862 | instance | + docker images 2026-04-13 20:41:50.771195 | instance | /bin/bash: line 7: docker: command not found 2026-04-13 20:41:50.771369 | instance | + brctl show 2026-04-13 20:41:50.771695 | instance | /bin/bash: line 8: brctl: command not found 2026-04-13 20:41:50.771871 | instance | + ps aux --sort=-%mem 2026-04-13 20:41:50.783450 | instance | + dpkg -l 2026-04-13 20:41:50.789921 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul)) 2026-04-13 20:41:50.790114 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul 2026-04-13 20:41:50.790168 | instance | /bin/bash: line 11: docker: command not found 2026-04-13 20:41:50.790378 | instance | + '[' '!' -z '' ']' 2026-04-13 20:41:50.963823 | instance | ok: Runtime: 0:00:00.042955 2026-04-13 20:41:50.971973 | 2026-04-13 20:41:50.972050 | TASK [gather-host-logs : Downloads logs to executor] 2026-04-13 20:41:51.593427 | instance | changed: 2026-04-13 20:41:51.593721 | instance | created directory /var/lib/zuul/builds/1def5d2c3cac443cb20131220d0b49b3/work/logs/instance 2026-04-13 20:41:51.593797 | instance | cd+++++++++ system/ 2026-04-13 20:41:51.593863 | instance | >f+++++++++ system/brctl-show.txt 2026-04-13 20:41:51.593914 | instance | >f+++++++++ system/docker-images.txt 2026-04-13 20:41:51.593952 | instance | >f+++++++++ system/ip-addr.txt 2026-04-13 20:41:51.593992 | instance | >f+++++++++ system/ip-route.txt 2026-04-13 20:41:51.594028 | instance | >f+++++++++ system/lsblk.txt 2026-04-13 20:41:51.594062 | instance | >f+++++++++ system/mount.txt 2026-04-13 20:41:51.594099 | instance | >f+++++++++ system/packages.txt 2026-04-13 20:41:51.594131 | instance | >f+++++++++ system/ps.txt 2026-04-13 20:41:51.594187 | instance | >f+++++++++ system/systemd-cgls.txt 2026-04-13 20:41:51.617733 | 2026-04-13 20:41:51.617829 | LOOP [helm-release-status : creating directory for helm release status] 2026-04-13 20:41:51.813791 | instance | changed: "values" 2026-04-13 20:41:51.991454 | instance | changed: "releases" 2026-04-13 20:41:52.000517 | 2026-04-13 20:41:52.000651 | TASK [helm-release-status : Gather get release status for helm charts] 2026-04-13 20:41:52.253629 | instance | /bin/bash: line 3: kubectl: command not found 2026-04-13 20:41:52.542416 | instance | ok: Runtime: 0:00:00.025949 2026-04-13 20:41:52.549331 | 2026-04-13 20:41:52.549431 | TASK [helm-release-status : Downloads logs to executor] 2026-04-13 20:41:53.038915 | instance | changed: 2026-04-13 20:41:53.039421 | instance | cd+++++++++ helm/ 2026-04-13 20:41:53.039476 | instance | cd+++++++++ helm/releases/ 2026-04-13 20:41:53.040044 | instance | cd+++++++++ helm/values/ 2026-04-13 20:41:53.056838 | 2026-04-13 20:41:53.056976 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects] 2026-04-13 20:41:53.273000 | instance | changed 2026-04-13 20:41:53.279940 | 2026-04-13 20:41:53.280112 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects] 2026-04-13 20:41:53.510305 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-13 20:41:53.510580 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-13 20:41:53.514216 | instance | environment: line 1: kubectl: command not found 2026-04-13 20:41:53.515108 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-13 20:41:53.516450 | instance | environment: line 1: kubectl: command not found 2026-04-13 20:41:53.517919 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-13 20:41:53.518293 | instance | environment: line 1: kubectl: command not found 2026-04-13 20:41:53.519085 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-13 20:41:53.521196 | instance | environment: line 1: kubectl: command not found 2026-04-13 20:41:53.521888 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-13 20:41:53.522439 | instance | environment: line 1: kubectl: command not found 2026-04-13 20:41:53.523157 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-13 20:41:53.908414 | instance | ok: Runtime: 0:00:00.020887 2026-04-13 20:41:53.915905 | 2026-04-13 20:41:53.915996 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects] 2026-04-13 20:41:54.113564 | instance | changed 2026-04-13 20:41:54.119426 | 2026-04-13 20:41:54.119505 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects] 2026-04-13 20:41:54.346065 | instance | environment: line 5: kubectl: command not found 2026-04-13 20:41:54.347167 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-13 20:41:54.347256 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-13 20:41:54.347265 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-13 20:41:54.678556 | instance | ok: Runtime: 0:00:00.007405 2026-04-13 20:41:54.685625 | 2026-04-13 20:41:54.685727 | TASK [describe-kubernetes-objects : Downloads logs to executor] 2026-04-13 20:41:55.287052 | instance | changed: 2026-04-13 20:41:55.287248 | instance | cd+++++++++ objects/ 2026-04-13 20:41:55.287293 | instance | cd+++++++++ objects/cluster/ 2026-04-13 20:41:55.287329 | instance | cd+++++++++ objects/namespaced/ 2026-04-13 20:41:55.318590 | 2026-04-13 20:41:55.318690 | TASK [gather-pod-logs : creating directory for pod logs] 2026-04-13 20:41:55.518574 | instance | changed 2026-04-13 20:41:55.523954 | 2026-04-13 20:41:55.524022 | TASK [gather-pod-logs : creating directory for failed pod logs] 2026-04-13 20:41:55.719699 | instance | changed 2026-04-13 20:41:55.727284 | 2026-04-13 20:41:55.727409 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)] 2026-04-13 20:41:55.956909 | instance | environment: line 3: kubectl: command not found 2026-04-13 20:41:56.271042 | instance | ok: Runtime: 0:00:00.006774 2026-04-13 20:41:56.279198 | 2026-04-13 20:41:56.279376 | TASK [gather-pod-logs : Downloads pod logs to executor] 2026-04-13 20:41:56.787601 | instance | changed: 2026-04-13 20:41:56.787790 | instance | cd+++++++++ pod-logs/ 2026-04-13 20:41:56.787829 | instance | cd+++++++++ pod-logs/failed-pods/ 2026-04-13 20:41:56.798357 | 2026-04-13 20:41:56.798423 | TASK [gather-prom-metrics : creating directory for helm release descriptions] 2026-04-13 20:41:57.003748 | instance | changed 2026-04-13 20:41:57.008893 | 2026-04-13 20:41:57.008988 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces] 2026-04-13 20:41:57.232312 | instance | /bin/bash: line 2: kubectl: command not found 2026-04-13 20:41:57.546783 | instance | ok: Runtime: 0:00:00.036888 2026-04-13 20:41:57.553982 | 2026-04-13 20:41:57.554112 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr] 2026-04-13 20:41:57.761760 | instance | /bin/bash: line 2: kubectl: command not found 2026-04-13 20:41:57.790744 | instance | ceph-mgr endpoints: 2026-04-13 20:41:58.093058 | instance | ok: Runtime: 0:00:00.034954 2026-04-13 20:41:58.098564 | 2026-04-13 20:41:58.098661 | TASK [gather-prom-metrics : Get metrics from fluentd pods] 2026-04-13 20:41:58.297167 | instance | /bin/bash: line 4: kubectl: command not found 2026-04-13 20:41:59.134908 | instance | ok: Runtime: 0:00:00.041042 2026-04-13 20:41:59.142023 | 2026-04-13 20:41:59.142137 | TASK [gather-prom-metrics : Downloads logs to executor] 2026-04-13 20:41:59.805417 | instance | changed: cd+++++++++ prometheus/ 2026-04-13 20:41:59.818356 | 2026-04-13 20:41:59.818515 | TASK [gather-selenium-data : creating directory for helm release descriptions] 2026-04-13 20:42:00.019590 | instance | changed 2026-04-13 20:42:00.426641 | 2026-04-13 20:42:00.426778 | TASK [gather-selenium-data : Get selenium data] 2026-04-13 20:42:00.640508 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/. 2026-04-13 20:42:00.641297 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory 2026-04-13 20:42:00.967993 | instance | ERROR 2026-04-13 20:42:00.968211 | instance | { 2026-04-13 20:42:00.968262 | instance | "delta": "0:00:00.003472", 2026-04-13 20:42:00.968299 | instance | "end": "2026-04-13 20:42:00.641528", 2026-04-13 20:42:00.968334 | instance | "msg": "non-zero return code", 2026-04-13 20:42:00.968366 | instance | "rc": 1, 2026-04-13 20:42:00.968397 | instance | "start": "2026-04-13 20:42:00.638056" 2026-04-13 20:42:00.968427 | instance | } 2026-04-13 20:42:00.968464 | instance | ERROR: Ignoring Errors 2026-04-13 20:42:00.974747 | 2026-04-13 20:42:00.974838 | TASK [gather-selenium-data : Downloads logs to executor] 2026-04-13 20:42:01.615767 | instance | changed: cd+++++++++ selenium/ 2026-04-13 20:42:01.623988 | 2026-04-13 20:42:01.624110 | PLAY RECAP 2026-04-13 20:42:01.624167 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1 2026-04-13 20:42:01.624195 | 2026-04-13 20:42:01.767841 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@main] 2026-04-13 20:42:01.770667 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-13 20:42:02.442224 | 2026-04-13 20:42:02.442337 | PLAY [all] 2026-04-13 20:42:02.453646 | 2026-04-13 20:42:02.453727 | TASK [fetch-output : Set log path for multiple nodes] 2026-04-13 20:42:02.497880 | instance | skipping: Conditional result was False 2026-04-13 20:42:02.502701 | 2026-04-13 20:42:02.502778 | TASK [fetch-output : Set log path for single node] 2026-04-13 20:42:02.540717 | instance | ok 2026-04-13 20:42:02.545099 | 2026-04-13 20:42:02.545162 | LOOP [fetch-output : Ensure local output dirs] 2026-04-13 20:42:03.026136 | instance -> localhost | ok: "/var/lib/zuul/builds/1def5d2c3cac443cb20131220d0b49b3/work/logs" 2026-04-13 20:42:03.256744 | instance -> localhost | changed: "/var/lib/zuul/builds/1def5d2c3cac443cb20131220d0b49b3/work/artifacts" 2026-04-13 20:42:03.507001 | instance -> localhost | changed: "/var/lib/zuul/builds/1def5d2c3cac443cb20131220d0b49b3/work/docs" 2026-04-13 20:42:03.525080 | 2026-04-13 20:42:03.525676 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-04-13 20:42:04.227878 | instance | changed: .d..t...... ./ 2026-04-13 20:42:04.228173 | instance | changed: All items complete 2026-04-13 20:42:04.228207 | 2026-04-13 20:42:04.693519 | instance | changed: .d..t...... ./ 2026-04-13 20:42:05.174815 | instance | changed: .d..t...... ./ 2026-04-13 20:42:05.190348 | 2026-04-13 20:42:05.190468 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-04-13 20:42:06.581982 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.007045 2026-04-13 20:42:06.835944 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.006651 2026-04-13 20:42:06.850393 | 2026-04-13 20:42:06.850509 | PLAY [all] 2026-04-13 20:42:06.860435 | 2026-04-13 20:42:06.860552 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-04-13 20:42:07.269079 | instance | changed 2026-04-13 20:42:07.809443 | 2026-04-13 20:42:07.809571 | PLAY RECAP 2026-04-13 20:42:07.809642 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-04-13 20:42:07.809678 | 2026-04-13 20:42:07.947841 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-13 20:42:07.952734 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-04-13 20:42:08.570854 | 2026-04-13 20:42:09.130478 | PLAY [localhost] 2026-04-13 20:42:09.150163 | 2026-04-13 20:42:09.150497 | TASK [Generate Zuul manifest] 2026-04-13 20:42:09.174851 | localhost | ok 2026-04-13 20:42:09.198412 | 2026-04-13 20:42:09.198591 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-04-13 20:42:09.566322 | localhost | changed 2026-04-13 20:42:09.580050 | 2026-04-13 20:42:09.580236 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-04-13 20:42:09.849259 | localhost | ok 2026-04-13 20:42:09.857872 | 2026-04-13 20:42:09.857985 | TASK [Upload logs] 2026-04-13 20:42:09.881218 | localhost | ok 2026-04-13 20:42:09.946638 | 2026-04-13 20:42:09.946765 | TASK [Set zuul-log-path fact] 2026-04-13 20:42:09.965118 | localhost | ok 2026-04-13 20:42:09.977178 | 2026-04-13 20:42:09.977257 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-13 20:42:10.008334 | localhost | ok 2026-04-13 20:42:10.018714 | 2026-04-13 20:42:10.018781 | TASK [upload-logs : Create log directories] 2026-04-13 20:42:10.464296 | localhost | changed 2026-04-13 20:42:10.473252 | 2026-04-13 20:42:10.473397 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-04-13 20:42:11.388793 | localhost -> localhost | ok: Runtime: 0:00:00.004436 2026-04-13 20:42:11.396234 | 2026-04-13 20:42:11.396331 | TASK [upload-logs : Upload logs to log server] 2026-04-13 20:42:11.847309 | localhost | Output suppressed because no_log was given 2026-04-13 20:42:11.853572 | 2026-04-13 20:42:11.853682 | LOOP [upload-logs : Compress console log and json output] 2026-04-13 20:42:11.905024 | localhost | skipping: Conditional result was False 2026-04-13 20:42:11.916332 | localhost | skipping: Conditional result was False 2026-04-13 20:42:11.929072 | 2026-04-13 20:42:11.929168 | LOOP [upload-logs : Upload compressed console log and json output] 2026-04-13 20:42:11.968338 | localhost | skipping: Conditional result was False 2026-04-13 20:42:11.968591 | 2026-04-13 20:42:11.975053 | localhost | skipping: Conditional result was False 2026-04-13 20:42:11.988641 | 2026-04-13 20:42:11.988752 | LOOP [upload-logs : Upload console log and json output]