2026-03-18 12:40:14.227307 | Job console starting 2026-03-18 12:40:14.236129 | Updating git repos 2026-03-18 12:40:14.293414 | Cloning repos into workspace 2026-03-18 12:40:14.362731 | Restoring repo states 2026-03-18 12:40:14.382347 | Merging changes 2026-03-18 12:40:15.293856 | Checking out repos 2026-03-18 12:40:15.391405 | Preparing playbooks 2026-03-18 12:40:23.230866 | Running Ansible setup 2026-03-18 12:40:26.629138 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-03-18 12:40:27.249980 | 2026-03-18 12:40:27.250149 | PLAY [localhost] 2026-03-18 12:40:27.259047 | 2026-03-18 12:40:27.259130 | TASK [Gathering Facts] 2026-03-18 12:40:28.157607 | localhost | ok 2026-03-18 12:40:28.174594 | 2026-03-18 12:40:28.174711 | TASK [Setup log path fact] 2026-03-18 12:40:28.195426 | localhost | ok 2026-03-18 12:40:28.212677 | 2026-03-18 12:40:28.212767 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-18 12:40:28.250072 | localhost | ok 2026-03-18 12:40:28.256266 | 2026-03-18 12:40:28.256333 | TASK [emit-job-header : Print job information] 2026-03-18 12:40:28.295667 | # Job Information 2026-03-18 12:40:28.295872 | Ansible Version: 2.16.16 2026-03-18 12:40:28.295918 | Job: atmosphere-molecule-csi-rbd 2026-03-18 12:40:28.295949 | Pipeline: check 2026-03-18 12:40:28.295977 | Executor: 0a8996d2b663 2026-03-18 12:40:28.296004 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3501 2026-03-18 12:40:28.296035 | Event ID: 22b80e80-22c7-11f1-9939-0f4f6e9b239e 2026-03-18 12:40:28.300032 | 2026-03-18 12:40:28.300120 | LOOP [emit-job-header : Print node information] 2026-03-18 12:40:28.404322 | localhost | ok: 2026-03-18 12:40:28.404618 | localhost | # Node Information 2026-03-18 12:40:28.404681 | localhost | Inventory Hostname: instance 2026-03-18 12:40:28.404732 | localhost | Hostname: np0000159836 2026-03-18 12:40:28.404777 | localhost | Username: zuul 2026-03-18 12:40:28.404827 | localhost | Distro: Ubuntu 22.04 2026-03-18 12:40:28.404871 | localhost | Provider: yul1 2026-03-18 12:40:28.404913 | localhost | Region: ca-ymq-1 2026-03-18 12:40:28.404987 | localhost | Label: ubuntu-jammy 2026-03-18 12:40:28.405040 | localhost | Product Name: OpenStack Nova 2026-03-18 12:40:28.405082 | localhost | Interface IP: 199.19.213.119 2026-03-18 12:40:28.426547 | 2026-03-18 12:40:28.426727 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-03-18 12:40:28.848111 | localhost -> localhost | changed 2026-03-18 12:40:28.857657 | 2026-03-18 12:40:28.857799 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-03-18 12:40:29.702522 | localhost -> localhost | changed 2026-03-18 12:40:29.716348 | 2026-03-18 12:40:29.716525 | PLAY [all] 2026-03-18 12:40:29.727690 | 2026-03-18 12:40:29.727761 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-03-18 12:40:29.956304 | instance -> localhost | ok 2026-03-18 12:40:29.966257 | 2026-03-18 12:40:29.966443 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-03-18 12:40:29.996247 | instance | ok 2026-03-18 12:40:30.011851 | instance | included: /var/lib/zuul/builds/396ef8f81818447b82d2ba3e24ee67db/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-03-18 12:40:30.017849 | 2026-03-18 12:40:30.017913 | TASK [add-build-sshkey : Create Temp SSH key] 2026-03-18 12:40:30.733069 | instance -> localhost | Generating public/private rsa key pair. 2026-03-18 12:40:30.733332 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/396ef8f81818447b82d2ba3e24ee67db/work/396ef8f81818447b82d2ba3e24ee67db_id_rsa 2026-03-18 12:40:30.733378 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/396ef8f81818447b82d2ba3e24ee67db/work/396ef8f81818447b82d2ba3e24ee67db_id_rsa.pub 2026-03-18 12:40:30.733416 | instance -> localhost | The key fingerprint is: 2026-03-18 12:40:30.733453 | instance -> localhost | SHA256:YrAY47nu30tV8Ezu7hjh6Fv3QTXdRamgEaQgumIw930 zuul-build-sshkey 2026-03-18 12:40:30.733517 | instance -> localhost | The key's randomart image is: 2026-03-18 12:40:30.733552 | instance -> localhost | +---[RSA 3072]----+ 2026-03-18 12:40:30.733596 | instance -> localhost | | . . ooo .+| 2026-03-18 12:40:30.733630 | instance -> localhost | | . . . .B . oo| 2026-03-18 12:40:30.733662 | instance -> localhost | |o.+ . . B . + o| 2026-03-18 12:40:30.733694 | instance -> localhost | |.+.* + + o . | 2026-03-18 12:40:30.733726 | instance -> localhost | |.o+ o + E . . | 2026-03-18 12:40:30.733758 | instance -> localhost | |o . . * o . | 2026-03-18 12:40:30.733789 | instance -> localhost | | . o + o . | 2026-03-18 12:40:30.733851 | instance -> localhost | | . + . = . . | 2026-03-18 12:40:30.733912 | instance -> localhost | | .o.. =o. . . | 2026-03-18 12:40:30.733958 | instance -> localhost | +----[SHA256]-----+ 2026-03-18 12:40:30.734052 | instance -> localhost | ok: Runtime: 0:00:00.284059 2026-03-18 12:40:30.740480 | 2026-03-18 12:40:30.740644 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-03-18 12:40:30.775433 | instance | ok 2026-03-18 12:40:30.784390 | instance | included: /var/lib/zuul/builds/396ef8f81818447b82d2ba3e24ee67db/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-03-18 12:40:30.791793 | 2026-03-18 12:40:30.791862 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-03-18 12:40:30.816215 | instance | skipping: Conditional result was False 2026-03-18 12:40:30.825651 | 2026-03-18 12:40:30.825771 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-03-18 12:40:31.268512 | instance | changed 2026-03-18 12:40:31.273707 | 2026-03-18 12:40:31.273774 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-03-18 12:40:31.470584 | instance | ok 2026-03-18 12:40:31.477564 | 2026-03-18 12:40:31.477680 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-03-18 12:40:31.967572 | instance | changed 2026-03-18 12:40:31.973727 | 2026-03-18 12:40:31.973793 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-03-18 12:40:32.432329 | instance | changed 2026-03-18 12:40:32.437959 | 2026-03-18 12:40:32.438040 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-03-18 12:40:32.462984 | instance | skipping: Conditional result was False 2026-03-18 12:40:32.472483 | 2026-03-18 12:40:32.472584 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-03-18 12:40:32.833289 | instance -> localhost | changed 2026-03-18 12:40:32.848622 | 2026-03-18 12:40:32.848734 | TASK [add-build-sshkey : Add back temp key] 2026-03-18 12:40:33.146049 | instance -> localhost | Identity added: /var/lib/zuul/builds/396ef8f81818447b82d2ba3e24ee67db/work/396ef8f81818447b82d2ba3e24ee67db_id_rsa (zuul-build-sshkey) 2026-03-18 12:40:33.146329 | instance -> localhost | ok: Runtime: 0:00:00.016270 2026-03-18 12:40:33.155337 | 2026-03-18 12:40:33.155419 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-03-18 12:40:33.497688 | instance | ok 2026-03-18 12:40:33.504869 | 2026-03-18 12:40:33.504980 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-03-18 12:40:33.530421 | instance | skipping: Conditional result was False 2026-03-18 12:40:33.544586 | 2026-03-18 12:40:33.544691 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-03-18 12:40:33.913065 | instance | ok 2026-03-18 12:40:33.922922 | 2026-03-18 12:40:33.922988 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-03-18 12:40:35.575845 | instance | Output suppressed because no_log was given 2026-03-18 12:40:35.593487 | 2026-03-18 12:40:35.593627 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-03-18 12:40:35.794613 | instance | ok: "logs" 2026-03-18 12:40:35.795058 | instance | ok: All items complete 2026-03-18 12:40:35.795122 | 2026-03-18 12:40:35.946973 | instance | ok: "artifacts" 2026-03-18 12:40:36.116073 | instance | ok: "docs" 2026-03-18 12:40:36.138235 | 2026-03-18 12:40:36.138398 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-03-18 12:40:36.367505 | instance | changed: "logs" 2026-03-18 12:40:36.532332 | instance | changed: "artifacts" 2026-03-18 12:40:36.718509 | instance | changed: "docs" 2026-03-18 12:40:36.732357 | 2026-03-18 12:40:36.732505 | PLAY RECAP 2026-03-18 12:40:36.732574 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-03-18 12:40:36.732611 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-18 12:40:36.732634 | 2026-03-18 12:40:36.878646 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-03-18 12:40:36.882690 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-03-18 12:40:37.496881 | 2026-03-18 12:40:37.497080 | PLAY [all] 2026-03-18 12:40:37.509270 | 2026-03-18 12:40:37.509401 | TASK [setup-uv : Extract archive] 2026-03-18 12:40:39.672040 | instance | changed 2026-03-18 12:40:39.681101 | 2026-03-18 12:40:39.681316 | TASK [setup-uv : Print version] 2026-03-18 12:40:39.669575 | instance | uv 0.8.13 2026-03-18 12:40:40.227510 | instance | ok: Runtime: 0:00:00.011636 2026-03-18 12:40:40.235804 | 2026-03-18 12:40:40.235874 | PLAY RECAP 2026-03-18 12:40:40.235940 | instance | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-18 12:40:40.235973 | 2026-03-18 12:40:40.363086 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-03-18 12:40:40.368746 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/pre.yml@main] 2026-03-18 12:40:40.985971 | 2026-03-18 12:40:40.986109 | PLAY [all] 2026-03-18 12:40:40.999577 | 2026-03-18 12:40:40.999768 | TASK [Install "jq" for log collection] 2026-03-18 13:10:31.614731 | PRE-RUN END RESULT_TIMED_OUT: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/pre.yml@main] 2026-03-18 13:10:31.641532 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@main] 2026-03-18 13:10:32.308618 | 2026-03-18 13:10:32.308855 | PLAY [all] 2026-03-18 13:10:32.326982 | 2026-03-18 13:10:32.327147 | TASK [gather-host-logs : creating directory for system status] 2026-03-18 13:10:32.697183 | instance | changed 2026-03-18 13:10:32.703043 | 2026-03-18 13:10:32.703192 | TASK [gather-host-logs : Get logs for each host] 2026-03-18 13:10:33.132119 | instance | + systemd-cgls --full --all --no-pager 2026-03-18 13:10:33.143888 | instance | + ip addr 2026-03-18 13:10:33.145620 | instance | + ip route 2026-03-18 13:10:33.146700 | instance | + lsblk 2026-03-18 13:10:33.150781 | instance | + mount 2026-03-18 13:10:33.152635 | instance | + docker images 2026-03-18 13:10:33.153065 | instance | /bin/bash: line 7: docker: command not found 2026-03-18 13:10:33.153322 | instance | + brctl show 2026-03-18 13:10:33.153712 | instance | /bin/bash: line 8: brctl: command not found 2026-03-18 13:10:33.153963 | instance | + ps aux --sort=-%mem 2026-03-18 13:10:33.167732 | instance | + dpkg -l 2026-03-18 13:10:33.173480 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul)) 2026-03-18 13:10:33.173822 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul 2026-03-18 13:10:33.173854 | instance | /bin/bash: line 11: docker: command not found 2026-03-18 13:10:33.174088 | instance | + '[' '!' -z '' ']' 2026-03-18 13:10:33.247479 | instance | ok: Runtime: 0:00:00.046988 2026-03-18 13:10:33.257248 | 2026-03-18 13:10:33.257369 | TASK [gather-host-logs : Downloads logs to executor] 2026-03-18 13:10:34.005023 | instance | changed: 2026-03-18 13:10:34.005735 | instance | created directory /var/lib/zuul/builds/396ef8f81818447b82d2ba3e24ee67db/work/logs/instance 2026-03-18 13:10:34.005785 | instance | cd+++++++++ system/ 2026-03-18 13:10:34.005820 | instance | >f+++++++++ system/brctl-show.txt 2026-03-18 13:10:34.005875 | instance | >f+++++++++ system/docker-images.txt 2026-03-18 13:10:34.005907 | instance | >f+++++++++ system/ip-addr.txt 2026-03-18 13:10:34.005943 | instance | >f+++++++++ system/ip-route.txt 2026-03-18 13:10:34.005976 | instance | >f+++++++++ system/lsblk.txt 2026-03-18 13:10:34.006007 | instance | >f+++++++++ system/mount.txt 2026-03-18 13:10:34.006040 | instance | >f+++++++++ system/packages.txt 2026-03-18 13:10:34.006068 | instance | >f+++++++++ system/ps.txt 2026-03-18 13:10:34.006099 | instance | >f+++++++++ system/systemd-cgls.txt 2026-03-18 13:10:34.017714 | 2026-03-18 13:10:34.018030 | LOOP [helm-release-status : creating directory for helm release status] 2026-03-18 13:10:34.227231 | instance | changed: "values" 2026-03-18 13:10:34.393087 | instance | changed: "releases" 2026-03-18 13:10:34.411023 | 2026-03-18 13:10:34.411189 | TASK [helm-release-status : Gather get release status for helm charts] 2026-03-18 13:10:34.646633 | instance | /bin/bash: line 3: kubectl: command not found 2026-03-18 13:10:34.952563 | instance | ok: Runtime: 0:00:00.005528 2026-03-18 13:10:34.959788 | 2026-03-18 13:10:34.959925 | TASK [helm-release-status : Downloads logs to executor] 2026-03-18 13:10:35.449481 | instance | changed: 2026-03-18 13:10:35.449657 | instance | cd+++++++++ helm/ 2026-03-18 13:10:35.449686 | instance | cd+++++++++ helm/releases/ 2026-03-18 13:10:35.449708 | instance | cd+++++++++ helm/values/ 2026-03-18 13:10:35.458787 | 2026-03-18 13:10:35.458919 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects] 2026-03-18 13:10:35.645686 | instance | changed 2026-03-18 13:10:35.652351 | 2026-03-18 13:10:35.652474 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects] 2026-03-18 13:10:35.886407 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-03-18 13:10:35.886709 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-03-18 13:10:35.891809 | instance | environment: line 1: kubectl: command not found 2026-03-18 13:10:35.893247 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-03-18 13:10:35.893720 | instance | environment: line 1: kubectl: command not found 2026-03-18 13:10:35.895345 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-03-18 13:10:35.895745 | instance | environment: line 1: kubectl: command not found 2026-03-18 13:10:35.896758 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-03-18 13:10:35.898847 | instance | environment: line 1: kubectl: command not found 2026-03-18 13:10:35.899068 | instance | environment: line 1: kubectl: command not found 2026-03-18 13:10:35.900303 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines valuexargs: 2026-03-18 13:10:35.900319 | instance | warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-03-18 13:10:36.191059 | instance | ok: Runtime: 0:00:00.023172 2026-03-18 13:10:36.199024 | 2026-03-18 13:10:36.199153 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects] 2026-03-18 13:10:36.394056 | instance | changed 2026-03-18 13:10:36.401357 | 2026-03-18 13:10:36.401464 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects] 2026-03-18 13:10:36.671930 | instance | environment: line 5: kubectl: command not found 2026-03-18 13:10:36.672149 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-03-18 13:10:36.673573 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-03-18 13:10:36.673607 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-03-18 13:10:36.971368 | instance | ok: Runtime: 0:00:00.007656 2026-03-18 13:10:36.979602 | 2026-03-18 13:10:36.979771 | TASK [describe-kubernetes-objects : Downloads logs to executor] 2026-03-18 13:10:37.491598 | instance | changed: 2026-03-18 13:10:37.491847 | instance | cd+++++++++ objects/ 2026-03-18 13:10:37.491888 | instance | cd+++++++++ objects/cluster/ 2026-03-18 13:10:37.491920 | instance | cd+++++++++ objects/namespaced/ 2026-03-18 13:10:37.505735 | 2026-03-18 13:10:37.505928 | TASK [gather-pod-logs : creating directory for pod logs] 2026-03-18 13:10:37.703819 | instance | changed 2026-03-18 13:10:37.712803 | 2026-03-18 13:10:37.713074 | TASK [gather-pod-logs : creating directory for failed pod logs] 2026-03-18 13:10:37.907327 | instance | changed 2026-03-18 13:10:37.912395 | 2026-03-18 13:10:37.912611 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)] 2026-03-18 13:10:38.143356 | instance | environment: line 3: kubectl: command not found 2026-03-18 13:10:38.447831 | instance | ok: Runtime: 0:00:00.007608 2026-03-18 13:10:38.455936 | 2026-03-18 13:10:38.456099 | TASK [gather-pod-logs : Downloads pod logs to executor] 2026-03-18 13:10:38.965573 | instance | changed: 2026-03-18 13:10:38.965804 | instance | cd+++++++++ pod-logs/ 2026-03-18 13:10:38.965846 | instance | cd+++++++++ pod-logs/failed-pods/ 2026-03-18 13:10:38.980693 | 2026-03-18 13:10:38.981131 | TASK [gather-prom-metrics : creating directory for helm release descriptions] 2026-03-18 13:10:39.195885 | instance | changed 2026-03-18 13:10:39.203531 | 2026-03-18 13:10:39.203708 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces] 2026-03-18 13:10:39.452030 | instance | /bin/bash: line 2: kubectl: command not found 2026-03-18 13:10:39.452162 | instance | /bin/bash: line 2: jq: command not found 2026-03-18 13:10:39.750258 | instance | ERROR 2026-03-18 13:10:39.750562 | instance | { 2026-03-18 13:10:39.750631 | instance | "delta": "0:00:00.006936", 2026-03-18 13:10:39.750678 | instance | "end": "2026-03-18 13:10:39.453328", 2026-03-18 13:10:39.750720 | instance | "msg": "non-zero return code", 2026-03-18 13:10:39.750761 | instance | "rc": 127, 2026-03-18 13:10:39.750802 | instance | "start": "2026-03-18 13:10:39.446392" 2026-03-18 13:10:39.750842 | instance | } 2026-03-18 13:10:39.750894 | instance | ERROR: Ignoring Errors 2026-03-18 13:10:39.760356 | 2026-03-18 13:10:39.760589 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr] 2026-03-18 13:10:39.999072 | instance | /bin/bash: line 2: kubectl: command not found 2026-03-18 13:10:39.999165 | instance | /bin/bash: line 2: jq: command not found 2026-03-18 13:10:40.301159 | instance | ERROR 2026-03-18 13:10:40.301449 | instance | { 2026-03-18 13:10:40.302492 | instance | "delta": "0:00:00.003841", 2026-03-18 13:10:40.302539 | instance | "end": "2026-03-18 13:10:39.999743", 2026-03-18 13:10:40.302569 | instance | "msg": "non-zero return code", 2026-03-18 13:10:40.302600 | instance | "rc": 127, 2026-03-18 13:10:40.302627 | instance | "start": "2026-03-18 13:10:39.995902" 2026-03-18 13:10:40.302653 | instance | } 2026-03-18 13:10:40.302692 | instance | ERROR: Ignoring Errors 2026-03-18 13:10:40.311358 | 2026-03-18 13:10:40.311506 | TASK [gather-prom-metrics : Get metrics from fluentd pods] 2026-03-18 13:10:40.544264 | instance | /bin/bash: line 4: kubectl: command not found 2026-03-18 13:10:40.544373 | instance | /bin/bash: line 4: jq: command not found 2026-03-18 13:10:40.844926 | instance | ERROR 2026-03-18 13:10:40.845160 | instance | { 2026-03-18 13:10:40.845191 | instance | "delta": "0:00:00.004735", 2026-03-18 13:10:40.845212 | instance | "end": "2026-03-18 13:10:40.545250", 2026-03-18 13:10:40.845232 | instance | "msg": "non-zero return code", 2026-03-18 13:10:40.845251 | instance | "rc": 127, 2026-03-18 13:10:40.845270 | instance | "start": "2026-03-18 13:10:40.540515" 2026-03-18 13:10:40.845305 | instance | } 2026-03-18 13:10:40.845333 | instance | ERROR: Ignoring Errors 2026-03-18 13:10:40.850781 | 2026-03-18 13:10:40.851021 | TASK [gather-prom-metrics : Downloads logs to executor] 2026-03-18 13:10:41.344079 | instance | changed: cd+++++++++ prometheus/ 2026-03-18 13:10:41.355651 | 2026-03-18 13:10:41.355718 | TASK [gather-selenium-data : creating directory for helm release descriptions] 2026-03-18 13:10:41.551347 | instance | changed 2026-03-18 13:10:41.559716 | 2026-03-18 13:10:41.559879 | TASK [gather-selenium-data : Get selenium data] 2026-03-18 13:10:41.792251 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/. 2026-03-18 13:10:41.793664 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory 2026-03-18 13:10:42.100585 | instance | ERROR 2026-03-18 13:10:42.100804 | instance | { 2026-03-18 13:10:42.100833 | instance | "delta": "0:00:00.005857", 2026-03-18 13:10:42.100854 | instance | "end": "2026-03-18 13:10:41.794044", 2026-03-18 13:10:42.100872 | instance | "msg": "non-zero return code", 2026-03-18 13:10:42.100891 | instance | "rc": 1, 2026-03-18 13:10:42.100916 | instance | "start": "2026-03-18 13:10:41.788187" 2026-03-18 13:10:42.100966 | instance | } 2026-03-18 13:10:42.100994 | instance | ERROR: Ignoring Errors 2026-03-18 13:10:42.106172 | 2026-03-18 13:10:42.106240 | TASK [gather-selenium-data : Downloads logs to executor] 2026-03-18 13:10:42.628773 | instance | changed: cd+++++++++ selenium/ 2026-03-18 13:10:42.637283 | 2026-03-18 13:10:42.637363 | PLAY RECAP 2026-03-18 13:10:42.637414 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 4 2026-03-18 13:10:42.637436 | 2026-03-18 13:10:42.786764 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@main] 2026-03-18 13:10:42.788837 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-03-18 13:10:43.426723 | 2026-03-18 13:10:43.426889 | PLAY [all] 2026-03-18 13:10:43.441978 | 2026-03-18 13:10:43.442158 | TASK [fetch-output : Set log path for multiple nodes] 2026-03-18 13:10:43.498181 | instance | skipping: Conditional result was False 2026-03-18 13:10:43.507390 | 2026-03-18 13:10:43.507631 | TASK [fetch-output : Set log path for single node] 2026-03-18 13:10:43.561900 | instance | ok 2026-03-18 13:10:43.568066 | 2026-03-18 13:10:43.568187 | LOOP [fetch-output : Ensure local output dirs] 2026-03-18 13:10:43.990195 | instance -> localhost | ok: "/var/lib/zuul/builds/396ef8f81818447b82d2ba3e24ee67db/work/logs" 2026-03-18 13:10:44.206618 | instance -> localhost | changed: "/var/lib/zuul/builds/396ef8f81818447b82d2ba3e24ee67db/work/artifacts" 2026-03-18 13:10:44.427066 | instance -> localhost | changed: "/var/lib/zuul/builds/396ef8f81818447b82d2ba3e24ee67db/work/docs" 2026-03-18 13:10:44.453224 | 2026-03-18 13:10:44.453353 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-03-18 13:10:45.097114 | instance | changed: .d..t...... ./ 2026-03-18 13:10:45.097911 | instance | changed: All items complete 2026-03-18 13:10:45.097968 | 2026-03-18 13:10:45.544896 | instance | changed: .d..t...... ./ 2026-03-18 13:10:46.005202 | instance | changed: .d..t...... ./ 2026-03-18 13:10:46.032401 | 2026-03-18 13:10:46.032560 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-03-18 13:10:46.496778 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.007251 2026-03-18 13:10:46.740742 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.007389 2026-03-18 13:10:46.761330 | 2026-03-18 13:10:46.761462 | PLAY [all] 2026-03-18 13:10:46.769579 | 2026-03-18 13:10:46.769668 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-03-18 13:10:47.212136 | instance | changed 2026-03-18 13:10:47.218647 | 2026-03-18 13:10:47.218773 | PLAY RECAP 2026-03-18 13:10:47.218824 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-03-18 13:10:47.218875 | 2026-03-18 13:10:47.377846 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-03-18 13:10:47.382114 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-03-18 13:10:47.942855 | 2026-03-18 13:10:47.943023 | PLAY [localhost] 2026-03-18 13:10:47.954185 | 2026-03-18 13:10:47.954340 | TASK [Generate Zuul manifest] 2026-03-18 13:10:47.978027 | localhost | ok 2026-03-18 13:10:47.996867 | 2026-03-18 13:10:47.997075 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-03-18 13:10:48.397639 | localhost | changed 2026-03-18 13:10:48.408310 | 2026-03-18 13:10:48.408385 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-03-18 13:10:48.439665 | localhost | ok 2026-03-18 13:10:48.449498 | 2026-03-18 13:10:48.449596 | TASK [Upload logs] 2026-03-18 13:10:48.472442 | localhost | ok 2026-03-18 13:10:48.532938 | 2026-03-18 13:10:48.533108 | TASK [Set zuul-log-path fact] 2026-03-18 13:10:48.551320 | localhost | ok 2026-03-18 13:10:48.562132 | 2026-03-18 13:10:48.562217 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-18 13:10:48.585070 | localhost | ok 2026-03-18 13:10:48.593511 | 2026-03-18 13:10:48.593582 | TASK [upload-logs : Create log directories] 2026-03-18 13:10:48.999695 | localhost | changed 2026-03-18 13:10:49.007056 | 2026-03-18 13:10:49.007147 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-03-18 13:10:49.456662 | localhost -> localhost | ok: Runtime: 0:00:00.005871 2026-03-18 13:10:49.464151 | 2026-03-18 13:10:49.464262 | TASK [upload-logs : Upload logs to log server] 2026-03-18 13:10:50.005798 | localhost | Output suppressed because no_log was given 2026-03-18 13:10:50.011220 | 2026-03-18 13:10:50.011328 | LOOP [upload-logs : Compress console log and json output] 2026-03-18 13:10:50.066934 | localhost | skipping: Conditional result was False 2026-03-18 13:10:50.075686 | localhost | skipping: Conditional result was False 2026-03-18 13:10:50.087862 | 2026-03-18 13:10:50.088128 | LOOP [upload-logs : Upload compressed console log and json output] 2026-03-18 13:10:50.133617 | localhost | skipping: Conditional result was False 2026-03-18 13:10:50.134276 | 2026-03-18 13:10:50.138669 | localhost | skipping: Conditional result was False 2026-03-18 13:10:50.153893 | 2026-03-18 13:10:50.154114 | LOOP [upload-logs : Upload console log and json output]