2026-04-13 16:35:10.471915 | Job console starting
2026-04-13 16:35:10.483492 | Updating git repos
2026-04-13 16:35:10.565201 | Cloning repos into workspace
2026-04-13 16:35:12.189399 | Restoring repo states
2026-04-13 16:35:12.211354 | Merging changes
2026-04-13 16:35:14.599692 | Checking out repos
2026-04-13 16:35:15.188367 | Preparing playbooks
2026-04-13 16:35:22.583262 | Running Ansible setup
2026-04-13 16:35:26.362728 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-13 16:35:26.967092 | 
2026-04-13 16:35:26.967201 | PLAY [localhost]
2026-04-13 16:35:26.974436 | 
2026-04-13 16:35:26.974505 | TASK [Gathering Facts]
2026-04-13 16:35:27.796781 | localhost | ok
2026-04-13 16:35:27.809512 | 
2026-04-13 16:35:27.809623 | TASK [Setup log path fact]
2026-04-13 16:35:27.829886 | localhost | ok
2026-04-13 16:35:27.841749 | 
2026-04-13 16:35:27.841822 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-13 16:35:27.871372 | localhost | ok
2026-04-13 16:35:27.878147 | 
2026-04-13 16:35:27.878217 | TASK [emit-job-header : Print job information]
2026-04-13 16:35:27.919583 | # Job Information
2026-04-13 16:35:27.919790 | Ansible Version: 2.16.16
2026-04-13 16:35:27.919861 | Job: atmosphere-molecule-aio-ovn
2026-04-13 16:35:27.919908 | Pipeline: check
2026-04-13 16:35:27.919951 | Executor: 0a8996d2b663
2026-04-13 16:35:27.919992 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3818
2026-04-13 16:35:27.920042 | Event ID: 5dc2aaf0-3756-11f1-8508-ec1623968e8a
2026-04-13 16:35:27.923907 | 
2026-04-13 16:35:27.923968 | LOOP [emit-job-header : Print node information]
2026-04-13 16:35:28.025734 | localhost | ok:
2026-04-13 16:35:28.026673 | localhost | # Node Information
2026-04-13 16:35:28.026743 | localhost | Inventory Hostname: instance
2026-04-13 16:35:28.026791 | localhost | Hostname: np0000165545
2026-04-13 16:35:28.026834 | localhost | Username: zuul
2026-04-13 16:35:28.026883 | localhost | Distro: Ubuntu 22.04
2026-04-13 16:35:28.026926 | localhost | Provider: yul1
2026-04-13 16:35:28.026968 | localhost | Region: ca-ymq-1
2026-04-13 16:35:28.027009 | localhost | Label: ubuntu-jammy-16
2026-04-13 16:35:28.027050 | localhost | Product Name: OpenStack Nova
2026-04-13 16:35:28.027091 | localhost | Interface IP: 199.204.45.116
2026-04-13 16:35:28.044392 | 
2026-04-13 16:35:28.044492 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-04-13 16:35:28.406694 | localhost -> localhost | changed
2026-04-13 16:35:28.415892 | 
2026-04-13 16:35:28.415961 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-04-13 16:35:29.376206 | localhost -> localhost | changed
2026-04-13 16:35:29.389516 | 
2026-04-13 16:35:29.389600 | PLAY [all]
2026-04-13 16:35:29.398261 | 
2026-04-13 16:35:29.398324 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-04-13 16:35:29.687901 | instance -> localhost | ok
2026-04-13 16:35:29.697861 | 
2026-04-13 16:35:29.697937 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-04-13 16:35:29.732859 | instance | ok
2026-04-13 16:35:29.745680 | instance | included: /var/lib/zuul/builds/630b37f2f0d44ed79fe54a99e9b8b55d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-04-13 16:35:29.751146 | 
2026-04-13 16:35:29.751209 | TASK [add-build-sshkey : Create Temp SSH key]
2026-04-13 16:35:31.904956 | instance -> localhost | Generating public/private rsa key pair.
2026-04-13 16:35:31.905211 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/630b37f2f0d44ed79fe54a99e9b8b55d/work/630b37f2f0d44ed79fe54a99e9b8b55d_id_rsa
2026-04-13 16:35:31.905254 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/630b37f2f0d44ed79fe54a99e9b8b55d/work/630b37f2f0d44ed79fe54a99e9b8b55d_id_rsa.pub
2026-04-13 16:35:31.905287 | instance -> localhost | The key fingerprint is:
2026-04-13 16:35:31.905317 | instance -> localhost | SHA256:hXgvhRNb/apxVNxgIIcD7QQzlsomW4RgLOkK5LX9ngo zuul-build-sshkey
2026-04-13 16:35:31.905362 | instance -> localhost | The key's randomart image is:
2026-04-13 16:35:31.905392 | instance -> localhost | +---[RSA 3072]----+
2026-04-13 16:35:31.905431 | instance -> localhost | | oo. .  B=oooooo |
2026-04-13 16:35:31.905461 | instance -> localhost | |oo... .o.O=o..o .|
2026-04-13 16:35:31.905490 | instance -> localhost | |+.. oo..*oo. o   |
2026-04-13 16:35:31.905538 | instance -> localhost | |.o ...=. =. . .  |
2026-04-13 16:35:31.905567 | instance -> localhost | |o    =. S .. .   |
2026-04-13 16:35:31.905594 | instance -> localhost | |.   .  . .. o    |
2026-04-13 16:35:31.905620 | instance -> localhost | |   E  . .  +     |
2026-04-13 16:35:31.905647 | instance -> localhost | |    .  o  .      |
2026-04-13 16:35:31.905678 | instance -> localhost | |     ..          |
2026-04-13 16:35:31.905706 | instance -> localhost | +----[SHA256]-----+
2026-04-13 16:35:31.905773 | instance -> localhost | ok: Runtime: 0:00:01.710105
2026-04-13 16:35:31.911327 | 
2026-04-13 16:35:31.911392 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-04-13 16:35:31.944641 | instance | ok
2026-04-13 16:35:31.954674 | instance | included: /var/lib/zuul/builds/630b37f2f0d44ed79fe54a99e9b8b55d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-04-13 16:35:31.962193 | 
2026-04-13 16:35:31.962259 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-04-13 16:35:31.986604 | instance | skipping: Conditional result was False
2026-04-13 16:35:31.996510 | 
2026-04-13 16:35:31.996576 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-04-13 16:35:32.456809 | instance | changed
2026-04-13 16:35:32.463796 | 
2026-04-13 16:35:32.463863 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-04-13 16:35:32.651199 | instance | ok
2026-04-13 16:35:32.657311 | 
2026-04-13 16:35:32.657375 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-04-13 16:35:33.137688 | instance | changed
2026-04-13 16:35:33.144057 | 
2026-04-13 16:35:33.144135 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-04-13 16:35:33.596455 | instance | changed
2026-04-13 16:35:33.601711 | 
2026-04-13 16:35:33.601779 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-04-13 16:35:33.626138 | instance | skipping: Conditional result was False
2026-04-13 16:35:33.637805 | 
2026-04-13 16:35:33.637874 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-04-13 16:35:33.966303 | instance -> localhost | changed
2026-04-13 16:35:34.011222 | 
2026-04-13 16:35:34.011354 | TASK [add-build-sshkey : Add back temp key]
2026-04-13 16:35:34.328622 | instance -> localhost | Identity added: /var/lib/zuul/builds/630b37f2f0d44ed79fe54a99e9b8b55d/work/630b37f2f0d44ed79fe54a99e9b8b55d_id_rsa (zuul-build-sshkey)
2026-04-13 16:35:34.328843 | instance -> localhost | ok: Runtime: 0:00:00.014885
2026-04-13 16:35:34.335007 | 
2026-04-13 16:35:34.335078 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-04-13 16:35:34.611981 | instance | ok
2026-04-13 16:35:34.619148 | 
2026-04-13 16:35:34.619239 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-04-13 16:35:34.645785 | instance | skipping: Conditional result was False
2026-04-13 16:35:34.660159 | 
2026-04-13 16:35:34.660240 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-04-13 16:35:34.948833 | instance | ok
2026-04-13 16:35:34.955113 | 
2026-04-13 16:35:34.955183 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-04-13 16:35:36.468262 | instance | Output suppressed because no_log was given
2026-04-13 16:35:36.479242 | 
2026-04-13 16:35:36.479313 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-04-13 16:35:36.697308 | instance | ok: "logs"
2026-04-13 16:35:36.697590 | instance | ok: All items complete
2026-04-13 16:35:36.697618 | 
2026-04-13 16:35:36.853341 | instance | ok: "artifacts"
2026-04-13 16:35:37.013812 | instance | ok: "docs"
2026-04-13 16:35:37.029795 | 
2026-04-13 16:35:37.029981 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-04-13 16:35:37.215935 | instance | changed: "logs"
2026-04-13 16:35:37.371519 | instance | changed: "artifacts"
2026-04-13 16:35:37.527908 | instance | changed: "docs"
2026-04-13 16:35:37.547652 | 
2026-04-13 16:35:37.547818 | PLAY RECAP
2026-04-13 16:35:37.547868 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-04-13 16:35:37.547898 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-13 16:35:37.547922 | 
2026-04-13 16:35:37.718881 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-13 16:35:37.727946 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-13 16:35:38.286288 | 
2026-04-13 16:35:38.286406 | PLAY [all]
2026-04-13 16:35:38.297838 | 
2026-04-13 16:35:38.297932 | TASK [setup-uv : Extract archive]
2026-04-13 16:35:40.442099 | instance | changed
2026-04-13 16:35:40.449608 | 
2026-04-13 16:35:40.449690 | TASK [setup-uv : Print version]
2026-04-13 16:35:40.596248 | instance | uv 0.8.13
2026-04-13 16:35:40.981587 | instance | ok: Runtime: 0:00:00.012650
2026-04-13 16:35:40.988583 | 
2026-04-13 16:35:40.988631 | PLAY RECAP
2026-04-13 16:35:40.988676 | instance | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-13 16:35:40.988699 | 
2026-04-13 16:35:41.085880 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-13 16:35:41.097148 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/pre.yml@main]
2026-04-13 16:35:41.670604 | 
2026-04-13 16:35:41.671037 | PLAY [all]
2026-04-13 16:35:41.682155 | 
2026-04-13 16:35:41.682236 | TASK [Install "jq" for log collection]
2026-04-13 16:35:52.669420 | instance | changed
2026-04-13 16:35:52.676791 | 
2026-04-13 16:35:52.676926 | TASK [Install Python "kubernetes" library for kubernetes.core modules]
2026-04-13 16:35:53.130592 | instance | ERROR
2026-04-13 16:35:53.130953 | instance | {
2026-04-13 16:35:53.130996 | instance |   "msg": "Unable to find any of pip3 to use.  pip needs to be installed."
2026-04-13 16:35:53.131027 | instance | }
failure
2026-04-13 16:35:53.132449 | 
2026-04-13 16:35:53.132511 | PLAY RECAP
2026-04-13 16:35:53.132572 | instance | ok: 1 changed: 1 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-04-13 16:35:53.132601 | 
2026-04-13 16:35:53.265484 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/pre.yml@main]
2026-04-13 16:35:53.277078 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@main]
2026-04-13 16:35:53.930147 | 
2026-04-13 16:35:53.930521 | PLAY [all]
2026-04-13 16:35:53.945505 | 
2026-04-13 16:35:53.945600 | TASK [gather-host-logs : creating directory for system status]
2026-04-13 16:35:54.269781 | instance | changed
2026-04-13 16:35:54.276112 | 
2026-04-13 16:35:54.276199 | TASK [gather-host-logs : Get logs for each host]
2026-04-13 16:35:54.594904 | instance | + systemd-cgls --full --all --no-pager
2026-04-13 16:35:54.608291 | instance | + ip addr
2026-04-13 16:35:54.611974 | instance | + ip route
2026-04-13 16:35:54.614966 | instance | + lsblk
2026-04-13 16:35:54.618079 | instance | + mount
2026-04-13 16:35:54.620858 | instance | + docker images
2026-04-13 16:35:54.621420 | instance | /bin/bash: line 7: docker: command not found
2026-04-13 16:35:54.621702 | instance | + brctl show
2026-04-13 16:35:54.622167 | instance | /bin/bash: line 8: brctl: command not found
2026-04-13 16:35:54.622444 | instance | + ps aux --sort=-%mem
2026-04-13 16:35:54.640700 | instance | + dpkg -l
2026-04-13 16:35:54.649056 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul))
2026-04-13 16:35:54.649486 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul
2026-04-13 16:35:54.649547 | instance | /bin/bash: line 11: docker: command not found
2026-04-13 16:35:54.649851 | instance | + '[' '!' -z '' ']'
2026-04-13 16:35:54.808339 | instance | ok: Runtime: 0:00:00.060799
2026-04-13 16:35:54.814453 | 
2026-04-13 16:35:54.814525 | TASK [gather-host-logs : Downloads logs to executor]
2026-04-13 16:35:55.424231 | instance | changed:
2026-04-13 16:35:55.424395 | instance | created directory /var/lib/zuul/builds/630b37f2f0d44ed79fe54a99e9b8b55d/work/logs/instance
2026-04-13 16:35:55.424425 | instance | cd+++++++++ system/
2026-04-13 16:35:55.424448 | instance | >f+++++++++ system/brctl-show.txt
2026-04-13 16:35:55.424469 | instance | >f+++++++++ system/docker-images.txt
2026-04-13 16:35:55.424489 | instance | >f+++++++++ system/ip-addr.txt
2026-04-13 16:35:55.424512 | instance | >f+++++++++ system/ip-route.txt
2026-04-13 16:35:55.424535 | instance | >f+++++++++ system/lsblk.txt
2026-04-13 16:35:55.424556 | instance | >f+++++++++ system/mount.txt
2026-04-13 16:35:55.424578 | instance | >f+++++++++ system/packages.txt
2026-04-13 16:35:55.424598 | instance | >f+++++++++ system/ps.txt
2026-04-13 16:35:55.424617 | instance | >f+++++++++ system/systemd-cgls.txt
2026-04-13 16:35:55.433775 | 
2026-04-13 16:35:55.433841 | LOOP [helm-release-status : creating directory for helm release status]
2026-04-13 16:35:55.629092 | instance | changed: "values"
2026-04-13 16:35:55.789360 | instance | changed: "releases"
2026-04-13 16:35:55.807967 | 
2026-04-13 16:35:55.808127 | TASK [helm-release-status : Gather get release status for helm charts]
2026-04-13 16:35:55.984263 | instance | /bin/bash: line 3: kubectl: command not found
2026-04-13 16:35:56.342106 | instance | ok: Runtime: 0:00:00.006159
2026-04-13 16:35:56.349497 | 
2026-04-13 16:35:56.349594 | TASK [helm-release-status : Downloads logs to executor]
2026-04-13 16:35:56.808978 | instance | changed:
2026-04-13 16:35:56.809188 | instance | cd+++++++++ helm/
2026-04-13 16:35:56.809237 | instance | cd+++++++++ helm/releases/
2026-04-13 16:35:56.809272 | instance | cd+++++++++ helm/values/
2026-04-13 16:35:56.822788 | 
2026-04-13 16:35:56.822881 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-04-13 16:35:57.022586 | instance | changed
2026-04-13 16:35:57.053972 | 
2026-04-13 16:35:57.054804 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-04-13 16:35:57.238788 | instance | xargs: xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args valuewarning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-04-13 16:35:57.238932 | instance |
2026-04-13 16:35:57.245251 | instance | environment: line 1: kubectl: command not found
2026-04-13 16:35:57.246963 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-04-13 16:35:57.247090 | instance | environment: line 1: kubectl: command not found
2026-04-13 16:35:57.248427 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-04-13 16:35:57.250600 | instance | environment: line 1: kubectl: command not found
2026-04-13 16:35:57.251954 | instance | environment: line 1: kubectl: command not found
2026-04-13 16:35:57.252217 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-04-13 16:35:57.253232 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-04-13 16:35:57.255100 | instance | environment: line 1: kubectl: command not found
2026-04-13 16:35:57.256533 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-04-13 16:35:57.589249 | instance | ok: Runtime: 0:00:00.029040
2026-04-13 16:35:57.597367 | 
2026-04-13 16:35:57.597431 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-04-13 16:35:57.802310 | instance | changed
2026-04-13 16:35:57.809391 | 
2026-04-13 16:35:57.809491 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-04-13 16:35:57.986237 | instance | environment: line 5: kubectl: command not found
2026-04-13 16:35:57.987348 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-04-13 16:35:57.987549 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-04-13 16:35:57.987586 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-04-13 16:35:58.346614 | instance | ok: Runtime: 0:00:00.009389
2026-04-13 16:35:58.354447 | 
2026-04-13 16:35:58.354520 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-04-13 16:35:58.822186 | instance | changed:
2026-04-13 16:35:58.822411 | instance | cd+++++++++ objects/
2026-04-13 16:35:58.822452 | instance | cd+++++++++ objects/cluster/
2026-04-13 16:35:58.822482 | instance | cd+++++++++ objects/namespaced/
2026-04-13 16:35:58.835219 | 
2026-04-13 16:35:58.835301 | TASK [gather-pod-logs : creating directory for pod logs]
2026-04-13 16:35:59.062188 | instance | changed
2026-04-13 16:35:59.067150 | 
2026-04-13 16:35:59.067213 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-04-13 16:35:59.260438 | instance | changed
2026-04-13 16:35:59.265486 | 
2026-04-13 16:35:59.265558 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-04-13 16:35:59.441117 | instance | environment: line 3: kubectl: command not found
2026-04-13 16:35:59.799381 | instance | ok: Runtime: 0:00:00.009017
2026-04-13 16:35:59.806121 | 
2026-04-13 16:35:59.806206 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-04-13 16:36:00.274451 | instance | changed:
2026-04-13 16:36:00.274636 | instance | cd+++++++++ pod-logs/
2026-04-13 16:36:00.274675 | instance | cd+++++++++ pod-logs/failed-pods/
2026-04-13 16:36:00.286826 | 
2026-04-13 16:36:00.286891 | TASK [gather-prom-metrics : creating directory for helm release descriptions]
2026-04-13 16:36:00.483360 | instance | changed
2026-04-13 16:36:00.489803 | 
2026-04-13 16:36:00.489898 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces]
2026-04-13 16:36:00.680925 | instance | /bin/bash: line 2: kubectl: command not found
2026-04-13 16:36:01.024798 | instance | ok: Runtime: 0:00:00.037678
2026-04-13 16:36:01.032009 | 
2026-04-13 16:36:01.032074 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr]
2026-04-13 16:36:01.219585 | instance | /bin/bash: line 2: kubectl: command not found
2026-04-13 16:36:01.252128 | instance | ceph-mgr endpoints:
2026-04-13 16:36:01.565957 | instance | ok: Runtime: 0:00:00.039531
2026-04-13 16:36:01.571309 | 
2026-04-13 16:36:01.571377 | TASK [gather-prom-metrics : Get metrics from fluentd pods]
2026-04-13 16:36:01.757346 | instance | /bin/bash: line 4: kubectl: command not found
2026-04-13 16:36:02.105568 | instance | ok: Runtime: 0:00:00.034850
2026-04-13 16:36:02.112747 | 
2026-04-13 16:36:02.112834 | TASK [gather-prom-metrics : Downloads logs to executor]
2026-04-13 16:36:02.587362 | instance | changed: cd+++++++++ prometheus/
2026-04-13 16:36:02.601933 | 
2026-04-13 16:36:02.602000 | TASK [gather-selenium-data : creating directory for helm release descriptions]
2026-04-13 16:36:02.844182 | instance | changed
2026-04-13 16:36:02.849327 | 
2026-04-13 16:36:02.849390 | TASK [gather-selenium-data : Get selenium data]
2026-04-13 16:36:03.040266 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/.
2026-04-13 16:36:03.042464 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory
2026-04-13 16:36:03.381778 | instance | ERROR
2026-04-13 16:36:03.382017 | instance | {
2026-04-13 16:36:03.382064 | instance |   "delta": "0:00:00.006675",
2026-04-13 16:36:03.382113 | instance |   "end": "2026-04-13 16:36:03.042937",
2026-04-13 16:36:03.382141 | instance |   "msg": "non-zero return code",
2026-04-13 16:36:03.382167 | instance |   "rc": 1,
2026-04-13 16:36:03.382192 | instance |   "start": "2026-04-13 16:36:03.036262"
2026-04-13 16:36:03.382217 | instance | }
2026-04-13 16:36:03.382249 | instance | ERROR: Ignoring Errors
2026-04-13 16:36:03.388882 | 
2026-04-13 16:36:03.388993 | TASK [gather-selenium-data : Downloads logs to executor]
2026-04-13 16:36:03.868243 | instance | changed: cd+++++++++ selenium/
2026-04-13 16:36:03.876078 | 
2026-04-13 16:36:03.876149 | PLAY RECAP
2026-04-13 16:36:03.876211 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1
2026-04-13 16:36:03.876243 | 
2026-04-13 16:36:04.007083 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@main]
2026-04-13 16:36:04.021291 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-13 16:36:04.623251 | 
2026-04-13 16:36:04.623380 | PLAY [all]
2026-04-13 16:36:04.634526 | 
2026-04-13 16:36:04.634608 | TASK [fetch-output : Set log path for multiple nodes]
2026-04-13 16:36:04.680034 | instance | skipping: Conditional result was False
2026-04-13 16:36:04.691139 | 
2026-04-13 16:36:04.691252 | TASK [fetch-output : Set log path for single node]
2026-04-13 16:36:04.735849 | instance | ok
2026-04-13 16:36:04.742159 | 
2026-04-13 16:36:04.742251 | LOOP [fetch-output : Ensure local output dirs]
2026-04-13 16:36:05.101104 | instance -> localhost | ok: "/var/lib/zuul/builds/630b37f2f0d44ed79fe54a99e9b8b55d/work/logs"
2026-04-13 16:36:05.301932 | instance -> localhost | changed: "/var/lib/zuul/builds/630b37f2f0d44ed79fe54a99e9b8b55d/work/artifacts"
2026-04-13 16:36:05.522969 | instance -> localhost | changed: "/var/lib/zuul/builds/630b37f2f0d44ed79fe54a99e9b8b55d/work/docs"
2026-04-13 16:36:05.539025 | 
2026-04-13 16:36:05.539130 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-04-13 16:36:06.152852 | instance | changed: .d..t...... ./
2026-04-13 16:36:06.153148 | instance | changed: All items complete
2026-04-13 16:36:06.153190 | 
2026-04-13 16:36:06.641351 | instance | changed: .d..t...... ./
2026-04-13 16:36:07.109209 | instance | changed: .d..t...... ./
2026-04-13 16:36:07.134230 | 
2026-04-13 16:36:07.134370 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-04-13 16:36:07.537351 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.007958
2026-04-13 16:36:07.752907 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.007733
2026-04-13 16:36:07.772376 | 
2026-04-13 16:36:07.772858 | PLAY [all]
2026-04-13 16:36:07.779988 | 
2026-04-13 16:36:07.780052 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-04-13 16:36:08.239583 | instance | changed
2026-04-13 16:36:08.245133 | 
2026-04-13 16:36:08.245184 | PLAY RECAP
2026-04-13 16:36:08.245228 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-04-13 16:36:08.245249 | 
2026-04-13 16:36:08.359819 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-13 16:36:08.373591 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-04-13 16:36:08.957610 | 
2026-04-13 16:36:08.957727 | PLAY [localhost]
2026-04-13 16:36:08.967043 | 
2026-04-13 16:36:08.967113 | TASK [Generate Zuul manifest]
2026-04-13 16:36:08.985699 | localhost | ok
2026-04-13 16:36:09.002506 | 
2026-04-13 16:36:09.002581 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-04-13 16:36:09.371364 | localhost | changed
2026-04-13 16:36:09.384672 | 
2026-04-13 16:36:09.384742 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-04-13 16:36:09.416739 | localhost | ok
2026-04-13 16:36:09.425489 | 
2026-04-13 16:36:09.425574 | TASK [Upload logs]
2026-04-13 16:36:09.446317 | localhost | ok
2026-04-13 16:36:09.542894 | 
2026-04-13 16:36:09.543025 | TASK [Set zuul-log-path fact]
2026-04-13 16:36:09.565175 | localhost | ok
2026-04-13 16:36:09.577295 | 
2026-04-13 16:36:09.577364 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-13 16:36:09.609035 | localhost | ok
2026-04-13 16:36:09.618862 | 
2026-04-13 16:36:09.618983 | TASK [upload-logs : Create log directories]
2026-04-13 16:36:09.979783 | localhost | changed
2026-04-13 16:36:09.984423 | 
2026-04-13 16:36:09.984493 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-04-13 16:36:10.340499 | localhost -> localhost | ok: Runtime: 0:00:00.005137
2026-04-13 16:36:10.347015 | 
2026-04-13 16:36:10.347103 | TASK [upload-logs : Upload logs to log server]
2026-04-13 16:36:10.884763 | localhost | Output suppressed because no_log was given
2026-04-13 16:36:10.889368 | 
2026-04-13 16:36:10.889436 | LOOP [upload-logs : Compress console log and json output]
2026-04-13 16:36:10.942251 | localhost | skipping: Conditional result was False
2026-04-13 16:36:10.949755 | localhost | skipping: Conditional result was False
2026-04-13 16:36:10.964871 | 
2026-04-13 16:36:10.965180 | LOOP [upload-logs : Upload compressed console log and json output]
2026-04-13 16:36:11.009200 | localhost | skipping: Conditional result was False
2026-04-13 16:36:11.009569 | 
2026-04-13 16:36:11.013204 | localhost | skipping: Conditional result was False
2026-04-13 16:36:11.024890 | 
2026-04-13 16:36:11.025019 | LOOP [upload-logs : Upload console log and json output]