2026-04-13 20:46:57.212486 | Job console starting 2026-04-13 20:46:57.226716 | Updating git repos 2026-04-13 20:46:57.314435 | Cloning repos into workspace 2026-04-13 20:46:57.374884 | Restoring repo states 2026-04-13 20:46:57.393323 | Merging changes 2026-04-13 20:46:58.858670 | Checking out repos 2026-04-13 20:46:59.038072 | Preparing playbooks 2026-04-13 20:47:02.946635 | Running Ansible setup 2026-04-13 20:47:08.398584 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-13 20:47:09.111310 | 2026-04-13 20:47:09.111478 | PLAY [localhost] 2026-04-13 20:47:09.120507 | 2026-04-13 20:47:09.120623 | TASK [Gathering Facts] 2026-04-13 20:47:10.056538 | localhost | ok 2026-04-13 20:47:10.067025 | 2026-04-13 20:47:10.067367 | TASK [Setup log path fact] 2026-04-13 20:47:10.089571 | localhost | ok 2026-04-13 20:47:10.105903 | 2026-04-13 20:47:10.106117 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-13 20:47:10.138864 | localhost | ok 2026-04-13 20:47:10.149683 | 2026-04-13 20:47:10.149903 | TASK [emit-job-header : Print job information] 2026-04-13 20:47:10.192027 | # Job Information 2026-04-13 20:47:10.192286 | Ansible Version: 2.16.16 2026-04-13 20:47:10.192340 | Job: atmosphere-molecule-keycloak 2026-04-13 20:47:10.192374 | Pipeline: check 2026-04-13 20:47:10.192413 | Executor: 0a8996d2b663 2026-04-13 20:47:10.192461 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3818 2026-04-13 20:47:10.192511 | Event ID: c9a01c80-3779-11f1-8db7-82665fdb899d 2026-04-13 20:47:10.197303 | 2026-04-13 20:47:10.197412 | LOOP [emit-job-header : Print node information] 2026-04-13 20:47:10.310366 | localhost | ok: 2026-04-13 20:47:10.310608 | localhost | # Node Information 2026-04-13 20:47:10.310672 | localhost | Inventory Hostname: instance 2026-04-13 20:47:10.310723 | localhost | Hostname: np0000165765 2026-04-13 20:47:10.310766 | localhost | Username: zuul 2026-04-13 20:47:10.310812 | localhost | Distro: Ubuntu 22.04 2026-04-13 20:47:10.310852 | localhost | Provider: yul1 2026-04-13 20:47:10.310897 | localhost | Region: ca-ymq-1 2026-04-13 20:47:10.310938 | localhost | Label: ubuntu-jammy 2026-04-13 20:47:10.310973 | localhost | Product Name: OpenStack Nova 2026-04-13 20:47:10.311008 | localhost | Interface IP: 199.19.213.163 2026-04-13 20:47:10.336875 | 2026-04-13 20:47:10.337229 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-04-13 20:47:10.770702 | localhost -> localhost | changed 2026-04-13 20:47:10.781747 | 2026-04-13 20:47:10.781990 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-04-13 20:47:11.666148 | localhost -> localhost | changed 2026-04-13 20:47:11.677230 | 2026-04-13 20:47:11.677367 | PLAY [all] 2026-04-13 20:47:11.688656 | 2026-04-13 20:47:11.688781 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-04-13 20:47:11.928467 | instance -> localhost | ok 2026-04-13 20:47:11.940455 | 2026-04-13 20:47:11.940535 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-04-13 20:47:11.974578 | instance | ok 2026-04-13 20:47:11.991485 | instance | included: /var/lib/zuul/builds/905f74e3d5d74680a11cf4b4551393f2/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-04-13 20:47:11.999768 | 2026-04-13 20:47:11.999925 | TASK [add-build-sshkey : Create Temp SSH key] 2026-04-13 20:47:12.712848 | instance -> localhost | Generating public/private rsa key pair. 2026-04-13 20:47:12.713397 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/905f74e3d5d74680a11cf4b4551393f2/work/905f74e3d5d74680a11cf4b4551393f2_id_rsa 2026-04-13 20:47:12.713443 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/905f74e3d5d74680a11cf4b4551393f2/work/905f74e3d5d74680a11cf4b4551393f2_id_rsa.pub 2026-04-13 20:47:12.713473 | instance -> localhost | The key fingerprint is: 2026-04-13 20:47:12.713503 | instance -> localhost | SHA256:n+M2nglT2ojfBgiW6AIUNIzxCNeG8zlEnhxgxQDJV5w zuul-build-sshkey 2026-04-13 20:47:12.713561 | instance -> localhost | The key's randomart image is: 2026-04-13 20:47:12.713590 | instance -> localhost | +---[RSA 3072]----+ 2026-04-13 20:47:12.713628 | instance -> localhost | |OB*O*.. | 2026-04-13 20:47:12.713658 | instance -> localhost | |+O++=E | 2026-04-13 20:47:12.713685 | instance -> localhost | |o o*+o | 2026-04-13 20:47:12.713713 | instance -> localhost | |. . B | 2026-04-13 20:47:12.713741 | instance -> localhost | |.. . o .S . | 2026-04-13 20:47:12.713767 | instance -> localhost | |. . ...* . | 2026-04-13 20:47:12.713794 | instance -> localhost | | . . =.= | 2026-04-13 20:47:12.713822 | instance -> localhost | | . =++ | 2026-04-13 20:47:12.713851 | instance -> localhost | | .+*. | 2026-04-13 20:47:12.713878 | instance -> localhost | +----[SHA256]-----+ 2026-04-13 20:47:12.713973 | instance -> localhost | ok: Runtime: 0:00:00.248997 2026-04-13 20:47:12.723615 | 2026-04-13 20:47:12.723847 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-04-13 20:47:12.756642 | instance | ok 2026-04-13 20:47:12.767711 | instance | included: /var/lib/zuul/builds/905f74e3d5d74680a11cf4b4551393f2/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-04-13 20:47:12.776090 | 2026-04-13 20:47:12.776160 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-04-13 20:47:12.801874 | instance | skipping: Conditional result was False 2026-04-13 20:47:12.814501 | 2026-04-13 20:47:12.814707 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-04-13 20:47:13.341489 | instance | changed 2026-04-13 20:47:13.349451 | 2026-04-13 20:47:13.349597 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-04-13 20:47:13.522617 | instance | ok 2026-04-13 20:47:13.530411 | 2026-04-13 20:47:13.530586 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-04-13 20:47:14.010920 | instance | changed 2026-04-13 20:47:14.020038 | 2026-04-13 20:47:14.020238 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-04-13 20:47:14.502249 | instance | changed 2026-04-13 20:47:14.510896 | 2026-04-13 20:47:14.511074 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-04-13 20:47:14.537235 | instance | skipping: Conditional result was False 2026-04-13 20:47:14.548121 | 2026-04-13 20:47:14.548349 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-04-13 20:47:14.940823 | instance -> localhost | changed 2026-04-13 20:47:14.956419 | 2026-04-13 20:47:14.956579 | TASK [add-build-sshkey : Add back temp key] 2026-04-13 20:47:15.256398 | instance -> localhost | Identity added: /var/lib/zuul/builds/905f74e3d5d74680a11cf4b4551393f2/work/905f74e3d5d74680a11cf4b4551393f2_id_rsa (zuul-build-sshkey) 2026-04-13 20:47:15.256626 | instance -> localhost | ok: Runtime: 0:00:00.009003 2026-04-13 20:47:15.262172 | 2026-04-13 20:47:15.262243 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-04-13 20:47:15.583399 | instance | ok 2026-04-13 20:47:15.631717 | 2026-04-13 20:47:15.631884 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-04-13 20:47:15.657401 | instance | skipping: Conditional result was False 2026-04-13 20:47:15.673508 | 2026-04-13 20:47:15.673695 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-04-13 20:47:16.040861 | instance | ok 2026-04-13 20:47:16.047197 | 2026-04-13 20:47:16.047335 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-04-13 20:47:17.770959 | instance | Output suppressed because no_log was given 2026-04-13 20:47:17.787410 | 2026-04-13 20:47:17.787631 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-04-13 20:47:17.997766 | instance | ok: "logs" 2026-04-13 20:47:17.998161 | instance | ok: All items complete 2026-04-13 20:47:17.998214 | 2026-04-13 20:47:18.154290 | instance | ok: "artifacts" 2026-04-13 20:47:18.312711 | instance | ok: "docs" 2026-04-13 20:47:18.326345 | 2026-04-13 20:47:18.326549 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-04-13 20:47:18.514624 | instance | changed: "logs" 2026-04-13 20:47:18.666369 | instance | changed: "artifacts" 2026-04-13 20:47:18.814802 | instance | changed: "docs" 2026-04-13 20:47:18.833308 | 2026-04-13 20:47:18.833445 | PLAY RECAP 2026-04-13 20:47:18.833510 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-04-13 20:47:18.833553 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-13 20:47:18.833585 | 2026-04-13 20:47:18.964502 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-13 20:47:18.968200 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-13 20:47:19.541375 | 2026-04-13 20:47:19.541537 | PLAY [all] 2026-04-13 20:47:19.553351 | 2026-04-13 20:47:19.553476 | TASK [setup-uv : Extract archive] 2026-04-13 20:47:21.856009 | instance | changed 2026-04-13 20:47:21.865417 | 2026-04-13 20:47:21.865622 | TASK [setup-uv : Print version] 2026-04-13 20:47:22.494190 | instance | uv 0.8.13 2026-04-13 20:47:22.410324 | instance | ok: Runtime: 0:00:00.013007 2026-04-13 20:47:22.419207 | 2026-04-13 20:47:22.419312 | PLAY RECAP 2026-04-13 20:47:22.419382 | instance | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-13 20:47:22.419448 | 2026-04-13 20:47:22.542219 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-13 20:47:22.544801 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/pre.yml@main] 2026-04-13 20:47:23.201134 | 2026-04-13 20:47:23.201297 | PLAY [all] 2026-04-13 20:47:23.213950 | 2026-04-13 20:47:23.214108 | TASK [Install "jq" for log collection] 2026-04-13 20:47:33.443829 | instance | changed 2026-04-13 20:47:33.450967 | 2026-04-13 20:47:33.451041 | TASK [Install pip3 for Python package management] 2026-04-13 20:47:37.910298 | instance | changed 2026-04-13 20:47:37.918690 | 2026-04-13 20:47:37.918869 | TASK [Install Python "kubernetes" library for kubernetes.core modules] 2026-04-13 20:47:40.996860 | instance | changed 2026-04-13 20:47:41.000619 | 2026-04-13 20:47:41.000776 | PLAY [all] 2026-04-13 20:47:41.011314 | 2026-04-13 20:47:41.011469 | TASK [ensure-go : Check installed go version] 2026-04-13 20:47:41.556902 | instance | ok: ERROR (ignored) 2026-04-13 20:47:41.557217 | instance | { 2026-04-13 20:47:41.557263 | instance | "failed_when_result": false, 2026-04-13 20:47:41.557295 | instance | "msg": "[Errno 2] No such file or directory: b'go'", 2026-04-13 20:47:41.557327 | instance | "rc": 2 2026-04-13 20:47:41.557364 | instance | } 2026-04-13 20:47:41.565187 | 2026-04-13 20:47:41.565328 | TASK [ensure-go : Skip if correct version of go is installed] 2026-04-13 20:47:41.616441 | instance | ok 2026-04-13 20:47:41.628023 | instance | included: /var/lib/zuul/builds/905f74e3d5d74680a11cf4b4551393f2/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/ensure-go/tasks/install-go.yaml 2026-04-13 20:47:41.636477 | 2026-04-13 20:47:41.636617 | TASK [ensure-go : Create temp directory] 2026-04-13 20:47:41.982018 | instance | changed 2026-04-13 20:47:41.988973 | 2026-04-13 20:47:41.989061 | TASK [ensure-go : Get archive checksum] 2026-04-13 20:47:42.703065 | instance | ok: OK (64 bytes) 2026-04-13 20:47:42.711106 | 2026-04-13 20:47:42.711255 | TASK [ensure-go : Download go archive] 2026-04-13 20:47:44.333718 | instance | changed: OK (78559214 bytes) 2026-04-13 20:47:44.351203 | 2026-04-13 20:47:44.351333 | TASK [ensure-go : Install go] 2026-04-13 20:47:50.207948 | instance | changed 2026-04-13 20:47:50.216858 | 2026-04-13 20:47:50.216995 | PLAY RECAP 2026-04-13 20:47:50.217053 | instance | ok: 9 changed: 6 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-13 20:47:50.217081 | 2026-04-13 20:47:50.393519 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/pre.yml@main] 2026-04-13 20:47:50.397543 | RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/run.yml@main] 2026-04-13 20:47:51.219562 | ERROR! 'molecule_scenario' is undefined. 'molecule_scenario' is undefined 2026-04-13 20:47:51.219940 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/run.yml@main] 2026-04-13 20:47:51.223782 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@main] 2026-04-13 20:47:52.011369 | 2026-04-13 20:47:52.011529 | PLAY [all] 2026-04-13 20:47:52.035145 | 2026-04-13 20:47:52.035340 | TASK [gather-host-logs : creating directory for system status] 2026-04-13 20:47:52.405270 | instance | changed 2026-04-13 20:47:52.411733 | 2026-04-13 20:47:52.411870 | TASK [gather-host-logs : Get logs for each host] 2026-04-13 20:47:53.196445 | instance | + systemd-cgls --full --all --no-pager 2026-04-13 20:47:53.207453 | instance | + ip addr 2026-04-13 20:47:53.209850 | instance | + ip route 2026-04-13 20:47:53.210951 | instance | + lsblk 2026-04-13 20:47:53.212905 | instance | + mount 2026-04-13 20:47:53.214242 | instance | + docker images 2026-04-13 20:47:53.214593 | instance | /bin/bash: line 7: docker: command not found 2026-04-13 20:47:53.214733 | instance | + brctl show 2026-04-13 20:47:53.215050 | instance | /bin/bash: line 8: brctl: command not found 2026-04-13 20:47:53.215254 | instance | + ps aux --sort=-%mem 2026-04-13 20:47:53.223133 | instance | + dpkg -l 2026-04-13 20:47:53.228470 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul)) 2026-04-13 20:47:53.228860 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul 2026-04-13 20:47:53.228913 | instance | /bin/bash: line 11: docker: command not found 2026-04-13 20:47:53.229216 | instance | + '[' '!' -z '' ']' 2026-04-13 20:47:53.454683 | instance | ok: Runtime: 0:00:00.038896 2026-04-13 20:47:53.465711 | 2026-04-13 20:47:53.465930 | TASK [gather-host-logs : Downloads logs to executor] 2026-04-13 20:47:54.142647 | instance | changed: 2026-04-13 20:47:54.142871 | instance | created directory /var/lib/zuul/builds/905f74e3d5d74680a11cf4b4551393f2/work/logs/instance 2026-04-13 20:47:54.142933 | instance | cd+++++++++ system/ 2026-04-13 20:47:54.142980 | instance | >f+++++++++ system/brctl-show.txt 2026-04-13 20:47:54.143016 | instance | >f+++++++++ system/docker-images.txt 2026-04-13 20:47:54.143045 | instance | >f+++++++++ system/ip-addr.txt 2026-04-13 20:47:54.143077 | instance | >f+++++++++ system/ip-route.txt 2026-04-13 20:47:54.143110 | instance | >f+++++++++ system/lsblk.txt 2026-04-13 20:47:54.143140 | instance | >f+++++++++ system/mount.txt 2026-04-13 20:47:54.143171 | instance | >f+++++++++ system/packages.txt 2026-04-13 20:47:54.143200 | instance | >f+++++++++ system/ps.txt 2026-04-13 20:47:54.143228 | instance | >f+++++++++ system/systemd-cgls.txt 2026-04-13 20:47:54.155608 | 2026-04-13 20:47:54.155736 | LOOP [helm-release-status : creating directory for helm release status] 2026-04-13 20:47:54.355546 | instance | changed: "values" 2026-04-13 20:47:54.524303 | instance | changed: "releases" 2026-04-13 20:47:54.546259 | 2026-04-13 20:47:54.546419 | TASK [helm-release-status : Gather get release status for helm charts] 2026-04-13 20:47:54.764688 | instance | /bin/bash: line 3: kubectl: command not found 2026-04-13 20:47:55.086833 | instance | ok: Runtime: 0:00:00.007202 2026-04-13 20:47:55.094971 | 2026-04-13 20:47:55.095154 | TASK [helm-release-status : Downloads logs to executor] 2026-04-13 20:47:55.610042 | instance | changed: 2026-04-13 20:47:55.610250 | instance | cd+++++++++ helm/ 2026-04-13 20:47:55.610294 | instance | cd+++++++++ helm/releases/ 2026-04-13 20:47:55.610331 | instance | cd+++++++++ helm/values/ 2026-04-13 20:47:55.625145 | 2026-04-13 20:47:55.625313 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects] 2026-04-13 20:47:55.829157 | instance | changed 2026-04-13 20:47:55.837655 | 2026-04-13 20:47:55.837871 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects] 2026-04-13 20:47:56.055761 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-13 20:47:56.056009 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-13 20:47:56.061440 | instance | environment: line 1: kubectl: command not found 2026-04-13 20:47:56.062835 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-13 20:47:56.063570 | instance | environment: line 1: kubectl: command not found 2026-04-13 20:47:56.064258 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-13 20:47:56.065423 | instance | environment: line 1: kubectl: command not found 2026-04-13 20:47:56.066595 | instance | environment: line 1: kubectl: command not found 2026-04-13 20:47:56.066621 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-13 20:47:56.067964 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-13 20:47:56.068825 | instance | environment: line 1: kubectl: command not found 2026-04-13 20:47:56.069644 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-13 20:47:56.434822 | instance | ok: Runtime: 0:00:00.023064 2026-04-13 20:47:56.440307 | 2026-04-13 20:47:56.440409 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects] 2026-04-13 20:47:56.626030 | instance | changed 2026-04-13 20:47:56.632333 | 2026-04-13 20:47:56.632460 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects] 2026-04-13 20:47:56.894306 | instance | environment: line 5: kubectl: command not found 2026-04-13 20:47:56.894604 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-13 20:47:56.895371 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-13 20:47:56.895410 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-13 20:47:57.200382 | instance | ok: Runtime: 0:00:00.007404 2026-04-13 20:47:57.206223 | 2026-04-13 20:47:57.206307 | TASK [describe-kubernetes-objects : Downloads logs to executor] 2026-04-13 20:47:57.725631 | instance | changed: 2026-04-13 20:47:57.725995 | instance | cd+++++++++ objects/ 2026-04-13 20:47:57.726043 | instance | cd+++++++++ objects/cluster/ 2026-04-13 20:47:57.726076 | instance | cd+++++++++ objects/namespaced/ 2026-04-13 20:47:57.736566 | 2026-04-13 20:47:57.736691 | TASK [gather-pod-logs : creating directory for pod logs] 2026-04-13 20:47:57.920351 | instance | changed 2026-04-13 20:47:57.928405 | 2026-04-13 20:47:57.928540 | TASK [gather-pod-logs : creating directory for failed pod logs] 2026-04-13 20:47:58.119259 | instance | changed 2026-04-13 20:47:58.126249 | 2026-04-13 20:47:58.126411 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)] 2026-04-13 20:47:58.330774 | instance | environment: line 3: kubectl: command not found 2026-04-13 20:47:58.660439 | instance | ok: Runtime: 0:00:00.008048 2026-04-13 20:47:58.667454 | 2026-04-13 20:47:58.667554 | TASK [gather-pod-logs : Downloads pod logs to executor] 2026-04-13 20:47:59.192563 | instance | changed: 2026-04-13 20:47:59.192740 | instance | cd+++++++++ pod-logs/ 2026-04-13 20:47:59.192769 | instance | cd+++++++++ pod-logs/failed-pods/ 2026-04-13 20:47:59.204741 | 2026-04-13 20:47:59.372419 | TASK [gather-prom-metrics : creating directory for helm release descriptions] 2026-04-13 20:47:59.577357 | instance | changed 2026-04-13 20:47:59.585414 | 2026-04-13 20:47:59.585504 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces] 2026-04-13 20:47:59.790880 | instance | /bin/bash: line 2: kubectl: command not found 2026-04-13 20:48:00.122349 | instance | ok: Runtime: 0:00:00.041031 2026-04-13 20:48:00.127674 | 2026-04-13 20:48:00.127818 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr] 2026-04-13 20:48:00.327223 | instance | /bin/bash: line 2: kubectl: command not found 2026-04-13 20:48:00.357176 | instance | ceph-mgr endpoints: 2026-04-13 20:48:00.666741 | instance | ok: Runtime: 0:00:00.036273 2026-04-13 20:48:00.674593 | 2026-04-13 20:48:00.674740 | TASK [gather-prom-metrics : Get metrics from fluentd pods] 2026-04-13 20:48:00.874379 | instance | /bin/bash: line 4: kubectl: command not found 2026-04-13 20:48:01.211864 | instance | ok: Runtime: 0:00:00.033810 2026-04-13 20:48:01.223630 | 2026-04-13 20:48:01.223860 | TASK [gather-prom-metrics : Downloads logs to executor] 2026-04-13 20:48:01.717677 | instance | changed: cd+++++++++ prometheus/ 2026-04-13 20:48:01.729540 | 2026-04-13 20:48:01.729677 | TASK [gather-selenium-data : creating directory for helm release descriptions] 2026-04-13 20:48:01.926317 | instance | changed 2026-04-13 20:48:01.935152 | 2026-04-13 20:48:01.935339 | TASK [gather-selenium-data : Get selenium data] 2026-04-13 20:48:02.144143 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/. 2026-04-13 20:48:02.145284 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory 2026-04-13 20:48:02.478512 | instance | ERROR 2026-04-13 20:48:02.478733 | instance | { 2026-04-13 20:48:02.478776 | instance | "delta": "0:00:00.005759", 2026-04-13 20:48:02.478809 | instance | "end": "2026-04-13 20:48:02.145587", 2026-04-13 20:48:02.478837 | instance | "msg": "non-zero return code", 2026-04-13 20:48:02.478862 | instance | "rc": 1, 2026-04-13 20:48:02.478884 | instance | "start": "2026-04-13 20:48:02.139828" 2026-04-13 20:48:02.478907 | instance | } 2026-04-13 20:48:02.478937 | instance | ERROR: Ignoring Errors 2026-04-13 20:48:02.485872 | 2026-04-13 20:48:02.486048 | TASK [gather-selenium-data : Downloads logs to executor] 2026-04-13 20:48:02.985949 | instance | changed: cd+++++++++ selenium/ 2026-04-13 20:48:02.992878 | 2026-04-13 20:48:02.993073 | PLAY RECAP 2026-04-13 20:48:02.993169 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1 2026-04-13 20:48:02.993201 | 2026-04-13 20:48:03.141994 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@main] 2026-04-13 20:48:03.147574 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-13 20:48:03.849600 | 2026-04-13 20:48:03.849748 | PLAY [all] 2026-04-13 20:48:03.861791 | 2026-04-13 20:48:03.861893 | TASK [fetch-output : Set log path for multiple nodes] 2026-04-13 20:48:03.907318 | instance | skipping: Conditional result was False 2026-04-13 20:48:03.918563 | 2026-04-13 20:48:03.918677 | TASK [fetch-output : Set log path for single node] 2026-04-13 20:48:03.950791 | instance | ok 2026-04-13 20:48:03.958282 | 2026-04-13 20:48:03.958475 | LOOP [fetch-output : Ensure local output dirs] 2026-04-13 20:48:04.668317 | instance -> localhost | ok: "/var/lib/zuul/builds/905f74e3d5d74680a11cf4b4551393f2/work/logs" 2026-04-13 20:48:04.886461 | instance -> localhost | changed: "/var/lib/zuul/builds/905f74e3d5d74680a11cf4b4551393f2/work/artifacts" 2026-04-13 20:48:05.566720 | instance -> localhost | changed: "/var/lib/zuul/builds/905f74e3d5d74680a11cf4b4551393f2/work/docs" 2026-04-13 20:48:05.580668 | 2026-04-13 20:48:05.580910 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-04-13 20:48:06.193453 | instance | changed: .d..t...... ./ 2026-04-13 20:48:06.193698 | instance | changed: All items complete 2026-04-13 20:48:06.193737 | 2026-04-13 20:48:06.651506 | instance | changed: .d..t...... ./ 2026-04-13 20:48:07.139717 | instance | changed: .d..t...... ./ 2026-04-13 20:48:07.152605 | 2026-04-13 20:48:07.152735 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-04-13 20:48:07.626367 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.006097 2026-04-13 20:48:07.897702 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.009579 2026-04-13 20:48:07.918364 | 2026-04-13 20:48:07.918547 | PLAY [all] 2026-04-13 20:48:07.928118 | 2026-04-13 20:48:07.928338 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-04-13 20:48:08.404150 | instance | changed 2026-04-13 20:48:08.411319 | 2026-04-13 20:48:08.411417 | PLAY RECAP 2026-04-13 20:48:08.411464 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-04-13 20:48:08.411487 | 2026-04-13 20:48:08.567910 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-13 20:48:08.572705 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-04-13 20:48:09.294651 | 2026-04-13 20:48:09.294825 | PLAY [localhost] 2026-04-13 20:48:09.308296 | 2026-04-13 20:48:09.308457 | TASK [Generate Zuul manifest] 2026-04-13 20:48:09.340074 | localhost | ok 2026-04-13 20:48:09.358412 | 2026-04-13 20:48:09.358586 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-04-13 20:48:09.933312 | localhost | changed 2026-04-13 20:48:09.943955 | 2026-04-13 20:48:09.944071 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-04-13 20:48:09.995876 | localhost | ok 2026-04-13 20:48:10.006817 | 2026-04-13 20:48:10.006956 | TASK [Upload logs] 2026-04-13 20:48:10.027645 | localhost | ok 2026-04-13 20:48:10.093623 | 2026-04-13 20:48:10.093752 | TASK [Set zuul-log-path fact] 2026-04-13 20:48:10.114740 | localhost | ok 2026-04-13 20:48:10.125956 | 2026-04-13 20:48:10.126045 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-13 20:48:10.156642 | localhost | ok 2026-04-13 20:48:10.166196 | 2026-04-13 20:48:10.166363 | TASK [upload-logs : Create log directories] 2026-04-13 20:48:10.653136 | localhost | changed 2026-04-13 20:48:10.660086 | 2026-04-13 20:48:10.660281 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-04-13 20:48:11.152216 | localhost -> localhost | ok: Runtime: 0:00:00.004600 2026-04-13 20:48:11.158514 | 2026-04-13 20:48:11.158576 | TASK [upload-logs : Upload logs to log server] 2026-04-13 20:48:11.654933 | localhost | Output suppressed because no_log was given 2026-04-13 20:48:11.659956 | 2026-04-13 20:48:11.660048 | LOOP [upload-logs : Compress console log and json output] 2026-04-13 20:48:11.709960 | localhost | skipping: Conditional result was False 2026-04-13 20:48:11.720345 | localhost | skipping: Conditional result was False 2026-04-13 20:48:11.735064 | 2026-04-13 20:48:11.735202 | LOOP [upload-logs : Upload compressed console log and json output] 2026-04-13 20:48:11.775371 | localhost | skipping: Conditional result was False 2026-04-13 20:48:11.775618 | 2026-04-13 20:48:11.780532 | localhost | skipping: Conditional result was False 2026-04-13 20:48:11.800037 | 2026-04-13 20:48:11.800168 | LOOP [upload-logs : Upload console log and json output]