2026-08-31 17:52:35.703346 | Job console starting
2026-08-31 17:52:35.712326 | Updating git repos
2026-08-31 17:52:35.747700 | Cloning repos into workspace
2026-08-31 17:52:35.832429 | Restoring repo states
2026-08-31 17:52:35.842512 | Merging changes
2026-08-31 17:52:36.491364 | Checking out repos
2026-08-31 17:52:36.523292 | Preparing playbooks
2026-08-31 17:52:38.492407 | Running Ansible setup
2026-08-31 17:52:43.229341 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-08-31 17:52:43.846642 | 
2026-08-31 17:52:43.846882 | PLAY [localhost]
2026-08-31 17:52:43.854622 | 
2026-08-31 17:52:43.854691 | TASK [Gathering Facts]
2026-08-31 17:52:44.738592 | localhost | ok
2026-08-31 17:52:44.750962 | 
2026-08-31 17:52:44.751320 | TASK [Setup log path fact]
2026-08-31 17:52:44.773605 | localhost | ok
2026-08-31 17:52:44.789922 | 
2026-08-31 17:52:44.789992 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-08-31 17:52:44.818615 | localhost | ok
2026-08-31 17:52:44.826228 | 
2026-08-31 17:52:44.826296 | TASK [emit-job-header : Print job information]
2026-08-31 17:52:44.865227 | # Job Information
2026-08-31 17:52:44.865377 | Ansible Version: 2.16.18
2026-08-31 17:52:44.865422 | Job: atmosphere-common-molecule-secretgen-controller
2026-08-31 17:52:44.865454 | Pipeline: check
2026-08-31 17:52:44.865482 | Executor: 2d72f0692154
2026-08-31 17:52:44.865510 | Triggered by: https://github.com/vexxhost/atmosphere.common/pull/102
2026-08-31 17:52:44.865542 | Event ID: 3b283740-a564-11f1-8d91-063946bebca3
2026-08-31 17:52:44.869293 | 
2026-08-31 17:52:44.869385 | LOOP [emit-job-header : Print node information]
2026-08-31 17:52:45.004187 | localhost | ok:
2026-08-31 17:52:45.004415 | localhost | # Node Information
2026-08-31 17:52:45.004445 | localhost | Inventory Hostname: controller
2026-08-31 17:52:45.004467 | localhost | Hostname: np0000191615
2026-08-31 17:52:45.004487 | localhost | Username: zuul
2026-08-31 17:52:45.004509 | localhost | Distro: Ubuntu 24.04
2026-08-31 17:52:45.004529 | localhost | Provider: yul1
2026-08-31 17:52:45.004547 | localhost | Region: ca-ymq-1
2026-08-31 17:52:45.004566 | localhost | Label: ubuntu-noble
2026-08-31 17:52:45.004584 | localhost | Product Name: OpenStack Nova
2026-08-31 17:52:45.004602 | localhost | Interface IP: 162.253.55.159
2026-08-31 17:52:45.020480 | 
2026-08-31 17:52:45.020622 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-08-31 17:52:45.411914 | localhost -> localhost | changed
2026-08-31 17:52:45.421802 | 
2026-08-31 17:52:45.421910 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-08-31 17:52:46.245973 | localhost -> localhost | changed
2026-08-31 17:52:46.257364 | 
2026-08-31 17:52:46.257427 | PLAY [all]
2026-08-31 17:52:46.267885 | 
2026-08-31 17:52:46.267962 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-08-31 17:52:46.495334 | controller -> localhost | ok
2026-08-31 17:52:46.505749 | 
2026-08-31 17:52:46.505815 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-08-31 17:52:46.540885 | controller | ok
2026-08-31 17:52:46.556810 | controller | included: /var/lib/zuul/builds/0636888ade7c46d7a5fcb0c7ba6359f5/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-08-31 17:52:46.564079 | 
2026-08-31 17:52:46.564140 | TASK [add-build-sshkey : Create Temp SSH key]
2026-08-31 17:52:47.206054 | controller -> localhost | Generating public/private rsa key pair.
2026-08-31 17:52:47.206270 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/0636888ade7c46d7a5fcb0c7ba6359f5/work/0636888ade7c46d7a5fcb0c7ba6359f5_id_rsa
2026-08-31 17:52:47.206312 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/0636888ade7c46d7a5fcb0c7ba6359f5/work/0636888ade7c46d7a5fcb0c7ba6359f5_id_rsa.pub
2026-08-31 17:52:47.206344 | controller -> localhost | The key fingerprint is:
2026-08-31 17:52:47.206373 | controller -> localhost | SHA256:KRpo1qNV8PL9ZNxuIlu5LeMORZ/qzKsI0ua/76nZylA zuul-build-sshkey
2026-08-31 17:52:47.206413 | controller -> localhost | The key's randomart image is:
2026-08-31 17:52:47.206443 | controller -> localhost | +---[RSA 3072]----+
2026-08-31 17:52:47.206480 | controller -> localhost | |    .            |
2026-08-31 17:52:47.206510 | controller -> localhost | |     o           |
2026-08-31 17:52:47.206539 | controller -> localhost | |    . o   .      |
2026-08-31 17:52:47.206567 | controller -> localhost | |   o + . + o .   |
2026-08-31 17:52:47.206595 | controller -> localhost | |  + = E S = +    |
2026-08-31 17:52:47.206624 | controller -> localhost | | o + = . = +     |
2026-08-31 17:52:47.206651 | controller -> localhost | |  o *   o * o    |
2026-08-31 17:52:47.206680 | controller -> localhost | |   + + + Xo=     |
2026-08-31 17:52:47.206727 | controller -> localhost | |    ..O*B+O+.    |
2026-08-31 17:52:47.206759 | controller -> localhost | +----[SHA256]-----+
2026-08-31 17:52:47.206827 | controller -> localhost | ok: Runtime: 0:00:00.229318
2026-08-31 17:52:47.214924 | 
2026-08-31 17:52:47.215010 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-08-31 17:52:47.250754 | controller | ok
2026-08-31 17:52:47.261760 | controller | included: /var/lib/zuul/builds/0636888ade7c46d7a5fcb0c7ba6359f5/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-08-31 17:52:47.269493 | 
2026-08-31 17:52:47.269551 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-08-31 17:52:47.293051 | controller | skipping: Conditional result was False
2026-08-31 17:52:47.305509 | 
2026-08-31 17:52:47.305579 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-08-31 17:52:47.880708 | controller | changed
2026-08-31 17:52:47.886011 | 
2026-08-31 17:52:47.886074 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-08-31 17:52:48.095064 | controller | ok
2026-08-31 17:52:48.101161 | 
2026-08-31 17:52:48.101230 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-08-31 17:52:48.711955 | controller | changed
2026-08-31 17:52:48.719361 | 
2026-08-31 17:52:48.719468 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-08-31 17:52:49.303518 | controller | changed
2026-08-31 17:52:49.312320 | 
2026-08-31 17:52:49.312389 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-08-31 17:52:49.339438 | controller | skipping: Conditional result was False
2026-08-31 17:52:49.351520 | 
2026-08-31 17:52:49.351586 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-08-31 17:52:49.710250 | controller -> localhost | changed
2026-08-31 17:52:49.725755 | 
2026-08-31 17:52:49.725825 | TASK [add-build-sshkey : Add back temp key]
2026-08-31 17:52:50.004553 | controller -> localhost | Identity added: /var/lib/zuul/builds/0636888ade7c46d7a5fcb0c7ba6359f5/work/0636888ade7c46d7a5fcb0c7ba6359f5_id_rsa (zuul-build-sshkey)
2026-08-31 17:52:50.004800 | controller -> localhost | ok: Runtime: 0:00:00.012972
2026-08-31 17:52:50.010783 | 
2026-08-31 17:52:50.010846 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-08-31 17:52:50.366903 | controller | ok
2026-08-31 17:52:50.374741 | 
2026-08-31 17:52:50.374926 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-08-31 17:52:50.401697 | controller | skipping: Conditional result was False
2026-08-31 17:52:50.420161 | 
2026-08-31 17:52:50.420233 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-08-31 17:52:50.841469 | controller | ok
2026-08-31 17:52:50.848582 | 
2026-08-31 17:52:50.848645 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-08-31 17:52:52.081116 | controller | Output suppressed because no_log was given
2026-08-31 17:52:52.091498 | 
2026-08-31 17:52:52.091571 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-08-31 17:52:52.333190 | controller | ok: "logs"
2026-08-31 17:52:52.333538 | controller | ok: All items complete
2026-08-31 17:52:52.333568 | 
2026-08-31 17:52:52.537562 | controller | ok: "artifacts"
2026-08-31 17:52:52.724493 | controller | ok: "docs"
2026-08-31 17:52:52.739885 | 
2026-08-31 17:52:52.740049 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-08-31 17:52:52.947844 | controller | changed: "logs"
2026-08-31 17:52:53.131771 | controller | changed: "artifacts"
2026-08-31 17:52:53.305449 | controller | changed: "docs"
2026-08-31 17:52:53.320418 | 
2026-08-31 17:52:53.320585 | PLAY RECAP
2026-08-31 17:52:53.320652 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-08-31 17:52:53.320692 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-31 17:52:53.320722 | 
2026-08-31 17:52:53.479655 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-08-31 17:52:53.482408 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-08-31 17:52:54.067332 | 
2026-08-31 17:52:54.067458 | PLAY [all]
2026-08-31 17:52:54.079309 | 
2026-08-31 17:52:54.079386 | TASK [setup-uv : Extract archive]
2026-08-31 17:52:56.828910 | controller | changed
2026-08-31 17:52:56.837109 | 
2026-08-31 17:52:56.837192 | TASK [setup-uv : Print version]
2026-08-31 17:52:57.233357 | controller | uv 0.8.13
2026-08-31 17:52:57.378066 | controller | ok: Runtime: 0:00:00.017721
2026-08-31 17:52:57.390921 | 
2026-08-31 17:52:57.390993 | TASK [Install Ansible collection siblings]
2026-08-31 17:52:57.829111 | controller | ok:
2026-08-31 17:52:57.829320 | controller | Skipping kubernetes.core: no checked-out sibling
2026-08-31 17:52:57.829359 | controller | Skipping openstack.cloud: no checked-out sibling
2026-08-31 17:52:57.829390 | controller | Skipping vexxhost.kubernetes: no checked-out sibling
2026-08-31 17:52:57.832411 | 
2026-08-31 17:52:57.832485 | PLAY RECAP
2026-08-31 17:52:57.832538 | controller | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-31 17:52:57.832560 | 
2026-08-31 17:52:57.965590 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-08-31 17:52:57.967590 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere.common/test-playbooks/molecule/pre.yml@main]
2026-08-31 17:52:58.558844 | 
2026-08-31 17:52:58.558988 | PLAY [Prepare Molecule log collection]
2026-08-31 17:52:58.570479 | 
2026-08-31 17:52:58.570553 | TASK [Install jq for log collection]
2026-08-31 17:53:06.554900 | controller | changed
2026-08-31 17:53:06.557480 | 
2026-08-31 17:53:06.557561 | PLAY RECAP
2026-08-31 17:53:06.557633 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-31 17:53:06.557704 | 
2026-08-31 17:53:06.674314 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere.common/test-playbooks/molecule/pre.yml@main]
2026-08-31 17:53:06.677154 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-08-31 17:53:07.305706 | 
2026-08-31 17:53:07.305840 | PLAY [all]
2026-08-31 17:53:07.317458 | 
2026-08-31 17:53:07.317530 | LOOP [Check inventory directories]
2026-08-31 17:53:07.696005 | controller | ok: "src/github.com/vexxhost/atmosphere.common"
2026-08-31 17:53:07.872181 | controller | ok: "src/github.com/vexxhost/atmosphere.common/molecule/secretgen-controller/inventory"
2026-08-31 17:53:08.041822 | controller | ok: "src/github.com/vexxhost/atmosphere.common/extensions/molecule/secretgen-controller/inventory"
2026-08-31 17:53:08.055479 | 
2026-08-31 17:53:08.055738 | LOOP [Copy inventory file for Zuul]
2026-08-31 17:53:09.001345 | controller | changed:
2026-08-31 17:53:09.001588 | controller | {
2026-08-31 17:53:09.001628 | controller |   "ansible_loop_var": "item",
2026-08-31 17:53:09.001659 | controller |   "changed": false,
2026-08-31 17:53:09.001686 | controller |   "failed": false,
2026-08-31 17:53:09.001717 | controller |   "invocation": {
2026-08-31 17:53:09.001746 | controller |     "module_args": {
2026-08-31 17:53:09.001788 | controller |       "checksum_algorithm": "sha1",
2026-08-31 17:53:09.001821 | controller |       "follow": true,
2026-08-31 17:53:09.001851 | controller |       "get_attributes": true,
2026-08-31 17:53:09.001883 | controller |       "get_checksum": true,
2026-08-31 17:53:09.001913 | controller |       "get_mime": true,
2026-08-31 17:53:09.001941 | controller |       "path": "src/github.com/vexxhost/atmosphere.common"
2026-08-31 17:53:09.001963 | controller |     }
2026-08-31 17:53:09.001984 | controller |   },
2026-08-31 17:53:09.002003 | controller |   "item": "src/github.com/vexxhost/atmosphere.common",
2026-08-31 17:53:09.002020 | controller |   "stat": {
2026-08-31 17:53:09.002038 | controller |     "atime": 1788198771.9289515,
2026-08-31 17:53:09.002055 | controller |     "attr_flags": "e",
2026-08-31 17:53:09.002072 | controller |     "attributes": [
2026-08-31 17:53:09.002089 | controller |       "extents"
2026-08-31 17:53:09.002106 | controller |     ],
2026-08-31 17:53:09.002124 | controller |     "block_size": 4096,
2026-08-31 17:53:09.002140 | controller |     "blocks": 8,
2026-08-31 17:53:09.002157 | controller |     "charset": "unknown",
2026-08-31 17:53:09.002176 | controller |     "ctime": 1788198771.96597,
2026-08-31 17:53:09.002193 | controller |     "dev": 64769,
2026-08-31 17:53:09.002210 | controller |     "device_type": 0,
2026-08-31 17:53:09.002227 | controller |     "executable": true,
2026-08-31 17:53:09.002243 | controller |     "exists": true,
2026-08-31 17:53:09.002260 | controller |     "gid": 1000,
2026-08-31 17:53:09.002277 | controller |     "gr_name": "zuul",
2026-08-31 17:53:09.002295 | controller |     "inode": 1034272,
2026-08-31 17:53:09.002312 | controller |     "isblk": false,
2026-08-31 17:53:09.002329 | controller |     "ischr": false,
2026-08-31 17:53:09.002345 | controller |     "isdir": true,
2026-08-31 17:53:09.002370 | controller |     "isfifo": false,
2026-08-31 17:53:09.002386 | controller |     "isgid": false,
2026-08-31 17:53:09.002403 | controller |     "islnk": false,
2026-08-31 17:53:09.002420 | controller |     "isreg": false,
2026-08-31 17:53:09.002436 | controller |     "issock": false,
2026-08-31 17:53:09.002452 | controller |     "isuid": false,
2026-08-31 17:53:09.002469 | controller |     "mimetype": "unknown",
2026-08-31 17:53:09.002485 | controller |     "mode": "0755",
2026-08-31 17:53:09.002501 | controller |     "mtime": 1788198755.878881,
2026-08-31 17:53:09.002517 | controller |     "nlink": 10,
2026-08-31 17:53:09.002534 | controller |     "path": "src/github.com/vexxhost/atmosphere.common",
2026-08-31 17:53:09.002551 | controller |     "pw_name": "zuul",
2026-08-31 17:53:09.002571 | controller |     "readable": true,
2026-08-31 17:53:09.002588 | controller |     "rgrp": true,
2026-08-31 17:53:09.002606 | controller |     "roth": true,
2026-08-31 17:53:09.002623 | controller |     "rusr": true,
2026-08-31 17:53:09.002640 | controller |     "size": 4096,
2026-08-31 17:53:09.002657 | controller |     "uid": 1000,
2026-08-31 17:53:09.002674 | controller |     "version": "3047115091",
2026-08-31 17:53:09.002691 | controller |     "wgrp": false,
2026-08-31 17:53:09.002710 | controller |     "woth": false,
2026-08-31 17:53:09.002727 | controller |     "writeable": true,
2026-08-31 17:53:09.002744 | controller |     "wusr": true,
2026-08-31 17:53:09.002761 | controller |     "xgrp": true,
2026-08-31 17:53:09.002778 | controller |     "xoth": true,
2026-08-31 17:53:09.002795 | controller |     "xusr": true
2026-08-31 17:53:09.002812 | controller |   }
2026-08-31 17:53:09.002829 | controller | }
2026-08-31 17:53:09.002865 | controller | ok: All items complete
2026-08-31 17:53:09.002886 | 
2026-08-31 17:53:09.004141 | controller | skipping: Conditional result was False
2026-08-31 17:53:09.006830 | controller | skipping: Conditional result was False
2026-08-31 17:53:09.020861 | 
2026-08-31 17:53:09.021065 | LOOP [Switch "ansible_host" to private IP]
2026-08-31 17:53:09.404106 | controller | changed: 1 replacements made
2026-08-31 17:53:09.405435 | controller | skipping: Conditional result was False
2026-08-31 17:53:09.406037 | controller | skipping: Conditional result was False
2026-08-31 17:53:09.425312 | 
2026-08-31 17:53:09.425408 | TASK [Run Molecule scenario]
2026-08-31 17:53:09.908094 | controller | Using CPython 3.12.3 interpreter at: /usr/bin/python3
2026-08-31 17:53:09.908368 | controller | Creating virtual environment at: .venv
2026-08-31 17:53:09.913775 | controller | warning: No `requires-python` value found in the workspace. Defaulting to `>=3.12`.
2026-08-31 17:53:10.392671 | controller | Downloading pydantic-core (2.0MiB)
2026-08-31 17:53:10.398430 | controller | Downloading ansible-core (2.1MiB)
2026-08-31 17:53:10.398719 | controller | Downloading kubernetes (1.9MiB)
2026-08-31 17:53:10.399014 | controller | Downloading cryptography (4.3MiB)
2026-08-31 17:53:10.399298 | controller | Downloading pygments (1.2MiB)
2026-08-31 17:53:10.613439 | controller |  Downloading pydantic-core
2026-08-31 17:53:10.663455 | controller |  Downloading cryptography
2026-08-31 17:53:10.724339 | controller |  Downloading pygments
2026-08-31 17:53:10.835352 | controller |  Downloading kubernetes
2026-08-31 17:53:10.863548 | controller |  Downloading ansible-core
2026-08-31 17:53:10.931056 | controller | Installed 52 packages in 67ms
2026-08-31 17:53:11.742708 | controller | INFO     Collection 'atmosphere.common' detected.
2026-08-31 17:53:11.742777 | controller | INFO     Scenarios will be used from 'extensions/molecule'
2026-08-31 17:53:12.394734 | controller | INFO     [secretgen-controller > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-08-31 17:53:12.394803 | controller | INFO     [secretgen-controller > prerun] Performing prerun with role_name_check=0...
2026-08-31 17:54:18.828679 | controller | WARNING  Retrying execution failure 250 of: ansible-galaxy collection install -vvv kubernetes.core:>=2.4.0
2026-08-31 17:54:18.828846 | controller | ERROR    Command ansible-galaxy collection install -vvv kubernetes.core:>=2.4.0, returned 250 code:
2026-08-31 17:54:18.828861 | controller | Starting galaxy collection install process
2026-08-31 17:54:18.828873 | controller | Process install dependency map
2026-08-31 17:54:18.828891 | controller | the full traceback was:
2026-08-31 17:54:18.828903 | controller |
2026-08-31 17:54:18.828914 | controller | Traceback (most recent call last):
2026-08-31 17:54:18.828926 | 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-08-31 17:54:18.828939 | controller |     exit_code = cli.run()
2026-08-31 17:54:18.828951 | controller |                 ^^^^^^^^^
2026-08-31 17:54:18.828962 | 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-08-31 17:54:18.828973 | controller |     return context.CLIARGS()
2026-08-31 17:54:18.828984 | controller |            ^^^^^^^^^^^^^^^^^^^^^^^^^
2026-08-31 17:54:18.828995 | 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-08-31 17:54:18.829006 | controller |     return wrapped_method(*args, **kwargs)
2026-08-31 17:54:18.829017 | controller |            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-08-31 17:54:18.829027 | 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-08-31 17:54:18.829038 | controller |     self._execute_install_collection(
2026-08-31 17:54:18.829049 | 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-08-31 17:54:18.829060 | controller |     install_collections(
2026-08-31 17:54:18.829071 | 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-08-31 17:54:18.829083 | controller |     dependency_map = _resolve_depenency_map(
2026-08-31 17:54:18.829093 | controller |                      ^^^^^^^^^^^^^^^^^^^^^^^
2026-08-31 17:54:18.829104 | 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-08-31 17:54:18.829115 | controller |     return collection_dep_resolver.resolve(
2026-08-31 17:54:18.829126 | controller |            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-08-31 17:54:18.829137 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/resolvelib/resolvers.py", line 546, in resolve
2026-08-31 17:54:18.829192 | controller |     state = resolution.resolve(requirements, max_rounds=max_rounds)
2026-08-31 17:54:18.829201 | controller |             ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-08-31 17:54:18.829210 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/resolvelib/resolvers.py", line 397, in resolve
2026-08-31 17:54:18.829218 | controller |     self._add_to_criteria(self.state.criteria, r, parent=None)
2026-08-31 17:54:18.829226 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/resolvelib/resolvers.py", line 148, in _add_to_criteria
2026-08-31 17:54:18.829234 | controller |     matches = self._p.find_matches(
2026-08-31 17:54:18.829294 | controller |               ^^^^^^^^^^^^^^^^^^^^^
2026-08-31 17:54:18.829302 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/ansible/galaxy/dependency_resolution/providers.py", line 477, in find_matches
2026-08-31 17:54:18.829310 | controller |     match for match in self._find_matches(list(requirements))
2026-08-31 17:54:18.829319 | controller |                        ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-08-31 17:54:18.829327 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/ansible/galaxy/dependency_resolution/providers.py", line 253, in _find_matches
2026-08-31 17:54:18.829335 | controller |     coll_versions =  if preinstalled_candidates else self._api_proxy.get_collection_versions(first_req)  # type: t.Iterable]
2026-08-31 17:54:18.829343 | controller |                                                        ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-08-31 17:54:18.829352 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 103, in get_collection_versions
2026-08-31 17:54:18.829360 | controller |     return set(
2026-08-31 17:54:18.829368 | controller |            ^^^^
2026-08-31 17:54:18.829376 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 103, in <genexpr>
2026-08-31 17:54:18.829385 | controller |     return set(
2026-08-31 17:54:18.829393 | controller |               ^
2026-08-31 17:54:18.829407 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 85, in _get_collection_versions
2026-08-31 17:54:18.829415 | controller |     raise last_error
2026-08-31 17:54:18.829423 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 64, in _get_collection_versions
2026-08-31 17:54:18.829432 | controller |     versions = api.get_collection_versions(requirement.namespace, requirement.name)
2026-08-31 17:54:18.829449 | controller |                ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-08-31 17:54:18.829458 | 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-08-31 17:54:18.829466 | controller |     return method(self, *args, **kwargs)
2026-08-31 17:54:18.829474 | controller |            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-08-31 17:54:18.829482 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/ansible/galaxy/api.py", line 873, in get_collection_versions
2026-08-31 17:54:18.829490 | controller |     data = self._call_galaxy(versions_url, error_context_msg=error_context_msg, cache=True, cache_key=cache_key)
2026-08-31 17:54:18.829498 | controller |            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-08-31 17:54:18.829506 | 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-08-31 17:54:18.829514 | controller |     return call_retryable_function()
2026-08-31 17:54:18.829522 | controller |            ^^^^^^^^^^^^^^^^^^^^^^^^^
2026-08-31 17:54:18.829555 | 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-08-31 17:54:18.829563 | controller |     res = path_cache
2026-08-31 17:54:18.829571 | controller |           ~~~~~~~~~~^^^^^^^^^^^
2026-08-31 17:54:18.829579 | controller | KeyError: 'results'
2026-08-31 17:54:18.829587 | controller |
2026-08-31 17:54:18.829595 | controller | ansible-galaxy
2026-08-31 17:54:18.829604 | controller |   config file = None
2026-08-31 17:54:18.829612 | controller |   configured module search path =
2026-08-31 17:54:18.829620 | controller |   ansible python module location = /home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/ansible
2026-08-31 17:54:18.829635 | 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-08-31 17:54:18.829647 | controller |   executable location = /home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/bin/ansible-galaxy
2026-08-31 17:54:18.829655 | 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-08-31 17:54:18.829663 | controller |   jinja version = 3.1.6
2026-08-31 17:54:18.829671 | controller |   libyaml = True
2026-08-31 17:54:18.829679 | controller | No config file found; using defaults
2026-08-31 17:54:18.829687 | controller | Created /home/zuul/.ansible/galaxy_token
2026-08-31 17:54:18.829695 | controller | : Skipping Galaxy server https://galaxy.ansible.com/api/. Got an
2026-08-31 17:54:18.829703 | controller | unexpected error when getting available versions of collection kubernetes.core:
2026-08-31 17:54:18.829711 | controller | 'results'
2026-08-31 17:54:18.829724 | controller | ERROR! Unexpected Exception, this is probably a bug: 'results'
2026-08-31 17:54:18.829733 | controller |
2026-08-31 17:54:18.830482 | controller | Traceback (most recent call last):
2026-08-31 17:54:18.830687 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/bin/molecule", line 10, in <module>
2026-08-31 17:54:18.832287 | controller |     sys.exit(main())
2026-08-31 17:54:18.832510 | controller |              ^^^^^^
2026-08-31 17:54:18.832716 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/click/core.py", line 1442, in __call__
2026-08-31 17:54:18.833500 | controller |     return self.main(*args, **kwargs)
2026-08-31 17:54:18.833720 | controller |            ^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-08-31 17:54:18.833960 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/click/core.py", line 1363, in main
2026-08-31 17:54:18.834684 | controller |     rv = self.invoke(ctx)
2026-08-31 17:54:18.834881 | controller |          ^^^^^^^^^^^^^^^^
2026-08-31 17:54:18.835070 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/click/core.py", line 1830, in invoke
2026-08-31 17:54:18.835950 | controller |     return _process_result(sub_ctx.command.invoke(sub_ctx))
2026-08-31 17:54:18.836152 | controller |                            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-08-31 17:54:18.836397 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/click/core.py", line 1226, in invoke
2026-08-31 17:54:18.836985 | controller |     return ctx.invoke(self.callback, **ctx.params)
2026-08-31 17:54:18.837285 | controller |            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-08-31 17:54:18.837488 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/click/core.py", line 794, in invoke
2026-08-31 17:54:18.837950 | controller |     return callback(*args, **kwargs)
2026-08-31 17:54:18.838197 | controller |            ^^^^^^^^^^^^^^^^^^^^^^^^^
2026-08-31 17:54:18.838506 | 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-08-31 17:54:18.838898 | controller |     return func(*args, **kwargs)
2026-08-31 17:54:18.839165 | controller |            ^^^^^^^^^^^^^^^^^^^^^
2026-08-31 17:54:18.839477 | 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-08-31 17:54:18.840408 | controller |     return f(get_current_context(), *args, **kwargs)
2026-08-31 17:54:18.840666 | controller |            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-08-31 17:54:18.840891 | 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-08-31 17:54:18.841314 | controller |     return func(ctx)
2026-08-31 17:54:18.841546 | controller |            ^^^^^^^^^
2026-08-31 17:54:18.841744 | 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-08-31 17:54:18.842053 | controller |     base.execute_cmdline_scenarios(scenario_name, args, command_args, ansible_args, exclude)
2026-08-31 17:54:18.842429 | 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-08-31 17:54:18.842745 | controller |     _run_scenarios(scenarios, command_args, default_config)
2026-08-31 17:54:18.842990 | 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-08-31 17:54:18.843407 | controller |     scenario.config.runtime.prepare_environment(
2026-08-31 17:54:18.843601 | 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-08-31 17:54:18.844063 | controller |     self.install_collection(
2026-08-31 17:54:18.844315 | 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-08-31 17:54:18.844742 | controller |     raise InvalidPrerequisiteError(msg)
2026-08-31 17:54:18.848768 | controller | ansible_compat.errors.InvalidPrerequisiteError: Command ansible-galaxy collection install -vvv kubernetes.core:>=2.4.0, returned 250 code:
2026-08-31 17:54:18.848792 | controller | Starting galaxy collection install process
2026-08-31 17:54:18.848800 | controller | Process install dependency map
2026-08-31 17:54:18.848808 | controller | the full traceback was:
2026-08-31 17:54:18.848817 | controller |
2026-08-31 17:54:18.848825 | controller | Traceback (most recent call last):
2026-08-31 17:54:18.848834 | 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-08-31 17:54:18.848842 | controller |     exit_code = cli.run()
2026-08-31 17:54:18.848850 | controller |                 ^^^^^^^^^
2026-08-31 17:54:18.848858 | 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-08-31 17:54:18.848866 | controller |     return context.CLIARGS['func']()
2026-08-31 17:54:18.848874 | controller |            ^^^^^^^^^^^^^^^^^^^^^^^^^
2026-08-31 17:54:18.848882 | 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-08-31 17:54:18.848889 | controller |     return wrapped_method(*args, **kwargs)
2026-08-31 17:54:18.848897 | controller |            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-08-31 17:54:18.848905 | 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-08-31 17:54:18.848913 | controller |     self._execute_install_collection(
2026-08-31 17:54:18.848921 | 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-08-31 17:54:18.848929 | controller |     install_collections(
2026-08-31 17:54:18.848937 | 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-08-31 17:54:18.848946 | controller |     dependency_map = _resolve_depenency_map(
2026-08-31 17:54:18.848966 | controller |                      ^^^^^^^^^^^^^^^^^^^^^^^
2026-08-31 17:54:18.848974 | 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-08-31 17:54:18.848982 | controller |     return collection_dep_resolver.resolve(
2026-08-31 17:54:18.848990 | controller |            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-08-31 17:54:18.848998 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/resolvelib/resolvers.py", line 546, in resolve
2026-08-31 17:54:18.849006 | controller |     state = resolution.resolve(requirements, max_rounds=max_rounds)
2026-08-31 17:54:18.849014 | controller |             ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-08-31 17:54:18.849022 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/resolvelib/resolvers.py", line 397, in resolve
2026-08-31 17:54:18.849030 | controller |     self._add_to_criteria(self.state.criteria, r, parent=None)
2026-08-31 17:54:18.849038 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/resolvelib/resolvers.py", line 148, in _add_to_criteria
2026-08-31 17:54:18.849045 | controller |     matches = self._p.find_matches(
2026-08-31 17:54:18.849053 | controller |               ^^^^^^^^^^^^^^^^^^^^^
2026-08-31 17:54:18.849061 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/ansible/galaxy/dependency_resolution/providers.py", line 477, in find_matches
2026-08-31 17:54:18.849077 | controller |     match for match in self._find_matches(list(requirements[identifier]))
2026-08-31 17:54:18.849085 | controller |                        ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-08-31 17:54:18.849093 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/ansible/galaxy/dependency_resolution/providers.py", line 253, in _find_matches
2026-08-31 17:54:18.849101 | controller |     coll_versions = [] if preinstalled_candidates else self._api_proxy.get_collection_versions(first_req)  # type: t.Iterable[t.Tuple[str, GalaxyAPI]]
2026-08-31 17:54:18.849109 | controller |                                                        ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-08-31 17:54:18.849117 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 103, in get_collection_versions
2026-08-31 17:54:18.849125 | controller |     return set(
2026-08-31 17:54:18.849133 | controller |            ^^^^
2026-08-31 17:54:18.849141 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 103, in <genexpr>
2026-08-31 17:54:18.849149 | controller |     return set(
2026-08-31 17:54:18.849157 | controller |               ^
2026-08-31 17:54:18.849164 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 85, in _get_collection_versions
2026-08-31 17:54:18.849190 | controller |     raise last_error
2026-08-31 17:54:18.849198 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 64, in _get_collection_versions
2026-08-31 17:54:18.849216 | controller |     versions = api.get_collection_versions(requirement.namespace, requirement.name)
2026-08-31 17:54:18.849224 | controller |                ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-08-31 17:54:18.849232 | 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-08-31 17:54:18.849262 | controller |     return method(self, *args, **kwargs)
2026-08-31 17:54:18.849275 | controller |            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-08-31 17:54:18.849283 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/ansible/galaxy/api.py", line 873, in get_collection_versions
2026-08-31 17:54:18.849301 | controller |     data = self._call_galaxy(versions_url, error_context_msg=error_context_msg, cache=True, cache_key=cache_key)
2026-08-31 17:54:18.849309 | controller |            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-08-31 17:54:18.849317 | 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-08-31 17:54:18.849325 | controller |     return call_retryable_function()
2026-08-31 17:54:18.849332 | controller |            ^^^^^^^^^^^^^^^^^^^^^^^^^
2026-08-31 17:54:18.849340 | 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-08-31 17:54:18.849348 | controller |     res = path_cache['results']
2026-08-31 17:54:18.849356 | controller |           ~~~~~~~~~~^^^^^^^^^^^
2026-08-31 17:54:18.849364 | controller | KeyError: 'results'
2026-08-31 17:54:18.849372 | controller |
2026-08-31 17:54:18.849380 | controller | ansible-galaxy [core 2.18.17]
2026-08-31 17:54:18.849389 | controller |   config file = None
2026-08-31 17:54:18.849397 | controller |   configured module search path = ['/home/zuul/.ansible/plugins/modules', '/usr/share/ansible/plugins/modules']
2026-08-31 17:54:18.849404 | controller |   ansible python module location = /home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/ansible
2026-08-31 17:54:18.849413 | 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-08-31 17:54:18.849421 | controller |   executable location = /home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/bin/ansible-galaxy
2026-08-31 17:54:18.849436 | 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-08-31 17:54:18.849444 | controller |   jinja version = 3.1.6
2026-08-31 17:54:18.849452 | controller |   libyaml = True
2026-08-31 17:54:18.849460 | controller | No config file found; using defaults
2026-08-31 17:54:18.849468 | controller | Created /home/zuul/.ansible/galaxy_token
2026-08-31 17:54:18.849476 | controller | [WARNING]: Skipping Galaxy server https://galaxy.ansible.com/api/. Got an
2026-08-31 17:54:18.849484 | controller | unexpected error when getting available versions of collection kubernetes.core:
2026-08-31 17:54:18.849492 | controller | 'results'
2026-08-31 17:54:18.849500 | controller | ERROR! Unexpected Exception, this is probably a bug: 'results'
2026-08-31 17:54:18.849508 | controller |
2026-08-31 17:54:19.013591 | controller | ERROR
2026-08-31 17:54:19.013809 | controller | {
2026-08-31 17:54:19.013837 | controller |   "delta": "0:01:09.133439",
2026-08-31 17:54:19.013858 | controller |   "end": "2026-08-31 17:54:18.940948",
2026-08-31 17:54:19.013879 | controller |   "msg": "non-zero return code",
2026-08-31 17:54:19.013913 | controller |   "rc": 1,
2026-08-31 17:54:19.013950 | controller |   "start": "2026-08-31 17:53:09.807509"
2026-08-31 17:54:19.013973 | controller | }
failure
2026-08-31 17:54:19.017008 | 
2026-08-31 17:54:19.017469 | PLAY RECAP
2026-08-31 17:54:19.017517 | controller | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-08-31 17:54:19.017538 | 
2026-08-31 17:54:19.154813 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-08-31 17:54:19.157630 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere.common/test-playbooks/molecule/post.yml@main]
2026-08-31 17:54:19.821301 | 
2026-08-31 17:54:19.821423 | PLAY [Collect Molecule logs]
2026-08-31 17:54:19.835182 | 
2026-08-31 17:54:19.835257 | TASK [gather-host-logs : creating directory for system status]
2026-08-31 17:54:20.279793 | controller | changed
2026-08-31 17:54:20.287277 | 
2026-08-31 17:54:20.287352 | TASK [gather-host-logs : Get logs for each host]
2026-08-31 17:54:20.673509 | controller | + systemd-cgls --full --all --no-pager
2026-08-31 17:54:20.699991 | controller | + ip addr
2026-08-31 17:54:20.704390 | controller | + ip route
2026-08-31 17:54:20.708604 | controller | + lsblk
2026-08-31 17:54:20.715921 | controller | + mount
2026-08-31 17:54:20.720237 | controller | + docker images
2026-08-31 17:54:20.721071 | controller | /bin/bash: line 7: docker: command not found
2026-08-31 17:54:20.721664 | controller | + brctl show
2026-08-31 17:54:20.722480 | controller | /bin/bash: line 8: brctl: command not found
2026-08-31 17:54:20.722989 | controller | + ps aux --sort=-%mem
2026-08-31 17:54:20.760292 | controller | + dpkg -l
2026-08-31 17:54:20.780521 | controller | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul))
2026-08-31 17:54:20.781439 | controller | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul
2026-08-31 17:54:20.781539 | controller | /bin/bash: line 11: docker: command not found
2026-08-31 17:54:20.782141 | controller | + '[' '!' -z '' ']'
2026-08-31 17:54:20.824907 | controller | ok: Runtime: 0:00:00.116475
2026-08-31 17:54:20.831867 | 
2026-08-31 17:54:20.831934 | TASK [gather-host-logs : Downloads logs to executor]
2026-08-31 17:54:21.493018 | controller | changed:
2026-08-31 17:54:21.493188 | controller | created directory /var/lib/zuul/builds/0636888ade7c46d7a5fcb0c7ba6359f5/work/logs/controller
2026-08-31 17:54:21.493216 | controller | cd+++++++++ system/
2026-08-31 17:54:21.493238 | controller | >f+++++++++ system/brctl-show.txt
2026-08-31 17:54:21.493258 | controller | >f+++++++++ system/docker-images.txt
2026-08-31 17:54:21.493277 | controller | >f+++++++++ system/ip-addr.txt
2026-08-31 17:54:21.493299 | controller | >f+++++++++ system/ip-route.txt
2026-08-31 17:54:21.493319 | controller | >f+++++++++ system/lsblk.txt
2026-08-31 17:54:21.493339 | controller | >f+++++++++ system/mount.txt
2026-08-31 17:54:21.493360 | controller | >f+++++++++ system/packages.txt
2026-08-31 17:54:21.493379 | controller | >f+++++++++ system/ps.txt
2026-08-31 17:54:21.493398 | controller | >f+++++++++ system/systemd-cgls.txt
2026-08-31 17:54:21.502950 | 
2026-08-31 17:54:21.503016 | LOOP [helm-release-status : creating directory for helm release status]
2026-08-31 17:54:21.760452 | controller | changed: "values"
2026-08-31 17:54:21.994198 | controller | changed: "releases"
2026-08-31 17:54:22.012293 | 
2026-08-31 17:54:22.012446 | TASK [helm-release-status : Gather get release status for helm charts]
2026-08-31 17:54:22.295469 | controller | /bin/bash: line 3: kubectl: command not found
2026-08-31 17:54:22.555778 | controller | ok: Runtime: 0:00:00.010993
2026-08-31 17:54:22.562118 | 
2026-08-31 17:54:22.562187 | TASK [helm-release-status : Downloads logs to executor]
2026-08-31 17:54:23.088748 | controller | changed:
2026-08-31 17:54:23.088938 | controller | cd+++++++++ helm/
2026-08-31 17:54:23.088985 | controller | cd+++++++++ helm/releases/
2026-08-31 17:54:23.089008 | controller | cd+++++++++ helm/values/
2026-08-31 17:54:23.099589 | 
2026-08-31 17:54:23.099659 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-08-31 17:54:23.367348 | controller | changed
2026-08-31 17:54:23.375179 | 
2026-08-31 17:54:23.375265 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-08-31 17:54:23.705122 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-31 17:54:23.705690 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-31 17:54:23.712513 | controller | environment: line 1: kubectl: command not found
2026-08-31 17:54:23.713841 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-31 17:54:23.716475 | controller | environment: line 1: kubectl: command not found
2026-08-31 17:54:23.719357 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-31 17:54:23.721400 | controller | environment: line 1: kubectl: command not found
2026-08-31 17:54:23.722863 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-31 17:54:23.724756 | controller | environment: line 1: kubectl: command not found
2026-08-31 17:54:23.727108 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-31 17:54:23.730540 | controller | environment: line 1: kubectl: command not found
2026-08-31 17:54:23.732830 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-31 17:54:23.964338 | controller | ok: Runtime: 0:00:00.042953
2026-08-31 17:54:23.971654 | 
2026-08-31 17:54:23.971744 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-08-31 17:54:24.251502 | controller | changed
2026-08-31 17:54:24.259997 | 
2026-08-31 17:54:24.260097 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-08-31 17:54:24.546075 | controller | environment: line 5: kubectl: command not found
2026-08-31 17:54:24.547701 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-31 17:54:24.548030 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-31 17:54:24.548169 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-31 17:54:24.799581 | controller | ok: Runtime: 0:00:00.015065
2026-08-31 17:54:24.806950 | 
2026-08-31 17:54:24.807039 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-08-31 17:54:25.358903 | controller | changed:
2026-08-31 17:54:25.359104 | controller | cd+++++++++ objects/
2026-08-31 17:54:25.359142 | controller | cd+++++++++ objects/cluster/
2026-08-31 17:54:25.359172 | controller | cd+++++++++ objects/namespaced/
2026-08-31 17:54:25.372168 | 
2026-08-31 17:54:25.372230 | TASK [gather-pod-logs : creating directory for pod logs]
2026-08-31 17:54:25.634953 | controller | changed
2026-08-31 17:54:25.640906 | 
2026-08-31 17:54:25.640996 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-08-31 17:54:25.916943 | controller | changed
2026-08-31 17:54:25.922229 | 
2026-08-31 17:54:25.922301 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-08-31 17:54:26.218981 | controller | environment: line 3: kubectl: command not found
2026-08-31 17:54:26.463247 | controller | ok: Runtime: 0:00:00.015771
2026-08-31 17:54:26.468660 | 
2026-08-31 17:54:26.468726 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-08-31 17:54:26.997863 | controller | changed:
2026-08-31 17:54:26.998144 | controller | cd+++++++++ pod-logs/
2026-08-31 17:54:26.998205 | controller | cd+++++++++ pod-logs/failed-pods/
2026-08-31 17:54:27.012838 | 
2026-08-31 17:54:27.012902 | TASK [gather-prom-metrics : creating directory for helm release descriptions]
2026-08-31 17:54:27.280632 | controller | changed
2026-08-31 17:54:27.285822 | 
2026-08-31 17:54:27.285888 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces]
2026-08-31 17:54:27.569212 | controller | /bin/bash: line 2: kubectl: command not found
2026-08-31 17:54:27.825276 | controller | ok: Runtime: 0:00:00.016188
2026-08-31 17:54:27.831053 | 
2026-08-31 17:54:27.831120 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr]
2026-08-31 17:54:28.112860 | controller | /bin/bash: line 2: kubectl: command not found
2026-08-31 17:54:28.120595 | controller | ceph-mgr endpoints:
2026-08-31 17:54:28.370728 | controller | ok: Runtime: 0:00:00.017092
2026-08-31 17:54:28.377746 | 
2026-08-31 17:54:28.377832 | TASK [gather-prom-metrics : Get metrics from fluentd pods]
2026-08-31 17:54:28.661615 | controller | /bin/bash: line 4: kubectl: command not found
2026-08-31 17:54:28.918490 | controller | ok: Runtime: 0:00:00.017259
2026-08-31 17:54:28.923932 | 
2026-08-31 17:54:28.923996 | TASK [gather-prom-metrics : Downloads logs to executor]
2026-08-31 17:54:29.447366 | controller | changed: cd+++++++++ prometheus/
2026-08-31 17:54:29.498968 | 
2026-08-31 17:54:29.499118 | TASK [gather-selenium-data : creating directory for helm release descriptions]
2026-08-31 17:54:29.776510 | controller | changed
2026-08-31 17:54:29.781921 | 
2026-08-31 17:54:29.781986 | TASK [gather-selenium-data : Get selenium data]
2026-08-31 17:54:30.055472 | controller | + cp '/tmp/artifacts/*' /tmp/logs/selenium/.
2026-08-31 17:54:30.060533 | controller | cp: cannot stat '/tmp/artifacts/*': No such file or directory
2026-08-31 17:54:30.323422 | controller | ERROR
2026-08-31 17:54:30.323691 | controller | {
2026-08-31 17:54:30.323747 | controller |   "delta": "0:00:00.013239",
2026-08-31 17:54:30.323782 | controller |   "end": "2026-08-31 17:54:30.061148",
2026-08-31 17:54:30.323824 | controller |   "msg": "non-zero return code",
2026-08-31 17:54:30.323847 | controller |   "rc": 1,
2026-08-31 17:54:30.323866 | controller |   "start": "2026-08-31 17:54:30.047909"
2026-08-31 17:54:30.323884 | controller | }
2026-08-31 17:54:30.323909 | controller | ERROR: Ignoring Errors
2026-08-31 17:54:30.329878 | 
2026-08-31 17:54:30.329947 | TASK [gather-selenium-data : Downloads logs to executor]
2026-08-31 17:54:30.831891 | controller | changed: cd+++++++++ selenium/
2026-08-31 17:54:30.841946 | 
2026-08-31 17:54:30.842023 | PLAY RECAP
2026-08-31 17:54:30.842089 | controller | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1
2026-08-31 17:54:30.842204 | 
2026-08-31 17:54:30.988516 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere.common/test-playbooks/molecule/post.yml@main]
2026-08-31 17:54:30.991706 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-08-31 17:54:31.569774 | 
2026-08-31 17:54:31.569912 | PLAY [all]
2026-08-31 17:54:31.581818 | 
2026-08-31 17:54:31.581907 | TASK [fetch-output : Set log path for multiple nodes]
2026-08-31 17:54:31.627921 | controller | skipping: Conditional result was False
2026-08-31 17:54:31.639419 | 
2026-08-31 17:54:31.639549 | TASK [fetch-output : Set log path for single node]
2026-08-31 17:54:31.688082 | controller | ok
2026-08-31 17:54:31.693258 | 
2026-08-31 17:54:31.693324 | LOOP [fetch-output : Ensure local output dirs]
2026-08-31 17:54:32.079924 | controller -> localhost | ok: "/var/lib/zuul/builds/0636888ade7c46d7a5fcb0c7ba6359f5/work/logs"
2026-08-31 17:54:32.286177 | controller -> localhost | changed: "/var/lib/zuul/builds/0636888ade7c46d7a5fcb0c7ba6359f5/work/artifacts"
2026-08-31 17:54:32.499925 | controller -> localhost | changed: "/var/lib/zuul/builds/0636888ade7c46d7a5fcb0c7ba6359f5/work/docs"
2026-08-31 17:54:32.521293 | 
2026-08-31 17:54:32.521455 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-08-31 17:54:33.206758 | controller | changed: .d..t...... ./
2026-08-31 17:54:33.206951 | controller | changed: All items complete
2026-08-31 17:54:33.206979 | 
2026-08-31 17:54:33.700238 | controller | changed: .d..t...... ./
2026-08-31 17:54:34.192974 | controller | changed: .d..t...... ./
2026-08-31 17:54:34.220135 | 
2026-08-31 17:54:34.220279 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-08-31 17:54:34.641407 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.011517
2026-08-31 17:54:34.870317 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.007247
2026-08-31 17:54:34.892299 | 
2026-08-31 17:54:34.892426 | PLAY [all]
2026-08-31 17:54:34.898990 | 
2026-08-31 17:54:34.899059 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-08-31 17:54:35.622501 | controller | changed
2026-08-31 17:54:35.630780 | 
2026-08-31 17:54:35.630829 | PLAY RECAP
2026-08-31 17:54:35.630876 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-08-31 17:54:35.630898 | 
2026-08-31 17:54:35.758668 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-08-31 17:54:35.760634 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-08-31 17:54:36.345642 | 
2026-08-31 17:54:36.346147 | PLAY [localhost]
2026-08-31 17:54:36.356574 | 
2026-08-31 17:54:36.356642 | TASK [Generate Zuul manifest]
2026-08-31 17:54:36.376999 | localhost | ok
2026-08-31 17:54:36.394383 | 
2026-08-31 17:54:36.394458 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-08-31 17:54:36.802815 | localhost | changed
2026-08-31 17:54:36.852279 | 
2026-08-31 17:54:36.852440 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-08-31 17:54:36.886384 | localhost | ok
2026-08-31 17:54:36.895399 | 
2026-08-31 17:54:36.895545 | TASK [Upload logs]
2026-08-31 17:54:36.916513 | localhost | ok
2026-08-31 17:54:36.982277 | 
2026-08-31 17:54:36.982408 | TASK [Set zuul-log-path fact]
2026-08-31 17:54:37.006273 | localhost | ok
2026-08-31 17:54:37.020758 | 
2026-08-31 17:54:37.020826 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-08-31 17:54:37.053730 | localhost | ok
2026-08-31 17:54:37.064294 | 
2026-08-31 17:54:37.064392 | TASK [upload-logs : Create log directories]
2026-08-31 17:54:37.472560 | localhost | changed
2026-08-31 17:54:37.478854 | 
2026-08-31 17:54:37.478922 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-08-31 17:54:37.887932 | localhost -> localhost | ok: Runtime: 0:00:00.005550
2026-08-31 17:54:37.893830 | 
2026-08-31 17:54:37.893892 | TASK [upload-logs : Upload logs to log server]
2026-08-31 17:54:38.367711 | localhost | Output suppressed because no_log was given
2026-08-31 17:54:38.371455 | 
2026-08-31 17:54:38.371520 | LOOP [upload-logs : Compress console log and json output]
2026-08-31 17:54:38.422333 | localhost | skipping: Conditional result was False
2026-08-31 17:54:38.486093 | localhost | skipping: Conditional result was False
2026-08-31 17:54:38.496822 | 
2026-08-31 17:54:38.497139 | LOOP [upload-logs : Upload compressed console log and json output]
2026-08-31 17:54:38.540695 | localhost | skipping: Conditional result was False
2026-08-31 17:54:38.541021 | 
2026-08-31 17:54:38.545646 | localhost | skipping: Conditional result was False
2026-08-31 17:54:38.561933 | 
2026-08-31 17:54:38.562113 | LOOP [upload-logs : Upload console log and json output]