2026-04-07 06:34:50.441029 | Job console starting
2026-04-07 06:34:50.451173 | Updating git repos
2026-04-07 06:34:50.506351 | Cloning repos into workspace
2026-04-07 06:34:50.996866 | Restoring repo states
2026-04-07 06:34:51.014405 | Merging changes
2026-04-07 06:34:52.110062 | Checking out repos
2026-04-07 06:34:52.223821 | Preparing playbooks
2026-04-07 06:34:55.115764 | Running Ansible setup
2026-04-07 06:34:58.801512 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-07 06:34:59.395981 | 
2026-04-07 06:34:59.396132 | PLAY [localhost]
2026-04-07 06:34:59.404126 | 
2026-04-07 06:34:59.404197 | TASK [Gathering Facts]
2026-04-07 06:35:00.256974 | localhost | ok
2026-04-07 06:35:00.263913 | 
2026-04-07 06:35:00.264028 | TASK [Setup log path fact]
2026-04-07 06:35:00.285828 | localhost | ok
2026-04-07 06:35:00.298115 | 
2026-04-07 06:35:00.298181 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-07 06:35:00.328691 | localhost | ok
2026-04-07 06:35:00.337417 | 
2026-04-07 06:35:00.337487 | TASK [emit-job-header : Print job information]
2026-04-07 06:35:00.378459 | # Job Information
2026-04-07 06:35:00.378616 | Ansible Version: 2.16.16
2026-04-07 06:35:00.378661 | Job: atmosphere-molecule-keycloak
2026-04-07 06:35:00.378693 | Pipeline: check
2026-04-07 06:35:00.378721 | Executor: 0a8996d2b663
2026-04-07 06:35:00.378748 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3809
2026-04-07 06:35:00.378779 | Event ID: caeb24a0-324b-11f1-8aad-186c9f15ddd1
2026-04-07 06:35:00.383081 | 
2026-04-07 06:35:00.383171 | LOOP [emit-job-header : Print node information]
2026-04-07 06:35:00.478727 | localhost | ok:
2026-04-07 06:35:00.479037 | localhost | # Node Information
2026-04-07 06:35:00.479100 | localhost | Inventory Hostname: instance
2026-04-07 06:35:00.479148 | localhost | Hostname: np0000163878
2026-04-07 06:35:00.479192 | localhost | Username: zuul
2026-04-07 06:35:00.479240 | localhost | Distro: Ubuntu 22.04
2026-04-07 06:35:00.479283 | localhost | Provider: yul1
2026-04-07 06:35:00.479325 | localhost | Region: ca-ymq-1
2026-04-07 06:35:00.479367 | localhost | Label: ubuntu-jammy
2026-04-07 06:35:00.479408 | localhost | Product Name: OpenStack Nova
2026-04-07 06:35:00.479448 | localhost | Interface IP: 199.204.45.209
2026-04-07 06:35:00.491616 | 
2026-04-07 06:35:00.491848 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-04-07 06:35:00.882613 | localhost -> localhost | changed
2026-04-07 06:35:00.891301 | 
2026-04-07 06:35:00.891376 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-04-07 06:35:01.708364 | localhost -> localhost | changed
2026-04-07 06:35:01.715361 | 
2026-04-07 06:35:01.715420 | PLAY [all]
2026-04-07 06:35:01.723747 | 
2026-04-07 06:35:01.723814 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-04-07 06:35:01.951401 | instance -> localhost | ok
2026-04-07 06:35:01.960481 | 
2026-04-07 06:35:01.960570 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-04-07 06:35:01.989144 | instance | ok
2026-04-07 06:35:02.004211 | instance | included: /var/lib/zuul/builds/55a15e3c563d4f03a6daf0915e0e5c5d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-04-07 06:35:02.011207 | 
2026-04-07 06:35:02.011277 | TASK [add-build-sshkey : Create Temp SSH key]
2026-04-07 06:35:03.076601 | instance -> localhost | Generating public/private rsa key pair.
2026-04-07 06:35:03.076795 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/55a15e3c563d4f03a6daf0915e0e5c5d/work/55a15e3c563d4f03a6daf0915e0e5c5d_id_rsa
2026-04-07 06:35:03.076838 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/55a15e3c563d4f03a6daf0915e0e5c5d/work/55a15e3c563d4f03a6daf0915e0e5c5d_id_rsa.pub
2026-04-07 06:35:03.076872 | instance -> localhost | The key fingerprint is:
2026-04-07 06:35:03.076903 | instance -> localhost | SHA256:9PYgT4+kLL+3Hftl1nfNHzbTAfLSzc43XAafrAGbXhk zuul-build-sshkey
2026-04-07 06:35:03.076947 | instance -> localhost | The key's randomart image is:
2026-04-07 06:35:03.077038 | instance -> localhost | +---[RSA 3072]----+
2026-04-07 06:35:03.077077 | instance -> localhost | |                 |
2026-04-07 06:35:03.077109 | instance -> localhost | |                 |
2026-04-07 06:35:03.077139 | instance -> localhost | |        .  o E.  |
2026-04-07 06:35:03.077168 | instance -> localhost | |       . .  B Bo.|
2026-04-07 06:35:03.077197 | instance -> localhost | |        S *+ * *+|
2026-04-07 06:35:03.077227 | instance -> localhost | |       . B.=o *o*|
2026-04-07 06:35:03.077256 | instance -> localhost | |      . o o.+. X%|
2026-04-07 06:35:03.077285 | instance -> localhost | |       o  .. o.+X|
2026-04-07 06:35:03.077318 | instance -> localhost | |        oo..o.. .|
2026-04-07 06:35:03.077348 | instance -> localhost | +----[SHA256]-----+
2026-04-07 06:35:03.077410 | instance -> localhost | ok: Runtime: 0:00:00.673513
2026-04-07 06:35:03.084378 | 
2026-04-07 06:35:03.084440 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-04-07 06:35:03.117878 | instance | ok
2026-04-07 06:35:03.127167 | instance | included: /var/lib/zuul/builds/55a15e3c563d4f03a6daf0915e0e5c5d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-04-07 06:35:03.134488 | 
2026-04-07 06:35:03.134550 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-04-07 06:35:03.159058 | instance | skipping: Conditional result was False
2026-04-07 06:35:03.170588 | 
2026-04-07 06:35:03.170654 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-04-07 06:35:03.606213 | instance | changed
2026-04-07 06:35:03.612902 | 
2026-04-07 06:35:03.612992 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-04-07 06:35:03.785513 | instance | ok
2026-04-07 06:35:03.792527 | 
2026-04-07 06:35:03.792650 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-04-07 06:35:04.237757 | instance | changed
2026-04-07 06:35:04.243892 | 
2026-04-07 06:35:04.243954 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-04-07 06:35:04.679944 | instance | changed
2026-04-07 06:35:04.686426 | 
2026-04-07 06:35:04.686488 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-04-07 06:35:04.711069 | instance | skipping: Conditional result was False
2026-04-07 06:35:04.720233 | 
2026-04-07 06:35:04.720322 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-04-07 06:35:05.057741 | instance -> localhost | changed
2026-04-07 06:35:05.073365 | 
2026-04-07 06:35:05.073465 | TASK [add-build-sshkey : Add back temp key]
2026-04-07 06:35:05.334968 | instance -> localhost | Identity added: /var/lib/zuul/builds/55a15e3c563d4f03a6daf0915e0e5c5d/work/55a15e3c563d4f03a6daf0915e0e5c5d_id_rsa (zuul-build-sshkey)
2026-04-07 06:35:05.335149 | instance -> localhost | ok: Runtime: 0:00:00.014393
2026-04-07 06:35:05.339866 | 
2026-04-07 06:35:05.339940 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-04-07 06:35:05.634131 | instance | ok
2026-04-07 06:35:05.641654 | 
2026-04-07 06:35:05.641771 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-04-07 06:35:05.666699 | instance | skipping: Conditional result was False
2026-04-07 06:35:05.681628 | 
2026-04-07 06:35:05.681762 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-04-07 06:35:06.001119 | instance | ok
2026-04-07 06:35:06.006584 | 
2026-04-07 06:35:06.006648 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-04-07 06:35:07.608487 | instance | Output suppressed because no_log was given
2026-04-07 06:35:07.618802 | 
2026-04-07 06:35:07.618868 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-04-07 06:35:07.808319 | instance | ok: "logs"
2026-04-07 06:35:07.808612 | instance | ok: All items complete
2026-04-07 06:35:07.808642 | 
2026-04-07 06:35:07.962503 | instance | ok: "artifacts"
2026-04-07 06:35:08.117556 | instance | ok: "docs"
2026-04-07 06:35:08.136345 | 
2026-04-07 06:35:08.136437 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-04-07 06:35:08.316816 | instance | changed: "logs"
2026-04-07 06:35:08.609122 | instance | changed: "artifacts"
2026-04-07 06:35:08.636752 | instance | changed: "docs"
2026-04-07 06:35:08.657269 | 
2026-04-07 06:35:08.657399 | PLAY RECAP
2026-04-07 06:35:08.657468 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-04-07 06:35:08.657510 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-07 06:35:08.657541 | 
2026-04-07 06:35:08.787553 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-07 06:35:08.792056 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-07 06:35:09.334442 | 
2026-04-07 06:35:09.334606 | PLAY [all]
2026-04-07 06:35:09.348003 | 
2026-04-07 06:35:09.348097 | TASK [setup-uv : Extract archive]
2026-04-07 06:35:11.572399 | instance | changed
2026-04-07 06:35:11.578152 | 
2026-04-07 06:35:11.578229 | TASK [setup-uv : Print version]
2026-04-07 06:35:11.133435 | instance | uv 0.8.13
2026-04-07 06:35:12.111850 | instance | ok: Runtime: 0:00:00.011969
2026-04-07 06:35:12.119234 | 
2026-04-07 06:35:12.119279 | PLAY RECAP
2026-04-07 06:35:12.119321 | instance | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-07 06:35:12.119344 | 
2026-04-07 06:35:12.231465 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-07 06:35:12.236048 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/pre.yml@main]
2026-04-07 06:35:12.786817 | 
2026-04-07 06:35:12.786944 | PLAY [all]
2026-04-07 06:35:12.797986 | 
2026-04-07 06:35:12.798057 | TASK [Install "jq" for log collection]
2026-04-07 06:35:25.478133 | instance | changed
2026-04-07 06:35:25.480704 | 
2026-04-07 06:35:25.480778 | PLAY RECAP
2026-04-07 06:35:25.480848 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-07 06:35:25.480916 | 
2026-04-07 06:35:25.609818 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/pre.yml@main]
2026-04-07 06:35:25.615209 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-04-07 06:35:26.161129 | 
2026-04-07 06:35:26.161275 | PLAY [all]
2026-04-07 06:35:26.174012 | 
2026-04-07 06:35:26.174093 | TASK [Copy inventory file for Zuul]
2026-04-07 06:35:27.009386 | instance | changed
2026-04-07 06:35:27.018402 | 
2026-04-07 06:35:27.018482 | TASK [Switch "ansible_host" to private IP]
2026-04-07 06:35:27.278064 | instance | changed: 1 replacements made
2026-04-07 06:35:27.285039 | 
2026-04-07 06:35:27.285107 | TASK [Run Molecule scenario]
2026-04-07 06:35:27.671139 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3
2026-04-07 06:35:27.671240 | instance | Creating virtual environment at: .venv
2026-04-07 06:35:27.695961 | instance |    Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-04-07 06:35:27.728843 | instance | Downloading kubernetes (1.9MiB)
2026-04-07 06:35:27.729259 | instance | Downloading pydantic-core (2.0MiB)
2026-04-07 06:35:27.729635 | instance | Downloading rjsonnet (1.2MiB)
2026-04-07 06:35:27.730117 | instance | Downloading ansible-core (2.1MiB)
2026-04-07 06:35:27.730509 | instance | Downloading openstacksdk (1.7MiB)
2026-04-07 06:35:27.730817 | instance | Downloading pygments (1.2MiB)
2026-04-07 06:35:27.731269 | instance | Downloading netaddr (2.2MiB)
2026-04-07 06:35:27.731618 | instance | Downloading cryptography (4.2MiB)
2026-04-07 06:35:27.732245 | instance | Downloading setuptools (1.1MiB)
2026-04-07 06:35:28.045958 | instance |    Building pyperclip==1.9.0
2026-04-07 06:35:28.061171 | instance |  Downloading rjsonnet
2026-04-07 06:35:28.164746 | instance |  Downloading pydantic-core
2026-04-07 06:35:28.199150 | instance |  Downloading netaddr
2026-04-07 06:35:28.216057 | instance |  Downloading pygments
2026-04-07 06:35:28.224346 | instance |  Downloading cryptography
2026-04-07 06:35:28.259854 | instance |  Downloading setuptools
2026-04-07 06:35:28.310354 | instance |  Downloading kubernetes
2026-04-07 06:35:28.343148 | instance |  Downloading ansible-core
2026-04-07 06:35:28.364564 | instance |  Downloading openstacksdk
2026-04-07 06:35:28.757023 | instance |       Built pyperclip==1.9.0
2026-04-07 06:35:28.948394 | instance |       Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-04-07 06:35:28.992900 | instance | Installed 83 packages in 42ms
2026-04-07 06:35:29.610132 | instance | WARNING  Molecule scenarios should migrate to 'extensions/molecule'
2026-04-07 06:35:30.208645 | instance | INFO     [keycloak > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-04-07 06:35:30.208719 | instance | INFO     [keycloak > prerun] Performing prerun with role_name_check=0...
2026-04-07 06:36:09.676847 | instance | CRITICAL The source path '/home/zuul/src/github.com/vexxhost/atmosphere/molecule/keycloak/host_vars' does not exist.
2026-04-07 06:36:09.677067 | instance | Traceback (most recent call last):
2026-04-07 06:36:09.677207 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/bin/molecule", line 10, in <module>
2026-04-07 06:36:09.677404 | instance |     sys.exit(main())
2026-04-07 06:36:09.677541 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/click/core.py", line 1442, in __call__
2026-04-07 06:36:09.677882 | instance |     return self.main(*args, **kwargs)
2026-04-07 06:36:09.677989 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/click/core.py", line 1363, in main
2026-04-07 06:36:09.678279 | instance |     rv = self.invoke(ctx)
2026-04-07 06:36:09.678422 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/click/core.py", line 1830, in invoke
2026-04-07 06:36:09.678793 | instance |     return _process_result(sub_ctx.command.invoke(sub_ctx))
2026-04-07 06:36:09.678917 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/click/core.py", line 1226, in invoke
2026-04-07 06:36:09.679188 | instance |     return ctx.invoke(self.callback, **ctx.params)
2026-04-07 06:36:09.679313 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/click/core.py", line 794, in invoke
2026-04-07 06:36:09.679556 | instance |     return callback(*args, **kwargs)
2026-04-07 06:36:09.679685 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/molecule/click_cfg.py", line 583, in wrapper
2026-04-07 06:36:09.679918 | instance |     return func(*args, **kwargs)
2026-04-07 06:36:09.680074 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/click/decorators.py", line 34, in new_func
2026-04-07 06:36:09.680234 | instance |     return f(get_current_context(), *args, **kwargs)
2026-04-07 06:36:09.680361 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/molecule/click_cfg.py", line 418, in wrapper
2026-04-07 06:36:09.680556 | instance |     return func(ctx)
2026-04-07 06:36:09.680680 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/molecule/command/test.py", line 81, in test
2026-04-07 06:36:09.680853 | instance |     base.execute_cmdline_scenarios(scenario_name, args, command_args, ansible_args, exclude)
2026-04-07 06:36:09.680980 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/molecule/command/base.py", line 169, in execute_cmdline_scenarios
2026-04-07 06:36:09.681178 | instance |     _run_scenarios(scenarios, command_args, default_config)
2026-04-07 06:36:09.681303 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/molecule/command/base.py", line 254, in _run_scenarios
2026-04-07 06:36:09.681516 | instance |     execute_scenario(scenario, shared_state=scenarios.shared_state)
2026-04-07 06:36:09.681629 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/molecule/command/base.py", line 373, in execute_scenario
2026-04-07 06:36:09.681826 | instance |     execute_subcommand(scenario.config, action)
2026-04-07 06:36:09.681951 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/molecule/command/base.py", line 358, in execute_subcommand
2026-04-07 06:36:09.682146 | instance |     return command(current_config).execute(args)
2026-04-07 06:36:09.682270 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/molecule/command/base.py", line 77, in __init__
2026-04-07 06:36:09.682446 | instance |     self._setup()
2026-04-07 06:36:09.682597 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/molecule/command/base.py", line 111, in _setup
2026-04-07 06:36:09.682765 | instance |     self._config.provisioner.manage_inventory()
2026-04-07 06:36:09.682890 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/molecule/provisioner/ansible.py", line 445, in manage_inventory
2026-04-07 06:36:09.683093 | instance |     self._link_or_update_vars()
2026-04-07 06:36:09.683217 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/molecule/provisioner/ansible.py", line 513, in _link_or_update_vars
2026-04-07 06:36:09.683437 | instance |     raise MoleculeError(msg)
2026-04-07 06:36:09.683563 | instance | molecule.exceptions.MoleculeError
2026-04-07 06:36:09.884807 | instance | ERROR
2026-04-07 06:36:09.885004 | instance | {
2026-04-07 06:36:09.885035 | instance |   "delta": "0:00:42.149128",
2026-04-07 06:36:09.885055 | instance |   "end": "2026-04-07 06:36:09.751294",
2026-04-07 06:36:09.885074 | instance |   "msg": "non-zero return code",
2026-04-07 06:36:09.885091 | instance |   "rc": 1,
2026-04-07 06:36:09.885112 | instance |   "start": "2026-04-07 06:35:27.602166"
2026-04-07 06:36:09.885129 | instance | }
failure
2026-04-07 06:36:09.886896 | 
2026-04-07 06:36:09.886938 | PLAY RECAP
2026-04-07 06:36:09.886981 | instance | ok: 2 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-04-07 06:36:09.887005 | 
2026-04-07 06:36:09.978355 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-04-07 06:36:09.983227 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@main]
2026-04-07 06:36:10.570886 | 
2026-04-07 06:36:10.571024 | PLAY [all]
2026-04-07 06:36:10.585461 | 
2026-04-07 06:36:10.585545 | TASK [gather-host-logs : creating directory for system status]
2026-04-07 06:36:10.923655 | instance | changed
2026-04-07 06:36:10.929195 | 
2026-04-07 06:36:10.929278 | TASK [gather-host-logs : Get logs for each host]
2026-04-07 06:36:11.262429 | instance | + systemd-cgls --full --all --no-pager
2026-04-07 06:36:11.275004 | instance | + ip addr
2026-04-07 06:36:11.277661 | instance | + ip route
2026-04-07 06:36:11.279342 | instance | + lsblk
2026-04-07 06:36:11.283800 | instance | + mount
2026-04-07 06:36:11.286396 | instance | + docker images
2026-04-07 06:36:11.286824 | instance | /bin/bash: line 7: docker: command not found
2026-04-07 06:36:11.287044 | instance | + brctl show
2026-04-07 06:36:11.287428 | instance | /bin/bash: line 8: brctl: command not found
2026-04-07 06:36:11.287591 | instance | + ps aux --sort=-%mem
2026-04-07 06:36:11.299469 | instance | + dpkg -l
2026-04-07 06:36:11.310272 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul))
2026-04-07 06:36:11.310742 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul
2026-04-07 06:36:11.310779 | instance | /bin/bash: line 11: docker: command not found
2026-04-07 06:36:11.311079 | instance | + '[' '!' -z '' ']'
2026-04-07 06:36:11.468367 | instance | ok: Runtime: 0:00:00.053780
2026-04-07 06:36:11.477210 | 
2026-04-07 06:36:11.477288 | TASK [gather-host-logs : Downloads logs to executor]
2026-04-07 06:36:12.077990 | instance | changed:
2026-04-07 06:36:12.078182 | instance | created directory /var/lib/zuul/builds/55a15e3c563d4f03a6daf0915e0e5c5d/work/logs/instance
2026-04-07 06:36:12.078392 | instance | cd+++++++++ system/
2026-04-07 06:36:12.078422 | instance | >f+++++++++ system/brctl-show.txt
2026-04-07 06:36:12.078453 | instance | >f+++++++++ system/docker-images.txt
2026-04-07 06:36:12.078481 | instance | >f+++++++++ system/ip-addr.txt
2026-04-07 06:36:12.078511 | instance | >f+++++++++ system/ip-route.txt
2026-04-07 06:36:12.078539 | instance | >f+++++++++ system/lsblk.txt
2026-04-07 06:36:12.078566 | instance | >f+++++++++ system/mount.txt
2026-04-07 06:36:12.078595 | instance | >f+++++++++ system/packages.txt
2026-04-07 06:36:12.078621 | instance | >f+++++++++ system/ps.txt
2026-04-07 06:36:12.078648 | instance | >f+++++++++ system/systemd-cgls.txt
2026-04-07 06:36:12.094945 | 
2026-04-07 06:36:12.095155 | LOOP [helm-release-status : creating directory for helm release status]
2026-04-07 06:36:12.298172 | instance | changed: "values"
2026-04-07 06:36:12.463986 | instance | changed: "releases"
2026-04-07 06:36:12.494858 | 
2026-04-07 06:36:12.495033 | TASK [helm-release-status : Gather get release status for helm charts]
2026-04-07 06:36:12.707577 | instance | /bin/bash: line 3: kubectl: command not found
2026-04-07 06:36:13.043361 | instance | ok: Runtime: 0:00:00.005679
2026-04-07 06:36:13.049958 | 
2026-04-07 06:36:13.050047 | TASK [helm-release-status : Downloads logs to executor]
2026-04-07 06:36:13.514808 | instance | changed:
2026-04-07 06:36:13.515091 | instance | cd+++++++++ helm/
2026-04-07 06:36:13.515151 | instance | cd+++++++++ helm/releases/
2026-04-07 06:36:13.515198 | instance | cd+++++++++ helm/values/
2026-04-07 06:36:13.524480 | 
2026-04-07 06:36:13.524547 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-04-07 06:36:13.710974 | instance | changed
2026-04-07 06:36:13.715763 | 
2026-04-07 06:36:13.715832 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-04-07 06:36:13.916964 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-04-07 06:36:13.917147 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-04-07 06:36:13.923062 | instance | environment: line 1: kubectl: command not found
2026-04-07 06:36:13.924045 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-04-07 06:36:13.925099 | instance | environment: line 1: kubectl: command not found
2026-04-07 06:36:13.926143 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-04-07 06:36:13.927342 | instance | environment: line 1: kubectl: command not found
2026-04-07 06:36:13.928446 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-04-07 06:36:13.929828 | instance | environment: line 1: kubectl: command not found
2026-04-07 06:36:13.930807 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-04-07 06:36:13.931708 | instance | environment: line 1: kubectl: command not found
2026-04-07 06:36:13.933268 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-04-07 06:36:14.251545 | instance | ok: Runtime: 0:00:00.025614
2026-04-07 06:36:14.258351 | 
2026-04-07 06:36:14.258415 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-04-07 06:36:14.452854 | instance | changed
2026-04-07 06:36:14.457917 | 
2026-04-07 06:36:14.457982 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-04-07 06:36:14.681269 | instance | environment: line 5: kubectl: command not found
2026-04-07 06:36:14.682355 | instance | xargs: xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args valuewarning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-04-07 06:36:14.682396 | instance |
2026-04-07 06:36:14.682461 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-04-07 06:36:15.020758 | instance | ok: Runtime: 0:00:00.009346
2026-04-07 06:36:15.028037 | 
2026-04-07 06:36:15.028101 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-04-07 06:36:15.508950 | instance | changed:
2026-04-07 06:36:15.509199 | instance | cd+++++++++ objects/
2026-04-07 06:36:15.509446 | instance | cd+++++++++ objects/cluster/
2026-04-07 06:36:15.509503 | instance | cd+++++++++ objects/namespaced/
2026-04-07 06:36:15.520262 | 
2026-04-07 06:36:15.520322 | TASK [gather-pod-logs : creating directory for pod logs]
2026-04-07 06:36:15.716470 | instance | changed
2026-04-07 06:36:15.723176 | 
2026-04-07 06:36:15.723236 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-04-07 06:36:15.961022 | instance | changed
2026-04-07 06:36:15.965738 | 
2026-04-07 06:36:15.965804 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-04-07 06:36:16.172150 | instance | environment: line 3: kubectl: command not found
2026-04-07 06:36:16.501985 | instance | ok: Runtime: 0:00:00.008939
2026-04-07 06:36:16.510691 | 
2026-04-07 06:36:16.510750 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-04-07 06:36:16.982852 | instance | changed:
2026-04-07 06:36:16.983119 | instance | cd+++++++++ pod-logs/
2026-04-07 06:36:16.983180 | instance | cd+++++++++ pod-logs/failed-pods/
2026-04-07 06:36:16.994042 | 
2026-04-07 06:36:16.994101 | TASK [gather-prom-metrics : creating directory for helm release descriptions]
2026-04-07 06:36:17.189548 | instance | changed
2026-04-07 06:36:17.196725 | 
2026-04-07 06:36:17.196815 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces]
2026-04-07 06:36:17.391459 | instance | /bin/bash: line 2: kubectl: command not found
2026-04-07 06:36:17.734222 | instance | ok: Runtime: 0:00:00.034426
2026-04-07 06:36:17.741298 | 
2026-04-07 06:36:17.741391 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr]
2026-04-07 06:36:17.946944 | instance | /bin/bash: line 2: kubectl: command not found
2026-04-07 06:36:17.975827 | instance | ceph-mgr endpoints:
2026-04-07 06:36:18.285824 | instance | ok: Runtime: 0:00:00.035842
2026-04-07 06:36:18.291377 | 
2026-04-07 06:36:18.291443 | TASK [gather-prom-metrics : Get metrics from fluentd pods]
2026-04-07 06:36:18.503229 | instance | /bin/bash: line 4: kubectl: command not found
2026-04-07 06:36:18.833415 | instance | ok: Runtime: 0:00:00.038008
2026-04-07 06:36:18.838873 | 
2026-04-07 06:36:18.838947 | TASK [gather-prom-metrics : Downloads logs to executor]
2026-04-07 06:36:19.302475 | instance | changed: cd+++++++++ prometheus/
2026-04-07 06:36:19.312605 | 
2026-04-07 06:36:19.312671 | TASK [gather-selenium-data : creating directory for helm release descriptions]
2026-04-07 06:36:19.512779 | instance | changed
2026-04-07 06:36:19.519065 | 
2026-04-07 06:36:19.519133 | TASK [gather-selenium-data : Get selenium data]
2026-04-07 06:36:19.719552 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/.
2026-04-07 06:36:19.720991 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory
2026-04-07 06:36:20.060603 | instance | ERROR
2026-04-07 06:36:20.061102 | instance | {
2026-04-07 06:36:20.061180 | instance |   "delta": "0:00:00.005228",
2026-04-07 06:36:20.061236 | instance |   "end": "2026-04-07 06:36:19.721310",
2026-04-07 06:36:20.061286 | instance |   "msg": "non-zero return code",
2026-04-07 06:36:20.061319 | instance |   "rc": 1,
2026-04-07 06:36:20.061341 | instance |   "start": "2026-04-07 06:36:19.716082"
2026-04-07 06:36:20.061363 | instance | }
2026-04-07 06:36:20.061392 | instance | ERROR: Ignoring Errors
2026-04-07 06:36:20.066375 | 
2026-04-07 06:36:20.066441 | TASK [gather-selenium-data : Downloads logs to executor]
2026-04-07 06:36:20.534840 | instance | changed: cd+++++++++ selenium/
2026-04-07 06:36:20.541283 | 
2026-04-07 06:36:20.541335 | PLAY RECAP
2026-04-07 06:36:20.541383 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1
2026-04-07 06:36:20.541404 | 
2026-04-07 06:36:20.647115 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@main]
2026-04-07 06:36:20.652053 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-07 06:36:21.196083 | 
2026-04-07 06:36:21.196196 | PLAY [all]
2026-04-07 06:36:21.207015 | 
2026-04-07 06:36:21.207089 | TASK [fetch-output : Set log path for multiple nodes]
2026-04-07 06:36:21.241180 | instance | skipping: Conditional result was False
2026-04-07 06:36:21.249586 | 
2026-04-07 06:36:21.249690 | TASK [fetch-output : Set log path for single node]
2026-04-07 06:36:21.281508 | instance | ok
2026-04-07 06:36:21.287440 | 
2026-04-07 06:36:21.287506 | LOOP [fetch-output : Ensure local output dirs]
2026-04-07 06:36:21.633218 | instance -> localhost | ok: "/var/lib/zuul/builds/55a15e3c563d4f03a6daf0915e0e5c5d/work/logs"
2026-04-07 06:36:21.828885 | instance -> localhost | changed: "/var/lib/zuul/builds/55a15e3c563d4f03a6daf0915e0e5c5d/work/artifacts"
2026-04-07 06:36:22.022069 | instance -> localhost | changed: "/var/lib/zuul/builds/55a15e3c563d4f03a6daf0915e0e5c5d/work/docs"
2026-04-07 06:36:22.042676 | 
2026-04-07 06:36:22.042810 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-04-07 06:36:22.628635 | instance | changed: .d..t...... ./
2026-04-07 06:36:22.628978 | instance | changed: All items complete
2026-04-07 06:36:22.629046 | 
2026-04-07 06:36:23.053594 | instance | changed: .d..t...... ./
2026-04-07 06:36:23.489762 | instance | changed: .d..t...... ./
2026-04-07 06:36:23.513201 | 
2026-04-07 06:36:23.513330 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-04-07 06:36:23.898623 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.008209
2026-04-07 06:36:24.099815 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.006990
2026-04-07 06:36:24.123427 | 
2026-04-07 06:36:24.123535 | PLAY [all]
2026-04-07 06:36:24.129267 | 
2026-04-07 06:36:24.129333 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-04-07 06:36:24.560325 | instance | changed
2026-04-07 06:36:24.567852 | 
2026-04-07 06:36:24.567902 | PLAY RECAP
2026-04-07 06:36:24.567944 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-04-07 06:36:24.567967 | 
2026-04-07 06:36:24.669225 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-07 06:36:24.671945 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-04-07 06:36:25.211260 | 
2026-04-07 06:36:25.211367 | PLAY [localhost]
2026-04-07 06:36:25.221156 | 
2026-04-07 06:36:25.221225 | TASK [Generate Zuul manifest]
2026-04-07 06:36:25.240989 | localhost | ok
2026-04-07 06:36:25.255262 | 
2026-04-07 06:36:25.255338 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-04-07 06:36:25.599028 | localhost | changed
2026-04-07 06:36:25.610490 | 
2026-04-07 06:36:25.610565 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-04-07 06:36:25.639833 | localhost | ok
2026-04-07 06:36:25.648726 | 
2026-04-07 06:36:25.648790 | TASK [Upload logs]
2026-04-07 06:36:25.668662 | localhost | ok
2026-04-07 06:36:25.724286 | 
2026-04-07 06:36:25.724374 | TASK [Set zuul-log-path fact]
2026-04-07 06:36:25.744308 | localhost | ok
2026-04-07 06:36:25.755926 | 
2026-04-07 06:36:25.755995 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-07 06:36:25.787263 | localhost | ok
2026-04-07 06:36:25.795281 | 
2026-04-07 06:36:25.795342 | TASK [upload-logs : Create log directories]
2026-04-07 06:36:26.171150 | localhost | changed
2026-04-07 06:36:26.177526 | 
2026-04-07 06:36:26.177590 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-04-07 06:36:26.563456 | localhost -> localhost | ok: Runtime: 0:00:00.005508
2026-04-07 06:36:26.570135 | 
2026-04-07 06:36:26.570225 | TASK [upload-logs : Upload logs to log server]
2026-04-07 06:36:27.024728 | localhost | Output suppressed because no_log was given
2026-04-07 06:36:27.029895 | 
2026-04-07 06:36:27.029987 | LOOP [upload-logs : Compress console log and json output]
2026-04-07 06:36:27.070955 | localhost | skipping: Conditional result was False
2026-04-07 06:36:27.078266 | localhost | skipping: Conditional result was False
2026-04-07 06:36:27.094095 | 
2026-04-07 06:36:27.094267 | LOOP [upload-logs : Upload compressed console log and json output]
2026-04-07 06:36:27.129983 | localhost | skipping: Conditional result was False
2026-04-07 06:36:27.130175 | 
2026-04-07 06:36:27.134465 | localhost | skipping: Conditional result was False
2026-04-07 06:36:27.147647 | 
2026-04-07 06:36:27.147766 | LOOP [upload-logs : Upload console log and json output]