2026-04-13 20:39:22.693389 | Job console starting 2026-04-13 20:39:22.706247 | Updating git repos 2026-04-13 20:39:22.758895 | Cloning repos into workspace 2026-04-13 20:39:22.811193 | Restoring repo states 2026-04-13 20:39:22.829153 | Merging changes 2026-04-13 20:39:23.746789 | Checking out repos 2026-04-13 20:39:24.063006 | Preparing playbooks 2026-04-13 20:39:26.663427 | Running Ansible setup 2026-04-13 20:39:30.100116 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-13 20:39:30.729502 | 2026-04-13 20:39:30.729914 | PLAY [localhost] 2026-04-13 20:39:30.738606 | 2026-04-13 20:39:30.738692 | TASK [Gathering Facts] 2026-04-13 20:39:31.594547 | localhost | ok 2026-04-13 20:39:31.604279 | 2026-04-13 20:39:31.604356 | TASK [Setup log path fact] 2026-04-13 20:39:31.622497 | localhost | ok 2026-04-13 20:39:31.634453 | 2026-04-13 20:39:31.634527 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-13 20:39:31.657618 | localhost | ok 2026-04-13 20:39:31.666041 | 2026-04-13 20:39:31.666135 | TASK [emit-job-header : Print job information] 2026-04-13 20:39:31.704822 | # Job Information 2026-04-13 20:39:31.733880 | Ansible Version: 2.16.16 2026-04-13 20:39:31.733967 | Job: atmosphere-molecule-keycloak 2026-04-13 20:39:31.733994 | Pipeline: check 2026-04-13 20:39:31.734014 | Executor: 0a8996d2b663 2026-04-13 20:39:31.734034 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3818 2026-04-13 20:39:31.734056 | Event ID: af35ae10-3778-11f1-8810-704e89e38670 2026-04-13 20:39:31.736951 | 2026-04-13 20:39:31.737034 | LOOP [emit-job-header : Print node information] 2026-04-13 20:39:31.825121 | localhost | ok: 2026-04-13 20:39:31.825394 | localhost | # Node Information 2026-04-13 20:39:31.825458 | localhost | Inventory Hostname: instance 2026-04-13 20:39:31.825506 | localhost | Hostname: np0000165708 2026-04-13 20:39:31.825549 | localhost | Username: zuul 2026-04-13 20:39:31.825598 | localhost | Distro: Ubuntu 22.04 2026-04-13 20:39:31.825642 | localhost | Provider: yul1 2026-04-13 20:39:31.825684 | localhost | Region: ca-ymq-1 2026-04-13 20:39:31.825725 | localhost | Label: ubuntu-jammy 2026-04-13 20:39:31.825766 | localhost | Product Name: OpenStack Nova 2026-04-13 20:39:31.825807 | localhost | Interface IP: 199.204.45.89 2026-04-13 20:39:31.840701 | 2026-04-13 20:39:31.840833 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-04-13 20:39:32.272805 | localhost -> localhost | changed 2026-04-13 20:39:32.282712 | 2026-04-13 20:39:32.282831 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-04-13 20:39:33.190240 | localhost -> localhost | changed 2026-04-13 20:39:33.198177 | 2026-04-13 20:39:33.198310 | PLAY [all] 2026-04-13 20:39:33.209101 | 2026-04-13 20:39:33.209257 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-04-13 20:39:33.451704 | instance -> localhost | ok 2026-04-13 20:39:33.459111 | 2026-04-13 20:39:33.459268 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-04-13 20:39:33.491313 | instance | ok 2026-04-13 20:39:33.506110 | instance | included: /var/lib/zuul/builds/10ee3aeb743740098ab62e02ab36e322/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-04-13 20:39:33.569355 | 2026-04-13 20:39:33.569525 | TASK [add-build-sshkey : Create Temp SSH key] 2026-04-13 20:39:35.258742 | instance -> localhost | Generating public/private rsa key pair. 2026-04-13 20:39:35.258971 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/10ee3aeb743740098ab62e02ab36e322/work/10ee3aeb743740098ab62e02ab36e322_id_rsa 2026-04-13 20:39:35.259004 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/10ee3aeb743740098ab62e02ab36e322/work/10ee3aeb743740098ab62e02ab36e322_id_rsa.pub 2026-04-13 20:39:35.259028 | instance -> localhost | The key fingerprint is: 2026-04-13 20:39:35.259049 | instance -> localhost | SHA256:eijY/V+ZHYyef284SNlaNoPuj3LUZfTJ/W/TIA8z8RI zuul-build-sshkey 2026-04-13 20:39:35.259090 | instance -> localhost | The key's randomart image is: 2026-04-13 20:39:35.259111 | instance -> localhost | +---[RSA 3072]----+ 2026-04-13 20:39:35.259139 | instance -> localhost | | | 2026-04-13 20:39:35.259162 | instance -> localhost | | .| 2026-04-13 20:39:35.259183 | instance -> localhost | | ..+| 2026-04-13 20:39:35.259203 | instance -> localhost | | Eo o=| 2026-04-13 20:39:35.259223 | instance -> localhost | | S .Ooo.| 2026-04-13 20:39:35.259243 | instance -> localhost | | o . o .@=X..| 2026-04-13 20:39:35.259263 | instance -> localhost | | . o + . +*%.=o| 2026-04-13 20:39:35.259283 | instance -> localhost | | . o ..=o+.*| 2026-04-13 20:39:35.259305 | instance -> localhost | | ...+o.o=+| 2026-04-13 20:39:35.259325 | instance -> localhost | +----[SHA256]-----+ 2026-04-13 20:39:35.259382 | instance -> localhost | ok: Runtime: 0:00:01.203300 2026-04-13 20:39:35.265071 | 2026-04-13 20:39:35.265152 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-04-13 20:39:35.303585 | instance | ok 2026-04-13 20:39:35.319432 | instance | included: /var/lib/zuul/builds/10ee3aeb743740098ab62e02ab36e322/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-04-13 20:39:35.327069 | 2026-04-13 20:39:35.327139 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-04-13 20:39:35.351274 | instance | skipping: Conditional result was False 2026-04-13 20:39:35.362429 | 2026-04-13 20:39:35.362518 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-04-13 20:39:35.803240 | instance | changed 2026-04-13 20:39:35.808770 | 2026-04-13 20:39:35.808845 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-04-13 20:39:36.011807 | instance | ok 2026-04-13 20:39:36.016826 | 2026-04-13 20:39:36.016894 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-04-13 20:39:36.487706 | instance | changed 2026-04-13 20:39:36.495990 | 2026-04-13 20:39:36.496093 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-04-13 20:39:37.019690 | instance | changed 2026-04-13 20:39:37.030907 | 2026-04-13 20:39:37.031017 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-04-13 20:39:37.059011 | instance | skipping: Conditional result was False 2026-04-13 20:39:37.065482 | 2026-04-13 20:39:37.065566 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-04-13 20:39:37.432651 | instance -> localhost | changed 2026-04-13 20:39:37.449291 | 2026-04-13 20:39:37.449398 | TASK [add-build-sshkey : Add back temp key] 2026-04-13 20:39:37.748699 | instance -> localhost | Identity added: /var/lib/zuul/builds/10ee3aeb743740098ab62e02ab36e322/work/10ee3aeb743740098ab62e02ab36e322_id_rsa (zuul-build-sshkey) 2026-04-13 20:39:37.748945 | instance -> localhost | ok: Runtime: 0:00:00.014249 2026-04-13 20:39:37.754895 | 2026-04-13 20:39:37.757571 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-04-13 20:39:38.080259 | instance | ok 2026-04-13 20:39:38.118571 | 2026-04-13 20:39:38.118697 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-04-13 20:39:38.142813 | instance | skipping: Conditional result was False 2026-04-13 20:39:38.157143 | 2026-04-13 20:39:38.157244 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-04-13 20:39:38.480048 | instance | ok 2026-04-13 20:39:38.485638 | 2026-04-13 20:39:38.485722 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-04-13 20:39:40.128523 | instance | Output suppressed because no_log was given 2026-04-13 20:39:40.140081 | 2026-04-13 20:39:40.140164 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-04-13 20:39:40.331135 | instance | ok: "logs" 2026-04-13 20:39:40.331402 | instance | ok: All items complete 2026-04-13 20:39:40.331444 | 2026-04-13 20:39:40.486407 | instance | ok: "artifacts" 2026-04-13 20:39:40.633615 | instance | ok: "docs" 2026-04-13 20:39:40.652694 | 2026-04-13 20:39:40.652858 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-04-13 20:39:40.854968 | instance | changed: "logs" 2026-04-13 20:39:41.012936 | instance | changed: "artifacts" 2026-04-13 20:39:41.174611 | instance | changed: "docs" 2026-04-13 20:39:41.196849 | 2026-04-13 20:39:41.196924 | PLAY RECAP 2026-04-13 20:39:41.196995 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-04-13 20:39:41.197028 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-13 20:39:41.197051 | 2026-04-13 20:39:41.328306 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-13 20:39:41.333368 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-13 20:39:41.940326 | 2026-04-13 20:39:41.940476 | PLAY [all] 2026-04-13 20:39:41.955892 | 2026-04-13 20:39:41.956037 | TASK [setup-uv : Extract archive] 2026-04-13 20:39:44.133067 | instance | changed 2026-04-13 20:39:44.293263 | 2026-04-13 20:39:44.293434 | TASK [setup-uv : Print version] 2026-04-13 20:39:44.795562 | instance | uv 0.8.13 2026-04-13 20:39:44.849338 | instance | ok: Runtime: 0:00:00.013421 2026-04-13 20:39:44.860663 | 2026-04-13 20:39:44.860804 | PLAY RECAP 2026-04-13 20:39:44.860861 | instance | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-13 20:39:44.860893 | 2026-04-13 20:39:44.981783 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-13 20:39:44.985124 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/pre.yml@main] 2026-04-13 20:39:45.592432 | 2026-04-13 20:39:45.592701 | PLAY [all] 2026-04-13 20:39:45.604159 | 2026-04-13 20:39:45.604268 | TASK [Install "jq" for log collection] 2026-04-13 20:39:55.522357 | instance | changed 2026-04-13 20:39:55.530894 | 2026-04-13 20:39:55.531087 | TASK [Install pip3 for Python package management] 2026-04-13 20:39:59.829762 | instance | changed 2026-04-13 20:39:59.836425 | 2026-04-13 20:39:59.836505 | TASK [Install Python "kubernetes" library for kubernetes.core modules] 2026-04-13 20:40:02.928387 | instance | changed 2026-04-13 20:40:02.931515 | 2026-04-13 20:40:02.931571 | PLAY [all] 2026-04-13 20:40:02.939370 | 2026-04-13 20:40:02.939439 | TASK [ensure-go : Check installed go version] 2026-04-13 20:40:03.476001 | instance | ok: ERROR (ignored) 2026-04-13 20:40:03.476303 | instance | { 2026-04-13 20:40:03.476352 | instance | "failed_when_result": false, 2026-04-13 20:40:03.476388 | instance | "msg": "[Errno 2] No such file or directory: b'go'", 2026-04-13 20:40:03.476429 | instance | "rc": 2 2026-04-13 20:40:03.476472 | instance | } 2026-04-13 20:40:03.484235 | 2026-04-13 20:40:03.484401 | TASK [ensure-go : Skip if correct version of go is installed] 2026-04-13 20:40:03.539210 | instance | ok 2026-04-13 20:40:03.547932 | instance | included: /var/lib/zuul/builds/10ee3aeb743740098ab62e02ab36e322/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/ensure-go/tasks/install-go.yaml 2026-04-13 20:40:03.553775 | 2026-04-13 20:40:03.553892 | TASK [ensure-go : Create temp directory] 2026-04-13 20:40:03.837681 | instance | changed 2026-04-13 20:40:03.844696 | 2026-04-13 20:40:03.844831 | TASK [ensure-go : Get archive checksum] 2026-04-13 20:40:04.459721 | instance | ok: OK (64 bytes) 2026-04-13 20:40:04.467073 | 2026-04-13 20:40:04.467297 | TASK [ensure-go : Download go archive] 2026-04-13 20:40:05.845712 | instance | changed: OK (78559214 bytes) 2026-04-13 20:40:05.852092 | 2026-04-13 20:40:05.852190 | TASK [ensure-go : Install go] 2026-04-13 20:40:11.639134 | instance | changed 2026-04-13 20:40:11.647979 | 2026-04-13 20:40:11.648048 | PLAY [all] 2026-04-13 20:40:11.657209 | 2026-04-13 20:40:11.657390 | TASK [Build atmosphere binary] 2026-04-13 20:40:13.410232 | instance -> localhost | ERROR 2026-04-13 20:40:13.410527 | instance -> localhost | { 2026-04-13 20:40:13.410580 | instance -> localhost | "delta": null, 2026-04-13 20:40:13.410602 | instance -> localhost | "end": null, 2026-04-13 20:40:13.410621 | instance -> localhost | "msg": "Unable to change directory before execution: [Errno 2] No such file or directory: b'src/github.com/vexxhost/atmosphere'", 2026-04-13 20:40:13.410640 | instance -> localhost | "rc": null, 2026-04-13 20:40:13.410657 | instance -> localhost | "start": null 2026-04-13 20:40:13.410675 | instance -> localhost | } failure 2026-04-13 20:40:13.412341 | 2026-04-13 20:40:13.412427 | PLAY RECAP 2026-04-13 20:40:13.412510 | instance | ok: 9 changed: 6 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-04-13 20:40:13.412562 | 2026-04-13 20:40:13.635620 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/pre.yml@main] 2026-04-13 20:40:13.641281 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@main] 2026-04-13 20:40:14.389086 | 2026-04-13 20:40:14.389266 | PLAY [all] 2026-04-13 20:40:14.410283 | 2026-04-13 20:40:14.410460 | TASK [gather-host-logs : creating directory for system status] 2026-04-13 20:40:14.771061 | instance | changed 2026-04-13 20:40:14.776774 | 2026-04-13 20:40:14.776901 | TASK [gather-host-logs : Get logs for each host] 2026-04-13 20:40:15.132799 | instance | + systemd-cgls --full --all --no-pager 2026-04-13 20:40:15.144641 | instance | + ip addr 2026-04-13 20:40:15.146864 | instance | + ip route 2026-04-13 20:40:15.148886 | instance | + lsblk 2026-04-13 20:40:15.154513 | instance | + mount 2026-04-13 20:40:15.156533 | instance | + docker images 2026-04-13 20:40:15.157091 | instance | /bin/bash: line 7: docker: command not found 2026-04-13 20:40:15.157379 | instance | + brctl show 2026-04-13 20:40:15.157879 | instance | /bin/bash: line 8: brctl: command not found 2026-04-13 20:40:15.158145 | instance | + ps aux --sort=-%mem 2026-04-13 20:40:15.170688 | instance | + dpkg -l 2026-04-13 20:40:15.177181 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul)) 2026-04-13 20:40:15.177567 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul 2026-04-13 20:40:15.177587 | instance | /bin/bash: line 11: docker: command not found 2026-04-13 20:40:15.177892 | instance | + '[' '!' -z '' ']' 2026-04-13 20:40:15.316895 | instance | ok: Runtime: 0:00:00.048981 2026-04-13 20:40:15.323430 | 2026-04-13 20:40:15.323543 | TASK [gather-host-logs : Downloads logs to executor] 2026-04-13 20:40:16.006272 | instance | changed: 2026-04-13 20:40:16.006455 | instance | created directory /var/lib/zuul/builds/10ee3aeb743740098ab62e02ab36e322/work/logs/instance 2026-04-13 20:40:16.006500 | instance | cd+++++++++ system/ 2026-04-13 20:40:16.006532 | instance | >f+++++++++ system/brctl-show.txt 2026-04-13 20:40:16.006563 | instance | >f+++++++++ system/docker-images.txt 2026-04-13 20:40:16.006592 | instance | >f+++++++++ system/ip-addr.txt 2026-04-13 20:40:16.006624 | instance | >f+++++++++ system/ip-route.txt 2026-04-13 20:40:16.006654 | instance | >f+++++++++ system/lsblk.txt 2026-04-13 20:40:16.006684 | instance | >f+++++++++ system/mount.txt 2026-04-13 20:40:16.006716 | instance | >f+++++++++ system/packages.txt 2026-04-13 20:40:16.006745 | instance | >f+++++++++ system/ps.txt 2026-04-13 20:40:16.006773 | instance | >f+++++++++ system/systemd-cgls.txt 2026-04-13 20:40:16.017419 | 2026-04-13 20:40:16.017513 | LOOP [helm-release-status : creating directory for helm release status] 2026-04-13 20:40:16.218052 | instance | changed: "values" 2026-04-13 20:40:16.429769 | instance | changed: "releases" 2026-04-13 20:40:16.440802 | 2026-04-13 20:40:16.440937 | TASK [helm-release-status : Gather get release status for helm charts] 2026-04-13 20:40:16.647913 | instance | /bin/bash: line 3: kubectl: command not found 2026-04-13 20:40:22.348058 | instance | ok: Runtime: 0:00:00.005383 2026-04-13 20:40:22.355617 | 2026-04-13 20:40:22.355780 | TASK [helm-release-status : Downloads logs to executor] 2026-04-13 20:40:22.846847 | instance | changed: 2026-04-13 20:40:22.847166 | instance | cd+++++++++ helm/ 2026-04-13 20:40:22.847270 | instance | cd+++++++++ helm/releases/ 2026-04-13 20:40:22.847334 | instance | cd+++++++++ helm/values/ 2026-04-13 20:40:22.858522 | 2026-04-13 20:40:22.858600 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects] 2026-04-13 20:40:23.063223 | instance | changed 2026-04-13 20:40:23.069577 | 2026-04-13 20:40:23.069653 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects] 2026-04-13 20:40:23.282699 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-13 20:40:23.282848 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-13 20:40:23.286616 | instance | environment: line 1: kubectl: command not found 2026-04-13 20:40:23.287939 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-13 20:40:23.291046 | instance | environment: line 1: kubectl: command not found 2026-04-13 20:40:23.292243 | instance | environment: line 1: kubectl: command not found 2026-04-13 20:40:23.292874 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-13 20:40:23.294067 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-13 20:40:23.295840 | instance | environment: line 1: kubectl: command not found 2026-04-13 20:40:23.297568 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-13 20:40:23.297820 | instance | environment: line 1: kubectl: command not found 2026-04-13 20:40:23.299141 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-13 20:40:23.604283 | instance | ok: Runtime: 0:00:00.029131 2026-04-13 20:40:23.611180 | 2026-04-13 20:40:23.611268 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects] 2026-04-13 20:40:23.828877 | instance | changed 2026-04-13 20:40:23.834517 | 2026-04-13 20:40:23.834611 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects] 2026-04-13 20:40:24.091808 | instance | environment: line 5: kubectl: command not found 2026-04-13 20:40:24.092609 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-13 20:40:24.092733 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-13 20:40:24.093038 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-13 20:40:24.407466 | instance | ok: Runtime: 0:00:00.008107 2026-04-13 20:40:24.415081 | 2026-04-13 20:40:24.415242 | TASK [describe-kubernetes-objects : Downloads logs to executor] 2026-04-13 20:40:24.946223 | instance | changed: 2026-04-13 20:40:24.946415 | instance | cd+++++++++ objects/ 2026-04-13 20:40:24.946522 | instance | cd+++++++++ objects/cluster/ 2026-04-13 20:40:24.946644 | instance | cd+++++++++ objects/namespaced/ 2026-04-13 20:40:24.959842 | 2026-04-13 20:40:24.959945 | TASK [gather-pod-logs : creating directory for pod logs] 2026-04-13 20:40:25.155873 | instance | changed 2026-04-13 20:40:25.163146 | 2026-04-13 20:40:25.163208 | TASK [gather-pod-logs : creating directory for failed pod logs] 2026-04-13 20:40:25.365842 | instance | changed 2026-04-13 20:40:25.372061 | 2026-04-13 20:40:25.372141 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)] 2026-04-13 20:40:25.582927 | instance | environment: line 3: kubectl: command not found 2026-04-13 20:40:25.905222 | instance | ok: Runtime: 0:00:00.007658 2026-04-13 20:40:25.912203 | 2026-04-13 20:40:25.912310 | TASK [gather-pod-logs : Downloads pod logs to executor] 2026-04-13 20:40:26.396806 | instance | changed: 2026-04-13 20:40:26.397031 | instance | cd+++++++++ pod-logs/ 2026-04-13 20:40:26.397076 | instance | cd+++++++++ pod-logs/failed-pods/ 2026-04-13 20:40:26.414713 | 2026-04-13 20:40:26.414871 | TASK [gather-prom-metrics : creating directory for helm release descriptions] 2026-04-13 20:40:26.600812 | instance | changed 2026-04-13 20:40:26.609336 | 2026-04-13 20:40:26.609414 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces] 2026-04-13 20:40:26.811269 | instance | /bin/bash: line 2: kubectl: command not found 2026-04-13 20:40:31.500477 | instance | ok: Runtime: 0:00:00.030313 2026-04-13 20:40:31.507744 | 2026-04-13 20:40:31.507847 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr] 2026-04-13 20:40:31.744807 | instance | /bin/bash: line 2: kubectl: command not found 2026-04-13 20:40:31.773137 | instance | ceph-mgr endpoints: 2026-04-13 20:40:32.615580 | instance | ok: Runtime: 0:00:00.035643 2026-04-13 20:40:32.622864 | 2026-04-13 20:40:32.622993 | TASK [gather-prom-metrics : Get metrics from fluentd pods] 2026-04-13 20:40:32.830161 | instance | /bin/bash: line 4: kubectl: command not found 2026-04-13 20:40:33.156769 | instance | ok: Runtime: 0:00:00.037817 2026-04-13 20:40:33.162745 | 2026-04-13 20:40:33.162883 | TASK [gather-prom-metrics : Downloads logs to executor] 2026-04-13 20:40:33.646795 | instance | changed: cd+++++++++ prometheus/ 2026-04-13 20:40:33.657882 | 2026-04-13 20:40:33.657962 | TASK [gather-selenium-data : creating directory for helm release descriptions] 2026-04-13 20:40:33.861447 | instance | changed 2026-04-13 20:40:33.868017 | 2026-04-13 20:40:33.868100 | TASK [gather-selenium-data : Get selenium data] 2026-04-13 20:40:34.082878 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/. 2026-04-13 20:40:34.084159 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory 2026-04-13 20:40:34.408399 | instance | ERROR 2026-04-13 20:40:34.408681 | instance | { 2026-04-13 20:40:34.408754 | instance | "delta": "0:00:00.005543", 2026-04-13 20:40:34.408804 | instance | "end": "2026-04-13 20:40:34.084476", 2026-04-13 20:40:34.408850 | instance | "msg": "non-zero return code", 2026-04-13 20:40:34.408894 | instance | "rc": 1, 2026-04-13 20:40:34.408937 | instance | "start": "2026-04-13 20:40:34.078933" 2026-04-13 20:40:34.409012 | instance | } 2026-04-13 20:40:34.409069 | instance | ERROR: Ignoring Errors 2026-04-13 20:40:34.415408 | 2026-04-13 20:40:34.415474 | TASK [gather-selenium-data : Downloads logs to executor] 2026-04-13 20:40:34.951411 | instance | changed: cd+++++++++ selenium/ 2026-04-13 20:40:34.958748 | 2026-04-13 20:40:34.959518 | PLAY RECAP 2026-04-13 20:40:34.959744 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1 2026-04-13 20:40:34.959840 | 2026-04-13 20:40:35.072239 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@main] 2026-04-13 20:40:35.077795 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-13 20:40:35.703120 | 2026-04-13 20:40:35.703255 | PLAY [all] 2026-04-13 20:40:35.718112 | 2026-04-13 20:40:35.718285 | TASK [fetch-output : Set log path for multiple nodes] 2026-04-13 20:40:35.762953 | instance | skipping: Conditional result was False 2026-04-13 20:40:35.770352 | 2026-04-13 20:40:35.770473 | TASK [fetch-output : Set log path for single node] 2026-04-13 20:40:35.815634 | instance | ok 2026-04-13 20:40:35.822451 | 2026-04-13 20:40:35.822521 | LOOP [fetch-output : Ensure local output dirs] 2026-04-13 20:40:36.248204 | instance -> localhost | ok: "/var/lib/zuul/builds/10ee3aeb743740098ab62e02ab36e322/work/logs" 2026-04-13 20:40:36.456412 | instance -> localhost | changed: "/var/lib/zuul/builds/10ee3aeb743740098ab62e02ab36e322/work/artifacts" 2026-04-13 20:40:36.713371 | instance -> localhost | changed: "/var/lib/zuul/builds/10ee3aeb743740098ab62e02ab36e322/work/docs" 2026-04-13 20:40:36.727572 | 2026-04-13 20:40:36.727691 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-04-13 20:40:38.556059 | instance | changed: .d..t...... ./ 2026-04-13 20:40:38.556398 | instance | changed: All items complete 2026-04-13 20:40:38.556448 | 2026-04-13 20:40:39.001981 | instance | changed: .d..t...... ./ 2026-04-13 20:40:39.452339 | instance | changed: .d..t...... ./ 2026-04-13 20:40:39.470202 | 2026-04-13 20:40:39.470473 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-04-13 20:40:39.913184 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.007462 2026-04-13 20:40:40.166678 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.029310 2026-04-13 20:40:40.188524 | 2026-04-13 20:40:40.188696 | PLAY [all] 2026-04-13 20:40:40.196404 | 2026-04-13 20:40:40.196480 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-04-13 20:40:40.633307 | instance | changed 2026-04-13 20:40:40.640316 | 2026-04-13 20:40:40.640410 | PLAY RECAP 2026-04-13 20:40:40.640469 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-04-13 20:40:40.640501 | 2026-04-13 20:40:40.745048 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-13 20:40:40.753610 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-04-13 20:40:41.369951 | 2026-04-13 20:40:42.994293 | PLAY [localhost] 2026-04-13 20:40:43.012890 | 2026-04-13 20:40:43.013101 | TASK [Generate Zuul manifest] 2026-04-13 20:40:43.047250 | localhost | ok 2026-04-13 20:40:43.067307 | 2026-04-13 20:40:43.067467 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-04-13 20:40:43.435266 | localhost | changed 2026-04-13 20:40:43.447027 | 2026-04-13 20:40:43.447108 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-04-13 20:40:43.488493 | localhost | ok 2026-04-13 20:40:43.498185 | 2026-04-13 20:40:43.498258 | TASK [Upload logs] 2026-04-13 20:40:43.516919 | localhost | ok 2026-04-13 20:40:43.585612 | 2026-04-13 20:40:43.585755 | TASK [Set zuul-log-path fact] 2026-04-13 20:40:43.616128 | localhost | ok 2026-04-13 20:40:43.630022 | 2026-04-13 20:40:43.630095 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-13 20:40:43.660681 | localhost | ok 2026-04-13 20:40:43.670176 | 2026-04-13 20:40:43.670264 | TASK [upload-logs : Create log directories] 2026-04-13 20:40:44.116024 | localhost | changed 2026-04-13 20:40:44.122660 | 2026-04-13 20:40:44.122961 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-04-13 20:40:44.510881 | localhost -> localhost | ok: Runtime: 0:00:00.005500 2026-04-13 20:40:44.516988 | 2026-04-13 20:40:44.517065 | TASK [upload-logs : Upload logs to log server] 2026-04-13 20:40:44.974484 | localhost | Output suppressed because no_log was given 2026-04-13 20:40:44.978591 | 2026-04-13 20:40:44.978660 | LOOP [upload-logs : Compress console log and json output] 2026-04-13 20:40:45.031911 | localhost | skipping: Conditional result was False 2026-04-13 20:40:45.040552 | localhost | skipping: Conditional result was False 2026-04-13 20:40:45.053358 | 2026-04-13 20:40:45.053546 | LOOP [upload-logs : Upload compressed console log and json output] 2026-04-13 20:40:45.115319 | localhost | skipping: Conditional result was False 2026-04-13 20:40:45.115867 | 2026-04-13 20:40:45.118811 | localhost | skipping: Conditional result was False 2026-04-13 20:40:45.136482 | 2026-04-13 20:40:45.136629 | LOOP [upload-logs : Upload console log and json output]