2026-04-13 20:38:52.685429 | Job console starting 2026-04-13 20:38:52.694434 | Updating git repos 2026-04-13 20:38:52.767670 | Cloning repos into workspace 2026-04-13 20:38:54.754815 | Restoring repo states 2026-04-13 20:38:54.772677 | Merging changes 2026-04-13 20:38:55.659247 | Checking out repos 2026-04-13 20:38:55.936504 | Preparing playbooks 2026-04-13 20:38:59.242765 | Running Ansible setup 2026-04-13 20:39:02.594344 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-13 20:39:03.209320 | 2026-04-13 20:39:03.209453 | PLAY [localhost] 2026-04-13 20:39:03.217502 | 2026-04-13 20:39:03.217589 | TASK [Gathering Facts] 2026-04-13 20:39:05.161963 | localhost | ok 2026-04-13 20:39:05.168457 | 2026-04-13 20:39:05.168540 | TASK [Setup log path fact] 2026-04-13 20:39:05.198492 | localhost | ok 2026-04-13 20:39:05.211181 | 2026-04-13 20:39:05.211290 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-13 20:39:05.241621 | localhost | ok 2026-04-13 20:39:05.256830 | 2026-04-13 20:39:05.256930 | TASK [emit-job-header : Print job information] 2026-04-13 20:39:05.294385 | # Job Information 2026-04-13 20:39:05.294539 | Ansible Version: 2.16.16 2026-04-13 20:39:05.294573 | Job: atmosphere-molecule-csi-rbd 2026-04-13 20:39:05.294595 | Pipeline: check 2026-04-13 20:39:05.294615 | Executor: 0a8996d2b663 2026-04-13 20:39:05.294635 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3818 2026-04-13 20:39:05.294658 | Event ID: af35ae10-3778-11f1-8810-704e89e38670 2026-04-13 20:39:05.297428 | 2026-04-13 20:39:05.297497 | LOOP [emit-job-header : Print node information] 2026-04-13 20:39:05.426786 | localhost | ok: 2026-04-13 20:39:05.427130 | localhost | # Node Information 2026-04-13 20:39:05.427197 | localhost | Inventory Hostname: instance 2026-04-13 20:39:05.427249 | localhost | Hostname: np0000165707 2026-04-13 20:39:05.427295 | localhost | Username: zuul 2026-04-13 20:39:05.427347 | localhost | Distro: Ubuntu 22.04 2026-04-13 20:39:05.427392 | localhost | Provider: yul1 2026-04-13 20:39:05.427436 | localhost | Region: ca-ymq-1 2026-04-13 20:39:05.427480 | localhost | Label: ubuntu-jammy 2026-04-13 20:39:05.427522 | localhost | Product Name: OpenStack Nova 2026-04-13 20:39:05.427565 | localhost | Interface IP: 199.204.45.238 2026-04-13 20:39:05.444703 | 2026-04-13 20:39:05.444869 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-04-13 20:39:05.824851 | localhost -> localhost | changed 2026-04-13 20:39:05.833209 | 2026-04-13 20:39:05.833317 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-04-13 20:39:06.773648 | localhost -> localhost | changed 2026-04-13 20:39:06.784355 | 2026-04-13 20:39:06.784454 | PLAY [all] 2026-04-13 20:39:06.793551 | 2026-04-13 20:39:06.793629 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-04-13 20:39:07.030105 | instance -> localhost | ok 2026-04-13 20:39:07.040895 | 2026-04-13 20:39:07.041574 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-04-13 20:39:07.074205 | instance | ok 2026-04-13 20:39:07.089967 | instance | included: /var/lib/zuul/builds/e6e45c45b7c548638232d139dc0ba7b5/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-04-13 20:39:07.096595 | 2026-04-13 20:39:07.096703 | TASK [add-build-sshkey : Create Temp SSH key] 2026-04-13 20:39:08.479955 | instance -> localhost | Generating public/private rsa key pair. 2026-04-13 20:39:08.480125 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/e6e45c45b7c548638232d139dc0ba7b5/work/e6e45c45b7c548638232d139dc0ba7b5_id_rsa 2026-04-13 20:39:08.480157 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/e6e45c45b7c548638232d139dc0ba7b5/work/e6e45c45b7c548638232d139dc0ba7b5_id_rsa.pub 2026-04-13 20:39:08.480181 | instance -> localhost | The key fingerprint is: 2026-04-13 20:39:08.480203 | instance -> localhost | SHA256:YbWOYMhgf/Di3ozYr8Nb57BUrPzO/8GvZTMBh9gB5Zg zuul-build-sshkey 2026-04-13 20:39:08.480243 | instance -> localhost | The key's randomart image is: 2026-04-13 20:39:08.480265 | instance -> localhost | +---[RSA 3072]----+ 2026-04-13 20:39:08.480290 | instance -> localhost | | o . ooo | 2026-04-13 20:39:08.480312 | instance -> localhost | | . + + . B o | 2026-04-13 20:39:08.480332 | instance -> localhost | | = = o E = . | 2026-04-13 20:39:08.480353 | instance -> localhost | | . + + + o | 2026-04-13 20:39:08.480373 | instance -> localhost | | . S . . | 2026-04-13 20:39:08.480393 | instance -> localhost | | + = o . . | 2026-04-13 20:39:08.480413 | instance -> localhost | | ..+ O . o = | 2026-04-13 20:39:08.480433 | instance -> localhost | | o+ B = o | 2026-04-13 20:39:08.480455 | instance -> localhost | | o+o.=...o.. | 2026-04-13 20:39:08.480476 | instance -> localhost | +----[SHA256]-----+ 2026-04-13 20:39:08.480529 | instance -> localhost | ok: Runtime: 0:00:00.986890 2026-04-13 20:39:08.485456 | 2026-04-13 20:39:08.485520 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-04-13 20:39:08.512181 | instance | ok 2026-04-13 20:39:08.522769 | instance | included: /var/lib/zuul/builds/e6e45c45b7c548638232d139dc0ba7b5/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-04-13 20:39:08.530203 | 2026-04-13 20:39:08.530300 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-04-13 20:39:08.554128 | instance | skipping: Conditional result was False 2026-04-13 20:39:08.565164 | 2026-04-13 20:39:08.565311 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-04-13 20:39:09.015852 | instance | changed 2026-04-13 20:39:09.023057 | 2026-04-13 20:39:09.023146 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-04-13 20:39:09.206257 | instance | ok 2026-04-13 20:39:09.213146 | 2026-04-13 20:39:09.213251 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-04-13 20:39:09.706499 | instance | changed 2026-04-13 20:39:09.715125 | 2026-04-13 20:39:09.715248 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-04-13 20:39:10.164605 | instance | changed 2026-04-13 20:39:10.363567 | 2026-04-13 20:39:10.365316 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-04-13 20:39:10.390393 | instance | skipping: Conditional result was False 2026-04-13 20:39:10.399401 | 2026-04-13 20:39:10.399564 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-04-13 20:39:10.971024 | instance -> localhost | changed 2026-04-13 20:39:10.985574 | 2026-04-13 20:39:10.985677 | TASK [add-build-sshkey : Add back temp key] 2026-04-13 20:39:11.528978 | instance -> localhost | Identity added: /var/lib/zuul/builds/e6e45c45b7c548638232d139dc0ba7b5/work/e6e45c45b7c548638232d139dc0ba7b5_id_rsa (zuul-build-sshkey) 2026-04-13 20:39:11.529182 | instance -> localhost | ok: Runtime: 0:00:00.013887 2026-04-13 20:39:11.537745 | 2026-04-13 20:39:11.537879 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-04-13 20:39:11.855197 | instance | ok 2026-04-13 20:39:11.861933 | 2026-04-13 20:39:11.862031 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-04-13 20:39:11.890465 | instance | skipping: Conditional result was False 2026-04-13 20:39:11.908092 | 2026-04-13 20:39:11.908225 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-04-13 20:39:12.252267 | instance | ok 2026-04-13 20:39:12.335356 | 2026-04-13 20:39:12.335501 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-04-13 20:39:13.970766 | instance | Output suppressed because no_log was given 2026-04-13 20:39:13.981367 | 2026-04-13 20:39:13.981460 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-04-13 20:39:14.183240 | instance | ok: "logs" 2026-04-13 20:39:14.183458 | instance | ok: All items complete 2026-04-13 20:39:14.183488 | 2026-04-13 20:39:14.338706 | instance | ok: "artifacts" 2026-04-13 20:39:14.505530 | instance | ok: "docs" 2026-04-13 20:39:14.518116 | 2026-04-13 20:39:14.518300 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-04-13 20:39:14.700019 | instance | changed: "logs" 2026-04-13 20:39:15.113725 | instance | changed: "artifacts" 2026-04-13 20:39:15.121732 | instance | changed: "docs" 2026-04-13 20:39:15.131696 | 2026-04-13 20:39:15.131775 | PLAY RECAP 2026-04-13 20:39:15.131820 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-04-13 20:39:15.131849 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-13 20:39:15.131872 | 2026-04-13 20:39:15.267860 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-13 20:39:15.272642 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-13 20:39:15.850099 | 2026-04-13 20:39:15.850241 | PLAY [all] 2026-04-13 20:39:15.862379 | 2026-04-13 20:39:15.862472 | TASK [setup-uv : Extract archive] 2026-04-13 20:39:18.030298 | instance | changed 2026-04-13 20:39:18.037371 | 2026-04-13 20:39:18.037541 | TASK [setup-uv : Print version] 2026-04-13 20:39:18.300882 | instance | uv 0.8.13 2026-04-13 20:39:18.584249 | instance | ok: Runtime: 0:00:00.012932 2026-04-13 20:39:18.592628 | 2026-04-13 20:39:18.592718 | PLAY RECAP 2026-04-13 20:39:18.592777 | instance | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-13 20:39:18.592815 | 2026-04-13 20:39:18.711347 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-13 20:39:18.715321 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/pre.yml@main] 2026-04-13 20:39:19.363918 | 2026-04-13 20:39:19.364401 | PLAY [all] 2026-04-13 20:39:19.376098 | 2026-04-13 20:39:19.376218 | TASK [Install "jq" for log collection] 2026-04-13 20:39:29.237168 | instance | changed 2026-04-13 20:39:29.244651 | 2026-04-13 20:39:29.244761 | TASK [Install pip3 for Python package management] 2026-04-13 20:39:33.887173 | instance | changed 2026-04-13 20:39:33.894465 | 2026-04-13 20:39:33.894605 | TASK [Install Python "kubernetes" library for kubernetes.core modules] 2026-04-13 20:39:36.883602 | instance | changed 2026-04-13 20:39:36.887166 | 2026-04-13 20:39:36.887243 | PLAY [all] 2026-04-13 20:39:36.899493 | 2026-04-13 20:39:36.899821 | TASK [ensure-go : Check installed go version] 2026-04-13 20:39:37.445399 | instance | ok: ERROR (ignored) 2026-04-13 20:39:37.445663 | instance | { 2026-04-13 20:39:37.445711 | instance | "failed_when_result": false, 2026-04-13 20:39:37.445742 | instance | "msg": "[Errno 2] No such file or directory: b'go'", 2026-04-13 20:39:37.445773 | instance | "rc": 2 2026-04-13 20:39:37.445806 | instance | } 2026-04-13 20:39:37.452266 | 2026-04-13 20:39:37.452339 | TASK [ensure-go : Skip if correct version of go is installed] 2026-04-13 20:39:37.505689 | instance | ok 2026-04-13 20:39:37.516716 | instance | included: /var/lib/zuul/builds/e6e45c45b7c548638232d139dc0ba7b5/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/ensure-go/tasks/install-go.yaml 2026-04-13 20:39:37.543291 | 2026-04-13 20:39:37.543412 | TASK [ensure-go : Create temp directory] 2026-04-13 20:39:37.822335 | instance | changed 2026-04-13 20:39:37.827901 | 2026-04-13 20:39:37.827969 | TASK [ensure-go : Get archive checksum] 2026-04-13 20:39:38.500272 | instance | ok: OK (64 bytes) 2026-04-13 20:39:38.505707 | 2026-04-13 20:39:38.505776 | TASK [ensure-go : Download go archive] 2026-04-13 20:39:39.951160 | instance | changed: OK (78559214 bytes) 2026-04-13 20:39:39.959992 | 2026-04-13 20:39:39.960152 | TASK [ensure-go : Install go] 2026-04-13 20:39:45.807684 | instance | changed 2026-04-13 20:39:45.818994 | 2026-04-13 20:39:45.819075 | PLAY [all] 2026-04-13 20:39:45.826634 | 2026-04-13 20:39:45.826732 | TASK [Build atmosphere binary] 2026-04-13 20:39:46.093998 | instance -> localhost | ERROR 2026-04-13 20:39:46.094267 | instance -> localhost | { 2026-04-13 20:39:46.094334 | instance -> localhost | "delta": null, 2026-04-13 20:39:46.094370 | instance -> localhost | "end": null, 2026-04-13 20:39:46.094401 | 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:39:46.094431 | instance -> localhost | "rc": null, 2026-04-13 20:39:46.094460 | instance -> localhost | "start": null 2026-04-13 20:39:46.094489 | instance -> localhost | } failure 2026-04-13 20:39:46.096150 | 2026-04-13 20:39:46.096210 | PLAY RECAP 2026-04-13 20:39:46.096274 | instance | ok: 9 changed: 6 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-04-13 20:39:46.096304 | 2026-04-13 20:39:46.201718 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/pre.yml@main] 2026-04-13 20:39:46.206329 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@main] 2026-04-13 20:39:46.824134 | 2026-04-13 20:39:46.824256 | PLAY [all] 2026-04-13 20:39:46.838106 | 2026-04-13 20:39:46.838208 | TASK [gather-host-logs : creating directory for system status] 2026-04-13 20:39:47.217541 | instance | changed 2026-04-13 20:39:47.224092 | 2026-04-13 20:39:47.224175 | TASK [gather-host-logs : Get logs for each host] 2026-04-13 20:39:47.580734 | instance | + systemd-cgls --full --all --no-pager 2026-04-13 20:39:47.592864 | instance | + ip addr 2026-04-13 20:39:47.594572 | instance | + ip route 2026-04-13 20:39:47.595927 | instance | + lsblk 2026-04-13 20:39:47.598553 | instance | + mount 2026-04-13 20:39:47.600268 | instance | + docker images 2026-04-13 20:39:47.600773 | instance | /bin/bash: line 7: docker: command not found 2026-04-13 20:39:47.601050 | instance | + brctl show 2026-04-13 20:39:47.601582 | instance | /bin/bash: line 8: brctl: command not found 2026-04-13 20:39:47.601872 | instance | + ps aux --sort=-%mem 2026-04-13 20:39:47.611664 | instance | + dpkg -l 2026-04-13 20:39:47.617181 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul)) 2026-04-13 20:39:47.617510 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul 2026-04-13 20:39:47.617543 | instance | /bin/bash: line 11: docker: command not found 2026-04-13 20:39:47.617779 | instance | + '[' '!' -z '' ']' 2026-04-13 20:39:47.762210 | instance | ok: Runtime: 0:00:00.044233 2026-04-13 20:39:47.769700 | 2026-04-13 20:39:47.769768 | TASK [gather-host-logs : Downloads logs to executor] 2026-04-13 20:39:48.438186 | instance | changed: 2026-04-13 20:39:48.438379 | instance | created directory /var/lib/zuul/builds/e6e45c45b7c548638232d139dc0ba7b5/work/logs/instance 2026-04-13 20:39:48.438423 | instance | cd+++++++++ system/ 2026-04-13 20:39:48.438456 | instance | >f+++++++++ system/brctl-show.txt 2026-04-13 20:39:48.438487 | instance | >f+++++++++ system/docker-images.txt 2026-04-13 20:39:48.438517 | instance | >f+++++++++ system/ip-addr.txt 2026-04-13 20:39:48.438550 | instance | >f+++++++++ system/ip-route.txt 2026-04-13 20:39:48.438581 | instance | >f+++++++++ system/lsblk.txt 2026-04-13 20:39:48.438613 | instance | >f+++++++++ system/mount.txt 2026-04-13 20:39:48.438646 | instance | >f+++++++++ system/packages.txt 2026-04-13 20:39:48.438675 | instance | >f+++++++++ system/ps.txt 2026-04-13 20:39:48.438703 | instance | >f+++++++++ system/systemd-cgls.txt 2026-04-13 20:39:48.449781 | 2026-04-13 20:39:48.456683 | LOOP [helm-release-status : creating directory for helm release status] 2026-04-13 20:39:48.658762 | instance | changed: "values" 2026-04-13 20:39:48.830534 | instance | changed: "releases" 2026-04-13 20:39:48.838354 | 2026-04-13 20:39:48.838428 | TASK [helm-release-status : Gather get release status for helm charts] 2026-04-13 20:39:49.060396 | instance | /bin/bash: line 3: kubectl: command not found 2026-04-13 20:39:49.376645 | instance | ok: Runtime: 0:00:00.006894 2026-04-13 20:39:49.383921 | 2026-04-13 20:39:49.384072 | TASK [helm-release-status : Downloads logs to executor] 2026-04-13 20:39:49.874131 | instance | changed: 2026-04-13 20:39:49.874382 | instance | cd+++++++++ helm/ 2026-04-13 20:39:49.874423 | instance | cd+++++++++ helm/releases/ 2026-04-13 20:39:49.874453 | instance | cd+++++++++ helm/values/ 2026-04-13 20:39:49.886216 | 2026-04-13 20:39:49.886375 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects] 2026-04-13 20:39:50.094639 | instance | changed 2026-04-13 20:39:50.102056 | 2026-04-13 20:39:50.102162 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects] 2026-04-13 20:39:50.337055 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-13 20:39:50.337515 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-13 20:39:50.348104 | instance | environment: line 1: kubectl: command not found 2026-04-13 20:39:50.348136 | instance | environment: line 1: kubectl: command not found 2026-04-13 20:39:50.348740 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-13 20:39:50.349101 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-13 20:39:50.351200 | instance | environment: line 1: kubectl: command not found 2026-04-13 20:39:50.351598 | instance | environment: line 1: kubectl: command not found 2026-04-13 20:39:50.351794 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-13 20:39:50.352451 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-13 20:39:50.355831 | instance | environment: line 1: kubectl: command not found 2026-04-13 20:39:50.356541 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-13 20:39:50.653814 | instance | ok: Runtime: 0:00:00.030120 2026-04-13 20:39:50.658887 | 2026-04-13 20:39:50.658961 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects] 2026-04-13 20:39:50.864382 | instance | changed 2026-04-13 20:39:50.870444 | 2026-04-13 20:39:50.870544 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects] 2026-04-13 20:39:51.119053 | instance | environment: line 5: kubectl: command not found 2026-04-13 20:39:51.120296 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-13 20:39:51.120432 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-13 20:39:51.120586 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-13 20:39:51.430404 | instance | ok: Runtime: 0:00:00.011204 2026-04-13 20:39:51.450854 | 2026-04-13 20:39:51.450956 | TASK [describe-kubernetes-objects : Downloads logs to executor] 2026-04-13 20:39:51.950324 | instance | changed: 2026-04-13 20:39:51.950872 | instance | cd+++++++++ objects/ 2026-04-13 20:39:51.950921 | instance | cd+++++++++ objects/cluster/ 2026-04-13 20:39:51.950954 | instance | cd+++++++++ objects/namespaced/ 2026-04-13 20:39:51.962022 | 2026-04-13 20:39:51.962091 | TASK [gather-pod-logs : creating directory for pod logs] 2026-04-13 20:39:52.158418 | instance | changed 2026-04-13 20:39:52.163700 | 2026-04-13 20:39:52.163770 | TASK [gather-pod-logs : creating directory for failed pod logs] 2026-04-13 20:39:52.358761 | instance | changed 2026-04-13 20:39:52.364256 | 2026-04-13 20:39:52.364326 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)] 2026-04-13 20:39:52.590822 | instance | environment: line 3: kubectl: command not found 2026-04-13 20:39:52.897066 | instance | ok: Runtime: 0:00:00.009284 2026-04-13 20:39:52.904570 | 2026-04-13 20:39:52.904660 | TASK [gather-pod-logs : Downloads pod logs to executor] 2026-04-13 20:39:55.307228 | instance | changed: 2026-04-13 20:39:55.307420 | instance | cd+++++++++ pod-logs/ 2026-04-13 20:39:55.307463 | instance | cd+++++++++ pod-logs/failed-pods/ 2026-04-13 20:39:55.317902 | 2026-04-13 20:39:55.317977 | TASK [gather-prom-metrics : creating directory for helm release descriptions] 2026-04-13 20:39:55.513584 | instance | changed 2026-04-13 20:39:55.519771 | 2026-04-13 20:39:55.519852 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces] 2026-04-13 20:39:55.737344 | instance | /bin/bash: line 2: kubectl: command not found 2026-04-13 20:39:56.055337 | instance | ok: Runtime: 0:00:00.036698 2026-04-13 20:39:56.063056 | 2026-04-13 20:39:56.063170 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr] 2026-04-13 20:39:56.268752 | instance | /bin/bash: line 2: kubectl: command not found 2026-04-13 20:39:56.299105 | instance | ceph-mgr endpoints: 2026-04-13 20:39:56.598155 | instance | ok: Runtime: 0:00:00.037451 2026-04-13 20:39:56.606264 | 2026-04-13 20:39:56.606339 | TASK [gather-prom-metrics : Get metrics from fluentd pods] 2026-04-13 20:39:56.820565 | instance | /bin/bash: line 4: kubectl: command not found 2026-04-13 20:39:57.146759 | instance | ok: Runtime: 0:00:00.036108 2026-04-13 20:39:57.153467 | 2026-04-13 20:39:57.153531 | TASK [gather-prom-metrics : Downloads logs to executor] 2026-04-13 20:39:57.660011 | instance | changed: cd+++++++++ prometheus/ 2026-04-13 20:39:57.670825 | 2026-04-13 20:39:57.670935 | TASK [gather-selenium-data : creating directory for helm release descriptions] 2026-04-13 20:39:57.881215 | instance | changed 2026-04-13 20:39:57.887070 | 2026-04-13 20:39:57.887170 | TASK [gather-selenium-data : Get selenium data] 2026-04-13 20:39:58.096529 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/. 2026-04-13 20:39:58.098024 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory 2026-04-13 20:39:58.965885 | instance | ERROR 2026-04-13 20:39:58.966125 | instance | { 2026-04-13 20:39:58.966171 | instance | "delta": "0:00:00.006516", 2026-04-13 20:39:58.966202 | instance | "end": "2026-04-13 20:39:58.098450", 2026-04-13 20:39:58.966229 | instance | "msg": "non-zero return code", 2026-04-13 20:39:58.966254 | instance | "rc": 1, 2026-04-13 20:39:58.966279 | instance | "start": "2026-04-13 20:39:58.091934" 2026-04-13 20:39:58.966303 | instance | } 2026-04-13 20:39:58.966364 | instance | ERROR: Ignoring Errors 2026-04-13 20:39:58.974620 | 2026-04-13 20:39:58.974750 | TASK [gather-selenium-data : Downloads logs to executor] 2026-04-13 20:39:59.456785 | instance | changed: cd+++++++++ selenium/ 2026-04-13 20:39:59.466450 | 2026-04-13 20:39:59.466600 | PLAY RECAP 2026-04-13 20:39:59.466670 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1 2026-04-13 20:39:59.466704 | 2026-04-13 20:39:59.679552 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@main] 2026-04-13 20:39:59.683203 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-13 20:40:00.295168 | 2026-04-13 20:40:00.295314 | PLAY [all] 2026-04-13 20:40:00.306911 | 2026-04-13 20:40:00.306997 | TASK [fetch-output : Set log path for multiple nodes] 2026-04-13 20:40:00.351353 | instance | skipping: Conditional result was False 2026-04-13 20:40:00.360555 | 2026-04-13 20:40:00.360682 | TASK [fetch-output : Set log path for single node] 2026-04-13 20:40:00.395766 | instance | ok 2026-04-13 20:40:00.400529 | 2026-04-13 20:40:00.400601 | LOOP [fetch-output : Ensure local output dirs] 2026-04-13 20:40:00.832401 | instance -> localhost | ok: "/var/lib/zuul/builds/e6e45c45b7c548638232d139dc0ba7b5/work/logs" 2026-04-13 20:40:01.065599 | instance -> localhost | changed: "/var/lib/zuul/builds/e6e45c45b7c548638232d139dc0ba7b5/work/artifacts" 2026-04-13 20:40:01.293459 | instance -> localhost | changed: "/var/lib/zuul/builds/e6e45c45b7c548638232d139dc0ba7b5/work/docs" 2026-04-13 20:40:01.310326 | 2026-04-13 20:40:01.310604 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-04-13 20:40:01.991466 | instance | changed: .d..t...... ./ 2026-04-13 20:40:01.991711 | instance | changed: All items complete 2026-04-13 20:40:01.991748 | 2026-04-13 20:40:02.473192 | instance | changed: .d..t...... ./ 2026-04-13 20:40:02.925409 | instance | changed: .d..t...... ./ 2026-04-13 20:40:02.945668 | 2026-04-13 20:40:02.945816 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-04-13 20:40:03.369503 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.007047 2026-04-13 20:40:03.606712 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.008585 2026-04-13 20:40:03.628810 | 2026-04-13 20:40:03.628996 | PLAY [all] 2026-04-13 20:40:03.635909 | 2026-04-13 20:40:03.635978 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-04-13 20:40:04.044895 | instance | changed 2026-04-13 20:40:04.052069 | 2026-04-13 20:40:04.052137 | PLAY RECAP 2026-04-13 20:40:04.052208 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-04-13 20:40:04.052240 | 2026-04-13 20:40:04.244992 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-13 20:40:04.247633 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-04-13 20:40:04.813100 | 2026-04-13 20:40:04.813261 | PLAY [localhost] 2026-04-13 20:40:04.824200 | 2026-04-13 20:40:04.824294 | TASK [Generate Zuul manifest] 2026-04-13 20:40:04.846345 | localhost | ok 2026-04-13 20:40:04.862523 | 2026-04-13 20:40:04.862620 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-04-13 20:40:05.261903 | localhost | changed 2026-04-13 20:40:05.272553 | 2026-04-13 20:40:05.272692 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-04-13 20:40:05.302606 | localhost | ok 2026-04-13 20:40:05.327151 | 2026-04-13 20:40:05.327242 | TASK [Upload logs] 2026-04-13 20:40:05.346094 | localhost | ok 2026-04-13 20:40:05.428491 | 2026-04-13 20:40:05.428616 | TASK [Set zuul-log-path fact] 2026-04-13 20:40:05.460124 | localhost | ok 2026-04-13 20:40:05.473552 | 2026-04-13 20:40:05.473674 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-13 20:40:05.503066 | localhost | ok 2026-04-13 20:40:05.510766 | 2026-04-13 20:40:05.510893 | TASK [upload-logs : Create log directories] 2026-04-13 20:40:06.013329 | localhost | changed 2026-04-13 20:40:06.020521 | 2026-04-13 20:40:06.020639 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-04-13 20:40:07.603315 | localhost -> localhost | ok: Runtime: 0:00:00.004285 2026-04-13 20:40:07.607985 | 2026-04-13 20:40:07.608049 | TASK [upload-logs : Upload logs to log server] 2026-04-13 20:40:08.126092 | localhost | Output suppressed because no_log was given 2026-04-13 20:40:08.130902 | 2026-04-13 20:40:08.130968 | LOOP [upload-logs : Compress console log and json output] 2026-04-13 20:40:08.172588 | localhost | skipping: Conditional result was False 2026-04-13 20:40:08.179607 | localhost | skipping: Conditional result was False 2026-04-13 20:40:08.191448 | 2026-04-13 20:40:08.191607 | LOOP [upload-logs : Upload compressed console log and json output] 2026-04-13 20:40:08.235406 | localhost | skipping: Conditional result was False 2026-04-13 20:40:08.235779 | 2026-04-13 20:40:08.241167 | localhost | skipping: Conditional result was False 2026-04-13 20:40:08.272849 | 2026-04-13 20:40:08.273031 | LOOP [upload-logs : Upload console log and json output]