2026-05-21 02:32:27.705267 | Job console starting
2026-05-21 02:32:27.718805 | Updating git repos
2026-05-21 02:32:27.745597 | Cloning repos into workspace
2026-05-21 02:32:27.854086 | Restoring repo states
2026-05-21 02:32:27.867884 | Merging changes
2026-05-21 02:32:28.128022 | Checking out repos
2026-05-21 02:32:28.153398 | Preparing playbooks
2026-05-21 02:32:29.057241 | Running Ansible setup
2026-05-21 02:32:32.628442 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-05-21 02:32:33.270149 | 
2026-05-21 02:32:33.270325 | PLAY [localhost]
2026-05-21 02:32:33.279224 | 
2026-05-21 02:32:33.279299 | TASK [Gathering Facts]
2026-05-21 02:32:34.879264 | localhost | ok
2026-05-21 02:32:34.886663 | 
2026-05-21 02:32:34.886794 | TASK [Setup log path fact]
2026-05-21 02:32:34.904306 | localhost | ok
2026-05-21 02:32:34.915403 | 
2026-05-21 02:32:34.915527 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-05-21 02:32:34.946433 | localhost | ok
2026-05-21 02:32:34.954836 | 
2026-05-21 02:32:34.954965 | TASK [emit-job-header : Print job information]
2026-05-21 02:32:34.995291 | # Job Information
2026-05-21 02:32:34.995479 | Ansible Version: 2.16.18
2026-05-21 02:32:34.995531 | Job: ansible-collection-containers-molecule-containerd-rockylinux-9
2026-05-21 02:32:34.995564 | Pipeline: check
2026-05-21 02:32:34.995595 | Executor: 2d72f0692154
2026-05-21 02:32:34.995624 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/126
2026-05-21 02:32:34.995658 | Event ID: fae3e460-54bc-11f1-8133-32d0a9e16fea
2026-05-21 02:32:34.999882 | 
2026-05-21 02:32:34.999987 | LOOP [emit-job-header : Print node information]
2026-05-21 02:32:35.106950 | localhost | ok:
2026-05-21 02:32:35.107135 | localhost | # Node Information
2026-05-21 02:32:35.107166 | localhost | Inventory Hostname: rockylinux-9
2026-05-21 02:32:35.107188 | localhost | Hostname: np0000175688
2026-05-21 02:32:35.107207 | localhost | Username: zuul
2026-05-21 02:32:35.107231 | localhost | Distro: Rocky 9.7
2026-05-21 02:32:35.107250 | localhost | Provider: yul1
2026-05-21 02:32:35.107268 | localhost | Region: ca-ymq-1
2026-05-21 02:32:35.107286 | localhost | Label: rockylinux-9
2026-05-21 02:32:35.107304 | localhost | Product Name: OpenStack Nova
2026-05-21 02:32:35.107322 | localhost | Interface IP: 199.19.213.11
2026-05-21 02:32:35.127012 | 
2026-05-21 02:32:35.127136 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-05-21 02:32:35.506603 | localhost -> localhost | changed
2026-05-21 02:32:35.511072 | 
2026-05-21 02:32:35.511138 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-05-21 02:32:36.369756 | localhost -> localhost | changed
2026-05-21 02:32:36.380418 | 
2026-05-21 02:32:36.380497 | PLAY [all]
2026-05-21 02:32:36.394001 | 
2026-05-21 02:32:36.394215 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-05-21 02:32:36.643491 | rockylinux-9 -> localhost | ok
2026-05-21 02:32:36.652156 | 
2026-05-21 02:32:36.652251 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-05-21 02:32:36.685139 | rockylinux-9 | ok
2026-05-21 02:32:36.700072 | rockylinux-9 | included: /var/lib/zuul/builds/008ca74409f8418c8a35848265c0d3f1/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-05-21 02:32:36.706474 | 
2026-05-21 02:32:36.706543 | TASK [add-build-sshkey : Create Temp SSH key]
2026-05-21 02:32:38.007681 | rockylinux-9 -> localhost | Generating public/private rsa key pair.
2026-05-21 02:32:38.008436 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/008ca74409f8418c8a35848265c0d3f1/work/008ca74409f8418c8a35848265c0d3f1_id_rsa
2026-05-21 02:32:38.008480 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/008ca74409f8418c8a35848265c0d3f1/work/008ca74409f8418c8a35848265c0d3f1_id_rsa.pub
2026-05-21 02:32:38.008507 | rockylinux-9 -> localhost | The key fingerprint is:
2026-05-21 02:32:38.008530 | rockylinux-9 -> localhost | SHA256:ylnOWfvLpCEJ67o1bRgkOoyAjQLdtGGHjkqDOs7oYq4 zuul-build-sshkey
2026-05-21 02:32:38.008566 | rockylinux-9 -> localhost | The key's randomart image is:
2026-05-21 02:32:38.008590 | rockylinux-9 -> localhost | +---[RSA 3072]----+
2026-05-21 02:32:38.008617 | rockylinux-9 -> localhost | |.. o+..          |
2026-05-21 02:32:38.008640 | rockylinux-9 -> localhost | |oo..o+           |
2026-05-21 02:32:38.008661 | rockylinux-9 -> localhost | |* .oo .          |
2026-05-21 02:32:38.008682 | rockylinux-9 -> localhost | |+=...o           |
2026-05-21 02:32:38.008703 | rockylinux-9 -> localhost | |+.=   o S .      |
2026-05-21 02:32:38.008724 | rockylinux-9 -> localhost | |+  . . % + .     |
2026-05-21 02:32:38.008744 | rockylinux-9 -> localhost | |+.    O O o .    |
2026-05-21 02:32:38.008765 | rockylinux-9 -> localhost | |o+   o o . *     |
2026-05-21 02:32:38.008788 | rockylinux-9 -> localhost | |Eo  oo.   . +.   |
2026-05-21 02:32:38.008809 | rockylinux-9 -> localhost | +----[SHA256]-----+
2026-05-21 02:32:38.009286 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.809573
2026-05-21 02:32:38.015433 | 
2026-05-21 02:32:38.015520 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-05-21 02:32:38.049289 | rockylinux-9 | ok
2026-05-21 02:32:38.068921 | rockylinux-9 | included: /var/lib/zuul/builds/008ca74409f8418c8a35848265c0d3f1/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-05-21 02:32:38.079285 | 
2026-05-21 02:32:38.079374 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-05-21 02:32:38.103643 | rockylinux-9 | skipping: Conditional result was False
2026-05-21 02:32:38.121835 | 
2026-05-21 02:32:38.122057 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-05-21 02:32:38.635615 | rockylinux-9 | changed
2026-05-21 02:32:38.646244 | 
2026-05-21 02:32:40.543198 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-05-21 02:32:40.793189 | rockylinux-9 | ok
2026-05-21 02:32:40.800582 | 
2026-05-21 02:32:40.800681 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-05-21 02:32:41.748631 | rockylinux-9 | changed
2026-05-21 02:32:42.124652 | 
2026-05-21 02:32:42.124998 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-05-21 02:32:43.088152 | rockylinux-9 | changed
2026-05-21 02:32:43.093284 | 
2026-05-21 02:32:43.093351 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-05-21 02:32:43.117560 | rockylinux-9 | skipping: Conditional result was False
2026-05-21 02:32:43.123274 | 
2026-05-21 02:32:43.123357 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-05-21 02:32:43.542562 | rockylinux-9 -> localhost | changed
2026-05-21 02:32:43.558170 | 
2026-05-21 02:32:43.558285 | TASK [add-build-sshkey : Add back temp key]
2026-05-21 02:32:44.936038 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/008ca74409f8418c8a35848265c0d3f1/work/008ca74409f8418c8a35848265c0d3f1_id_rsa (zuul-build-sshkey)
2026-05-21 02:32:44.936210 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.012614
2026-05-21 02:32:44.940802 | 
2026-05-21 02:32:44.940871 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-05-21 02:32:45.361859 | rockylinux-9 | ok
2026-05-21 02:32:45.366078 | 
2026-05-21 02:32:45.366145 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-05-21 02:32:45.390192 | rockylinux-9 | skipping: Conditional result was False
2026-05-21 02:32:45.404587 | 
2026-05-21 02:32:45.404678 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-05-21 02:32:45.791842 | rockylinux-9 | ok
2026-05-21 02:32:45.873109 | 
2026-05-21 02:32:45.873199 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-05-21 02:32:46.891823 | rockylinux-9 | Output suppressed because no_log was given
2026-05-21 02:32:46.901224 | 
2026-05-21 02:32:46.901314 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-05-21 02:32:47.151338 | rockylinux-9 | ok: "logs"
2026-05-21 02:32:47.151639 | rockylinux-9 | ok: All items complete
2026-05-21 02:32:47.151684 | 
2026-05-21 02:32:47.366451 | rockylinux-9 | ok: "artifacts"
2026-05-21 02:32:47.595491 | rockylinux-9 | ok: "docs"
2026-05-21 02:32:47.607258 | 
2026-05-21 02:32:47.607381 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-05-21 02:32:47.867325 | rockylinux-9 | changed: "logs"
2026-05-21 02:32:48.143561 | rockylinux-9 | changed: "artifacts"
2026-05-21 02:32:48.332562 | rockylinux-9 | changed: "docs"
2026-05-21 02:32:48.527193 | 
2026-05-21 02:32:48.527301 | PLAY RECAP
2026-05-21 02:32:48.527362 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-05-21 02:32:48.527407 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-05-21 02:32:48.527439 | 
2026-05-21 02:32:48.708477 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-05-21 02:32:48.937176 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-05-21 02:32:49.648009 | 
2026-05-21 02:32:49.846362 | PLAY [all]
2026-05-21 02:32:49.865142 | 
2026-05-21 02:32:49.865285 | TASK [setup-uv : Extract archive]
2026-05-21 02:32:52.602591 | rockylinux-9 | changed
2026-05-21 02:32:52.608573 | 
2026-05-21 02:32:52.608678 | TASK [setup-uv : Print version]
2026-05-21 02:32:52.642700 | [rockylinux-9] Waiting on logger
2026-05-21 02:32:53.155622 | rockylinux-9 | ok: Runtime: 0:00:00.014118
2026-05-21 02:32:53.166463 | 
2026-05-21 02:32:53.166538 | TASK [Install Ansible collection siblings]
2026-05-21 02:32:53.585536 | rockylinux-9 | ok:
2026-05-21 02:32:53.585712 | rockylinux-9 | Skipping ansible.posix: no checked-out sibling
2026-05-21 02:32:53.585753 | rockylinux-9 | Skipping community.general: no checked-out sibling
2026-05-21 02:32:53.587954 | 
2026-05-21 02:32:53.588021 | PLAY RECAP
2026-05-21 02:32:53.588083 | rockylinux-9 | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-05-21 02:32:53.588113 | 
2026-05-21 02:32:53.709103 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-05-21 02:32:53.710711 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-05-21 02:32:54.454381 | 
2026-05-21 02:32:54.864135 | PLAY [all]
2026-05-21 02:32:54.884873 | 
2026-05-21 02:32:54.885056 | TASK [Copy inventory file for Zuul]
2026-05-21 02:32:56.176946 | rockylinux-9 | changed
2026-05-21 02:32:56.249966 | 
2026-05-21 02:32:56.250299 | TASK [Switch "ansible_host" to private IP]
2026-05-21 02:32:56.736238 | rockylinux-9 | changed: 1 replacements made
2026-05-21 02:32:56.758640 | 
2026-05-21 02:32:56.758801 | TASK [Run Molecule scenario]
2026-05-21 02:32:56.782775 | [rockylinux-9] Waiting on logger
2026-05-21 02:33:03.913971 | [rockylinux-9] Waiting on logger
2026-05-21 02:33:14.122175 | [rockylinux-9] Waiting on logger
2026-05-21 02:33:24.294146 | [rockylinux-9] Waiting on logger
2026-05-21 02:33:34.469960 | [rockylinux-9] Waiting on logger
2026-05-21 02:33:44.645907 | [rockylinux-9] Waiting on logger
2026-05-21 02:33:53.797955 | [rockylinux-9] Waiting on logger
2026-05-21 02:34:03.974370 | [rockylinux-9] Waiting on logger
2026-05-21 02:34:14.150060 | [rockylinux-9] Waiting on logger
2026-05-21 02:34:24.329912 | [rockylinux-9] Waiting on logger
2026-05-21 02:34:34.502278 | [rockylinux-9] Waiting on logger
2026-05-21 02:34:43.659647 | [rockylinux-9] Waiting on logger
2026-05-21 02:34:53.830402 | [rockylinux-9] Waiting on logger
2026-05-21 02:35:04.006391 | [rockylinux-9] Waiting on logger
2026-05-21 02:35:14.186383 | [rockylinux-9] Waiting on logger
2026-05-21 02:35:24.361989 | [rockylinux-9] Waiting on logger
2026-05-21 02:35:34.534052 | [rockylinux-9] Waiting on logger
2026-05-21 02:35:43.691775 | [rockylinux-9] Waiting on logger
2026-05-21 02:35:53.863329 | [rockylinux-9] Waiting on logger
2026-05-21 02:36:04.102012 | [rockylinux-9] Waiting on logger
2026-05-21 02:36:13.766160 | [rockylinux-9] Waiting on logger
2026-05-21 02:36:23.942074 | [rockylinux-9] Waiting on logger
2026-05-21 02:36:34.118051 | [rockylinux-9] Waiting on logger
2026-05-21 02:36:44.518224 | [rockylinux-9] Waiting on logger
2026-05-21 02:36:53.675682 | [rockylinux-9] Waiting on logger
2026-05-21 02:37:03.882030 | [rockylinux-9] Waiting on logger
2026-05-21 02:37:14.054536 | [rockylinux-9] Waiting on logger
2026-05-21 02:37:24.229919 | [rockylinux-9] Waiting on logger
2026-05-21 02:37:32.175064 | rockylinux-9 | ok: Runtime: 0:04:34.546065
2026-05-21 02:37:32.181756 | 
2026-05-21 02:37:32.181819 | PLAY RECAP
2026-05-21 02:37:32.181872 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-05-21 02:37:32.181896 | 
2026-05-21 02:37:32.345597 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-05-21 02:37:32.348419 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-05-21 02:37:32.970854 | 
2026-05-21 02:37:32.971204 | PLAY [all]
2026-05-21 02:37:32.982623 | 
2026-05-21 02:37:32.982727 | TASK [fetch-output : Set log path for multiple nodes]
2026-05-21 02:37:33.028921 | rockylinux-9 | skipping: Conditional result was False
2026-05-21 02:37:33.037965 | 
2026-05-21 02:37:33.038082 | TASK [fetch-output : Set log path for single node]
2026-05-21 02:37:33.081538 | rockylinux-9 | ok
2026-05-21 02:37:33.087601 | 
2026-05-21 02:37:33.087681 | LOOP [fetch-output : Ensure local output dirs]
2026-05-21 02:37:33.478738 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/008ca74409f8418c8a35848265c0d3f1/work/logs"
2026-05-21 02:37:33.689946 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/008ca74409f8418c8a35848265c0d3f1/work/artifacts"
2026-05-21 02:37:33.889139 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/008ca74409f8418c8a35848265c0d3f1/work/docs"
2026-05-21 02:37:33.903803 | 
2026-05-21 02:37:33.903985 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-05-21 02:37:34.582110 | rockylinux-9 | changed: .d..t...... ./
2026-05-21 02:37:34.582343 | rockylinux-9 | changed: All items complete
2026-05-21 02:37:34.582380 | 
2026-05-21 02:37:35.084930 | rockylinux-9 | changed: .d..t...... ./
2026-05-21 02:37:35.580726 | rockylinux-9 | changed: .d..t...... ./
2026-05-21 02:37:35.598572 | 
2026-05-21 02:37:35.598690 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-05-21 02:37:35.998265 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.007496
2026-05-21 02:37:36.240183 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.009178
2026-05-21 02:37:36.255431 | 
2026-05-21 02:37:36.255643 | PLAY [all]
2026-05-21 02:37:36.268916 | 
2026-05-21 02:37:36.269033 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-05-21 02:37:36.728449 | rockylinux-9 | changed
2026-05-21 02:37:36.733499 | 
2026-05-21 02:37:36.733548 | PLAY RECAP
2026-05-21 02:37:36.733593 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-05-21 02:37:36.733614 | 
2026-05-21 02:37:36.836251 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-05-21 02:37:36.838561 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-05-21 02:37:37.455548 | 
2026-05-21 02:37:37.455724 | PLAY [localhost]
2026-05-21 02:37:37.467674 | 
2026-05-21 02:37:37.467783 | TASK [Generate Zuul manifest]
2026-05-21 02:37:37.487179 | localhost | ok
2026-05-21 02:37:37.502798 | 
2026-05-21 02:37:37.502947 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-05-21 02:37:37.892024 | localhost | changed
2026-05-21 02:37:37.904041 | 
2026-05-21 02:37:37.904229 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-05-21 02:37:37.938997 | localhost | ok
2026-05-21 02:37:37.947694 | 
2026-05-21 02:37:37.947848 | TASK [Upload logs]
2026-05-21 02:37:37.972261 | localhost | ok
2026-05-21 02:37:38.032309 | 
2026-05-21 02:37:38.032513 | TASK [Set zuul-log-path fact]
2026-05-21 02:37:38.056253 | localhost | ok
2026-05-21 02:37:38.075269 | 
2026-05-21 02:37:38.075456 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-05-21 02:37:38.108383 | localhost | ok
2026-05-21 02:37:38.115715 | 
2026-05-21 02:37:38.115788 | TASK [upload-logs : Create log directories]
2026-05-21 02:37:38.491480 | localhost | changed
2026-05-21 02:37:38.498885 | 
2026-05-21 02:37:38.498987 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-05-21 02:37:38.907640 | localhost -> localhost | ok: Runtime: 0:00:00.017552
2026-05-21 02:37:38.914725 | 
2026-05-21 02:37:38.914841 | TASK [upload-logs : Upload logs to log server]
2026-05-21 02:37:39.394036 | localhost | Output suppressed because no_log was given
2026-05-21 02:37:39.400077 | 
2026-05-21 02:37:39.400149 | LOOP [upload-logs : Compress console log and json output]
2026-05-21 02:37:39.446748 | localhost | skipping: Conditional result was False
2026-05-21 02:37:39.454514 | localhost | skipping: Conditional result was False
2026-05-21 02:37:39.466362 | 
2026-05-21 02:37:39.466582 | LOOP [upload-logs : Upload compressed console log and json output]
2026-05-21 02:37:39.510935 | localhost | skipping: Conditional result was False
2026-05-21 02:37:39.511333 | 
2026-05-21 02:37:39.515069 | localhost | skipping: Conditional result was False
2026-05-21 02:37:39.534570 | 
2026-05-21 02:37:39.534776 | LOOP [upload-logs : Upload console log and json output]