2026-05-20 20:55:22.646329 | Job console starting
2026-05-20 20:55:22.782265 | Updating git repos
2026-05-20 20:55:22.805346 | Cloning repos into workspace
2026-05-20 20:55:23.319408 | Restoring repo states
2026-05-20 20:55:23.327912 | Merging changes
2026-05-20 20:55:23.741633 | Checking out repos
2026-05-20 20:55:23.779863 | Preparing playbooks
2026-05-20 20:55:25.301995 | Running Ansible setup
2026-05-20 20:55:29.015051 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-05-20 20:55:29.659519 | 
2026-05-20 20:55:29.950972 | PLAY [localhost]
2026-05-20 20:55:29.986781 | 
2026-05-20 20:55:29.986932 | TASK [Gathering Facts]
2026-05-20 20:55:31.073241 | localhost | ok
2026-05-20 20:55:31.080275 | 
2026-05-20 20:55:31.080370 | TASK [Setup log path fact]
2026-05-20 20:55:31.099901 | localhost | ok
2026-05-20 20:55:31.114446 | 
2026-05-20 20:55:31.114586 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-05-20 20:55:31.143342 | localhost | ok
2026-05-20 20:55:31.150274 | 
2026-05-20 20:55:31.150342 | TASK [emit-job-header : Print job information]
2026-05-20 20:55:31.188777 | # Job Information
2026-05-20 20:55:31.188976 | Ansible Version: 2.16.18
2026-05-20 20:55:31.189025 | Job: ansible-collection-containers-molecule-containerd-rockylinux-9
2026-05-20 20:55:31.189056 | Pipeline: check
2026-05-20 20:55:31.189084 | Executor: 2d72f0692154
2026-05-20 20:55:31.189112 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/124
2026-05-20 20:55:31.189143 | Event ID: 0c2d4a60-548e-11f1-87c4-e1bb39321abb
2026-05-20 20:55:31.192927 | 
2026-05-20 20:55:31.193109 | LOOP [emit-job-header : Print node information]
2026-05-20 20:55:31.308724 | localhost | ok:
2026-05-20 20:55:31.308997 | localhost | # Node Information
2026-05-20 20:55:31.309044 | localhost | Inventory Hostname: rockylinux-9
2026-05-20 20:55:31.309077 | localhost | Hostname: np0000175477
2026-05-20 20:55:31.309105 | localhost | Username: zuul
2026-05-20 20:55:31.309149 | localhost | Distro: Rocky 9.7
2026-05-20 20:55:31.309180 | localhost | Provider: yul1
2026-05-20 20:55:31.309207 | localhost | Region: ca-ymq-1
2026-05-20 20:55:31.309232 | localhost | Label: rockylinux-9
2026-05-20 20:55:31.309257 | localhost | Product Name: OpenStack Nova
2026-05-20 20:55:31.309283 | localhost | Interface IP: 199.19.213.154
2026-05-20 20:55:31.328181 | 
2026-05-20 20:55:31.328528 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-05-20 20:55:31.798051 | localhost -> localhost | changed
2026-05-20 20:55:31.804112 | 
2026-05-20 20:55:31.804180 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-05-20 20:55:32.737124 | localhost -> localhost | changed
2026-05-20 20:55:32.746441 | 
2026-05-20 20:55:32.746540 | PLAY [all]
2026-05-20 20:55:32.757659 | 
2026-05-20 20:55:32.757789 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-05-20 20:55:33.017233 | rockylinux-9 -> localhost | ok
2026-05-20 20:55:33.023703 | 
2026-05-20 20:55:33.023798 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-05-20 20:55:33.055310 | rockylinux-9 | ok
2026-05-20 20:55:33.069179 | rockylinux-9 | included: /var/lib/zuul/builds/de13ad343d564fd9b32ceda06b99691a/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-05-20 20:55:33.075676 | 
2026-05-20 20:55:33.075750 | TASK [add-build-sshkey : Create Temp SSH key]
2026-05-20 20:55:34.070263 | rockylinux-9 -> localhost | Generating public/private rsa key pair.
2026-05-20 20:55:34.070470 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/de13ad343d564fd9b32ceda06b99691a/work/de13ad343d564fd9b32ceda06b99691a_id_rsa
2026-05-20 20:55:34.070514 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/de13ad343d564fd9b32ceda06b99691a/work/de13ad343d564fd9b32ceda06b99691a_id_rsa.pub
2026-05-20 20:55:34.070546 | rockylinux-9 -> localhost | The key fingerprint is:
2026-05-20 20:55:34.070578 | rockylinux-9 -> localhost | SHA256:un9Ti03PAx4sA+vvKe6vmsIxrLZkG3GsvJTOTUuxA54 zuul-build-sshkey
2026-05-20 20:55:34.070623 | rockylinux-9 -> localhost | The key's randomart image is:
2026-05-20 20:55:34.070654 | rockylinux-9 -> localhost | +---[RSA 3072]----+
2026-05-20 20:55:34.070689 | rockylinux-9 -> localhost | |                 |
2026-05-20 20:55:34.070722 | rockylinux-9 -> localhost | |                 |
2026-05-20 20:55:34.070751 | rockylinux-9 -> localhost | |                 |
2026-05-20 20:55:34.070781 | rockylinux-9 -> localhost | |   .    .        |
2026-05-20 20:55:34.070811 | rockylinux-9 -> localhost | |  o.+   So .     |
2026-05-20 20:55:34.070839 | rockylinux-9 -> localhost | | o B+o .. o *    |
2026-05-20 20:55:34.070866 | rockylinux-9 -> localhost | |  Eo=o..   O *   |
2026-05-20 20:55:34.070896 | rockylinux-9 -> localhost | | *oBoo oo +.+ +  |
2026-05-20 20:55:34.070929 | rockylinux-9 -> localhost | | .*.o.+==B=.   . |
2026-05-20 20:55:34.070959 | rockylinux-9 -> localhost | +----[SHA256]-----+
2026-05-20 20:55:34.071026 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.575366
2026-05-20 20:55:34.079078 | 
2026-05-20 20:55:34.079198 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-05-20 20:55:34.119452 | rockylinux-9 | ok
2026-05-20 20:55:34.127297 | rockylinux-9 | included: /var/lib/zuul/builds/de13ad343d564fd9b32ceda06b99691a/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-05-20 20:55:34.134525 | 
2026-05-20 20:55:34.134588 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-05-20 20:55:34.158796 | rockylinux-9 | skipping: Conditional result was False
2026-05-20 20:55:34.168090 | 
2026-05-20 20:55:34.168177 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-05-20 20:55:34.653739 | rockylinux-9 | changed
2026-05-20 20:55:34.660169 | 
2026-05-20 20:55:34.660238 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-05-20 20:55:34.926340 | rockylinux-9 | ok
2026-05-20 20:55:34.931098 | 
2026-05-20 20:55:34.931161 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-05-20 20:55:35.894949 | rockylinux-9 | changed
2026-05-20 20:55:36.053290 | 
2026-05-20 20:55:36.053423 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-05-20 20:55:37.009779 | rockylinux-9 | changed
2026-05-20 20:55:37.016372 | 
2026-05-20 20:55:37.016559 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-05-20 20:55:37.041681 | rockylinux-9 | skipping: Conditional result was False
2026-05-20 20:55:37.051286 | 
2026-05-20 20:55:37.051373 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-05-20 20:55:37.462057 | rockylinux-9 -> localhost | changed
2026-05-20 20:55:37.487798 | 
2026-05-20 20:55:37.487945 | TASK [add-build-sshkey : Add back temp key]
2026-05-20 20:55:37.785886 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/de13ad343d564fd9b32ceda06b99691a/work/de13ad343d564fd9b32ceda06b99691a_id_rsa (zuul-build-sshkey)
2026-05-20 20:55:37.786135 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.013014
2026-05-20 20:55:37.792812 | 
2026-05-20 20:55:37.792913 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-05-20 20:55:38.238471 | rockylinux-9 | ok
2026-05-20 20:55:38.243468 | 
2026-05-20 20:55:38.243534 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-05-20 20:55:38.267814 | rockylinux-9 | skipping: Conditional result was False
2026-05-20 20:55:38.282931 | 
2026-05-20 20:55:38.283023 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-05-20 20:55:38.682859 | rockylinux-9 | ok
2026-05-20 20:55:38.688670 | 
2026-05-20 20:55:38.688733 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-05-20 20:55:39.765630 | rockylinux-9 | Output suppressed because no_log was given
2026-05-20 20:55:39.773426 | 
2026-05-20 20:55:39.773498 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-05-20 20:55:40.017540 | rockylinux-9 | ok: "logs"
2026-05-20 20:55:40.017887 | rockylinux-9 | ok: All items complete
2026-05-20 20:55:40.017930 | 
2026-05-20 20:55:40.232903 | rockylinux-9 | ok: "artifacts"
2026-05-20 20:55:40.451120 | rockylinux-9 | ok: "docs"
2026-05-20 20:55:40.472380 | 
2026-05-20 20:55:40.472569 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-05-20 20:55:40.730013 | rockylinux-9 | changed: "logs"
2026-05-20 20:55:41.021090 | rockylinux-9 | changed: "artifacts"
2026-05-20 20:55:41.185122 | rockylinux-9 | changed: "docs"
2026-05-20 20:55:41.208130 | 
2026-05-20 20:55:41.208196 | PLAY RECAP
2026-05-20 20:55:41.208239 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-05-20 20:55:41.208268 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-05-20 20:55:41.208289 | 
2026-05-20 20:55:41.412087 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-05-20 20:55:41.414653 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-05-20 20:55:42.055624 | 
2026-05-20 20:55:42.056029 | PLAY [all]
2026-05-20 20:55:42.067874 | 
2026-05-20 20:55:42.067977 | TASK [setup-uv : Extract archive]
2026-05-20 20:55:44.910507 | rockylinux-9 | changed
2026-05-20 20:55:44.916541 | 
2026-05-20 20:55:44.916645 | TASK [setup-uv : Print version]
2026-05-20 20:55:44.938482 | [rockylinux-9] Waiting on logger
2026-05-20 20:55:45.452864 | rockylinux-9 | ok: Runtime: 0:00:00.009918
2026-05-20 20:55:45.461846 | 
2026-05-20 20:55:45.461947 | TASK [Install Ansible collection siblings]
2026-05-20 20:55:45.911529 | rockylinux-9 | ok:
2026-05-20 20:55:45.912017 | rockylinux-9 | Skipping ansible.posix: no checked-out sibling
2026-05-20 20:55:45.912091 | rockylinux-9 | Skipping community.general: no checked-out sibling
2026-05-20 20:55:45.914619 | 
2026-05-20 20:55:45.914685 | PLAY RECAP
2026-05-20 20:55:45.914746 | rockylinux-9 | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-05-20 20:55:45.914777 | 
2026-05-20 20:55:46.067359 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-05-20 20:55:46.069440 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-05-20 20:55:46.719665 | 
2026-05-20 20:55:46.720028 | PLAY [all]
2026-05-20 20:55:46.731667 | 
2026-05-20 20:55:46.731741 | TASK [Copy inventory file for Zuul]
2026-05-20 20:55:48.499785 | rockylinux-9 | changed
2026-05-20 20:55:48.507429 | 
2026-05-20 20:55:48.507560 | TASK [Switch "ansible_host" to private IP]
2026-05-20 20:55:48.917023 | rockylinux-9 | changed: 1 replacements made
2026-05-20 20:55:48.923396 | 
2026-05-20 20:55:48.923491 | TASK [Run Molecule scenario]
2026-05-20 20:55:48.946030 | [rockylinux-9] Waiting on logger
2026-05-20 20:55:56.074063 | [rockylinux-9] Waiting on logger
2026-05-20 20:56:06.246341 | [rockylinux-9] Waiting on logger
2026-05-20 20:56:16.422393 | [rockylinux-9] Waiting on logger
2026-05-20 20:56:26.598157 | [rockylinux-9] Waiting on logger
2026-05-20 20:56:36.774162 | [rockylinux-9] Waiting on logger
2026-05-20 20:56:46.949994 | [rockylinux-9] Waiting on logger
2026-05-20 20:56:56.102199 | [rockylinux-9] Waiting on logger
2026-05-20 20:57:06.278266 | [rockylinux-9] Waiting on logger
2026-05-20 20:57:16.454173 | [rockylinux-9] Waiting on logger
2026-05-20 20:57:26.630370 | [rockylinux-9] Waiting on logger
2026-05-20 20:57:36.806121 | [rockylinux-9] Waiting on logger
2026-05-20 20:57:45.967492 | [rockylinux-9] Waiting on logger
2026-05-20 20:57:56.134282 | [rockylinux-9] Waiting on logger
2026-05-20 20:58:06.310414 | [rockylinux-9] Waiting on logger
2026-05-20 20:58:16.486094 | [rockylinux-9] Waiting on logger
2026-05-20 20:58:26.666177 | [rockylinux-9] Waiting on logger
2026-05-20 20:58:36.842139 | [rockylinux-9] Waiting on logger
2026-05-20 20:58:45.995453 | [rockylinux-9] Waiting on logger
2026-05-20 20:58:56.166115 | [rockylinux-9] Waiting on logger
2026-05-20 20:59:06.341916 | [rockylinux-9] Waiting on logger
2026-05-20 20:59:16.518250 | [rockylinux-9] Waiting on logger
2026-05-20 20:59:26.694372 | [rockylinux-9] Waiting on logger
2026-05-20 20:59:36.870293 | [rockylinux-9] Waiting on logger
2026-05-20 20:59:46.027775 | [rockylinux-9] Waiting on logger
2026-05-20 20:59:56.198369 | [rockylinux-9] Waiting on logger
2026-05-20 21:00:06.374073 | [rockylinux-9] Waiting on logger
2026-05-20 21:00:16.550438 | [rockylinux-9] Waiting on logger
2026-05-20 21:00:26.726319 | [rockylinux-9] Waiting on logger
2026-05-20 21:00:36.906138 | [rockylinux-9] Waiting on logger
2026-05-20 21:00:45.966841 | rockylinux-9 | ok: Runtime: 0:04:56.474665
2026-05-20 21:00:45.974515 | 
2026-05-20 21:00:45.974597 | PLAY RECAP
2026-05-20 21:00:45.974646 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-05-20 21:00:45.974668 | 
2026-05-20 21:00:46.142848 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-05-20 21:00:46.145488 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-05-20 21:00:46.771330 | 
2026-05-20 21:00:46.771511 | PLAY [all]
2026-05-20 21:00:46.783388 | 
2026-05-20 21:00:46.783484 | TASK [fetch-output : Set log path for multiple nodes]
2026-05-20 21:00:46.828559 | rockylinux-9 | skipping: Conditional result was False
2026-05-20 21:00:46.837143 | 
2026-05-20 21:00:46.837301 | TASK [fetch-output : Set log path for single node]
2026-05-20 21:00:46.882545 | rockylinux-9 | ok
2026-05-20 21:00:46.889357 | 
2026-05-20 21:00:46.889454 | LOOP [fetch-output : Ensure local output dirs]
2026-05-20 21:00:47.256391 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/de13ad343d564fd9b32ceda06b99691a/work/logs"
2026-05-20 21:00:47.451867 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/de13ad343d564fd9b32ceda06b99691a/work/artifacts"
2026-05-20 21:00:47.645811 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/de13ad343d564fd9b32ceda06b99691a/work/docs"
2026-05-20 21:00:47.667057 | 
2026-05-20 21:00:47.667218 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-05-20 21:00:48.367623 | rockylinux-9 | changed: .d..t...... ./
2026-05-20 21:00:48.367851 | rockylinux-9 | changed: All items complete
2026-05-20 21:00:48.367878 | 
2026-05-20 21:00:48.869540 | rockylinux-9 | changed: .d..t...... ./
2026-05-20 21:00:49.394761 | rockylinux-9 | changed: .d..t...... ./
2026-05-20 21:00:49.412147 | 
2026-05-20 21:00:49.412299 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-05-20 21:00:49.805171 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.007077
2026-05-20 21:00:50.019991 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.007851
2026-05-20 21:00:50.039951 | 
2026-05-20 21:00:50.040105 | PLAY [all]
2026-05-20 21:00:50.046697 | 
2026-05-20 21:00:50.046811 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-05-20 21:00:50.496164 | rockylinux-9 | changed
2026-05-20 21:00:50.503507 | 
2026-05-20 21:00:50.503624 | PLAY RECAP
2026-05-20 21:00:50.503686 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-05-20 21:00:50.503716 | 
2026-05-20 21:00:50.636255 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-05-20 21:00:50.638745 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-05-20 21:00:51.206273 | 
2026-05-20 21:00:51.206403 | PLAY [localhost]
2026-05-20 21:00:51.217036 | 
2026-05-20 21:00:51.217118 | TASK [Generate Zuul manifest]
2026-05-20 21:00:51.237718 | localhost | ok
2026-05-20 21:00:51.254594 | 
2026-05-20 21:00:51.254676 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-05-20 21:00:51.618949 | localhost | changed
2026-05-20 21:00:51.631245 | 
2026-05-20 21:00:51.631323 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-05-20 21:00:51.662908 | localhost | ok
2026-05-20 21:00:51.670941 | 
2026-05-20 21:00:51.671018 | TASK [Upload logs]
2026-05-20 21:00:51.689641 | localhost | ok
2026-05-20 21:00:51.754052 | 
2026-05-20 21:00:51.754247 | TASK [Set zuul-log-path fact]
2026-05-20 21:00:51.776484 | localhost | ok
2026-05-20 21:00:51.791466 | 
2026-05-20 21:00:51.791592 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-05-20 21:00:51.823244 | localhost | ok
2026-05-20 21:00:51.832768 | 
2026-05-20 21:00:51.832835 | TASK [upload-logs : Create log directories]
2026-05-20 21:00:52.226972 | localhost | changed
2026-05-20 21:00:52.234801 | 
2026-05-20 21:00:52.234922 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-05-20 21:00:52.642796 | localhost -> localhost | ok: Runtime: 0:00:00.004937
2026-05-20 21:00:52.651222 | 
2026-05-20 21:00:52.651332 | TASK [upload-logs : Upload logs to log server]
2026-05-20 21:00:53.138356 | localhost | Output suppressed because no_log was given
2026-05-20 21:00:53.143331 | 
2026-05-20 21:00:53.143430 | LOOP [upload-logs : Compress console log and json output]
2026-05-20 21:00:53.186880 | localhost | skipping: Conditional result was False
2026-05-20 21:00:53.201620 | localhost | skipping: Conditional result was False
2026-05-20 21:00:53.215896 | 
2026-05-20 21:00:53.215992 | LOOP [upload-logs : Upload compressed console log and json output]
2026-05-20 21:00:53.254854 | localhost | skipping: Conditional result was False
2026-05-20 21:00:53.255204 | 
2026-05-20 21:00:53.258984 | localhost | skipping: Conditional result was False
2026-05-20 21:00:53.270706 | 
2026-05-20 21:00:53.270944 | LOOP [upload-logs : Upload console log and json output]