2026-04-13 20:41:16.845362 | Job console starting
2026-04-13 20:41:16.861410 | Updating git repos
2026-04-13 20:41:17.788793 | Cloning repos into workspace
2026-04-13 20:41:17.968423 | Restoring repo states
2026-04-13 20:41:17.991040 | Merging changes
2026-04-13 20:41:19.574315 | Checking out repos
2026-04-13 20:41:19.680277 | Preparing playbooks
2026-04-13 20:41:22.447421 | Running Ansible setup
2026-04-13 20:41:25.917883 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-13 20:41:26.578354 | 
2026-04-13 20:41:26.578509 | PLAY [localhost]
2026-04-13 20:41:26.588763 | 
2026-04-13 20:41:26.588880 | TASK [Gathering Facts]
2026-04-13 20:41:28.063855 | localhost | ok
2026-04-13 20:41:28.075915 | 
2026-04-13 20:41:28.076063 | TASK [Setup log path fact]
2026-04-13 20:41:28.096348 | localhost | ok
2026-04-13 20:41:28.115155 | 
2026-04-13 20:41:28.115390 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-13 20:41:28.149256 | localhost | ok
2026-04-13 20:41:28.156482 | 
2026-04-13 20:41:28.156561 | TASK [emit-job-header : Print job information]
2026-04-13 20:41:28.200087 | # Job Information
2026-04-13 20:41:28.200354 | Ansible Version: 2.16.16
2026-04-13 20:41:28.200407 | Job: atmosphere-molecule-keycloak
2026-04-13 20:41:28.200441 | Pipeline: check
2026-04-13 20:41:28.200471 | Executor: 0a8996d2b663
2026-04-13 20:41:28.200500 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3818
2026-04-13 20:41:28.200533 | Event ID: af35ae10-3778-11f1-8810-704e89e38670
2026-04-13 20:41:28.204780 | 
2026-04-13 20:41:28.204920 | LOOP [emit-job-header : Print node information]
2026-04-13 20:41:28.315049 | localhost | ok:
2026-04-13 20:41:28.315611 | localhost | # Node Information
2026-04-13 20:41:28.315664 | localhost | Inventory Hostname: instance
2026-04-13 20:41:28.315697 | localhost | Hostname: np0000165711
2026-04-13 20:41:28.315725 | localhost | Username: zuul
2026-04-13 20:41:28.315755 | localhost | Distro: Ubuntu 22.04
2026-04-13 20:41:28.315784 | localhost | Provider: yul1
2026-04-13 20:41:28.315811 | localhost | Region: ca-ymq-1
2026-04-13 20:41:28.315837 | localhost | Label: ubuntu-jammy
2026-04-13 20:41:28.315862 | localhost | Product Name: OpenStack Nova
2026-04-13 20:41:28.315887 | localhost | Interface IP: 199.204.45.25
2026-04-13 20:41:28.337339 | 
2026-04-13 20:41:28.337542 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-04-13 20:41:28.720920 | localhost -> localhost | changed
2026-04-13 20:41:28.732237 | 
2026-04-13 20:41:28.732360 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-04-13 20:41:29.651914 | localhost -> localhost | changed
2026-04-13 20:41:29.661662 | 
2026-04-13 20:41:29.661721 | PLAY [all]
2026-04-13 20:41:29.669292 | 
2026-04-13 20:41:29.669364 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-04-13 20:41:29.905765 | instance -> localhost | ok
2026-04-13 20:41:29.917441 | 
2026-04-13 20:41:29.917553 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-04-13 20:41:29.948240 | instance | ok
2026-04-13 20:41:29.965962 | instance | included: /var/lib/zuul/builds/041bbd06ba7348eb801f16d0291a3f78/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-04-13 20:41:29.972667 | 
2026-04-13 20:41:29.972726 | TASK [add-build-sshkey : Create Temp SSH key]
2026-04-13 20:41:31.329233 | instance -> localhost | Generating public/private rsa key pair.
2026-04-13 20:41:31.329442 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/041bbd06ba7348eb801f16d0291a3f78/work/041bbd06ba7348eb801f16d0291a3f78_id_rsa
2026-04-13 20:41:31.329483 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/041bbd06ba7348eb801f16d0291a3f78/work/041bbd06ba7348eb801f16d0291a3f78_id_rsa.pub
2026-04-13 20:41:31.329515 | instance -> localhost | The key fingerprint is:
2026-04-13 20:41:31.329545 | instance -> localhost | SHA256:xjTM86r4mkro0jU8ptR+4oTSwmhU+ForMBb2lHLXezs zuul-build-sshkey
2026-04-13 20:41:31.329592 | instance -> localhost | The key's randomart image is:
2026-04-13 20:41:31.329622 | instance -> localhost | +---[RSA 3072]----+
2026-04-13 20:41:31.329651 | instance -> localhost | |                 |
2026-04-13 20:41:31.329681 | instance -> localhost | |  . . .o         |
2026-04-13 20:41:31.329709 | instance -> localhost | | = = . .*        |
2026-04-13 20:41:31.329737 | instance -> localhost | |. O .  o.+       |
2026-04-13 20:41:31.329764 | instance -> localhost | |oo *   .S..      |
2026-04-13 20:41:31.329792 | instance -> localhost | |*++.O  ....      |
2026-04-13 20:41:31.329819 | instance -> localhost | |=B+*.o  .E       |
2026-04-13 20:41:31.329846 | instance -> localhost | |+++.oo..  .      |
2026-04-13 20:41:31.329876 | instance -> localhost | |...o==o          |
2026-04-13 20:41:31.329903 | instance -> localhost | +----[SHA256]-----+
2026-04-13 20:41:31.329970 | instance -> localhost | ok: Runtime: 0:00:00.956400
2026-04-13 20:41:31.335624 | 
2026-04-13 20:41:31.335716 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-04-13 20:41:31.369284 | instance | ok
2026-04-13 20:41:31.381416 | instance | included: /var/lib/zuul/builds/041bbd06ba7348eb801f16d0291a3f78/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-04-13 20:41:31.389778 | 
2026-04-13 20:41:31.389902 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-04-13 20:41:31.413621 | instance | skipping: Conditional result was False
2026-04-13 20:41:31.424948 | 
2026-04-13 20:41:31.425136 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-04-13 20:41:31.883698 | instance | changed
2026-04-13 20:41:31.891898 | 
2026-04-13 20:41:31.892060 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-04-13 20:41:32.088597 | instance | ok
2026-04-13 20:41:32.097897 | 
2026-04-13 20:41:32.098072 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-04-13 20:41:32.546660 | instance | changed
2026-04-13 20:41:32.552849 | 
2026-04-13 20:41:32.553004 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-04-13 20:41:32.993064 | instance | changed
2026-04-13 20:41:32.999438 | 
2026-04-13 20:41:32.999504 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-04-13 20:41:33.022627 | instance | skipping: Conditional result was False
2026-04-13 20:41:33.031283 | 
2026-04-13 20:41:33.031388 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-04-13 20:41:33.394613 | instance -> localhost | changed
2026-04-13 20:41:33.410703 | 
2026-04-13 20:41:33.410838 | TASK [add-build-sshkey : Add back temp key]
2026-04-13 20:41:33.707753 | instance -> localhost | Identity added: /var/lib/zuul/builds/041bbd06ba7348eb801f16d0291a3f78/work/041bbd06ba7348eb801f16d0291a3f78_id_rsa (zuul-build-sshkey)
2026-04-13 20:41:33.707995 | instance -> localhost | ok: Runtime: 0:00:00.015105
2026-04-13 20:41:33.713591 | 
2026-04-13 20:41:33.713672 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-04-13 20:41:34.003264 | instance | ok
2026-04-13 20:41:34.009742 | 
2026-04-13 20:41:34.009854 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-04-13 20:41:34.034070 | instance | skipping: Conditional result was False
2026-04-13 20:41:34.048848 | 
2026-04-13 20:41:34.049007 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-04-13 20:41:34.356555 | instance | ok
2026-04-13 20:41:34.362423 | 
2026-04-13 20:41:34.362488 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-04-13 20:41:36.023084 | instance | Output suppressed because no_log was given
2026-04-13 20:41:36.034891 | 
2026-04-13 20:41:36.035000 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-04-13 20:41:36.233506 | instance | ok: "logs"
2026-04-13 20:41:36.233735 | instance | ok: All items complete
2026-04-13 20:41:36.233766 | 
2026-04-13 20:41:36.390478 | instance | ok: "artifacts"
2026-04-13 20:41:36.553951 | instance | ok: "docs"
2026-04-13 20:41:36.569259 | 
2026-04-13 20:41:36.569433 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-04-13 20:41:36.762678 | instance | changed: "logs"
2026-04-13 20:41:36.917022 | instance | changed: "artifacts"
2026-04-13 20:41:37.069922 | instance | changed: "docs"
2026-04-13 20:41:37.082534 | 
2026-04-13 20:41:37.082648 | PLAY RECAP
2026-04-13 20:41:37.082708 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-04-13 20:41:37.082747 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-13 20:41:37.082777 | 
2026-04-13 20:41:37.204703 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-13 20:41:37.210417 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-13 20:41:37.774516 | 
2026-04-13 20:41:37.774651 | PLAY [all]
2026-04-13 20:41:37.787459 | 
2026-04-13 20:41:37.787598 | TASK [setup-uv : Extract archive]
2026-04-13 20:41:40.017691 | instance | changed
2026-04-13 20:41:40.023830 | 
2026-04-13 20:41:40.023948 | TASK [setup-uv : Print version]
2026-04-13 20:41:39.547858 | instance | uv 0.8.13
2026-04-13 20:41:40.557724 | instance | ok: Runtime: 0:00:00.011567
2026-04-13 20:41:40.565581 | 
2026-04-13 20:41:40.565672 | PLAY RECAP
2026-04-13 20:41:40.565737 | instance | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-13 20:41:40.565773 | 
2026-04-13 20:41:40.669956 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-13 20:41:40.673238 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/pre.yml@main]
2026-04-13 20:41:41.298560 | 
2026-04-13 20:41:41.298719 | PLAY [all]
2026-04-13 20:41:41.311888 | 
2026-04-13 20:41:41.312021 | TASK [Install "jq" for log collection]
2026-04-13 20:41:51.028520 | instance | changed
2026-04-13 20:41:51.043148 | 
2026-04-13 20:41:51.043247 | TASK [Install pip3 for Python package management]
2026-04-13 20:41:55.280234 | instance | changed
2026-04-13 20:41:55.287383 | 
2026-04-13 20:41:55.287473 | TASK [Install Python "kubernetes" library for kubernetes.core modules]
2026-04-13 20:41:58.675670 | instance | changed
2026-04-13 20:41:59.130713 | 
2026-04-13 20:41:59.130912 | PLAY [all]
2026-04-13 20:41:59.142057 | 
2026-04-13 20:41:59.142154 | TASK [ensure-go : Check installed go version]
2026-04-13 20:41:59.678942 | instance | ok: ERROR (ignored)
2026-04-13 20:41:59.742984 | instance | {
2026-04-13 20:41:59.743155 | instance |   "failed_when_result": false,
2026-04-13 20:41:59.743213 | instance |   "msg": "[Errno 2] No such file or directory: b'go'",
2026-04-13 20:41:59.743266 | instance |   "rc": 2
2026-04-13 20:41:59.743321 | instance | }
2026-04-13 20:41:59.753108 | 
2026-04-13 20:41:59.753287 | TASK [ensure-go : Skip if correct version of go is installed]
2026-04-13 20:41:59.804773 | instance | ok
2026-04-13 20:41:59.815987 | instance | included: /var/lib/zuul/builds/041bbd06ba7348eb801f16d0291a3f78/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/ensure-go/tasks/install-go.yaml
2026-04-13 20:41:59.825041 | 
2026-04-13 20:41:59.825160 | TASK [ensure-go : Create temp directory]
2026-04-13 20:42:00.128183 | instance | changed
2026-04-13 20:42:00.424357 | 
2026-04-13 20:42:00.424475 | TASK [ensure-go : Get archive checksum]
2026-04-13 20:42:01.028441 | instance | ok: OK (64 bytes)
2026-04-13 20:42:01.034461 | 
2026-04-13 20:42:01.034537 | TASK [ensure-go : Download go archive]
2026-04-13 20:42:02.536551 | instance | changed: OK (78559214 bytes)
2026-04-13 20:42:02.543002 | 
2026-04-13 20:42:02.543068 | TASK [ensure-go : Install go]
2026-04-13 20:42:08.390764 | instance | changed
2026-04-13 20:42:08.397920 | 
2026-04-13 20:42:08.398169 | PLAY [all]
2026-04-13 20:42:08.405899 | 
2026-04-13 20:42:08.406032 | TASK [Build atmosphere binary]
2026-04-13 20:42:09.153328 | instance -> localhost | ERROR
2026-04-13 20:42:09.153603 | instance -> localhost | {
2026-04-13 20:42:09.153654 | instance -> localhost |   "delta": null,
2026-04-13 20:42:09.153676 | instance -> localhost |   "end": null,
2026-04-13 20:42:09.153695 | 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:42:09.153714 | instance -> localhost |   "rc": null,
2026-04-13 20:42:09.153731 | instance -> localhost |   "start": null
2026-04-13 20:42:09.153748 | instance -> localhost | }
failure
2026-04-13 20:42:09.155325 | 
2026-04-13 20:42:09.155373 | PLAY RECAP
2026-04-13 20:42:09.155423 | instance | ok: 9 changed: 6 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-04-13 20:42:09.155447 | 
2026-04-13 20:42:09.375514 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/pre.yml@main]
2026-04-13 20:42:09.381694 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@main]
2026-04-13 20:42:10.046493 | 
2026-04-13 20:42:10.046991 | PLAY [all]
2026-04-13 20:42:10.061114 | 
2026-04-13 20:42:10.061214 | TASK [gather-host-logs : creating directory for system status]
2026-04-13 20:42:10.427069 | instance | changed
2026-04-13 20:42:10.436271 | 
2026-04-13 20:42:10.436517 | TASK [gather-host-logs : Get logs for each host]
2026-04-13 20:42:10.816842 | instance | + systemd-cgls --full --all --no-pager
2026-04-13 20:42:10.831069 | instance | + ip addr
2026-04-13 20:42:10.832676 | instance | + ip route
2026-04-13 20:42:10.833819 | instance | + lsblk
2026-04-13 20:42:10.835997 | instance | + mount
2026-04-13 20:42:10.837256 | instance | + docker images
2026-04-13 20:42:10.837632 | instance | /bin/bash: line 7: docker: command not found
2026-04-13 20:42:10.837819 | instance | + brctl show
2026-04-13 20:42:10.838164 | instance | /bin/bash: line 8: brctl: command not found
2026-04-13 20:42:10.838341 | instance | + ps aux --sort=-%mem
2026-04-13 20:42:10.846645 | instance | + dpkg -l
2026-04-13 20:42:10.852144 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul))
2026-04-13 20:42:10.852649 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul
2026-04-13 20:42:10.852666 | instance | /bin/bash: line 11: docker: command not found
2026-04-13 20:42:10.852995 | instance | + '[' '!' -z '' ']'
2026-04-13 20:42:11.093749 | instance | ok: Runtime: 0:00:00.042202
2026-04-13 20:42:11.101786 | 
2026-04-13 20:42:11.101907 | TASK [gather-host-logs : Downloads logs to executor]
2026-04-13 20:42:11.817933 | instance | changed:
2026-04-13 20:42:11.818298 | instance | created directory /var/lib/zuul/builds/041bbd06ba7348eb801f16d0291a3f78/work/logs/instance
2026-04-13 20:42:11.818426 | instance | cd+++++++++ system/
2026-04-13 20:42:11.818478 | instance | >f+++++++++ system/brctl-show.txt
2026-04-13 20:42:11.818514 | instance | >f+++++++++ system/docker-images.txt
2026-04-13 20:42:11.818544 | instance | >f+++++++++ system/ip-addr.txt
2026-04-13 20:42:11.818582 | instance | >f+++++++++ system/ip-route.txt
2026-04-13 20:42:11.818617 | instance | >f+++++++++ system/lsblk.txt
2026-04-13 20:42:11.818648 | instance | >f+++++++++ system/mount.txt
2026-04-13 20:42:11.818681 | instance | >f+++++++++ system/packages.txt
2026-04-13 20:42:11.818710 | instance | >f+++++++++ system/ps.txt
2026-04-13 20:42:11.818738 | instance | >f+++++++++ system/systemd-cgls.txt
2026-04-13 20:42:11.835582 | 
2026-04-13 20:42:11.835725 | LOOP [helm-release-status : creating directory for helm release status]
2026-04-13 20:42:12.037970 | instance | changed: "values"
2026-04-13 20:42:12.203342 | instance | changed: "releases"
2026-04-13 20:42:12.221409 | 
2026-04-13 20:42:12.221578 | TASK [helm-release-status : Gather get release status for helm charts]
2026-04-13 20:42:12.426796 | instance | /bin/bash: line 3: kubectl: command not found
2026-04-13 20:42:14.360809 | instance | ok: Runtime: 0:00:00.006579
2026-04-13 20:42:14.369438 | 
2026-04-13 20:42:14.369693 | TASK [helm-release-status : Downloads logs to executor]
2026-04-13 20:42:14.911123 | instance | changed:
2026-04-13 20:42:14.911314 | instance | cd+++++++++ helm/
2026-04-13 20:42:14.911354 | instance | cd+++++++++ helm/releases/
2026-04-13 20:42:14.911385 | instance | cd+++++++++ helm/values/
2026-04-13 20:42:14.922705 | 
2026-04-13 20:42:14.922775 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-04-13 20:42:15.134472 | instance | changed
2026-04-13 20:42:15.144714 | 
2026-04-13 20:42:15.144843 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-04-13 20:42:15.394861 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-04-13 20:42:15.394974 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-04-13 20:42:15.401703 | instance | environment: line 1: kubectl: command not found
2026-04-13 20:42:15.403225 | instance | environment: line 1: kubectl: command not found
2026-04-13 20:42:15.403317 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-04-13 20:42:15.404567 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-04-13 20:42:15.406400 | instance | environment: line 1: kubectl: command not found
2026-04-13 20:42:15.407380 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-04-13 20:42:15.408937 | instance | environment: line 1: kubectl: command not found
2026-04-13 20:42:15.410377 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-04-13 20:42:15.410720 | instance | environment: line 1: kubectl: command not found
2026-04-13 20:42:15.411544 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-04-13 20:42:16.977595 | instance | ok: Runtime: 0:00:00.032479
2026-04-13 20:42:16.986020 | 
2026-04-13 20:42:16.986146 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-04-13 20:42:17.203853 | instance | changed
2026-04-13 20:42:17.365710 | 
2026-04-13 20:42:17.365895 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-04-13 20:42:17.616677 | instance | environment: line 5: kubectl: command not found
2026-04-13 20:42:17.617730 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-04-13 20:42:17.617817 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-04-13 20:42:17.618062 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-04-13 20:42:18.240620 | instance | ok: Runtime: 0:00:00.010076
2026-04-13 20:42:18.249160 | 
2026-04-13 20:42:18.249319 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-04-13 20:42:19.005545 | instance | changed:
2026-04-13 20:42:19.005757 | instance | cd+++++++++ objects/
2026-04-13 20:42:19.005806 | instance | cd+++++++++ objects/cluster/
2026-04-13 20:42:19.005844 | instance | cd+++++++++ objects/namespaced/
2026-04-13 20:42:19.023231 | 
2026-04-13 20:42:19.023350 | TASK [gather-pod-logs : creating directory for pod logs]
2026-04-13 20:42:19.230494 | instance | changed
2026-04-13 20:42:19.353390 | 
2026-04-13 20:42:19.353565 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-04-13 20:42:19.548239 | instance | changed
2026-04-13 20:42:19.558803 | 
2026-04-13 20:42:19.558943 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-04-13 20:42:19.790262 | instance | environment: line 3: kubectl: command not found
2026-04-13 20:42:20.106917 | instance | ok: Runtime: 0:00:00.008557
2026-04-13 20:42:20.114969 | 
2026-04-13 20:42:20.115088 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-04-13 20:42:20.721387 | instance | changed:
2026-04-13 20:42:20.721564 | instance | cd+++++++++ pod-logs/
2026-04-13 20:42:20.721604 | instance | cd+++++++++ pod-logs/failed-pods/
2026-04-13 20:42:20.734688 | 
2026-04-13 20:42:20.734798 | TASK [gather-prom-metrics : creating directory for helm release descriptions]
2026-04-13 20:42:20.927099 | instance | changed
2026-04-13 20:42:20.934518 | 
2026-04-13 20:42:22.748184 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces]
2026-04-13 20:42:22.988178 | instance | /bin/bash: line 2: kubectl: command not found
2026-04-13 20:42:24.036622 | instance | ok: Runtime: 0:00:00.035819
2026-04-13 20:42:24.046706 | 
2026-04-13 20:42:24.046896 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr]
2026-04-13 20:42:24.257438 | instance | /bin/bash: line 2: kubectl: command not found
2026-04-13 20:42:24.288311 | instance | ceph-mgr endpoints:
2026-04-13 20:42:26.270223 | instance | ok: Runtime: 0:00:00.037901
2026-04-13 20:42:26.279371 | 
2026-04-13 20:42:26.279685 | TASK [gather-prom-metrics : Get metrics from fluentd pods]
2026-04-13 20:42:26.506303 | instance | /bin/bash: line 4: kubectl: command not found
2026-04-13 20:42:26.822949 | instance | ok: Runtime: 0:00:00.036390
2026-04-13 20:42:26.837289 | 
2026-04-13 20:42:26.837653 | TASK [gather-prom-metrics : Downloads logs to executor]
2026-04-13 20:42:29.263496 | instance | changed: cd+++++++++ prometheus/
2026-04-13 20:42:29.284792 | 
2026-04-13 20:42:29.285065 | TASK [gather-selenium-data : creating directory for helm release descriptions]
2026-04-13 20:42:29.507200 | instance | changed
2026-04-13 20:42:29.513311 | 
2026-04-13 20:42:29.513404 | TASK [gather-selenium-data : Get selenium data]
2026-04-13 20:42:29.773612 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/.
2026-04-13 20:42:29.775177 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory
2026-04-13 20:42:31.533630 | instance | ERROR
2026-04-13 20:42:31.533893 | instance | {
2026-04-13 20:42:31.533976 | instance |   "delta": "0:00:00.007618",
2026-04-13 20:42:31.534077 | instance |   "end": "2026-04-13 20:42:29.775565",
2026-04-13 20:42:31.534165 | instance |   "msg": "non-zero return code",
2026-04-13 20:42:31.534236 | instance |   "rc": 1,
2026-04-13 20:42:31.534303 | instance |   "start": "2026-04-13 20:42:29.767947"
2026-04-13 20:42:31.534372 | instance | }
2026-04-13 20:42:31.534430 | instance | ERROR: Ignoring Errors
2026-04-13 20:42:31.544692 | 
2026-04-13 20:42:31.555542 | TASK [gather-selenium-data : Downloads logs to executor]
2026-04-13 20:42:32.745806 | instance | changed: cd+++++++++ selenium/
2026-04-13 20:42:32.752494 | 
2026-04-13 20:42:32.752553 | PLAY RECAP
2026-04-13 20:42:32.752603 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1
2026-04-13 20:42:32.752627 | 
2026-04-13 20:42:32.861533 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@main]
2026-04-13 20:42:32.866582 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-13 20:42:33.466284 | 
2026-04-13 20:42:33.466429 | PLAY [all]
2026-04-13 20:42:33.479455 | 
2026-04-13 20:42:33.479792 | TASK [fetch-output : Set log path for multiple nodes]
2026-04-13 20:42:33.516208 | instance | skipping: Conditional result was False
2026-04-13 20:42:33.527136 | 
2026-04-13 20:42:33.527347 | TASK [fetch-output : Set log path for single node]
2026-04-13 20:42:33.570113 | instance | ok
2026-04-13 20:42:33.577122 | 
2026-04-13 20:42:33.577221 | LOOP [fetch-output : Ensure local output dirs]
2026-04-13 20:42:33.985767 | instance -> localhost | ok: "/var/lib/zuul/builds/041bbd06ba7348eb801f16d0291a3f78/work/logs"
2026-04-13 20:42:34.194816 | instance -> localhost | changed: "/var/lib/zuul/builds/041bbd06ba7348eb801f16d0291a3f78/work/artifacts"
2026-04-13 20:42:38.603134 | instance -> localhost | changed: "/var/lib/zuul/builds/041bbd06ba7348eb801f16d0291a3f78/work/docs"
2026-04-13 20:42:38.621452 | 
2026-04-13 20:42:38.621596 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-04-13 20:42:39.237733 | instance | changed: .d..t...... ./
2026-04-13 20:42:39.237965 | instance | changed: All items complete
2026-04-13 20:42:39.238009 | 
2026-04-13 20:42:39.752163 | instance | changed: .d..t...... ./
2026-04-13 20:42:40.235560 | instance | changed: .d..t...... ./
2026-04-13 20:42:40.255188 | 
2026-04-13 20:42:40.372342 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-04-13 20:42:40.880002 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.012301
2026-04-13 20:42:41.306666 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.008347
2026-04-13 20:42:41.326257 | 
2026-04-13 20:42:41.326613 | PLAY [all]
2026-04-13 20:42:41.334303 | 
2026-04-13 20:42:41.536423 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-04-13 20:42:41.977599 | instance | changed
2026-04-13 20:42:41.983486 | 
2026-04-13 20:42:41.983542 | PLAY RECAP
2026-04-13 20:42:41.983593 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-04-13 20:42:41.983614 | 
2026-04-13 20:42:42.220314 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-13 20:42:42.298851 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-04-13 20:42:42.954295 | 
2026-04-13 20:42:43.338752 | PLAY [localhost]
2026-04-13 20:42:43.358697 | 
2026-04-13 20:42:43.358875 | TASK [Generate Zuul manifest]
2026-04-13 20:42:43.378703 | localhost | ok
2026-04-13 20:42:43.398465 | 
2026-04-13 20:42:43.398826 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-04-13 20:42:44.010454 | localhost | changed
2026-04-13 20:42:44.020583 | 
2026-04-13 20:42:44.020742 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-04-13 20:42:44.503382 | localhost | ok
2026-04-13 20:42:44.512092 | 
2026-04-13 20:42:44.512175 | TASK [Upload logs]
2026-04-13 20:42:44.533026 | localhost | ok
2026-04-13 20:42:44.591550 | 
2026-04-13 20:42:44.591675 | TASK [Set zuul-log-path fact]
2026-04-13 20:42:44.611722 | localhost | ok
2026-04-13 20:42:44.623061 | 
2026-04-13 20:42:44.623171 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-13 20:42:44.654781 | localhost | ok
2026-04-13 20:42:44.843077 | 
2026-04-13 20:42:44.843223 | TASK [upload-logs : Create log directories]
2026-04-13 20:42:46.556386 | localhost | changed
2026-04-13 20:42:46.563931 | 
2026-04-13 20:42:46.564049 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-04-13 20:42:47.202125 | localhost -> localhost | ok: Runtime: 0:00:00.004549
2026-04-13 20:42:47.212389 | 
2026-04-13 20:42:47.212544 | TASK [upload-logs : Upload logs to log server]
2026-04-13 20:42:50.696118 | localhost | Output suppressed because no_log was given
2026-04-13 20:42:50.701168 | 
2026-04-13 20:42:50.701254 | LOOP [upload-logs : Compress console log and json output]
2026-04-13 20:42:50.752555 | localhost | skipping: Conditional result was False
2026-04-13 20:42:50.760055 | localhost | skipping: Conditional result was False
2026-04-13 20:42:50.773180 | 
2026-04-13 20:42:50.773313 | LOOP [upload-logs : Upload compressed console log and json output]
2026-04-13 20:42:50.813978 | localhost | skipping: Conditional result was False
2026-04-13 20:42:50.814226 | 
2026-04-13 20:42:50.819297 | localhost | skipping: Conditional result was False
2026-04-13 20:42:50.837718 | 
2026-04-13 20:42:50.838006 | LOOP [upload-logs : Upload console log and json output]