2026-05-21 02:30:38.309872 | Job console starting 2026-05-21 02:30:38.320010 | Updating git repos 2026-05-21 02:30:38.351691 | Cloning repos into workspace 2026-05-21 02:30:44.820113 | Restoring repo states 2026-05-21 02:30:44.865293 | Merging changes 2026-05-21 02:30:47.036737 | Checking out repos 2026-05-21 02:30:47.852250 | Preparing playbooks 2026-05-21 02:31:18.352983 | Running Ansible setup 2026-05-21 02:31:22.606452 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-05-21 02:31:23.345083 | 2026-05-21 02:31:23.345354 | PLAY [localhost] 2026-05-21 02:31:23.353149 | 2026-05-21 02:31:23.353228 | TASK [Gathering Facts] 2026-05-21 02:31:24.365295 | localhost | ok 2026-05-21 02:31:24.376072 | 2026-05-21 02:31:24.376151 | TASK [Setup log path fact] 2026-05-21 02:31:24.395770 | localhost | ok 2026-05-21 02:31:24.406305 | 2026-05-21 02:31:24.406370 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-21 02:31:24.436476 | localhost | ok 2026-05-21 02:31:24.442489 | 2026-05-21 02:31:24.442558 | TASK [emit-job-header : Print job information] 2026-05-21 02:31:24.496165 | # Job Information 2026-05-21 02:31:24.496379 | Ansible Version: 2.16.18 2026-05-21 02:31:24.496448 | Job: ansible-collection-containers-molecule-containerd-rockylinux-9 2026-05-21 02:31:24.496496 | Pipeline: check 2026-05-21 02:31:24.496539 | Executor: 2d72f0692154 2026-05-21 02:31:24.496699 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/129 2026-05-21 02:31:24.496800 | Event ID: f2afac20-54bc-11f1-906a-0dc10c943ad7 2026-05-21 02:31:24.499724 | 2026-05-21 02:31:24.499787 | LOOP [emit-job-header : Print node information] 2026-05-21 02:31:24.594340 | localhost | ok: 2026-05-21 02:31:24.594500 | localhost | # Node Information 2026-05-21 02:31:24.594528 | localhost | Inventory Hostname: rockylinux-9 2026-05-21 02:31:24.594549 | localhost | Hostname: np0000175644 2026-05-21 02:31:24.594569 | localhost | Username: zuul 2026-05-21 02:31:24.594591 | localhost | Distro: Rocky 9.7 2026-05-21 02:31:24.594611 | localhost | Provider: yul1 2026-05-21 02:31:24.594630 | localhost | Region: ca-ymq-1 2026-05-21 02:31:24.594648 | localhost | Label: rockylinux-9 2026-05-21 02:31:24.594666 | localhost | Product Name: OpenStack Nova 2026-05-21 02:31:24.594684 | localhost | Interface IP: 199.204.45.240 2026-05-21 02:31:24.606781 | 2026-05-21 02:31:24.606900 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-21 02:31:25.101012 | localhost -> localhost | changed 2026-05-21 02:31:25.106267 | 2026-05-21 02:31:25.106347 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-21 02:31:26.343885 | localhost -> localhost | changed 2026-05-21 02:31:26.351325 | 2026-05-21 02:31:26.351399 | PLAY [all] 2026-05-21 02:31:26.362501 | 2026-05-21 02:31:26.362596 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-21 02:31:26.612320 | rockylinux-9 -> localhost | ok 2026-05-21 02:31:26.621022 | 2026-05-21 02:31:26.621225 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-21 02:31:26.655040 | rockylinux-9 | ok 2026-05-21 02:31:26.843620 | rockylinux-9 | included: /var/lib/zuul/builds/8f4e4b28484e466386568320c07a169a/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-21 02:31:26.850688 | 2026-05-21 02:31:26.850764 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-21 02:31:29.001874 | rockylinux-9 -> localhost | Generating public/private rsa key pair. 2026-05-21 02:31:29.002102 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/8f4e4b28484e466386568320c07a169a/work/8f4e4b28484e466386568320c07a169a_id_rsa 2026-05-21 02:31:29.002152 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/8f4e4b28484e466386568320c07a169a/work/8f4e4b28484e466386568320c07a169a_id_rsa.pub 2026-05-21 02:31:29.002191 | rockylinux-9 -> localhost | The key fingerprint is: 2026-05-21 02:31:29.002228 | rockylinux-9 -> localhost | SHA256:lB8yWcGeMRRuqR/iHs1XVVsc87NOGvX+fge6vOuLXRc zuul-build-sshkey 2026-05-21 02:31:29.002282 | rockylinux-9 -> localhost | The key's randomart image is: 2026-05-21 02:31:29.002317 | rockylinux-9 -> localhost | +---[RSA 3072]----+ 2026-05-21 02:31:29.002360 | rockylinux-9 -> localhost | | o=o ++| 2026-05-21 02:31:29.002394 | rockylinux-9 -> localhost | | =+. B| 2026-05-21 02:31:29.002426 | rockylinux-9 -> localhost | | *.=+ +o| 2026-05-21 02:31:29.002459 | rockylinux-9 -> localhost | | . *o. ..+| 2026-05-21 02:31:29.002492 | rockylinux-9 -> localhost | | S o ..E.| 2026-05-21 02:31:29.002524 | rockylinux-9 -> localhost | | . = . .*..| 2026-05-21 02:31:29.002556 | rockylinux-9 -> localhost | | o + .o.oo| 2026-05-21 02:31:29.002588 | rockylinux-9 -> localhost | | . . =.. .+| 2026-05-21 02:31:29.002625 | rockylinux-9 -> localhost | | . ..O= .=| 2026-05-21 02:31:29.002658 | rockylinux-9 -> localhost | +----[SHA256]-----+ 2026-05-21 02:31:29.002737 | rockylinux-9 -> localhost | ok: Runtime: 0:00:01.743076 2026-05-21 02:31:29.008426 | 2026-05-21 02:31:29.008490 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-21 02:31:29.037707 | rockylinux-9 | ok 2026-05-21 02:31:29.045757 | rockylinux-9 | included: /var/lib/zuul/builds/8f4e4b28484e466386568320c07a169a/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-21 02:31:29.057256 | 2026-05-21 02:31:29.057330 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-21 02:31:29.072386 | rockylinux-9 | skipping: Conditional result was False 2026-05-21 02:31:29.078519 | 2026-05-21 02:31:29.078589 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-21 02:31:29.663913 | rockylinux-9 | changed 2026-05-21 02:31:29.674413 | 2026-05-21 02:31:29.674650 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-21 02:31:29.915137 | rockylinux-9 | ok 2026-05-21 02:31:29.919748 | 2026-05-21 02:31:29.919819 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-21 02:31:30.800447 | rockylinux-9 | changed 2026-05-21 02:31:31.512112 | 2026-05-21 02:31:31.512210 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-21 02:31:32.448712 | rockylinux-9 | changed 2026-05-21 02:31:32.459336 | 2026-05-21 02:31:32.459404 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-21 02:31:32.490899 | rockylinux-9 | skipping: Conditional result was False 2026-05-21 02:31:32.496391 | 2026-05-21 02:31:32.496459 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-21 02:31:33.127904 | rockylinux-9 -> localhost | changed 2026-05-21 02:31:33.154782 | 2026-05-21 02:31:33.155009 | TASK [add-build-sshkey : Add back temp key] 2026-05-21 02:31:33.479078 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/8f4e4b28484e466386568320c07a169a/work/8f4e4b28484e466386568320c07a169a_id_rsa (zuul-build-sshkey) 2026-05-21 02:31:33.479306 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.021220 2026-05-21 02:31:33.486457 | 2026-05-21 02:31:33.486544 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-21 02:31:33.933098 | rockylinux-9 | ok 2026-05-21 02:31:33.937324 | 2026-05-21 02:31:33.937387 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-21 02:31:33.961680 | rockylinux-9 | skipping: Conditional result was False 2026-05-21 02:31:33.970136 | 2026-05-21 02:31:33.970200 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-05-21 02:31:34.398123 | rockylinux-9 | ok 2026-05-21 02:31:34.405349 | 2026-05-21 02:31:34.405441 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-05-21 02:31:35.425137 | rockylinux-9 | Output suppressed because no_log was given 2026-05-21 02:31:35.440340 | 2026-05-21 02:31:35.440501 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-05-21 02:31:35.705874 | rockylinux-9 | ok: "logs" 2026-05-21 02:31:35.706481 | rockylinux-9 | ok: All items complete 2026-05-21 02:31:35.706535 | 2026-05-21 02:31:35.936741 | rockylinux-9 | ok: "artifacts" 2026-05-21 02:31:36.163258 | rockylinux-9 | ok: "docs" 2026-05-21 02:31:36.174391 | 2026-05-21 02:31:36.174547 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-05-21 02:31:36.449791 | rockylinux-9 | changed: "logs" 2026-05-21 02:31:36.718588 | rockylinux-9 | changed: "artifacts" 2026-05-21 02:31:36.896354 | rockylinux-9 | changed: "docs" 2026-05-21 02:31:36.910862 | 2026-05-21 02:31:36.911004 | PLAY RECAP 2026-05-21 02:31:36.911052 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-21 02:31:36.911084 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-05-21 02:31:36.911108 | 2026-05-21 02:31:37.099154 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-05-21 02:31:37.101134 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-05-21 02:31:37.778994 | 2026-05-21 02:31:38.077113 | PLAY [all] 2026-05-21 02:31:38.091446 | 2026-05-21 02:31:38.091549 | TASK [setup-uv : Extract archive] 2026-05-21 02:31:40.928748 | rockylinux-9 | changed 2026-05-21 02:31:41.320319 | 2026-05-21 02:31:41.320441 | TASK [setup-uv : Print version] 2026-05-21 02:31:41.368194 | [rockylinux-9] Waiting on logger 2026-05-21 02:31:41.909205 | rockylinux-9 | ok: Runtime: 0:00:00.018984 2026-05-21 02:31:41.923621 | 2026-05-21 02:31:41.923812 | TASK [Install Ansible collection siblings] 2026-05-21 02:31:42.417301 | rockylinux-9 | ok: 2026-05-21 02:31:42.417487 | rockylinux-9 | Skipping ansible.posix: no checked-out sibling 2026-05-21 02:31:42.417549 | rockylinux-9 | Skipping community.general: no checked-out sibling 2026-05-21 02:31:42.422094 | 2026-05-21 02:31:42.422189 | PLAY RECAP 2026-05-21 02:31:42.422263 | rockylinux-9 | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-21 02:31:42.422304 | 2026-05-21 02:31:42.555225 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-05-21 02:31:42.557719 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-05-21 02:31:43.182533 | 2026-05-21 02:31:43.182660 | PLAY [all] 2026-05-21 02:31:43.193937 | 2026-05-21 02:31:43.194050 | TASK [Copy inventory file for Zuul] 2026-05-21 02:31:44.662980 | rockylinux-9 | changed 2026-05-21 02:31:44.667866 | 2026-05-21 02:31:44.667959 | TASK [Switch "ansible_host" to private IP] 2026-05-21 02:31:45.063282 | rockylinux-9 | changed: 1 replacements made 2026-05-21 02:31:45.070111 | 2026-05-21 02:31:45.070200 | TASK [Run Molecule scenario] 2026-05-21 02:31:45.094679 | [rockylinux-9] Waiting on logger 2026-05-21 02:31:53.226098 | [rockylinux-9] Waiting on logger 2026-05-21 02:32:02.950863 | [rockylinux-9] Waiting on logger 2026-05-21 02:32:13.126593 | [rockylinux-9] Waiting on logger 2026-05-21 02:32:23.306313 | [rockylinux-9] Waiting on logger 2026-05-21 02:32:32.459424 | [rockylinux-9] Waiting on logger 2026-05-21 02:32:42.630243 | [rockylinux-9] Waiting on logger 2026-05-21 02:32:52.809920 | [rockylinux-9] Waiting on logger 2026-05-21 02:33:03.013951 | [rockylinux-9] Waiting on logger 2026-05-21 02:33:13.194305 | [rockylinux-9] Waiting on logger 2026-05-21 02:33:23.369929 | [rockylinux-9] Waiting on logger 2026-05-21 02:33:32.613997 | [rockylinux-9] Waiting on logger 2026-05-21 02:33:42.794526 | [rockylinux-9] Waiting on logger 2026-05-21 02:33:52.966128 | [rockylinux-9] Waiting on logger 2026-05-21 02:34:03.142009 | [rockylinux-9] Waiting on logger 2026-05-21 02:34:13.318349 | [rockylinux-9] Waiting on logger 2026-05-21 02:34:22.479380 | [rockylinux-9] Waiting on logger 2026-05-21 02:34:32.645965 | [rockylinux-9] Waiting on logger 2026-05-21 02:34:42.822321 | [rockylinux-9] Waiting on logger 2026-05-21 02:34:52.998260 | [rockylinux-9] Waiting on logger 2026-05-21 02:35:03.174199 | [rockylinux-9] Waiting on logger 2026-05-21 02:35:13.350296 | [rockylinux-9] Waiting on logger 2026-05-21 02:35:22.502169 | [rockylinux-9] Waiting on logger 2026-05-21 02:35:32.682360 | [rockylinux-9] Waiting on logger 2026-05-21 02:35:42.854305 | [rockylinux-9] Waiting on logger 2026-05-21 02:35:53.034145 | [rockylinux-9] Waiting on logger 2026-05-21 02:36:03.206486 | [rockylinux-9] Waiting on logger 2026-05-21 02:36:13.382035 | [rockylinux-9] Waiting on logger 2026-05-21 02:36:22.534247 | [rockylinux-9] Waiting on logger 2026-05-21 02:36:29.259818 | rockylinux-9 | ok: Runtime: 0:04:43.008954 2026-05-21 02:36:29.268088 | 2026-05-21 02:36:29.268177 | PLAY RECAP 2026-05-21 02:36:29.268224 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-21 02:36:29.268247 | 2026-05-21 02:36:29.385975 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-05-21 02:36:29.388368 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-05-21 02:36:30.014717 | 2026-05-21 02:36:30.021280 | PLAY [all] 2026-05-21 02:36:30.033931 | 2026-05-21 02:36:30.034005 | TASK [fetch-output : Set log path for multiple nodes] 2026-05-21 02:36:30.078838 | rockylinux-9 | skipping: Conditional result was False 2026-05-21 02:36:30.086289 | 2026-05-21 02:36:30.086411 | TASK [fetch-output : Set log path for single node] 2026-05-21 02:36:30.129465 | rockylinux-9 | ok 2026-05-21 02:36:30.134013 | 2026-05-21 02:36:30.134085 | LOOP [fetch-output : Ensure local output dirs] 2026-05-21 02:36:30.511317 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/8f4e4b28484e466386568320c07a169a/work/logs" 2026-05-21 02:36:30.811990 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/8f4e4b28484e466386568320c07a169a/work/artifacts" 2026-05-21 02:36:31.002524 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/8f4e4b28484e466386568320c07a169a/work/docs" 2026-05-21 02:36:31.024236 | 2026-05-21 02:36:31.024347 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-21 02:36:31.702748 | rockylinux-9 | changed: .d..t...... ./ 2026-05-21 02:36:31.702988 | rockylinux-9 | changed: All items complete 2026-05-21 02:36:31.703027 | 2026-05-21 02:36:32.251130 | rockylinux-9 | changed: .d..t...... ./ 2026-05-21 02:36:32.771489 | rockylinux-9 | changed: .d..t...... ./ 2026-05-21 02:36:32.790168 | 2026-05-21 02:36:32.790289 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-21 02:36:33.228187 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.007767 2026-05-21 02:36:33.438859 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.005189 2026-05-21 02:36:33.453380 | 2026-05-21 02:36:33.453480 | PLAY [all] 2026-05-21 02:36:33.458970 | 2026-05-21 02:36:33.459119 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-21 02:36:33.946915 | rockylinux-9 | changed 2026-05-21 02:36:33.954562 | 2026-05-21 02:36:33.954637 | PLAY RECAP 2026-05-21 02:36:33.954726 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-05-21 02:36:33.954781 | 2026-05-21 02:36:34.072525 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-05-21 02:36:34.340652 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-21 02:36:34.931447 | 2026-05-21 02:36:34.931599 | PLAY [localhost] 2026-05-21 02:36:34.943307 | 2026-05-21 02:36:34.943412 | TASK [Generate Zuul manifest] 2026-05-21 02:36:34.966279 | localhost | ok 2026-05-21 02:36:34.982671 | 2026-05-21 02:36:34.982770 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-21 02:36:35.327585 | localhost | changed 2026-05-21 02:36:35.339122 | 2026-05-21 02:36:35.339193 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-21 02:36:35.855932 | localhost | ok 2026-05-21 02:36:35.862476 | 2026-05-21 02:36:35.862539 | TASK [Upload logs] 2026-05-21 02:36:35.883094 | localhost | ok 2026-05-21 02:36:35.940468 | 2026-05-21 02:36:35.940584 | TASK [Set zuul-log-path fact] 2026-05-21 02:36:35.962632 | localhost | ok 2026-05-21 02:36:35.978566 | 2026-05-21 02:36:35.978632 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-21 02:36:36.011413 | localhost | ok 2026-05-21 02:36:36.021199 | 2026-05-21 02:36:36.021287 | TASK [upload-logs : Create log directories] 2026-05-21 02:36:36.437850 | localhost | changed 2026-05-21 02:36:36.445384 | 2026-05-21 02:36:36.445475 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-21 02:36:36.829376 | localhost -> localhost | ok: Runtime: 0:00:00.005128 2026-05-21 02:36:36.836489 | 2026-05-21 02:36:36.836580 | TASK [upload-logs : Upload logs to log server] 2026-05-21 02:36:37.284933 | localhost | Output suppressed because no_log was given 2026-05-21 02:36:37.288908 | 2026-05-21 02:36:37.288993 | LOOP [upload-logs : Compress console log and json output] 2026-05-21 02:36:37.331784 | localhost | skipping: Conditional result was False 2026-05-21 02:36:37.339038 | localhost | skipping: Conditional result was False 2026-05-21 02:36:37.352731 | 2026-05-21 02:36:37.353025 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-21 02:36:37.394543 | localhost | skipping: Conditional result was False 2026-05-21 02:36:37.394879 | 2026-05-21 02:36:37.399001 | localhost | skipping: Conditional result was False 2026-05-21 02:36:37.409459 | 2026-05-21 02:36:37.409641 | LOOP [upload-logs : Upload console log and json output]