2026-06-09 16:14:37.281676 | Job console starting 2026-06-09 16:14:37.319151 | Updating git repos 2026-06-09 16:14:37.397750 | Cloning repos into workspace 2026-06-09 16:14:37.507588 | Restoring repo states 2026-06-09 16:14:37.522014 | Merging changes 2026-06-09 16:14:38.561954 | Checking out repos 2026-06-09 16:14:38.612838 | Preparing playbooks 2026-06-09 16:14:40.388819 | Running Ansible setup 2026-06-09 16:14:44.786572 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-06-09 16:14:45.511944 | 2026-06-09 16:14:45.512123 | PLAY [localhost] 2026-06-09 16:14:45.520145 | 2026-06-09 16:14:45.520221 | TASK [Gathering Facts] 2026-06-09 16:14:46.529919 | localhost | ok 2026-06-09 16:14:46.570332 | 2026-06-09 16:14:46.570525 | TASK [Setup log path fact] 2026-06-09 16:14:46.601607 | localhost | ok 2026-06-09 16:14:46.616693 | 2026-06-09 16:14:46.616772 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-09 16:14:46.649516 | localhost | ok 2026-06-09 16:14:46.655592 | 2026-06-09 16:14:46.655660 | TASK [emit-job-header : Print job information] 2026-06-09 16:14:46.690684 | # Job Information 2026-06-09 16:14:46.691267 | Ansible Version: 2.16.18 2026-06-09 16:14:46.691308 | Job: ansible-collection-containers-molecule-docker-rockylinux-9 2026-06-09 16:14:46.691332 | Pipeline: check 2026-06-09 16:14:46.691354 | Executor: 2d72f0692154 2026-06-09 16:14:46.691374 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/135 2026-06-09 16:14:46.691397 | Event ID: 2cce4c70-641e-11f1-8c62-24e0191aa320 2026-06-09 16:14:46.694450 | 2026-06-09 16:14:46.694517 | LOOP [emit-job-header : Print node information] 2026-06-09 16:14:46.823559 | localhost | ok: 2026-06-09 16:14:46.823785 | localhost | # Node Information 2026-06-09 16:14:46.823828 | localhost | Inventory Hostname: rockylinux-9 2026-06-09 16:14:46.823857 | localhost | Hostname: np0000179201 2026-06-09 16:14:46.823882 | localhost | Username: zuul 2026-06-09 16:14:46.823910 | localhost | Distro: Rocky 9.7 2026-06-09 16:14:46.823935 | localhost | Provider: yul1 2026-06-09 16:14:46.823959 | localhost | Region: ca-ymq-1 2026-06-09 16:14:46.823982 | localhost | Label: rockylinux-9 2026-06-09 16:14:46.824006 | localhost | Product Name: OpenStack Nova 2026-06-09 16:14:46.824029 | localhost | Interface IP: 199.204.45.49 2026-06-09 16:14:46.830925 | 2026-06-09 16:14:46.831092 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-06-09 16:14:47.573507 | localhost -> localhost | changed 2026-06-09 16:14:47.579296 | 2026-06-09 16:14:47.579373 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-06-09 16:14:48.586610 | localhost -> localhost | changed 2026-06-09 16:14:48.593854 | 2026-06-09 16:14:48.593921 | PLAY [all] 2026-06-09 16:14:48.604028 | 2026-06-09 16:14:48.604145 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-06-09 16:14:48.859014 | rockylinux-9 -> localhost | ok 2026-06-09 16:14:48.868026 | 2026-06-09 16:14:48.868202 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-06-09 16:14:48.897305 | rockylinux-9 | ok 2026-06-09 16:14:48.911275 | rockylinux-9 | included: /var/lib/zuul/builds/2ab82b6997b6409bb05bebbccfa2f0db/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-06-09 16:14:48.918165 | 2026-06-09 16:14:48.918264 | TASK [add-build-sshkey : Create Temp SSH key] 2026-06-09 16:14:50.115520 | rockylinux-9 -> localhost | Generating public/private rsa key pair. 2026-06-09 16:14:50.115741 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/2ab82b6997b6409bb05bebbccfa2f0db/work/2ab82b6997b6409bb05bebbccfa2f0db_id_rsa 2026-06-09 16:14:50.115773 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/2ab82b6997b6409bb05bebbccfa2f0db/work/2ab82b6997b6409bb05bebbccfa2f0db_id_rsa.pub 2026-06-09 16:14:50.115796 | rockylinux-9 -> localhost | The key fingerprint is: 2026-06-09 16:14:50.115818 | rockylinux-9 -> localhost | SHA256:8ztSTmQO6MFimRk9l1wzF9iYQRJa3HjQALxjVf/wEYA zuul-build-sshkey 2026-06-09 16:14:50.115854 | rockylinux-9 -> localhost | The key's randomart image is: 2026-06-09 16:14:50.115877 | rockylinux-9 -> localhost | +---[RSA 3072]----+ 2026-06-09 16:14:50.115901 | rockylinux-9 -> localhost | | o.+*%BB+o. | 2026-06-09 16:14:50.115923 | rockylinux-9 -> localhost | | . +oB.E=. . | 2026-06-09 16:14:50.115944 | rockylinux-9 -> localhost | | *.* . o . | 2026-06-09 16:14:50.115964 | rockylinux-9 -> localhost | | * B . o + . | 2026-06-09 16:14:50.115983 | rockylinux-9 -> localhost | | . + oS= o | 2026-06-09 16:14:50.116003 | rockylinux-9 -> localhost | | . o+ | 2026-06-09 16:14:50.116022 | rockylinux-9 -> localhost | | +. | 2026-06-09 16:14:50.116042 | rockylinux-9 -> localhost | | . o. | 2026-06-09 16:14:50.116066 | rockylinux-9 -> localhost | | ... | 2026-06-09 16:14:50.116086 | rockylinux-9 -> localhost | +----[SHA256]-----+ 2026-06-09 16:14:50.116142 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.710325 2026-06-09 16:14:50.131660 | 2026-06-09 16:14:50.131804 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-06-09 16:14:50.176340 | rockylinux-9 | ok 2026-06-09 16:14:50.194441 | rockylinux-9 | included: /var/lib/zuul/builds/2ab82b6997b6409bb05bebbccfa2f0db/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-06-09 16:14:50.204032 | 2026-06-09 16:14:50.204096 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-06-09 16:14:50.228496 | rockylinux-9 | skipping: Conditional result was False 2026-06-09 16:14:50.237245 | 2026-06-09 16:14:50.237322 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-06-09 16:14:50.705795 | rockylinux-9 | changed 2026-06-09 16:14:50.710900 | 2026-06-09 16:14:50.710968 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-06-09 16:14:50.947092 | rockylinux-9 | ok 2026-06-09 16:14:50.952744 | 2026-06-09 16:14:50.952814 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-06-09 16:14:51.845561 | rockylinux-9 | changed 2026-06-09 16:14:51.854868 | 2026-06-09 16:14:51.855030 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-06-09 16:14:52.772815 | rockylinux-9 | changed 2026-06-09 16:14:52.822543 | 2026-06-09 16:14:52.822649 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-06-09 16:14:52.846079 | rockylinux-9 | skipping: Conditional result was False 2026-06-09 16:14:52.852055 | 2026-06-09 16:14:52.852127 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-06-09 16:14:53.265533 | rockylinux-9 -> localhost | changed 2026-06-09 16:14:53.274748 | 2026-06-09 16:14:53.274825 | TASK [add-build-sshkey : Add back temp key] 2026-06-09 16:14:53.549734 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/2ab82b6997b6409bb05bebbccfa2f0db/work/2ab82b6997b6409bb05bebbccfa2f0db_id_rsa (zuul-build-sshkey) 2026-06-09 16:14:53.550268 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.017190 2026-06-09 16:14:53.557425 | 2026-06-09 16:14:53.557602 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-06-09 16:14:53.966376 | rockylinux-9 | ok 2026-06-09 16:14:54.183058 | 2026-06-09 16:14:54.183217 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-06-09 16:14:54.209442 | rockylinux-9 | skipping: Conditional result was False 2026-06-09 16:14:54.221222 | 2026-06-09 16:14:54.221298 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-06-09 16:14:54.671257 | rockylinux-9 | ok 2026-06-09 16:14:54.682414 | 2026-06-09 16:14:54.682500 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-06-09 16:14:55.683560 | rockylinux-9 | Output suppressed because no_log was given 2026-06-09 16:14:55.692102 | 2026-06-09 16:14:55.692187 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-06-09 16:14:55.949447 | rockylinux-9 | ok: "logs" 2026-06-09 16:14:55.949723 | rockylinux-9 | ok: All items complete 2026-06-09 16:14:55.949753 | 2026-06-09 16:14:56.170641 | rockylinux-9 | ok: "artifacts" 2026-06-09 16:14:56.413216 | rockylinux-9 | ok: "docs" 2026-06-09 16:14:56.420310 | 2026-06-09 16:14:56.420458 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-06-09 16:14:56.677728 | rockylinux-9 | changed: "logs" 2026-06-09 16:14:56.946828 | rockylinux-9 | changed: "artifacts" 2026-06-09 16:14:57.110773 | rockylinux-9 | changed: "docs" 2026-06-09 16:14:57.133155 | 2026-06-09 16:14:57.133285 | PLAY RECAP 2026-06-09 16:14:57.133349 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-06-09 16:14:57.133397 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-06-09 16:14:57.133461 | 2026-06-09 16:14:57.338002 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-06-09 16:14:57.340057 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-06-09 16:14:57.972873 | 2026-06-09 16:14:57.973030 | PLAY [all] 2026-06-09 16:14:57.985084 | 2026-06-09 16:14:57.985214 | TASK [setup-uv : Extract archive] 2026-06-09 16:15:00.810234 | rockylinux-9 | changed 2026-06-09 16:15:00.834166 | 2026-06-09 16:15:00.834499 | TASK [setup-uv : Print version] 2026-06-09 16:15:00.876786 | [rockylinux-9] Waiting on logger 2026-06-09 16:15:01.391841 | rockylinux-9 | ok: Runtime: 0:00:00.014658 2026-06-09 16:15:01.403454 | 2026-06-09 16:15:01.403527 | TASK [Install Ansible collection siblings] 2026-06-09 16:15:01.901222 | rockylinux-9 | ok: 2026-06-09 16:15:01.901491 | rockylinux-9 | Skipping ansible.posix: no checked-out sibling 2026-06-09 16:15:01.901556 | rockylinux-9 | Skipping community.general: no checked-out sibling 2026-06-09 16:15:01.904513 | 2026-06-09 16:15:01.904594 | PLAY RECAP 2026-06-09 16:15:01.904643 | rockylinux-9 | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-06-09 16:15:01.904666 | 2026-06-09 16:15:02.122622 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-06-09 16:15:02.125383 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-06-09 16:15:02.827172 | 2026-06-09 16:15:02.827564 | PLAY [all] 2026-06-09 16:15:02.839224 | 2026-06-09 16:15:02.839300 | TASK [Copy inventory file for Zuul] 2026-06-09 16:15:04.161224 | rockylinux-9 | changed 2026-06-09 16:15:04.168353 | 2026-06-09 16:15:04.168455 | TASK [Switch "ansible_host" to private IP] 2026-06-09 16:15:04.538413 | rockylinux-9 | changed: 1 replacements made 2026-06-09 16:15:04.544281 | 2026-06-09 16:15:04.544346 | TASK [Run Molecule scenario] 2026-06-09 16:15:04.568715 | [rockylinux-9] Waiting on logger 2026-06-09 16:15:12.710009 | [rockylinux-9] Waiting on logger 2026-06-09 16:15:22.886171 | [rockylinux-9] Waiting on logger 2026-06-09 16:15:32.038206 | [rockylinux-9] Waiting on logger 2026-06-09 16:15:42.214156 | [rockylinux-9] Waiting on logger 2026-06-09 16:15:52.389972 | [rockylinux-9] Waiting on logger 2026-06-09 16:16:02.570035 | [rockylinux-9] Waiting on logger 2026-06-09 16:16:12.743961 | [rockylinux-9] Waiting on logger 2026-06-09 16:16:21.903394 | [rockylinux-9] Waiting on logger 2026-06-09 16:16:32.070280 | [rockylinux-9] Waiting on logger 2026-06-09 16:16:42.246058 | [rockylinux-9] Waiting on logger 2026-06-09 16:16:52.422015 | [rockylinux-9] Waiting on logger 2026-06-09 16:17:02.597906 | [rockylinux-9] Waiting on logger 2026-06-09 16:17:12.774410 | [rockylinux-9] Waiting on logger 2026-06-09 16:17:18.609997 | rockylinux-9 | ok: Runtime: 0:02:13.541314 2026-06-09 16:17:18.618101 | 2026-06-09 16:17:18.618170 | PLAY RECAP 2026-06-09 16:17:18.618221 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-06-09 16:17:18.618244 | 2026-06-09 16:17:18.775475 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-06-09 16:17:18.779231 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-06-09 16:17:19.410751 | 2026-06-09 16:17:19.411402 | PLAY [all] 2026-06-09 16:17:19.422900 | 2026-06-09 16:17:19.423012 | TASK [fetch-output : Set log path for multiple nodes] 2026-06-09 16:17:19.469509 | rockylinux-9 | skipping: Conditional result was False 2026-06-09 16:17:19.479510 | 2026-06-09 16:17:19.479683 | TASK [fetch-output : Set log path for single node] 2026-06-09 16:17:19.525800 | rockylinux-9 | ok 2026-06-09 16:17:19.532127 | 2026-06-09 16:17:19.532222 | LOOP [fetch-output : Ensure local output dirs] 2026-06-09 16:17:20.017222 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/2ab82b6997b6409bb05bebbccfa2f0db/work/logs" 2026-06-09 16:17:20.244271 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/2ab82b6997b6409bb05bebbccfa2f0db/work/artifacts" 2026-06-09 16:17:20.481004 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/2ab82b6997b6409bb05bebbccfa2f0db/work/docs" 2026-06-09 16:17:20.498513 | 2026-06-09 16:17:20.498701 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-06-09 16:17:21.208502 | rockylinux-9 | changed: .d..t...... ./ 2026-06-09 16:17:21.208861 | rockylinux-9 | changed: All items complete 2026-06-09 16:17:21.209010 | 2026-06-09 16:17:21.854717 | rockylinux-9 | changed: .d..t...... ./ 2026-06-09 16:17:22.386758 | rockylinux-9 | changed: .d..t...... ./ 2026-06-09 16:17:22.410168 | 2026-06-09 16:17:22.410368 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-06-09 16:17:22.855186 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.008764 2026-06-09 16:17:23.087415 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.009597 2026-06-09 16:17:23.105536 | 2026-06-09 16:17:23.105678 | PLAY [all] 2026-06-09 16:17:23.115764 | 2026-06-09 16:17:23.115981 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-06-09 16:17:23.574230 | rockylinux-9 | changed 2026-06-09 16:17:23.580500 | 2026-06-09 16:17:23.580605 | PLAY RECAP 2026-06-09 16:17:23.580676 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-06-09 16:17:23.580769 | 2026-06-09 16:17:23.728340 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-06-09 16:17:23.729640 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-06-09 16:17:24.339723 | 2026-06-09 16:17:24.340276 | PLAY [localhost] 2026-06-09 16:17:24.355203 | 2026-06-09 16:17:24.355303 | TASK [Generate Zuul manifest] 2026-06-09 16:17:24.378507 | localhost | ok 2026-06-09 16:17:24.393463 | 2026-06-09 16:17:24.393546 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-06-09 16:17:24.790398 | localhost | changed 2026-06-09 16:17:24.803676 | 2026-06-09 16:17:24.803759 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-06-09 16:17:24.839288 | localhost | ok 2026-06-09 16:17:24.846919 | 2026-06-09 16:17:24.846989 | TASK [Upload logs] 2026-06-09 16:17:24.868987 | localhost | ok 2026-06-09 16:17:24.928646 | 2026-06-09 16:17:24.928823 | TASK [Set zuul-log-path fact] 2026-06-09 16:17:24.950825 | localhost | ok 2026-06-09 16:17:24.963358 | 2026-06-09 16:17:24.963489 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-09 16:17:24.995644 | localhost | ok 2026-06-09 16:17:25.006026 | 2026-06-09 16:17:25.006116 | TASK [upload-logs : Create log directories] 2026-06-09 16:17:25.413512 | localhost | changed 2026-06-09 16:17:25.421380 | 2026-06-09 16:17:25.421510 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-06-09 16:17:25.861065 | localhost -> localhost | ok: Runtime: 0:00:00.004617 2026-06-09 16:17:25.866815 | 2026-06-09 16:17:25.866883 | TASK [upload-logs : Upload logs to log server] 2026-06-09 16:17:26.485715 | localhost | Output suppressed because no_log was given 2026-06-09 16:17:26.491103 | 2026-06-09 16:17:26.491197 | LOOP [upload-logs : Compress console log and json output] 2026-06-09 16:17:26.542425 | localhost | skipping: Conditional result was False 2026-06-09 16:17:26.551672 | localhost | skipping: Conditional result was False 2026-06-09 16:17:26.570578 | 2026-06-09 16:17:26.570752 | LOOP [upload-logs : Upload compressed console log and json output] 2026-06-09 16:17:26.617750 | localhost | skipping: Conditional result was False 2026-06-09 16:17:26.618249 | 2026-06-09 16:17:26.621319 | localhost | skipping: Conditional result was False 2026-06-09 16:17:26.632494 | 2026-06-09 16:17:26.632810 | LOOP [upload-logs : Upload console log and json output]