2026-05-01 01:10:07.903036 | Job console starting 2026-05-01 01:10:07.939968 | Updating git repos 2026-05-01 01:10:08.005050 | Cloning repos into workspace 2026-05-01 01:10:08.064607 | Restoring repo states 2026-05-01 01:10:08.072045 | Merging changes 2026-05-01 01:10:08.645755 | Checking out repos 2026-05-01 01:10:08.754671 | Preparing playbooks 2026-05-01 01:10:10.766988 | Running Ansible setup 2026-05-01 01:10:14.957597 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-05-01 01:10:15.578144 | 2026-05-01 01:10:15.578254 | PLAY [localhost] 2026-05-01 01:10:15.595784 | 2026-05-01 01:10:15.595894 | TASK [Gathering Facts] 2026-05-01 01:10:16.695395 | localhost | ok 2026-05-01 01:10:16.702359 | 2026-05-01 01:10:16.702437 | TASK [Setup log path fact] 2026-05-01 01:10:16.743124 | localhost | ok 2026-05-01 01:10:16.754551 | 2026-05-01 01:10:16.754621 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-01 01:10:16.795233 | localhost | ok 2026-05-01 01:10:16.805009 | 2026-05-01 01:10:16.805098 | TASK [emit-job-header : Print job information] 2026-05-01 01:10:16.846518 | # Job Information 2026-05-01 01:10:16.846684 | Ansible Version: 2.16.18 2026-05-01 01:10:16.846729 | Job: ansible-collection-containers-molecule-containerd-rockylinux-9 2026-05-01 01:10:16.846761 | Pipeline: check 2026-05-01 01:10:16.846789 | Executor: 2d72f0692154 2026-05-01 01:10:16.846817 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/127 2026-05-01 01:10:16.846848 | Event ID: 5d95fee0-44fa-11f1-996a-0310ba4962fe 2026-05-01 01:10:16.850358 | 2026-05-01 01:10:16.850423 | LOOP [emit-job-header : Print node information] 2026-05-01 01:10:16.935210 | localhost | ok: 2026-05-01 01:10:16.935368 | localhost | # Node Information 2026-05-01 01:10:16.935396 | localhost | Inventory Hostname: rockylinux-9 2026-05-01 01:10:16.935418 | localhost | Hostname: np0000171413 2026-05-01 01:10:16.935438 | localhost | Username: zuul 2026-05-01 01:10:16.935460 | localhost | Distro: Rocky 9.7 2026-05-01 01:10:16.935479 | localhost | Provider: yul1 2026-05-01 01:10:16.935497 | localhost | Region: ca-ymq-1 2026-05-01 01:10:16.935516 | localhost | Label: rockylinux-9 2026-05-01 01:10:16.935534 | localhost | Product Name: OpenStack Nova 2026-05-01 01:10:16.935552 | localhost | Interface IP: 199.204.45.109 2026-05-01 01:10:16.950017 | 2026-05-01 01:10:16.950132 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-01 01:10:17.422412 | localhost -> localhost | changed 2026-05-01 01:10:17.429837 | 2026-05-01 01:10:17.429911 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-01 01:10:18.555081 | localhost -> localhost | changed 2026-05-01 01:10:18.562289 | 2026-05-01 01:10:18.562386 | PLAY [all] 2026-05-01 01:10:18.576108 | 2026-05-01 01:10:18.576234 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-01 01:10:18.845992 | rockylinux-9 -> localhost | ok 2026-05-01 01:10:18.853943 | 2026-05-01 01:10:18.907177 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-01 01:10:18.939576 | rockylinux-9 | ok 2026-05-01 01:10:18.955374 | rockylinux-9 | included: /var/lib/zuul/builds/585bd0af2b634f44b4557d7a888caa08/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-01 01:10:18.963141 | 2026-05-01 01:10:18.963219 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-01 01:10:20.520639 | rockylinux-9 -> localhost | Generating public/private rsa key pair. 2026-05-01 01:10:20.520830 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/585bd0af2b634f44b4557d7a888caa08/work/585bd0af2b634f44b4557d7a888caa08_id_rsa 2026-05-01 01:10:20.520874 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/585bd0af2b634f44b4557d7a888caa08/work/585bd0af2b634f44b4557d7a888caa08_id_rsa.pub 2026-05-01 01:10:20.520908 | rockylinux-9 -> localhost | The key fingerprint is: 2026-05-01 01:10:20.520941 | rockylinux-9 -> localhost | SHA256:1XpWaizVoBHCeCzMooAp4GhcFixhqADQHY5FBg88/20 zuul-build-sshkey 2026-05-01 01:10:20.521007 | rockylinux-9 -> localhost | The key's randomart image is: 2026-05-01 01:10:20.521039 | rockylinux-9 -> localhost | +---[RSA 3072]----+ 2026-05-01 01:10:20.521075 | rockylinux-9 -> localhost | |X*=B*+ +. o.. | 2026-05-01 01:10:20.521110 | rockylinux-9 -> localhost | |%.*Oo = +. + o | 2026-05-01 01:10:20.521142 | rockylinux-9 -> localhost | |==o+o. o o o o | 2026-05-01 01:10:20.521182 | rockylinux-9 -> localhost | |o . . . + o | 2026-05-01 01:10:20.521213 | rockylinux-9 -> localhost | | . .S o * | 2026-05-01 01:10:20.521240 | rockylinux-9 -> localhost | | . E = | 2026-05-01 01:10:20.521268 | rockylinux-9 -> localhost | | . | 2026-05-01 01:10:20.521297 | rockylinux-9 -> localhost | | | 2026-05-01 01:10:20.521331 | rockylinux-9 -> localhost | | | 2026-05-01 01:10:20.521362 | rockylinux-9 -> localhost | +----[SHA256]-----+ 2026-05-01 01:10:20.521427 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.834611 2026-05-01 01:10:20.532753 | 2026-05-01 01:10:20.532845 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-01 01:10:20.553021 | rockylinux-9 | ok 2026-05-01 01:10:20.565787 | rockylinux-9 | included: /var/lib/zuul/builds/585bd0af2b634f44b4557d7a888caa08/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-01 01:10:20.577883 | 2026-05-01 01:10:20.577974 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-01 01:10:20.603243 | rockylinux-9 | skipping: Conditional result was False 2026-05-01 01:10:20.608142 | 2026-05-01 01:10:20.608219 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-01 01:10:21.086626 | rockylinux-9 | changed 2026-05-01 01:10:21.092693 | 2026-05-01 01:10:21.092779 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-01 01:10:21.340058 | rockylinux-9 | ok 2026-05-01 01:10:21.345127 | 2026-05-01 01:10:21.345201 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-01 01:10:22.303085 | rockylinux-9 | changed 2026-05-01 01:10:22.311077 | 2026-05-01 01:10:22.311205 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-01 01:10:23.191243 | rockylinux-9 | changed 2026-05-01 01:10:23.196750 | 2026-05-01 01:10:23.196840 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-01 01:10:23.221439 | rockylinux-9 | skipping: Conditional result was False 2026-05-01 01:10:23.231600 | 2026-05-01 01:10:23.231694 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-01 01:10:23.641648 | rockylinux-9 -> localhost | changed 2026-05-01 01:10:23.650377 | 2026-05-01 01:10:23.650443 | TASK [add-build-sshkey : Add back temp key] 2026-05-01 01:10:24.031480 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/585bd0af2b634f44b4557d7a888caa08/work/585bd0af2b634f44b4557d7a888caa08_id_rsa (zuul-build-sshkey) 2026-05-01 01:10:24.031650 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.008197 2026-05-01 01:10:24.035975 | 2026-05-01 01:10:24.036034 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-01 01:10:24.418207 | rockylinux-9 | ok 2026-05-01 01:10:24.424545 | 2026-05-01 01:10:24.424679 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-01 01:10:24.448927 | rockylinux-9 | skipping: Conditional result was False 2026-05-01 01:10:24.465968 | 2026-05-01 01:10:24.466315 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-05-01 01:10:24.849706 | rockylinux-9 | ok 2026-05-01 01:10:24.857240 | 2026-05-01 01:10:24.857335 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-05-01 01:10:25.801902 | rockylinux-9 | Output suppressed because no_log was given 2026-05-01 01:10:25.814539 | 2026-05-01 01:10:25.814605 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-05-01 01:10:26.069206 | rockylinux-9 | ok: "logs" 2026-05-01 01:10:26.069424 | rockylinux-9 | ok: All items complete 2026-05-01 01:10:26.069452 | 2026-05-01 01:10:26.276907 | rockylinux-9 | ok: "artifacts" 2026-05-01 01:10:26.488774 | rockylinux-9 | ok: "docs" 2026-05-01 01:10:26.496774 | 2026-05-01 01:10:26.496895 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-05-01 01:10:26.739526 | rockylinux-9 | changed: "logs" 2026-05-01 01:10:26.964869 | rockylinux-9 | changed: "artifacts" 2026-05-01 01:10:27.210696 | rockylinux-9 | changed: "docs" 2026-05-01 01:10:27.228411 | 2026-05-01 01:10:27.228500 | PLAY RECAP 2026-05-01 01:10:27.228543 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-01 01:10:27.228572 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-05-01 01:10:27.228593 | 2026-05-01 01:10:27.352744 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-05-01 01:10:27.354170 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-05-01 01:10:27.949271 | 2026-05-01 01:10:27.949411 | PLAY [all] 2026-05-01 01:10:27.962019 | 2026-05-01 01:10:27.962163 | TASK [setup-uv : Extract archive] 2026-05-01 01:10:30.742036 | rockylinux-9 | changed 2026-05-01 01:10:30.757976 | 2026-05-01 01:10:30.758060 | TASK [setup-uv : Print version] 2026-05-01 01:10:30.786803 | [rockylinux-9] Waiting on logger 2026-05-01 01:10:31.298815 | rockylinux-9 | ok: Runtime: 0:00:00.019446 2026-05-01 01:10:31.304850 | 2026-05-01 01:10:31.304913 | PLAY RECAP 2026-05-01 01:10:31.304984 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-01 01:10:31.305012 | 2026-05-01 01:10:31.488729 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-05-01 01:10:31.489832 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-05-01 01:10:32.052348 | 2026-05-01 01:10:32.052649 | PLAY [all] 2026-05-01 01:10:32.064271 | 2026-05-01 01:10:32.064415 | TASK [Copy inventory file for Zuul] 2026-05-01 01:10:33.403235 | rockylinux-9 | changed 2026-05-01 01:10:33.408895 | 2026-05-01 01:10:33.409004 | TASK [Switch "ansible_host" to private IP] 2026-05-01 01:10:33.796861 | rockylinux-9 | changed: 1 replacements made 2026-05-01 01:10:33.802120 | 2026-05-01 01:10:33.802184 | TASK [Run Molecule scenario] 2026-05-01 01:10:33.831133 | [rockylinux-9] Waiting on logger 2026-05-01 01:10:41.957972 | [rockylinux-9] Waiting on logger 2026-05-01 01:10:52.134106 | [rockylinux-9] Waiting on logger 2026-05-01 01:11:02.310152 | [rockylinux-9] Waiting on logger 2026-05-01 01:11:12.486139 | [rockylinux-9] Waiting on logger 2026-05-01 01:11:22.661881 | [rockylinux-9] Waiting on logger 2026-05-01 01:11:31.819352 | [rockylinux-9] Waiting on logger 2026-05-01 01:11:41.994156 | [rockylinux-9] Waiting on logger 2026-05-01 01:11:52.165951 | [rockylinux-9] Waiting on logger 2026-05-01 01:12:02.341924 | [rockylinux-9] Waiting on logger 2026-05-01 01:12:12.517854 | [rockylinux-9] Waiting on logger 2026-05-01 01:12:22.694092 | [rockylinux-9] Waiting on logger 2026-05-01 01:12:31.851473 | [rockylinux-9] Waiting on logger 2026-05-01 01:12:42.022250 | [rockylinux-9] Waiting on logger 2026-05-01 01:12:52.198070 | [rockylinux-9] Waiting on logger 2026-05-01 01:13:02.374402 | [rockylinux-9] Waiting on logger 2026-05-01 01:13:12.550008 | [rockylinux-9] Waiting on logger 2026-05-01 01:13:22.726107 | [rockylinux-9] Waiting on logger 2026-05-01 01:13:31.883302 | [rockylinux-9] Waiting on logger 2026-05-01 01:13:42.055061 | [rockylinux-9] Waiting on logger 2026-05-01 01:13:52.229977 | [rockylinux-9] Waiting on logger 2026-05-01 01:14:02.406278 | [rockylinux-9] Waiting on logger 2026-05-01 01:14:12.582343 | [rockylinux-9] Waiting on logger 2026-05-01 01:14:22.758082 | [rockylinux-9] Waiting on logger 2026-05-01 01:14:31.910492 | [rockylinux-9] Waiting on logger 2026-05-01 01:14:42.086331 | [rockylinux-9] Waiting on logger 2026-05-01 01:14:52.262897 | [rockylinux-9] Waiting on logger 2026-05-01 01:15:02.437817 | [rockylinux-9] Waiting on logger 2026-05-01 01:15:12.614458 | [rockylinux-9] Waiting on logger 2026-05-01 01:15:22.790270 | [rockylinux-9] Waiting on logger 2026-05-01 01:15:26.254012 | rockylinux-9 | ok: Runtime: 0:04:51.376645 2026-05-01 01:15:26.261318 | 2026-05-01 01:15:26.261371 | PLAY RECAP 2026-05-01 01:15:26.261411 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-01 01:15:26.261433 | 2026-05-01 01:15:26.360685 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-05-01 01:15:26.361951 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-05-01 01:15:26.964204 | 2026-05-01 01:15:26.964607 | PLAY [all] 2026-05-01 01:15:26.976079 | 2026-05-01 01:15:26.976159 | TASK [fetch-output : Set log path for multiple nodes] 2026-05-01 01:15:27.022677 | rockylinux-9 | skipping: Conditional result was False 2026-05-01 01:15:27.032118 | 2026-05-01 01:15:27.032280 | TASK [fetch-output : Set log path for single node] 2026-05-01 01:15:27.076433 | rockylinux-9 | ok 2026-05-01 01:15:27.082641 | 2026-05-01 01:15:27.082914 | LOOP [fetch-output : Ensure local output dirs] 2026-05-01 01:15:27.452743 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/585bd0af2b634f44b4557d7a888caa08/work/logs" 2026-05-01 01:15:27.643780 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/585bd0af2b634f44b4557d7a888caa08/work/artifacts" 2026-05-01 01:15:27.844637 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/585bd0af2b634f44b4557d7a888caa08/work/docs" 2026-05-01 01:15:27.863092 | 2026-05-01 01:15:27.863298 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-01 01:15:28.545860 | rockylinux-9 | changed: .d..t...... ./ 2026-05-01 01:15:28.546089 | rockylinux-9 | changed: All items complete 2026-05-01 01:15:28.546127 | 2026-05-01 01:15:29.065034 | rockylinux-9 | changed: .d..t...... ./ 2026-05-01 01:15:29.560010 | rockylinux-9 | changed: .d..t...... ./ 2026-05-01 01:15:29.583641 | 2026-05-01 01:15:29.583763 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-01 01:15:29.961808 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.005525 2026-05-01 01:15:30.169130 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.006120 2026-05-01 01:15:30.186991 | 2026-05-01 01:15:30.187090 | PLAY [all] 2026-05-01 01:15:30.194829 | 2026-05-01 01:15:30.194902 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-01 01:15:30.650290 | rockylinux-9 | changed 2026-05-01 01:15:30.657528 | 2026-05-01 01:15:30.657589 | PLAY RECAP 2026-05-01 01:15:30.657634 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-05-01 01:15:30.657657 | 2026-05-01 01:15:30.774009 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-05-01 01:15:30.776252 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-01 01:15:31.330411 | 2026-05-01 01:15:31.330523 | PLAY [localhost] 2026-05-01 01:15:31.340911 | 2026-05-01 01:15:31.341018 | TASK [Generate Zuul manifest] 2026-05-01 01:15:31.361039 | localhost | ok 2026-05-01 01:15:31.378199 | 2026-05-01 01:15:31.378287 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-01 01:15:31.721517 | localhost | changed 2026-05-01 01:15:31.735496 | 2026-05-01 01:15:31.735578 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-01 01:15:31.764297 | localhost | ok 2026-05-01 01:15:31.774032 | 2026-05-01 01:15:31.774109 | TASK [Upload logs] 2026-05-01 01:15:31.792071 | localhost | ok 2026-05-01 01:15:31.851342 | 2026-05-01 01:15:31.851465 | TASK [Set zuul-log-path fact] 2026-05-01 01:15:31.869131 | localhost | ok 2026-05-01 01:15:31.883836 | 2026-05-01 01:15:31.883964 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-01 01:15:31.913860 | localhost | ok 2026-05-01 01:15:31.923211 | 2026-05-01 01:15:31.923302 | TASK [upload-logs : Create log directories] 2026-05-01 01:15:32.294566 | localhost | changed 2026-05-01 01:15:32.302656 | 2026-05-01 01:15:32.302752 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-01 01:15:32.670411 | localhost -> localhost | ok: Runtime: 0:00:00.003557 2026-05-01 01:15:32.676861 | 2026-05-01 01:15:32.676951 | TASK [upload-logs : Upload logs to log server] 2026-05-01 01:15:33.099753 | localhost | Output suppressed because no_log was given 2026-05-01 01:15:33.105958 | 2026-05-01 01:15:33.106026 | LOOP [upload-logs : Compress console log and json output] 2026-05-01 01:15:33.148808 | localhost | skipping: Conditional result was False 2026-05-01 01:15:33.156213 | localhost | skipping: Conditional result was False 2026-05-01 01:15:33.167879 | 2026-05-01 01:15:33.167998 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-01 01:15:33.210953 | localhost | skipping: Conditional result was False 2026-05-01 01:15:33.211360 | 2026-05-01 01:15:33.215327 | localhost | skipping: Conditional result was False 2026-05-01 01:15:33.232182 | 2026-05-01 01:15:33.232338 | LOOP [upload-logs : Upload console log and json output]