2026-01-21 18:15:47.508540 | Job console starting
2026-01-21 18:15:47.521831 | Updating git repos
2026-01-21 18:15:47.543910 | Cloning repos into workspace
2026-01-21 18:15:47.588151 | Restoring repo states
2026-01-21 18:15:47.597870 | Merging changes
2026-01-21 18:15:47.877137 | Checking out repos
2026-01-21 18:15:47.905673 | Preparing playbooks
2026-01-21 18:15:48.906494 | Running Ansible setup
2026-01-21 18:15:52.317774 | PRE-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main]
2026-01-21 18:15:52.930840 | 
2026-01-21 18:15:52.930984 | PLAY [localhost]
2026-01-21 18:15:52.939185 | 
2026-01-21 18:15:52.939262 | TASK [Gathering Facts]
2026-01-21 18:15:53.914632 | localhost | ok
2026-01-21 18:15:53.925259 | 
2026-01-21 18:15:53.925354 | TASK [Setup log path fact]
2026-01-21 18:15:53.944855 | localhost | ok
2026-01-21 18:15:53.957115 | 
2026-01-21 18:15:53.957238 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-01-21 18:15:53.987446 | localhost | ok
2026-01-21 18:15:53.994296 | 
2026-01-21 18:15:53.994375 | TASK [emit-job-header : Print job information]
2026-01-21 18:15:54.036264 | # Job Information
2026-01-21 18:15:54.036535 | Ansible Version: 2.16.15
2026-01-21 18:15:54.036583 | Job: ansible-collection-containers-molecule-docker-rockylinux-9
2026-01-21 18:15:54.036613 | Pipeline: check
2026-01-21 18:15:54.036641 | Executor: 3a2793d2bd32
2026-01-21 18:15:54.036669 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/94
2026-01-21 18:15:54.036700 | Event ID: 1131c460-f6f5-11f0-99c8-3dea310db7ca
2026-01-21 18:15:54.040971 | 
2026-01-21 18:15:54.041054 | LOOP [emit-job-header : Print node information]
2026-01-21 18:15:54.135728 | localhost | ok:
2026-01-21 18:15:54.144525 | localhost | # Node Information
2026-01-21 18:15:54.144634 | localhost | Inventory Hostname: rockylinux-9
2026-01-21 18:15:54.144691 | localhost | Hostname: np0000154379
2026-01-21 18:15:54.144728 | localhost | Username: zuul
2026-01-21 18:15:54.144779 | localhost | Distro: Rocky 9.7
2026-01-21 18:15:54.144815 | localhost | Provider: yul1
2026-01-21 18:15:54.144849 | localhost | Region: ca-ymq-1
2026-01-21 18:15:54.144877 | localhost | Label: rockylinux-9
2026-01-21 18:15:54.144905 | localhost | Product Name: OpenStack Nova
2026-01-21 18:15:54.144933 | localhost | Interface IP: 162.253.55.52
2026-01-21 18:15:54.161323 | 
2026-01-21 18:15:54.161491 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-01-21 18:15:54.612936 | localhost -> localhost | changed
2026-01-21 18:15:54.621541 | 
2026-01-21 18:15:54.621644 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-01-21 18:15:55.435743 | localhost -> localhost | changed
2026-01-21 18:15:55.447554 | 
2026-01-21 18:15:55.447665 | PLAY [all]
2026-01-21 18:15:55.457771 | 
2026-01-21 18:15:55.457835 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-01-21 18:15:55.685469 | rockylinux-9 -> localhost | ok
2026-01-21 18:15:55.694201 | 
2026-01-21 18:15:55.694293 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-01-21 18:15:55.727498 | rockylinux-9 | ok
2026-01-21 18:15:55.743099 | rockylinux-9 | included: /var/lib/zuul/builds/30f2f46585374139bc69b4d7ffdfca08/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-01-21 18:15:55.749238 | 
2026-01-21 18:15:55.749297 | TASK [add-build-sshkey : Create Temp SSH key]
2026-01-21 18:15:56.769701 | rockylinux-9 -> localhost | Generating public/private rsa key pair.
2026-01-21 18:15:56.769931 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/30f2f46585374139bc69b4d7ffdfca08/work/30f2f46585374139bc69b4d7ffdfca08_id_rsa
2026-01-21 18:15:56.769963 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/30f2f46585374139bc69b4d7ffdfca08/work/30f2f46585374139bc69b4d7ffdfca08_id_rsa.pub
2026-01-21 18:15:56.769986 | rockylinux-9 -> localhost | The key fingerprint is:
2026-01-21 18:15:56.770008 | rockylinux-9 -> localhost | SHA256:2JtwR7o3VqicvM3GfE6QV30QGWd+D3If4KeHF7nwJtU zuul-build-sshkey
2026-01-21 18:15:56.770044 | rockylinux-9 -> localhost | The key's randomart image is:
2026-01-21 18:15:56.770065 | rockylinux-9 -> localhost | +---[RSA 3072]----+
2026-01-21 18:15:56.770092 | rockylinux-9 -> localhost | |             .++o|
2026-01-21 18:15:56.770113 | rockylinux-9 -> localhost | |            . o*o|
2026-01-21 18:15:56.770132 | rockylinux-9 -> localhost | |          . .o+*E|
2026-01-21 18:15:56.770152 | rockylinux-9 -> localhost | |       o o o +BoB|
2026-01-21 18:15:56.770171 | rockylinux-9 -> localhost | |      o S = o+ *o|
2026-01-21 18:15:56.770190 | rockylinux-9 -> localhost | |       = B +  =  |
2026-01-21 18:15:56.770208 | rockylinux-9 -> localhost | |        Oo+ .    |
2026-01-21 18:15:56.770228 | rockylinux-9 -> localhost | |         *+o.    |
2026-01-21 18:15:56.770249 | rockylinux-9 -> localhost | |        ..oo.    |
2026-01-21 18:15:56.770269 | rockylinux-9 -> localhost | +----[SHA256]-----+
2026-01-21 18:15:56.770318 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.617947
2026-01-21 18:15:56.774971 | 
2026-01-21 18:15:56.775031 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-01-21 18:15:56.803628 | rockylinux-9 | ok
2026-01-21 18:15:56.811608 | rockylinux-9 | included: /var/lib/zuul/builds/30f2f46585374139bc69b4d7ffdfca08/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-01-21 18:15:56.818758 | 
2026-01-21 18:15:56.818819 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-01-21 18:15:56.843274 | rockylinux-9 | skipping: Conditional result was False
2026-01-21 18:15:56.849204 | 
2026-01-21 18:15:56.849265 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-01-21 18:15:57.312197 | rockylinux-9 | changed
2026-01-21 18:15:57.317985 | 
2026-01-21 18:15:57.318044 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-01-21 18:15:57.564432 | rockylinux-9 | ok
2026-01-21 18:15:57.571104 | 
2026-01-21 18:15:57.571168 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-01-21 18:15:58.474622 | rockylinux-9 | changed
2026-01-21 18:15:58.480863 | 
2026-01-21 18:15:58.480927 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-01-21 18:15:59.275503 | rockylinux-9 | changed
2026-01-21 18:15:59.279897 | 
2026-01-21 18:15:59.279957 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-01-21 18:15:59.302613 | rockylinux-9 | skipping: Conditional result was False
2026-01-21 18:15:59.311497 | 
2026-01-21 18:15:59.311664 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-01-21 18:15:59.682997 | rockylinux-9 -> localhost | changed
2026-01-21 18:15:59.694802 | 
2026-01-21 18:15:59.694868 | TASK [add-build-sshkey : Add back temp key]
2026-01-21 18:15:59.988244 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/30f2f46585374139bc69b4d7ffdfca08/work/30f2f46585374139bc69b4d7ffdfca08_id_rsa (zuul-build-sshkey)
2026-01-21 18:15:59.988509 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.012542
2026-01-21 18:15:59.995562 | 
2026-01-21 18:15:59.995643 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-01-21 18:16:00.381527 | rockylinux-9 | ok
2026-01-21 18:16:00.389414 | 
2026-01-21 18:16:00.389498 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-01-21 18:16:00.414144 | rockylinux-9 | skipping: Conditional result was False
2026-01-21 18:16:00.428666 | 
2026-01-21 18:16:00.428775 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-01-21 18:16:00.835640 | rockylinux-9 | ok
2026-01-21 18:16:00.843134 | 
2026-01-21 18:16:00.843228 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-01-21 18:16:01.734570 | rockylinux-9 | Output suppressed because no_log was given
2026-01-21 18:16:01.745742 | 
2026-01-21 18:16:01.745815 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-01-21 18:16:02.009177 | rockylinux-9 | ok: "logs"
2026-01-21 18:16:02.009463 | rockylinux-9 | ok: All items complete
2026-01-21 18:16:02.009492 | 
2026-01-21 18:16:02.241211 | rockylinux-9 | ok: "artifacts"
2026-01-21 18:16:02.471723 | rockylinux-9 | ok: "docs"
2026-01-21 18:16:02.490029 | 
2026-01-21 18:16:02.490205 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-01-21 18:16:02.762269 | rockylinux-9 | changed: "logs"
2026-01-21 18:16:02.974269 | rockylinux-9 | changed: "artifacts"
2026-01-21 18:16:03.195141 | rockylinux-9 | changed: "docs"
2026-01-21 18:16:03.210124 | 
2026-01-21 18:16:03.210249 | PLAY RECAP
2026-01-21 18:16:03.210298 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-01-21 18:16:03.210328 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-01-21 18:16:03.210352 | 
2026-01-21 18:16:03.336347 | PRE-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main]
2026-01-21 18:16:03.339045 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-01-21 18:16:03.964694 | 
2026-01-21 18:16:03.964851 | PLAY [all]
2026-01-21 18:16:03.977098 | 
2026-01-21 18:16:04.015628 | TASK [setup-uv : Extract archive]
2026-01-21 18:16:06.652216 | rockylinux-9 | changed
2026-01-21 18:16:06.657638 | 
2026-01-21 18:16:06.657719 | TASK [setup-uv : Print version]
2026-01-21 18:16:06.699015 | [rockylinux-9] Waiting on logger
2026-01-21 18:16:07.213450 | rockylinux-9 | ok: Runtime: 0:00:00.012922
2026-01-21 18:16:07.220786 | 
2026-01-21 18:16:07.220838 | PLAY RECAP
2026-01-21 18:16:07.220888 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-01-21 18:16:07.220914 | 
2026-01-21 18:16:07.354083 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-01-21 18:16:07.395907 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-01-21 18:16:07.989448 | 
2026-01-21 18:16:07.990083 | PLAY [all]
2026-01-21 18:16:08.001297 | 
2026-01-21 18:16:08.001396 | TASK [Copy inventory file for Zuul]
2026-01-21 18:16:09.215287 | rockylinux-9 | changed
2026-01-21 18:16:09.221846 | 
2026-01-21 18:16:09.221950 | TASK [Switch "ansible_host" to private IP]
2026-01-21 18:16:09.548121 | rockylinux-9 | changed: 1 replacements made
2026-01-21 18:16:09.554899 | 
2026-01-21 18:16:09.554990 | TASK [Run Molecule scenario]
2026-01-21 18:16:09.579568 | [rockylinux-9] Waiting on logger
2026-01-21 18:16:17.711308 | [rockylinux-9] Waiting on logger
2026-01-21 18:16:27.878074 | [rockylinux-9] Waiting on logger
2026-01-21 18:16:38.054023 | [rockylinux-9] Waiting on logger
2026-01-21 18:16:48.233788 | [rockylinux-9] Waiting on logger
2026-01-21 18:16:58.410468 | [rockylinux-9] Waiting on logger
2026-01-21 18:17:08.581763 | [rockylinux-9] Waiting on logger
2026-01-21 18:17:17.789237 | [rockylinux-9] Waiting on logger
2026-01-21 18:17:28.006065 | [rockylinux-9] Waiting on logger
2026-01-21 18:17:38.214064 | [rockylinux-9] Waiting on logger
2026-01-21 18:17:48.421854 | [rockylinux-9] Waiting on logger
2026-01-21 18:17:58.598045 | [rockylinux-9] Waiting on logger
2026-01-21 18:18:07.755479 | [rockylinux-9] Waiting on logger
2026-01-21 18:18:17.929981 | [rockylinux-9] Waiting on logger
2026-01-21 18:18:28.102070 | [rockylinux-9] Waiting on logger
2026-01-21 18:18:38.277895 | [rockylinux-9] Waiting on logger
2026-01-21 18:18:48.454038 | [rockylinux-9] Waiting on logger
2026-01-21 18:18:50.702289 | rockylinux-9 | ok: Runtime: 0:02:40.106556
2026-01-21 18:18:50.709837 | 
2026-01-21 18:18:50.709891 | PLAY RECAP
2026-01-21 18:18:50.709938 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-01-21 18:18:50.709960 | 
2026-01-21 18:18:50.837750 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-01-21 18:18:50.840110 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main]
2026-01-21 18:18:51.431917 | 
2026-01-21 18:18:51.432081 | PLAY [all]
2026-01-21 18:18:51.443145 | 
2026-01-21 18:18:51.443681 | TASK [fetch-output : Set log path for multiple nodes]
2026-01-21 18:18:51.489706 | rockylinux-9 | skipping: Conditional result was False
2026-01-21 18:18:51.498414 | 
2026-01-21 18:18:51.498491 | TASK [fetch-output : Set log path for single node]
2026-01-21 18:18:51.542661 | rockylinux-9 | ok
2026-01-21 18:18:51.548271 | 
2026-01-21 18:18:51.548361 | LOOP [fetch-output : Ensure local output dirs]
2026-01-21 18:18:51.975992 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/30f2f46585374139bc69b4d7ffdfca08/work/logs"
2026-01-21 18:18:52.196069 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/30f2f46585374139bc69b4d7ffdfca08/work/artifacts"
2026-01-21 18:18:52.411208 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/30f2f46585374139bc69b4d7ffdfca08/work/docs"
2026-01-21 18:18:52.425891 | 
2026-01-21 18:18:52.426016 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-01-21 18:18:53.102221 | rockylinux-9 | changed: .d..t...... ./
2026-01-21 18:18:53.102480 | rockylinux-9 | changed: All items complete
2026-01-21 18:18:53.102518 | 
2026-01-21 18:18:53.606970 | rockylinux-9 | changed: .d..t...... ./
2026-01-21 18:18:54.092451 | rockylinux-9 | changed: .d..t...... ./
2026-01-21 18:18:54.121448 | 
2026-01-21 18:18:54.121649 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-01-21 18:18:54.530421 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.007696
2026-01-21 18:18:54.746918 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.007641
2026-01-21 18:18:54.767805 | 
2026-01-21 18:18:54.767940 | PLAY [all]
2026-01-21 18:18:54.773797 | 
2026-01-21 18:18:54.773869 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-01-21 18:18:55.232934 | rockylinux-9 | changed
2026-01-21 18:18:55.241214 | 
2026-01-21 18:18:55.241268 | PLAY RECAP
2026-01-21 18:18:55.241315 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-01-21 18:18:55.241337 | 
2026-01-21 18:18:55.398133 | POST-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main]
2026-01-21 18:18:55.400532 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post-logs.yaml@main]
2026-01-21 18:18:55.954760 | 
2026-01-21 18:18:55.955606 | PLAY [localhost]
2026-01-21 18:18:55.966247 | 
2026-01-21 18:18:55.966323 | TASK [Generate Zuul manifest]
2026-01-21 18:18:55.987714 | localhost | ok
2026-01-21 18:18:56.003738 | 
2026-01-21 18:18:56.003817 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-01-21 18:18:56.320294 | localhost | changed
2026-01-21 18:18:56.333320 | 
2026-01-21 18:18:56.333398 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-01-21 18:18:56.364718 | localhost | ok
2026-01-21 18:18:56.372730 | 
2026-01-21 18:18:56.372795 | TASK [Upload logs]
2026-01-21 18:18:56.392999 | localhost | ok
2026-01-21 18:18:56.453951 | 
2026-01-21 18:18:56.454048 | TASK [Set zuul-log-path fact]
2026-01-21 18:18:56.473838 | localhost | ok
2026-01-21 18:18:56.486065 | 
2026-01-21 18:18:56.486132 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-01-21 18:18:56.516923 | localhost | ok
2026-01-21 18:18:56.525928 | 
2026-01-21 18:18:56.526017 | TASK [upload-logs : Create log directories]
2026-01-21 18:18:56.932453 | localhost | changed
2026-01-21 18:18:56.939753 | 
2026-01-21 18:18:56.939845 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-01-21 18:18:57.356908 | localhost -> localhost | ok: Runtime: 0:00:00.006103
2026-01-21 18:18:57.363707 | 
2026-01-21 18:18:57.363794 | TASK [upload-logs : Upload logs to log server]
2026-01-21 18:18:57.870927 | localhost | Output suppressed because no_log was given
2026-01-21 18:18:57.877764 | 
2026-01-21 18:18:57.877835 | LOOP [upload-logs : Compress console log and json output]
2026-01-21 18:18:57.919821 | localhost | skipping: Conditional result was False
2026-01-21 18:18:57.926917 | localhost | skipping: Conditional result was False
2026-01-21 18:18:57.941618 | 
2026-01-21 18:18:57.941773 | LOOP [upload-logs : Upload compressed console log and json output]
2026-01-21 18:18:57.982463 | localhost | skipping: Conditional result was False
2026-01-21 18:18:57.982810 | 
2026-01-21 18:18:57.985876 | localhost | skipping: Conditional result was False
2026-01-21 18:18:57.996403 | 
2026-01-21 18:18:57.996609 | LOOP [upload-logs : Upload console log and json output]