2026-04-13 16:36:37.709974 | Job console starting
2026-04-13 16:36:37.719848 | Updating git repos
2026-04-13 16:36:37.780397 | Cloning repos into workspace
2026-04-13 16:36:37.823081 | Restoring repo states
2026-04-13 16:36:37.842307 | Merging changes
2026-04-13 16:36:38.807743 | Checking out repos
2026-04-13 16:36:39.073030 | Preparing playbooks
2026-04-13 16:36:41.470414 | Running Ansible setup
2026-04-13 16:36:45.424114 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-13 16:36:46.040546 | 
2026-04-13 16:36:46.040920 | PLAY [localhost]
2026-04-13 16:36:46.052667 | 
2026-04-13 16:36:46.052835 | TASK [Gathering Facts]
2026-04-13 16:36:46.954540 | localhost | ok
2026-04-13 16:36:46.967018 | 
2026-04-13 16:36:46.967176 | TASK [Setup log path fact]
2026-04-13 16:36:46.987802 | localhost | ok
2026-04-13 16:36:47.002124 | 
2026-04-13 16:36:47.002208 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-13 16:36:47.031590 | localhost | ok
2026-04-13 16:36:47.040032 | 
2026-04-13 16:36:47.040133 | TASK [emit-job-header : Print job information]
2026-04-13 16:36:47.080908 | # Job Information
2026-04-13 16:36:47.081129 | Ansible Version: 2.16.16
2026-04-13 16:36:47.081201 | Job: atmosphere-molecule-aio-ovn
2026-04-13 16:36:47.081248 | Pipeline: check
2026-04-13 16:36:47.081279 | Executor: 0a8996d2b663
2026-04-13 16:36:47.081309 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3818
2026-04-13 16:36:47.081341 | Event ID: 5dc2aaf0-3756-11f1-8508-ec1623968e8a
2026-04-13 16:36:47.085493 | 
2026-04-13 16:36:47.085583 | LOOP [emit-job-header : Print node information]
2026-04-13 16:36:47.181032 | localhost | ok:
2026-04-13 16:36:47.181515 | localhost | # Node Information
2026-04-13 16:36:47.181557 | localhost | Inventory Hostname: instance
2026-04-13 16:36:47.181589 | localhost | Hostname: np0000165551
2026-04-13 16:36:47.181618 | localhost | Username: zuul
2026-04-13 16:36:47.181649 | localhost | Distro: Ubuntu 22.04
2026-04-13 16:36:47.181677 | localhost | Provider: yul1
2026-04-13 16:36:47.181705 | localhost | Region: ca-ymq-1
2026-04-13 16:36:47.181731 | localhost | Label: ubuntu-jammy-16
2026-04-13 16:36:47.181758 | localhost | Product Name: OpenStack Nova
2026-04-13 16:36:47.181784 | localhost | Interface IP: 199.19.213.170
2026-04-13 16:36:47.192979 | 
2026-04-13 16:36:47.197223 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-04-13 16:36:47.577550 | localhost -> localhost | changed
2026-04-13 16:36:47.587289 | 
2026-04-13 16:36:47.587431 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-04-13 16:36:48.382372 | localhost -> localhost | changed
2026-04-13 16:36:48.396649 | 
2026-04-13 16:36:48.396735 | PLAY [all]
2026-04-13 16:36:48.405197 | 
2026-04-13 16:36:48.405261 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-04-13 16:36:48.620886 | instance -> localhost | ok
2026-04-13 16:36:48.632012 | 
2026-04-13 16:36:48.632073 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-04-13 16:36:48.663502 | instance | ok
2026-04-13 16:36:48.688977 | instance | included: /var/lib/zuul/builds/7e980f3b99cb4a5685c53abbc94d18b3/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-04-13 16:36:48.694719 | 
2026-04-13 16:36:48.694780 | TASK [add-build-sshkey : Create Temp SSH key]
2026-04-13 16:36:49.423557 | instance -> localhost | Generating public/private rsa key pair.
2026-04-13 16:36:49.423726 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/7e980f3b99cb4a5685c53abbc94d18b3/work/7e980f3b99cb4a5685c53abbc94d18b3_id_rsa
2026-04-13 16:36:49.423756 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/7e980f3b99cb4a5685c53abbc94d18b3/work/7e980f3b99cb4a5685c53abbc94d18b3_id_rsa.pub
2026-04-13 16:36:49.423778 | instance -> localhost | The key fingerprint is:
2026-04-13 16:36:49.423800 | instance -> localhost | SHA256:FXCRFyjD5/QFhOS74gLgqD5WARguqn5g75KXJb8Xiqk zuul-build-sshkey
2026-04-13 16:36:49.423833 | instance -> localhost | The key's randomart image is:
2026-04-13 16:36:49.423855 | instance -> localhost | +---[RSA 3072]----+
2026-04-13 16:36:49.423882 | instance -> localhost | |o.     ..o=*+o   |
2026-04-13 16:36:49.423904 | instance -> localhost | |o.      +o*.. .  |
2026-04-13 16:36:49.423924 | instance -> localhost | |...      *oo .   |
2026-04-13 16:36:49.423945 | instance -> localhost | |o ..     ....    |
2026-04-13 16:36:49.423964 | instance -> localhost | |.o ..   S .      |
2026-04-13 16:36:49.423984 | instance -> localhost | |oo.o.. .   .     |
2026-04-13 16:36:49.424003 | instance -> localhost | |+ = B.. o .      |
2026-04-13 16:36:49.424022 | instance -> localhost | |o= B o.o .       |
2026-04-13 16:36:49.424044 | instance -> localhost | |oEB. .o..        |
2026-04-13 16:36:49.424064 | instance -> localhost | +----[SHA256]-----+
2026-04-13 16:36:49.424110 | instance -> localhost | ok: Runtime: 0:00:00.331731
2026-04-13 16:36:49.429034 | 
2026-04-13 16:36:49.429097 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-04-13 16:36:49.463232 | instance | ok
2026-04-13 16:36:49.471521 | instance | included: /var/lib/zuul/builds/7e980f3b99cb4a5685c53abbc94d18b3/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-04-13 16:36:49.479071 | 
2026-04-13 16:36:49.479140 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-04-13 16:36:49.504226 | instance | skipping: Conditional result was False
2026-04-13 16:36:49.514108 | 
2026-04-13 16:36:49.514219 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-04-13 16:36:49.995410 | instance | changed
2026-04-13 16:36:50.006073 | 
2026-04-13 16:36:50.006156 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-04-13 16:36:50.198359 | instance | ok
2026-04-13 16:36:50.203387 | 
2026-04-13 16:36:50.203453 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-04-13 16:36:50.665978 | instance | changed
2026-04-13 16:36:50.783476 | 
2026-04-13 16:36:50.783581 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-04-13 16:36:51.233228 | instance | changed
2026-04-13 16:36:51.242528 | 
2026-04-13 16:36:51.242598 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-04-13 16:36:51.265206 | instance | skipping: Conditional result was False
2026-04-13 16:36:51.273971 | 
2026-04-13 16:36:51.274063 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-04-13 16:36:51.613440 | instance -> localhost | changed
2026-04-13 16:36:51.659976 | 
2026-04-13 16:36:51.660100 | TASK [add-build-sshkey : Add back temp key]
2026-04-13 16:36:51.937262 | instance -> localhost | Identity added: /var/lib/zuul/builds/7e980f3b99cb4a5685c53abbc94d18b3/work/7e980f3b99cb4a5685c53abbc94d18b3_id_rsa (zuul-build-sshkey)
2026-04-13 16:36:51.937455 | instance -> localhost | ok: Runtime: 0:00:00.013067
2026-04-13 16:36:51.942350 | 
2026-04-13 16:36:51.942413 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-04-13 16:36:52.209639 | instance | ok
2026-04-13 16:36:52.214228 | 
2026-04-13 16:36:52.214288 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-04-13 16:36:52.238500 | instance | skipping: Conditional result was False
2026-04-13 16:36:52.254237 | 
2026-04-13 16:36:52.254313 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-04-13 16:36:52.531876 | instance | ok
2026-04-13 16:36:52.538156 | 
2026-04-13 16:36:52.538222 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-04-13 16:36:54.196368 | instance | Output suppressed because no_log was given
2026-04-13 16:36:54.206338 | 
2026-04-13 16:36:54.206455 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-04-13 16:36:54.388351 | instance | ok: "logs"
2026-04-13 16:36:54.388627 | instance | ok: All items complete
2026-04-13 16:36:54.388659 | 
2026-04-13 16:36:54.540046 | instance | ok: "artifacts"
2026-04-13 16:36:54.693793 | instance | ok: "docs"
2026-04-13 16:36:54.706942 | 
2026-04-13 16:36:54.707114 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-04-13 16:36:54.901233 | instance | changed: "logs"
2026-04-13 16:36:55.062649 | instance | changed: "artifacts"
2026-04-13 16:36:55.216015 | instance | changed: "docs"
2026-04-13 16:36:55.239847 | 
2026-04-13 16:36:55.239926 | PLAY RECAP
2026-04-13 16:36:55.239983 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-04-13 16:36:55.240021 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-13 16:36:55.240050 | 
2026-04-13 16:36:55.408054 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-13 16:36:55.415412 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-13 16:36:55.998183 | 
2026-04-13 16:36:55.998318 | PLAY [all]
2026-04-13 16:36:56.010074 | 
2026-04-13 16:36:56.010155 | TASK [setup-uv : Extract archive]
2026-04-13 16:36:58.122472 | instance | changed
2026-04-13 16:36:58.128451 | 
2026-04-13 16:36:58.128534 | TASK [setup-uv : Print version]
2026-04-13 16:36:58.247684 | instance | uv 0.8.13
2026-04-13 16:36:58.669135 | instance | ok: Runtime: 0:00:00.012826
2026-04-13 16:36:58.678349 | 
2026-04-13 16:36:58.678399 | PLAY RECAP
2026-04-13 16:36:58.678446 | instance | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-13 16:36:58.678472 | 
2026-04-13 16:36:58.830951 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-13 16:36:58.840685 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/pre.yml@main]
2026-04-13 16:36:59.401707 | 
2026-04-13 16:36:59.401828 | PLAY [all]
2026-04-13 16:36:59.412981 | 
2026-04-13 16:36:59.413067 | TASK [Install "jq" for log collection]
2026-04-13 16:37:09.117440 | instance | changed
2026-04-13 16:37:09.122595 | 
2026-04-13 16:37:09.122668 | TASK [Install Python "kubernetes" library for kubernetes.core modules]
2026-04-13 16:37:09.588521 | instance | ERROR
2026-04-13 16:37:09.588904 | instance | {
2026-04-13 16:37:09.588948 | instance |   "msg": "Unable to find any of pip3 to use.  pip needs to be installed."
2026-04-13 16:37:09.589006 | instance | }
failure
2026-04-13 16:37:09.590505 | 
2026-04-13 16:37:09.590566 | PLAY RECAP
2026-04-13 16:37:09.590688 | instance | ok: 1 changed: 1 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-04-13 16:37:09.590748 | 
2026-04-13 16:37:09.719768 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/pre.yml@main]
2026-04-13 16:37:09.729676 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@main]
2026-04-13 16:37:10.376530 | 
2026-04-13 16:37:10.376709 | PLAY [all]
2026-04-13 16:37:10.391283 | 
2026-04-13 16:37:10.391385 | TASK [gather-host-logs : creating directory for system status]
2026-04-13 16:37:10.775044 | instance | changed
2026-04-13 16:37:10.781180 | 
2026-04-13 16:37:10.781515 | TASK [gather-host-logs : Get logs for each host]
2026-04-13 16:37:10.990704 | instance | + systemd-cgls --full --all --no-pager
2026-04-13 16:37:11.003455 | instance | + ip addr
2026-04-13 16:37:11.005119 | instance | + ip route
2026-04-13 16:37:11.006430 | instance | + lsblk
2026-04-13 16:37:11.008927 | instance | + mount
2026-04-13 16:37:11.010432 | instance | + docker images
2026-04-13 16:37:11.011029 | instance | /bin/bash: line 7: docker: command not found
2026-04-13 16:37:11.011195 | instance | + brctl show
2026-04-13 16:37:11.011654 | instance | /bin/bash: line 8: brctl: command not found
2026-04-13 16:37:11.011802 | instance | + ps aux --sort=-%mem
2026-04-13 16:37:11.027541 | instance | + dpkg -l
2026-04-13 16:37:11.039515 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul))
2026-04-13 16:37:11.040162 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul
2026-04-13 16:37:11.040201 | instance | /bin/bash: line 11: docker: command not found
2026-04-13 16:37:11.040661 | instance | + '[' '!' -z '' ']'
2026-04-13 16:37:11.344108 | instance | ok: Runtime: 0:00:00.057210
2026-04-13 16:37:11.353799 | 
2026-04-13 16:37:11.353889 | TASK [gather-host-logs : Downloads logs to executor]
2026-04-13 16:37:12.017381 | instance | changed:
2026-04-13 16:37:12.017636 | instance | created directory /var/lib/zuul/builds/7e980f3b99cb4a5685c53abbc94d18b3/work/logs/instance
2026-04-13 16:37:12.017669 | instance | cd+++++++++ system/
2026-04-13 16:37:12.017692 | instance | >f+++++++++ system/brctl-show.txt
2026-04-13 16:37:12.017713 | instance | >f+++++++++ system/docker-images.txt
2026-04-13 16:37:12.017733 | instance | >f+++++++++ system/ip-addr.txt
2026-04-13 16:37:12.017756 | instance | >f+++++++++ system/ip-route.txt
2026-04-13 16:37:12.017776 | instance | >f+++++++++ system/lsblk.txt
2026-04-13 16:37:12.017796 | instance | >f+++++++++ system/mount.txt
2026-04-13 16:37:12.017817 | instance | >f+++++++++ system/packages.txt
2026-04-13 16:37:12.017836 | instance | >f+++++++++ system/ps.txt
2026-04-13 16:37:12.017855 | instance | >f+++++++++ system/systemd-cgls.txt
2026-04-13 16:37:12.027053 | 
2026-04-13 16:37:12.027119 | LOOP [helm-release-status : creating directory for helm release status]
2026-04-13 16:37:12.225953 | instance | changed: "values"
2026-04-13 16:37:12.397111 | instance | changed: "releases"
2026-04-13 16:37:12.416350 | 
2026-04-13 16:37:12.416529 | TASK [helm-release-status : Gather get release status for helm charts]
2026-04-13 16:37:12.504232 | instance | /bin/bash: line 3: kubectl: command not found
2026-04-13 16:37:12.961032 | instance | ok: Runtime: 0:00:00.006165
2026-04-13 16:37:12.966795 | 
2026-04-13 16:37:12.966863 | TASK [helm-release-status : Downloads logs to executor]
2026-04-13 16:37:13.457544 | instance | changed:
2026-04-13 16:37:13.457725 | instance | cd+++++++++ helm/
2026-04-13 16:37:13.457764 | instance | cd+++++++++ helm/releases/
2026-04-13 16:37:13.457794 | instance | cd+++++++++ helm/values/
2026-04-13 16:37:13.468126 | 
2026-04-13 16:37:13.468189 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-04-13 16:37:13.650184 | instance | changed
2026-04-13 16:37:13.685809 | 
2026-04-13 16:37:13.685936 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-04-13 16:37:13.792858 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-04-13 16:37:13.793196 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-04-13 16:37:13.798926 | instance | environment: line 1: kubectl: command not found
2026-04-13 16:37:13.800594 | instance | environment: line 1: kubectl: command not found
2026-04-13 16:37:13.800633 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-04-13 16:37:13.802109 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-04-13 16:37:13.803994 | instance | environment: line 1: kubectl: command not found
2026-04-13 16:37:13.805373 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-04-13 16:37:13.805630 | instance | environment: line 1: kubectl: command not found
2026-04-13 16:37:13.806973 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-04-13 16:37:13.807966 | instance | environment: line 1: kubectl: command not found
2026-04-13 16:37:13.808767 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-04-13 16:37:14.220164 | instance | ok: Runtime: 0:00:00.025219
2026-04-13 16:37:14.225619 | 
2026-04-13 16:37:14.225689 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-04-13 16:37:14.419640 | instance | changed
2026-04-13 16:37:14.424618 | 
2026-04-13 16:37:14.424686 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-04-13 16:37:14.547202 | instance | environment: line 5: kubectl: command not found
2026-04-13 16:37:14.548199 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-04-13 16:37:14.548236 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-04-13 16:37:14.548512 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-04-13 16:37:14.961885 | instance | ok: Runtime: 0:00:00.009762
2026-04-13 16:37:14.967590 | 
2026-04-13 16:37:14.967652 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-04-13 16:37:15.467397 | instance | changed:
2026-04-13 16:37:15.467599 | instance | cd+++++++++ objects/
2026-04-13 16:37:15.467653 | instance | cd+++++++++ objects/cluster/
2026-04-13 16:37:15.467686 | instance | cd+++++++++ objects/namespaced/
2026-04-13 16:37:15.478001 | 
2026-04-13 16:37:15.478069 | TASK [gather-pod-logs : creating directory for pod logs]
2026-04-13 16:37:15.664332 | instance | changed
2026-04-13 16:37:15.669222 | 
2026-04-13 16:37:15.669281 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-04-13 16:37:15.861546 | instance | changed
2026-04-13 16:37:15.866478 | 
2026-04-13 16:37:15.866543 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-04-13 16:37:16.001319 | instance | environment: line 3: kubectl: command not found
2026-04-13 16:37:16.399513 | instance | ok: Runtime: 0:00:00.008840
2026-04-13 16:37:16.405652 | 
2026-04-13 16:37:16.405715 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-04-13 16:37:16.905541 | instance | changed:
2026-04-13 16:37:16.905784 | instance | cd+++++++++ pod-logs/
2026-04-13 16:37:16.905845 | instance | cd+++++++++ pod-logs/failed-pods/
2026-04-13 16:37:16.916752 | 
2026-04-13 16:37:16.916818 | TASK [gather-prom-metrics : creating directory for helm release descriptions]
2026-04-13 16:37:17.110959 | instance | changed
2026-04-13 16:37:17.116403 | 
2026-04-13 16:37:17.116471 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces]
2026-04-13 16:37:17.256902 | instance | /bin/bash: line 2: kubectl: command not found
2026-04-13 16:37:17.651973 | instance | ok: Runtime: 0:00:00.036088
2026-04-13 16:37:17.657227 | 
2026-04-13 16:37:17.657293 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr]
2026-04-13 16:37:17.802591 | instance | /bin/bash: line 2: kubectl: command not found
2026-04-13 16:37:17.831230 | instance | ceph-mgr endpoints:
2026-04-13 16:37:18.194348 | instance | ok: Runtime: 0:00:00.035814
2026-04-13 16:37:18.201619 | 
2026-04-13 16:37:18.201688 | TASK [gather-prom-metrics : Get metrics from fluentd pods]
2026-04-13 16:37:18.350701 | instance | /bin/bash: line 4: kubectl: command not found
2026-04-13 16:37:18.737357 | instance | ok: Runtime: 0:00:00.036072
2026-04-13 16:37:18.744362 | 
2026-04-13 16:37:18.744445 | TASK [gather-prom-metrics : Downloads logs to executor]
2026-04-13 16:37:19.258897 | instance | changed: cd+++++++++ prometheus/
2026-04-13 16:37:19.268738 | 
2026-04-13 16:37:19.294063 | TASK [gather-selenium-data : creating directory for helm release descriptions]
2026-04-13 16:37:19.542075 | instance | changed
2026-04-13 16:37:19.547094 | 
2026-04-13 16:37:19.547156 | TASK [gather-selenium-data : Get selenium data]
2026-04-13 16:37:19.703838 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/.
2026-04-13 16:37:19.704843 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory
2026-04-13 16:37:20.082038 | instance | ERROR
2026-04-13 16:37:20.082386 | instance | {
2026-04-13 16:37:20.082460 | instance |   "delta": "0:00:00.005854",
2026-04-13 16:37:20.082528 | instance |   "end": "2026-04-13 16:37:19.705186",
2026-04-13 16:37:20.082573 | instance |   "msg": "non-zero return code",
2026-04-13 16:37:20.082614 | instance |   "rc": 1,
2026-04-13 16:37:20.082654 | instance |   "start": "2026-04-13 16:37:19.699332"
2026-04-13 16:37:20.082693 | instance | }
2026-04-13 16:37:20.082745 | instance | ERROR: Ignoring Errors
2026-04-13 16:37:20.089265 | 
2026-04-13 16:37:20.089326 | TASK [gather-selenium-data : Downloads logs to executor]
2026-04-13 16:37:20.556597 | instance | changed: cd+++++++++ selenium/
2026-04-13 16:37:20.564054 | 
2026-04-13 16:37:20.564101 | PLAY RECAP
2026-04-13 16:37:20.564145 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1
2026-04-13 16:37:20.564167 | 
2026-04-13 16:37:20.666846 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@main]
2026-04-13 16:37:20.679102 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-13 16:37:21.260240 | 
2026-04-13 16:37:21.260706 | PLAY [all]
2026-04-13 16:37:21.271666 | 
2026-04-13 16:37:21.271748 | TASK [fetch-output : Set log path for multiple nodes]
2026-04-13 16:37:21.316591 | instance | skipping: Conditional result was False
2026-04-13 16:37:21.328008 | 
2026-04-13 16:37:21.328085 | TASK [fetch-output : Set log path for single node]
2026-04-13 16:37:21.371746 | instance | ok
2026-04-13 16:37:21.378037 | 
2026-04-13 16:37:21.378115 | LOOP [fetch-output : Ensure local output dirs]
2026-04-13 16:37:21.745162 | instance -> localhost | ok: "/var/lib/zuul/builds/7e980f3b99cb4a5685c53abbc94d18b3/work/logs"
2026-04-13 16:37:21.941937 | instance -> localhost | changed: "/var/lib/zuul/builds/7e980f3b99cb4a5685c53abbc94d18b3/work/artifacts"
2026-04-13 16:37:22.145697 | instance -> localhost | changed: "/var/lib/zuul/builds/7e980f3b99cb4a5685c53abbc94d18b3/work/docs"
2026-04-13 16:37:22.165131 | 
2026-04-13 16:37:22.165280 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-04-13 16:37:22.787246 | instance | changed: .d..t...... ./
2026-04-13 16:37:22.787440 | instance | changed: All items complete
2026-04-13 16:37:22.787468 | 
2026-04-13 16:37:23.241491 | instance | changed: .d..t...... ./
2026-04-13 16:37:23.681286 | instance | changed: .d..t...... ./
2026-04-13 16:37:23.705436 | 
2026-04-13 16:37:23.705583 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-04-13 16:37:24.112647 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.007691
2026-04-13 16:37:24.325544 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.005755
2026-04-13 16:37:24.344086 | 
2026-04-13 16:37:24.344219 | PLAY [all]
2026-04-13 16:37:24.352701 | 
2026-04-13 16:37:24.352764 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-04-13 16:37:24.731922 | instance | changed
2026-04-13 16:37:24.737330 | 
2026-04-13 16:37:24.737379 | PLAY RECAP
2026-04-13 16:37:24.737422 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-04-13 16:37:24.737443 | 
2026-04-13 16:37:24.842571 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-13 16:37:24.854794 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-04-13 16:37:25.440047 | 
2026-04-13 16:37:25.440174 | PLAY [localhost]
2026-04-13 16:37:25.449704 | 
2026-04-13 16:37:25.449774 | TASK [Generate Zuul manifest]
2026-04-13 16:37:25.469168 | localhost | ok
2026-04-13 16:37:25.488089 | 
2026-04-13 16:37:25.488173 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-04-13 16:37:25.830525 | localhost | changed
2026-04-13 16:37:25.840151 | 
2026-04-13 16:37:25.840221 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-04-13 16:37:25.870724 | localhost | ok
2026-04-13 16:37:25.879303 | 
2026-04-13 16:37:25.879365 | TASK [Upload logs]
2026-04-13 16:37:25.896105 | localhost | ok
2026-04-13 16:37:25.988237 | 
2026-04-13 16:37:25.988380 | TASK [Set zuul-log-path fact]
2026-04-13 16:37:26.006840 | localhost | ok
2026-04-13 16:37:26.018425 | 
2026-04-13 16:37:26.018508 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-13 16:37:26.047808 | localhost | ok
2026-04-13 16:37:26.057405 | 
2026-04-13 16:37:26.057492 | TASK [upload-logs : Create log directories]
2026-04-13 16:37:26.398803 | localhost | changed
2026-04-13 16:37:26.404415 | 
2026-04-13 16:37:26.404484 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-04-13 16:37:26.748143 | localhost -> localhost | ok: Runtime: 0:00:00.005758
2026-04-13 16:37:26.754287 | 
2026-04-13 16:37:26.754350 | TASK [upload-logs : Upload logs to log server]
2026-04-13 16:37:27.180199 | localhost | Output suppressed because no_log was given
2026-04-13 16:37:27.185018 | 
2026-04-13 16:37:27.185119 | LOOP [upload-logs : Compress console log and json output]
2026-04-13 16:37:27.232304 | localhost | skipping: Conditional result was False
2026-04-13 16:37:27.239544 | localhost | skipping: Conditional result was False
2026-04-13 16:37:27.249063 | 
2026-04-13 16:37:27.249229 | LOOP [upload-logs : Upload compressed console log and json output]
2026-04-13 16:37:27.290918 | localhost | skipping: Conditional result was False
2026-04-13 16:37:27.291274 | 
2026-04-13 16:37:27.295215 | localhost | skipping: Conditional result was False
2026-04-13 16:37:27.312776 | 
2026-04-13 16:37:27.312950 | LOOP [upload-logs : Upload console log and json output]