2026-04-15 12:05:37.154807 | Job console starting
2026-04-15 12:05:37.166129 | Updating git repos
2026-04-15 12:05:37.240338 | Cloning repos into workspace
2026-04-15 12:05:37.385734 | Restoring repo states
2026-04-15 12:05:37.407215 | Merging changes
2026-04-15 12:05:38.388525 | Checking out repos
2026-04-15 12:05:38.512789 | Preparing playbooks
2026-04-15 12:05:42.125663 | Running Ansible setup
2026-04-15 12:05:45.608565 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-15 12:05:46.271210 | 
2026-04-15 12:05:46.271386 | PLAY [localhost]
2026-04-15 12:05:46.281389 | 
2026-04-15 12:05:46.281540 | TASK [Gathering Facts]
2026-04-15 12:05:47.180446 | localhost | ok
2026-04-15 12:05:47.189203 | 
2026-04-15 12:05:47.189341 | TASK [Setup log path fact]
2026-04-15 12:05:47.211730 | localhost | ok
2026-04-15 12:05:47.226928 | 
2026-04-15 12:05:47.227132 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-15 12:05:47.259000 | localhost | ok
2026-04-15 12:05:47.267819 | 
2026-04-15 12:05:47.267977 | TASK [emit-job-header : Print job information]
2026-04-15 12:05:47.308987 | # Job Information
2026-04-15 12:05:47.309239 | Ansible Version: 2.16.16
2026-04-15 12:05:47.309275 | Job: atmosphere-molecule-csi-rbd
2026-04-15 12:05:47.309300 | Pipeline: check
2026-04-15 12:05:47.309331 | Executor: 0a8996d2b663
2026-04-15 12:05:47.309354 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3835
2026-04-15 12:05:47.309380 | Event ID: 554fb020-38c3-11f1-8ff6-4f000f7144a1
2026-04-15 12:05:47.313390 | 
2026-04-15 12:05:47.313476 | LOOP [emit-job-header : Print node information]
2026-04-15 12:05:47.425853 | localhost | ok:
2026-04-15 12:05:47.426734 | localhost | # Node Information
2026-04-15 12:05:47.426783 | localhost | Inventory Hostname: instance
2026-04-15 12:05:47.426817 | localhost | Hostname: np0000166450
2026-04-15 12:05:47.426848 | localhost | Username: zuul
2026-04-15 12:05:47.426882 | localhost | Distro: Ubuntu 22.04
2026-04-15 12:05:47.426912 | localhost | Provider: yul1
2026-04-15 12:05:47.426940 | localhost | Region: ca-ymq-1
2026-04-15 12:05:47.426968 | localhost | Label: ubuntu-jammy
2026-04-15 12:05:47.426995 | localhost | Product Name: OpenStack Nova
2026-04-15 12:05:47.427023 | localhost | Interface IP: 199.204.45.23
2026-04-15 12:05:47.444947 | 
2026-04-15 12:05:47.445135 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-04-15 12:05:47.848520 | localhost -> localhost | changed
2026-04-15 12:05:47.856246 | 
2026-04-15 12:05:47.856343 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-04-15 12:05:48.714425 | localhost -> localhost | changed
2026-04-15 12:05:48.725108 | 
2026-04-15 12:05:48.725241 | PLAY [all]
2026-04-15 12:05:48.735017 | 
2026-04-15 12:05:48.735088 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-04-15 12:05:49.015543 | instance -> localhost | ok
2026-04-15 12:05:49.025887 | 
2026-04-15 12:05:49.025972 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-04-15 12:05:49.059670 | instance | ok
2026-04-15 12:05:49.073833 | instance | included: /var/lib/zuul/builds/666d28a6f255406cb81e7feb68b21074/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-04-15 12:05:49.080544 | 
2026-04-15 12:05:49.080628 | TASK [add-build-sshkey : Create Temp SSH key]
2026-04-15 12:05:49.950966 | instance -> localhost | Generating public/private rsa key pair.
2026-04-15 12:05:49.951190 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/666d28a6f255406cb81e7feb68b21074/work/666d28a6f255406cb81e7feb68b21074_id_rsa
2026-04-15 12:05:49.951223 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/666d28a6f255406cb81e7feb68b21074/work/666d28a6f255406cb81e7feb68b21074_id_rsa.pub
2026-04-15 12:05:49.951246 | instance -> localhost | The key fingerprint is:
2026-04-15 12:05:49.951270 | instance -> localhost | SHA256:DCCIBiMH9zqXXCroBlsluGhp2PXBN+7LEHcQGCttHWk zuul-build-sshkey
2026-04-15 12:05:49.951315 | instance -> localhost | The key's randomart image is:
2026-04-15 12:05:49.951337 | instance -> localhost | +---[RSA 3072]----+
2026-04-15 12:05:49.951367 | instance -> localhost | |Bo+ . .oo.       |
2026-04-15 12:05:49.951389 | instance -> localhost | |+* o +.oEo       |
2026-04-15 12:05:49.951408 | instance -> localhost | |o . = O.=        |
2026-04-15 12:05:49.951428 | instance -> localhost | |o+.* B * o       |
2026-04-15 12:05:49.951449 | instance -> localhost | |*+* = o S .      |
2026-04-15 12:05:49.951468 | instance -> localhost | |=o +   + .       |
2026-04-15 12:05:49.951487 | instance -> localhost | |.o    . .        |
2026-04-15 12:05:49.951506 | instance -> localhost | |.      o .       |
2026-04-15 12:05:49.951529 | instance -> localhost | |        o        |
2026-04-15 12:05:49.951557 | instance -> localhost | +----[SHA256]-----+
2026-04-15 12:05:49.951639 | instance -> localhost | ok: Runtime: 0:00:00.342906
2026-04-15 12:05:49.957432 | 
2026-04-15 12:05:49.957555 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-04-15 12:05:49.991572 | instance | ok
2026-04-15 12:05:50.004120 | instance | included: /var/lib/zuul/builds/666d28a6f255406cb81e7feb68b21074/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-04-15 12:05:50.016052 | 
2026-04-15 12:05:50.016248 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-04-15 12:05:50.042001 | instance | skipping: Conditional result was False
2026-04-15 12:05:50.052115 | 
2026-04-15 12:05:50.052306 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-04-15 12:05:50.492573 | instance | changed
2026-04-15 12:05:50.499557 | 
2026-04-15 12:05:50.499651 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-04-15 12:05:50.677295 | instance | ok
2026-04-15 12:05:50.686402 | 
2026-04-15 12:05:50.686521 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-04-15 12:05:51.158698 | instance | changed
2026-04-15 12:05:51.166760 | 
2026-04-15 12:05:51.166912 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-04-15 12:05:51.601789 | instance | changed
2026-04-15 12:05:51.609767 | 
2026-04-15 12:05:51.609905 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-04-15 12:05:51.634630 | instance | skipping: Conditional result was False
2026-04-15 12:05:51.646489 | 
2026-04-15 12:05:51.646755 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-04-15 12:05:52.058843 | instance -> localhost | changed
2026-04-15 12:05:52.075951 | 
2026-04-15 12:05:52.076137 | TASK [add-build-sshkey : Add back temp key]
2026-04-15 12:05:52.373417 | instance -> localhost | Identity added: /var/lib/zuul/builds/666d28a6f255406cb81e7feb68b21074/work/666d28a6f255406cb81e7feb68b21074_id_rsa (zuul-build-sshkey)
2026-04-15 12:05:52.373740 | instance -> localhost | ok: Runtime: 0:00:00.013357
2026-04-15 12:05:52.383435 | 
2026-04-15 12:05:52.383630 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-04-15 12:05:52.726527 | instance | ok
2026-04-15 12:05:53.432735 | 
2026-04-15 12:05:53.432881 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-04-15 12:05:53.457394 | instance | skipping: Conditional result was False
2026-04-15 12:05:53.473559 | 
2026-04-15 12:05:53.473658 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-04-15 12:05:53.799757 | instance | ok
2026-04-15 12:05:53.812015 | 
2026-04-15 12:05:53.812178 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-04-15 12:05:55.503380 | instance | Output suppressed because no_log was given
2026-04-15 12:05:55.516292 | 
2026-04-15 12:05:55.516388 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-04-15 12:05:55.714419 | instance | ok: "logs"
2026-04-15 12:05:55.714734 | instance | ok: All items complete
2026-04-15 12:05:55.714776 | 
2026-04-15 12:05:55.878223 | instance | ok: "artifacts"
2026-04-15 12:05:56.037754 | instance | ok: "docs"
2026-04-15 12:05:56.059274 | 
2026-04-15 12:05:56.059415 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-04-15 12:05:56.246134 | instance | changed: "logs"
2026-04-15 12:05:56.427323 | instance | changed: "artifacts"
2026-04-15 12:05:56.590101 | instance | changed: "docs"
2026-04-15 12:05:56.603582 | 
2026-04-15 12:05:56.604116 | PLAY RECAP
2026-04-15 12:05:56.604195 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-04-15 12:05:56.604239 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-15 12:05:56.604270 | 
2026-04-15 12:05:56.804507 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-15 12:05:56.808709 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-15 12:05:57.377071 | 
2026-04-15 12:05:57.377235 | PLAY [all]
2026-04-15 12:05:57.390656 | 
2026-04-15 12:05:57.390777 | TASK [setup-uv : Extract archive]
2026-04-15 12:05:59.572710 | instance | changed
2026-04-15 12:05:59.580699 | 
2026-04-15 12:05:59.580813 | TASK [setup-uv : Print version]
2026-04-15 12:05:59.663649 | instance | uv 0.8.13
2026-04-15 12:06:00.118671 | instance | ok: Runtime: 0:00:00.010815
2026-04-15 12:06:00.127836 | 
2026-04-15 12:06:00.127892 | PLAY RECAP
2026-04-15 12:06:00.127942 | instance | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-15 12:06:00.127967 | 
2026-04-15 12:06:00.261656 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-15 12:06:00.264691 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/pre-install-deps.yml@main]
2026-04-15 12:06:00.958722 | 
2026-04-15 12:06:00.958931 | PLAY [all]
2026-04-15 12:06:00.974186 | 
2026-04-15 12:06:00.974363 | TASK [Install system packages]
2026-04-15 12:06:28.109795 | instance | changed
2026-04-15 12:06:28.117498 | 
2026-04-15 12:06:28.117587 | TASK [Enable iscsid service]
2026-04-15 12:06:29.296857 | instance | changed
2026-04-15 12:06:29.306130 | 
2026-04-15 12:06:29.306297 | TASK [Enable multipathd service]
2026-04-15 12:06:29.649046 | instance | ok
2026-04-15 12:06:29.660178 | 
2026-04-15 12:06:29.660338 | TASK [Install Helm 3.14.4]
2026-04-15 12:06:31.203115 | instance | ok: Runtime: 0:00:00.709955
2026-04-15 12:06:31.207922 | 
2026-04-15 12:06:31.207987 | TASK [Install Go 1.24.2]
2026-04-15 12:06:33.746137 | instance | ok: Runtime: 0:00:02.256010
2026-04-15 12:06:33.752309 | 
2026-04-15 12:06:33.752374 | TASK [Create Python venv and install openstacksdk]
2026-04-15 12:06:49.210508 | instance | changed
2026-04-15 12:06:49.214521 | 
2026-04-15 12:06:49.214588 | PLAY RECAP
2026-04-15 12:06:49.214656 | instance | ok: 6 changed: 5 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-15 12:06:49.214686 | 
2026-04-15 12:06:49.351433 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/pre-install-deps.yml@main]
2026-04-15 12:06:49.356060 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/pre.yml@main]
2026-04-15 12:06:50.019192 | 
2026-04-15 12:06:50.019411 | PLAY [all]
2026-04-15 12:06:50.029005 | 
2026-04-15 12:06:50.029168 | PLAY RECAP
2026-04-15 12:06:50.029229 | 
2026-04-15 12:06:50.169884 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/pre.yml@main]
2026-04-15 12:06:50.174190 | RUN START: [untrusted : github.com/vexxhost/atmosphere/molecule/csi/converge.yml@main]
2026-04-15 12:06:50.780487 | 
2026-04-15 12:06:50.780675 | PLAY [all]
2026-04-15 12:06:50.792718 | 
2026-04-15 12:06:50.792807 | TASK [Build atmosphere binary]
2026-04-15 12:06:51.176666 | instance | go: downloading go1.24.4 (linux/amd64)
2026-04-15 12:06:57.123074 | instance | go: downloading github.com/spf13/cobra v1.9.1
2026-04-15 12:06:57.130109 | instance | go: downloading golang.org/x/sync v0.18.0
2026-04-15 12:06:57.420835 | instance | go: downloading github.com/spf13/pflag v1.0.7
2026-04-15 12:07:04.343389 | instance | ok: Runtime: 0:00:12.952736
2026-04-15 12:07:04.351115 | 
2026-04-15 12:07:04.351224 | TASK [Deploy with parallel orchestrator]
2026-04-15 12:07:04.558190 | instance | /bin/bash: line 1: .venv/bin/activate: No such file or directory
2026-04-15 12:07:04.889587 | instance | ERROR
2026-04-15 12:07:04.889942 | instance | {
2026-04-15 12:07:04.890009 | instance |   "delta": "0:00:00.009223",
2026-04-15 12:07:04.890058 | instance |   "end": "2026-04-15 12:07:04.558890",
2026-04-15 12:07:04.890102 | instance |   "msg": "non-zero return code",
2026-04-15 12:07:04.890145 | instance |   "rc": 1,
2026-04-15 12:07:04.890186 | instance |   "start": "2026-04-15 12:07:04.549667"
2026-04-15 12:07:04.890235 | instance | }
failure
2026-04-15 12:07:04.892301 | 
2026-04-15 12:07:04.892350 | PLAY RECAP
2026-04-15 12:07:04.892400 | instance | ok: 1 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-04-15 12:07:04.892422 | 
2026-04-15 12:07:05.004920 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/molecule/csi/converge.yml@main]
2026-04-15 12:07:05.010037 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@main]
2026-04-15 12:07:05.686107 | 
2026-04-15 12:07:05.686367 | PLAY [all]
2026-04-15 12:07:05.705238 | 
2026-04-15 12:07:05.705439 | TASK [gather-host-logs : creating directory for system status]
2026-04-15 12:07:06.066815 | instance | changed
2026-04-15 12:07:06.074681 | 
2026-04-15 12:07:06.074801 | TASK [gather-host-logs : Get logs for each host]
2026-04-15 12:07:06.451084 | instance | + systemd-cgls --full --all --no-pager
2026-04-15 12:07:06.463035 | instance | + ip addr
2026-04-15 12:07:06.465126 | instance | + ip route
2026-04-15 12:07:06.467747 | instance | + lsblk
2026-04-15 12:07:06.470977 | instance | + mount
2026-04-15 12:07:06.474792 | instance | + docker images
2026-04-15 12:07:06.475501 | instance | /bin/bash: line 7: docker: command not found
2026-04-15 12:07:06.475884 | instance | + brctl show
2026-04-15 12:07:06.476326 | instance | /bin/bash: line 8: brctl: command not found
2026-04-15 12:07:06.476531 | instance | + ps aux --sort=-%mem
2026-04-15 12:07:06.491081 | instance | + dpkg -l
2026-04-15 12:07:06.501093 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul))
2026-04-15 12:07:06.501503 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul
2026-04-15 12:07:06.501562 | instance | /bin/bash: line 11: docker: command not found
2026-04-15 12:07:06.501893 | instance | + '[' '!' -z '' ']'
2026-04-15 12:07:08.318758 | instance | ok: Runtime: 0:00:00.057415
2026-04-15 12:07:08.943387 | 
2026-04-15 12:07:08.943560 | TASK [gather-host-logs : Downloads logs to executor]
2026-04-15 12:07:09.667077 | instance | changed:
2026-04-15 12:07:09.667335 | instance | created directory /var/lib/zuul/builds/666d28a6f255406cb81e7feb68b21074/work/logs/instance
2026-04-15 12:07:09.667377 | instance | cd+++++++++ system/
2026-04-15 12:07:09.667421 | instance | >f+++++++++ system/brctl-show.txt
2026-04-15 12:07:09.667453 | instance | >f+++++++++ system/docker-images.txt
2026-04-15 12:07:09.667483 | instance | >f+++++++++ system/ip-addr.txt
2026-04-15 12:07:09.667525 | instance | >f+++++++++ system/ip-route.txt
2026-04-15 12:07:09.667552 | instance | >f+++++++++ system/lsblk.txt
2026-04-15 12:07:09.667575 | instance | >f+++++++++ system/mount.txt
2026-04-15 12:07:09.667600 | instance | >f+++++++++ system/packages.txt
2026-04-15 12:07:09.667624 | instance | >f+++++++++ system/ps.txt
2026-04-15 12:07:09.667648 | instance | >f+++++++++ system/systemd-cgls.txt
2026-04-15 12:07:09.676905 | 
2026-04-15 12:07:09.677035 | LOOP [helm-release-status : creating directory for helm release status]
2026-04-15 12:07:09.874649 | instance | changed: "values"
2026-04-15 12:07:10.039809 | instance | changed: "releases"
2026-04-15 12:07:10.061579 | 
2026-04-15 12:07:10.061756 | TASK [helm-release-status : Gather get release status for helm charts]
2026-04-15 12:07:10.303857 | instance | /bin/bash: line 3: kubectl: command not found
2026-04-15 12:07:10.601139 | instance | ok: Runtime: 0:00:00.008322
2026-04-15 12:07:10.608758 | 
2026-04-15 12:07:10.608883 | TASK [helm-release-status : Downloads logs to executor]
2026-04-15 12:07:11.106763 | instance | changed:
2026-04-15 12:07:11.106982 | instance | cd+++++++++ helm/
2026-04-15 12:07:11.107021 | instance | cd+++++++++ helm/releases/
2026-04-15 12:07:11.107053 | instance | cd+++++++++ helm/values/
2026-04-15 12:07:11.117081 | 
2026-04-15 12:07:11.117217 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-04-15 12:07:11.333014 | instance | changed
2026-04-15 12:07:11.339623 | 
2026-04-15 12:07:11.339714 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-04-15 12:07:11.579248 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-04-15 12:07:11.579437 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-04-15 12:07:11.585311 | instance | environment: line 1: kubectl: command not found
2026-04-15 12:07:11.586697 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-04-15 12:07:11.586905 | instance | environment: line 1: kubectl: command not found
2026-04-15 12:07:11.588514 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-04-15 12:07:11.590855 | instance | environment: line 1: kubectl: command not found
2026-04-15 12:07:11.591740 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-04-15 12:07:11.591774 | instance | environment: line 1: kubectl: command not found
2026-04-15 12:07:11.593488 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-04-15 12:07:11.594302 | instance | environment: line 1: kubectl: command not found
2026-04-15 12:07:11.595757 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-04-15 12:07:11.879297 | instance | ok: Runtime: 0:00:00.025795
2026-04-15 12:07:11.885550 | 
2026-04-15 12:07:11.885639 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-04-15 12:07:12.103091 | instance | changed
2026-04-15 12:07:12.110820 | 
2026-04-15 12:07:12.110974 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-04-15 12:07:12.357491 | instance | environment: line 5: kubectl: command not found
2026-04-15 12:07:12.359194 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-04-15 12:07:12.359393 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-04-15 12:07:12.359516 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-04-15 12:07:12.684421 | instance | ok: Runtime: 0:00:00.010299
2026-04-15 12:07:12.690777 | 
2026-04-15 12:07:12.690882 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-04-15 12:07:13.208132 | instance | changed:
2026-04-15 12:07:13.208336 | instance | cd+++++++++ objects/
2026-04-15 12:07:13.208363 | instance | cd+++++++++ objects/cluster/
2026-04-15 12:07:13.208385 | instance | cd+++++++++ objects/namespaced/
2026-04-15 12:07:13.217450 | 
2026-04-15 12:07:13.217518 | TASK [gather-pod-logs : creating directory for pod logs]
2026-04-15 12:07:13.414631 | instance | changed
2026-04-15 12:07:13.420024 | 
2026-04-15 12:07:13.420094 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-04-15 12:07:13.628857 | instance | changed
2026-04-15 12:07:13.636055 | 
2026-04-15 12:07:13.636156 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-04-15 12:07:13.896235 | instance | environment: line 3: kubectl: command not found
2026-04-15 12:07:14.172849 | instance | ok: Runtime: 0:00:00.009928
2026-04-15 12:07:14.179214 | 
2026-04-15 12:07:14.179294 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-04-15 12:07:14.700422 | instance | changed:
2026-04-15 12:07:14.700695 | instance | cd+++++++++ pod-logs/
2026-04-15 12:07:14.700748 | instance | cd+++++++++ pod-logs/failed-pods/
2026-04-15 12:07:14.713230 | 
2026-04-15 12:07:14.713340 | TASK [gather-prom-metrics : creating directory for helm release descriptions]
2026-04-15 12:07:14.920856 | instance | changed
2026-04-15 12:07:14.927842 | 
2026-04-15 12:07:14.927937 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces]
2026-04-15 12:07:15.140457 | instance | /bin/bash: line 2: kubectl: command not found
2026-04-15 12:07:15.467296 | instance | ok: Runtime: 0:00:00.036567
2026-04-15 12:07:15.475279 | 
2026-04-15 12:07:15.475496 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr]
2026-04-15 12:07:15.709628 | instance | /bin/bash: line 2: kubectl: command not found
2026-04-15 12:07:15.740399 | instance | ceph-mgr endpoints:
2026-04-15 12:07:16.022672 | instance | ok: Runtime: 0:00:00.037498
2026-04-15 12:07:16.029951 | 
2026-04-15 12:07:16.030052 | TASK [gather-prom-metrics : Get metrics from fluentd pods]
2026-04-15 12:07:16.251629 | instance | /bin/bash: line 4: kubectl: command not found
2026-04-15 12:07:16.562266 | instance | ok: Runtime: 0:00:00.037494
2026-04-15 12:07:16.567182 | 
2026-04-15 12:07:16.567258 | TASK [gather-prom-metrics : Downloads logs to executor]
2026-04-15 12:07:17.078265 | instance | changed: cd+++++++++ prometheus/
2026-04-15 12:07:17.090526 | 
2026-04-15 12:07:17.090707 | TASK [gather-selenium-data : creating directory for helm release descriptions]
2026-04-15 12:07:17.278233 | instance | changed
2026-04-15 12:07:17.284978 | 
2026-04-15 12:07:17.285050 | TASK [gather-selenium-data : Get selenium data]
2026-04-15 12:07:17.505445 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/.
2026-04-15 12:07:17.506853 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory
2026-04-15 12:07:17.824564 | instance | ERROR
2026-04-15 12:07:17.824782 | instance | {
2026-04-15 12:07:17.824821 | instance |   "delta": "0:00:00.006279",
2026-04-15 12:07:17.824850 | instance |   "end": "2026-04-15 12:07:17.507222",
2026-04-15 12:07:17.824870 | instance |   "msg": "non-zero return code",
2026-04-15 12:07:17.824890 | instance |   "rc": 1,
2026-04-15 12:07:17.824909 | instance |   "start": "2026-04-15 12:07:17.500943"
2026-04-15 12:07:17.824928 | instance | }
2026-04-15 12:07:17.824969 | instance | ERROR: Ignoring Errors
2026-04-15 12:07:17.830205 | 
2026-04-15 12:07:17.830295 | TASK [gather-selenium-data : Downloads logs to executor]
2026-04-15 12:07:18.339235 | instance | changed: cd+++++++++ selenium/
2026-04-15 12:07:18.687171 | 
2026-04-15 12:07:18.687309 | PLAY RECAP
2026-04-15 12:07:18.687372 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1
2026-04-15 12:07:18.687413 | 
2026-04-15 12:07:18.823222 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@main]
2026-04-15 12:07:18.829057 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-15 12:07:19.502912 | 
2026-04-15 12:07:19.503081 | PLAY [all]
2026-04-15 12:07:19.516728 | 
2026-04-15 12:07:19.516831 | TASK [fetch-output : Set log path for multiple nodes]
2026-04-15 12:07:19.562065 | instance | skipping: Conditional result was False
2026-04-15 12:07:19.573718 | 
2026-04-15 12:07:19.573830 | TASK [fetch-output : Set log path for single node]
2026-04-15 12:07:19.618089 | instance | ok
2026-04-15 12:07:19.625542 | 
2026-04-15 12:07:19.625679 | LOOP [fetch-output : Ensure local output dirs]
2026-04-15 12:07:20.082082 | instance -> localhost | ok: "/var/lib/zuul/builds/666d28a6f255406cb81e7feb68b21074/work/logs"
2026-04-15 12:07:20.318284 | instance -> localhost | changed: "/var/lib/zuul/builds/666d28a6f255406cb81e7feb68b21074/work/artifacts"
2026-04-15 12:07:20.564576 | instance -> localhost | changed: "/var/lib/zuul/builds/666d28a6f255406cb81e7feb68b21074/work/docs"
2026-04-15 12:07:20.585131 | 
2026-04-15 12:07:20.585331 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-04-15 12:07:21.262932 | instance | changed: .d..t...... ./
2026-04-15 12:07:21.263355 | instance | changed: All items complete
2026-04-15 12:07:21.263398 | 
2026-04-15 12:07:21.741226 | instance | changed: .d..t...... ./
2026-04-15 12:07:22.264911 | instance | changed: .d..t...... ./
2026-04-15 12:07:22.288693 | 
2026-04-15 12:07:22.288855 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-04-15 12:07:22.744942 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.007857
2026-04-15 12:07:22.993120 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.008998
2026-04-15 12:07:23.013494 | 
2026-04-15 12:07:23.013651 | PLAY [all]
2026-04-15 12:07:23.021939 | 
2026-04-15 12:07:23.022025 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-04-15 12:07:23.463963 | instance | changed
2026-04-15 12:07:23.658429 | 
2026-04-15 12:07:23.658547 | PLAY RECAP
2026-04-15 12:07:23.658600 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-04-15 12:07:23.658622 | 
2026-04-15 12:07:23.803812 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-15 12:07:23.808715 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-04-15 12:07:24.402876 | 
2026-04-15 12:07:24.403609 | PLAY [localhost]
2026-04-15 12:07:24.418889 | 
2026-04-15 12:07:24.419077 | TASK [Generate Zuul manifest]
2026-04-15 12:07:24.443206 | localhost | ok
2026-04-15 12:07:24.463246 | 
2026-04-15 12:07:24.463427 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-04-15 12:07:24.794431 | localhost | changed
2026-04-15 12:07:24.807522 | 
2026-04-15 12:07:24.807609 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-04-15 12:07:25.682200 | localhost | ok
2026-04-15 12:07:25.691028 | 
2026-04-15 12:07:25.691173 | TASK [Upload logs]
2026-04-15 12:07:25.723100 | localhost | ok
2026-04-15 12:07:25.787423 | 
2026-04-15 12:07:25.787563 | TASK [Set zuul-log-path fact]
2026-04-15 12:07:25.805423 | localhost | ok
2026-04-15 12:07:25.819436 | 
2026-04-15 12:07:25.819508 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-15 12:07:25.849910 | localhost | ok
2026-04-15 12:07:25.859374 | 
2026-04-15 12:07:25.859475 | TASK [upload-logs : Create log directories]
2026-04-15 12:07:26.313987 | localhost | changed
2026-04-15 12:07:26.320030 | 
2026-04-15 12:07:26.320128 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-04-15 12:07:26.789102 | localhost -> localhost | ok: Runtime: 0:00:00.007049
2026-04-15 12:07:26.796605 | 
2026-04-15 12:07:26.796715 | TASK [upload-logs : Upload logs to log server]
2026-04-15 12:07:27.306941 | localhost | Output suppressed because no_log was given
2026-04-15 12:07:27.311717 | 
2026-04-15 12:07:27.311811 | LOOP [upload-logs : Compress console log and json output]
2026-04-15 12:07:27.362796 | localhost | skipping: Conditional result was False
2026-04-15 12:07:27.370332 | localhost | skipping: Conditional result was False
2026-04-15 12:07:27.387886 | 
2026-04-15 12:07:27.388123 | LOOP [upload-logs : Upload compressed console log and json output]
2026-04-15 12:07:27.431001 | localhost | skipping: Conditional result was False
2026-04-15 12:07:27.431453 | 
2026-04-15 12:07:27.436667 | localhost | skipping: Conditional result was False
2026-04-15 12:07:27.451317 | 
2026-04-15 12:07:27.451476 | LOOP [upload-logs : Upload console log and json output]