2026-09-08 00:17:44.875561 | Job console starting
2026-09-08 00:17:44.885713 | Updating git repos
2026-09-08 00:17:44.926284 | Cloning repos into workspace
2026-09-08 00:17:44.983522 | Restoring repo states
2026-09-08 00:17:44.992209 | Merging changes
2026-09-08 00:17:45.378392 | Checking out repos
2026-09-08 00:17:45.426312 | Preparing playbooks
2026-09-08 00:17:47.374273 | Running Ansible setup
2026-09-08 00:17:51.047707 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-08 00:17:51.587778 | 
2026-09-08 00:17:51.587919 | PLAY [localhost]
2026-09-08 00:17:51.595068 | 
2026-09-08 00:17:51.595135 | TASK [Gathering Facts]
2026-09-08 00:17:52.453099 | localhost | ok
2026-09-08 00:17:52.466017 | 
2026-09-08 00:17:52.466166 | TASK [Setup log path fact]
2026-09-08 00:17:52.487796 | localhost | ok
2026-09-08 00:17:52.509535 | 
2026-09-08 00:17:52.509697 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-08 00:17:52.550104 | localhost | ok
2026-09-08 00:17:52.561660 | 
2026-09-08 00:17:52.561794 | TASK [emit-job-header : Print job information]
2026-09-08 00:17:52.614429 | # Job Information
2026-09-08 00:17:52.614638 | Ansible Version: 2.16.18
2026-09-08 00:17:52.614702 | Job: atmosphere-common-molecule-secretgen-controller
2026-09-08 00:17:52.614745 | Pipeline: check
2026-09-08 00:17:52.614790 | Executor: 2d72f0692154
2026-09-08 00:17:52.614848 | Triggered by: https://github.com/vexxhost/atmosphere.common/pull/84
2026-09-08 00:17:52.614896 | Event ID: 3afb4080-ab1a-11f1-9188-bbdd2c171c4d
2026-09-08 00:17:52.620319 | 
2026-09-08 00:17:52.620446 | LOOP [emit-job-header : Print node information]
2026-09-08 00:17:52.727564 | localhost | ok:
2026-09-08 00:17:52.727861 | localhost | # Node Information
2026-09-08 00:17:52.728006 | localhost | Inventory Hostname: controller
2026-09-08 00:17:52.728074 | localhost | Hostname: np0000192186
2026-09-08 00:17:52.728124 | localhost | Username: zuul
2026-09-08 00:17:52.728179 | localhost | Distro: Ubuntu 24.04
2026-09-08 00:17:52.728228 | localhost | Provider: yul1
2026-09-08 00:17:52.728272 | localhost | Region: ca-ymq-1
2026-09-08 00:17:52.728319 | localhost | Label: ubuntu-noble
2026-09-08 00:17:52.728363 | localhost | Product Name: OpenStack Nova
2026-09-08 00:17:52.728408 | localhost | Interface IP: 162.253.55.136
2026-09-08 00:17:52.741377 | 
2026-09-08 00:17:52.741572 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-09-08 00:17:53.142800 | localhost -> localhost | changed
2026-09-08 00:17:53.152275 | 
2026-09-08 00:17:53.152424 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-09-08 00:17:54.039447 | localhost -> localhost | changed
2026-09-08 00:17:54.050931 | 
2026-09-08 00:17:54.051069 | PLAY [all]
2026-09-08 00:17:54.066647 | 
2026-09-08 00:17:54.066779 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-09-08 00:17:54.295066 | controller -> localhost | ok
2026-09-08 00:17:54.307459 | 
2026-09-08 00:17:54.307703 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-09-08 00:17:54.345031 | controller | ok
2026-09-08 00:17:54.367722 | controller | included: /var/lib/zuul/builds/63a4efa4b25e48458921148c7cc7cfbc/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-09-08 00:17:54.375438 | 
2026-09-08 00:17:54.375512 | TASK [add-build-sshkey : Create Temp SSH key]
2026-09-08 00:17:55.227063 | controller -> localhost | Generating public/private rsa key pair.
2026-09-08 00:17:55.227800 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/63a4efa4b25e48458921148c7cc7cfbc/work/63a4efa4b25e48458921148c7cc7cfbc_id_rsa
2026-09-08 00:17:55.227857 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/63a4efa4b25e48458921148c7cc7cfbc/work/63a4efa4b25e48458921148c7cc7cfbc_id_rsa.pub
2026-09-08 00:17:55.227897 | controller -> localhost | The key fingerprint is:
2026-09-08 00:17:55.227933 | controller -> localhost | SHA256:wOFP1ZtBEWVMaA+Pkw+0YsdwILy4HrL4xiiBT0/cF3w zuul-build-sshkey
2026-09-08 00:17:55.228014 | controller -> localhost | The key's randomart image is:
2026-09-08 00:17:55.228052 | controller -> localhost | +---[RSA 3072]----+
2026-09-08 00:17:55.228099 | controller -> localhost | |      ... o++B+  |
2026-09-08 00:17:55.228138 | controller -> localhost | |     o ..o. O..  |
2026-09-08 00:17:55.228173 | controller -> localhost | |      +o.. * @   |
2026-09-08 00:17:55.228207 | controller -> localhost | |      .++ E @ o  |
2026-09-08 00:17:55.228240 | controller -> localhost | |.  . . .S+ o +   |
2026-09-08 00:17:55.228274 | controller -> localhost | |o . + + .     .  |
2026-09-08 00:17:55.228308 | controller -> localhost | | + * + o         |
2026-09-08 00:17:55.228342 | controller -> localhost | |. + = .          |
2026-09-08 00:17:55.228379 | controller -> localhost | | . o.            |
2026-09-08 00:17:55.228412 | controller -> localhost | +----[SHA256]-----+
2026-09-08 00:17:55.228487 | controller -> localhost | ok: Runtime: 0:00:00.389604
2026-09-08 00:17:55.239185 | 
2026-09-08 00:17:55.239453 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-09-08 00:17:55.274728 | controller | ok
2026-09-08 00:17:55.288129 | controller | included: /var/lib/zuul/builds/63a4efa4b25e48458921148c7cc7cfbc/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-09-08 00:17:55.299859 | 
2026-09-08 00:17:55.299976 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-09-08 00:17:55.324585 | controller | skipping: Conditional result was False
2026-09-08 00:17:55.336356 | 
2026-09-08 00:17:55.336471 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-09-08 00:17:55.869428 | controller | changed
2026-09-08 00:17:55.879256 | 
2026-09-08 00:17:55.879386 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-09-08 00:17:56.127718 | controller | ok
2026-09-08 00:17:56.139641 | 
2026-09-08 00:17:56.139848 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-09-08 00:17:56.752217 | controller | changed
2026-09-08 00:17:56.762661 | 
2026-09-08 00:17:56.762792 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-09-08 00:17:57.340154 | controller | changed
2026-09-08 00:17:57.345404 | 
2026-09-08 00:17:57.345473 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-09-08 00:17:57.369716 | controller | skipping: Conditional result was False
2026-09-08 00:17:57.377119 | 
2026-09-08 00:17:57.377217 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-09-08 00:17:57.745862 | controller -> localhost | changed
2026-09-08 00:17:57.766085 | 
2026-09-08 00:17:57.766262 | TASK [add-build-sshkey : Add back temp key]
2026-09-08 00:17:58.076230 | controller -> localhost | Identity added: /var/lib/zuul/builds/63a4efa4b25e48458921148c7cc7cfbc/work/63a4efa4b25e48458921148c7cc7cfbc_id_rsa (zuul-build-sshkey)
2026-09-08 00:17:58.076584 | controller -> localhost | ok: Runtime: 0:00:00.015580
2026-09-08 00:17:58.086413 | 
2026-09-08 00:17:58.086551 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-09-08 00:17:58.456740 | controller | ok
2026-09-08 00:17:58.466384 | 
2026-09-08 00:17:58.466509 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-09-08 00:17:58.502390 | controller | skipping: Conditional result was False
2026-09-08 00:17:58.523607 | 
2026-09-08 00:17:58.523741 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-09-08 00:17:58.971161 | controller | ok
2026-09-08 00:17:58.978634 | 
2026-09-08 00:17:58.978718 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-09-08 00:17:59.964325 | controller | Output suppressed because no_log was given
2026-09-08 00:17:59.989949 | 
2026-09-08 00:17:59.990146 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-09-08 00:18:00.261697 | controller | ok: "logs"
2026-09-08 00:18:00.262094 | controller | ok: All items complete
2026-09-08 00:18:00.262153 | 
2026-09-08 00:18:00.500033 | controller | ok: "artifacts"
2026-09-08 00:18:00.725026 | controller | ok: "docs"
2026-09-08 00:18:00.744060 | 
2026-09-08 00:18:00.744232 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-09-08 00:18:01.006376 | controller | changed: "logs"
2026-09-08 00:18:01.225426 | controller | changed: "artifacts"
2026-09-08 00:18:01.445044 | controller | changed: "docs"
2026-09-08 00:18:01.465864 | 
2026-09-08 00:18:01.466057 | PLAY RECAP
2026-09-08 00:18:01.466182 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-09-08 00:18:01.466265 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-08 00:18:01.466311 | 
2026-09-08 00:18:01.612896 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-08 00:18:01.614701 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-08 00:18:02.127069 | 
2026-09-08 00:18:02.127184 | PLAY [all]
2026-09-08 00:18:02.136776 | 
2026-09-08 00:18:02.136849 | TASK [setup-uv : Extract archive]
2026-09-08 00:18:04.761048 | controller | changed
2026-09-08 00:18:04.772461 | 
2026-09-08 00:18:04.772646 | TASK [setup-uv : Print version]
2026-09-08 00:18:05.188132 | controller | uv 0.8.13
2026-09-08 00:18:05.325075 | controller | ok: Runtime: 0:00:00.019696
2026-09-08 00:18:05.343960 | 
2026-09-08 00:18:05.344305 | TASK [Install Ansible collection siblings]
2026-09-08 00:18:05.807409 | controller | ok:
2026-09-08 00:18:05.807684 | controller | Skipping kubernetes.core: no checked-out sibling
2026-09-08 00:18:05.807742 | controller | Skipping openstack.cloud: no checked-out sibling
2026-09-08 00:18:05.807784 | controller | Skipping vexxhost.kubernetes: no checked-out sibling
2026-09-08 00:18:05.812206 | 
2026-09-08 00:18:05.812302 | PLAY RECAP
2026-09-08 00:18:05.812391 | controller | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-08 00:18:05.812603 | 
2026-09-08 00:18:05.955913 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-08 00:18:05.958442 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere.common/test-playbooks/molecule/pre.yml@main]
2026-09-08 00:18:06.508926 | 
2026-09-08 00:18:06.509084 | PLAY [Prepare Molecule log collection]
2026-09-08 00:18:06.520517 | 
2026-09-08 00:18:06.520590 | TASK [Install jq for log collection]
2026-09-08 00:18:15.219213 | controller | changed
2026-09-08 00:18:15.226306 | 
2026-09-08 00:18:15.226435 | PLAY RECAP
2026-09-08 00:18:15.226545 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-08 00:18:15.226675 | 
2026-09-08 00:18:15.392732 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere.common/test-playbooks/molecule/pre.yml@main]
2026-09-08 00:18:15.394012 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-08 00:18:15.965229 | 
2026-09-08 00:18:15.965395 | PLAY [all]
2026-09-08 00:18:15.976292 | 
2026-09-08 00:18:15.976380 | LOOP [Check inventory directories]
2026-09-08 00:18:16.363264 | controller | ok: "src/github.com/vexxhost/atmosphere.common"
2026-09-08 00:18:16.577706 | controller | ok: "src/github.com/vexxhost/atmosphere.common/molecule/secretgen-controller/inventory"
2026-09-08 00:18:16.778737 | controller | ok: "src/github.com/vexxhost/atmosphere.common/extensions/molecule/secretgen-controller/inventory"
2026-09-08 00:18:16.792751 | 
2026-09-08 00:18:16.793043 | LOOP [Copy inventory file for Zuul]
2026-09-08 00:18:17.833796 | controller | changed:
2026-09-08 00:18:17.834152 | controller | {
2026-09-08 00:18:17.834209 | controller |   "ansible_loop_var": "item",
2026-09-08 00:18:17.834254 | controller |   "changed": false,
2026-09-08 00:18:17.834304 | controller |   "failed": false,
2026-09-08 00:18:17.834379 | controller |   "invocation": {
2026-09-08 00:18:17.834440 | controller |     "module_args": {
2026-09-08 00:18:17.834521 | controller |       "checksum_algorithm": "sha1",
2026-09-08 00:18:17.834563 | controller |       "follow": true,
2026-09-08 00:18:17.834598 | controller |       "get_attributes": true,
2026-09-08 00:18:17.834635 | controller |       "get_checksum": true,
2026-09-08 00:18:17.834670 | controller |       "get_mime": true,
2026-09-08 00:18:17.834704 | controller |       "path": "src/github.com/vexxhost/atmosphere.common"
2026-09-08 00:18:17.834739 | controller |     }
2026-09-08 00:18:17.834772 | controller |   },
2026-09-08 00:18:17.834805 | controller |   "item": "src/github.com/vexxhost/atmosphere.common",
2026-09-08 00:18:17.834838 | controller |   "stat": {
2026-09-08 00:18:17.834871 | controller |     "atime": 1788826679.79697,
2026-09-08 00:18:17.834905 | controller |     "attr_flags": "e",
2026-09-08 00:18:17.834963 | controller |     "attributes": [
2026-09-08 00:18:17.835001 | controller |       "extents"
2026-09-08 00:18:17.835037 | controller |     ],
2026-09-08 00:18:17.835070 | controller |     "block_size": 4096,
2026-09-08 00:18:17.835104 | controller |     "blocks": 8,
2026-09-08 00:18:17.835138 | controller |     "charset": "unknown",
2026-09-08 00:18:17.835174 | controller |     "ctime": 1788826679.83297,
2026-09-08 00:18:17.835208 | controller |     "dev": 64769,
2026-09-08 00:18:17.835242 | controller |     "device_type": 0,
2026-09-08 00:18:17.835275 | controller |     "executable": true,
2026-09-08 00:18:17.835308 | controller |     "exists": true,
2026-09-08 00:18:17.835341 | controller |     "gid": 1000,
2026-09-08 00:18:17.835375 | controller |     "gr_name": "zuul",
2026-09-08 00:18:17.835408 | controller |     "inode": 1034272,
2026-09-08 00:18:17.835442 | controller |     "isblk": false,
2026-09-08 00:18:17.835476 | controller |     "ischr": false,
2026-09-08 00:18:17.835509 | controller |     "isdir": true,
2026-09-08 00:18:17.835543 | controller |     "isfifo": false,
2026-09-08 00:18:17.835576 | controller |     "isgid": false,
2026-09-08 00:18:17.835609 | controller |     "islnk": false,
2026-09-08 00:18:17.835642 | controller |     "isreg": false,
2026-09-08 00:18:17.835676 | controller |     "issock": false,
2026-09-08 00:18:17.835709 | controller |     "isuid": false,
2026-09-08 00:18:17.835741 | controller |     "mimetype": "unknown",
2026-09-08 00:18:17.835774 | controller |     "mode": "0755",
2026-09-08 00:18:17.835806 | controller |     "mtime": 1788826665.019065,
2026-09-08 00:18:17.835839 | controller |     "nlink": 10,
2026-09-08 00:18:17.835872 | controller |     "path": "src/github.com/vexxhost/atmosphere.common",
2026-09-08 00:18:17.835906 | controller |     "pw_name": "zuul",
2026-09-08 00:18:17.835958 | controller |     "readable": true,
2026-09-08 00:18:17.835993 | controller |     "rgrp": true,
2026-09-08 00:18:17.836027 | controller |     "roth": true,
2026-09-08 00:18:17.836060 | controller |     "rusr": true,
2026-09-08 00:18:17.836093 | controller |     "size": 4096,
2026-09-08 00:18:17.836128 | controller |     "uid": 1000,
2026-09-08 00:18:17.836161 | controller |     "version": "3895560504",
2026-09-08 00:18:17.836196 | controller |     "wgrp": false,
2026-09-08 00:18:17.836233 | controller |     "woth": false,
2026-09-08 00:18:17.836268 | controller |     "writeable": true,
2026-09-08 00:18:17.836301 | controller |     "wusr": true,
2026-09-08 00:18:17.836335 | controller |     "xgrp": true,
2026-09-08 00:18:17.836367 | controller |     "xoth": true,
2026-09-08 00:18:17.836400 | controller |     "xusr": true
2026-09-08 00:18:17.836431 | controller |   }
2026-09-08 00:18:17.836463 | controller | }
2026-09-08 00:18:17.836563 | controller | ok: All items complete
2026-09-08 00:18:17.836609 | 
2026-09-08 00:18:17.839182 | controller | skipping: Conditional result was False
2026-09-08 00:18:17.843480 | controller | skipping: Conditional result was False
2026-09-08 00:18:17.862999 | 
2026-09-08 00:18:17.863134 | LOOP [Switch "ansible_host" to private IP]
2026-09-08 00:18:18.281911 | controller | changed: 1 replacements made
2026-09-08 00:18:18.284730 | controller | skipping: Conditional result was False
2026-09-08 00:18:18.287953 | controller | skipping: Conditional result was False
2026-09-08 00:18:18.309394 | 
2026-09-08 00:18:18.309617 | TASK [Run Molecule scenario]
2026-09-08 00:18:18.823269 | controller | Using CPython 3.12.3 interpreter at: /usr/bin/python3
2026-09-08 00:18:18.823386 | controller | Creating virtual environment at: .venv
2026-09-08 00:18:18.832363 | controller | warning: No `requires-python` value found in the workspace. Defaulting to `>=3.12`.
2026-09-08 00:18:19.402852 | controller | Downloading pygments (1.2MiB)
2026-09-08 00:18:19.403075 | controller | Downloading pydantic-core (2.0MiB)
2026-09-08 00:18:19.406197 | controller | Downloading ansible-core (2.1MiB)
2026-09-08 00:18:19.406761 | controller | Downloading kubernetes (1.9MiB)
2026-09-08 00:18:19.407310 | controller | Downloading cryptography (4.3MiB)
2026-09-08 00:18:19.619996 | controller |  Downloading pydantic-core
2026-09-08 00:18:19.682256 | controller |  Downloading cryptography
2026-09-08 00:18:19.728369 | controller |  Downloading pygments
2026-09-08 00:18:19.842812 | controller |  Downloading kubernetes
2026-09-08 00:18:19.881852 | controller |  Downloading ansible-core
2026-09-08 00:18:19.950845 | controller | Installed 52 packages in 68ms
2026-09-08 00:18:20.731417 | controller | INFO     Collection 'atmosphere.common' detected.
2026-09-08 00:18:20.731508 | controller | INFO     Scenarios will be used from 'extensions/molecule'
2026-09-08 00:18:21.396677 | controller | INFO     [secretgen-controller > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-09-08 00:18:21.396776 | controller | INFO     [secretgen-controller > prerun] Performing prerun with role_name_check=0...
2026-09-08 00:22:56.821214 | controller | WARNING  Retrying execution failure 250 of: ansible-galaxy collection install -vvv vexxhost.kubernetes:>=2.5.0
2026-09-08 00:22:56.821378 | controller | ERROR    Command ansible-galaxy collection install -vvv vexxhost.kubernetes:>=2.5.0, returned 250 code:
2026-09-08 00:22:56.821394 | controller | Starting galaxy collection install process
2026-09-08 00:22:56.821406 | controller | Process install dependency map
2026-09-08 00:22:56.821418 | controller | the full traceback was:
2026-09-08 00:22:56.821430 | controller |
2026-09-08 00:22:56.821441 | controller | Traceback (most recent call last):
2026-09-08 00:22:56.821453 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/ansible/cli/__init__.py", line 646, in cli_executor
2026-09-08 00:22:56.821467 | controller |     exit_code = cli.run()
2026-09-08 00:22:56.821479 | controller |                 ^^^^^^^^^
2026-09-08 00:22:56.821490 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/ansible/cli/galaxy.py", line 727, in run
2026-09-08 00:22:56.821502 | controller |     return context.CLIARGS()
2026-09-08 00:22:56.821513 | controller |            ^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-08 00:22:56.821525 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/ansible/cli/galaxy.py", line 98, in method_wrapper
2026-09-08 00:22:56.821536 | controller |     return wrapped_method(*args, **kwargs)
2026-09-08 00:22:56.821547 | controller |            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-08 00:22:56.821558 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/ansible/cli/galaxy.py", line 1392, in execute_install
2026-09-08 00:22:56.821569 | controller |     self._execute_install_collection(
2026-09-08 00:22:56.821580 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/ansible/cli/galaxy.py", line 1441, in _execute_install_collection
2026-09-08 00:22:56.821592 | controller |     install_collections(
2026-09-08 00:22:56.821605 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/ansible/galaxy/collection/__init__.py", line 736, in install_collections
2026-09-08 00:22:56.821618 | controller |     dependency_map = _resolve_depenency_map(
2026-09-08 00:22:56.821629 | controller |                      ^^^^^^^^^^^^^^^^^^^^^^^
2026-09-08 00:22:56.821640 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/ansible/galaxy/collection/__init__.py", line 1863, in _resolve_depenency_map
2026-09-08 00:22:56.821651 | controller |     return collection_dep_resolver.resolve(
2026-09-08 00:22:56.821662 | controller |            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-08 00:22:56.821673 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/resolvelib/resolvers.py", line 546, in resolve
2026-09-08 00:22:56.821712 | controller |     state = resolution.resolve(requirements, max_rounds=max_rounds)
2026-09-08 00:22:56.821721 | controller |             ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-08 00:22:56.821729 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/resolvelib/resolvers.py", line 427, in resolve
2026-09-08 00:22:56.821737 | controller |     failure_causes = self._attempt_to_pin_criterion(name)
2026-09-08 00:22:56.821745 | controller |                      ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-08 00:22:56.821754 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/resolvelib/resolvers.py", line 239, in _attempt_to_pin_criterion
2026-09-08 00:22:56.821762 | controller |     criteria = self._get_updated_criteria(candidate)
2026-09-08 00:22:56.821770 | controller |                ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-08 00:22:56.821779 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/resolvelib/resolvers.py", line 229, in _get_updated_criteria
2026-09-08 00:22:56.821787 | controller |     for requirement in self._p.get_dependencies(candidate=candidate):
2026-09-08 00:22:56.821795 | controller |                        ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-08 00:22:56.821803 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/ansible/galaxy/dependency_resolution/providers.py", line 441, in get_dependencies
2026-09-08 00:22:56.821811 | controller |     req_map = self._api_proxy.get_collection_dependencies(candidate)
2026-09-08 00:22:56.821820 | controller |               ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-08 00:22:56.821828 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 171, in get_collection_dependencies
2026-09-08 00:22:56.821837 | controller |     get_collection_version_metadata(collection_candidate).
2026-09-08 00:22:56.821845 | controller |     ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-08 00:22:56.821853 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 157, in get_collection_version_metadata
2026-09-08 00:22:56.821861 | controller |     raise last_err
2026-09-08 00:22:56.821870 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 124, in get_collection_version_metadata
2026-09-08 00:22:56.821884 | controller |     version_metadata = api.get_collection_version_metadata(
2026-09-08 00:22:56.821932 | controller |                        ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-08 00:22:56.821951 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/ansible/galaxy/api.py", line 144, in wrapped
2026-09-08 00:22:56.821960 | controller |     return method(self, *args, **kwargs)
2026-09-08 00:22:56.821968 | controller |            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-08 00:22:56.821976 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/ansible/galaxy/api.py", line 815, in get_collection_version_metadata
2026-09-08 00:22:56.821984 | controller |     data = self._call_galaxy(n_collection_url, error_context_msg=error_context_msg, cache=True)
2026-09-08 00:22:56.821992 | controller |            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-08 00:22:56.822001 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/ansible/module_utils/api.py", line 169, in run_function
2026-09-08 00:22:56.822009 | controller |     return call_retryable_function()
2026-09-08 00:22:56.822017 | controller |            ^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-08 00:22:56.822025 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/ansible/galaxy/api.py", line 389, in _call_galaxy
2026-09-08 00:22:56.822033 | controller |     res = path_cache
2026-09-08 00:22:56.822051 | controller |           ~~~~~~~~~~^^^^^^^^^^^
2026-09-08 00:22:56.822059 | controller | KeyError: 'results'
2026-09-08 00:22:56.822068 | controller |
2026-09-08 00:22:56.822076 | controller | ansible-galaxy
2026-09-08 00:22:56.822084 | controller |   config file = None
2026-09-08 00:22:56.822092 | controller |   configured module search path =
2026-09-08 00:22:56.822100 | controller |   ansible python module location = /home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/ansible
2026-09-08 00:22:56.822117 | controller |   ansible collection location = /home/zuul/.ansible/collections:/usr/share/ansible/collections:/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/dist-packages:/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3/dist-packages:/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/local/lib/python3.12/dist-packages:/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages
2026-09-08 00:22:56.822129 | controller |   executable location = /home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/bin/ansible-galaxy
2026-09-08 00:22:56.822137 | controller |   python version = 3.12.3 (main, Jul 15 2026, 23:46:41)  (/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/bin/python)
2026-09-08 00:22:56.822145 | controller |   jinja version = 3.1.6
2026-09-08 00:22:56.822153 | controller |   libyaml = True
2026-09-08 00:22:56.822161 | controller | No config file found; using defaults
2026-09-08 00:22:56.822169 | controller | Found installed collection kubernetes.core:6.5.0 at '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core'
2026-09-08 00:22:56.822177 | controller | Found installed collection openstack.cloud:2.6.0 at '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud'
2026-09-08 00:22:56.822185 | controller | Opened /home/zuul/.ansible/galaxy_token
2026-09-08 00:22:56.822193 | controller | : Skipping Galaxy server https://galaxy.ansible.com/api/. Got an
2026-09-08 00:22:56.822201 | controller | unexpected error when getting available versions of collection
2026-09-08 00:22:56.822209 | controller | community.library_inventory_filtering_v1: 'results'
2026-09-08 00:22:56.822218 | controller | ERROR! Unexpected Exception, this is probably a bug: 'results'
2026-09-08 00:22:56.822226 | controller |
2026-09-08 00:22:56.822234 | controller | Traceback (most recent call last):
2026-09-08 00:22:56.822273 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/bin/molecule", line 10, in <module>
2026-09-08 00:22:56.822483 | controller |     sys.exit(main())
2026-09-08 00:22:56.822722 | controller |              ^^^^^^
2026-09-08 00:22:56.822963 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/click/core.py", line 1442, in __call__
2026-09-08 00:22:56.823644 | controller |     return self.main(*args, **kwargs)
2026-09-08 00:22:56.823918 | controller |            ^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-08 00:22:56.824126 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/click/core.py", line 1363, in main
2026-09-08 00:22:56.824770 | controller |     rv = self.invoke(ctx)
2026-09-08 00:22:56.825051 | controller |          ^^^^^^^^^^^^^^^^
2026-09-08 00:22:56.825236 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/click/core.py", line 1830, in invoke
2026-09-08 00:22:56.826032 | controller |     return _process_result(sub_ctx.command.invoke(sub_ctx))
2026-09-08 00:22:56.826312 | controller |                            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-08 00:22:56.826517 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/click/core.py", line 1226, in invoke
2026-09-08 00:22:56.827148 | controller |     return ctx.invoke(self.callback, **ctx.params)
2026-09-08 00:22:56.827412 | controller |            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-08 00:22:56.827594 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/click/core.py", line 794, in invoke
2026-09-08 00:22:56.828136 | controller |     return callback(*args, **kwargs)
2026-09-08 00:22:56.828394 | controller |            ^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-08 00:22:56.828572 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/molecule/click_cfg.py", line 583, in wrapper
2026-09-08 00:22:56.829025 | controller |     return func(*args, **kwargs)
2026-09-08 00:22:56.829283 | controller |            ^^^^^^^^^^^^^^^^^^^^^
2026-09-08 00:22:56.829490 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/click/decorators.py", line 34, in new_func
2026-09-08 00:22:56.829764 | controller |     return f(get_current_context(), *args, **kwargs)
2026-09-08 00:22:56.830070 | controller |            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-08 00:22:56.830242 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/molecule/click_cfg.py", line 418, in wrapper
2026-09-08 00:22:56.830658 | controller |     return func(ctx)
2026-09-08 00:22:56.830879 | controller |            ^^^^^^^^^
2026-09-08 00:22:56.831099 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/molecule/command/test.py", line 81, in test
2026-09-08 00:22:56.831449 | controller |     base.execute_cmdline_scenarios(scenario_name, args, command_args, ansible_args, exclude)
2026-09-08 00:22:56.831708 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/molecule/command/base.py", line 169, in execute_cmdline_scenarios
2026-09-08 00:22:56.832075 | controller |     _run_scenarios(scenarios, command_args, default_config)
2026-09-08 00:22:56.832324 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/molecule/command/base.py", line 240, in _run_scenarios
2026-09-08 00:22:56.832682 | controller |     scenario.config.runtime.prepare_environment(
2026-09-08 00:22:56.832883 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/ansible_compat/runtime.py", line 752, in prepare_environment
2026-09-08 00:22:56.833396 | controller |     self.install_collection(
2026-09-08 00:22:56.833600 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/ansible_compat/runtime.py", line 609, in install_collection
2026-09-08 00:22:56.834039 | controller |     raise InvalidPrerequisiteError(msg)
2026-09-08 00:22:56.837932 | controller | ansible_compat.errors.InvalidPrerequisiteError: Command ansible-galaxy collection install -vvv vexxhost.kubernetes:>=2.5.0, returned 250 code:
2026-09-08 00:22:56.837954 | controller | Starting galaxy collection install process
2026-09-08 00:22:56.837962 | controller | Process install dependency map
2026-09-08 00:22:56.837970 | controller | the full traceback was:
2026-09-08 00:22:56.837979 | controller |
2026-09-08 00:22:56.837987 | controller | Traceback (most recent call last):
2026-09-08 00:22:56.837996 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/ansible/cli/__init__.py", line 646, in cli_executor
2026-09-08 00:22:56.838004 | controller |     exit_code = cli.run()
2026-09-08 00:22:56.838012 | controller |                 ^^^^^^^^^
2026-09-08 00:22:56.838020 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/ansible/cli/galaxy.py", line 727, in run
2026-09-08 00:22:56.838029 | controller |     return context.CLIARGS['func']()
2026-09-08 00:22:56.838037 | controller |            ^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-08 00:22:56.838045 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/ansible/cli/galaxy.py", line 98, in method_wrapper
2026-09-08 00:22:56.838053 | controller |     return wrapped_method(*args, **kwargs)
2026-09-08 00:22:56.838061 | controller |            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-08 00:22:56.838069 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/ansible/cli/galaxy.py", line 1392, in execute_install
2026-09-08 00:22:56.838077 | controller |     self._execute_install_collection(
2026-09-08 00:22:56.838085 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/ansible/cli/galaxy.py", line 1441, in _execute_install_collection
2026-09-08 00:22:56.838093 | controller |     install_collections(
2026-09-08 00:22:56.838101 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/ansible/galaxy/collection/__init__.py", line 736, in install_collections
2026-09-08 00:22:56.838125 | controller |     dependency_map = _resolve_depenency_map(
2026-09-08 00:22:56.838135 | controller |                      ^^^^^^^^^^^^^^^^^^^^^^^
2026-09-08 00:22:56.838145 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/ansible/galaxy/collection/__init__.py", line 1863, in _resolve_depenency_map
2026-09-08 00:22:56.838156 | controller |     return collection_dep_resolver.resolve(
2026-09-08 00:22:56.838172 | controller |            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-08 00:22:56.838180 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/resolvelib/resolvers.py", line 546, in resolve
2026-09-08 00:22:56.838188 | controller |     state = resolution.resolve(requirements, max_rounds=max_rounds)
2026-09-08 00:22:56.838196 | controller |             ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-08 00:22:56.838204 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/resolvelib/resolvers.py", line 427, in resolve
2026-09-08 00:22:56.838212 | controller |     failure_causes = self._attempt_to_pin_criterion(name)
2026-09-08 00:22:56.838220 | controller |                      ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-08 00:22:56.838229 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/resolvelib/resolvers.py", line 239, in _attempt_to_pin_criterion
2026-09-08 00:22:56.838237 | controller |     criteria = self._get_updated_criteria(candidate)
2026-09-08 00:22:56.838245 | controller |                ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-08 00:22:56.838275 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/resolvelib/resolvers.py", line 229, in _get_updated_criteria
2026-09-08 00:22:56.838283 | controller |     for requirement in self._p.get_dependencies(candidate=candidate):
2026-09-08 00:22:56.838292 | controller |                        ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-08 00:22:56.838302 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/ansible/galaxy/dependency_resolution/providers.py", line 441, in get_dependencies
2026-09-08 00:22:56.838312 | controller |     req_map = self._api_proxy.get_collection_dependencies(candidate)
2026-09-08 00:22:56.838322 | controller |               ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-08 00:22:56.838332 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 171, in get_collection_dependencies
2026-09-08 00:22:56.838340 | controller |     get_collection_version_metadata(collection_candidate).
2026-09-08 00:22:56.838348 | controller |     ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-08 00:22:56.838356 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 157, in get_collection_version_metadata
2026-09-08 00:22:56.838364 | controller |     raise last_err
2026-09-08 00:22:56.838372 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 124, in get_collection_version_metadata
2026-09-08 00:22:56.838380 | controller |     version_metadata = api.get_collection_version_metadata(
2026-09-08 00:22:56.838388 | controller |                        ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-08 00:22:56.838408 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/ansible/galaxy/api.py", line 144, in wrapped
2026-09-08 00:22:56.838417 | controller |     return method(self, *args, **kwargs)
2026-09-08 00:22:56.838425 | controller |            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-08 00:22:56.838433 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/ansible/galaxy/api.py", line 815, in get_collection_version_metadata
2026-09-08 00:22:56.838441 | controller |     data = self._call_galaxy(n_collection_url, error_context_msg=error_context_msg, cache=True)
2026-09-08 00:22:56.838460 | controller |            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-08 00:22:56.838468 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/ansible/module_utils/api.py", line 169, in run_function
2026-09-08 00:22:56.838476 | controller |     return call_retryable_function()
2026-09-08 00:22:56.838484 | controller |            ^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-08 00:22:56.838492 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/ansible/galaxy/api.py", line 389, in _call_galaxy
2026-09-08 00:22:56.838500 | controller |     res = path_cache['results']
2026-09-08 00:22:56.838508 | controller |           ~~~~~~~~~~^^^^^^^^^^^
2026-09-08 00:22:56.838516 | controller | KeyError: 'results'
2026-09-08 00:22:56.838524 | controller |
2026-09-08 00:22:56.838533 | controller | ansible-galaxy [core 2.18.17]
2026-09-08 00:22:56.838541 | controller |   config file = None
2026-09-08 00:22:56.838556 | controller |   configured module search path = ['/home/zuul/.ansible/plugins/modules', '/usr/share/ansible/plugins/modules']
2026-09-08 00:22:56.838564 | controller |   ansible python module location = /home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/ansible
2026-09-08 00:22:56.838572 | controller |   ansible collection location = /home/zuul/.ansible/collections:/usr/share/ansible/collections:/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/dist-packages:/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3/dist-packages:/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/local/lib/python3.12/dist-packages:/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages
2026-09-08 00:22:56.838580 | controller |   executable location = /home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/bin/ansible-galaxy
2026-09-08 00:22:56.838588 | controller |   python version = 3.12.3 (main, Jul 15 2026, 23:46:41) [GCC 13.3.0] (/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/bin/python)
2026-09-08 00:22:56.838596 | controller |   jinja version = 3.1.6
2026-09-08 00:22:56.838604 | controller |   libyaml = True
2026-09-08 00:22:56.838612 | controller | No config file found; using defaults
2026-09-08 00:22:56.838620 | controller | Found installed collection kubernetes.core:6.5.0 at '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core'
2026-09-08 00:22:56.838628 | controller | Found installed collection openstack.cloud:2.6.0 at '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud'
2026-09-08 00:22:56.838636 | controller | Opened /home/zuul/.ansible/galaxy_token
2026-09-08 00:22:56.838644 | controller | [WARNING]: Skipping Galaxy server https://galaxy.ansible.com/api/. Got an
2026-09-08 00:22:56.838652 | controller | unexpected error when getting available versions of collection
2026-09-08 00:22:56.838660 | controller | community.library_inventory_filtering_v1: 'results'
2026-09-08 00:22:56.838668 | controller | ERROR! Unexpected Exception, this is probably a bug: 'results'
2026-09-08 00:22:56.838677 | controller |
2026-09-08 00:22:57.855847 | controller | ERROR
2026-09-08 00:22:57.856245 | controller | {
2026-09-08 00:22:57.856343 | controller |   "delta": "0:04:38.213444",
2026-09-08 00:22:57.856434 | controller |   "end": "2026-09-08 00:22:56.929381",
2026-09-08 00:22:57.856525 | controller |   "msg": "non-zero return code",
2026-09-08 00:22:57.856578 | controller |   "rc": 1,
2026-09-08 00:22:57.856655 | controller |   "start": "2026-09-08 00:18:18.715937"
2026-09-08 00:22:57.856750 | controller | }
failure
2026-09-08 00:22:57.864075 | 
2026-09-08 00:22:57.864173 | PLAY RECAP
2026-09-08 00:22:57.864255 | controller | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-09-08 00:22:57.864296 | 
2026-09-08 00:22:58.024732 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-08 00:22:58.028320 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere.common/test-playbooks/molecule/post.yml@main]
2026-09-08 00:23:01.422778 | 
2026-09-08 00:23:01.422962 | PLAY [Collect Molecule logs]
2026-09-08 00:23:01.476310 | 
2026-09-08 00:23:01.476585 | TASK [gather-host-logs : creating directory for system status]
2026-09-08 00:23:01.940687 | controller | changed
2026-09-08 00:23:01.952394 | 
2026-09-08 00:23:01.952624 | TASK [gather-host-logs : Get logs for each host]
2026-09-08 00:23:02.404538 | controller | + systemd-cgls --full --all --no-pager
2026-09-08 00:23:02.429708 | controller | + ip addr
2026-09-08 00:23:02.433882 | controller | + ip route
2026-09-08 00:23:02.438584 | controller | + lsblk
2026-09-08 00:23:02.446437 | controller | + mount
2026-09-08 00:23:02.451035 | controller | + docker images
2026-09-08 00:23:02.452132 | controller | /bin/bash: line 7: docker: command not found
2026-09-08 00:23:02.452712 | controller | + brctl show
2026-09-08 00:23:02.453727 | controller | /bin/bash: line 8: brctl: command not found
2026-09-08 00:23:02.454393 | controller | + ps aux --sort=-%mem
2026-09-08 00:23:02.487844 | controller | + dpkg -l
2026-09-08 00:23:02.506673 | controller | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul))
2026-09-08 00:23:02.507700 | controller | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul
2026-09-08 00:23:02.507777 | controller | /bin/bash: line 11: docker: command not found
2026-09-08 00:23:02.508480 | controller | + '[' '!' -z '' ']'
2026-09-08 00:23:02.999817 | controller | ok: Runtime: 0:00:00.113241
2026-09-08 00:23:03.012154 | 
2026-09-08 00:23:03.012237 | TASK [gather-host-logs : Downloads logs to executor]
2026-09-08 00:23:04.251452 | controller | changed:
2026-09-08 00:23:04.251776 | controller | created directory /var/lib/zuul/builds/63a4efa4b25e48458921148c7cc7cfbc/work/logs/controller
2026-09-08 00:23:04.251879 | controller | cd+++++++++ system/
2026-09-08 00:23:04.251929 | controller | >f+++++++++ system/brctl-show.txt
2026-09-08 00:23:04.251972 | controller | >f+++++++++ system/docker-images.txt
2026-09-08 00:23:04.252012 | controller | >f+++++++++ system/ip-addr.txt
2026-09-08 00:23:04.252058 | controller | >f+++++++++ system/ip-route.txt
2026-09-08 00:23:04.252100 | controller | >f+++++++++ system/lsblk.txt
2026-09-08 00:23:04.252139 | controller | >f+++++++++ system/mount.txt
2026-09-08 00:23:04.252184 | controller | >f+++++++++ system/packages.txt
2026-09-08 00:23:04.252222 | controller | >f+++++++++ system/ps.txt
2026-09-08 00:23:04.252264 | controller | >f+++++++++ system/systemd-cgls.txt
2026-09-08 00:23:04.274463 | 
2026-09-08 00:23:04.274552 | LOOP [helm-release-status : creating directory for helm release status]
2026-09-08 00:23:04.552393 | controller | changed: "values"
2026-09-08 00:23:04.778350 | controller | changed: "releases"
2026-09-08 00:23:04.798676 | 
2026-09-08 00:23:04.798932 | TASK [helm-release-status : Gather get release status for helm charts]
2026-09-08 00:23:05.101853 | controller | /bin/bash: line 3: kubectl: command not found
2026-09-08 00:23:06.905706 | controller | ok: Runtime: 0:00:00.009786
2026-09-08 00:23:06.915665 | 
2026-09-08 00:23:06.915789 | TASK [helm-release-status : Downloads logs to executor]
2026-09-08 00:23:07.455640 | controller | changed:
2026-09-08 00:23:07.455895 | controller | cd+++++++++ helm/
2026-09-08 00:23:07.455942 | controller | cd+++++++++ helm/releases/
2026-09-08 00:23:07.455978 | controller | cd+++++++++ helm/values/
2026-09-08 00:23:07.473282 | 
2026-09-08 00:23:07.473405 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-09-08 00:23:07.763210 | controller | changed
2026-09-08 00:23:07.776487 | 
2026-09-08 00:23:07.776839 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-09-08 00:23:08.149477 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-08 00:23:08.150168 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-08 00:23:08.159620 | controller | environment: line 1: kubectl: command not found
2026-09-08 00:23:08.159980 | controller | environment: line 1: kubectl: command not found
2026-09-08 00:23:08.161614 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-08 00:23:08.162821 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-08 00:23:08.167173 | controller | environment: line 1: kubectl: command not found
2026-09-08 00:23:08.168180 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-08 00:23:08.169926 | controller | environment: line 1: kubectl: command not found
2026-09-08 00:23:08.172021 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-08 00:23:08.174066 | controller | environment: line 1: kubectl: command not found
2026-09-08 00:23:08.176804 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-08 00:23:08.376463 | controller | ok: Runtime: 0:00:00.042777
2026-09-08 00:23:08.387176 | 
2026-09-08 00:23:08.387316 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-09-08 00:23:08.652911 | controller | changed
2026-09-08 00:23:08.661993 | 
2026-09-08 00:23:08.662093 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-09-08 00:23:08.944737 | controller | environment: line 5: kubectl: command not found
2026-09-08 00:23:08.946945 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-08 00:23:08.946986 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-08 00:23:08.947314 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-08 00:23:09.195489 | controller | ok: Runtime: 0:00:00.016837
2026-09-08 00:23:09.200610 | 
2026-09-08 00:23:09.200705 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-09-08 00:23:09.851513 | controller | changed:
2026-09-08 00:23:09.851858 | controller | cd+++++++++ objects/
2026-09-08 00:23:09.851920 | controller | cd+++++++++ objects/cluster/
2026-09-08 00:23:09.851962 | controller | cd+++++++++ objects/namespaced/
2026-09-08 00:23:09.873650 | 
2026-09-08 00:23:09.873778 | TASK [gather-pod-logs : creating directory for pod logs]
2026-09-08 00:23:10.161311 | controller | changed
2026-09-08 00:23:10.173338 | 
2026-09-08 00:23:10.173481 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-09-08 00:23:10.432454 | controller | changed
2026-09-08 00:23:10.438262 | 
2026-09-08 00:23:10.438343 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-09-08 00:23:10.722843 | controller | environment: line 3: kubectl: command not found
2026-09-08 00:23:10.970750 | controller | ok: Runtime: 0:00:00.015014
2026-09-08 00:23:10.976130 | 
2026-09-08 00:23:10.976260 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-09-08 00:23:11.589030 | controller | changed:
2026-09-08 00:23:11.589296 | controller | cd+++++++++ pod-logs/
2026-09-08 00:23:11.589341 | controller | cd+++++++++ pod-logs/failed-pods/
2026-09-08 00:23:11.604066 | 
2026-09-08 00:23:11.604180 | TASK [gather-prom-metrics : creating directory for helm release descriptions]
2026-09-08 00:23:11.906828 | controller | changed
2026-09-08 00:23:11.913532 | 
2026-09-08 00:23:11.913628 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces]
2026-09-08 00:23:12.204696 | controller | /bin/bash: line 2: kubectl: command not found
2026-09-08 00:23:12.454456 | controller | ok: Runtime: 0:00:00.017915
2026-09-08 00:23:12.465171 | 
2026-09-08 00:23:12.465316 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr]
2026-09-08 00:23:12.772746 | controller | /bin/bash: line 2: kubectl: command not found
2026-09-08 00:23:12.780769 | controller | ceph-mgr endpoints:
2026-09-08 00:23:13.012035 | controller | ok: Runtime: 0:00:00.016187
2026-09-08 00:23:13.018297 | 
2026-09-08 00:23:13.018398 | TASK [gather-prom-metrics : Get metrics from fluentd pods]
2026-09-08 00:23:13.290075 | controller | /bin/bash: line 4: kubectl: command not found
2026-09-08 00:23:13.562575 | controller | ok: Runtime: 0:00:00.015808
2026-09-08 00:23:13.575959 | 
2026-09-08 00:23:13.576110 | TASK [gather-prom-metrics : Downloads logs to executor]
2026-09-08 00:23:14.092779 | controller | changed: cd+++++++++ prometheus/
2026-09-08 00:23:14.193971 | 
2026-09-08 00:23:14.194238 | TASK [gather-selenium-data : creating directory for helm release descriptions]
2026-09-08 00:23:14.474221 | controller | changed
2026-09-08 00:23:14.502648 | 
2026-09-08 00:23:14.502860 | TASK [gather-selenium-data : Get selenium data]
2026-09-08 00:23:14.802625 | controller | + cp '/tmp/artifacts/*' /tmp/logs/selenium/.
2026-09-08 00:23:14.807139 | controller | cp: cannot stat '/tmp/artifacts/*': No such file or directory
2026-09-08 00:23:15.053580 | controller | ERROR
2026-09-08 00:23:15.053966 | controller | {
2026-09-08 00:23:15.054038 | controller |   "delta": "0:00:00.011885",
2026-09-08 00:23:15.054084 | controller |   "end": "2026-09-08 00:23:14.807681",
2026-09-08 00:23:15.054128 | controller |   "msg": "non-zero return code",
2026-09-08 00:23:15.054166 | controller |   "rc": 1,
2026-09-08 00:23:15.054205 | controller |   "start": "2026-09-08 00:23:14.795796"
2026-09-08 00:23:15.054242 | controller | }
2026-09-08 00:23:15.054290 | controller | ERROR: Ignoring Errors
2026-09-08 00:23:15.065976 | 
2026-09-08 00:23:15.066118 | TASK [gather-selenium-data : Downloads logs to executor]
2026-09-08 00:23:15.589519 | controller | changed: cd+++++++++ selenium/
2026-09-08 00:23:15.603017 | 
2026-09-08 00:23:15.603191 | PLAY RECAP
2026-09-08 00:23:15.603315 | controller | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1
2026-09-08 00:23:15.603379 | 
2026-09-08 00:23:15.770452 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere.common/test-playbooks/molecule/post.yml@main]
2026-09-08 00:23:15.772343 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-08 00:23:16.393217 | 
2026-09-08 00:23:16.393397 | PLAY [all]
2026-09-08 00:23:16.406171 | 
2026-09-08 00:23:16.406330 | TASK [fetch-output : Set log path for multiple nodes]
2026-09-08 00:23:16.471540 | controller | skipping: Conditional result was False
2026-09-08 00:23:16.479312 | 
2026-09-08 00:23:16.479426 | TASK [fetch-output : Set log path for single node]
2026-09-08 00:23:16.538776 | controller | ok
2026-09-08 00:23:16.547385 | 
2026-09-08 00:23:16.547477 | LOOP [fetch-output : Ensure local output dirs]
2026-09-08 00:23:16.954004 | controller -> localhost | ok: "/var/lib/zuul/builds/63a4efa4b25e48458921148c7cc7cfbc/work/logs"
2026-09-08 00:23:17.248946 | controller -> localhost | changed: "/var/lib/zuul/builds/63a4efa4b25e48458921148c7cc7cfbc/work/artifacts"
2026-09-08 00:23:17.481958 | controller -> localhost | changed: "/var/lib/zuul/builds/63a4efa4b25e48458921148c7cc7cfbc/work/docs"
2026-09-08 00:23:17.496689 | 
2026-09-08 00:23:17.496854 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-09-08 00:23:18.285406 | controller | changed: .d..t...... ./
2026-09-08 00:23:18.285846 | controller | changed: All items complete
2026-09-08 00:23:18.285918 | 
2026-09-08 00:23:18.885780 | controller | changed: .d..t...... ./
2026-09-08 00:23:19.436470 | controller | changed: .d..t...... ./
2026-09-08 00:23:19.465917 | 
2026-09-08 00:23:19.466084 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-09-08 00:23:19.949216 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.008747
2026-09-08 00:23:20.194996 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.008955
2026-09-08 00:23:20.216305 | 
2026-09-08 00:23:20.216462 | PLAY [all]
2026-09-08 00:23:20.223719 | 
2026-09-08 00:23:20.223785 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-09-08 00:23:20.738168 | controller | changed
2026-09-08 00:23:20.744647 | 
2026-09-08 00:23:20.745093 | PLAY RECAP
2026-09-08 00:23:20.745202 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-09-08 00:23:20.745247 | 
2026-09-08 00:23:20.909819 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-08 00:23:20.912749 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-09-08 00:23:21.593379 | 
2026-09-08 00:23:21.594596 | PLAY [localhost]
2026-09-08 00:23:21.621635 | 
2026-09-08 00:23:21.621943 | TASK [Generate Zuul manifest]
2026-09-08 00:23:21.647260 | localhost | ok
2026-09-08 00:23:21.675130 | 
2026-09-08 00:23:21.675335 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-09-08 00:23:22.125542 | localhost | changed
2026-09-08 00:23:22.140783 | 
2026-09-08 00:23:22.140901 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-09-08 00:23:22.171871 | localhost | ok
2026-09-08 00:23:22.182015 | 
2026-09-08 00:23:22.182101 | TASK [Upload logs]
2026-09-08 00:23:22.200484 | localhost | ok
2026-09-08 00:23:22.261661 | 
2026-09-08 00:23:22.261804 | TASK [Set zuul-log-path fact]
2026-09-08 00:23:22.289775 | localhost | ok
2026-09-08 00:23:22.300356 | 
2026-09-08 00:23:22.300425 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-08 00:23:22.338138 | localhost | ok
2026-09-08 00:23:22.345244 | 
2026-09-08 00:23:22.345309 | TASK [upload-logs : Create log directories]
2026-09-08 00:23:22.797875 | localhost | changed
2026-09-08 00:23:22.803596 | 
2026-09-08 00:23:22.803663 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-09-08 00:23:23.197597 | localhost -> localhost | ok: Runtime: 0:00:00.005792
2026-09-08 00:23:23.205714 | 
2026-09-08 00:23:23.205891 | TASK [upload-logs : Upload logs to log server]
2026-09-08 00:23:23.730429 | localhost | Output suppressed because no_log was given
2026-09-08 00:23:23.734398 | 
2026-09-08 00:23:23.734464 | LOOP [upload-logs : Compress console log and json output]
2026-09-08 00:23:23.801138 | localhost | skipping: Conditional result was False
2026-09-08 00:23:23.856131 | localhost | skipping: Conditional result was False
2026-09-08 00:23:23.864432 | 
2026-09-08 00:23:23.864606 | LOOP [upload-logs : Upload compressed console log and json output]
2026-09-08 00:23:23.906533 | localhost | skipping: Conditional result was False
2026-09-08 00:23:23.906946 | 
2026-09-08 00:23:23.909747 | localhost | skipping: Conditional result was False
2026-09-08 00:23:23.925160 | 
2026-09-08 00:23:23.925331 | LOOP [upload-logs : Upload console log and json output]