2026-04-16 00:16:20.517668 | Job console starting
2026-04-16 00:16:20.530800 | Updating git repos
2026-04-16 00:16:20.608200 | Cloning repos into workspace
2026-04-16 00:16:20.662548 | Restoring repo states
2026-04-16 00:16:20.721165 | Merging changes
2026-04-16 00:16:21.589348 | Checking out repos
2026-04-16 00:16:21.876232 | Preparing playbooks
2026-04-16 00:16:24.304508 | Running Ansible setup
2026-04-16 00:16:27.514461 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-16 00:16:28.101212 | 
2026-04-16 00:16:28.101339 | PLAY [localhost]
2026-04-16 00:16:28.108806 | 
2026-04-16 00:16:28.108873 | TASK [Gathering Facts]
2026-04-16 00:16:29.893294 | localhost | ok
2026-04-16 00:16:29.903367 | 
2026-04-16 00:16:29.903445 | TASK [Setup log path fact]
2026-04-16 00:16:29.923581 | localhost | ok
2026-04-16 00:16:29.935639 | 
2026-04-16 00:16:29.935703 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-16 00:16:29.965521 | localhost | ok
2026-04-16 00:16:29.973404 | 
2026-04-16 00:16:29.973493 | TASK [emit-job-header : Print job information]
2026-04-16 00:16:30.014320 | # Job Information
2026-04-16 00:16:30.014480 | Ansible Version: 2.16.16
2026-04-16 00:16:30.014526 | Job: atmosphere-molecule-keycloak
2026-04-16 00:16:30.014556 | Pipeline: check
2026-04-16 00:16:30.014584 | Executor: 0a8996d2b663
2026-04-16 00:16:30.014611 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3835
2026-04-16 00:16:30.014641 | Event ID: 69329484-3929-11f1-806c-0c126485a0af
2026-04-16 00:16:30.018199 | 
2026-04-16 00:16:30.018281 | LOOP [emit-job-header : Print node information]
2026-04-16 00:16:30.107483 | localhost | ok:
2026-04-16 00:16:30.107708 | localhost | # Node Information
2026-04-16 00:16:30.107747 | localhost | Inventory Hostname: instance
2026-04-16 00:16:30.107778 | localhost | Hostname: np0000166503
2026-04-16 00:16:30.107809 | localhost | Username: zuul
2026-04-16 00:16:30.107851 | localhost | Distro: Ubuntu 22.04
2026-04-16 00:16:30.107881 | localhost | Provider: yul1
2026-04-16 00:16:30.107908 | localhost | Region: ca-ymq-1
2026-04-16 00:16:30.107934 | localhost | Label: ubuntu-jammy
2026-04-16 00:16:30.107960 | localhost | Product Name: OpenStack Nova
2026-04-16 00:16:30.107986 | localhost | Interface IP: 199.204.45.30
2026-04-16 00:16:30.127568 | 
2026-04-16 00:16:30.127703 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-04-16 00:16:30.495872 | localhost -> localhost | changed
2026-04-16 00:16:30.505378 | 
2026-04-16 00:16:30.505575 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-04-16 00:16:31.646896 | localhost -> localhost | changed
2026-04-16 00:16:31.654985 | 
2026-04-16 00:16:31.655041 | PLAY [all]
2026-04-16 00:16:31.667817 | 
2026-04-16 00:16:31.667889 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-04-16 00:16:32.038106 | instance -> localhost | ok
2026-04-16 00:16:32.044993 | 
2026-04-16 00:16:32.045067 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-04-16 00:16:32.077813 | instance | ok
2026-04-16 00:16:32.103197 | instance | included: /var/lib/zuul/builds/d10a6dc3a8ba42bab01262380d79f266/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-04-16 00:16:32.108745 | 
2026-04-16 00:16:32.108807 | TASK [add-build-sshkey : Create Temp SSH key]
2026-04-16 00:16:33.147811 | instance -> localhost | Generating public/private rsa key pair.
2026-04-16 00:16:33.392562 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/d10a6dc3a8ba42bab01262380d79f266/work/d10a6dc3a8ba42bab01262380d79f266_id_rsa
2026-04-16 00:16:33.392670 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/d10a6dc3a8ba42bab01262380d79f266/work/d10a6dc3a8ba42bab01262380d79f266_id_rsa.pub
2026-04-16 00:16:33.392733 | instance -> localhost | The key fingerprint is:
2026-04-16 00:16:33.392786 | instance -> localhost | SHA256:m5KJ3n9v49qvxHMKvZPPc+iCeP8NuFTVF4dNwpUu2JU zuul-build-sshkey
2026-04-16 00:16:33.392856 | instance -> localhost | The key's randomart image is:
2026-04-16 00:16:33.392908 | instance -> localhost | +---[RSA 3072]----+
2026-04-16 00:16:33.393003 | instance -> localhost | |             ..**|
2026-04-16 00:16:33.393061 | instance -> localhost | |              oE=|
2026-04-16 00:16:33.393111 | instance -> localhost | |            o o +|
2026-04-16 00:16:33.393160 | instance -> localhost | |           . o o.|
2026-04-16 00:16:33.393207 | instance -> localhost | |        S     o  |
2026-04-16 00:16:33.393254 | instance -> localhost | |     . o o o o   |
2026-04-16 00:16:33.393300 | instance -> localhost | |    . + o...B.o. |
2026-04-16 00:16:33.393346 | instance -> localhost | |   . . .. +=*Boo.|
2026-04-16 00:16:33.393398 | instance -> localhost | |    . ...o.*XX*o.|
2026-04-16 00:16:33.393446 | instance -> localhost | +----[SHA256]-----+
2026-04-16 00:16:33.393545 | instance -> localhost | ok: Runtime: 0:00:00.608391
2026-04-16 00:16:33.404090 | 
2026-04-16 00:16:33.404156 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-04-16 00:16:33.437703 | instance | ok
2026-04-16 00:16:33.447428 | instance | included: /var/lib/zuul/builds/d10a6dc3a8ba42bab01262380d79f266/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-04-16 00:16:33.454404 | 
2026-04-16 00:16:33.454465 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-04-16 00:16:33.478023 | instance | skipping: Conditional result was False
2026-04-16 00:16:33.486790 | 
2026-04-16 00:16:33.486876 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-04-16 00:16:33.931300 | instance | changed
2026-04-16 00:16:33.991641 | 
2026-04-16 00:16:33.991764 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-04-16 00:16:34.167032 | instance | ok
2026-04-16 00:16:34.175106 | 
2026-04-16 00:16:34.175195 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-04-16 00:16:34.611165 | instance | changed
2026-04-16 00:16:34.794562 | 
2026-04-16 00:16:34.794900 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-04-16 00:16:35.249060 | instance | changed
2026-04-16 00:16:35.255096 | 
2026-04-16 00:16:35.255159 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-04-16 00:16:35.280088 | instance | skipping: Conditional result was False
2026-04-16 00:16:35.289892 | 
2026-04-16 00:16:35.289978 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-04-16 00:16:35.629082 | instance -> localhost | changed
2026-04-16 00:16:35.644252 | 
2026-04-16 00:16:35.644346 | TASK [add-build-sshkey : Add back temp key]
2026-04-16 00:16:35.906588 | instance -> localhost | Identity added: /var/lib/zuul/builds/d10a6dc3a8ba42bab01262380d79f266/work/d10a6dc3a8ba42bab01262380d79f266_id_rsa (zuul-build-sshkey)
2026-04-16 00:16:35.906790 | instance -> localhost | ok: Runtime: 0:00:00.012737
2026-04-16 00:16:35.911823 | 
2026-04-16 00:16:35.911889 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-04-16 00:16:36.217780 | instance | ok
2026-04-16 00:16:36.223920 | 
2026-04-16 00:16:36.223983 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-04-16 00:16:36.250343 | instance | skipping: Conditional result was False
2026-04-16 00:16:36.268530 | 
2026-04-16 00:16:36.268696 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-04-16 00:16:36.602213 | instance | ok
2026-04-16 00:16:36.608442 | 
2026-04-16 00:16:36.608509 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-04-16 00:16:38.204547 | instance | Output suppressed because no_log was given
2026-04-16 00:16:38.215643 | 
2026-04-16 00:16:38.215720 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-04-16 00:16:38.405889 | instance | ok: "logs"
2026-04-16 00:16:38.406217 | instance | ok: All items complete
2026-04-16 00:16:38.406246 | 
2026-04-16 00:16:38.590893 | instance | ok: "artifacts"
2026-04-16 00:16:38.779704 | instance | ok: "docs"
2026-04-16 00:16:38.807992 | 
2026-04-16 00:16:38.808180 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-04-16 00:16:39.012593 | instance | changed: "logs"
2026-04-16 00:16:39.173333 | instance | changed: "artifacts"
2026-04-16 00:16:39.333710 | instance | changed: "docs"
2026-04-16 00:16:39.358285 | 
2026-04-16 00:16:39.358427 | PLAY RECAP
2026-04-16 00:16:39.358482 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-04-16 00:16:39.358512 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-16 00:16:39.358532 | 
2026-04-16 00:16:39.513455 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-16 00:16:39.516721 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-16 00:16:40.088352 | 
2026-04-16 00:16:40.088482 | PLAY [all]
2026-04-16 00:16:40.100803 | 
2026-04-16 00:16:40.100895 | TASK [setup-uv : Extract archive]
2026-04-16 00:16:42.301518 | instance | changed
2026-04-16 00:16:42.310291 | 
2026-04-16 00:16:42.310431 | TASK [setup-uv : Print version]
2026-04-16 00:16:42.830329 | instance | uv 0.8.13
2026-04-16 00:16:42.853456 | instance | ok: Runtime: 0:00:00.012048
2026-04-16 00:16:42.859570 | 
2026-04-16 00:16:42.859620 | PLAY RECAP
2026-04-16 00:16:42.859664 | instance | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-16 00:16:42.859688 | 
2026-04-16 00:16:42.967211 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-16 00:16:42.972067 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/pre-install-deps.yml@main]
2026-04-16 00:16:43.499848 | 
2026-04-16 00:16:43.499968 | PLAY [all]
2026-04-16 00:16:43.511192 | 
2026-04-16 00:16:43.511266 | TASK [Install system packages]
2026-04-16 00:18:17.410188 | instance | changed
2026-04-16 00:18:17.416089 | 
2026-04-16 00:18:17.416162 | TASK [Enable iscsid service]
2026-04-16 00:18:18.445173 | instance | changed
2026-04-16 00:18:18.455363 | 
2026-04-16 00:18:18.455425 | TASK [Enable multipathd service]
2026-04-16 00:18:18.781536 | instance | ok
2026-04-16 00:18:18.791491 | 
2026-04-16 00:18:18.791557 | TASK [Install Helm 3.14.4]
2026-04-16 00:18:20.330112 | instance | ok: Runtime: 0:00:00.745134
2026-04-16 00:18:20.335166 | 
2026-04-16 00:18:20.335226 | TASK [Install Go 1.24.2]
2026-04-16 00:18:23.373686 | instance | ok: Runtime: 0:00:02.376284
2026-04-16 00:18:23.379728 | 
2026-04-16 00:18:23.379797 | TASK [Create Python venv and install openstacksdk]
2026-04-16 00:18:39.983125 | instance | changed
2026-04-16 00:18:39.986326 | 
2026-04-16 00:18:39.986370 | PLAY RECAP
2026-04-16 00:18:39.986415 | instance | ok: 6 changed: 5 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-16 00:18:39.986437 | 
2026-04-16 00:18:40.094435 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/pre-install-deps.yml@main]
2026-04-16 00:18:40.099229 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/pre.yml@main]
2026-04-16 00:18:40.647396 | 
2026-04-16 00:18:40.647545 | PLAY [all]
2026-04-16 00:18:40.653693 | 
2026-04-16 00:18:40.653753 | PLAY RECAP
2026-04-16 00:18:40.653787 | 
2026-04-16 00:18:40.743871 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/pre.yml@main]
2026-04-16 00:18:40.748829 | RUN START: [untrusted : github.com/vexxhost/atmosphere/molecule/keycloak/converge.yml@main]
2026-04-16 00:18:41.288993 | 
2026-04-16 00:18:41.289609 | PLAY [all]
2026-04-16 00:18:41.300827 | 
2026-04-16 00:18:41.300899 | TASK [Build atmosphere binary]
2026-04-16 00:18:41.636297 | instance | go: downloading go1.24.4 (linux/amd64)
2026-04-16 00:18:47.181801 | instance | go: downloading github.com/spf13/cobra v1.9.1
2026-04-16 00:18:47.187515 | instance | go: downloading golang.org/x/sync v0.18.0
2026-04-16 00:18:47.350860 | instance | go: downloading github.com/spf13/pflag v1.0.7
2026-04-16 00:18:54.359757 | instance | ok: Runtime: 0:00:12.570599
2026-04-16 00:18:54.367332 | 
2026-04-16 00:18:54.367401 | TASK [Deploy with parallel orchestrator]
2026-04-16 00:18:54.574928 | instance | /bin/bash: line 1: .venv/bin/activate: No such file or directory
2026-04-16 00:18:54.902657 | instance | ERROR
2026-04-16 00:18:54.902908 | instance | {
2026-04-16 00:18:54.902950 | instance |   "delta": "0:00:00.008030",
2026-04-16 00:18:54.902981 | instance |   "end": "2026-04-16 00:18:54.575329",
2026-04-16 00:18:54.903008 | instance |   "msg": "non-zero return code",
2026-04-16 00:18:54.903034 | instance |   "rc": 1,
2026-04-16 00:18:54.903060 | instance |   "start": "2026-04-16 00:18:54.567299"
2026-04-16 00:18:54.903091 | instance | }
failure
2026-04-16 00:18:54.904937 | 
2026-04-16 00:18:54.905027 | PLAY RECAP
2026-04-16 00:18:54.905089 | instance | ok: 1 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-04-16 00:18:54.905121 | 
2026-04-16 00:18:55.017915 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/molecule/keycloak/converge.yml@main]
2026-04-16 00:18:55.021061 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@main]
2026-04-16 00:18:55.612444 | 
2026-04-16 00:18:55.612646 | PLAY [all]
2026-04-16 00:18:55.626042 | 
2026-04-16 00:18:55.626124 | TASK [gather-host-logs : creating directory for system status]
2026-04-16 00:18:55.959076 | instance | changed
2026-04-16 00:18:55.965606 | 
2026-04-16 00:18:55.965686 | TASK [gather-host-logs : Get logs for each host]
2026-04-16 00:18:56.298476 | instance | + systemd-cgls --full --all --no-pager
2026-04-16 00:18:56.309324 | instance | + ip addr
2026-04-16 00:18:56.311829 | instance | + ip route
2026-04-16 00:18:56.314024 | instance | + lsblk
2026-04-16 00:18:56.318031 | instance | + mount
2026-04-16 00:18:56.320456 | instance | + docker images
2026-04-16 00:18:56.320861 | instance | /bin/bash: line 7: docker: command not found
2026-04-16 00:18:56.321125 | instance | + brctl show
2026-04-16 00:18:56.321516 | instance | /bin/bash: line 8: brctl: command not found
2026-04-16 00:18:56.321723 | instance | + ps aux --sort=-%mem
2026-04-16 00:18:56.332018 | instance | + dpkg -l
2026-04-16 00:18:56.339294 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul))
2026-04-16 00:18:56.339726 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul
2026-04-16 00:18:56.339871 | instance | /bin/bash: line 11: docker: command not found
2026-04-16 00:18:56.340256 | instance | + '[' '!' -z '' ']'
2026-04-16 00:18:56.500357 | instance | ok: Runtime: 0:00:00.046792
2026-04-16 00:18:56.508198 | 
2026-04-16 00:18:56.509002 | TASK [gather-host-logs : Downloads logs to executor]
2026-04-16 00:18:57.181693 | instance | changed:
2026-04-16 00:18:57.181933 | instance | created directory /var/lib/zuul/builds/d10a6dc3a8ba42bab01262380d79f266/work/logs/instance
2026-04-16 00:18:57.181976 | instance | cd+++++++++ system/
2026-04-16 00:18:57.182009 | instance | >f+++++++++ system/brctl-show.txt
2026-04-16 00:18:57.182039 | instance | >f+++++++++ system/docker-images.txt
2026-04-16 00:18:57.182067 | instance | >f+++++++++ system/ip-addr.txt
2026-04-16 00:18:57.182098 | instance | >f+++++++++ system/ip-route.txt
2026-04-16 00:18:57.182128 | instance | >f+++++++++ system/lsblk.txt
2026-04-16 00:18:57.182156 | instance | >f+++++++++ system/mount.txt
2026-04-16 00:18:57.182187 | instance | >f+++++++++ system/packages.txt
2026-04-16 00:18:57.182215 | instance | >f+++++++++ system/ps.txt
2026-04-16 00:18:57.182242 | instance | >f+++++++++ system/systemd-cgls.txt
2026-04-16 00:18:57.194582 | 
2026-04-16 00:18:57.194652 | LOOP [helm-release-status : creating directory for helm release status]
2026-04-16 00:18:57.413734 | instance | changed: "values"
2026-04-16 00:18:57.588571 | instance | changed: "releases"
2026-04-16 00:18:57.600194 | 
2026-04-16 00:18:57.600358 | TASK [helm-release-status : Gather get release status for helm charts]
2026-04-16 00:18:57.810408 | instance | /bin/bash: line 3: kubectl: command not found
2026-04-16 00:18:58.137416 | instance | ok: Runtime: 0:00:00.007417
2026-04-16 00:18:58.144673 | 
2026-04-16 00:18:58.144770 | TASK [helm-release-status : Downloads logs to executor]
2026-04-16 00:18:58.687294 | instance | changed:
2026-04-16 00:18:58.687521 | instance | cd+++++++++ helm/
2026-04-16 00:18:58.687561 | instance | cd+++++++++ helm/releases/
2026-04-16 00:18:58.687592 | instance | cd+++++++++ helm/values/
2026-04-16 00:18:58.701121 | 
2026-04-16 00:18:58.701191 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-04-16 00:18:58.904191 | instance | changed
2026-04-16 00:18:58.908996 | 
2026-04-16 00:18:58.909067 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-04-16 00:18:59.120695 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-04-16 00:18:59.121271 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-04-16 00:18:59.127165 | instance | environment: line 1: kubectl: command not found
2026-04-16 00:18:59.128700 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-04-16 00:18:59.131489 | instance | environment: line 1: kubectl: command not found
2026-04-16 00:18:59.132541 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-04-16 00:18:59.134202 | instance | environment: line 1: kubectl: command not found
2026-04-16 00:18:59.135167 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-04-16 00:18:59.137099 | instance | environment: line 1: kubectl: command not found
2026-04-16 00:18:59.138008 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-04-16 00:18:59.138464 | instance | environment: line 1: kubectl: command not found
2026-04-16 00:18:59.139336 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-04-16 00:18:59.446542 | instance | ok: Runtime: 0:00:00.026976
2026-04-16 00:18:59.452864 | 
2026-04-16 00:18:59.452929 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-04-16 00:18:59.648375 | instance | changed
2026-04-16 00:18:59.654341 | 
2026-04-16 00:18:59.654412 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-04-16 00:18:59.894183 | instance | environment: line 5: kubectl: command not found
2026-04-16 00:18:59.894438 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-04-16 00:18:59.895033 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-04-16 00:18:59.895327 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-04-16 00:19:00.225904 | instance | ok: Runtime: 0:00:00.008584
2026-04-16 00:19:00.232603 | 
2026-04-16 00:19:00.232687 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-04-16 00:19:00.742880 | instance | changed:
2026-04-16 00:19:00.743105 | instance | cd+++++++++ objects/
2026-04-16 00:19:00.743144 | instance | cd+++++++++ objects/cluster/
2026-04-16 00:19:00.743173 | instance | cd+++++++++ objects/namespaced/
2026-04-16 00:19:00.755746 | 
2026-04-16 00:19:00.755812 | TASK [gather-pod-logs : creating directory for pod logs]
2026-04-16 00:19:00.963350 | instance | changed
2026-04-16 00:19:00.970779 | 
2026-04-16 00:19:00.970855 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-04-16 00:19:01.168122 | instance | changed
2026-04-16 00:19:01.173791 | 
2026-04-16 00:19:01.173868 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-04-16 00:19:01.381801 | instance | environment: line 3: kubectl: command not found
2026-04-16 00:19:01.712458 | instance | ok: Runtime: 0:00:00.007112
2026-04-16 00:19:01.718442 | 
2026-04-16 00:19:01.718510 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-04-16 00:19:02.218281 | instance | changed:
2026-04-16 00:19:02.218452 | instance | cd+++++++++ pod-logs/
2026-04-16 00:19:02.218480 | instance | cd+++++++++ pod-logs/failed-pods/
2026-04-16 00:19:02.229779 | 
2026-04-16 00:19:02.229911 | TASK [gather-prom-metrics : creating directory for helm release descriptions]
2026-04-16 00:19:02.425850 | instance | changed
2026-04-16 00:19:02.432469 | 
2026-04-16 00:19:02.432557 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces]
2026-04-16 00:19:02.639872 | instance | /bin/bash: line 2: kubectl: command not found
2026-04-16 00:19:02.969216 | instance | ok: Runtime: 0:00:00.036296
2026-04-16 00:19:02.974557 | 
2026-04-16 00:19:02.974625 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr]
2026-04-16 00:19:03.194714 | instance | /bin/bash: line 2: kubectl: command not found
2026-04-16 00:19:03.226353 | instance | ceph-mgr endpoints:
2026-04-16 00:19:03.508618 | instance | ok: Runtime: 0:00:00.038052
2026-04-16 00:19:03.515135 | 
2026-04-16 00:19:03.515211 | TASK [gather-prom-metrics : Get metrics from fluentd pods]
2026-04-16 00:19:03.730574 | instance | /bin/bash: line 4: kubectl: command not found
2026-04-16 00:19:04.050567 | instance | ok: Runtime: 0:00:00.034875
2026-04-16 00:19:04.056576 | 
2026-04-16 00:19:04.056653 | TASK [gather-prom-metrics : Downloads logs to executor]
2026-04-16 00:19:04.552673 | instance | changed: cd+++++++++ prometheus/
2026-04-16 00:19:04.563297 | 
2026-04-16 00:19:04.563391 | TASK [gather-selenium-data : creating directory for helm release descriptions]
2026-04-16 00:19:04.769483 | instance | changed
2026-04-16 00:19:04.775795 | 
2026-04-16 00:19:04.775857 | TASK [gather-selenium-data : Get selenium data]
2026-04-16 00:19:04.984592 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/.
2026-04-16 00:19:04.986128 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory
2026-04-16 00:19:05.312333 | instance | ERROR
2026-04-16 00:19:05.312679 | instance | {
2026-04-16 00:19:05.312746 | instance |   "delta": "0:00:00.008693",
2026-04-16 00:19:05.312793 | instance |   "end": "2026-04-16 00:19:04.986583",
2026-04-16 00:19:05.312836 | instance |   "msg": "non-zero return code",
2026-04-16 00:19:05.312877 | instance |   "rc": 1,
2026-04-16 00:19:05.312916 | instance |   "start": "2026-04-16 00:19:04.977890"
2026-04-16 00:19:05.312981 | instance | }
2026-04-16 00:19:05.313038 | instance | ERROR: Ignoring Errors
2026-04-16 00:19:05.319063 | 
2026-04-16 00:19:05.319129 | TASK [gather-selenium-data : Downloads logs to executor]
2026-04-16 00:19:05.796332 | instance | changed: cd+++++++++ selenium/
2026-04-16 00:19:05.837756 | 
2026-04-16 00:19:05.837884 | PLAY RECAP
2026-04-16 00:19:05.837929 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1
2026-04-16 00:19:05.837951 | 
2026-04-16 00:19:05.947855 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@main]
2026-04-16 00:19:05.953044 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-16 00:19:06.540416 | 
2026-04-16 00:19:06.540932 | PLAY [all]
2026-04-16 00:19:06.552051 | 
2026-04-16 00:19:06.552123 | TASK [fetch-output : Set log path for multiple nodes]
2026-04-16 00:19:06.597350 | instance | skipping: Conditional result was False
2026-04-16 00:19:06.607575 | 
2026-04-16 00:19:06.607651 | TASK [fetch-output : Set log path for single node]
2026-04-16 00:19:06.652038 | instance | ok
2026-04-16 00:19:06.656431 | 
2026-04-16 00:19:06.656495 | LOOP [fetch-output : Ensure local output dirs]
2026-04-16 00:19:07.043803 | instance -> localhost | ok: "/var/lib/zuul/builds/d10a6dc3a8ba42bab01262380d79f266/work/logs"
2026-04-16 00:19:07.245770 | instance -> localhost | changed: "/var/lib/zuul/builds/d10a6dc3a8ba42bab01262380d79f266/work/artifacts"
2026-04-16 00:19:07.452165 | instance -> localhost | changed: "/var/lib/zuul/builds/d10a6dc3a8ba42bab01262380d79f266/work/docs"
2026-04-16 00:19:07.466315 | 
2026-04-16 00:19:07.466428 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-04-16 00:19:08.084397 | instance | changed: .d..t...... ./
2026-04-16 00:19:08.084691 | instance | changed: All items complete
2026-04-16 00:19:08.084752 | 
2026-04-16 00:19:08.529640 | instance | changed: .d..t...... ./
2026-04-16 00:19:09.007118 | instance | changed: .d..t...... ./
2026-04-16 00:19:09.034749 | 
2026-04-16 00:19:09.034875 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-04-16 00:19:09.445878 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.007348
2026-04-16 00:19:09.663760 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.007721
2026-04-16 00:19:09.683551 | 
2026-04-16 00:19:09.683684 | PLAY [all]
2026-04-16 00:19:09.690289 | 
2026-04-16 00:19:09.690353 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-04-16 00:19:10.120230 | instance | changed
2026-04-16 00:19:10.125554 | 
2026-04-16 00:19:10.125605 | PLAY RECAP
2026-04-16 00:19:10.125652 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-04-16 00:19:10.125673 | 
2026-04-16 00:19:10.252600 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-16 00:19:10.257877 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-04-16 00:19:10.850036 | 
2026-04-16 00:19:10.850202 | PLAY [localhost]
2026-04-16 00:19:10.860648 | 
2026-04-16 00:19:10.860730 | TASK [Generate Zuul manifest]
2026-04-16 00:19:10.886549 | localhost | ok
2026-04-16 00:19:10.904485 | 
2026-04-16 00:19:10.904569 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-04-16 00:19:11.262621 | localhost | changed
2026-04-16 00:19:11.276853 | 
2026-04-16 00:19:11.277002 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-04-16 00:19:11.310817 | localhost | ok
2026-04-16 00:19:11.321615 | 
2026-04-16 00:19:11.321715 | TASK [Upload logs]
2026-04-16 00:19:11.342786 | localhost | ok
2026-04-16 00:19:11.414222 | 
2026-04-16 00:19:11.414382 | TASK [Set zuul-log-path fact]
2026-04-16 00:19:11.435212 | localhost | ok
2026-04-16 00:19:11.448464 | 
2026-04-16 00:19:11.448530 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-16 00:19:11.480262 | localhost | ok
2026-04-16 00:19:11.489423 | 
2026-04-16 00:19:11.489485 | TASK [upload-logs : Create log directories]
2026-04-16 00:19:11.875969 | localhost | changed
2026-04-16 00:19:11.882989 | 
2026-04-16 00:19:11.883061 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-04-16 00:19:12.287370 | localhost -> localhost | ok: Runtime: 0:00:00.006189
2026-04-16 00:19:12.292767 | 
2026-04-16 00:19:12.292839 | TASK [upload-logs : Upload logs to log server]
2026-04-16 00:19:12.751287 | localhost | Output suppressed because no_log was given
2026-04-16 00:19:12.755506 | 
2026-04-16 00:19:12.755575 | LOOP [upload-logs : Compress console log and json output]
2026-04-16 00:19:12.800675 | localhost | skipping: Conditional result was False
2026-04-16 00:19:12.846086 | localhost | skipping: Conditional result was False
2026-04-16 00:19:12.860902 | 
2026-04-16 00:19:12.861113 | LOOP [upload-logs : Upload compressed console log and json output]
2026-04-16 00:19:12.902888 | localhost | skipping: Conditional result was False
2026-04-16 00:19:12.903617 | 
2026-04-16 00:19:12.907370 | localhost | skipping: Conditional result was False
2026-04-16 00:19:12.925309 | 
2026-04-16 00:19:12.925507 | LOOP [upload-logs : Upload console log and json output]