2026-04-13 20:38:55.652377 | Job console starting 2026-04-13 20:38:55.670492 | Updating git repos 2026-04-13 20:38:55.732861 | Cloning repos into workspace 2026-04-13 20:38:55.839232 | Restoring repo states 2026-04-13 20:38:55.863955 | Merging changes 2026-04-13 20:38:56.982125 | Checking out repos 2026-04-13 20:38:57.323919 | Preparing playbooks 2026-04-13 20:39:00.307472 | Running Ansible setup 2026-04-13 20:39:03.818806 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-13 20:39:04.449054 | 2026-04-13 20:39:04.449235 | PLAY [localhost] 2026-04-13 20:39:04.456746 | 2026-04-13 20:39:04.456829 | TASK [Gathering Facts] 2026-04-13 20:39:05.335425 | localhost | ok 2026-04-13 20:39:05.343658 | 2026-04-13 20:39:05.343742 | TASK [Setup log path fact] 2026-04-13 20:39:05.360469 | localhost | ok 2026-04-13 20:39:05.370682 | 2026-04-13 20:39:05.370784 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-13 20:39:05.400937 | localhost | ok 2026-04-13 20:39:05.407810 | 2026-04-13 20:39:05.407885 | TASK [emit-job-header : Print job information] 2026-04-13 20:39:05.448071 | # Job Information 2026-04-13 20:39:05.448260 | Ansible Version: 2.16.16 2026-04-13 20:39:05.448306 | Job: atmosphere-molecule-aio-openvswitch 2026-04-13 20:39:05.448339 | Pipeline: check 2026-04-13 20:39:05.448367 | Executor: 0a8996d2b663 2026-04-13 20:39:05.448395 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3818 2026-04-13 20:39:05.448427 | Event ID: af35ae10-3778-11f1-8810-704e89e38670 2026-04-13 20:39:05.452252 | 2026-04-13 20:39:05.452345 | LOOP [emit-job-header : Print node information] 2026-04-13 20:39:05.547586 | localhost | ok: 2026-04-13 20:39:05.547822 | localhost | # Node Information 2026-04-13 20:39:05.547874 | localhost | Inventory Hostname: instance 2026-04-13 20:39:05.547913 | localhost | Hostname: np0000165704 2026-04-13 20:39:05.547947 | localhost | Username: zuul 2026-04-13 20:39:05.547986 | localhost | Distro: Ubuntu 22.04 2026-04-13 20:39:05.548021 | localhost | Provider: yul1 2026-04-13 20:39:05.548054 | localhost | Region: ca-ymq-1 2026-04-13 20:39:05.548086 | localhost | Label: ubuntu-jammy-16 2026-04-13 20:39:05.548117 | localhost | Product Name: OpenStack Nova 2026-04-13 20:39:05.548147 | localhost | Interface IP: 199.204.45.222 2026-04-13 20:39:05.561372 | 2026-04-13 20:39:05.561555 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-04-13 20:39:06.154696 | localhost -> localhost | changed 2026-04-13 20:39:06.160473 | 2026-04-13 20:39:06.160582 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-04-13 20:39:06.995604 | localhost -> localhost | changed 2026-04-13 20:39:07.001289 | 2026-04-13 20:39:07.001349 | PLAY [all] 2026-04-13 20:39:07.009911 | 2026-04-13 20:39:07.009978 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-04-13 20:39:07.248180 | instance -> localhost | ok 2026-04-13 20:39:07.253842 | 2026-04-13 20:39:07.253933 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-04-13 20:39:07.283317 | instance | ok 2026-04-13 20:39:07.295785 | instance | included: /var/lib/zuul/builds/f396e1bcf4ec4295a4cd75c56fbd2b96/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-04-13 20:39:07.301158 | 2026-04-13 20:39:07.301223 | TASK [add-build-sshkey : Create Temp SSH key] 2026-04-13 20:39:08.087306 | instance -> localhost | Generating public/private rsa key pair. 2026-04-13 20:39:08.087467 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/f396e1bcf4ec4295a4cd75c56fbd2b96/work/f396e1bcf4ec4295a4cd75c56fbd2b96_id_rsa 2026-04-13 20:39:08.087497 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/f396e1bcf4ec4295a4cd75c56fbd2b96/work/f396e1bcf4ec4295a4cd75c56fbd2b96_id_rsa.pub 2026-04-13 20:39:08.087520 | instance -> localhost | The key fingerprint is: 2026-04-13 20:39:08.087543 | instance -> localhost | SHA256:SM6I/8JU8JfWW09PszOn4bnYM/YgM+6pDZ0llLuCUKs zuul-build-sshkey 2026-04-13 20:39:08.087581 | instance -> localhost | The key's randomart image is: 2026-04-13 20:39:08.087603 | instance -> localhost | +---[RSA 3072]----+ 2026-04-13 20:39:08.087631 | instance -> localhost | | | 2026-04-13 20:39:08.087653 | instance -> localhost | | . . | 2026-04-13 20:39:08.087674 | instance -> localhost | | o. .o o | 2026-04-13 20:39:08.087694 | instance -> localhost | | . =oo+........| 2026-04-13 20:39:08.087714 | instance -> localhost | | . ..=oS ooo.oo| 2026-04-13 20:39:08.087734 | instance -> localhost | | .. o ... =o+o| 2026-04-13 20:39:08.087753 | instance -> localhost | | o. E . o B..++| 2026-04-13 20:39:08.087772 | instance -> localhost | | o. = BB. | 2026-04-13 20:39:08.087794 | instance -> localhost | | .. .o*.o=.| 2026-04-13 20:39:08.087814 | instance -> localhost | +----[SHA256]-----+ 2026-04-13 20:39:08.087870 | instance -> localhost | ok: Runtime: 0:00:00.358023 2026-04-13 20:39:08.093550 | 2026-04-13 20:39:08.093624 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-04-13 20:39:08.128096 | instance | ok 2026-04-13 20:39:08.136387 | instance | included: /var/lib/zuul/builds/f396e1bcf4ec4295a4cd75c56fbd2b96/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-04-13 20:39:08.143764 | 2026-04-13 20:39:08.143831 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-04-13 20:39:08.167531 | instance | skipping: Conditional result was False 2026-04-13 20:39:08.177560 | 2026-04-13 20:39:08.177671 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-04-13 20:39:08.648792 | instance | changed 2026-04-13 20:39:08.655959 | 2026-04-13 20:39:08.656109 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-04-13 20:39:08.843560 | instance | ok 2026-04-13 20:39:08.849010 | 2026-04-13 20:39:08.849090 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-04-13 20:39:09.318620 | instance | changed 2026-04-13 20:39:09.327534 | 2026-04-13 20:39:09.327876 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-04-13 20:39:09.782665 | instance | changed 2026-04-13 20:39:09.789469 | 2026-04-13 20:39:09.789544 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-04-13 20:39:09.813377 | instance | skipping: Conditional result was False 2026-04-13 20:39:09.822948 | 2026-04-13 20:39:09.823079 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-04-13 20:39:10.362621 | instance -> localhost | changed 2026-04-13 20:39:10.415104 | 2026-04-13 20:39:10.415236 | TASK [add-build-sshkey : Add back temp key] 2026-04-13 20:39:10.998188 | instance -> localhost | Identity added: /var/lib/zuul/builds/f396e1bcf4ec4295a4cd75c56fbd2b96/work/f396e1bcf4ec4295a4cd75c56fbd2b96_id_rsa (zuul-build-sshkey) 2026-04-13 20:39:10.998434 | instance -> localhost | ok: Runtime: 0:00:00.015179 2026-04-13 20:39:11.006290 | 2026-04-13 20:39:11.006420 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-04-13 20:39:11.261694 | instance | ok 2026-04-13 20:39:11.528201 | 2026-04-13 20:39:11.528337 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-04-13 20:39:11.552516 | instance | skipping: Conditional result was False 2026-04-13 20:39:11.569971 | 2026-04-13 20:39:11.570103 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-04-13 20:39:11.910253 | instance | ok 2026-04-13 20:39:11.916275 | 2026-04-13 20:39:11.916390 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-04-13 20:39:13.884026 | instance | Output suppressed because no_log was given 2026-04-13 20:39:13.894512 | 2026-04-13 20:39:13.894613 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-04-13 20:39:14.077419 | instance | ok: "logs" 2026-04-13 20:39:14.077753 | instance | ok: All items complete 2026-04-13 20:39:14.077793 | 2026-04-13 20:39:14.252095 | instance | ok: "artifacts" 2026-04-13 20:39:14.406700 | instance | ok: "docs" 2026-04-13 20:39:14.418529 | 2026-04-13 20:39:14.418709 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-04-13 20:39:14.634367 | instance | changed: "logs" 2026-04-13 20:39:14.766664 | instance | changed: "artifacts" 2026-04-13 20:39:15.115134 | instance | changed: "docs" 2026-04-13 20:39:15.133452 | 2026-04-13 20:39:15.133557 | PLAY RECAP 2026-04-13 20:39:15.133622 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-04-13 20:39:15.133667 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-13 20:39:15.133700 | 2026-04-13 20:39:15.257105 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-13 20:39:15.267009 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-13 20:39:16.093387 | 2026-04-13 20:39:16.093517 | PLAY [all] 2026-04-13 20:39:16.105517 | 2026-04-13 20:39:16.105640 | TASK [setup-uv : Extract archive] 2026-04-13 20:39:18.300824 | instance | changed 2026-04-13 20:39:18.310733 | 2026-04-13 20:39:18.310893 | TASK [setup-uv : Print version] 2026-04-13 20:39:19.922585 | instance | uv 0.8.13 2026-04-13 20:39:18.856521 | instance | ok: Runtime: 0:00:00.011515 2026-04-13 20:39:18.866331 | 2026-04-13 20:39:18.866393 | PLAY RECAP 2026-04-13 20:39:18.866436 | instance | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-13 20:39:18.866460 | 2026-04-13 20:39:19.014627 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-13 20:39:19.031945 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/pre.yml@main] 2026-04-13 20:39:19.683035 | 2026-04-13 20:39:19.683196 | PLAY [all] 2026-04-13 20:39:19.695179 | 2026-04-13 20:39:19.695309 | TASK [Install "jq" for log collection] 2026-04-13 20:39:29.768723 | instance | changed 2026-04-13 20:39:29.776267 | 2026-04-13 20:39:29.776403 | TASK [Install pip3 for Python package management] 2026-04-13 20:39:34.660335 | instance | changed 2026-04-13 20:39:34.667290 | 2026-04-13 20:39:34.667358 | TASK [Install Python "kubernetes" library for kubernetes.core modules] 2026-04-13 20:39:37.965598 | instance | changed 2026-04-13 20:39:37.969657 | 2026-04-13 20:39:37.969765 | PLAY [all] 2026-04-13 20:39:37.978707 | 2026-04-13 20:39:37.978788 | TASK [ensure-go : Check installed go version] 2026-04-13 20:39:38.516609 | instance | ok: ERROR (ignored) 2026-04-13 20:39:38.516872 | instance | { 2026-04-13 20:39:38.516917 | instance | "failed_when_result": false, 2026-04-13 20:39:38.516948 | instance | "msg": "[Errno 2] No such file or directory: b'go'", 2026-04-13 20:39:38.517000 | instance | "rc": 2 2026-04-13 20:39:38.517034 | instance | } 2026-04-13 20:39:38.523789 | 2026-04-13 20:39:38.523855 | TASK [ensure-go : Skip if correct version of go is installed] 2026-04-13 20:39:38.575085 | instance | ok 2026-04-13 20:39:38.584330 | instance | included: /var/lib/zuul/builds/f396e1bcf4ec4295a4cd75c56fbd2b96/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/ensure-go/tasks/install-go.yaml 2026-04-13 20:39:38.589145 | 2026-04-13 20:39:38.589209 | TASK [ensure-go : Create temp directory] 2026-04-13 20:39:38.904048 | instance | changed 2026-04-13 20:39:38.910675 | 2026-04-13 20:39:38.910773 | TASK [ensure-go : Get archive checksum] 2026-04-13 20:39:39.544945 | instance | ok: OK (64 bytes) 2026-04-13 20:39:39.551720 | 2026-04-13 20:39:39.551793 | TASK [ensure-go : Download go archive] 2026-04-13 20:39:41.088595 | instance | changed: OK (78559214 bytes) 2026-04-13 20:39:41.095213 | 2026-04-13 20:39:41.095278 | TASK [ensure-go : Install go] 2026-04-13 20:39:46.987574 | instance | changed 2026-04-13 20:39:46.994396 | 2026-04-13 20:39:46.994460 | PLAY [all] 2026-04-13 20:39:46.999832 | 2026-04-13 20:39:46.999920 | TASK [Build atmosphere binary] 2026-04-13 20:39:47.298178 | instance -> localhost | ERROR 2026-04-13 20:39:47.298497 | instance -> localhost | { 2026-04-13 20:39:47.298585 | instance -> localhost | "delta": null, 2026-04-13 20:39:47.299057 | instance -> localhost | "end": null, 2026-04-13 20:39:47.299107 | instance -> localhost | "msg": "Unable to change directory before execution: [Errno 2] No such file or directory: b'src/github.com/vexxhost/atmosphere'", 2026-04-13 20:39:47.299142 | instance -> localhost | "rc": null, 2026-04-13 20:39:47.299173 | instance -> localhost | "start": null 2026-04-13 20:39:47.299203 | instance -> localhost | } failure 2026-04-13 20:39:47.300821 | 2026-04-13 20:39:47.300875 | PLAY RECAP 2026-04-13 20:39:47.300928 | instance | ok: 9 changed: 6 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-04-13 20:39:47.300975 | 2026-04-13 20:39:47.439669 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/pre.yml@main] 2026-04-13 20:39:47.449522 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@main] 2026-04-13 20:39:48.126501 | 2026-04-13 20:39:48.126657 | PLAY [all] 2026-04-13 20:39:48.143680 | 2026-04-13 20:39:48.166902 | TASK [gather-host-logs : creating directory for system status] 2026-04-13 20:39:48.525376 | instance | changed 2026-04-13 20:39:48.532828 | 2026-04-13 20:39:48.532993 | TASK [gather-host-logs : Get logs for each host] 2026-04-13 20:39:48.953931 | instance | + systemd-cgls --full --all --no-pager 2026-04-13 20:39:48.965572 | instance | + ip addr 2026-04-13 20:39:48.967945 | instance | + ip route 2026-04-13 20:39:48.969587 | instance | + lsblk 2026-04-13 20:39:48.972251 | instance | + mount 2026-04-13 20:39:48.973756 | instance | + docker images 2026-04-13 20:39:48.974141 | instance | /bin/bash: line 7: docker: command not found 2026-04-13 20:39:48.974327 | instance | + brctl show 2026-04-13 20:39:48.974998 | instance | /bin/bash: line 8: brctl: command not found 2026-04-13 20:39:48.975204 | instance | + ps aux --sort=-%mem 2026-04-13 20:39:48.991571 | instance | + dpkg -l 2026-04-13 20:39:49.004111 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul)) 2026-04-13 20:39:49.004857 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul 2026-04-13 20:39:49.004954 | instance | /bin/bash: line 11: docker: command not found 2026-04-13 20:39:49.005367 | instance | + '[' '!' -z '' ']' 2026-04-13 20:39:49.076254 | instance | ok: Runtime: 0:00:00.057722 2026-04-13 20:39:49.085516 | 2026-04-13 20:39:49.085644 | TASK [gather-host-logs : Downloads logs to executor] 2026-04-13 20:39:49.727958 | instance | changed: 2026-04-13 20:39:49.728452 | instance | created directory /var/lib/zuul/builds/f396e1bcf4ec4295a4cd75c56fbd2b96/work/logs/instance 2026-04-13 20:39:49.728501 | instance | cd+++++++++ system/ 2026-04-13 20:39:49.728537 | instance | >f+++++++++ system/brctl-show.txt 2026-04-13 20:39:49.728567 | instance | >f+++++++++ system/docker-images.txt 2026-04-13 20:39:49.728597 | instance | >f+++++++++ system/ip-addr.txt 2026-04-13 20:39:49.728630 | instance | >f+++++++++ system/ip-route.txt 2026-04-13 20:39:49.728660 | instance | >f+++++++++ system/lsblk.txt 2026-04-13 20:39:49.728689 | instance | >f+++++++++ system/mount.txt 2026-04-13 20:39:49.728722 | instance | >f+++++++++ system/packages.txt 2026-04-13 20:39:49.728751 | instance | >f+++++++++ system/ps.txt 2026-04-13 20:39:49.728797 | instance | >f+++++++++ system/systemd-cgls.txt 2026-04-13 20:39:49.740098 | 2026-04-13 20:39:49.740288 | LOOP [helm-release-status : creating directory for helm release status] 2026-04-13 20:39:49.938051 | instance | changed: "values" 2026-04-13 20:39:50.116107 | instance | changed: "releases" 2026-04-13 20:39:50.130551 | 2026-04-13 20:39:50.130662 | TASK [helm-release-status : Gather get release status for helm charts] 2026-04-13 20:39:50.335690 | instance | /bin/bash: line 3: kubectl: command not found 2026-04-13 20:39:50.672445 | instance | ok: Runtime: 0:00:00.006556 2026-04-13 20:39:50.679267 | 2026-04-13 20:39:50.679338 | TASK [helm-release-status : Downloads logs to executor] 2026-04-13 20:39:51.200024 | instance | changed: 2026-04-13 20:39:51.200211 | instance | cd+++++++++ helm/ 2026-04-13 20:39:51.200251 | instance | cd+++++++++ helm/releases/ 2026-04-13 20:39:51.200282 | instance | cd+++++++++ helm/values/ 2026-04-13 20:39:51.211719 | 2026-04-13 20:39:51.212052 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects] 2026-04-13 20:39:51.455988 | instance | changed 2026-04-13 20:39:51.501578 | 2026-04-13 20:39:51.501742 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects] 2026-04-13 20:39:51.713564 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-13 20:39:51.713713 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-13 20:39:51.719490 | instance | environment: line 1: kubectl: command not found 2026-04-13 20:39:51.721593 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-13 20:39:51.723129 | instance | environment: line 1: kubectl: command not found 2026-04-13 20:39:51.723841 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-13 20:39:51.723920 | instance | environment: line 1: kubectl: command not found 2026-04-13 20:39:51.725439 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-13 20:39:51.726303 | instance | environment: line 1: kubectl: command not found 2026-04-13 20:39:51.727197 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-13 20:39:51.729169 | instance | environment: line 1: kubectl: command not found 2026-04-13 20:39:51.730797 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-13 20:39:52.051940 | instance | ok: Runtime: 0:00:00.026513 2026-04-13 20:39:52.057918 | 2026-04-13 20:39:52.057985 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects] 2026-04-13 20:39:52.250684 | instance | changed 2026-04-13 20:39:52.258217 | 2026-04-13 20:39:52.258303 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects] 2026-04-13 20:39:52.464384 | instance | environment: line 5: kubectl: command not found 2026-04-13 20:39:52.465683 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-13 20:39:52.465813 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-13 20:39:52.465962 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-13 20:39:52.795218 | instance | ok: Runtime: 0:00:00.009579 2026-04-13 20:39:52.804351 | 2026-04-13 20:39:52.804547 | TASK [describe-kubernetes-objects : Downloads logs to executor] 2026-04-13 20:39:55.310264 | instance | changed: 2026-04-13 20:39:55.310409 | instance | cd+++++++++ objects/ 2026-04-13 20:39:55.310439 | instance | cd+++++++++ objects/cluster/ 2026-04-13 20:39:55.310462 | instance | cd+++++++++ objects/namespaced/ 2026-04-13 20:39:55.319181 | 2026-04-13 20:39:55.319244 | TASK [gather-pod-logs : creating directory for pod logs] 2026-04-13 20:39:55.509226 | instance | changed 2026-04-13 20:39:55.517493 | 2026-04-13 20:39:55.517579 | TASK [gather-pod-logs : creating directory for failed pod logs] 2026-04-13 20:39:55.724867 | instance | changed 2026-04-13 20:39:55.730400 | 2026-04-13 20:39:55.730481 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)] 2026-04-13 20:39:55.966267 | instance | environment: line 3: kubectl: command not found 2026-04-13 20:39:56.270467 | instance | ok: Runtime: 0:00:00.008052 2026-04-13 20:39:56.275495 | 2026-04-13 20:39:56.275561 | TASK [gather-pod-logs : Downloads pod logs to executor] 2026-04-13 20:39:56.806247 | instance | changed: 2026-04-13 20:39:56.806393 | instance | cd+++++++++ pod-logs/ 2026-04-13 20:39:56.806422 | instance | cd+++++++++ pod-logs/failed-pods/ 2026-04-13 20:39:56.815975 | 2026-04-13 20:39:56.816040 | TASK [gather-prom-metrics : creating directory for helm release descriptions] 2026-04-13 20:39:57.012047 | instance | changed 2026-04-13 20:39:57.019070 | 2026-04-13 20:39:57.019165 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces] 2026-04-13 20:39:57.233726 | instance | /bin/bash: line 2: kubectl: command not found 2026-04-13 20:39:57.557268 | instance | ok: Runtime: 0:00:00.033569 2026-04-13 20:39:57.562775 | 2026-04-13 20:39:57.562864 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr] 2026-04-13 20:39:57.774901 | instance | /bin/bash: line 2: kubectl: command not found 2026-04-13 20:39:57.800667 | instance | ceph-mgr endpoints: 2026-04-13 20:39:58.096530 | instance | ok: Runtime: 0:00:00.033330 2026-04-13 20:39:58.101729 | 2026-04-13 20:39:58.101797 | TASK [gather-prom-metrics : Get metrics from fluentd pods] 2026-04-13 20:39:58.302153 | instance | /bin/bash: line 4: kubectl: command not found 2026-04-13 20:39:58.967670 | instance | ok: Runtime: 0:00:00.036810 2026-04-13 20:39:58.974641 | 2026-04-13 20:39:58.974743 | TASK [gather-prom-metrics : Downloads logs to executor] 2026-04-13 20:39:59.461679 | instance | changed: cd+++++++++ prometheus/ 2026-04-13 20:39:59.471295 | 2026-04-13 20:39:59.471397 | TASK [gather-selenium-data : creating directory for helm release descriptions] 2026-04-13 20:39:59.758260 | instance | changed 2026-04-13 20:39:59.765013 | 2026-04-13 20:39:59.765133 | TASK [gather-selenium-data : Get selenium data] 2026-04-13 20:39:59.978316 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/. 2026-04-13 20:39:59.979788 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory 2026-04-13 20:40:00.310450 | instance | ERROR 2026-04-13 20:40:00.310738 | instance | { 2026-04-13 20:40:00.310805 | instance | "delta": "0:00:00.005831", 2026-04-13 20:40:00.310851 | instance | "end": "2026-04-13 20:39:59.980124", 2026-04-13 20:40:00.310890 | instance | "msg": "non-zero return code", 2026-04-13 20:40:00.310927 | instance | "rc": 1, 2026-04-13 20:40:00.310963 | instance | "start": "2026-04-13 20:39:59.974293" 2026-04-13 20:40:00.310999 | instance | } 2026-04-13 20:40:00.311046 | instance | ERROR: Ignoring Errors 2026-04-13 20:40:00.319642 | 2026-04-13 20:40:00.319783 | TASK [gather-selenium-data : Downloads logs to executor] 2026-04-13 20:40:00.841159 | instance | changed: cd+++++++++ selenium/ 2026-04-13 20:40:00.850125 | 2026-04-13 20:40:00.850254 | PLAY RECAP 2026-04-13 20:40:00.850307 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1 2026-04-13 20:40:00.850330 | 2026-04-13 20:40:00.970112 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@main] 2026-04-13 20:40:00.983326 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-13 20:40:01.627739 | 2026-04-13 20:40:01.728545 | PLAY [all] 2026-04-13 20:40:01.745424 | 2026-04-13 20:40:01.745537 | TASK [fetch-output : Set log path for multiple nodes] 2026-04-13 20:40:01.789936 | instance | skipping: Conditional result was False 2026-04-13 20:40:01.799874 | 2026-04-13 20:40:01.800047 | TASK [fetch-output : Set log path for single node] 2026-04-13 20:40:01.843393 | instance | ok 2026-04-13 20:40:01.849717 | 2026-04-13 20:40:01.849807 | LOOP [fetch-output : Ensure local output dirs] 2026-04-13 20:40:02.298805 | instance -> localhost | ok: "/var/lib/zuul/builds/f396e1bcf4ec4295a4cd75c56fbd2b96/work/logs" 2026-04-13 20:40:02.496051 | instance -> localhost | changed: "/var/lib/zuul/builds/f396e1bcf4ec4295a4cd75c56fbd2b96/work/artifacts" 2026-04-13 20:40:02.717686 | instance -> localhost | changed: "/var/lib/zuul/builds/f396e1bcf4ec4295a4cd75c56fbd2b96/work/docs" 2026-04-13 20:40:02.736940 | 2026-04-13 20:40:02.737190 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-04-13 20:40:03.405045 | instance | changed: .d..t...... ./ 2026-04-13 20:40:03.405592 | instance | changed: All items complete 2026-04-13 20:40:03.405644 | 2026-04-13 20:40:03.887652 | instance | changed: .d..t...... ./ 2026-04-13 20:40:04.463838 | instance | changed: .d..t...... ./ 2026-04-13 20:40:04.487180 | 2026-04-13 20:40:04.487353 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-04-13 20:40:04.966628 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.007349 2026-04-13 20:40:05.182663 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.006516 2026-04-13 20:40:05.205291 | 2026-04-13 20:40:05.205431 | PLAY [all] 2026-04-13 20:40:05.213095 | 2026-04-13 20:40:05.213192 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-04-13 20:40:05.628109 | instance | changed 2026-04-13 20:40:05.635439 | 2026-04-13 20:40:05.635488 | PLAY RECAP 2026-04-13 20:40:05.635529 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-04-13 20:40:05.635550 | 2026-04-13 20:40:05.755071 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-13 20:40:05.767848 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-04-13 20:40:06.434996 | 2026-04-13 20:40:07.578538 | PLAY [localhost] 2026-04-13 20:40:07.594056 | 2026-04-13 20:40:07.594239 | TASK [Generate Zuul manifest] 2026-04-13 20:40:07.615173 | localhost | ok 2026-04-13 20:40:07.637376 | 2026-04-13 20:40:07.637539 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-04-13 20:40:08.066988 | localhost | changed 2026-04-13 20:40:08.084483 | 2026-04-13 20:40:08.084620 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-04-13 20:40:08.136430 | localhost | ok 2026-04-13 20:40:08.146809 | 2026-04-13 20:40:08.146907 | TASK [Upload logs] 2026-04-13 20:40:08.178267 | localhost | ok 2026-04-13 20:40:08.286759 | 2026-04-13 20:40:08.286862 | TASK [Set zuul-log-path fact] 2026-04-13 20:40:08.306031 | localhost | ok 2026-04-13 20:40:08.319854 | 2026-04-13 20:40:08.319931 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-13 20:40:08.350424 | localhost | ok 2026-04-13 20:40:08.360942 | 2026-04-13 20:40:08.361042 | TASK [upload-logs : Create log directories] 2026-04-13 20:40:08.723660 | localhost | changed 2026-04-13 20:40:08.729842 | 2026-04-13 20:40:08.729919 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-04-13 20:40:09.105568 | localhost -> localhost | ok: Runtime: 0:00:00.004806 2026-04-13 20:40:09.113029 | 2026-04-13 20:40:09.113106 | TASK [upload-logs : Upload logs to log server] 2026-04-13 20:40:09.530239 | localhost | Output suppressed because no_log was given 2026-04-13 20:40:09.535292 | 2026-04-13 20:40:09.535389 | LOOP [upload-logs : Compress console log and json output] 2026-04-13 20:40:09.585499 | localhost | skipping: Conditional result was False 2026-04-13 20:40:09.595297 | localhost | skipping: Conditional result was False 2026-04-13 20:40:09.609914 | 2026-04-13 20:40:09.610084 | LOOP [upload-logs : Upload compressed console log and json output] 2026-04-13 20:40:09.652002 | localhost | skipping: Conditional result was False 2026-04-13 20:40:09.652413 | 2026-04-13 20:40:09.657718 | localhost | skipping: Conditional result was False 2026-04-13 20:40:09.681729 | 2026-04-13 20:40:09.681855 | LOOP [upload-logs : Upload console log and json output]