2026-04-13 16:35:51.581058 | Job console starting
2026-04-13 16:35:51.591404 | Updating git repos
2026-04-13 16:35:51.644164 | Cloning repos into workspace
2026-04-13 16:35:51.825034 | Restoring repo states
2026-04-13 16:35:51.848656 | Merging changes
2026-04-13 16:35:52.928186 | Checking out repos
2026-04-13 16:35:53.248269 | Preparing playbooks
2026-04-13 16:35:55.135973 | Running Ansible setup
2026-04-13 16:35:58.304535 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-13 16:35:58.887590 | 
2026-04-13 16:35:58.918282 | PLAY [localhost]
2026-04-13 16:35:58.932772 | 
2026-04-13 16:35:58.932909 | TASK [Gathering Facts]
2026-04-13 16:35:59.802873 | localhost | ok
2026-04-13 16:35:59.812355 | 
2026-04-13 16:35:59.812545 | TASK [Setup log path fact]
2026-04-13 16:35:59.831904 | localhost | ok
2026-04-13 16:35:59.847582 | 
2026-04-13 16:35:59.847654 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-13 16:35:59.874214 | localhost | ok
2026-04-13 16:35:59.879997 | 
2026-04-13 16:35:59.880063 | TASK [emit-job-header : Print job information]
2026-04-13 16:35:59.918124 | # Job Information
2026-04-13 16:35:59.918392 | Ansible Version: 2.16.16
2026-04-13 16:35:59.918446 | Job: atmosphere-molecule-keycloak
2026-04-13 16:35:59.918486 | Pipeline: check
2026-04-13 16:35:59.918517 | Executor: 0a8996d2b663
2026-04-13 16:35:59.918546 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3818
2026-04-13 16:35:59.918579 | Event ID: 5dc2aaf0-3756-11f1-8508-ec1623968e8a
2026-04-13 16:35:59.923498 | 
2026-04-13 16:35:59.923599 | LOOP [emit-job-header : Print node information]
2026-04-13 16:36:00.018150 | localhost | ok:
2026-04-13 16:36:00.018446 | localhost | # Node Information
2026-04-13 16:36:00.018509 | localhost | Inventory Hostname: instance
2026-04-13 16:36:00.018557 | localhost | Hostname: np0000165548
2026-04-13 16:36:00.018600 | localhost | Username: zuul
2026-04-13 16:36:00.018654 | localhost | Distro: Ubuntu 22.04
2026-04-13 16:36:00.018718 | localhost | Provider: yul1
2026-04-13 16:36:00.018787 | localhost | Region: ca-ymq-1
2026-04-13 16:36:00.018834 | localhost | Label: ubuntu-jammy
2026-04-13 16:36:00.018876 | localhost | Product Name: OpenStack Nova
2026-04-13 16:36:00.018916 | localhost | Interface IP: 199.204.45.248
2026-04-13 16:36:00.035112 | 
2026-04-13 16:36:00.035336 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-04-13 16:36:00.406855 | localhost -> localhost | changed
2026-04-13 16:36:00.416890 | 
2026-04-13 16:36:00.417011 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-04-13 16:36:01.211663 | localhost -> localhost | changed
2026-04-13 16:36:01.217880 | 
2026-04-13 16:36:01.217938 | PLAY [all]
2026-04-13 16:36:01.226293 | 
2026-04-13 16:36:01.226360 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-04-13 16:36:01.452462 | instance -> localhost | ok
2026-04-13 16:36:01.460812 | 
2026-04-13 16:36:01.460896 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-04-13 16:36:01.493506 | instance | ok
2026-04-13 16:36:01.508984 | instance | included: /var/lib/zuul/builds/348623d3022b4c0fb6d5ef738c25e001/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-04-13 16:36:01.515093 | 
2026-04-13 16:36:01.515153 | TASK [add-build-sshkey : Create Temp SSH key]
2026-04-13 16:36:02.829223 | instance -> localhost | Generating public/private rsa key pair.
2026-04-13 16:36:02.829478 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/348623d3022b4c0fb6d5ef738c25e001/work/348623d3022b4c0fb6d5ef738c25e001_id_rsa
2026-04-13 16:36:02.829543 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/348623d3022b4c0fb6d5ef738c25e001/work/348623d3022b4c0fb6d5ef738c25e001_id_rsa.pub
2026-04-13 16:36:02.829593 | instance -> localhost | The key fingerprint is:
2026-04-13 16:36:02.829641 | instance -> localhost | SHA256:tI/XlBH+zDWi5itZ5WbL4s38IQ4noOgTlgS8HHYwLXM zuul-build-sshkey
2026-04-13 16:36:02.829702 | instance -> localhost | The key's randomart image is:
2026-04-13 16:36:02.829751 | instance -> localhost | +---[RSA 3072]----+
2026-04-13 16:36:02.829805 | instance -> localhost | |  .oo       .    |
2026-04-13 16:36:02.829852 | instance -> localhost | |   B.E     . .   |
2026-04-13 16:36:02.829896 | instance -> localhost | |  o O   .   o. ..|
2026-04-13 16:36:02.829940 | instance -> localhost | |   o . . .  .B...|
2026-04-13 16:36:02.830034 | instance -> localhost | |    . . S. o= +  |
2026-04-13 16:36:02.830089 | instance -> localhost | |     +. .o++ +   |
2026-04-13 16:36:02.830135 | instance -> localhost | |    ..... =+=o.. |
2026-04-13 16:36:02.830179 | instance -> localhost | |    ..   + .Xo. .|
2026-04-13 16:36:02.830228 | instance -> localhost | |     ..   oo.=.. |
2026-04-13 16:36:02.830273 | instance -> localhost | +----[SHA256]-----+
2026-04-13 16:36:02.830363 | instance -> localhost | ok: Runtime: 0:00:00.908314
2026-04-13 16:36:02.835209 | 
2026-04-13 16:36:02.835266 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-04-13 16:36:02.867313 | instance | ok
2026-04-13 16:36:02.878165 | instance | included: /var/lib/zuul/builds/348623d3022b4c0fb6d5ef738c25e001/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-04-13 16:36:02.885461 | 
2026-04-13 16:36:02.885525 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-04-13 16:36:02.909426 | instance | skipping: Conditional result was False
2026-04-13 16:36:02.921241 | 
2026-04-13 16:36:02.921377 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-04-13 16:36:03.358786 | instance | changed
2026-04-13 16:36:03.365371 | 
2026-04-13 16:36:03.365454 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-04-13 16:36:03.535846 | instance | ok
2026-04-13 16:36:03.540703 | 
2026-04-13 16:36:03.540767 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-04-13 16:36:03.987813 | instance | changed
2026-04-13 16:36:03.995407 | 
2026-04-13 16:36:03.995495 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-04-13 16:36:04.435057 | instance | changed
2026-04-13 16:36:04.442135 | 
2026-04-13 16:36:04.442241 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-04-13 16:36:04.467165 | instance | skipping: Conditional result was False
2026-04-13 16:36:04.478174 | 
2026-04-13 16:36:04.478309 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-04-13 16:36:04.858593 | instance -> localhost | changed
2026-04-13 16:36:04.873094 | 
2026-04-13 16:36:04.873200 | TASK [add-build-sshkey : Add back temp key]
2026-04-13 16:36:05.146954 | instance -> localhost | Identity added: /var/lib/zuul/builds/348623d3022b4c0fb6d5ef738c25e001/work/348623d3022b4c0fb6d5ef738c25e001_id_rsa (zuul-build-sshkey)
2026-04-13 16:36:05.147164 | instance -> localhost | ok: Runtime: 0:00:00.015557
2026-04-13 16:36:05.152257 | 
2026-04-13 16:36:05.152351 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-04-13 16:36:05.444064 | instance | ok
2026-04-13 16:36:05.448825 | 
2026-04-13 16:36:05.448892 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-04-13 16:36:05.473475 | instance | skipping: Conditional result was False
2026-04-13 16:36:05.489485 | 
2026-04-13 16:36:05.489579 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-04-13 16:36:05.812795 | instance | ok
2026-04-13 16:36:05.818342 | 
2026-04-13 16:36:05.818406 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-04-13 16:36:07.381341 | instance | Output suppressed because no_log was given
2026-04-13 16:36:07.392599 | 
2026-04-13 16:36:07.392663 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-04-13 16:36:07.574535 | instance | ok: "logs"
2026-04-13 16:36:07.575207 | instance | ok: All items complete
2026-04-13 16:36:07.575264 | 
2026-04-13 16:36:07.712474 | instance | ok: "artifacts"
2026-04-13 16:36:07.864687 | instance | ok: "docs"
2026-04-13 16:36:07.879529 | 
2026-04-13 16:36:07.879776 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-04-13 16:36:08.060673 | instance | changed: "logs"
2026-04-13 16:36:08.210773 | instance | changed: "artifacts"
2026-04-13 16:36:08.363143 | instance | changed: "docs"
2026-04-13 16:36:08.384245 | 
2026-04-13 16:36:08.384390 | PLAY RECAP
2026-04-13 16:36:08.384446 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-04-13 16:36:08.384475 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-13 16:36:08.384504 | 
2026-04-13 16:36:08.555222 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-13 16:36:08.560992 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-13 16:36:09.171269 | 
2026-04-13 16:36:09.171407 | PLAY [all]
2026-04-13 16:36:09.183574 | 
2026-04-13 16:36:09.183673 | TASK [setup-uv : Extract archive]
2026-04-13 16:36:11.312426 | instance | changed
2026-04-13 16:36:11.318237 | 
2026-04-13 16:36:11.318314 | TASK [setup-uv : Print version]
2026-04-13 16:36:11.553515 | instance | uv 0.8.13
2026-04-13 16:36:11.853544 | instance | ok: Runtime: 0:00:00.011105
2026-04-13 16:36:11.859486 | 
2026-04-13 16:36:11.859535 | PLAY RECAP
2026-04-13 16:36:11.859578 | instance | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-13 16:36:11.859602 | 
2026-04-13 16:36:11.978930 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-13 16:36:12.181078 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/pre.yml@main]
2026-04-13 16:36:12.754726 | 
2026-04-13 16:36:12.754846 | PLAY [all]
2026-04-13 16:36:12.765374 | 
2026-04-13 16:36:12.765446 | TASK [Install "jq" for log collection]
2026-04-13 16:36:55.227683 | instance | changed
2026-04-13 16:36:55.232774 | 
2026-04-13 16:36:55.232848 | TASK [Install Python "kubernetes" library for kubernetes.core modules]
2026-04-13 16:36:55.681689 | instance | ERROR
2026-04-13 16:36:55.682002 | instance | {
2026-04-13 16:36:55.682049 | instance |   "msg": "Unable to find any of pip3 to use.  pip needs to be installed."
2026-04-13 16:36:55.682083 | instance | }
failure
2026-04-13 16:36:55.683328 | 
2026-04-13 16:36:55.683386 | PLAY RECAP
2026-04-13 16:36:55.683449 | instance | ok: 1 changed: 1 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-04-13 16:36:55.683483 | 
2026-04-13 16:36:55.785827 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/pre.yml@main]
2026-04-13 16:36:55.788140 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@main]
2026-04-13 16:36:56.411714 | 
2026-04-13 16:36:56.411887 | PLAY [all]
2026-04-13 16:36:56.427013 | 
2026-04-13 16:36:56.427203 | TASK [gather-host-logs : creating directory for system status]
2026-04-13 16:36:56.773343 | instance | changed
2026-04-13 16:36:56.780523 | 
2026-04-13 16:36:56.780608 | TASK [gather-host-logs : Get logs for each host]
2026-04-13 16:36:57.138027 | instance | + systemd-cgls --full --all --no-pager
2026-04-13 16:36:57.149700 | instance | + ip addr
2026-04-13 16:36:57.150926 | instance | + ip route
2026-04-13 16:36:57.151920 | instance | + lsblk
2026-04-13 16:36:57.153843 | instance | + mount
2026-04-13 16:36:57.154991 | instance | + docker images
2026-04-13 16:36:57.155219 | instance | /bin/bash: line 7: docker: command not found
2026-04-13 16:36:57.155341 | instance | + brctl show
2026-04-13 16:36:57.155600 | instance | /bin/bash: line 8: brctl: command not found
2026-04-13 16:36:57.155889 | instance | + ps aux --sort=-%mem
2026-04-13 16:36:57.165897 | instance | + dpkg -l
2026-04-13 16:36:57.173086 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul))
2026-04-13 16:36:57.173348 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul
2026-04-13 16:36:57.173381 | instance | /bin/bash: line 11: docker: command not found
2026-04-13 16:36:57.173532 | instance | + '[' '!' -z '' ']'
2026-04-13 16:36:57.318700 | instance | ok: Runtime: 0:00:00.040801
2026-04-13 16:36:57.327107 | 
2026-04-13 16:36:57.327180 | TASK [gather-host-logs : Downloads logs to executor]
2026-04-13 16:36:57.943446 | instance | changed:
2026-04-13 16:36:57.943663 | instance | created directory /var/lib/zuul/builds/348623d3022b4c0fb6d5ef738c25e001/work/logs/instance
2026-04-13 16:36:57.943704 | instance | cd+++++++++ system/
2026-04-13 16:36:57.943737 | instance | >f+++++++++ system/brctl-show.txt
2026-04-13 16:36:57.943770 | instance | >f+++++++++ system/docker-images.txt
2026-04-13 16:36:57.943801 | instance | >f+++++++++ system/ip-addr.txt
2026-04-13 16:36:57.943833 | instance | >f+++++++++ system/ip-route.txt
2026-04-13 16:36:57.943863 | instance | >f+++++++++ system/lsblk.txt
2026-04-13 16:36:57.943891 | instance | >f+++++++++ system/mount.txt
2026-04-13 16:36:57.943923 | instance | >f+++++++++ system/packages.txt
2026-04-13 16:36:57.943951 | instance | >f+++++++++ system/ps.txt
2026-04-13 16:36:57.943979 | instance | >f+++++++++ system/systemd-cgls.txt
2026-04-13 16:36:57.954470 | 
2026-04-13 16:36:57.954541 | LOOP [helm-release-status : creating directory for helm release status]
2026-04-13 16:36:58.153645 | instance | changed: "values"
2026-04-13 16:36:58.310835 | instance | changed: "releases"
2026-04-13 16:36:58.329216 | 
2026-04-13 16:36:58.329413 | TASK [helm-release-status : Gather get release status for helm charts]
2026-04-13 16:36:58.532253 | instance | /bin/bash: line 3: kubectl: command not found
2026-04-13 16:36:58.869698 | instance | ok: Runtime: 0:00:00.005688
2026-04-13 16:36:58.875032 | 
2026-04-13 16:36:58.875104 | TASK [helm-release-status : Downloads logs to executor]
2026-04-13 16:36:59.355213 | instance | changed:
2026-04-13 16:36:59.355457 | instance | cd+++++++++ helm/
2026-04-13 16:36:59.355499 | instance | cd+++++++++ helm/releases/
2026-04-13 16:36:59.355530 | instance | cd+++++++++ helm/values/
2026-04-13 16:36:59.366296 | 
2026-04-13 16:36:59.366398 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-04-13 16:36:59.576738 | instance | changed
2026-04-13 16:36:59.583028 | 
2026-04-13 16:36:59.583108 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-04-13 16:36:59.792225 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-04-13 16:36:59.792312 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-04-13 16:36:59.797607 | instance | environment: line 1: kubectl: command not found
2026-04-13 16:36:59.799160 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-04-13 16:36:59.799364 | instance | environment: line 1: kubectl: command not found
2026-04-13 16:36:59.800487 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-04-13 16:36:59.802106 | instance | environment: line 1: kubectl: command not found
2026-04-13 16:36:59.802777 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-04-13 16:36:59.804183 | instance | environment: line 1: kubectl: command not found
2026-04-13 16:36:59.805072 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-04-13 16:36:59.805515 | instance | environment: line 1: kubectl: command not found
2026-04-13 16:36:59.806280 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-04-13 16:37:00.120385 | instance | ok: Runtime: 0:00:00.023762
2026-04-13 16:37:00.127040 | 
2026-04-13 16:37:00.127107 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-04-13 16:37:00.324872 | instance | changed
2026-04-13 16:37:00.331635 | 
2026-04-13 16:37:00.331716 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-04-13 16:37:00.580350 | instance | environment: line 5: kubectl: command not found
2026-04-13 16:37:00.581217 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-04-13 16:37:00.581259 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-04-13 16:37:00.581372 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-04-13 16:37:00.909516 | instance | ok: Runtime: 0:00:00.009761
2026-04-13 16:37:00.914765 | 
2026-04-13 16:37:00.914826 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-04-13 16:37:01.406968 | instance | changed:
2026-04-13 16:37:01.407166 | instance | cd+++++++++ objects/
2026-04-13 16:37:01.407205 | instance | cd+++++++++ objects/cluster/
2026-04-13 16:37:01.407237 | instance | cd+++++++++ objects/namespaced/
2026-04-13 16:37:01.418962 | 
2026-04-13 16:37:01.419022 | TASK [gather-pod-logs : creating directory for pod logs]
2026-04-13 16:37:01.605609 | instance | changed
2026-04-13 16:37:01.612523 | 
2026-04-13 16:37:01.612590 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-04-13 16:37:01.810028 | instance | changed
2026-04-13 16:37:01.815760 | 
2026-04-13 16:37:01.815827 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-04-13 16:37:02.016179 | instance | environment: line 3: kubectl: command not found
2026-04-13 16:37:02.352652 | instance | ok: Runtime: 0:00:00.008920
2026-04-13 16:37:02.358374 | 
2026-04-13 16:37:02.358436 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-04-13 16:37:02.850489 | instance | changed:
2026-04-13 16:37:02.850657 | instance | cd+++++++++ pod-logs/
2026-04-13 16:37:02.850684 | instance | cd+++++++++ pod-logs/failed-pods/
2026-04-13 16:37:02.860049 | 
2026-04-13 16:37:02.860111 | TASK [gather-prom-metrics : creating directory for helm release descriptions]
2026-04-13 16:37:03.055563 | instance | changed
2026-04-13 16:37:03.061813 | 
2026-04-13 16:37:03.061878 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces]
2026-04-13 16:37:03.263674 | instance | /bin/bash: line 2: kubectl: command not found
2026-04-13 16:37:03.599077 | instance | ok: Runtime: 0:00:00.033586
2026-04-13 16:37:03.604560 | 
2026-04-13 16:37:03.604624 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr]
2026-04-13 16:37:03.803492 | instance | /bin/bash: line 2: kubectl: command not found
2026-04-13 16:37:03.830880 | instance | ceph-mgr endpoints:
2026-04-13 16:37:04.141471 | instance | ok: Runtime: 0:00:00.033437
2026-04-13 16:37:04.148700 | 
2026-04-13 16:37:04.148769 | TASK [gather-prom-metrics : Get metrics from fluentd pods]
2026-04-13 16:37:04.360357 | instance | /bin/bash: line 4: kubectl: command not found
2026-04-13 16:37:04.683266 | instance | ok: Runtime: 0:00:00.033149
2026-04-13 16:37:04.689867 | 
2026-04-13 16:37:04.689935 | TASK [gather-prom-metrics : Downloads logs to executor]
2026-04-13 16:37:05.171441 | instance | changed: cd+++++++++ prometheus/
2026-04-13 16:37:05.181423 | 
2026-04-13 16:37:05.181486 | TASK [gather-selenium-data : creating directory for helm release descriptions]
2026-04-13 16:37:05.390687 | instance | changed
2026-04-13 16:37:05.397031 | 
2026-04-13 16:37:05.397094 | TASK [gather-selenium-data : Get selenium data]
2026-04-13 16:37:05.602024 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/.
2026-04-13 16:37:05.603429 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory
2026-04-13 16:37:05.934786 | instance | ERROR
2026-04-13 16:37:05.935219 | instance | {
2026-04-13 16:37:05.935291 | instance |   "delta": "0:00:00.006481",
2026-04-13 16:37:05.935370 | instance |   "end": "2026-04-13 16:37:05.603826",
2026-04-13 16:37:05.935422 | instance |   "msg": "non-zero return code",
2026-04-13 16:37:05.935470 | instance |   "rc": 1,
2026-04-13 16:37:05.935501 | instance |   "start": "2026-04-13 16:37:05.597345"
2026-04-13 16:37:05.935523 | instance | }
2026-04-13 16:37:05.935550 | instance | ERROR: Ignoring Errors
2026-04-13 16:37:05.940749 | 
2026-04-13 16:37:05.940819 | TASK [gather-selenium-data : Downloads logs to executor]
2026-04-13 16:37:06.435869 | instance | changed: cd+++++++++ selenium/
2026-04-13 16:37:06.441888 | 
2026-04-13 16:37:06.441943 | PLAY RECAP
2026-04-13 16:37:06.441997 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1
2026-04-13 16:37:06.442019 | 
2026-04-13 16:37:06.578487 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@main]
2026-04-13 16:37:06.584422 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-13 16:37:07.148207 | 
2026-04-13 16:37:07.148320 | PLAY [all]
2026-04-13 16:37:07.159661 | 
2026-04-13 16:37:07.159736 | TASK [fetch-output : Set log path for multiple nodes]
2026-04-13 16:37:07.204802 | instance | skipping: Conditional result was False
2026-04-13 16:37:07.210303 | 
2026-04-13 16:37:07.210385 | TASK [fetch-output : Set log path for single node]
2026-04-13 16:37:07.253090 | instance | ok
2026-04-13 16:37:07.259359 | 
2026-04-13 16:37:07.259438 | LOOP [fetch-output : Ensure local output dirs]
2026-04-13 16:37:07.656238 | instance -> localhost | ok: "/var/lib/zuul/builds/348623d3022b4c0fb6d5ef738c25e001/work/logs"
2026-04-13 16:37:07.860412 | instance -> localhost | changed: "/var/lib/zuul/builds/348623d3022b4c0fb6d5ef738c25e001/work/artifacts"
2026-04-13 16:37:08.060049 | instance -> localhost | changed: "/var/lib/zuul/builds/348623d3022b4c0fb6d5ef738c25e001/work/docs"
2026-04-13 16:37:08.081204 | 
2026-04-13 16:37:08.081345 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-04-13 16:37:08.720184 | instance | changed: .d..t...... ./
2026-04-13 16:37:08.720390 | instance | changed: All items complete
2026-04-13 16:37:08.720418 | 
2026-04-13 16:37:09.154194 | instance | changed: .d..t...... ./
2026-04-13 16:37:09.598206 | instance | changed: .d..t...... ./
2026-04-13 16:37:09.620615 | 
2026-04-13 16:37:09.620687 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-04-13 16:37:10.027976 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.007121
2026-04-13 16:37:10.240317 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.006818
2026-04-13 16:37:10.259292 | 
2026-04-13 16:37:10.259350 | PLAY [all]
2026-04-13 16:37:10.265015 | 
2026-04-13 16:37:10.265080 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-04-13 16:37:10.654003 | instance | changed
2026-04-13 16:37:10.659309 | 
2026-04-13 16:37:10.659357 | PLAY RECAP
2026-04-13 16:37:10.659400 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-04-13 16:37:10.659421 | 
2026-04-13 16:37:10.776369 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-13 16:37:10.779851 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-04-13 16:37:11.352525 | 
2026-04-13 16:37:11.352639 | PLAY [localhost]
2026-04-13 16:37:11.363314 | 
2026-04-13 16:37:11.363404 | TASK [Generate Zuul manifest]
2026-04-13 16:37:11.380463 | localhost | ok
2026-04-13 16:37:11.394242 | 
2026-04-13 16:37:11.394320 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-04-13 16:37:11.790278 | localhost | changed
2026-04-13 16:37:11.802299 | 
2026-04-13 16:37:11.802373 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-04-13 16:37:11.833367 | localhost | ok
2026-04-13 16:37:11.847757 | 
2026-04-13 16:37:11.847941 | TASK [Upload logs]
2026-04-13 16:37:11.869853 | localhost | ok
2026-04-13 16:37:11.932072 | 
2026-04-13 16:37:11.932263 | TASK [Set zuul-log-path fact]
2026-04-13 16:37:11.955823 | localhost | ok
2026-04-13 16:37:11.968388 | 
2026-04-13 16:37:11.968505 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-13 16:37:12.001752 | localhost | ok
2026-04-13 16:37:12.010786 | 
2026-04-13 16:37:12.010879 | TASK [upload-logs : Create log directories]
2026-04-13 16:37:12.399660 | localhost | changed
2026-04-13 16:37:12.406374 | 
2026-04-13 16:37:12.406446 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-04-13 16:37:12.812153 | localhost -> localhost | ok: Runtime: 0:00:00.007091
2026-04-13 16:37:12.817031 | 
2026-04-13 16:37:12.817095 | TASK [upload-logs : Upload logs to log server]
2026-04-13 16:37:13.294241 | localhost | Output suppressed because no_log was given
2026-04-13 16:37:13.298498 | 
2026-04-13 16:37:13.298562 | LOOP [upload-logs : Compress console log and json output]
2026-04-13 16:37:13.341143 | localhost | skipping: Conditional result was False
2026-04-13 16:37:13.348014 | localhost | skipping: Conditional result was False
2026-04-13 16:37:13.361901 | 
2026-04-13 16:37:13.362065 | LOOP [upload-logs : Upload compressed console log and json output]
2026-04-13 16:37:13.402558 | localhost | skipping: Conditional result was False
2026-04-13 16:37:13.402907 | 
2026-04-13 16:37:13.406647 | localhost | skipping: Conditional result was False
2026-04-13 16:37:13.415937 | 
2026-04-13 16:37:13.416033 | LOOP [upload-logs : Upload console log and json output]