2026-04-16 06:33:35.503615 | Job console starting
2026-04-16 06:33:35.515160 | Updating git repos
2026-04-16 06:33:35.669402 | Cloning repos into workspace
2026-04-16 06:33:36.393047 | Restoring repo states
2026-04-16 06:33:36.413689 | Merging changes
2026-04-16 06:33:38.327720 | Checking out repos
2026-04-16 06:33:38.764203 | Preparing playbooks
2026-04-16 06:33:52.480429 | Running Ansible setup
2026-04-16 06:33:56.413440 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-16 06:33:57.070698 | 
2026-04-16 06:33:57.070907 | PLAY [localhost]
2026-04-16 06:33:57.080252 | 
2026-04-16 06:33:57.080330 | TASK [Gathering Facts]
2026-04-16 06:33:57.959115 | localhost | ok
2026-04-16 06:33:57.969740 | 
2026-04-16 06:33:57.969840 | TASK [Setup log path fact]
2026-04-16 06:33:57.995481 | localhost | ok
2026-04-16 06:33:58.010518 | 
2026-04-16 06:33:58.010583 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-16 06:33:58.040694 | localhost | ok
2026-04-16 06:33:58.048892 | 
2026-04-16 06:33:58.048975 | TASK [emit-job-header : Print job information]
2026-04-16 06:33:58.091398 | # Job Information
2026-04-16 06:33:58.091682 | Ansible Version: 2.16.16
2026-04-16 06:33:58.091759 | Job: atmosphere-molecule-aio-openvswitch
2026-04-16 06:33:58.091815 | Pipeline: check
2026-04-16 06:33:58.091866 | Executor: 0a8996d2b663
2026-04-16 06:33:58.091912 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3834
2026-04-16 06:33:58.091968 | Event ID: 0ea83610-395e-11f1-85e6-c624545eaabd
2026-04-16 06:33:58.095305 | 
2026-04-16 06:33:58.095374 | LOOP [emit-job-header : Print node information]
2026-04-16 06:33:58.196197 | localhost | ok:
2026-04-16 06:33:58.196494 | localhost | # Node Information
2026-04-16 06:33:58.196546 | localhost | Inventory Hostname: instance
2026-04-16 06:33:58.196583 | localhost | Hostname: np0000166628
2026-04-16 06:33:58.196618 | localhost | Username: zuul
2026-04-16 06:33:58.196649 | localhost | Distro: Ubuntu 22.04
2026-04-16 06:33:58.196675 | localhost | Provider: yul1
2026-04-16 06:33:58.196701 | localhost | Region: ca-ymq-1
2026-04-16 06:33:58.196728 | localhost | Label: ubuntu-jammy-16
2026-04-16 06:33:58.196758 | localhost | Product Name: OpenStack Nova
2026-04-16 06:33:58.196788 | localhost | Interface IP: 199.204.45.240
2026-04-16 06:33:58.214726 | 
2026-04-16 06:33:58.214886 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-04-16 06:33:58.605717 | localhost -> localhost | changed
2026-04-16 06:33:58.614757 | 
2026-04-16 06:33:58.614890 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-04-16 06:33:59.704812 | localhost -> localhost | changed
2026-04-16 06:33:59.714333 | 
2026-04-16 06:33:59.714466 | PLAY [all]
2026-04-16 06:33:59.723464 | 
2026-04-16 06:33:59.723615 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-04-16 06:33:59.962985 | instance -> localhost | ok
2026-04-16 06:33:59.970705 | 
2026-04-16 06:33:59.970774 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-04-16 06:34:00.001387 | instance | ok
2026-04-16 06:34:00.015507 | instance | included: /var/lib/zuul/builds/01217327233d406285fc834edb941efb/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-04-16 06:34:00.021403 | 
2026-04-16 06:34:00.021468 | TASK [add-build-sshkey : Create Temp SSH key]
2026-04-16 06:34:00.856354 | instance -> localhost | Generating public/private rsa key pair.
2026-04-16 06:34:00.856593 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/01217327233d406285fc834edb941efb/work/01217327233d406285fc834edb941efb_id_rsa
2026-04-16 06:34:00.856636 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/01217327233d406285fc834edb941efb/work/01217327233d406285fc834edb941efb_id_rsa.pub
2026-04-16 06:34:00.856670 | instance -> localhost | The key fingerprint is:
2026-04-16 06:34:00.856701 | instance -> localhost | SHA256:mXl+iaTll5zsiw+jdrreZyk99NA7YyFg4OyZ4aqzqI0 zuul-build-sshkey
2026-04-16 06:34:00.856751 | instance -> localhost | The key's randomart image is:
2026-04-16 06:34:00.856782 | instance -> localhost | +---[RSA 3072]----+
2026-04-16 06:34:00.856819 | instance -> localhost | |                 |
2026-04-16 06:34:00.856849 | instance -> localhost | |        .        |
2026-04-16 06:34:00.856877 | instance -> localhost | |       o .       |
2026-04-16 06:34:00.856905 | instance -> localhost | |        ++o      |
2026-04-16 06:34:00.856933 | instance -> localhost | |       oS=+. .   |
2026-04-16 06:34:00.856986 | instance -> localhost | |        =B +++o  |
2026-04-16 06:34:00.857020 | instance -> localhost | |       .. *oO= o |
2026-04-16 06:34:00.857052 | instance -> localhost | | o .. . .ooB* *  |
2026-04-16 06:34:00.857084 | instance -> localhost | |E.o o+ o=+o=++ o |
2026-04-16 06:34:00.857111 | instance -> localhost | +----[SHA256]-----+
2026-04-16 06:34:00.857180 | instance -> localhost | ok: Runtime: 0:00:00.359754
2026-04-16 06:34:00.863798 | 
2026-04-16 06:34:00.863873 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-04-16 06:34:00.898691 | instance | ok
2026-04-16 06:34:00.907470 | instance | included: /var/lib/zuul/builds/01217327233d406285fc834edb941efb/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-04-16 06:34:00.915144 | 
2026-04-16 06:34:00.915211 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-04-16 06:34:00.940791 | instance | skipping: Conditional result was False
2026-04-16 06:34:00.951090 | 
2026-04-16 06:34:00.951192 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-04-16 06:34:01.447876 | instance | changed
2026-04-16 06:34:01.455881 | 
2026-04-16 06:34:01.456018 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-04-16 06:34:01.639589 | instance | ok
2026-04-16 06:34:01.645028 | 
2026-04-16 06:34:01.645101 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-04-16 06:34:02.123140 | instance | changed
2026-04-16 06:34:02.128448 | 
2026-04-16 06:34:02.128519 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-04-16 06:34:02.641526 | instance | changed
2026-04-16 06:34:02.650683 | 
2026-04-16 06:34:02.650772 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-04-16 06:34:02.676498 | instance | skipping: Conditional result was False
2026-04-16 06:34:02.738699 | 
2026-04-16 06:34:02.738892 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-04-16 06:34:03.065764 | instance -> localhost | changed
2026-04-16 06:34:03.084110 | 
2026-04-16 06:34:03.084380 | TASK [add-build-sshkey : Add back temp key]
2026-04-16 06:34:03.384932 | instance -> localhost | Identity added: /var/lib/zuul/builds/01217327233d406285fc834edb941efb/work/01217327233d406285fc834edb941efb_id_rsa (zuul-build-sshkey)
2026-04-16 06:34:03.385202 | instance -> localhost | ok: Runtime: 0:00:00.014749
2026-04-16 06:34:03.390882 | 
2026-04-16 06:34:03.390963 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-04-16 06:34:03.693209 | instance | ok
2026-04-16 06:34:03.700049 | 
2026-04-16 06:34:03.700152 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-04-16 06:34:03.726266 | instance | skipping: Conditional result was False
2026-04-16 06:34:03.742502 | 
2026-04-16 06:34:03.742634 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-04-16 06:34:04.036750 | instance | ok
2026-04-16 06:34:04.044230 | 
2026-04-16 06:34:04.044313 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-04-16 06:34:05.613027 | instance | Output suppressed because no_log was given
2026-04-16 06:34:05.634675 | 
2026-04-16 06:34:05.634899 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-04-16 06:34:05.828721 | instance | ok: "logs"
2026-04-16 06:34:05.829013 | instance | ok: All items complete
2026-04-16 06:34:05.829060 | 
2026-04-16 06:34:05.980282 | instance | ok: "artifacts"
2026-04-16 06:34:06.156582 | instance | ok: "docs"
2026-04-16 06:34:06.178221 | 
2026-04-16 06:34:06.178414 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-04-16 06:34:06.397099 | instance | changed: "logs"
2026-04-16 06:34:06.567011 | instance | changed: "artifacts"
2026-04-16 06:34:06.728116 | instance | changed: "docs"
2026-04-16 06:34:06.752591 | 
2026-04-16 06:34:06.752752 | PLAY RECAP
2026-04-16 06:34:06.752819 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-04-16 06:34:06.752860 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-16 06:34:06.752890 | 
2026-04-16 06:34:06.919071 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-16 06:34:06.930263 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-16 06:34:07.551142 | 
2026-04-16 06:34:07.551657 | PLAY [all]
2026-04-16 06:34:07.565018 | 
2026-04-16 06:34:07.565167 | TASK [setup-uv : Extract archive]
2026-04-16 06:34:09.899594 | instance | changed
2026-04-16 06:34:09.907987 | 
2026-04-16 06:34:09.908104 | TASK [setup-uv : Print version]
2026-04-16 06:34:10.265599 | instance | uv 0.8.13
2026-04-16 06:34:10.451524 | instance | ok: Runtime: 0:00:00.013541
2026-04-16 06:34:10.464735 | 
2026-04-16 06:34:10.465100 | PLAY RECAP
2026-04-16 06:34:10.465377 | instance | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-16 06:34:10.465413 | 
2026-04-16 06:34:10.646620 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-16 06:34:10.653498 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/pre.yml@main]
2026-04-16 06:34:11.274718 | 
2026-04-16 06:34:11.274856 | PLAY [all]
2026-04-16 06:34:11.287102 | 
2026-04-16 06:34:11.287211 | TASK [Install "jq" for log collection]
2026-04-16 06:44:58.857316 | instance | ERROR
2026-04-16 06:44:58.857645 | instance | {
2026-04-16 06:44:58.857685 | instance |   "msg": "Failed to update apt cache: unknown reason"
2026-04-16 06:44:58.857715 | instance | }
failure
2026-04-16 06:44:58.859236 | 
2026-04-16 06:44:58.859284 | PLAY RECAP
2026-04-16 06:44:58.859332 | instance | ok: 0 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-04-16 06:44:58.859356 | 
2026-04-16 06:44:59.015449 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/pre.yml@main]
2026-04-16 06:44:59.022328 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@main]
2026-04-16 06:44:59.691166 | 
2026-04-16 06:44:59.691325 | PLAY [all]
2026-04-16 06:44:59.705930 | 
2026-04-16 06:44:59.706073 | TASK [gather-host-logs : creating directory for system status]
2026-04-16 06:45:00.047309 | instance | changed
2026-04-16 06:45:00.052788 | 
2026-04-16 06:45:00.052871 | TASK [gather-host-logs : Get logs for each host]
2026-04-16 06:45:00.403073 | instance | + systemd-cgls --full --all --no-pager
2026-04-16 06:45:00.415244 | instance | + ip addr
2026-04-16 06:45:00.417647 | instance | + ip route
2026-04-16 06:45:00.419779 | instance | + lsblk
2026-04-16 06:45:00.422883 | instance | + mount
2026-04-16 06:45:00.424639 | instance | + docker images
2026-04-16 06:45:00.425105 | instance | /bin/bash: line 7: docker: command not found
2026-04-16 06:45:00.425301 | instance | + brctl show
2026-04-16 06:45:00.425711 | instance | /bin/bash: line 8: brctl: command not found
2026-04-16 06:45:00.425977 | instance | + ps aux --sort=-%mem
2026-04-16 06:45:00.441229 | instance | + dpkg -l
2026-04-16 06:45:00.452780 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul))
2026-04-16 06:45:00.453258 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul
2026-04-16 06:45:00.453292 | instance | /bin/bash: line 11: docker: command not found
2026-04-16 06:45:00.453638 | instance | + '[' '!' -z '' ']'
2026-04-16 06:45:00.590385 | instance | ok: Runtime: 0:00:00.057087
2026-04-16 06:45:00.599685 | 
2026-04-16 06:45:00.599806 | TASK [gather-host-logs : Downloads logs to executor]
2026-04-16 06:45:01.285048 | instance | changed:
2026-04-16 06:45:01.285282 | instance | created directory /var/lib/zuul/builds/01217327233d406285fc834edb941efb/work/logs/instance
2026-04-16 06:45:01.285325 | instance | cd+++++++++ system/
2026-04-16 06:45:01.285357 | instance | >f+++++++++ system/brctl-show.txt
2026-04-16 06:45:01.285388 | instance | >f+++++++++ system/docker-images.txt
2026-04-16 06:45:01.285417 | instance | >f+++++++++ system/ip-addr.txt
2026-04-16 06:45:01.285450 | instance | >f+++++++++ system/ip-route.txt
2026-04-16 06:45:01.285483 | instance | >f+++++++++ system/lsblk.txt
2026-04-16 06:45:01.285513 | instance | >f+++++++++ system/mount.txt
2026-04-16 06:45:01.285545 | instance | >f+++++++++ system/packages.txt
2026-04-16 06:45:01.285574 | instance | >f+++++++++ system/ps.txt
2026-04-16 06:45:01.285602 | instance | >f+++++++++ system/systemd-cgls.txt
2026-04-16 06:45:01.296187 | 
2026-04-16 06:45:01.296312 | LOOP [helm-release-status : creating directory for helm release status]
2026-04-16 06:45:01.490332 | instance | changed: "values"
2026-04-16 06:45:01.659399 | instance | changed: "releases"
2026-04-16 06:45:01.674419 | 
2026-04-16 06:45:01.674613 | TASK [helm-release-status : Gather get release status for helm charts]
2026-04-16 06:45:01.878008 | instance | /bin/bash: line 3: kubectl: command not found
2026-04-16 06:45:02.213435 | instance | ok: Runtime: 0:00:00.006220
2026-04-16 06:45:02.219576 | 
2026-04-16 06:45:02.219651 | TASK [helm-release-status : Downloads logs to executor]
2026-04-16 06:45:02.722520 | instance | changed:
2026-04-16 06:45:02.722808 | instance | cd+++++++++ helm/
2026-04-16 06:45:02.722871 | instance | cd+++++++++ helm/releases/
2026-04-16 06:45:02.722918 | instance | cd+++++++++ helm/values/
2026-04-16 06:45:02.732298 | 
2026-04-16 06:45:02.732378 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-04-16 06:45:02.971636 | instance | changed
2026-04-16 06:45:02.977113 | 
2026-04-16 06:45:02.977195 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-04-16 06:45:03.186439 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-04-16 06:45:03.186535 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-04-16 06:45:03.192155 | instance | environment: line 1: kubectl: command not found
2026-04-16 06:45:03.193553 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-04-16 06:45:03.194668 | instance | environment: line 1: kubectl: command not found
2026-04-16 06:45:03.196665 | instance | environment: line 1: kubectl: command not found
2026-04-16 06:45:03.196690 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-04-16 06:45:03.197894 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-04-16 06:45:03.199066 | instance | environment: line 1: kubectl: command not found
2026-04-16 06:45:03.200833 | instance | environment: line 1: kubectl: command not found
2026-04-16 06:45:03.201090 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-04-16 06:45:03.201911 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-04-16 06:45:03.513909 | instance | ok: Runtime: 0:00:00.025274
2026-04-16 06:45:03.520049 | 
2026-04-16 06:45:03.520118 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-04-16 06:45:03.715903 | instance | changed
2026-04-16 06:45:03.723056 | 
2026-04-16 06:45:03.723139 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-04-16 06:45:03.954528 | instance | environment: line 5: kubectl: command not found
2026-04-16 06:45:03.955463 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-04-16 06:45:03.955498 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-04-16 06:45:03.955513 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-04-16 06:45:04.263555 | instance | ok: Runtime: 0:00:00.010253
2026-04-16 06:45:04.269445 | 
2026-04-16 06:45:04.269568 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-04-16 06:45:04.772880 | instance | changed:
2026-04-16 06:45:04.773125 | instance | cd+++++++++ objects/
2026-04-16 06:45:04.773166 | instance | cd+++++++++ objects/cluster/
2026-04-16 06:45:04.773199 | instance | cd+++++++++ objects/namespaced/
2026-04-16 06:45:04.785935 | 
2026-04-16 06:45:04.786018 | TASK [gather-pod-logs : creating directory for pod logs]
2026-04-16 06:45:04.990411 | instance | changed
2026-04-16 06:45:04.996727 | 
2026-04-16 06:45:04.996811 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-04-16 06:45:05.193430 | instance | changed
2026-04-16 06:45:05.199835 | 
2026-04-16 06:45:05.213443 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-04-16 06:45:05.420550 | instance | environment: line 3: kubectl: command not found
2026-04-16 06:45:05.757267 | instance | ok: Runtime: 0:00:00.008182
2026-04-16 06:45:05.763089 | 
2026-04-16 06:45:05.763161 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-04-16 06:45:06.253270 | instance | changed:
2026-04-16 06:45:06.253511 | instance | cd+++++++++ pod-logs/
2026-04-16 06:45:06.253553 | instance | cd+++++++++ pod-logs/failed-pods/
2026-04-16 06:45:06.264468 | 
2026-04-16 06:45:06.264806 | TASK [gather-prom-metrics : creating directory for helm release descriptions]
2026-04-16 06:45:06.452855 | instance | changed
2026-04-16 06:45:06.457986 | 
2026-04-16 06:45:06.458063 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces]
2026-04-16 06:45:06.672375 | instance | /bin/bash: line 2: kubectl: command not found
2026-04-16 06:45:06.672517 | instance | /bin/bash: line 2: jq: command not found
2026-04-16 06:45:06.997278 | instance | ERROR
2026-04-16 06:45:06.997607 | instance | {
2026-04-16 06:45:06.997670 | instance |   "delta": "0:00:00.006310",
2026-04-16 06:45:06.997711 | instance |   "end": "2026-04-16 06:45:06.673362",
2026-04-16 06:45:06.997749 | instance |   "msg": "non-zero return code",
2026-04-16 06:45:06.997789 | instance |   "rc": 127,
2026-04-16 06:45:06.997826 | instance |   "start": "2026-04-16 06:45:06.667052"
2026-04-16 06:45:06.997866 | instance | }
2026-04-16 06:45:06.997915 | instance | ERROR: Ignoring Errors
2026-04-16 06:45:07.004121 | 
2026-04-16 06:45:07.004199 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr]
2026-04-16 06:45:07.222663 | instance | /bin/bash: line 2: jq: command not found
2026-04-16 06:45:07.222776 | instance | /bin/bash: line 2: kubectl: command not found
2026-04-16 06:45:07.543513 | instance | ERROR
2026-04-16 06:45:07.543776 | instance | {
2026-04-16 06:45:07.543831 | instance |   "delta": "0:00:00.007494",
2026-04-16 06:45:07.543870 | instance |   "end": "2026-04-16 06:45:07.224017",
2026-04-16 06:45:07.543904 | instance |   "msg": "non-zero return code",
2026-04-16 06:45:07.543939 | instance |   "rc": 127,
2026-04-16 06:45:07.543973 | instance |   "start": "2026-04-16 06:45:07.216523"
2026-04-16 06:45:07.544002 | instance | }
2026-04-16 06:45:07.544040 | instance | ERROR: Ignoring Errors
2026-04-16 06:45:07.551223 | 
2026-04-16 06:45:07.551340 | TASK [gather-prom-metrics : Get metrics from fluentd pods]
2026-04-16 06:45:07.760974 | instance | /bin/bash: line 4: jq: command not found
2026-04-16 06:45:07.761082 | instance | /bin/bash: line 4: kubectl: command not found
2026-04-16 06:45:08.093605 | instance | ERROR
2026-04-16 06:45:08.093759 | instance | {
2026-04-16 06:45:08.093803 | instance |   "delta": "0:00:00.007685",
2026-04-16 06:45:08.093834 | instance |   "end": "2026-04-16 06:45:07.762423",
2026-04-16 06:45:08.093861 | instance |   "msg": "non-zero return code",
2026-04-16 06:45:08.093887 | instance |   "rc": 127,
2026-04-16 06:45:08.093913 | instance |   "start": "2026-04-16 06:45:07.754738"
2026-04-16 06:45:08.093938 | instance | }
2026-04-16 06:45:08.093971 | instance | ERROR: Ignoring Errors
2026-04-16 06:45:08.100240 | 
2026-04-16 06:45:08.100328 | TASK [gather-prom-metrics : Downloads logs to executor]
2026-04-16 06:45:08.580666 | instance | changed: cd+++++++++ prometheus/
2026-04-16 06:45:08.590851 | 
2026-04-16 06:45:08.590946 | TASK [gather-selenium-data : creating directory for helm release descriptions]
2026-04-16 06:45:08.854784 | instance | changed
2026-04-16 06:45:08.860416 | 
2026-04-16 06:45:08.860483 | TASK [gather-selenium-data : Get selenium data]
2026-04-16 06:45:09.061643 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/.
2026-04-16 06:45:09.063596 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory
2026-04-16 06:45:09.397101 | instance | ERROR
2026-04-16 06:45:09.397388 | instance | {
2026-04-16 06:45:09.397454 | instance |   "delta": "0:00:00.008434",
2026-04-16 06:45:09.397500 | instance |   "end": "2026-04-16 06:45:09.063893",
2026-04-16 06:45:09.397541 | instance |   "msg": "non-zero return code",
2026-04-16 06:45:09.397576 | instance |   "rc": 1,
2026-04-16 06:45:09.397597 | instance |   "start": "2026-04-16 06:45:09.055459"
2026-04-16 06:45:09.397615 | instance | }
2026-04-16 06:45:09.397637 | instance | ERROR: Ignoring Errors
2026-04-16 06:45:09.402548 | 
2026-04-16 06:45:09.402618 | TASK [gather-selenium-data : Downloads logs to executor]
2026-04-16 06:45:09.892126 | instance | changed: cd+++++++++ selenium/
2026-04-16 06:45:09.901461 | 
2026-04-16 06:45:09.901617 | PLAY RECAP
2026-04-16 06:45:09.901697 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 4
2026-04-16 06:45:09.901734 | 
2026-04-16 06:45:10.113298 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@main]
2026-04-16 06:45:10.126621 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-16 06:45:10.793552 | 
2026-04-16 06:45:10.793804 | PLAY [all]
2026-04-16 06:45:10.811312 | 
2026-04-16 06:45:10.811549 | TASK [fetch-output : Set log path for multiple nodes]
2026-04-16 06:45:10.858359 | instance | skipping: Conditional result was False
2026-04-16 06:45:10.867845 | 
2026-04-16 06:45:10.868070 | TASK [fetch-output : Set log path for single node]
2026-04-16 06:45:10.915530 | instance | ok
2026-04-16 06:45:10.920562 | 
2026-04-16 06:45:10.920641 | LOOP [fetch-output : Ensure local output dirs]
2026-04-16 06:45:11.320083 | instance -> localhost | ok: "/var/lib/zuul/builds/01217327233d406285fc834edb941efb/work/logs"
2026-04-16 06:45:11.535319 | instance -> localhost | changed: "/var/lib/zuul/builds/01217327233d406285fc834edb941efb/work/artifacts"
2026-04-16 06:45:11.745381 | instance -> localhost | changed: "/var/lib/zuul/builds/01217327233d406285fc834edb941efb/work/docs"
2026-04-16 06:45:11.759989 | 
2026-04-16 06:45:11.760206 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-04-16 06:45:12.393988 | instance | changed: .d..t...... ./
2026-04-16 06:45:12.394321 | instance | changed: All items complete
2026-04-16 06:45:12.394383 | 
2026-04-16 06:45:12.874188 | instance | changed: .d..t...... ./
2026-04-16 06:45:13.322274 | instance | changed: .d..t...... ./
2026-04-16 06:45:13.341045 | 
2026-04-16 06:45:13.341192 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-04-16 06:45:13.761541 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.007400
2026-04-16 06:45:14.015706 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.008095
2026-04-16 06:45:14.034540 | 
2026-04-16 06:45:14.034680 | PLAY [all]
2026-04-16 06:45:14.043805 | 
2026-04-16 06:45:14.043921 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-04-16 06:45:14.484485 | instance | changed
2026-04-16 06:45:14.490300 | 
2026-04-16 06:45:14.490380 | PLAY RECAP
2026-04-16 06:45:14.490429 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-04-16 06:45:14.490452 | 
2026-04-16 06:45:14.636684 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-16 06:45:14.649540 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-04-16 06:45:15.330894 | 
2026-04-16 06:45:15.331032 | PLAY [localhost]
2026-04-16 06:45:15.340746 | 
2026-04-16 06:45:15.340826 | TASK [Generate Zuul manifest]
2026-04-16 06:45:15.362297 | localhost | ok
2026-04-16 06:45:15.382062 | 
2026-04-16 06:45:15.382248 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-04-16 06:45:15.749850 | localhost | changed
2026-04-16 06:45:15.760719 | 
2026-04-16 06:45:15.760820 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-04-16 06:45:15.792457 | localhost | ok
2026-04-16 06:45:15.805072 | 
2026-04-16 06:45:15.805293 | TASK [Upload logs]
2026-04-16 06:45:15.830307 | localhost | ok
2026-04-16 06:45:15.961416 | 
2026-04-16 06:45:15.961623 | TASK [Set zuul-log-path fact]
2026-04-16 06:45:15.983907 | localhost | ok
2026-04-16 06:45:15.996886 | 
2026-04-16 06:45:15.997005 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-16 06:45:16.032405 | localhost | ok
2026-04-16 06:45:16.040018 | 
2026-04-16 06:45:16.040116 | TASK [upload-logs : Create log directories]
2026-04-16 06:45:16.397319 | localhost | changed
2026-04-16 06:45:16.403758 | 
2026-04-16 06:45:16.403879 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-04-16 06:45:16.772157 | localhost -> localhost | ok: Runtime: 0:00:00.005965
2026-04-16 06:45:16.778295 | 
2026-04-16 06:45:16.778399 | TASK [upload-logs : Upload logs to log server]
2026-04-16 06:45:17.225139 | localhost | Output suppressed because no_log was given
2026-04-16 06:45:17.229945 | 
2026-04-16 06:45:17.230037 | LOOP [upload-logs : Compress console log and json output]
2026-04-16 06:45:17.276848 | localhost | skipping: Conditional result was False
2026-04-16 06:45:17.286577 | localhost | skipping: Conditional result was False
2026-04-16 06:45:17.296071 | 
2026-04-16 06:45:17.296238 | LOOP [upload-logs : Upload compressed console log and json output]
2026-04-16 06:45:17.342852 | localhost | skipping: Conditional result was False
2026-04-16 06:45:17.343390 | 
2026-04-16 06:45:17.346882 | localhost | skipping: Conditional result was False
2026-04-16 06:45:17.363512 | 
2026-04-16 06:45:17.363681 | LOOP [upload-logs : Upload console log and json output]