2026-04-13 20:43:38.639436 | Job console starting 2026-04-13 20:43:38.648978 | Updating git repos 2026-04-13 20:43:38.734587 | Cloning repos into workspace 2026-04-13 20:43:38.856910 | Restoring repo states 2026-04-13 20:43:38.911848 | Merging changes 2026-04-13 20:43:40.784030 | Checking out repos 2026-04-13 20:43:40.972889 | Preparing playbooks 2026-04-13 20:43:47.200739 | Running Ansible setup 2026-04-13 20:43:53.117290 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-13 20:43:53.871425 | 2026-04-13 20:43:53.871593 | PLAY [localhost] 2026-04-13 20:43:53.879957 | 2026-04-13 20:43:53.880056 | TASK [Gathering Facts] 2026-04-13 20:43:54.772032 | localhost | ok 2026-04-13 20:43:54.780132 | 2026-04-13 20:43:54.780295 | TASK [Setup log path fact] 2026-04-13 20:43:54.798762 | localhost | ok 2026-04-13 20:43:54.811552 | 2026-04-13 20:43:54.811697 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-13 20:43:54.848229 | localhost | ok 2026-04-13 20:43:54.854628 | 2026-04-13 20:43:54.854712 | TASK [emit-job-header : Print job information] 2026-04-13 20:43:54.905470 | # Job Information 2026-04-13 20:43:54.905681 | Ansible Version: 2.16.16 2026-04-13 20:43:54.905720 | Job: atmosphere-molecule-keycloak 2026-04-13 20:43:54.905746 | Pipeline: check 2026-04-13 20:43:54.905769 | Executor: 0a8996d2b663 2026-04-13 20:43:54.905791 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3818 2026-04-13 20:43:54.905816 | Event ID: 47cd3f80-3779-11f1-9d54-63e76b9c6aba 2026-04-13 20:43:54.908655 | 2026-04-13 20:43:54.908725 | LOOP [emit-job-header : Print node information] 2026-04-13 20:43:55.020865 | localhost | ok: 2026-04-13 20:43:55.021050 | localhost | # Node Information 2026-04-13 20:43:55.021081 | localhost | Inventory Hostname: instance 2026-04-13 20:43:55.021104 | localhost | Hostname: np0000165738 2026-04-13 20:43:55.021124 | localhost | Username: zuul 2026-04-13 20:43:55.021150 | localhost | Distro: Ubuntu 22.04 2026-04-13 20:43:55.021171 | localhost | Provider: yul1 2026-04-13 20:43:55.021191 | localhost | Region: ca-ymq-1 2026-04-13 20:43:55.021211 | localhost | Label: ubuntu-jammy 2026-04-13 20:43:55.021230 | localhost | Product Name: OpenStack Nova 2026-04-13 20:43:55.021249 | localhost | Interface IP: 199.204.45.248 2026-04-13 20:43:55.035781 | 2026-04-13 20:43:55.035914 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-04-13 20:43:55.800646 | localhost -> localhost | changed 2026-04-13 20:43:55.815624 | 2026-04-13 20:43:55.815850 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-04-13 20:43:56.893163 | localhost -> localhost | changed 2026-04-13 20:43:56.903374 | 2026-04-13 20:43:56.903476 | PLAY [all] 2026-04-13 20:43:56.913460 | 2026-04-13 20:43:56.913575 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-04-13 20:43:57.453914 | instance -> localhost | ok 2026-04-13 20:43:57.462399 | 2026-04-13 20:43:57.462519 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-04-13 20:43:57.492134 | instance | ok 2026-04-13 20:43:57.507095 | instance | included: /var/lib/zuul/builds/f37fef0406b1470a94bed2397b938b69/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-04-13 20:43:57.516458 | 2026-04-13 20:43:57.516585 | TASK [add-build-sshkey : Create Temp SSH key] 2026-04-13 20:43:58.668603 | instance -> localhost | Generating public/private rsa key pair. 2026-04-13 20:43:58.669143 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/f37fef0406b1470a94bed2397b938b69/work/f37fef0406b1470a94bed2397b938b69_id_rsa 2026-04-13 20:43:58.669218 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/f37fef0406b1470a94bed2397b938b69/work/f37fef0406b1470a94bed2397b938b69_id_rsa.pub 2026-04-13 20:43:58.669333 | instance -> localhost | The key fingerprint is: 2026-04-13 20:43:58.669381 | instance -> localhost | SHA256:KZ+nV7VFHb7EQ2x4oEQbEY7OKsBN6gW8eiG8QA/Oi70 zuul-build-sshkey 2026-04-13 20:43:58.669441 | instance -> localhost | The key's randomart image is: 2026-04-13 20:43:58.669482 | instance -> localhost | +---[RSA 3072]----+ 2026-04-13 20:43:58.669529 | instance -> localhost | | .*o.+oo| 2026-04-13 20:43:58.669576 | instance -> localhost | | . + +.++o| 2026-04-13 20:43:58.669617 | instance -> localhost | | o o . . + o* | 2026-04-13 20:43:58.669655 | instance -> localhost | |= + * o. o +| 2026-04-13 20:43:58.669692 | instance -> localhost | |.= B o. So . + | 2026-04-13 20:43:58.669727 | instance -> localhost | |o.* + o.. . . | 2026-04-13 20:43:58.669762 | instance -> localhost | |.+.o . .o .. | 2026-04-13 20:43:58.669797 | instance -> localhost | | .. . o. | 2026-04-13 20:43:58.669836 | instance -> localhost | | E .. | 2026-04-13 20:43:58.669871 | instance -> localhost | +----[SHA256]-----+ 2026-04-13 20:43:58.669958 | instance -> localhost | ok: Runtime: 0:00:00.608842 2026-04-13 20:43:58.677219 | 2026-04-13 20:43:58.677310 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-04-13 20:43:58.713510 | instance | ok 2026-04-13 20:43:58.723438 | instance | included: /var/lib/zuul/builds/f37fef0406b1470a94bed2397b938b69/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-04-13 20:43:58.731220 | 2026-04-13 20:43:58.731318 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-04-13 20:43:58.754431 | instance | skipping: Conditional result was False 2026-04-13 20:43:58.765198 | 2026-04-13 20:43:58.765294 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-04-13 20:43:59.259010 | instance | changed 2026-04-13 20:43:59.266873 | 2026-04-13 20:43:59.266979 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-04-13 20:43:59.452628 | instance | ok 2026-04-13 20:43:59.458601 | 2026-04-13 20:43:59.458679 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-04-13 20:43:59.950966 | instance | changed 2026-04-13 20:43:59.987070 | 2026-04-13 20:43:59.987219 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-04-13 20:44:00.436868 | instance | changed 2026-04-13 20:44:00.443114 | 2026-04-13 20:44:00.443188 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-04-13 20:44:00.466683 | instance | skipping: Conditional result was False 2026-04-13 20:44:00.476516 | 2026-04-13 20:44:00.476605 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-04-13 20:44:00.906428 | instance -> localhost | changed 2026-04-13 20:44:00.921034 | 2026-04-13 20:44:00.921158 | TASK [add-build-sshkey : Add back temp key] 2026-04-13 20:44:01.229424 | instance -> localhost | Identity added: /var/lib/zuul/builds/f37fef0406b1470a94bed2397b938b69/work/f37fef0406b1470a94bed2397b938b69_id_rsa (zuul-build-sshkey) 2026-04-13 20:44:01.229604 | instance -> localhost | ok: Runtime: 0:00:00.023904 2026-04-13 20:44:01.234750 | 2026-04-13 20:44:01.234813 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-04-13 20:44:01.590830 | instance | ok 2026-04-13 20:44:01.598577 | 2026-04-13 20:44:01.598710 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-04-13 20:44:01.622365 | instance | skipping: Conditional result was False 2026-04-13 20:44:01.638104 | 2026-04-13 20:44:01.638243 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-04-13 20:44:01.988590 | instance | ok 2026-04-13 20:44:01.996859 | 2026-04-13 20:44:01.996955 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-04-13 20:44:04.284783 | instance | Output suppressed because no_log was given 2026-04-13 20:44:04.295355 | 2026-04-13 20:44:04.295505 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-04-13 20:44:04.478808 | instance | ok: "logs" 2026-04-13 20:44:04.479089 | instance | ok: All items complete 2026-04-13 20:44:04.479134 | 2026-04-13 20:44:04.634389 | instance | ok: "artifacts" 2026-04-13 20:44:04.791238 | instance | ok: "docs" 2026-04-13 20:44:04.809318 | 2026-04-13 20:44:04.809533 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-04-13 20:44:04.992305 | instance | changed: "logs" 2026-04-13 20:44:05.154912 | instance | changed: "artifacts" 2026-04-13 20:44:05.528381 | instance | changed: "docs" 2026-04-13 20:44:05.543659 | 2026-04-13 20:44:05.543741 | PLAY RECAP 2026-04-13 20:44:05.543804 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-04-13 20:44:05.543842 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-13 20:44:05.543863 | 2026-04-13 20:44:05.707456 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-13 20:44:05.714340 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-13 20:44:06.345021 | 2026-04-13 20:44:06.345196 | PLAY [all] 2026-04-13 20:44:06.364251 | 2026-04-13 20:44:06.364416 | TASK [setup-uv : Extract archive] 2026-04-13 20:44:08.595648 | instance | changed 2026-04-13 20:44:08.604583 | 2026-04-13 20:44:08.604754 | TASK [setup-uv : Print version] 2026-04-13 20:44:08.970891 | instance | uv 0.8.13 2026-04-13 20:44:09.377206 | instance | ok: Runtime: 0:00:00.014297 2026-04-13 20:44:09.386124 | 2026-04-13 20:44:09.386197 | PLAY RECAP 2026-04-13 20:44:09.386245 | instance | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-13 20:44:09.386270 | 2026-04-13 20:44:09.510014 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-13 20:44:09.512516 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/pre.yml@main] 2026-04-13 20:44:10.181467 | 2026-04-13 20:44:10.181606 | PLAY [all] 2026-04-13 20:44:10.193625 | 2026-04-13 20:44:10.193744 | TASK [Install "jq" for log collection] 2026-04-13 20:44:23.648700 | instance | changed 2026-04-13 20:44:23.658985 | 2026-04-13 20:44:23.659229 | TASK [Install pip3 for Python package management] 2026-04-13 20:44:28.823801 | instance | changed 2026-04-13 20:44:28.831596 | 2026-04-13 20:44:28.831685 | TASK [Install Python "kubernetes" library for kubernetes.core modules] 2026-04-13 20:44:32.020201 | instance | changed 2026-04-13 20:44:32.023689 | 2026-04-13 20:44:32.023771 | PLAY [all] 2026-04-13 20:44:32.032031 | 2026-04-13 20:44:32.032221 | TASK [ensure-go : Check installed go version] 2026-04-13 20:44:32.575310 | instance | ok: ERROR (ignored) 2026-04-13 20:44:32.575544 | instance | { 2026-04-13 20:44:32.575591 | instance | "failed_when_result": false, 2026-04-13 20:44:32.575623 | instance | "msg": "[Errno 2] No such file or directory: b'go'", 2026-04-13 20:44:32.575656 | instance | "rc": 2 2026-04-13 20:44:32.575688 | instance | } 2026-04-13 20:44:32.582988 | 2026-04-13 20:44:32.583098 | TASK [ensure-go : Skip if correct version of go is installed] 2026-04-13 20:44:32.645957 | instance | ok 2026-04-13 20:44:32.656460 | instance | included: /var/lib/zuul/builds/f37fef0406b1470a94bed2397b938b69/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/ensure-go/tasks/install-go.yaml 2026-04-13 20:44:32.663679 | 2026-04-13 20:44:32.663786 | TASK [ensure-go : Create temp directory] 2026-04-13 20:44:32.970185 | instance | changed 2026-04-13 20:44:32.976706 | 2026-04-13 20:44:32.976815 | TASK [ensure-go : Get archive checksum] 2026-04-13 20:44:33.665399 | instance | ok: OK (64 bytes) 2026-04-13 20:44:33.675113 | 2026-04-13 20:44:33.675178 | TASK [ensure-go : Download go archive] 2026-04-13 20:44:35.092076 | instance | changed: OK (78559214 bytes) 2026-04-13 20:44:35.098297 | 2026-04-13 20:44:35.098368 | TASK [ensure-go : Install go] 2026-04-13 20:44:40.771604 | instance | changed 2026-04-13 20:44:40.779807 | 2026-04-13 20:44:40.779920 | PLAY [all] 2026-04-13 20:44:40.785658 | 2026-04-13 20:44:40.785761 | TASK [Build atmosphere binary] 2026-04-13 20:44:41.055330 | instance -> localhost | ERROR 2026-04-13 20:44:41.055583 | instance -> localhost | { 2026-04-13 20:44:41.055642 | instance -> localhost | "delta": null, 2026-04-13 20:44:41.055674 | instance -> localhost | "end": null, 2026-04-13 20:44:41.055707 | 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:44:41.055745 | instance -> localhost | "rc": null, 2026-04-13 20:44:41.055774 | instance -> localhost | "start": null 2026-04-13 20:44:41.055800 | instance -> localhost | } failure 2026-04-13 20:44:41.057469 | 2026-04-13 20:44:41.057531 | PLAY RECAP 2026-04-13 20:44:41.057594 | instance | ok: 9 changed: 6 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-04-13 20:44:41.057626 | 2026-04-13 20:44:41.182354 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/pre.yml@main] 2026-04-13 20:44:41.185723 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@main] 2026-04-13 20:44:41.837039 | 2026-04-13 20:44:41.837175 | PLAY [all] 2026-04-13 20:44:41.852334 | 2026-04-13 20:44:41.852451 | TASK [gather-host-logs : creating directory for system status] 2026-04-13 20:44:42.211362 | instance | changed 2026-04-13 20:44:42.218866 | 2026-04-13 20:44:42.218993 | TASK [gather-host-logs : Get logs for each host] 2026-04-13 20:44:42.594118 | instance | + systemd-cgls --full --all --no-pager 2026-04-13 20:44:42.606695 | instance | + ip addr 2026-04-13 20:44:42.609854 | instance | + ip route 2026-04-13 20:44:42.612218 | instance | + lsblk 2026-04-13 20:44:42.615004 | instance | + mount 2026-04-13 20:44:42.618169 | instance | + docker images 2026-04-13 20:44:42.618640 | instance | /bin/bash: line 7: docker: command not found 2026-04-13 20:44:42.618924 | instance | + brctl show 2026-04-13 20:44:42.619362 | instance | /bin/bash: line 8: brctl: command not found 2026-04-13 20:44:42.619579 | instance | + ps aux --sort=-%mem 2026-04-13 20:44:42.630651 | instance | + dpkg -l 2026-04-13 20:44:42.641373 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul)) 2026-04-13 20:44:42.641761 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul 2026-04-13 20:44:42.641794 | instance | /bin/bash: line 11: docker: command not found 2026-04-13 20:44:42.642093 | instance | + '[' '!' -z '' ']' 2026-04-13 20:44:42.762818 | instance | ok: Runtime: 0:00:00.053274 2026-04-13 20:44:42.772259 | 2026-04-13 20:44:42.772378 | TASK [gather-host-logs : Downloads logs to executor] 2026-04-13 20:44:43.615797 | instance | changed: 2026-04-13 20:44:43.616082 | instance | created directory /var/lib/zuul/builds/f37fef0406b1470a94bed2397b938b69/work/logs/instance 2026-04-13 20:44:43.616142 | instance | cd+++++++++ system/ 2026-04-13 20:44:43.616178 | instance | >f+++++++++ system/brctl-show.txt 2026-04-13 20:44:43.616208 | instance | >f+++++++++ system/docker-images.txt 2026-04-13 20:44:43.616237 | instance | >f+++++++++ system/ip-addr.txt 2026-04-13 20:44:43.616269 | instance | >f+++++++++ system/ip-route.txt 2026-04-13 20:44:43.616299 | instance | >f+++++++++ system/lsblk.txt 2026-04-13 20:44:43.616329 | instance | >f+++++++++ system/mount.txt 2026-04-13 20:44:43.616361 | instance | >f+++++++++ system/packages.txt 2026-04-13 20:44:43.616391 | instance | >f+++++++++ system/ps.txt 2026-04-13 20:44:43.616419 | instance | >f+++++++++ system/systemd-cgls.txt 2026-04-13 20:44:43.628686 | 2026-04-13 20:44:43.628806 | LOOP [helm-release-status : creating directory for helm release status] 2026-04-13 20:44:43.819628 | instance | changed: "values" 2026-04-13 20:44:43.990428 | instance | changed: "releases" 2026-04-13 20:44:44.000076 | 2026-04-13 20:44:44.000214 | TASK [helm-release-status : Gather get release status for helm charts] 2026-04-13 20:44:44.203491 | instance | /bin/bash: line 3: kubectl: command not found 2026-04-13 20:44:44.541681 | instance | ok: Runtime: 0:00:00.007827 2026-04-13 20:44:44.549739 | 2026-04-13 20:44:44.549896 | TASK [helm-release-status : Downloads logs to executor] 2026-04-13 20:44:45.115951 | instance | changed: 2026-04-13 20:44:45.116130 | instance | cd+++++++++ helm/ 2026-04-13 20:44:45.116172 | instance | cd+++++++++ helm/releases/ 2026-04-13 20:44:45.116202 | instance | cd+++++++++ helm/values/ 2026-04-13 20:44:45.145183 | 2026-04-13 20:44:45.208191 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects] 2026-04-13 20:44:45.400633 | instance | changed 2026-04-13 20:44:45.405524 | 2026-04-13 20:44:45.405698 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects] 2026-04-13 20:44:45.611866 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-13 20:44:45.612008 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-13 20:44:45.617088 | instance | environment: line 1: kubectl: command not found 2026-04-13 20:44:45.618336 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-13 20:44:45.619443 | instance | environment: line 1: kubectl: command not found 2026-04-13 20:44:45.620944 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-13 20:44:45.621977 | instance | environment: line 1: kubectl: command not found 2026-04-13 20:44:45.623015 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-13 20:44:45.624574 | instance | environment: line 1: kubectl: command not found 2026-04-13 20:44:45.625301 | instance | environment: line 1: kubectl: command not found 2026-04-13 20:44:45.625915 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-13 20:44:45.627096 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-13 20:44:45.958154 | instance | ok: Runtime: 0:00:00.026237 2026-04-13 20:44:45.963135 | 2026-04-13 20:44:45.963213 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects] 2026-04-13 20:44:46.158215 | instance | changed 2026-04-13 20:44:46.163942 | 2026-04-13 20:44:46.164013 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects] 2026-04-13 20:44:46.423358 | instance | environment: line 5: kubectl: command not found 2026-04-13 20:44:46.424223 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-13 20:44:46.424367 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-13 20:44:46.424508 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-13 20:44:46.734937 | instance | ok: Runtime: 0:00:00.011276 2026-04-13 20:44:46.740295 | 2026-04-13 20:44:46.740451 | TASK [describe-kubernetes-objects : Downloads logs to executor] 2026-04-13 20:44:48.122690 | instance | changed: 2026-04-13 20:44:48.122861 | instance | cd+++++++++ objects/ 2026-04-13 20:44:48.122903 | instance | cd+++++++++ objects/cluster/ 2026-04-13 20:44:48.122937 | instance | cd+++++++++ objects/namespaced/ 2026-04-13 20:44:48.141080 | 2026-04-13 20:44:48.141288 | TASK [gather-pod-logs : creating directory for pod logs] 2026-04-13 20:44:48.336721 | instance | changed 2026-04-13 20:44:48.345473 | 2026-04-13 20:44:48.345630 | TASK [gather-pod-logs : creating directory for failed pod logs] 2026-04-13 20:44:48.546962 | instance | changed 2026-04-13 20:44:49.582698 | 2026-04-13 20:44:49.582871 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)] 2026-04-13 20:44:49.808417 | instance | environment: line 3: kubectl: command not found 2026-04-13 20:44:50.116750 | instance | ok: Runtime: 0:00:00.010358 2026-04-13 20:44:50.121958 | 2026-04-13 20:44:50.122044 | TASK [gather-pod-logs : Downloads pod logs to executor] 2026-04-13 20:44:50.935653 | instance | changed: 2026-04-13 20:44:50.935869 | instance | cd+++++++++ pod-logs/ 2026-04-13 20:44:50.935901 | instance | cd+++++++++ pod-logs/failed-pods/ 2026-04-13 20:44:50.949562 | 2026-04-13 20:44:50.949704 | TASK [gather-prom-metrics : creating directory for helm release descriptions] 2026-04-13 20:44:51.144239 | instance | changed 2026-04-13 20:44:51.155117 | 2026-04-13 20:44:51.155276 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces] 2026-04-13 20:44:51.360322 | instance | /bin/bash: line 2: kubectl: command not found 2026-04-13 20:44:51.696443 | instance | ok: Runtime: 0:00:00.038833 2026-04-13 20:44:51.701363 | 2026-04-13 20:44:51.701480 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr] 2026-04-13 20:44:51.908241 | instance | /bin/bash: line 2: kubectl: command not found 2026-04-13 20:44:51.936450 | instance | ceph-mgr endpoints: 2026-04-13 20:44:52.235620 | instance | ok: Runtime: 0:00:00.035660 2026-04-13 20:44:52.243083 | 2026-04-13 20:44:52.243219 | TASK [gather-prom-metrics : Get metrics from fluentd pods] 2026-04-13 20:44:52.443111 | instance | /bin/bash: line 4: kubectl: command not found 2026-04-13 20:44:52.779026 | instance | ok: Runtime: 0:00:00.038091 2026-04-13 20:44:52.786078 | 2026-04-13 20:44:52.786160 | TASK [gather-prom-metrics : Downloads logs to executor] 2026-04-13 20:44:53.317528 | instance | changed: cd+++++++++ prometheus/ 2026-04-13 20:44:53.330818 | 2026-04-13 20:44:53.330947 | TASK [gather-selenium-data : creating directory for helm release descriptions] 2026-04-13 20:44:53.539504 | instance | changed 2026-04-13 20:44:53.545538 | 2026-04-13 20:44:53.545664 | TASK [gather-selenium-data : Get selenium data] 2026-04-13 20:44:53.759374 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/. 2026-04-13 20:44:53.760996 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory 2026-04-13 20:44:54.202845 | instance | ERROR 2026-04-13 20:44:54.203063 | instance | { 2026-04-13 20:44:54.203111 | instance | "delta": "0:00:00.006482", 2026-04-13 20:44:54.203145 | instance | "end": "2026-04-13 20:44:53.761385", 2026-04-13 20:44:54.203175 | instance | "msg": "non-zero return code", 2026-04-13 20:44:54.203204 | instance | "rc": 1, 2026-04-13 20:44:54.203232 | instance | "start": "2026-04-13 20:44:53.754903" 2026-04-13 20:44:54.203274 | instance | } 2026-04-13 20:44:54.203350 | instance | ERROR: Ignoring Errors 2026-04-13 20:44:54.211244 | 2026-04-13 20:44:54.211327 | TASK [gather-selenium-data : Downloads logs to executor] 2026-04-13 20:44:54.703716 | instance | changed: cd+++++++++ selenium/ 2026-04-13 20:44:54.711877 | 2026-04-13 20:44:54.711951 | PLAY RECAP 2026-04-13 20:44:54.711998 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1 2026-04-13 20:44:54.712022 | 2026-04-13 20:44:54.853627 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@main] 2026-04-13 20:44:54.859751 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-13 20:44:55.619245 | 2026-04-13 20:44:55.619407 | PLAY [all] 2026-04-13 20:44:55.636118 | 2026-04-13 20:44:55.636285 | TASK [fetch-output : Set log path for multiple nodes] 2026-04-13 20:44:55.681485 | instance | skipping: Conditional result was False 2026-04-13 20:44:55.692579 | 2026-04-13 20:44:55.692728 | TASK [fetch-output : Set log path for single node] 2026-04-13 20:44:55.735055 | instance | ok 2026-04-13 20:44:55.741785 | 2026-04-13 20:44:55.741935 | LOOP [fetch-output : Ensure local output dirs] 2026-04-13 20:44:56.340739 | instance -> localhost | ok: "/var/lib/zuul/builds/f37fef0406b1470a94bed2397b938b69/work/logs" 2026-04-13 20:44:56.555435 | instance -> localhost | changed: "/var/lib/zuul/builds/f37fef0406b1470a94bed2397b938b69/work/artifacts" 2026-04-13 20:44:56.803327 | instance -> localhost | changed: "/var/lib/zuul/builds/f37fef0406b1470a94bed2397b938b69/work/docs" 2026-04-13 20:44:56.813621 | 2026-04-13 20:44:56.813731 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-04-13 20:44:57.476401 | instance | changed: .d..t...... ./ 2026-04-13 20:44:57.476730 | instance | changed: All items complete 2026-04-13 20:44:57.476797 | 2026-04-13 20:44:57.916301 | instance | changed: .d..t...... ./ 2026-04-13 20:44:58.629682 | instance | changed: .d..t...... ./ 2026-04-13 20:44:58.649579 | 2026-04-13 20:44:58.649721 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-04-13 20:44:59.123005 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.026568 2026-04-13 20:44:59.381140 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.007847 2026-04-13 20:44:59.400228 | 2026-04-13 20:44:59.400367 | PLAY [all] 2026-04-13 20:44:59.410721 | 2026-04-13 20:44:59.410837 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-04-13 20:44:59.825350 | instance | changed 2026-04-13 20:44:59.830945 | 2026-04-13 20:44:59.831000 | PLAY RECAP 2026-04-13 20:44:59.831047 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-04-13 20:44:59.831070 | 2026-04-13 20:44:59.954364 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-13 20:44:59.960812 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-04-13 20:45:00.581443 | 2026-04-13 20:45:00.581592 | PLAY [localhost] 2026-04-13 20:45:00.600450 | 2026-04-13 20:45:00.600759 | TASK [Generate Zuul manifest] 2026-04-13 20:45:00.626650 | localhost | ok 2026-04-13 20:45:00.646297 | 2026-04-13 20:45:00.646472 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-04-13 20:45:01.282478 | localhost | changed 2026-04-13 20:45:01.296248 | 2026-04-13 20:45:01.296420 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-04-13 20:45:01.330649 | localhost | ok 2026-04-13 20:45:01.343860 | 2026-04-13 20:45:01.344035 | TASK [Upload logs] 2026-04-13 20:45:01.364024 | localhost | ok 2026-04-13 20:45:01.453196 | 2026-04-13 20:45:01.453356 | TASK [Set zuul-log-path fact] 2026-04-13 20:45:01.472909 | localhost | ok 2026-04-13 20:45:01.489086 | 2026-04-13 20:45:01.489241 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-13 20:45:01.518977 | localhost | ok 2026-04-13 20:45:01.528137 | 2026-04-13 20:45:01.528257 | TASK [upload-logs : Create log directories] 2026-04-13 20:45:01.964712 | localhost | changed 2026-04-13 20:45:01.971218 | 2026-04-13 20:45:01.971337 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-04-13 20:45:02.369155 | localhost -> localhost | ok: Runtime: 0:00:00.004772 2026-04-13 20:45:02.377247 | 2026-04-13 20:45:02.377405 | TASK [upload-logs : Upload logs to log server] 2026-04-13 20:45:02.863918 | localhost | Output suppressed because no_log was given 2026-04-13 20:45:02.868850 | 2026-04-13 20:45:02.868945 | LOOP [upload-logs : Compress console log and json output] 2026-04-13 20:45:02.910252 | localhost | skipping: Conditional result was False 2026-04-13 20:45:02.918101 | localhost | skipping: Conditional result was False 2026-04-13 20:45:02.931720 | 2026-04-13 20:45:02.931870 | LOOP [upload-logs : Upload compressed console log and json output] 2026-04-13 20:45:02.974285 | localhost | skipping: Conditional result was False 2026-04-13 20:45:02.974669 | 2026-04-13 20:45:02.979492 | localhost | skipping: Conditional result was False 2026-04-13 20:45:02.985289 | 2026-04-13 20:45:02.985499 | LOOP [upload-logs : Upload console log and json output]