2026-04-01 00:58:00.275791 | Job console starting 2026-04-01 00:58:00.282910 | Updating git repos 2026-04-01 00:58:00.311753 | Cloning repos into workspace 2026-04-01 00:58:00.368874 | Restoring repo states 2026-04-01 00:58:00.389334 | Merging changes 2026-04-01 00:58:00.678320 | Checking out repos 2026-04-01 00:58:00.712645 | Preparing playbooks 2026-04-01 00:58:01.573414 | Running Ansible setup 2026-04-01 00:58:05.191899 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-01 00:58:05.805617 | 2026-04-01 00:58:05.805765 | PLAY [localhost] 2026-04-01 00:58:05.813545 | 2026-04-01 00:58:05.813617 | TASK [Gathering Facts] 2026-04-01 00:58:06.742896 | localhost | ok 2026-04-01 00:58:06.754693 | 2026-04-01 00:58:06.754867 | TASK [Setup log path fact] 2026-04-01 00:58:06.774290 | localhost | ok 2026-04-01 00:58:06.788489 | 2026-04-01 00:58:06.788610 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-01 00:58:06.816014 | localhost | ok 2026-04-01 00:58:06.826188 | 2026-04-01 00:58:06.826287 | TASK [emit-job-header : Print job information] 2026-04-01 00:58:06.866887 | # Job Information 2026-04-01 00:58:06.867129 | Ansible Version: 2.16.16 2026-04-01 00:58:06.867222 | Job: ansible-collection-containers-molecule-containerd-rockylinux-9 2026-04-01 00:58:06.867277 | Pipeline: check 2026-04-01 00:58:06.867323 | Executor: 0a8996d2b663 2026-04-01 00:58:06.867366 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/105 2026-04-01 00:58:06.867434 | Event ID: ba6bf4a0-2d65-11f1-99f2-ed7bf96431dc 2026-04-01 00:58:06.871881 | 2026-04-01 00:58:06.871969 | LOOP [emit-job-header : Print node information] 2026-04-01 00:58:06.958135 | localhost | ok: 2026-04-01 00:58:06.958376 | localhost | # Node Information 2026-04-01 00:58:06.958419 | localhost | Inventory Hostname: rockylinux-9 2026-04-01 00:58:06.958448 | localhost | Hostname: np0000162034 2026-04-01 00:58:06.958473 | localhost | Username: zuul 2026-04-01 00:58:06.958501 | localhost | Distro: Rocky 9.7 2026-04-01 00:58:06.958525 | localhost | Provider: yul1 2026-04-01 00:58:06.958548 | localhost | Region: ca-ymq-1 2026-04-01 00:58:06.958571 | localhost | Label: rockylinux-9 2026-04-01 00:58:06.958595 | localhost | Product Name: OpenStack Nova 2026-04-01 00:58:06.958618 | localhost | Interface IP: 162.253.55.204 2026-04-01 00:58:06.969395 | 2026-04-01 00:58:06.969537 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-04-01 00:58:07.338338 | localhost -> localhost | changed 2026-04-01 00:58:07.343756 | 2026-04-01 00:58:07.343825 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-04-01 00:58:09.100096 | localhost -> localhost | changed 2026-04-01 00:58:09.105950 | 2026-04-01 00:58:09.106004 | PLAY [all] 2026-04-01 00:58:09.113269 | 2026-04-01 00:58:09.113333 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-04-01 00:58:09.330919 | rockylinux-9 -> localhost | ok 2026-04-01 00:58:09.340926 | 2026-04-01 00:58:09.341040 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-04-01 00:58:09.373707 | rockylinux-9 | ok 2026-04-01 00:58:09.388536 | rockylinux-9 | included: /var/lib/zuul/builds/217bab7302554dc5a64c0be17a7c4d50/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-04-01 00:58:09.393519 | 2026-04-01 00:58:09.393578 | TASK [add-build-sshkey : Create Temp SSH key] 2026-04-01 00:58:10.561076 | rockylinux-9 -> localhost | Generating public/private rsa key pair. 2026-04-01 00:58:10.561348 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/217bab7302554dc5a64c0be17a7c4d50/work/217bab7302554dc5a64c0be17a7c4d50_id_rsa 2026-04-01 00:58:10.561403 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/217bab7302554dc5a64c0be17a7c4d50/work/217bab7302554dc5a64c0be17a7c4d50_id_rsa.pub 2026-04-01 00:58:10.561431 | rockylinux-9 -> localhost | The key fingerprint is: 2026-04-01 00:58:10.561454 | rockylinux-9 -> localhost | SHA256:VRYKYa4+bM/YofyaDU0Ur41gyRvnJvPgWKlwKolMtDk zuul-build-sshkey 2026-04-01 00:58:10.561488 | rockylinux-9 -> localhost | The key's randomart image is: 2026-04-01 00:58:10.561510 | rockylinux-9 -> localhost | +---[RSA 3072]----+ 2026-04-01 00:58:10.561645 | rockylinux-9 -> localhost | | =. +. | 2026-04-01 00:58:10.561689 | rockylinux-9 -> localhost | | . + + + | 2026-04-01 00:58:10.561713 | rockylinux-9 -> localhost | | . * + + | 2026-04-01 00:58:10.561734 | rockylinux-9 -> localhost | |. o . X = | 2026-04-01 00:58:10.562088 | rockylinux-9 -> localhost | | E. . O S . | 2026-04-01 00:58:10.562127 | rockylinux-9 -> localhost | |+..+ B O | 2026-04-01 00:58:10.562218 | rockylinux-9 -> localhost | |+.. o B + | 2026-04-01 00:58:10.562254 | rockylinux-9 -> localhost | | . o @ . | 2026-04-01 00:58:10.562279 | rockylinux-9 -> localhost | | *o* | 2026-04-01 00:58:10.562300 | rockylinux-9 -> localhost | +----[SHA256]-----+ 2026-04-01 00:58:10.562534 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.735767 2026-04-01 00:58:10.567900 | 2026-04-01 00:58:10.567966 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-04-01 00:58:10.597827 | rockylinux-9 | ok 2026-04-01 00:58:10.609702 | rockylinux-9 | included: /var/lib/zuul/builds/217bab7302554dc5a64c0be17a7c4d50/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-04-01 00:58:10.618739 | 2026-04-01 00:58:10.618908 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-04-01 00:58:10.643631 | rockylinux-9 | skipping: Conditional result was False 2026-04-01 00:58:10.650901 | 2026-04-01 00:58:10.723071 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-04-01 00:58:11.213296 | rockylinux-9 | changed 2026-04-01 00:58:11.474718 | 2026-04-01 00:58:11.474923 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-04-01 00:58:11.729677 | rockylinux-9 | ok 2026-04-01 00:58:11.738363 | 2026-04-01 00:58:11.738470 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-04-01 00:58:12.706321 | rockylinux-9 | changed 2026-04-01 00:58:12.712261 | 2026-04-01 00:58:12.712331 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-04-01 00:58:13.560163 | rockylinux-9 | changed 2026-04-01 00:58:13.564757 | 2026-04-01 00:58:13.564818 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-04-01 00:58:13.588882 | rockylinux-9 | skipping: Conditional result was False 2026-04-01 00:58:13.598860 | 2026-04-01 00:58:13.598997 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-04-01 00:58:13.938031 | rockylinux-9 -> localhost | changed 2026-04-01 00:58:13.952439 | 2026-04-01 00:58:13.952560 | TASK [add-build-sshkey : Add back temp key] 2026-04-01 00:58:14.259056 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/217bab7302554dc5a64c0be17a7c4d50/work/217bab7302554dc5a64c0be17a7c4d50_id_rsa (zuul-build-sshkey) 2026-04-01 00:58:14.259278 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.012824 2026-04-01 00:58:14.268669 | 2026-04-01 00:58:14.268754 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-04-01 00:58:14.685340 | rockylinux-9 | ok 2026-04-01 00:58:14.691000 | 2026-04-01 00:58:14.691086 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-04-01 00:58:14.726632 | rockylinux-9 | skipping: Conditional result was False 2026-04-01 00:58:14.741396 | 2026-04-01 00:58:14.741486 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-04-01 00:58:15.126348 | rockylinux-9 | ok 2026-04-01 00:58:15.133306 | 2026-04-01 00:58:15.133374 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-04-01 00:58:16.533891 | rockylinux-9 | Output suppressed because no_log was given 2026-04-01 00:58:16.543715 | 2026-04-01 00:58:16.543792 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-04-01 00:58:16.820925 | rockylinux-9 | ok: "logs" 2026-04-01 00:58:16.821637 | rockylinux-9 | ok: All items complete 2026-04-01 00:58:16.821670 | 2026-04-01 00:58:17.045634 | rockylinux-9 | ok: "artifacts" 2026-04-01 00:58:17.256927 | rockylinux-9 | ok: "docs" 2026-04-01 00:58:17.457397 | 2026-04-01 00:58:17.457509 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-04-01 00:58:17.715434 | rockylinux-9 | changed: "logs" 2026-04-01 00:58:17.937594 | rockylinux-9 | changed: "artifacts" 2026-04-01 00:58:18.169960 | rockylinux-9 | changed: "docs" 2026-04-01 00:58:18.179448 | 2026-04-01 00:58:18.179575 | PLAY RECAP 2026-04-01 00:58:18.179622 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-01 00:58:18.179653 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-04-01 00:58:18.179676 | 2026-04-01 00:58:18.312021 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-01 00:58:18.314343 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-01 00:58:18.998359 | 2026-04-01 00:58:18.998610 | PLAY [all] 2026-04-01 00:58:19.012057 | 2026-04-01 00:58:19.012139 | TASK [setup-uv : Extract archive] 2026-04-01 00:58:21.971678 | rockylinux-9 | changed 2026-04-01 00:58:22.028175 | 2026-04-01 00:58:22.028282 | TASK [setup-uv : Print version] 2026-04-01 00:58:22.052132 | [rockylinux-9] Waiting on logger 2026-04-01 00:58:22.567099 | rockylinux-9 | ok: Runtime: 0:00:00.015009 2026-04-01 00:58:22.643948 | 2026-04-01 00:58:22.644168 | PLAY RECAP 2026-04-01 00:58:22.644229 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-01 00:58:22.644255 | 2026-04-01 00:58:22.842546 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-01 00:58:22.905462 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-01 00:58:23.562820 | 2026-04-01 00:58:23.562938 | PLAY [all] 2026-04-01 00:58:23.572964 | 2026-04-01 00:58:23.573035 | TASK [Copy inventory file for Zuul] 2026-04-01 00:58:24.905897 | rockylinux-9 | changed 2026-04-01 00:58:24.910872 | 2026-04-01 00:58:24.910951 | TASK [Switch "ansible_host" to private IP] 2026-04-01 00:58:25.299829 | rockylinux-9 | changed: 1 replacements made 2026-04-01 00:58:25.311141 | 2026-04-01 00:58:25.311207 | TASK [Run Molecule scenario] 2026-04-01 00:58:25.337132 | [rockylinux-9] Waiting on logger 2026-04-01 00:58:33.478007 | [rockylinux-9] Waiting on logger 2026-04-01 00:58:43.654124 | [rockylinux-9] Waiting on logger 2026-04-01 00:58:53.830158 | [rockylinux-9] Waiting on logger 2026-04-01 00:59:04.006197 | [rockylinux-9] Waiting on logger 2026-04-01 00:59:13.167584 | [rockylinux-9] Waiting on logger 2026-04-01 00:59:23.334190 | [rockylinux-9] Waiting on logger 2026-04-01 00:59:33.510081 | [rockylinux-9] Waiting on logger 2026-04-01 00:59:43.689959 | [rockylinux-9] Waiting on logger 2026-04-01 00:59:53.861798 | [rockylinux-9] Waiting on logger 2026-04-01 01:00:04.042013 | [rockylinux-9] Waiting on logger 2026-04-01 01:00:13.189881 | [rockylinux-9] Waiting on logger 2026-04-01 01:00:23.366428 | [rockylinux-9] Waiting on logger 2026-04-01 01:00:33.546160 | [rockylinux-9] Waiting on logger 2026-04-01 01:00:43.722289 | [rockylinux-9] Waiting on logger 2026-04-01 01:00:53.894431 | [rockylinux-9] Waiting on logger 2026-04-01 01:01:03.334119 | [rockylinux-9] Waiting on logger 2026-04-01 01:01:13.510130 | [rockylinux-9] Waiting on logger 2026-04-01 01:01:23.686104 | [rockylinux-9] Waiting on logger 2026-04-01 01:01:33.862204 | [rockylinux-9] Waiting on logger 2026-04-01 01:01:44.038333 | [rockylinux-9] Waiting on logger 2026-04-01 01:01:53.190126 | [rockylinux-9] Waiting on logger 2026-04-01 01:02:03.366052 | [rockylinux-9] Waiting on logger 2026-04-01 01:02:13.542374 | [rockylinux-9] Waiting on logger 2026-04-01 01:02:23.718121 | [rockylinux-9] Waiting on logger 2026-04-01 01:02:33.958188 | [rockylinux-9] Waiting on logger 2026-04-01 01:02:43.115439 | [rockylinux-9] Waiting on logger 2026-04-01 01:02:46.481819 | rockylinux-9 | ok: Runtime: 0:04:20.133917 2026-04-01 01:02:46.490425 | 2026-04-01 01:02:46.490467 | PLAY RECAP 2026-04-01 01:02:46.490508 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-01 01:02:46.490529 | 2026-04-01 01:02:46.592182 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-01 01:02:46.594453 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-01 01:02:47.214550 | 2026-04-01 01:02:47.214690 | PLAY [all] 2026-04-01 01:02:47.225146 | 2026-04-01 01:02:47.225229 | TASK [fetch-output : Set log path for multiple nodes] 2026-04-01 01:02:47.269880 | rockylinux-9 | skipping: Conditional result was False 2026-04-01 01:02:47.279945 | 2026-04-01 01:02:47.280074 | TASK [fetch-output : Set log path for single node] 2026-04-01 01:02:47.321042 | rockylinux-9 | ok 2026-04-01 01:02:47.329140 | 2026-04-01 01:02:47.329209 | LOOP [fetch-output : Ensure local output dirs] 2026-04-01 01:02:47.685855 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/217bab7302554dc5a64c0be17a7c4d50/work/logs" 2026-04-01 01:02:47.882998 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/217bab7302554dc5a64c0be17a7c4d50/work/artifacts" 2026-04-01 01:02:48.076975 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/217bab7302554dc5a64c0be17a7c4d50/work/docs" 2026-04-01 01:02:48.093492 | 2026-04-01 01:02:48.093644 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-04-01 01:02:48.769767 | rockylinux-9 | changed: .d..t...... ./ 2026-04-01 01:02:48.770001 | rockylinux-9 | changed: All items complete 2026-04-01 01:02:48.770040 | 2026-04-01 01:02:49.265677 | rockylinux-9 | changed: .d..t...... ./ 2026-04-01 01:02:49.766694 | rockylinux-9 | changed: .d..t...... ./ 2026-04-01 01:02:49.790669 | 2026-04-01 01:02:49.790801 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-04-01 01:02:50.175750 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.006429 2026-04-01 01:02:50.383181 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.006165 2026-04-01 01:02:50.400320 | 2026-04-01 01:02:50.400416 | PLAY [all] 2026-04-01 01:02:50.406898 | 2026-04-01 01:02:50.406982 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-04-01 01:02:50.865219 | rockylinux-9 | changed 2026-04-01 01:02:50.871659 | 2026-04-01 01:02:50.871724 | PLAY RECAP 2026-04-01 01:02:50.871782 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-04-01 01:02:50.871814 | 2026-04-01 01:02:50.971597 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-01 01:02:50.973632 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-04-01 01:02:51.515339 | 2026-04-01 01:02:51.515449 | PLAY [localhost] 2026-04-01 01:02:51.525081 | 2026-04-01 01:02:51.525160 | TASK [Generate Zuul manifest] 2026-04-01 01:02:51.546885 | localhost | ok 2026-04-01 01:02:51.560463 | 2026-04-01 01:02:51.560563 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-04-01 01:02:51.864402 | localhost | changed 2026-04-01 01:02:51.875661 | 2026-04-01 01:02:51.875731 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-04-01 01:02:51.901279 | localhost | ok 2026-04-01 01:02:51.907500 | 2026-04-01 01:02:51.907562 | TASK [Upload logs] 2026-04-01 01:02:51.927087 | localhost | ok 2026-04-01 01:02:51.987515 | 2026-04-01 01:02:51.987639 | TASK [Set zuul-log-path fact] 2026-04-01 01:02:52.005764 | localhost | ok 2026-04-01 01:02:52.019264 | 2026-04-01 01:02:52.019334 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-01 01:02:52.050812 | localhost | ok 2026-04-01 01:02:52.061506 | 2026-04-01 01:02:52.061569 | TASK [upload-logs : Create log directories] 2026-04-01 01:02:52.443006 | localhost | changed 2026-04-01 01:02:52.447442 | 2026-04-01 01:02:52.447507 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-04-01 01:02:52.852343 | localhost -> localhost | ok: Runtime: 0:00:00.003310 2026-04-01 01:02:52.859106 | 2026-04-01 01:02:52.859193 | TASK [upload-logs : Upload logs to log server] 2026-04-01 01:02:54.350937 | localhost | Output suppressed because no_log was given 2026-04-01 01:02:54.356623 | 2026-04-01 01:02:54.356780 | LOOP [upload-logs : Compress console log and json output] 2026-04-01 01:02:54.417488 | localhost | skipping: Conditional result was False 2026-04-01 01:02:54.429848 | localhost | skipping: Conditional result was False 2026-04-01 01:02:54.441117 | 2026-04-01 01:02:54.441367 | LOOP [upload-logs : Upload compressed console log and json output] 2026-04-01 01:02:54.489772 | localhost | skipping: Conditional result was False 2026-04-01 01:02:54.490134 | 2026-04-01 01:02:54.494066 | localhost | skipping: Conditional result was False 2026-04-01 01:02:54.503163 | 2026-04-01 01:02:54.503328 | LOOP [upload-logs : Upload console log and json output]