2026-04-15 12:05:37.318448 | Job console starting
2026-04-15 12:05:37.329424 | Updating git repos
2026-04-15 12:05:37.398499 | Cloning repos into workspace
2026-04-15 12:05:37.638183 | Restoring repo states
2026-04-15 12:05:37.657243 | Merging changes
2026-04-15 12:05:38.355536 | Checking out repos
2026-04-15 12:05:38.509058 | Preparing playbooks
2026-04-15 12:05:42.096000 | Running Ansible setup
2026-04-15 12:05:45.680955 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-15 12:05:46.293733 | 
2026-04-15 12:05:46.294195 | PLAY [localhost]
2026-04-15 12:05:46.301958 | 
2026-04-15 12:05:46.302039 | TASK [Gathering Facts]
2026-04-15 12:05:48.362994 | localhost | ok
2026-04-15 12:05:48.372325 | 
2026-04-15 12:05:48.372426 | TASK [Setup log path fact]
2026-04-15 12:05:48.392838 | localhost | ok
2026-04-15 12:05:48.406393 | 
2026-04-15 12:05:48.406459 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-15 12:05:48.436623 | localhost | ok
2026-04-15 12:05:48.445102 | 
2026-04-15 12:05:48.445174 | TASK [emit-job-header : Print job information]
2026-04-15 12:05:48.488696 | # Job Information
2026-04-15 12:05:48.488881 | Ansible Version: 2.16.16
2026-04-15 12:05:48.488917 | Job: atmosphere-molecule-keycloak
2026-04-15 12:05:48.488941 | Pipeline: check
2026-04-15 12:05:48.488979 | Executor: 0a8996d2b663
2026-04-15 12:05:48.489002 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3835
2026-04-15 12:05:48.489027 | Event ID: 554fb020-38c3-11f1-8ff6-4f000f7144a1
2026-04-15 12:05:48.492078 | 
2026-04-15 12:05:48.492144 | LOOP [emit-job-header : Print node information]
2026-04-15 12:05:48.591125 | localhost | ok:
2026-04-15 12:05:48.591512 | localhost | # Node Information
2026-04-15 12:05:48.591641 | localhost | Inventory Hostname: instance
2026-04-15 12:05:48.591711 | localhost | Hostname: np0000166451
2026-04-15 12:05:48.591771 | localhost | Username: zuul
2026-04-15 12:05:48.591828 | localhost | Distro: Ubuntu 22.04
2026-04-15 12:05:48.591872 | localhost | Provider: yul1
2026-04-15 12:05:48.591902 | localhost | Region: ca-ymq-1
2026-04-15 12:05:48.591928 | localhost | Label: ubuntu-jammy
2026-04-15 12:05:48.591954 | localhost | Product Name: OpenStack Nova
2026-04-15 12:05:48.591980 | localhost | Interface IP: 199.204.45.115
2026-04-15 12:05:48.608454 | 
2026-04-15 12:05:48.608607 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-04-15 12:05:49.127562 | localhost -> localhost | changed
2026-04-15 12:05:49.136161 | 
2026-04-15 12:05:49.136337 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-04-15 12:05:50.163598 | localhost -> localhost | changed
2026-04-15 12:05:50.175448 | 
2026-04-15 12:05:50.175785 | PLAY [all]
2026-04-15 12:05:50.185218 | 
2026-04-15 12:05:50.185314 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-04-15 12:05:50.457441 | instance -> localhost | ok
2026-04-15 12:05:50.468502 | 
2026-04-15 12:05:50.468663 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-04-15 12:05:50.503911 | instance | ok
2026-04-15 12:05:50.530194 | instance | included: /var/lib/zuul/builds/59d92752cbf24549b4588f779762ac2f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-04-15 12:05:50.537303 | 
2026-04-15 12:05:50.537478 | TASK [add-build-sshkey : Create Temp SSH key]
2026-04-15 12:05:53.481360 | instance -> localhost | Generating public/private rsa key pair.
2026-04-15 12:05:53.484020 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/59d92752cbf24549b4588f779762ac2f/work/59d92752cbf24549b4588f779762ac2f_id_rsa
2026-04-15 12:05:53.484065 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/59d92752cbf24549b4588f779762ac2f/work/59d92752cbf24549b4588f779762ac2f_id_rsa.pub
2026-04-15 12:05:53.484217 | instance -> localhost | The key fingerprint is:
2026-04-15 12:05:53.484657 | instance -> localhost | SHA256:qR4RhqyOIkty7rTUMdVswf2z2cBS3AKPls4scglBFkI zuul-build-sshkey
2026-04-15 12:05:53.484730 | instance -> localhost | The key's randomart image is:
2026-04-15 12:05:53.484762 | instance -> localhost | +---[RSA 3072]----+
2026-04-15 12:05:53.484796 | instance -> localhost | |   .E.=o...o .   |
2026-04-15 12:05:53.484822 | instance -> localhost | |   . + +...++ .  |
2026-04-15 12:05:53.484846 | instance -> localhost | |    o = + ++..   |
2026-04-15 12:05:53.484868 | instance -> localhost | |   . o + B. =    |
2026-04-15 12:05:53.484891 | instance -> localhost | |  . o o S +. *   |
2026-04-15 12:05:53.484913 | instance -> localhost | | o . o = .  o .  |
2026-04-15 12:05:53.484939 | instance -> localhost | |=.= . o          |
2026-04-15 12:05:53.485009 | instance -> localhost | |=* . . .         |
2026-04-15 12:05:53.485038 | instance -> localhost | |..+   .          |
2026-04-15 12:05:53.485063 | instance -> localhost | +----[SHA256]-----+
2026-04-15 12:05:53.485138 | instance -> localhost | ok: Runtime: 0:00:02.502198
2026-04-15 12:05:53.492491 | 
2026-04-15 12:05:53.492564 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-04-15 12:05:53.528324 | instance | ok
2026-04-15 12:05:53.547057 | instance | included: /var/lib/zuul/builds/59d92752cbf24549b4588f779762ac2f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-04-15 12:05:53.557779 | 
2026-04-15 12:05:53.557856 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-04-15 12:05:53.583253 | instance | skipping: Conditional result was False
2026-04-15 12:05:53.592690 | 
2026-04-15 12:05:53.592793 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-04-15 12:05:54.094800 | instance | changed
2026-04-15 12:05:54.100874 | 
2026-04-15 12:05:54.100982 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-04-15 12:05:54.286788 | instance | ok
2026-04-15 12:05:54.292796 | 
2026-04-15 12:05:54.292859 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-04-15 12:05:54.774352 | instance | changed
2026-04-15 12:05:54.781570 | 
2026-04-15 12:05:54.781697 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-04-15 12:05:55.249326 | instance | changed
2026-04-15 12:05:55.256720 | 
2026-04-15 12:05:55.256822 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-04-15 12:05:55.281678 | instance | skipping: Conditional result was False
2026-04-15 12:05:55.291497 | 
2026-04-15 12:05:55.291586 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-04-15 12:05:55.691096 | instance -> localhost | changed
2026-04-15 12:05:55.707556 | 
2026-04-15 12:05:55.707701 | TASK [add-build-sshkey : Add back temp key]
2026-04-15 12:05:56.035387 | instance -> localhost | Identity added: /var/lib/zuul/builds/59d92752cbf24549b4588f779762ac2f/work/59d92752cbf24549b4588f779762ac2f_id_rsa (zuul-build-sshkey)
2026-04-15 12:05:56.036154 | instance -> localhost | ok: Runtime: 0:00:00.014668
2026-04-15 12:05:56.042971 | 
2026-04-15 12:05:56.043039 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-04-15 12:05:56.350695 | instance | ok
2026-04-15 12:05:56.357755 | 
2026-04-15 12:05:56.357847 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-04-15 12:05:56.383118 | instance | skipping: Conditional result was False
2026-04-15 12:05:56.402978 | 
2026-04-15 12:05:56.403137 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-04-15 12:05:56.764002 | instance | ok
2026-04-15 12:05:56.771982 | 
2026-04-15 12:05:56.772118 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-04-15 12:05:58.448112 | instance | Output suppressed because no_log was given
2026-04-15 12:05:58.462537 | 
2026-04-15 12:05:58.462733 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-04-15 12:05:58.660477 | instance | ok: "logs"
2026-04-15 12:05:58.660763 | instance | ok: All items complete
2026-04-15 12:05:58.660802 | 
2026-04-15 12:05:58.816098 | instance | ok: "artifacts"
2026-04-15 12:05:58.995923 | instance | ok: "docs"
2026-04-15 12:05:59.008824 | 
2026-04-15 12:05:59.009048 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-04-15 12:05:59.213793 | instance | changed: "logs"
2026-04-15 12:05:59.374536 | instance | changed: "artifacts"
2026-04-15 12:05:59.539642 | instance | changed: "docs"
2026-04-15 12:05:59.557322 | 
2026-04-15 12:05:59.557465 | PLAY RECAP
2026-04-15 12:05:59.557534 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-04-15 12:05:59.557575 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-15 12:05:59.557607 | 
2026-04-15 12:05:59.706196 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-15 12:05:59.711765 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-15 12:06:00.371937 | 
2026-04-15 12:06:00.372593 | PLAY [all]
2026-04-15 12:06:00.389970 | 
2026-04-15 12:06:00.390171 | TASK [setup-uv : Extract archive]
2026-04-15 12:06:02.716874 | instance | changed
2026-04-15 12:06:02.724799 | 
2026-04-15 12:06:02.724938 | TASK [setup-uv : Print version]
2026-04-15 12:06:02.035301 | instance | uv 0.8.13
2026-04-15 12:06:03.262675 | instance | ok: Runtime: 0:00:00.012244
2026-04-15 12:06:03.269401 | 
2026-04-15 12:06:03.269501 | PLAY RECAP
2026-04-15 12:06:03.269548 | instance | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-15 12:06:03.269571 | 
2026-04-15 12:06:03.425660 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-15 12:06:03.429340 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/pre-install-deps.yml@main]
2026-04-15 12:06:04.122080 | 
2026-04-15 12:06:04.151284 | PLAY [all]
2026-04-15 12:06:04.170392 | 
2026-04-15 12:06:04.170562 | TASK [Install system packages]
2026-04-15 12:06:31.823774 | instance | changed
2026-04-15 12:06:31.830425 | 
2026-04-15 12:06:31.830506 | TASK [Enable iscsid service]
2026-04-15 12:06:32.921742 | instance | changed
2026-04-15 12:06:32.930015 | 
2026-04-15 12:06:32.930088 | TASK [Enable multipathd service]
2026-04-15 12:06:33.241323 | instance | ok
2026-04-15 12:06:33.248859 | 
2026-04-15 12:06:33.248935 | TASK [Install Helm 3.14.4]
2026-04-15 12:06:34.790538 | instance | ok: Runtime: 0:00:00.713169
2026-04-15 12:06:34.797281 | 
2026-04-15 12:06:34.797354 | TASK [Install Go 1.24.2]
2026-04-15 12:06:37.842828 | instance | ok: Runtime: 0:00:02.447289
2026-04-15 12:06:37.849464 | 
2026-04-15 12:06:37.849544 | TASK [Create Python venv and install openstacksdk]
2026-04-15 12:06:56.005208 | instance | changed
2026-04-15 12:06:56.008913 | 
2026-04-15 12:06:56.009000 | PLAY RECAP
2026-04-15 12:06:56.009064 | instance | ok: 6 changed: 5 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-15 12:06:56.009096 | 
2026-04-15 12:06:56.120913 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/pre-install-deps.yml@main]
2026-04-15 12:06:56.126420 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/pre.yml@main]
2026-04-15 12:06:56.759426 | 
2026-04-15 12:06:56.759826 | PLAY [all]
2026-04-15 12:06:56.766233 | 
2026-04-15 12:06:56.766295 | PLAY RECAP
2026-04-15 12:06:56.766328 | 
2026-04-15 12:06:56.855499 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/pre.yml@main]
2026-04-15 12:06:56.858856 | RUN START: [untrusted : github.com/vexxhost/atmosphere/molecule/keycloak/converge.yml@main]
2026-04-15 12:06:57.511617 | 
2026-04-15 12:06:57.511991 | PLAY [all]
2026-04-15 12:06:57.526373 | 
2026-04-15 12:06:57.526520 | TASK [Build atmosphere binary]
2026-04-15 12:06:57.888592 | instance | go: downloading go1.24.4 (linux/amd64)
2026-04-15 12:07:03.318737 | instance | go: downloading github.com/spf13/cobra v1.9.1
2026-04-15 12:07:03.325572 | instance | go: downloading golang.org/x/sync v0.18.0
2026-04-15 12:07:03.479998 | instance | go: downloading github.com/spf13/pflag v1.0.7
2026-04-15 12:07:10.076397 | instance | ok: Runtime: 0:00:11.709986
2026-04-15 12:07:10.084796 | 
2026-04-15 12:07:10.084890 | TASK [Deploy with parallel orchestrator]
2026-04-15 12:07:10.288541 | instance | /bin/bash: line 1: .venv/bin/activate: No such file or directory
2026-04-15 12:07:10.623876 | instance | ERROR
2026-04-15 12:07:10.624077 | instance | {
2026-04-15 12:07:10.624121 | instance |   "delta": "0:00:00.006588",
2026-04-15 12:07:10.624153 | instance |   "end": "2026-04-15 12:07:10.288873",
2026-04-15 12:07:10.624181 | instance |   "msg": "non-zero return code",
2026-04-15 12:07:10.624208 | instance |   "rc": 1,
2026-04-15 12:07:10.624234 | instance |   "start": "2026-04-15 12:07:10.282285"
2026-04-15 12:07:10.624267 | instance | }
failure
2026-04-15 12:07:10.626431 | 
2026-04-15 12:07:10.626500 | PLAY RECAP
2026-04-15 12:07:10.626568 | instance | ok: 1 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-04-15 12:07:10.626599 | 
2026-04-15 12:07:10.805708 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/molecule/keycloak/converge.yml@main]
2026-04-15 12:07:10.811222 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@main]
2026-04-15 12:07:11.469846 | 
2026-04-15 12:07:11.470510 | PLAY [all]
2026-04-15 12:07:11.485454 | 
2026-04-15 12:07:11.485553 | TASK [gather-host-logs : creating directory for system status]
2026-04-15 12:07:11.857695 | instance | changed
2026-04-15 12:07:11.864413 | 
2026-04-15 12:07:11.864530 | TASK [gather-host-logs : Get logs for each host]
2026-04-15 12:07:12.239633 | instance | + systemd-cgls --full --all --no-pager
2026-04-15 12:07:12.250771 | instance | + ip addr
2026-04-15 12:07:12.253304 | instance | + ip route
2026-04-15 12:07:12.254597 | instance | + lsblk
2026-04-15 12:07:12.256556 | instance | + mount
2026-04-15 12:07:12.257808 | instance | + docker images
2026-04-15 12:07:12.258139 | instance | /bin/bash: line 7: docker: command not found
2026-04-15 12:07:12.258313 | instance | + brctl show
2026-04-15 12:07:12.258628 | instance | /bin/bash: line 8: brctl: command not found
2026-04-15 12:07:12.258835 | instance | + ps aux --sort=-%mem
2026-04-15 12:07:12.269336 | instance | + dpkg -l
2026-04-15 12:07:12.277002 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul))
2026-04-15 12:07:12.277370 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul
2026-04-15 12:07:12.277396 | instance | /bin/bash: line 11: docker: command not found
2026-04-15 12:07:12.277698 | instance | + '[' '!' -z '' ']'
2026-04-15 12:07:12.412215 | instance | ok: Runtime: 0:00:00.042354
2026-04-15 12:07:12.422177 | 
2026-04-15 12:07:12.422329 | TASK [gather-host-logs : Downloads logs to executor]
2026-04-15 12:07:13.077800 | instance | changed:
2026-04-15 12:07:13.078067 | instance | created directory /var/lib/zuul/builds/59d92752cbf24549b4588f779762ac2f/work/logs/instance
2026-04-15 12:07:13.078123 | instance | cd+++++++++ system/
2026-04-15 12:07:13.078159 | instance | >f+++++++++ system/brctl-show.txt
2026-04-15 12:07:13.078189 | instance | >f+++++++++ system/docker-images.txt
2026-04-15 12:07:13.078218 | instance | >f+++++++++ system/ip-addr.txt
2026-04-15 12:07:13.078251 | instance | >f+++++++++ system/ip-route.txt
2026-04-15 12:07:13.078281 | instance | >f+++++++++ system/lsblk.txt
2026-04-15 12:07:13.078311 | instance | >f+++++++++ system/mount.txt
2026-04-15 12:07:13.078356 | instance | >f+++++++++ system/packages.txt
2026-04-15 12:07:13.078469 | instance | >f+++++++++ system/ps.txt
2026-04-15 12:07:13.078628 | instance | >f+++++++++ system/systemd-cgls.txt
2026-04-15 12:07:13.098158 | 
2026-04-15 12:07:13.098331 | LOOP [helm-release-status : creating directory for helm release status]
2026-04-15 12:07:13.301445 | instance | changed: "values"
2026-04-15 12:07:13.500599 | instance | changed: "releases"
2026-04-15 12:07:13.511716 | 
2026-04-15 12:07:13.511903 | TASK [helm-release-status : Gather get release status for helm charts]
2026-04-15 12:07:13.725159 | instance | /bin/bash: line 3: kubectl: command not found
2026-04-15 12:07:14.049409 | instance | ok: Runtime: 0:00:00.006076
2026-04-15 12:07:14.055136 | 
2026-04-15 12:07:14.055246 | TASK [helm-release-status : Downloads logs to executor]
2026-04-15 12:07:14.587939 | instance | changed:
2026-04-15 12:07:14.588202 | instance | cd+++++++++ helm/
2026-04-15 12:07:14.588244 | instance | cd+++++++++ helm/releases/
2026-04-15 12:07:14.588275 | instance | cd+++++++++ helm/values/
2026-04-15 12:07:14.602724 | 
2026-04-15 12:07:14.602917 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-04-15 12:07:14.808241 | instance | changed
2026-04-15 12:07:14.815371 | 
2026-04-15 12:07:14.815475 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-04-15 12:07:15.033986 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-04-15 12:07:15.034095 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-04-15 12:07:15.038813 | instance | environment: line 1: kubectl: command not found
2026-04-15 12:07:15.039934 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-04-15 12:07:15.041601 | instance | environment: line 1: kubectl: command not found
2026-04-15 12:07:15.042573 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-04-15 12:07:15.042853 | instance | environment: line 1: kubectl: command not found
2026-04-15 12:07:15.043671 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-04-15 12:07:15.045798 | instance | environment: line 1: kubectl: command not found
2026-04-15 12:07:15.046857 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-04-15 12:07:15.047844 | instance | environment: line 1: kubectl: command not found
2026-04-15 12:07:15.048890 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-04-15 12:07:15.357808 | instance | ok: Runtime: 0:00:00.024335
2026-04-15 12:07:15.365176 | 
2026-04-15 12:07:15.365276 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-04-15 12:07:15.583224 | instance | changed
2026-04-15 12:07:15.589952 | 
2026-04-15 12:07:15.590060 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-04-15 12:07:15.823246 | instance | environment: line 5: kubectl: command not found
2026-04-15 12:07:15.824322 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-04-15 12:07:15.824377 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-04-15 12:07:15.825488 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-04-15 12:07:16.159691 | instance | ok: Runtime: 0:00:00.008479
2026-04-15 12:07:16.168195 | 
2026-04-15 12:07:16.168282 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-04-15 12:07:16.681186 | instance | changed:
2026-04-15 12:07:16.681493 | instance | cd+++++++++ objects/
2026-04-15 12:07:16.681539 | instance | cd+++++++++ objects/cluster/
2026-04-15 12:07:16.681573 | instance | cd+++++++++ objects/namespaced/
2026-04-15 12:07:16.696497 | 
2026-04-15 12:07:16.696609 | TASK [gather-pod-logs : creating directory for pod logs]
2026-04-15 12:07:16.895827 | instance | changed
2026-04-15 12:07:16.904879 | 
2026-04-15 12:07:16.905076 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-04-15 12:07:17.104243 | instance | changed
2026-04-15 12:07:17.111544 | 
2026-04-15 12:07:17.111670 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-04-15 12:07:17.325945 | instance | environment: line 3: kubectl: command not found
2026-04-15 12:07:17.648310 | instance | ok: Runtime: 0:00:00.007859
2026-04-15 12:07:17.655668 | 
2026-04-15 12:07:17.655754 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-04-15 12:07:18.165187 | instance | changed:
2026-04-15 12:07:18.165405 | instance | cd+++++++++ pod-logs/
2026-04-15 12:07:18.165436 | instance | cd+++++++++ pod-logs/failed-pods/
2026-04-15 12:07:18.178141 | 
2026-04-15 12:07:18.178321 | TASK [gather-prom-metrics : creating directory for helm release descriptions]
2026-04-15 12:07:18.375809 | instance | changed
2026-04-15 12:07:18.689242 | 
2026-04-15 12:07:18.689453 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces]
2026-04-15 12:07:18.911469 | instance | /bin/bash: line 2: kubectl: command not found
2026-04-15 12:07:19.241572 | instance | ok: Runtime: 0:00:00.037597
2026-04-15 12:07:19.251785 | 
2026-04-15 12:07:19.251910 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr]
2026-04-15 12:07:19.484301 | instance | /bin/bash: line 2: kubectl: command not found
2026-04-15 12:07:19.509447 | instance | ceph-mgr endpoints:
2026-04-15 12:07:19.789323 | instance | ok: Runtime: 0:00:00.032540
2026-04-15 12:07:19.797196 | 
2026-04-15 12:07:19.797351 | TASK [gather-prom-metrics : Get metrics from fluentd pods]
2026-04-15 12:07:20.019821 | instance | /bin/bash: line 4: kubectl: command not found
2026-04-15 12:07:20.337916 | instance | ok: Runtime: 0:00:00.036073
2026-04-15 12:07:20.343484 | 
2026-04-15 12:07:20.343550 | TASK [gather-prom-metrics : Downloads logs to executor]
2026-04-15 12:07:20.851314 | instance | changed: cd+++++++++ prometheus/
2026-04-15 12:07:20.863174 | 
2026-04-15 12:07:20.863250 | TASK [gather-selenium-data : creating directory for helm release descriptions]
2026-04-15 12:07:21.067061 | instance | changed
2026-04-15 12:07:21.075236 | 
2026-04-15 12:07:21.075351 | TASK [gather-selenium-data : Get selenium data]
2026-04-15 12:07:21.289209 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/.
2026-04-15 12:07:21.290759 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory
2026-04-15 12:07:21.616439 | instance | ERROR
2026-04-15 12:07:21.616637 | instance | {
2026-04-15 12:07:21.616707 | instance |   "delta": "0:00:00.006676",
2026-04-15 12:07:21.616752 | instance |   "end": "2026-04-15 12:07:21.291141",
2026-04-15 12:07:21.616788 | instance |   "msg": "non-zero return code",
2026-04-15 12:07:21.616825 | instance |   "rc": 1,
2026-04-15 12:07:21.616864 | instance |   "start": "2026-04-15 12:07:21.284465"
2026-04-15 12:07:21.616898 | instance | }
2026-04-15 12:07:21.616941 | instance | ERROR: Ignoring Errors
2026-04-15 12:07:21.625002 | 
2026-04-15 12:07:21.625129 | TASK [gather-selenium-data : Downloads logs to executor]
2026-04-15 12:07:22.164574 | instance | changed: cd+++++++++ selenium/
2026-04-15 12:07:22.210275 | 
2026-04-15 12:07:22.210390 | PLAY RECAP
2026-04-15 12:07:22.210429 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1
2026-04-15 12:07:22.210452 | 
2026-04-15 12:07:22.409290 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@main]
2026-04-15 12:07:22.413756 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-15 12:07:23.025327 | 
2026-04-15 12:07:23.025796 | PLAY [all]
2026-04-15 12:07:23.036623 | 
2026-04-15 12:07:23.036700 | TASK [fetch-output : Set log path for multiple nodes]
2026-04-15 12:07:23.123293 | instance | skipping: Conditional result was False
2026-04-15 12:07:23.132803 | 
2026-04-15 12:07:23.132927 | TASK [fetch-output : Set log path for single node]
2026-04-15 12:07:23.178019 | instance | ok
2026-04-15 12:07:23.184341 | 
2026-04-15 12:07:23.184435 | LOOP [fetch-output : Ensure local output dirs]
2026-04-15 12:07:23.856591 | instance -> localhost | ok: "/var/lib/zuul/builds/59d92752cbf24549b4588f779762ac2f/work/logs"
2026-04-15 12:07:24.083939 | instance -> localhost | changed: "/var/lib/zuul/builds/59d92752cbf24549b4588f779762ac2f/work/artifacts"
2026-04-15 12:07:24.302148 | instance -> localhost | changed: "/var/lib/zuul/builds/59d92752cbf24549b4588f779762ac2f/work/docs"
2026-04-15 12:07:24.316696 | 
2026-04-15 12:07:24.316824 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-04-15 12:07:24.974552 | instance | changed: .d..t...... ./
2026-04-15 12:07:24.974829 | instance | changed: All items complete
2026-04-15 12:07:24.974879 | 
2026-04-15 12:07:25.465750 | instance | changed: .d..t...... ./
2026-04-15 12:07:25.928307 | instance | changed: .d..t...... ./
2026-04-15 12:07:25.956377 | 
2026-04-15 12:07:25.956593 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-04-15 12:07:26.396160 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.009160
2026-04-15 12:07:26.627905 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.008044
2026-04-15 12:07:26.642563 | 
2026-04-15 12:07:26.642722 | PLAY [all]
2026-04-15 12:07:26.649624 | 
2026-04-15 12:07:26.649695 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-04-15 12:07:27.058291 | instance | changed
2026-04-15 12:07:27.064715 | 
2026-04-15 12:07:27.064800 | PLAY RECAP
2026-04-15 12:07:27.064857 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-04-15 12:07:27.064882 | 
2026-04-15 12:07:27.245855 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-15 12:07:27.251966 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-04-15 12:07:27.840249 | 
2026-04-15 12:07:27.840710 | PLAY [localhost]
2026-04-15 12:07:27.850357 | 
2026-04-15 12:07:27.850430 | TASK [Generate Zuul manifest]
2026-04-15 12:07:27.868168 | localhost | ok
2026-04-15 12:07:27.882216 | 
2026-04-15 12:07:27.882330 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-04-15 12:07:28.298557 | localhost | changed
2026-04-15 12:07:28.313344 | 
2026-04-15 12:07:28.313540 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-04-15 12:07:28.364798 | localhost | ok
2026-04-15 12:07:28.375140 | 
2026-04-15 12:07:28.375315 | TASK [Upload logs]
2026-04-15 12:07:28.399536 | localhost | ok
2026-04-15 12:07:28.873440 | 
2026-04-15 12:07:28.873606 | TASK [Set zuul-log-path fact]
2026-04-15 12:07:28.894887 | localhost | ok
2026-04-15 12:07:28.908002 | 
2026-04-15 12:07:28.908122 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-15 12:07:28.950705 | localhost | ok
2026-04-15 12:07:28.961505 | 
2026-04-15 12:07:28.961690 | TASK [upload-logs : Create log directories]
2026-04-15 12:07:29.440619 | localhost | changed
2026-04-15 12:07:29.447215 | 
2026-04-15 12:07:29.447363 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-04-15 12:07:29.876957 | localhost -> localhost | ok: Runtime: 0:00:00.006388
2026-04-15 12:07:29.882869 | 
2026-04-15 12:07:29.882941 | TASK [upload-logs : Upload logs to log server]
2026-04-15 12:07:30.798271 | localhost | Output suppressed because no_log was given
2026-04-15 12:07:30.803888 | 
2026-04-15 12:07:30.804049 | LOOP [upload-logs : Compress console log and json output]
2026-04-15 12:07:30.851965 | localhost | skipping: Conditional result was False
2026-04-15 12:07:30.901771 | localhost | skipping: Conditional result was False
2026-04-15 12:07:30.911335 | 
2026-04-15 12:07:30.911595 | LOOP [upload-logs : Upload compressed console log and json output]
2026-04-15 12:07:30.957936 | localhost | skipping: Conditional result was False
2026-04-15 12:07:30.958434 | 
2026-04-15 12:07:30.962159 | localhost | skipping: Conditional result was False
2026-04-15 12:07:30.981256 | 
2026-04-15 12:07:30.981449 | LOOP [upload-logs : Upload console log and json output]