2026-06-04 17:59:57.215936 | Job console starting 2026-06-04 17:59:57.231944 | Updating git repos 2026-06-04 17:59:57.254650 | Cloning repos into workspace 2026-06-04 17:59:57.477873 | Restoring repo states 2026-06-04 17:59:57.488561 | Merging changes 2026-06-04 17:59:57.802629 | Checking out repos 2026-06-04 17:59:57.839175 | Preparing playbooks 2026-06-04 17:59:58.989254 | Running Ansible setup 2026-06-04 18:00:02.749863 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-06-04 18:00:03.374135 | 2026-06-04 18:00:03.374265 | PLAY [localhost] 2026-06-04 18:00:03.382773 | 2026-06-04 18:00:03.382848 | TASK [Gathering Facts] 2026-06-04 18:00:04.302285 | localhost | ok 2026-06-04 18:00:04.312012 | 2026-06-04 18:00:04.312137 | TASK [Setup log path fact] 2026-06-04 18:00:04.331094 | localhost | ok 2026-06-04 18:00:04.345727 | 2026-06-04 18:00:04.345840 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-04 18:00:04.377195 | localhost | ok 2026-06-04 18:00:04.385513 | 2026-06-04 18:00:04.385578 | TASK [emit-job-header : Print job information] 2026-06-04 18:00:04.426312 | # Job Information 2026-06-04 18:00:04.426481 | Ansible Version: 2.16.18 2026-06-04 18:00:04.426527 | Job: ansible-collection-containers-molecule-containerd-rockylinux-9 2026-06-04 18:00:04.426560 | Pipeline: check 2026-06-04 18:00:04.426589 | Executor: 2d72f0692154 2026-06-04 18:00:04.426617 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/134 2026-06-04 18:00:04.426649 | Event ID: f1cae1c0-603e-11f1-90c0-a491a17f1f0e 2026-06-04 18:00:04.430523 | 2026-06-04 18:00:04.430612 | LOOP [emit-job-header : Print node information] 2026-06-04 18:00:04.533905 | localhost | ok: 2026-06-04 18:00:04.534202 | localhost | # Node Information 2026-06-04 18:00:04.534268 | localhost | Inventory Hostname: rockylinux-9 2026-06-04 18:00:04.534320 | localhost | Hostname: np0000178552 2026-06-04 18:00:04.534365 | localhost | Username: zuul 2026-06-04 18:00:04.534419 | localhost | Distro: Rocky 9.7 2026-06-04 18:00:04.534465 | localhost | Provider: yul1 2026-06-04 18:00:04.534510 | localhost | Region: ca-ymq-1 2026-06-04 18:00:04.534554 | localhost | Label: rockylinux-9 2026-06-04 18:00:04.534599 | localhost | Product Name: OpenStack Nova 2026-06-04 18:00:04.534645 | localhost | Interface IP: 199.19.213.31 2026-06-04 18:00:04.551021 | 2026-06-04 18:00:04.551174 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-06-04 18:00:04.921275 | localhost -> localhost | changed 2026-06-04 18:00:04.931086 | 2026-06-04 18:00:04.931188 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-06-04 18:00:05.838799 | localhost -> localhost | changed 2026-06-04 18:00:05.851092 | 2026-06-04 18:00:05.851150 | PLAY [all] 2026-06-04 18:00:05.858455 | 2026-06-04 18:00:05.858519 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-06-04 18:00:06.085835 | rockylinux-9 -> localhost | ok 2026-06-04 18:00:06.097086 | 2026-06-04 18:00:06.097220 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-06-04 18:00:06.130350 | rockylinux-9 | ok 2026-06-04 18:00:06.144479 | rockylinux-9 | included: /var/lib/zuul/builds/f6f6c95043c94ca39e2288948cc758a5/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-06-04 18:00:06.150332 | 2026-06-04 18:00:06.150405 | TASK [add-build-sshkey : Create Temp SSH key] 2026-06-04 18:00:07.104507 | rockylinux-9 -> localhost | Generating public/private rsa key pair. 2026-06-04 18:00:07.104676 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/f6f6c95043c94ca39e2288948cc758a5/work/f6f6c95043c94ca39e2288948cc758a5_id_rsa 2026-06-04 18:00:07.104707 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/f6f6c95043c94ca39e2288948cc758a5/work/f6f6c95043c94ca39e2288948cc758a5_id_rsa.pub 2026-06-04 18:00:07.104730 | rockylinux-9 -> localhost | The key fingerprint is: 2026-06-04 18:00:07.104752 | rockylinux-9 -> localhost | SHA256:s6MX18e4GPW2JXB6lnTDrio+HpgPqMNOMqFMh53/IOI zuul-build-sshkey 2026-06-04 18:00:07.104782 | rockylinux-9 -> localhost | The key's randomart image is: 2026-06-04 18:00:07.104804 | rockylinux-9 -> localhost | +---[RSA 3072]----+ 2026-06-04 18:00:07.104832 | rockylinux-9 -> localhost | | | 2026-06-04 18:00:07.104855 | rockylinux-9 -> localhost | | | 2026-06-04 18:00:07.104875 | rockylinux-9 -> localhost | | . | 2026-06-04 18:00:07.104896 | rockylinux-9 -> localhost | | o . o oo.| 2026-06-04 18:00:07.104916 | rockylinux-9 -> localhost | | + + S o O.o.| 2026-06-04 18:00:07.104936 | rockylinux-9 -> localhost | |+ o . . +oo + X..| 2026-06-04 18:00:07.104992 | rockylinux-9 -> localhost | |.=.o + +o+ o *.+ | 2026-06-04 18:00:07.105017 | rockylinux-9 -> localhost | |. =oo o.++o ... | 2026-06-04 18:00:07.105039 | rockylinux-9 -> localhost | | E.o. .oo+o.. | 2026-06-04 18:00:07.105058 | rockylinux-9 -> localhost | +----[SHA256]-----+ 2026-06-04 18:00:07.105109 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.532640 2026-06-04 18:00:07.109764 | 2026-06-04 18:00:07.109825 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-06-04 18:00:07.143015 | rockylinux-9 | ok 2026-06-04 18:00:07.154487 | rockylinux-9 | included: /var/lib/zuul/builds/f6f6c95043c94ca39e2288948cc758a5/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-06-04 18:00:07.162337 | 2026-06-04 18:00:07.162446 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-06-04 18:00:07.187520 | rockylinux-9 | skipping: Conditional result was False 2026-06-04 18:00:07.198750 | 2026-06-04 18:00:07.198846 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-06-04 18:00:07.677765 | rockylinux-9 | changed 2026-06-04 18:00:07.684002 | 2026-06-04 18:00:07.684078 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-06-04 18:00:07.927746 | rockylinux-9 | ok 2026-06-04 18:00:07.932786 | 2026-06-04 18:00:07.932847 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-06-04 18:00:08.842628 | rockylinux-9 | changed 2026-06-04 18:00:08.849782 | 2026-06-04 18:00:08.849879 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-06-04 18:00:09.785126 | rockylinux-9 | changed 2026-06-04 18:00:09.790931 | 2026-06-04 18:00:09.791001 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-06-04 18:00:09.816383 | rockylinux-9 | skipping: Conditional result was False 2026-06-04 18:00:09.825835 | 2026-06-04 18:00:09.825938 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-06-04 18:00:10.206964 | rockylinux-9 -> localhost | changed 2026-06-04 18:00:10.224172 | 2026-06-04 18:00:10.224245 | TASK [add-build-sshkey : Add back temp key] 2026-06-04 18:00:10.524505 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/f6f6c95043c94ca39e2288948cc758a5/work/f6f6c95043c94ca39e2288948cc758a5_id_rsa (zuul-build-sshkey) 2026-06-04 18:00:10.524796 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.014952 2026-06-04 18:00:10.531981 | 2026-06-04 18:00:10.532085 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-06-04 18:00:10.914429 | rockylinux-9 | ok 2026-06-04 18:00:10.920523 | 2026-06-04 18:00:10.920624 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-06-04 18:00:10.947015 | rockylinux-9 | skipping: Conditional result was False 2026-06-04 18:00:10.961137 | 2026-06-04 18:00:10.961251 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-06-04 18:00:11.370567 | rockylinux-9 | ok 2026-06-04 18:00:11.378601 | 2026-06-04 18:00:11.378713 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-06-04 18:00:12.429762 | rockylinux-9 | Output suppressed because no_log was given 2026-06-04 18:00:12.483924 | 2026-06-04 18:00:12.484348 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-06-04 18:00:12.750031 | rockylinux-9 | ok: "logs" 2026-06-04 18:00:12.750478 | rockylinux-9 | ok: All items complete 2026-06-04 18:00:12.750531 | 2026-06-04 18:00:12.979604 | rockylinux-9 | ok: "artifacts" 2026-06-04 18:00:13.194998 | rockylinux-9 | ok: "docs" 2026-06-04 18:00:13.204804 | 2026-06-04 18:00:13.204873 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-06-04 18:00:13.482854 | rockylinux-9 | changed: "logs" 2026-06-04 18:00:13.755508 | rockylinux-9 | changed: "artifacts" 2026-06-04 18:00:13.942170 | rockylinux-9 | changed: "docs" 2026-06-04 18:00:13.952705 | 2026-06-04 18:00:13.952771 | PLAY RECAP 2026-06-04 18:00:13.952827 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-06-04 18:00:13.952867 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-06-04 18:00:13.952896 | 2026-06-04 18:00:14.070317 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-06-04 18:00:14.072389 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-06-04 18:00:14.724896 | 2026-06-04 18:00:14.725044 | PLAY [all] 2026-06-04 18:00:14.735904 | 2026-06-04 18:00:14.735989 | TASK [setup-uv : Extract archive] 2026-06-04 18:00:17.691699 | rockylinux-9 | changed 2026-06-04 18:00:17.699685 | 2026-06-04 18:00:17.699788 | TASK [setup-uv : Print version] 2026-06-04 18:00:17.723953 | [rockylinux-9] Waiting on logger 2026-06-04 18:00:18.241328 | rockylinux-9 | ok: Runtime: 0:00:00.018078 2026-06-04 18:00:18.252671 | 2026-06-04 18:00:18.252815 | TASK [Install Ansible collection siblings] 2026-06-04 18:00:18.701188 | rockylinux-9 | ok: 2026-06-04 18:00:18.701384 | rockylinux-9 | Skipping ansible.posix: no checked-out sibling 2026-06-04 18:00:18.701425 | rockylinux-9 | Skipping community.general: no checked-out sibling 2026-06-04 18:00:18.703360 | 2026-06-04 18:00:18.703421 | PLAY RECAP 2026-06-04 18:00:18.703483 | rockylinux-9 | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-06-04 18:00:18.703513 | 2026-06-04 18:00:18.823508 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-06-04 18:00:18.825952 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-06-04 18:00:19.438093 | 2026-06-04 18:00:19.438249 | PLAY [all] 2026-06-04 18:00:19.450402 | 2026-06-04 18:00:19.450490 | TASK [Copy inventory file for Zuul] 2026-06-04 18:00:20.881612 | rockylinux-9 | changed 2026-06-04 18:00:20.886597 | 2026-06-04 18:00:20.886680 | TASK [Switch "ansible_host" to private IP] 2026-06-04 18:00:21.276553 | rockylinux-9 | changed: 1 replacements made 2026-06-04 18:00:21.284002 | 2026-06-04 18:00:21.284101 | TASK [Run Molecule scenario] 2026-06-04 18:00:21.309267 | [rockylinux-9] Waiting on logger 2026-06-04 18:00:29.445992 | [rockylinux-9] Waiting on logger 2026-06-04 18:00:39.622474 | [rockylinux-9] Waiting on logger 2026-06-04 18:00:48.779909 | [rockylinux-9] Waiting on logger 2026-06-04 18:00:58.950039 | [rockylinux-9] Waiting on logger 2026-06-04 18:01:09.125823 | [rockylinux-9] Waiting on logger 2026-06-04 18:01:19.306073 | [rockylinux-9] Waiting on logger 2026-06-04 18:01:29.477917 | [rockylinux-9] Waiting on logger 2026-06-04 18:01:39.653885 | [rockylinux-9] Waiting on logger 2026-06-04 18:01:48.810782 | [rockylinux-9] Waiting on logger 2026-06-04 18:01:58.981771 | [rockylinux-9] Waiting on logger 2026-06-04 18:02:09.158224 | [rockylinux-9] Waiting on logger 2026-06-04 18:02:19.334056 | [rockylinux-9] Waiting on logger 2026-06-04 18:02:29.515045 | [rockylinux-9] Waiting on logger 2026-06-04 18:02:39.717940 | [rockylinux-9] Waiting on logger 2026-06-04 18:02:48.870226 | [rockylinux-9] Waiting on logger 2026-06-04 18:02:59.049985 | [rockylinux-9] Waiting on logger 2026-06-04 18:03:09.254137 | [rockylinux-9] Waiting on logger 2026-06-04 18:03:19.434592 | [rockylinux-9] Waiting on logger 2026-06-04 18:03:29.606167 | [rockylinux-9] Waiting on logger 2026-06-04 18:03:38.763351 | [rockylinux-9] Waiting on logger 2026-06-04 18:03:48.934100 | [rockylinux-9] Waiting on logger 2026-06-04 18:03:59.110950 | [rockylinux-9] Waiting on logger 2026-06-04 18:04:09.286195 | [rockylinux-9] Waiting on logger 2026-06-04 18:04:19.461996 | [rockylinux-9] Waiting on logger 2026-06-04 18:04:29.638488 | [rockylinux-9] Waiting on logger 2026-06-04 18:04:38.799115 | [rockylinux-9] Waiting on logger 2026-06-04 18:04:48.966173 | [rockylinux-9] Waiting on logger 2026-06-04 18:04:59.142046 | [rockylinux-9] Waiting on logger 2026-06-04 18:05:09.318218 | [rockylinux-9] Waiting on logger 2026-06-04 18:05:19.494270 | [rockylinux-9] Waiting on logger 2026-06-04 18:05:29.670756 | [rockylinux-9] Waiting on logger 2026-06-04 18:05:38.827659 | [rockylinux-9] Waiting on logger 2026-06-04 18:05:48.998516 | [rockylinux-9] Waiting on logger 2026-06-04 18:05:59.174070 | [rockylinux-9] Waiting on logger 2026-06-04 18:06:09.350104 | [rockylinux-9] Waiting on logger 2026-06-04 18:06:12.715882 | rockylinux-9 | ok: Runtime: 0:05:50.620487 2026-06-04 18:06:12.725430 | 2026-06-04 18:06:12.725524 | PLAY RECAP 2026-06-04 18:06:12.725580 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-06-04 18:06:12.725612 | 2026-06-04 18:06:12.859349 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-06-04 18:06:12.862085 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-06-04 18:06:13.446753 | 2026-06-04 18:06:13.446899 | PLAY [all] 2026-06-04 18:06:13.457467 | 2026-06-04 18:06:13.457542 | TASK [fetch-output : Set log path for multiple nodes] 2026-06-04 18:06:13.503060 | rockylinux-9 | skipping: Conditional result was False 2026-06-04 18:06:13.513331 | 2026-06-04 18:06:13.513511 | TASK [fetch-output : Set log path for single node] 2026-06-04 18:06:13.557683 | rockylinux-9 | ok 2026-06-04 18:06:13.562237 | 2026-06-04 18:06:13.562312 | LOOP [fetch-output : Ensure local output dirs] 2026-06-04 18:06:13.944619 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/f6f6c95043c94ca39e2288948cc758a5/work/logs" 2026-06-04 18:06:14.158938 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/f6f6c95043c94ca39e2288948cc758a5/work/artifacts" 2026-06-04 18:06:14.360875 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/f6f6c95043c94ca39e2288948cc758a5/work/docs" 2026-06-04 18:06:14.380850 | 2026-06-04 18:06:14.381021 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-06-04 18:06:15.123525 | rockylinux-9 | ok 2026-06-04 18:06:15.123830 | rockylinux-9 | changed: All items complete 2026-06-04 18:06:15.123869 | 2026-06-04 18:06:15.633653 | rockylinux-9 | changed: .d..t...... ./ 2026-06-04 18:06:16.157714 | rockylinux-9 | changed: .d..t...... ./ 2026-06-04 18:06:16.175524 | 2026-06-04 18:06:16.175687 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-06-04 18:06:16.580402 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.008926 2026-06-04 18:06:16.800127 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.008396 2026-06-04 18:06:16.819491 | 2026-06-04 18:06:16.819564 | PLAY [all] 2026-06-04 18:06:16.825881 | 2026-06-04 18:06:16.825941 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-06-04 18:06:17.323160 | rockylinux-9 | changed 2026-06-04 18:06:17.329452 | 2026-06-04 18:06:17.329501 | PLAY RECAP 2026-06-04 18:06:17.329547 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-06-04 18:06:17.329569 | 2026-06-04 18:06:17.450240 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-06-04 18:06:17.451439 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-06-04 18:06:18.050386 | 2026-06-04 18:06:18.050540 | PLAY [localhost] 2026-06-04 18:06:18.061833 | 2026-06-04 18:06:18.061911 | TASK [Generate Zuul manifest] 2026-06-04 18:06:18.082515 | localhost | ok 2026-06-04 18:06:18.098841 | 2026-06-04 18:06:18.098923 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-06-04 18:06:18.465812 | localhost | changed 2026-06-04 18:06:18.477459 | 2026-06-04 18:06:18.477545 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-06-04 18:06:18.511505 | localhost | ok 2026-06-04 18:06:18.518411 | 2026-06-04 18:06:18.518474 | TASK [Upload logs] 2026-06-04 18:06:18.540569 | localhost | ok 2026-06-04 18:06:18.601271 | 2026-06-04 18:06:18.601382 | TASK [Set zuul-log-path fact] 2026-06-04 18:06:18.625143 | localhost | ok 2026-06-04 18:06:18.639189 | 2026-06-04 18:06:18.639258 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-04 18:06:18.670390 | localhost | ok 2026-06-04 18:06:18.679285 | 2026-06-04 18:06:18.679352 | TASK [upload-logs : Create log directories] 2026-06-04 18:06:19.088206 | localhost | changed 2026-06-04 18:06:19.096253 | 2026-06-04 18:06:19.096345 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-06-04 18:06:19.521418 | localhost -> localhost | ok: Runtime: 0:00:00.005990 2026-06-04 18:06:19.526723 | 2026-06-04 18:06:19.526789 | TASK [upload-logs : Upload logs to log server] 2026-06-04 18:06:19.995147 | localhost | Output suppressed because no_log was given 2026-06-04 18:06:20.000412 | 2026-06-04 18:06:20.000601 | LOOP [upload-logs : Compress console log and json output] 2026-06-04 18:06:20.044988 | localhost | skipping: Conditional result was False 2026-06-04 18:06:20.051899 | localhost | skipping: Conditional result was False 2026-06-04 18:06:20.063782 | 2026-06-04 18:06:20.063958 | LOOP [upload-logs : Upload compressed console log and json output] 2026-06-04 18:06:20.106079 | localhost | skipping: Conditional result was False 2026-06-04 18:06:20.106473 | 2026-06-04 18:06:20.109785 | localhost | skipping: Conditional result was False 2026-06-04 18:06:20.126544 | 2026-06-04 18:06:20.126725 | LOOP [upload-logs : Upload console log and json output]