2026-05-14 09:59:47.820814 | Job console starting 2026-05-14 09:59:47.836725 | Updating git repos 2026-05-14 09:59:47.923766 | Cloning repos into workspace 2026-05-14 09:59:48.170104 | Restoring repo states 2026-05-14 09:59:48.179368 | Merging changes 2026-05-14 09:59:48.694325 | Checking out repos 2026-05-14 09:59:48.724671 | Preparing playbooks 2026-05-14 09:59:50.802643 | Running Ansible setup 2026-05-14 09:59:57.263094 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-05-14 09:59:58.053841 | 2026-05-14 09:59:58.054031 | PLAY [localhost] 2026-05-14 09:59:58.063607 | 2026-05-14 09:59:58.063744 | TASK [Gathering Facts] 2026-05-14 09:59:59.361513 | localhost | ok 2026-05-14 09:59:59.377278 | 2026-05-14 09:59:59.377425 | TASK [Setup log path fact] 2026-05-14 09:59:59.418473 | localhost | ok 2026-05-14 09:59:59.430571 | 2026-05-14 09:59:59.430650 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-14 09:59:59.471516 | localhost | ok 2026-05-14 09:59:59.480249 | 2026-05-14 09:59:59.480335 | TASK [emit-job-header : Print job information] 2026-05-14 09:59:59.527349 | # Job Information 2026-05-14 09:59:59.527547 | Ansible Version: 2.16.18 2026-05-14 09:59:59.527596 | Job: ansible-collection-containers-molecule-docker-rockylinux-9 2026-05-14 09:59:59.527631 | Pipeline: check 2026-05-14 09:59:59.527711 | Executor: 2d72f0692154 2026-05-14 09:59:59.527745 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/129 2026-05-14 09:59:59.528242 | Event ID: e1cdee90-4f7a-11f1-8e86-6e434c085c6c 2026-05-14 09:59:59.536572 | 2026-05-14 09:59:59.536665 | LOOP [emit-job-header : Print node information] 2026-05-14 09:59:59.642940 | localhost | ok: 2026-05-14 09:59:59.648272 | localhost | # Node Information 2026-05-14 09:59:59.648355 | localhost | Inventory Hostname: rockylinux-9 2026-05-14 09:59:59.648394 | localhost | Hostname: np0000174154 2026-05-14 09:59:59.648427 | localhost | Username: zuul 2026-05-14 09:59:59.648460 | localhost | Distro: Rocky 9.7 2026-05-14 09:59:59.648491 | localhost | Provider: yul1 2026-05-14 09:59:59.648522 | localhost | Region: ca-ymq-1 2026-05-14 09:59:59.648552 | localhost | Label: rockylinux-9 2026-05-14 09:59:59.648582 | localhost | Product Name: OpenStack Nova 2026-05-14 09:59:59.648614 | localhost | Interface IP: 199.19.213.11 2026-05-14 09:59:59.664337 | 2026-05-14 09:59:59.664509 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-14 10:00:00.084875 | localhost -> localhost | changed 2026-05-14 10:00:00.089747 | 2026-05-14 10:00:00.089992 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-14 10:00:01.007014 | localhost -> localhost | changed 2026-05-14 10:00:01.012884 | 2026-05-14 10:00:01.013013 | PLAY [all] 2026-05-14 10:00:01.031653 | 2026-05-14 10:00:01.031824 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-14 10:00:01.300483 | rockylinux-9 -> localhost | ok 2026-05-14 10:00:01.901687 | 2026-05-14 10:00:01.901812 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-14 10:00:01.942754 | rockylinux-9 | ok 2026-05-14 10:00:01.958331 | rockylinux-9 | included: /var/lib/zuul/builds/dad4c898fa594aee8f8bbc0ce3dc5fe6/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-14 10:00:01.965037 | 2026-05-14 10:00:01.965125 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-14 10:00:02.780687 | rockylinux-9 -> localhost | Generating public/private rsa key pair. 2026-05-14 10:00:02.780912 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/dad4c898fa594aee8f8bbc0ce3dc5fe6/work/dad4c898fa594aee8f8bbc0ce3dc5fe6_id_rsa 2026-05-14 10:00:02.781007 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/dad4c898fa594aee8f8bbc0ce3dc5fe6/work/dad4c898fa594aee8f8bbc0ce3dc5fe6_id_rsa.pub 2026-05-14 10:00:02.781047 | rockylinux-9 -> localhost | The key fingerprint is: 2026-05-14 10:00:02.781079 | rockylinux-9 -> localhost | SHA256:4MDM2Y0GKuh8kkM53acv3LiQsl6DHTBS8pAGUv13vC0 zuul-build-sshkey 2026-05-14 10:00:02.781130 | rockylinux-9 -> localhost | The key's randomart image is: 2026-05-14 10:00:02.781162 | rockylinux-9 -> localhost | +---[RSA 3072]----+ 2026-05-14 10:00:02.781204 | rockylinux-9 -> localhost | |*oo.. | 2026-05-14 10:00:02.781238 | rockylinux-9 -> localhost | |+*o=o+ o | 2026-05-14 10:00:02.781268 | rockylinux-9 -> localhost | |=*o.*o=... | 2026-05-14 10:00:02.781298 | rockylinux-9 -> localhost | |=.= ++.. o | 2026-05-14 10:00:02.781328 | rockylinux-9 -> localhost | | * o ...S. o | 2026-05-14 10:00:02.781358 | rockylinux-9 -> localhost | | * + + E . | 2026-05-14 10:00:02.781387 | rockylinux-9 -> localhost | | o * + o . | 2026-05-14 10:00:02.781416 | rockylinux-9 -> localhost | | + o o | 2026-05-14 10:00:02.781451 | rockylinux-9 -> localhost | |.o . | 2026-05-14 10:00:02.781482 | rockylinux-9 -> localhost | +----[SHA256]-----+ 2026-05-14 10:00:02.781560 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.352664 2026-05-14 10:00:02.789071 | 2026-05-14 10:00:02.789279 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-14 10:00:02.822488 | rockylinux-9 | ok 2026-05-14 10:00:02.833926 | rockylinux-9 | included: /var/lib/zuul/builds/dad4c898fa594aee8f8bbc0ce3dc5fe6/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-14 10:00:02.842241 | 2026-05-14 10:00:02.842396 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-14 10:00:02.867851 | rockylinux-9 | skipping: Conditional result was False 2026-05-14 10:00:02.878988 | 2026-05-14 10:00:02.879134 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-14 10:00:03.591367 | rockylinux-9 | changed 2026-05-14 10:00:08.066101 | 2026-05-14 10:00:08.066234 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-14 10:00:08.417038 | rockylinux-9 | ok 2026-05-14 10:00:08.422297 | 2026-05-14 10:00:08.422365 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-14 10:00:09.631644 | rockylinux-9 | changed 2026-05-14 10:00:09.638332 | 2026-05-14 10:00:09.638528 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-14 10:00:10.654327 | rockylinux-9 | changed 2026-05-14 10:00:10.659198 | 2026-05-14 10:00:10.659265 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-14 10:00:10.684010 | rockylinux-9 | skipping: Conditional result was False 2026-05-14 10:00:10.689319 | 2026-05-14 10:00:10.689390 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-14 10:00:11.070105 | rockylinux-9 -> localhost | changed 2026-05-14 10:00:11.095376 | 2026-05-14 10:00:11.095463 | TASK [add-build-sshkey : Add back temp key] 2026-05-14 10:00:12.079116 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/dad4c898fa594aee8f8bbc0ce3dc5fe6/work/dad4c898fa594aee8f8bbc0ce3dc5fe6_id_rsa (zuul-build-sshkey) 2026-05-14 10:00:12.079368 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.013734 2026-05-14 10:00:12.093721 | 2026-05-14 10:00:12.093860 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-14 10:00:12.513030 | rockylinux-9 | ok 2026-05-14 10:00:12.517257 | 2026-05-14 10:00:12.517323 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-14 10:00:12.553120 | rockylinux-9 | skipping: Conditional result was False 2026-05-14 10:00:12.566007 | 2026-05-14 10:00:12.566089 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-05-14 10:00:12.992039 | rockylinux-9 | ok 2026-05-14 10:00:13.000085 | 2026-05-14 10:00:13.000206 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-05-14 10:00:14.072671 | rockylinux-9 | Output suppressed because no_log was given 2026-05-14 10:00:14.081142 | 2026-05-14 10:00:14.081272 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-05-14 10:00:14.330836 | rockylinux-9 | ok: "logs" 2026-05-14 10:00:14.331294 | rockylinux-9 | ok: All items complete 2026-05-14 10:00:14.331347 | 2026-05-14 10:00:14.562173 | rockylinux-9 | ok: "artifacts" 2026-05-14 10:00:14.786715 | rockylinux-9 | ok: "docs" 2026-05-14 10:00:14.799676 | 2026-05-14 10:00:14.799814 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-05-14 10:00:15.071007 | rockylinux-9 | changed: "logs" 2026-05-14 10:00:15.370104 | rockylinux-9 | changed: "artifacts" 2026-05-14 10:00:15.517744 | rockylinux-9 | changed: "docs" 2026-05-14 10:00:15.539571 | 2026-05-14 10:00:15.539648 | PLAY RECAP 2026-05-14 10:00:15.539701 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-14 10:00:15.539739 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-05-14 10:00:15.539768 | 2026-05-14 10:00:15.681966 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-05-14 10:00:15.683680 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-05-14 10:00:16.310487 | 2026-05-14 10:00:16.733808 | PLAY [all] 2026-05-14 10:00:16.753890 | 2026-05-14 10:00:16.754083 | TASK [setup-uv : Extract archive] 2026-05-14 10:00:20.331738 | rockylinux-9 | changed 2026-05-14 10:00:21.519067 | 2026-05-14 10:00:21.519258 | TASK [setup-uv : Print version] 2026-05-14 10:00:21.548850 | [rockylinux-9] Waiting on logger 2026-05-14 10:00:22.065911 | rockylinux-9 | ok: Runtime: 0:00:00.012643 2026-05-14 10:00:22.077273 | 2026-05-14 10:00:22.077348 | PLAY RECAP 2026-05-14 10:00:22.077398 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-14 10:00:22.077422 | 2026-05-14 10:00:22.242396 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-05-14 10:00:22.448484 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-05-14 10:00:23.181638 | 2026-05-14 10:00:23.852826 | PLAY [all] 2026-05-14 10:00:23.876159 | 2026-05-14 10:00:23.876333 | TASK [Copy inventory file for Zuul] 2026-05-14 10:00:25.257654 | rockylinux-9 | changed 2026-05-14 10:00:25.262389 | 2026-05-14 10:00:25.262471 | TASK [Switch "ansible_host" to private IP] 2026-05-14 10:00:25.667260 | rockylinux-9 | changed: 1 replacements made 2026-05-14 10:00:25.672451 | 2026-05-14 10:00:25.672535 | TASK [Run Molecule scenario] 2026-05-14 10:00:25.705827 | [rockylinux-9] Waiting on logger 2026-05-14 10:00:32.966109 | [rockylinux-9] Waiting on logger 2026-05-14 10:00:42.822163 | [rockylinux-9] Waiting on logger 2026-05-14 10:00:53.094050 | [rockylinux-9] Waiting on logger 2026-05-14 10:01:07.369894 | [rockylinux-9] Waiting on logger 2026-05-14 10:01:16.518027 | [rockylinux-9] Waiting on logger 2026-05-14 10:01:26.693935 | [rockylinux-9] Waiting on logger 2026-05-14 10:01:37.093856 | [rockylinux-9] Waiting on logger 2026-05-14 10:01:47.273835 | [rockylinux-9] Waiting on logger 2026-05-14 10:01:56.426826 | [rockylinux-9] Waiting on logger 2026-05-14 10:02:06.597974 | [rockylinux-9] Waiting on logger 2026-05-14 10:02:16.773838 | [rockylinux-9] Waiting on logger 2026-05-14 10:02:26.950131 | [rockylinux-9] Waiting on logger 2026-05-14 10:02:37.126021 | [rockylinux-9] Waiting on logger 2026-05-14 10:02:47.301920 | [rockylinux-9] Waiting on logger 2026-05-14 10:02:56.459398 | [rockylinux-9] Waiting on logger 2026-05-14 10:03:06.629940 | [rockylinux-9] Waiting on logger 2026-05-14 10:03:16.806046 | [rockylinux-9] Waiting on logger 2026-05-14 10:03:26.982130 | [rockylinux-9] Waiting on logger 2026-05-14 10:03:37.158167 | [rockylinux-9] Waiting on logger 2026-05-14 10:03:47.334077 | [rockylinux-9] Waiting on logger 2026-05-14 10:03:48.560080 | rockylinux-9 | ok: Runtime: 0:03:21.974959 2026-05-14 10:03:48.567234 | 2026-05-14 10:03:48.567305 | PLAY RECAP 2026-05-14 10:03:48.567351 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-14 10:03:48.567373 | 2026-05-14 10:03:48.729581 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-05-14 10:03:48.800671 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-05-14 10:03:49.392249 | 2026-05-14 10:03:49.528905 | PLAY [all] 2026-05-14 10:03:49.554141 | 2026-05-14 10:03:49.554330 | TASK [fetch-output : Set log path for multiple nodes] 2026-05-14 10:03:49.589717 | rockylinux-9 | skipping: Conditional result was False 2026-05-14 10:03:49.596064 | 2026-05-14 10:03:49.596268 | TASK [fetch-output : Set log path for single node] 2026-05-14 10:03:49.637765 | rockylinux-9 | ok 2026-05-14 10:03:49.642794 | 2026-05-14 10:03:49.642923 | LOOP [fetch-output : Ensure local output dirs] 2026-05-14 10:03:50.069264 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/dad4c898fa594aee8f8bbc0ce3dc5fe6/work/logs" 2026-05-14 10:03:50.301116 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/dad4c898fa594aee8f8bbc0ce3dc5fe6/work/artifacts" 2026-05-14 10:03:50.530253 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/dad4c898fa594aee8f8bbc0ce3dc5fe6/work/docs" 2026-05-14 10:03:50.545443 | 2026-05-14 10:03:50.545669 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-14 10:03:51.299526 | rockylinux-9 | changed: .d..t...... ./ 2026-05-14 10:03:51.300226 | rockylinux-9 | changed: All items complete 2026-05-14 10:03:51.300269 | 2026-05-14 10:03:51.874936 | rockylinux-9 | changed: .d..t...... ./ 2026-05-14 10:03:52.409596 | rockylinux-9 | changed: .d..t...... ./ 2026-05-14 10:03:52.425669 | 2026-05-14 10:03:52.425805 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-14 10:03:52.872284 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.007909 2026-05-14 10:03:53.134726 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.007949 2026-05-14 10:03:53.177928 | 2026-05-14 10:03:53.178128 | PLAY [all] 2026-05-14 10:03:53.185385 | 2026-05-14 10:03:53.185466 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-14 10:03:53.660022 | rockylinux-9 | changed 2026-05-14 10:03:53.667075 | 2026-05-14 10:03:53.667127 | PLAY RECAP 2026-05-14 10:03:53.667173 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-05-14 10:03:53.667195 | 2026-05-14 10:03:53.803660 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-05-14 10:03:53.806317 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-14 10:03:54.433091 | 2026-05-14 10:03:54.433207 | PLAY [localhost] 2026-05-14 10:03:54.444409 | 2026-05-14 10:03:54.444495 | TASK [Generate Zuul manifest] 2026-05-14 10:03:54.465786 | localhost | ok 2026-05-14 10:03:54.487921 | 2026-05-14 10:03:54.488093 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-14 10:03:54.881666 | localhost | changed 2026-05-14 10:03:54.894584 | 2026-05-14 10:03:54.894700 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-14 10:03:54.929125 | localhost | ok 2026-05-14 10:03:54.936377 | 2026-05-14 10:03:54.936443 | TASK [Upload logs] 2026-05-14 10:03:54.967405 | localhost | ok 2026-05-14 10:03:55.036846 | 2026-05-14 10:03:55.037010 | TASK [Set zuul-log-path fact] 2026-05-14 10:03:55.055420 | localhost | ok 2026-05-14 10:03:55.068792 | 2026-05-14 10:03:55.068901 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-14 10:03:55.099724 | localhost | ok 2026-05-14 10:03:55.108581 | 2026-05-14 10:03:55.108683 | TASK [upload-logs : Create log directories] 2026-05-14 10:03:55.538679 | localhost | changed 2026-05-14 10:03:55.546449 | 2026-05-14 10:03:55.546549 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-14 10:03:55.959916 | localhost -> localhost | ok: Runtime: 0:00:00.006704 2026-05-14 10:03:55.965741 | 2026-05-14 10:03:55.965830 | TASK [upload-logs : Upload logs to log server] 2026-05-14 10:03:56.443662 | localhost | Output suppressed because no_log was given 2026-05-14 10:03:56.449207 | 2026-05-14 10:03:56.449306 | LOOP [upload-logs : Compress console log and json output] 2026-05-14 10:03:56.495797 | localhost | skipping: Conditional result was False 2026-05-14 10:03:56.504577 | localhost | skipping: Conditional result was False 2026-05-14 10:03:56.512308 | 2026-05-14 10:03:56.512481 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-14 10:03:56.557041 | localhost | skipping: Conditional result was False 2026-05-14 10:03:56.557618 | 2026-05-14 10:03:56.562350 | localhost | skipping: Conditional result was False 2026-05-14 10:03:56.574809 | 2026-05-14 10:03:56.574977 | LOOP [upload-logs : Upload console log and json output]