2026-03-18 16:44:27.729928 | Job console starting 2026-03-18 16:44:27.740933 | Updating git repos 2026-03-18 16:44:27.826546 | Cloning repos into workspace 2026-03-18 16:44:27.899015 | Restoring repo states 2026-03-18 16:44:27.925462 | Merging changes 2026-03-18 16:44:28.956092 | Checking out repos 2026-03-18 16:44:29.381754 | Preparing playbooks 2026-03-18 16:44:42.726972 | Running Ansible setup 2026-03-18 16:44:49.029644 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-03-18 16:44:49.664164 | 2026-03-18 16:44:49.664330 | PLAY [localhost] 2026-03-18 16:44:49.673200 | 2026-03-18 16:44:49.673272 | TASK [Gathering Facts] 2026-03-18 16:44:50.763295 | localhost | ok 2026-03-18 16:44:50.790537 | 2026-03-18 16:44:50.790739 | TASK [Setup log path fact] 2026-03-18 16:44:50.813388 | localhost | ok 2026-03-18 16:44:50.825467 | 2026-03-18 16:44:50.825555 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-18 16:44:50.860528 | localhost | ok 2026-03-18 16:44:50.867512 | 2026-03-18 16:44:50.867646 | TASK [emit-job-header : Print job information] 2026-03-18 16:44:50.900984 | # Job Information 2026-03-18 16:44:50.901301 | Ansible Version: 2.16.16 2026-03-18 16:44:50.901384 | Job: atmosphere-molecule-csi-rbd 2026-03-18 16:44:50.901441 | Pipeline: check 2026-03-18 16:44:50.901494 | Executor: 0a8996d2b663 2026-03-18 16:44:50.901544 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3739 2026-03-18 16:44:50.901602 | Event ID: 982b1280-22e9-11f1-861f-572aaec3c01e 2026-03-18 16:44:50.906205 | 2026-03-18 16:44:50.906302 | LOOP [emit-job-header : Print node information] 2026-03-18 16:44:51.003673 | localhost | ok: 2026-03-18 16:44:51.003901 | localhost | # Node Information 2026-03-18 16:44:51.003939 | localhost | Inventory Hostname: instance 2026-03-18 16:44:51.003969 | localhost | Hostname: np0000159871 2026-03-18 16:44:51.003996 | localhost | Username: zuul 2026-03-18 16:44:51.004026 | localhost | Distro: Ubuntu 22.04 2026-03-18 16:44:51.004053 | localhost | Provider: yul1 2026-03-18 16:44:51.004080 | localhost | Region: ca-ymq-1 2026-03-18 16:44:51.004105 | localhost | Label: ubuntu-jammy 2026-03-18 16:44:51.004131 | localhost | Product Name: OpenStack Nova 2026-03-18 16:44:51.004156 | localhost | Interface IP: 199.204.45.149 2026-03-18 16:44:51.014360 | 2026-03-18 16:44:51.014663 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-03-18 16:44:51.441857 | localhost -> localhost | changed 2026-03-18 16:44:51.451065 | 2026-03-18 16:44:51.451185 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-03-18 16:44:52.393154 | localhost -> localhost | changed 2026-03-18 16:44:52.405035 | 2026-03-18 16:44:52.405100 | PLAY [all] 2026-03-18 16:44:52.414915 | 2026-03-18 16:44:52.414988 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-03-18 16:44:52.653884 | instance -> localhost | ok 2026-03-18 16:44:52.666053 | 2026-03-18 16:44:52.666232 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-03-18 16:44:52.703770 | instance | ok 2026-03-18 16:44:52.718686 | instance | included: /var/lib/zuul/builds/a4af5ed7babf4370b26e90ce1323fb91/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-03-18 16:44:52.725129 | 2026-03-18 16:44:52.725215 | TASK [add-build-sshkey : Create Temp SSH key] 2026-03-18 16:44:53.420683 | instance -> localhost | Generating public/private rsa key pair. 2026-03-18 16:44:53.420896 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/a4af5ed7babf4370b26e90ce1323fb91/work/a4af5ed7babf4370b26e90ce1323fb91_id_rsa 2026-03-18 16:44:53.420934 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/a4af5ed7babf4370b26e90ce1323fb91/work/a4af5ed7babf4370b26e90ce1323fb91_id_rsa.pub 2026-03-18 16:44:53.420985 | instance -> localhost | The key fingerprint is: 2026-03-18 16:44:53.421016 | instance -> localhost | SHA256:63Hbz+P+SJxk28RVmjLziTv4KYHY1BJvgIkhXAf9MMY zuul-build-sshkey 2026-03-18 16:44:53.421061 | instance -> localhost | The key's randomart image is: 2026-03-18 16:44:53.421089 | instance -> localhost | +---[RSA 3072]----+ 2026-03-18 16:44:53.421125 | instance -> localhost | | ...+*.o .| 2026-03-18 16:44:53.421153 | instance -> localhost | | ....E o o.| 2026-03-18 16:44:53.421180 | instance -> localhost | | . + = + o .| 2026-03-18 16:44:53.421206 | instance -> localhost | | + + * o.| 2026-03-18 16:44:53.421231 | instance -> localhost | | +S+ . = o| 2026-03-18 16:44:53.421257 | instance -> localhost | | . o... = = | 2026-03-18 16:44:53.421283 | instance -> localhost | | o o.o = .| 2026-03-18 16:44:53.421309 | instance -> localhost | | . o.+ =.. | 2026-03-18 16:44:53.421337 | instance -> localhost | | . ..++*o.| 2026-03-18 16:44:53.421363 | instance -> localhost | +----[SHA256]-----+ 2026-03-18 16:44:53.421423 | instance -> localhost | ok: Runtime: 0:00:00.218624 2026-03-18 16:44:53.426781 | 2026-03-18 16:44:53.426850 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-03-18 16:44:53.458795 | instance | ok 2026-03-18 16:44:53.470776 | instance | included: /var/lib/zuul/builds/a4af5ed7babf4370b26e90ce1323fb91/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-03-18 16:44:53.482434 | 2026-03-18 16:44:53.482572 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-03-18 16:44:53.508222 | instance | skipping: Conditional result was False 2026-03-18 16:44:53.514986 | 2026-03-18 16:44:53.515135 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-03-18 16:44:54.034308 | instance | changed 2026-03-18 16:44:54.146518 | 2026-03-18 16:44:54.146698 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-03-18 16:44:54.354041 | instance | ok 2026-03-18 16:44:54.359393 | 2026-03-18 16:44:54.359499 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-03-18 16:44:54.829637 | instance | changed 2026-03-18 16:44:54.957681 | 2026-03-18 16:44:54.957856 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-03-18 16:44:55.479706 | instance | changed 2026-03-18 16:44:55.485204 | 2026-03-18 16:44:55.485302 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-03-18 16:44:55.510073 | instance | skipping: Conditional result was False 2026-03-18 16:44:55.518522 | 2026-03-18 16:44:55.518650 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-03-18 16:44:55.884318 | instance -> localhost | changed 2026-03-18 16:44:55.902445 | 2026-03-18 16:44:55.902556 | TASK [add-build-sshkey : Add back temp key] 2026-03-18 16:44:56.182884 | instance -> localhost | Identity added: /var/lib/zuul/builds/a4af5ed7babf4370b26e90ce1323fb91/work/a4af5ed7babf4370b26e90ce1323fb91_id_rsa (zuul-build-sshkey) 2026-03-18 16:44:56.183152 | instance -> localhost | ok: Runtime: 0:00:00.013709 2026-03-18 16:44:56.192297 | 2026-03-18 16:44:56.192437 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-03-18 16:44:56.517200 | instance | ok 2026-03-18 16:44:56.522006 | 2026-03-18 16:44:56.522095 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-03-18 16:44:56.546915 | instance | skipping: Conditional result was False 2026-03-18 16:44:56.560264 | 2026-03-18 16:44:56.560345 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-03-18 16:44:56.918771 | instance | ok 2026-03-18 16:44:56.926940 | 2026-03-18 16:44:56.927041 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-03-18 16:44:59.809712 | instance | Output suppressed because no_log was given 2026-03-18 16:44:59.821053 | 2026-03-18 16:44:59.821125 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-03-18 16:45:00.149698 | instance | ok: "logs" 2026-03-18 16:45:00.149980 | instance | ok: All items complete 2026-03-18 16:45:00.150019 | 2026-03-18 16:45:00.305598 | instance | ok: "artifacts" 2026-03-18 16:45:00.461375 | instance | ok: "docs" 2026-03-18 16:45:00.478875 | 2026-03-18 16:45:00.479054 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-03-18 16:45:00.685973 | instance | changed: "logs" 2026-03-18 16:45:00.845249 | instance | changed: "artifacts" 2026-03-18 16:45:01.019929 | instance | changed: "docs" 2026-03-18 16:45:01.040828 | 2026-03-18 16:45:01.041040 | PLAY RECAP 2026-03-18 16:45:01.041124 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-03-18 16:45:01.041179 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-18 16:45:01.041214 | 2026-03-18 16:45:01.232034 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-03-18 16:45:01.236209 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-03-18 16:45:01.861842 | 2026-03-18 16:45:01.862026 | PLAY [all] 2026-03-18 16:45:01.877907 | 2026-03-18 16:45:01.878097 | TASK [setup-uv : Extract archive] 2026-03-18 16:45:04.248892 | instance | changed 2026-03-18 16:45:04.740578 | 2026-03-18 16:45:04.740777 | TASK [setup-uv : Print version] 2026-03-18 16:45:05.212979 | instance | uv 0.8.13 2026-03-18 16:45:05.643285 | instance | ok: Runtime: 0:00:00.119249 2026-03-18 16:45:05.650487 | 2026-03-18 16:45:05.650567 | PLAY RECAP 2026-03-18 16:45:05.650636 | instance | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-18 16:45:05.650671 | 2026-03-18 16:45:05.792177 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-03-18 16:45:05.795948 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/pre.yml@stable/2025.2] 2026-03-18 16:45:06.463974 | 2026-03-18 16:45:06.464140 | PLAY [all] 2026-03-18 16:45:06.475455 | 2026-03-18 16:45:06.475593 | TASK [Install "jq" for log collection] 2026-03-18 17:14:55.851004 | PRE-RUN END RESULT_TIMED_OUT: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/pre.yml@stable/2025.2] 2026-03-18 17:14:55.855609 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@stable/2025.2] 2026-03-18 17:14:56.481895 | 2026-03-18 17:14:56.482066 | PLAY [all] 2026-03-18 17:14:56.497322 | 2026-03-18 17:14:56.497446 | TASK [gather-host-logs : creating directory for system status] 2026-03-18 17:14:56.855478 | instance | changed 2026-03-18 17:14:56.862897 | 2026-03-18 17:14:56.863003 | TASK [gather-host-logs : Get logs for each host] 2026-03-18 17:14:57.223377 | instance | + systemd-cgls --full --all --no-pager 2026-03-18 17:14:57.232066 | instance | + ip addr 2026-03-18 17:14:57.233645 | instance | + ip route 2026-03-18 17:14:57.236069 | instance | + lsblk 2026-03-18 17:14:57.238832 | instance | + mount 2026-03-18 17:14:57.240527 | instance | + docker images 2026-03-18 17:14:57.240920 | instance | /bin/bash: line 7: docker: command not found 2026-03-18 17:14:57.241076 | instance | + brctl show 2026-03-18 17:14:57.241361 | instance | /bin/bash: line 8: brctl: command not found 2026-03-18 17:14:57.241532 | instance | + ps aux --sort=-%mem 2026-03-18 17:14:57.254793 | instance | + dpkg -l 2026-03-18 17:14:57.260793 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul)) 2026-03-18 17:14:57.261089 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul 2026-03-18 17:14:57.261118 | instance | /bin/bash: line 11: docker: command not found 2026-03-18 17:14:57.261338 | instance | + '[' '!' -z '' ']' 2026-03-18 17:14:57.402808 | instance | ok: Runtime: 0:00:00.042825 2026-03-18 17:14:57.414279 | 2026-03-18 17:14:57.414349 | TASK [gather-host-logs : Downloads logs to executor] 2026-03-18 17:14:58.078726 | instance | changed: 2026-03-18 17:14:58.078955 | instance | created directory /var/lib/zuul/builds/a4af5ed7babf4370b26e90ce1323fb91/work/logs/instance 2026-03-18 17:14:58.078993 | instance | cd+++++++++ system/ 2026-03-18 17:14:58.079023 | instance | >f+++++++++ system/brctl-show.txt 2026-03-18 17:14:58.079067 | instance | >f+++++++++ system/docker-images.txt 2026-03-18 17:14:58.079095 | instance | >f+++++++++ system/ip-addr.txt 2026-03-18 17:14:58.079126 | instance | >f+++++++++ system/ip-route.txt 2026-03-18 17:14:58.079154 | instance | >f+++++++++ system/lsblk.txt 2026-03-18 17:14:58.079182 | instance | >f+++++++++ system/mount.txt 2026-03-18 17:14:58.079211 | instance | >f+++++++++ system/packages.txt 2026-03-18 17:14:58.079238 | instance | >f+++++++++ system/ps.txt 2026-03-18 17:14:58.079265 | instance | >f+++++++++ system/systemd-cgls.txt 2026-03-18 17:14:58.089929 | 2026-03-18 17:14:58.089999 | LOOP [helm-release-status : creating directory for helm release status] 2026-03-18 17:14:58.304575 | instance | changed: "values" 2026-03-18 17:14:58.498220 | instance | changed: "releases" 2026-03-18 17:14:58.516058 | 2026-03-18 17:14:58.516260 | TASK [helm-release-status : Gather get release status for helm charts] 2026-03-18 17:14:58.733246 | instance | /bin/bash: line 3: kubectl: command not found 2026-03-18 17:14:59.056530 | instance | ok: Runtime: 0:00:00.004248 2026-03-18 17:14:59.062458 | 2026-03-18 17:14:59.062533 | TASK [helm-release-status : Downloads logs to executor] 2026-03-18 17:14:59.546230 | instance | changed: 2026-03-18 17:14:59.546401 | instance | cd+++++++++ helm/ 2026-03-18 17:14:59.546429 | instance | cd+++++++++ helm/releases/ 2026-03-18 17:14:59.546450 | instance | cd+++++++++ helm/values/ 2026-03-18 17:14:59.555428 | 2026-03-18 17:14:59.555498 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects] 2026-03-18 17:14:59.750743 | instance | changed 2026-03-18 17:14:59.758678 | 2026-03-18 17:14:59.758776 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects] 2026-03-18 17:14:59.982838 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-03-18 17:14:59.983350 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-03-18 17:14:59.987788 | instance | environment: line 1: kubectl: command not found 2026-03-18 17:14:59.989127 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-03-18 17:14:59.990480 | instance | environment: line 1: kubectl: command not found 2026-03-18 17:14:59.991703 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-03-18 17:14:59.993663 | instance | environment: line 1: kubectl: command not found 2026-03-18 17:14:59.994691 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-03-18 17:14:59.995125 | instance | environment: line 1: kubectl: command not found 2026-03-18 17:14:59.996190 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-03-18 17:14:59.997198 | instance | environment: line 1: kubectl: command not found 2026-03-18 17:14:59.998614 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-03-18 17:15:00.293393 | instance | ok: Runtime: 0:00:00.025748 2026-03-18 17:15:00.300556 | 2026-03-18 17:15:00.300671 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects] 2026-03-18 17:15:00.519096 | instance | changed 2026-03-18 17:15:00.525991 | 2026-03-18 17:15:00.526101 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects] 2026-03-18 17:15:00.782711 | instance | environment: line 5: kubectl: command not found 2026-03-18 17:15:00.783692 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-03-18 17:15:00.783856 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-03-18 17:15:00.784134 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-03-18 17:15:01.099809 | instance | ok: Runtime: 0:00:00.010435 2026-03-18 17:15:01.106064 | 2026-03-18 17:15:01.106174 | TASK [describe-kubernetes-objects : Downloads logs to executor] 2026-03-18 17:15:01.594293 | instance | changed: 2026-03-18 17:15:01.594533 | instance | cd+++++++++ objects/ 2026-03-18 17:15:01.594582 | instance | cd+++++++++ objects/cluster/ 2026-03-18 17:15:01.594623 | instance | cd+++++++++ objects/namespaced/ 2026-03-18 17:15:01.604615 | 2026-03-18 17:15:01.604702 | TASK [gather-pod-logs : creating directory for pod logs] 2026-03-18 17:15:01.810172 | instance | changed 2026-03-18 17:15:01.817341 | 2026-03-18 17:15:01.817456 | TASK [gather-pod-logs : creating directory for failed pod logs] 2026-03-18 17:15:02.045450 | instance | changed 2026-03-18 17:15:02.050585 | 2026-03-18 17:15:02.050679 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)] 2026-03-18 17:15:02.286533 | instance | environment: line 3: kubectl: command not found 2026-03-18 17:15:02.588691 | instance | ok: Runtime: 0:00:00.009608 2026-03-18 17:15:02.595055 | 2026-03-18 17:15:02.595128 | TASK [gather-pod-logs : Downloads pod logs to executor] 2026-03-18 17:15:03.083142 | instance | changed: 2026-03-18 17:15:03.083344 | instance | cd+++++++++ pod-logs/ 2026-03-18 17:15:03.083387 | instance | cd+++++++++ pod-logs/failed-pods/ 2026-03-18 17:15:03.096371 | 2026-03-18 17:15:03.096467 | TASK [gather-prom-metrics : creating directory for helm release descriptions] 2026-03-18 17:15:03.323648 | instance | changed 2026-03-18 17:15:03.328471 | 2026-03-18 17:15:03.328539 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces] 2026-03-18 17:15:03.554201 | instance | /bin/bash: line 2: kubectl: command not found 2026-03-18 17:15:03.554591 | instance | /bin/bash: line 2: jq: command not found 2026-03-18 17:15:03.862538 | instance | ERROR 2026-03-18 17:15:03.862814 | instance | { 2026-03-18 17:15:03.862887 | instance | "delta": "0:00:00.006111", 2026-03-18 17:15:03.862937 | instance | "end": "2026-03-18 17:15:03.555857", 2026-03-18 17:15:03.862983 | instance | "msg": "non-zero return code", 2026-03-18 17:15:03.863026 | instance | "rc": 127, 2026-03-18 17:15:03.863069 | instance | "start": "2026-03-18 17:15:03.549746" 2026-03-18 17:15:03.863112 | instance | } 2026-03-18 17:15:03.863168 | instance | ERROR: Ignoring Errors 2026-03-18 17:15:03.868878 | 2026-03-18 17:15:03.868965 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr] 2026-03-18 17:15:04.090368 | instance | /bin/bash: line 2: kubectl: command not found 2026-03-18 17:15:04.090512 | instance | /bin/bash: line 2: jq: command not found 2026-03-18 17:15:04.405046 | instance | ERROR 2026-03-18 17:15:04.405289 | instance | { 2026-03-18 17:15:04.405362 | instance | "delta": "0:00:00.007112", 2026-03-18 17:15:04.405410 | instance | "end": "2026-03-18 17:15:04.091419", 2026-03-18 17:15:04.405452 | instance | "msg": "non-zero return code", 2026-03-18 17:15:04.405496 | instance | "rc": 127, 2026-03-18 17:15:04.405544 | instance | "start": "2026-03-18 17:15:04.084307" 2026-03-18 17:15:04.405589 | instance | } 2026-03-18 17:15:04.405648 | instance | ERROR: Ignoring Errors 2026-03-18 17:15:04.412261 | 2026-03-18 17:15:04.412339 | TASK [gather-prom-metrics : Get metrics from fluentd pods] 2026-03-18 17:15:04.643042 | instance | /bin/bash: line 4: kubectl: command not found 2026-03-18 17:15:04.643187 | instance | /bin/bash: line 4: jq: command not found 2026-03-18 17:15:04.947082 | instance | ERROR 2026-03-18 17:15:04.947396 | instance | { 2026-03-18 17:15:04.947467 | instance | "delta": "0:00:00.007224", 2026-03-18 17:15:04.947520 | instance | "end": "2026-03-18 17:15:04.644262", 2026-03-18 17:15:04.947572 | instance | "msg": "non-zero return code", 2026-03-18 17:15:04.947618 | instance | "rc": 127, 2026-03-18 17:15:04.947684 | instance | "start": "2026-03-18 17:15:04.637038" 2026-03-18 17:15:04.947732 | instance | } 2026-03-18 17:15:04.947793 | instance | ERROR: Ignoring Errors 2026-03-18 17:15:04.954880 | 2026-03-18 17:15:04.954973 | TASK [gather-prom-metrics : Downloads logs to executor] 2026-03-18 17:15:05.464932 | instance | changed: cd+++++++++ prometheus/ 2026-03-18 17:15:05.478047 | 2026-03-18 17:15:05.478183 | TASK [gather-selenium-data : creating directory for helm release descriptions] 2026-03-18 17:15:05.726979 | instance | changed 2026-03-18 17:15:05.734273 | 2026-03-18 17:15:05.734388 | TASK [gather-selenium-data : Get selenium data] 2026-03-18 17:15:05.951585 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/. 2026-03-18 17:15:05.952969 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory 2026-03-18 17:15:06.273266 | instance | ERROR 2026-03-18 17:15:06.273630 | instance | { 2026-03-18 17:15:06.273678 | instance | "delta": "0:00:00.005949", 2026-03-18 17:15:06.273713 | instance | "end": "2026-03-18 17:15:05.953332", 2026-03-18 17:15:06.273745 | instance | "msg": "non-zero return code", 2026-03-18 17:15:06.273776 | instance | "rc": 1, 2026-03-18 17:15:06.273807 | instance | "start": "2026-03-18 17:15:05.947383" 2026-03-18 17:15:06.273838 | instance | } 2026-03-18 17:15:06.273878 | instance | ERROR: Ignoring Errors 2026-03-18 17:15:06.281233 | 2026-03-18 17:15:06.281356 | TASK [gather-selenium-data : Downloads logs to executor] 2026-03-18 17:15:06.776832 | instance | changed: cd+++++++++ selenium/ 2026-03-18 17:15:06.783516 | 2026-03-18 17:15:06.783591 | PLAY RECAP 2026-03-18 17:15:06.783636 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 4 2026-03-18 17:15:06.783658 | 2026-03-18 17:15:06.913921 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@stable/2025.2] 2026-03-18 17:15:06.918025 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-03-18 17:15:07.571178 | 2026-03-18 17:15:07.571310 | PLAY [all] 2026-03-18 17:15:07.583408 | 2026-03-18 17:15:07.583515 | TASK [fetch-output : Set log path for multiple nodes] 2026-03-18 17:15:07.628098 | instance | skipping: Conditional result was False 2026-03-18 17:15:07.639218 | 2026-03-18 17:15:07.639437 | TASK [fetch-output : Set log path for single node] 2026-03-18 17:15:07.683260 | instance | ok 2026-03-18 17:15:07.688170 | 2026-03-18 17:15:07.688271 | LOOP [fetch-output : Ensure local output dirs] 2026-03-18 17:15:08.090838 | instance -> localhost | ok: "/var/lib/zuul/builds/a4af5ed7babf4370b26e90ce1323fb91/work/logs" 2026-03-18 17:15:08.319156 | instance -> localhost | changed: "/var/lib/zuul/builds/a4af5ed7babf4370b26e90ce1323fb91/work/artifacts" 2026-03-18 17:15:08.549169 | instance -> localhost | changed: "/var/lib/zuul/builds/a4af5ed7babf4370b26e90ce1323fb91/work/docs" 2026-03-18 17:15:08.569792 | 2026-03-18 17:15:08.570022 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-03-18 17:15:09.193243 | instance | changed: .d..t...... ./ 2026-03-18 17:15:09.193507 | instance | changed: All items complete 2026-03-18 17:15:09.193549 | 2026-03-18 17:15:09.647743 | instance | changed: .d..t...... ./ 2026-03-18 17:15:10.135915 | instance | changed: .d..t...... ./ 2026-03-18 17:15:10.160735 | 2026-03-18 17:15:10.160869 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-03-18 17:15:10.615375 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.008624 2026-03-18 17:15:10.864471 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.007488 2026-03-18 17:15:10.884532 | 2026-03-18 17:15:10.884696 | PLAY [all] 2026-03-18 17:15:10.891510 | 2026-03-18 17:15:10.891638 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-03-18 17:15:11.324693 | instance | changed 2026-03-18 17:15:11.331498 | 2026-03-18 17:15:11.331550 | PLAY RECAP 2026-03-18 17:15:11.331596 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-03-18 17:15:11.331637 | 2026-03-18 17:15:11.471975 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-03-18 17:15:11.476686 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-03-18 17:15:12.078654 | 2026-03-18 17:15:12.078822 | PLAY [localhost] 2026-03-18 17:15:12.091514 | 2026-03-18 17:15:12.091681 | TASK [Generate Zuul manifest] 2026-03-18 17:15:12.116062 | localhost | ok 2026-03-18 17:15:12.135237 | 2026-03-18 17:15:12.135464 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-03-18 17:15:12.502742 | localhost | changed 2026-03-18 17:15:12.515986 | 2026-03-18 17:15:12.516165 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-03-18 17:15:12.649190 | localhost | ok 2026-03-18 17:15:12.697227 | 2026-03-18 17:15:12.697380 | TASK [Upload logs] 2026-03-18 17:15:12.731610 | localhost | ok 2026-03-18 17:15:12.800433 | 2026-03-18 17:15:12.800600 | TASK [Set zuul-log-path fact] 2026-03-18 17:15:12.824461 | localhost | ok 2026-03-18 17:15:12.837810 | 2026-03-18 17:15:12.837960 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-18 17:15:12.871203 | localhost | ok 2026-03-18 17:15:12.883274 | 2026-03-18 17:15:12.883509 | TASK [upload-logs : Create log directories] 2026-03-18 17:15:13.324819 | localhost | changed 2026-03-18 17:15:13.329919 | 2026-03-18 17:15:13.329991 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-03-18 17:15:13.760870 | localhost -> localhost | ok: Runtime: 0:00:00.004955 2026-03-18 17:15:13.766523 | 2026-03-18 17:15:13.766628 | TASK [upload-logs : Upload logs to log server] 2026-03-18 17:15:14.391121 | localhost | Output suppressed because no_log was given 2026-03-18 17:15:14.396555 | 2026-03-18 17:15:14.396647 | LOOP [upload-logs : Compress console log and json output] 2026-03-18 17:15:14.443759 | localhost | skipping: Conditional result was False 2026-03-18 17:15:14.453213 | localhost | skipping: Conditional result was False 2026-03-18 17:15:14.464083 | 2026-03-18 17:15:14.473138 | LOOP [upload-logs : Upload compressed console log and json output] 2026-03-18 17:15:14.518559 | localhost | skipping: Conditional result was False 2026-03-18 17:15:14.519104 | 2026-03-18 17:15:14.522461 | localhost | skipping: Conditional result was False 2026-03-18 17:15:14.540278 | 2026-03-18 17:15:14.540457 | LOOP [upload-logs : Upload console log and json output]