2026-04-13 20:36:32.276657 | Job console starting 2026-04-13 20:36:32.297259 | Updating git repos 2026-04-13 20:36:32.352931 | Cloning repos into workspace 2026-04-13 20:36:32.610152 | Restoring repo states 2026-04-13 20:36:32.636176 | Merging changes 2026-04-13 20:36:33.942847 | Checking out repos 2026-04-13 20:36:35.145954 | Preparing playbooks 2026-04-13 20:37:06.002535 | Running Ansible setup 2026-04-13 20:37:09.921443 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-13 20:37:10.557580 | 2026-04-13 20:37:10.557740 | PLAY [localhost] 2026-04-13 20:37:10.566778 | 2026-04-13 20:37:10.566910 | TASK [Gathering Facts] 2026-04-13 20:37:11.455349 | localhost | ok 2026-04-13 20:37:11.465433 | 2026-04-13 20:37:11.469306 | TASK [Setup log path fact] 2026-04-13 20:37:11.486857 | localhost | ok 2026-04-13 20:37:11.501408 | 2026-04-13 20:37:11.501749 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-13 20:37:11.545576 | localhost | ok 2026-04-13 20:37:11.555019 | 2026-04-13 20:37:11.555160 | TASK [emit-job-header : Print job information] 2026-04-13 20:37:11.595236 | # Job Information 2026-04-13 20:37:11.595437 | Ansible Version: 2.16.16 2026-04-13 20:37:11.595488 | Job: atmosphere-molecule-keycloak 2026-04-13 20:37:11.595530 | Pipeline: check 2026-04-13 20:37:11.595565 | Executor: 0a8996d2b663 2026-04-13 20:37:11.595600 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3818 2026-04-13 20:37:11.595645 | Event ID: 5e803620-3778-11f1-8282-f22f713c42c2 2026-04-13 20:37:11.599909 | 2026-04-13 20:37:11.600011 | LOOP [emit-job-header : Print node information] 2026-04-13 20:37:11.699372 | localhost | ok: 2026-04-13 20:37:11.699588 | localhost | # Node Information 2026-04-13 20:37:11.699646 | localhost | Inventory Hostname: instance 2026-04-13 20:37:11.699684 | localhost | Hostname: np0000165701 2026-04-13 20:37:11.699724 | localhost | Username: zuul 2026-04-13 20:37:11.699764 | localhost | Distro: Ubuntu 22.04 2026-04-13 20:37:11.699794 | localhost | Provider: yul1 2026-04-13 20:37:11.699830 | localhost | Region: ca-ymq-1 2026-04-13 20:37:11.699863 | localhost | Label: ubuntu-jammy 2026-04-13 20:37:11.699890 | localhost | Product Name: OpenStack Nova 2026-04-13 20:37:11.699917 | localhost | Interface IP: 199.19.213.188 2026-04-13 20:37:11.709898 | 2026-04-13 20:37:11.713291 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-04-13 20:37:12.133597 | localhost -> localhost | changed 2026-04-13 20:37:12.142605 | 2026-04-13 20:37:12.142751 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-04-13 20:37:13.147599 | localhost -> localhost | changed 2026-04-13 20:37:13.158238 | 2026-04-13 20:37:13.158351 | PLAY [all] 2026-04-13 20:37:13.167930 | 2026-04-13 20:37:13.168016 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-04-13 20:37:13.535223 | instance -> localhost | ok 2026-04-13 20:37:13.543883 | 2026-04-13 20:37:13.544228 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-04-13 20:37:13.576346 | instance | ok 2026-04-13 20:37:13.592465 | instance | included: /var/lib/zuul/builds/606598c048ad48d4b7a6bcb6a8ac0607/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-04-13 20:37:13.615619 | 2026-04-13 20:37:13.615760 | TASK [add-build-sshkey : Create Temp SSH key] 2026-04-13 20:37:16.034873 | instance -> localhost | Generating public/private rsa key pair. 2026-04-13 20:37:16.035067 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/606598c048ad48d4b7a6bcb6a8ac0607/work/606598c048ad48d4b7a6bcb6a8ac0607_id_rsa 2026-04-13 20:37:16.035097 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/606598c048ad48d4b7a6bcb6a8ac0607/work/606598c048ad48d4b7a6bcb6a8ac0607_id_rsa.pub 2026-04-13 20:37:16.035120 | instance -> localhost | The key fingerprint is: 2026-04-13 20:37:16.035141 | instance -> localhost | SHA256:UWRBVjojlUnr58idhCn2aaQvBH0e0LjDEGg0Hm9hZ40 zuul-build-sshkey 2026-04-13 20:37:16.035182 | instance -> localhost | The key's randomart image is: 2026-04-13 20:37:16.035207 | instance -> localhost | +---[RSA 3072]----+ 2026-04-13 20:37:16.035245 | instance -> localhost | | .+.+.oOX=. | 2026-04-13 20:37:16.035270 | instance -> localhost | | .o=.+E==o | 2026-04-13 20:37:16.035293 | instance -> localhost | | .. o+oo= | 2026-04-13 20:37:16.035313 | instance -> localhost | | .. =+o= | 2026-04-13 20:37:16.035335 | instance -> localhost | | .S+=.o | 2026-04-13 20:37:16.035355 | instance -> localhost | | ..*.B . | 2026-04-13 20:37:16.035376 | instance -> localhost | | .. * + | 2026-04-13 20:37:16.035397 | instance -> localhost | | .o | 2026-04-13 20:37:16.035420 | instance -> localhost | | .. | 2026-04-13 20:37:16.035440 | instance -> localhost | +----[SHA256]-----+ 2026-04-13 20:37:16.035499 | instance -> localhost | ok: Runtime: 0:00:01.940996 2026-04-13 20:37:16.041104 | 2026-04-13 20:37:16.041209 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-04-13 20:37:16.073004 | instance | ok 2026-04-13 20:37:16.084240 | instance | included: /var/lib/zuul/builds/606598c048ad48d4b7a6bcb6a8ac0607/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-04-13 20:37:16.092324 | 2026-04-13 20:37:16.092447 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-04-13 20:37:16.116177 | instance | skipping: Conditional result was False 2026-04-13 20:37:16.125100 | 2026-04-13 20:37:16.125272 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-04-13 20:37:16.584172 | instance | changed 2026-04-13 20:37:16.591844 | 2026-04-13 20:37:16.592008 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-04-13 20:37:16.775170 | instance | ok 2026-04-13 20:37:17.077134 | 2026-04-13 20:37:17.077303 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-04-13 20:37:17.545739 | instance | changed 2026-04-13 20:37:17.552202 | 2026-04-13 20:37:17.552282 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-04-13 20:37:18.004186 | instance | changed 2026-04-13 20:37:18.011067 | 2026-04-13 20:37:18.011163 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-04-13 20:37:18.034629 | instance | skipping: Conditional result was False 2026-04-13 20:37:18.042427 | 2026-04-13 20:37:18.042904 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-04-13 20:37:18.465926 | instance -> localhost | changed 2026-04-13 20:37:18.483121 | 2026-04-13 20:37:18.483231 | TASK [add-build-sshkey : Add back temp key] 2026-04-13 20:37:18.768124 | instance -> localhost | Identity added: /var/lib/zuul/builds/606598c048ad48d4b7a6bcb6a8ac0607/work/606598c048ad48d4b7a6bcb6a8ac0607_id_rsa (zuul-build-sshkey) 2026-04-13 20:37:18.768350 | instance -> localhost | ok: Runtime: 0:00:00.011134 2026-04-13 20:37:18.774856 | 2026-04-13 20:37:18.774924 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-04-13 20:37:19.132282 | instance | ok 2026-04-13 20:37:19.137297 | 2026-04-13 20:37:19.137363 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-04-13 20:37:19.171784 | instance | skipping: Conditional result was False 2026-04-13 20:37:19.186463 | 2026-04-13 20:37:19.186545 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-04-13 20:37:19.546493 | instance | ok 2026-04-13 20:37:19.552382 | 2026-04-13 20:37:19.552498 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-04-13 20:37:21.346439 | instance | Output suppressed because no_log was given 2026-04-13 20:37:21.372931 | 2026-04-13 20:37:21.373086 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-04-13 20:37:21.551649 | instance | ok: "logs" 2026-04-13 20:37:21.552275 | instance | ok: All items complete 2026-04-13 20:37:21.552344 | 2026-04-13 20:37:21.715700 | instance | ok: "artifacts" 2026-04-13 20:37:21.878018 | instance | ok: "docs" 2026-04-13 20:37:21.885565 | 2026-04-13 20:37:21.885688 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-04-13 20:37:22.058190 | instance | changed: "logs" 2026-04-13 20:37:22.211695 | instance | changed: "artifacts" 2026-04-13 20:37:22.354151 | instance | changed: "docs" 2026-04-13 20:37:22.372936 | 2026-04-13 20:37:22.373142 | PLAY RECAP 2026-04-13 20:37:22.373257 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-04-13 20:37:22.373302 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-13 20:37:22.373333 | 2026-04-13 20:37:22.535994 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-13 20:37:22.538315 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-13 20:37:23.155982 | 2026-04-13 20:37:23.156116 | PLAY [all] 2026-04-13 20:37:23.171109 | 2026-04-13 20:37:23.171263 | TASK [setup-uv : Extract archive] 2026-04-13 20:37:25.424386 | instance | changed 2026-04-13 20:37:25.432019 | 2026-04-13 20:37:25.432143 | TASK [setup-uv : Print version] 2026-04-13 20:37:25.750815 | instance | uv 0.8.13 2026-04-13 20:37:25.966099 | instance | ok: Runtime: 0:00:00.011180 2026-04-13 20:37:25.975681 | 2026-04-13 20:37:25.975757 | PLAY RECAP 2026-04-13 20:37:25.975803 | instance | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-13 20:37:25.975827 | 2026-04-13 20:37:26.082369 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-13 20:37:26.086874 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/pre.yml@main] 2026-04-13 20:37:26.743815 | 2026-04-13 20:37:26.743945 | PLAY [all] 2026-04-13 20:37:26.755012 | 2026-04-13 20:37:26.755093 | TASK [Install "jq" for log collection] 2026-04-13 20:37:37.142013 | instance | changed 2026-04-13 20:37:37.150295 | 2026-04-13 20:37:37.150393 | TASK [Install pip3 for Python package management] 2026-04-13 20:37:41.450390 | instance | changed 2026-04-13 20:37:41.456572 | 2026-04-13 20:37:41.456635 | TASK [Install Python "kubernetes" library for kubernetes.core modules] 2026-04-13 20:37:44.595364 | instance | changed 2026-04-13 20:37:44.598576 | 2026-04-13 20:37:44.598646 | PLAY [all] 2026-04-13 20:37:44.607918 | 2026-04-13 20:37:44.608031 | TASK [ensure-go : Check installed go version] 2026-04-13 20:37:45.145017 | instance | ok: ERROR (ignored) 2026-04-13 20:37:45.145360 | instance | { 2026-04-13 20:37:45.145439 | instance | "failed_when_result": false, 2026-04-13 20:37:45.145489 | instance | "msg": "[Errno 2] No such file or directory: b'go'", 2026-04-13 20:37:45.145538 | instance | "rc": 2 2026-04-13 20:37:45.145585 | instance | } 2026-04-13 20:37:45.152168 | 2026-04-13 20:37:45.152232 | TASK [ensure-go : Skip if correct version of go is installed] 2026-04-13 20:37:45.202597 | instance | ok 2026-04-13 20:37:45.227327 | instance | included: /var/lib/zuul/builds/606598c048ad48d4b7a6bcb6a8ac0607/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/ensure-go/tasks/install-go.yaml 2026-04-13 20:37:45.235016 | 2026-04-13 20:37:45.244196 | TASK [ensure-go : Create temp directory] 2026-04-13 20:37:45.541294 | instance | changed 2026-04-13 20:37:45.547683 | 2026-04-13 20:37:45.547782 | TASK [ensure-go : Get archive checksum] 2026-04-13 20:37:46.179911 | instance | ok: OK (64 bytes) 2026-04-13 20:37:46.186332 | 2026-04-13 20:37:46.186430 | TASK [ensure-go : Download go archive] 2026-04-13 20:37:47.718796 | instance | changed: OK (78559214 bytes) 2026-04-13 20:37:47.725497 | 2026-04-13 20:37:47.725612 | TASK [ensure-go : Install go] 2026-04-13 20:37:53.610998 | instance | changed 2026-04-13 20:37:53.620285 | 2026-04-13 20:37:53.620390 | PLAY [all] 2026-04-13 20:37:53.626177 | 2026-04-13 20:37:53.626276 | TASK [Build atmosphere binary] 2026-04-13 20:37:53.910590 | instance -> localhost | ERROR 2026-04-13 20:37:53.910849 | instance -> localhost | { 2026-04-13 20:37:53.910911 | instance -> localhost | "delta": null, 2026-04-13 20:37:53.910943 | instance -> localhost | "end": null, 2026-04-13 20:37:53.910970 | 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:37:53.910996 | instance -> localhost | "rc": null, 2026-04-13 20:37:53.911021 | instance -> localhost | "start": null 2026-04-13 20:37:53.911046 | instance -> localhost | } failure 2026-04-13 20:37:53.912473 | 2026-04-13 20:37:53.912534 | PLAY RECAP 2026-04-13 20:37:53.912598 | instance | ok: 9 changed: 6 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-04-13 20:37:53.912633 | 2026-04-13 20:37:54.056534 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/pre.yml@main] 2026-04-13 20:37:54.062540 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@main] 2026-04-13 20:37:54.667851 | 2026-04-13 20:37:54.667981 | PLAY [all] 2026-04-13 20:37:54.681953 | 2026-04-13 20:37:54.682079 | TASK [gather-host-logs : creating directory for system status] 2026-04-13 20:37:55.022056 | instance | changed 2026-04-13 20:37:55.027687 | 2026-04-13 20:37:55.027799 | TASK [gather-host-logs : Get logs for each host] 2026-04-13 20:37:55.401722 | instance | + systemd-cgls --full --all --no-pager 2026-04-13 20:37:55.411743 | instance | + ip addr 2026-04-13 20:37:55.413330 | instance | + ip route 2026-04-13 20:37:55.415116 | instance | + lsblk 2026-04-13 20:37:55.417178 | instance | + mount 2026-04-13 20:37:55.418343 | instance | + docker images 2026-04-13 20:37:55.418659 | instance | /bin/bash: line 7: docker: command not found 2026-04-13 20:37:55.418822 | instance | + brctl show 2026-04-13 20:37:55.419111 | instance | /bin/bash: line 8: brctl: command not found 2026-04-13 20:37:55.419280 | instance | + ps aux --sort=-%mem 2026-04-13 20:37:55.429877 | instance | + dpkg -l 2026-04-13 20:37:55.435439 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul)) 2026-04-13 20:37:55.435766 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul 2026-04-13 20:37:55.435811 | instance | /bin/bash: line 11: docker: command not found 2026-04-13 20:37:55.436029 | instance | + '[' '!' -z '' ']' 2026-04-13 20:37:55.565951 | instance | ok: Runtime: 0:00:00.039403 2026-04-13 20:37:55.572671 | 2026-04-13 20:37:55.572781 | TASK [gather-host-logs : Downloads logs to executor] 2026-04-13 20:37:56.235731 | instance | changed: 2026-04-13 20:37:56.235920 | instance | created directory /var/lib/zuul/builds/606598c048ad48d4b7a6bcb6a8ac0607/work/logs/instance 2026-04-13 20:37:56.235967 | instance | cd+++++++++ system/ 2026-04-13 20:37:56.236003 | instance | >f+++++++++ system/brctl-show.txt 2026-04-13 20:37:56.236037 | instance | >f+++++++++ system/docker-images.txt 2026-04-13 20:37:56.236071 | instance | >f+++++++++ system/ip-addr.txt 2026-04-13 20:37:56.236112 | instance | >f+++++++++ system/ip-route.txt 2026-04-13 20:37:56.236151 | instance | >f+++++++++ system/lsblk.txt 2026-04-13 20:37:56.236190 | instance | >f+++++++++ system/mount.txt 2026-04-13 20:37:56.236229 | instance | >f+++++++++ system/packages.txt 2026-04-13 20:37:56.236263 | instance | >f+++++++++ system/ps.txt 2026-04-13 20:37:56.236297 | instance | >f+++++++++ system/systemd-cgls.txt 2026-04-13 20:37:56.250676 | 2026-04-13 20:37:56.250825 | LOOP [helm-release-status : creating directory for helm release status] 2026-04-13 20:37:56.442315 | instance | changed: "values" 2026-04-13 20:37:56.607699 | instance | changed: "releases" 2026-04-13 20:37:56.626459 | 2026-04-13 20:37:56.626590 | TASK [helm-release-status : Gather get release status for helm charts] 2026-04-13 20:37:56.822835 | instance | /bin/bash: line 3: kubectl: command not found 2026-04-13 20:37:57.160596 | instance | ok: Runtime: 0:00:00.005389 2026-04-13 20:37:57.168264 | 2026-04-13 20:37:57.168384 | TASK [helm-release-status : Downloads logs to executor] 2026-04-13 20:37:57.654470 | instance | changed: 2026-04-13 20:37:57.654642 | instance | cd+++++++++ helm/ 2026-04-13 20:37:57.654685 | instance | cd+++++++++ helm/releases/ 2026-04-13 20:37:57.654718 | instance | cd+++++++++ helm/values/ 2026-04-13 20:37:57.670742 | 2026-04-13 20:37:57.670904 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects] 2026-04-13 20:37:57.867089 | instance | changed 2026-04-13 20:37:57.872471 | 2026-04-13 20:37:57.872547 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects] 2026-04-13 20:37:58.077507 | instance | xargs: xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args valuewarning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-13 20:37:58.077614 | instance | 2026-04-13 20:37:58.082500 | instance | environment: line 1: kubectl: command not found 2026-04-13 20:37:58.083426 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-13 20:37:58.085408 | instance | environment: line 1: kubectl: command not found 2026-04-13 20:37:58.086017 | instance | environment: line 1: kubectl: command not found 2026-04-13 20:37:58.086432 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-13 20:37:58.086997 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-13 20:37:58.088603 | instance | environment: line 1: kubectl: command not found 2026-04-13 20:37:58.089267 | instance | environment: line 1: kubectl: command not found 2026-04-13 20:37:58.089506 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-13 20:37:58.090255 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-13 20:37:58.410125 | instance | ok: Runtime: 0:00:00.021755 2026-04-13 20:37:58.417595 | 2026-04-13 20:37:58.417707 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects] 2026-04-13 20:37:58.617245 | instance | changed 2026-04-13 20:37:58.627891 | 2026-04-13 20:37:58.628024 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects] 2026-04-13 20:37:58.886041 | instance | environment: line 5: kubectl: command not found 2026-04-13 20:37:58.886966 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-13 20:37:58.887299 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-13 20:37:58.887391 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-13 20:37:59.203822 | instance | ok: Runtime: 0:00:00.007961 2026-04-13 20:37:59.214074 | 2026-04-13 20:37:59.214317 | TASK [describe-kubernetes-objects : Downloads logs to executor] 2026-04-13 20:37:59.716811 | instance | changed: 2026-04-13 20:37:59.717075 | instance | cd+++++++++ objects/ 2026-04-13 20:37:59.717118 | instance | cd+++++++++ objects/cluster/ 2026-04-13 20:37:59.717150 | instance | cd+++++++++ objects/namespaced/ 2026-04-13 20:37:59.730583 | 2026-04-13 20:37:59.730704 | TASK [gather-pod-logs : creating directory for pod logs] 2026-04-13 20:37:59.928270 | instance | changed 2026-04-13 20:37:59.934834 | 2026-04-13 20:37:59.934929 | TASK [gather-pod-logs : creating directory for failed pod logs] 2026-04-13 20:38:00.137136 | instance | changed 2026-04-13 20:38:00.143109 | 2026-04-13 20:38:00.143216 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)] 2026-04-13 20:38:00.366384 | instance | environment: line 3: kubectl: command not found 2026-04-13 20:38:00.688433 | instance | ok: Runtime: 0:00:00.008386 2026-04-13 20:38:00.695226 | 2026-04-13 20:38:00.695316 | TASK [gather-pod-logs : Downloads pod logs to executor] 2026-04-13 20:38:01.225571 | instance | changed: 2026-04-13 20:38:01.225779 | instance | cd+++++++++ pod-logs/ 2026-04-13 20:38:01.225848 | instance | cd+++++++++ pod-logs/failed-pods/ 2026-04-13 20:38:01.238243 | 2026-04-13 20:38:01.238320 | TASK [gather-prom-metrics : creating directory for helm release descriptions] 2026-04-13 20:38:01.433901 | instance | changed 2026-04-13 20:38:01.439111 | 2026-04-13 20:38:01.439182 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces] 2026-04-13 20:38:01.641540 | instance | /bin/bash: line 2: kubectl: command not found 2026-04-13 20:38:01.973824 | instance | ok: Runtime: 0:00:00.037609 2026-04-13 20:38:01.980821 | 2026-04-13 20:38:01.980996 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr] 2026-04-13 20:38:02.197756 | instance | /bin/bash: line 2: kubectl: command not found 2026-04-13 20:38:02.233089 | instance | ceph-mgr endpoints: 2026-04-13 20:38:02.523504 | instance | ok: Runtime: 0:00:00.041095 2026-04-13 20:38:02.530255 | 2026-04-13 20:38:02.530352 | TASK [gather-prom-metrics : Get metrics from fluentd pods] 2026-04-13 20:38:02.734081 | instance | /bin/bash: line 4: kubectl: command not found 2026-04-13 20:38:03.064133 | instance | ok: Runtime: 0:00:00.031327 2026-04-13 20:38:03.072311 | 2026-04-13 20:38:03.072410 | TASK [gather-prom-metrics : Downloads logs to executor] 2026-04-13 20:38:03.594089 | instance | changed: cd+++++++++ prometheus/ 2026-04-13 20:38:03.607899 | 2026-04-13 20:38:03.608054 | TASK [gather-selenium-data : creating directory for helm release descriptions] 2026-04-13 20:38:03.799741 | instance | changed 2026-04-13 20:38:03.806552 | 2026-04-13 20:38:03.806716 | TASK [gather-selenium-data : Get selenium data] 2026-04-13 20:38:04.012202 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/. 2026-04-13 20:38:04.013628 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory 2026-04-13 20:38:04.342802 | instance | ERROR 2026-04-13 20:38:04.342975 | instance | { 2026-04-13 20:38:04.343007 | instance | "delta": "0:00:00.006022", 2026-04-13 20:38:04.343029 | instance | "end": "2026-04-13 20:38:04.014039", 2026-04-13 20:38:04.343048 | instance | "msg": "non-zero return code", 2026-04-13 20:38:04.343067 | instance | "rc": 1, 2026-04-13 20:38:04.343086 | instance | "start": "2026-04-13 20:38:04.008017" 2026-04-13 20:38:04.343105 | instance | } 2026-04-13 20:38:04.343129 | instance | ERROR: Ignoring Errors 2026-04-13 20:38:04.347993 | 2026-04-13 20:38:04.348058 | TASK [gather-selenium-data : Downloads logs to executor] 2026-04-13 20:38:04.837970 | instance | changed: cd+++++++++ selenium/ 2026-04-13 20:38:04.863617 | 2026-04-13 20:38:04.863718 | PLAY RECAP 2026-04-13 20:38:04.863780 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1 2026-04-13 20:38:04.863806 | 2026-04-13 20:38:04.987054 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@main] 2026-04-13 20:38:04.992276 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-13 20:38:05.604850 | 2026-04-13 20:38:05.605069 | PLAY [all] 2026-04-13 20:38:05.618598 | 2026-04-13 20:38:05.618732 | TASK [fetch-output : Set log path for multiple nodes] 2026-04-13 20:38:05.663370 | instance | skipping: Conditional result was False 2026-04-13 20:38:05.673518 | 2026-04-13 20:38:05.673656 | TASK [fetch-output : Set log path for single node] 2026-04-13 20:38:05.716826 | instance | ok 2026-04-13 20:38:05.724376 | 2026-04-13 20:38:05.724514 | LOOP [fetch-output : Ensure local output dirs] 2026-04-13 20:38:06.157636 | instance -> localhost | ok: "/var/lib/zuul/builds/606598c048ad48d4b7a6bcb6a8ac0607/work/logs" 2026-04-13 20:38:06.390266 | instance -> localhost | changed: "/var/lib/zuul/builds/606598c048ad48d4b7a6bcb6a8ac0607/work/artifacts" 2026-04-13 20:38:06.625397 | instance -> localhost | changed: "/var/lib/zuul/builds/606598c048ad48d4b7a6bcb6a8ac0607/work/docs" 2026-04-13 20:38:06.638780 | 2026-04-13 20:38:06.638923 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-04-13 20:38:07.327246 | instance | changed: .d..t...... ./ 2026-04-13 20:38:07.327601 | instance | changed: All items complete 2026-04-13 20:38:07.327649 | 2026-04-13 20:38:07.770393 | instance | changed: .d..t...... ./ 2026-04-13 20:38:08.226972 | instance | changed: .d..t...... ./ 2026-04-13 20:38:08.246691 | 2026-04-13 20:38:08.246823 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-04-13 20:38:08.816054 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.009204 2026-04-13 20:38:09.078545 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.039976 2026-04-13 20:38:09.091696 | 2026-04-13 20:38:09.091851 | PLAY [all] 2026-04-13 20:38:09.097806 | 2026-04-13 20:38:09.097870 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-04-13 20:38:09.510473 | instance | changed 2026-04-13 20:38:09.519617 | 2026-04-13 20:38:09.519723 | PLAY RECAP 2026-04-13 20:38:09.519792 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-04-13 20:38:09.519831 | 2026-04-13 20:38:09.644184 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-13 20:38:09.650654 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-04-13 20:38:10.260368 | 2026-04-13 20:38:10.650858 | PLAY [localhost] 2026-04-13 20:38:10.668674 | 2026-04-13 20:38:10.668832 | TASK [Generate Zuul manifest] 2026-04-13 20:38:10.687641 | localhost | ok 2026-04-13 20:38:10.705160 | 2026-04-13 20:38:10.705320 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-04-13 20:38:11.133266 | localhost | changed 2026-04-13 20:38:11.150062 | 2026-04-13 20:38:11.150211 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-04-13 20:38:11.280145 | localhost | ok 2026-04-13 20:38:11.290257 | 2026-04-13 20:38:11.290403 | TASK [Upload logs] 2026-04-13 20:38:11.310777 | localhost | ok 2026-04-13 20:38:11.379164 | 2026-04-13 20:38:11.379325 | TASK [Set zuul-log-path fact] 2026-04-13 20:38:11.400208 | localhost | ok 2026-04-13 20:38:11.413846 | 2026-04-13 20:38:11.413962 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-13 20:38:11.446045 | localhost | ok 2026-04-13 20:38:11.452502 | 2026-04-13 20:38:11.452576 | TASK [upload-logs : Create log directories] 2026-04-13 20:38:11.909866 | localhost | changed 2026-04-13 20:38:11.915640 | 2026-04-13 20:38:11.915712 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-04-13 20:38:12.373189 | localhost -> localhost | ok: Runtime: 0:00:00.004361 2026-04-13 20:38:12.379749 | 2026-04-13 20:38:12.379886 | TASK [upload-logs : Upload logs to log server] 2026-04-13 20:38:12.883788 | localhost | Output suppressed because no_log was given 2026-04-13 20:38:12.889077 | 2026-04-13 20:38:12.889166 | LOOP [upload-logs : Compress console log and json output] 2026-04-13 20:38:12.933659 | localhost | skipping: Conditional result was False 2026-04-13 20:38:12.941232 | localhost | skipping: Conditional result was False 2026-04-13 20:38:12.952896 | 2026-04-13 20:38:12.953184 | LOOP [upload-logs : Upload compressed console log and json output] 2026-04-13 20:38:13.002045 | localhost | skipping: Conditional result was False 2026-04-13 20:38:13.002433 | 2026-04-13 20:38:13.006995 | localhost | skipping: Conditional result was False 2026-04-13 20:38:13.018772 | 2026-04-13 20:38:13.018947 | LOOP [upload-logs : Upload console log and json output]