2026-02-14 00:46:27.717687 | Job console starting 2026-02-14 00:46:27.727286 | Updating git repos 2026-02-14 00:46:27.755725 | Cloning repos into workspace 2026-02-14 00:46:27.829439 | Restoring repo states 2026-02-14 00:46:27.835653 | Merging changes 2026-02-14 00:46:28.126631 | Checking out repos 2026-02-14 00:46:28.184546 | Preparing playbooks 2026-02-14 00:46:29.286456 | Running Ansible setup 2026-02-14 00:46:33.106293 | PRE-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main] 2026-02-14 00:46:33.747227 | 2026-02-14 00:46:33.747769 | PLAY [localhost] 2026-02-14 00:46:33.762851 | 2026-02-14 00:46:33.763081 | TASK [Gathering Facts] 2026-02-14 00:46:34.839821 | localhost | ok 2026-02-14 00:46:34.849153 | 2026-02-14 00:46:34.872545 | TASK [Setup log path fact] 2026-02-14 00:46:34.898134 | localhost | ok 2026-02-14 00:46:34.912874 | 2026-02-14 00:46:34.913077 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-02-14 00:46:34.944427 | localhost | ok 2026-02-14 00:46:34.953424 | 2026-02-14 00:46:34.953618 | TASK [emit-job-header : Print job information] 2026-02-14 00:46:34.999049 | # Job Information 2026-02-14 00:46:34.999306 | Ansible Version: 2.16.15 2026-02-14 00:46:34.999353 | Job: ansible-collection-containers-molecule-docker-rockylinux-9 2026-02-14 00:46:34.999389 | Pipeline: check 2026-02-14 00:46:34.999417 | Executor: 3a2793d2bd32 2026-02-14 00:46:34.999446 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/92 2026-02-14 00:46:34.999479 | Event ID: 7be9d3a0-093e-11f1-9258-d7bae6f876bb 2026-02-14 00:46:35.004367 | 2026-02-14 00:46:35.004444 | LOOP [emit-job-header : Print node information] 2026-02-14 00:46:35.115630 | localhost | ok: 2026-02-14 00:46:35.115831 | localhost | # Node Information 2026-02-14 00:46:35.115860 | localhost | Inventory Hostname: rockylinux-9 2026-02-14 00:46:35.115881 | localhost | Hostname: np0000155524 2026-02-14 00:46:35.115901 | localhost | Username: zuul 2026-02-14 00:46:35.115924 | localhost | Distro: Rocky 9.7 2026-02-14 00:46:35.115947 | localhost | Provider: yul1 2026-02-14 00:46:35.115973 | localhost | Region: ca-ymq-1 2026-02-14 00:46:35.115996 | localhost | Label: rockylinux-9 2026-02-14 00:46:35.116016 | localhost | Product Name: OpenStack Nova 2026-02-14 00:46:35.116069 | localhost | Interface IP: 199.204.45.149 2026-02-14 00:46:35.130651 | 2026-02-14 00:46:35.149111 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-02-14 00:46:35.618794 | localhost -> localhost | changed 2026-02-14 00:46:35.626605 | 2026-02-14 00:46:35.626681 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-02-14 00:46:36.667652 | localhost -> localhost | changed 2026-02-14 00:46:36.678874 | 2026-02-14 00:46:36.678972 | PLAY [all] 2026-02-14 00:46:36.689512 | 2026-02-14 00:46:36.689621 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-02-14 00:46:36.988392 | rockylinux-9 -> localhost | ok 2026-02-14 00:46:36.998259 | 2026-02-14 00:46:36.998449 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-02-14 00:46:37.035249 | rockylinux-9 | ok 2026-02-14 00:46:37.057396 | rockylinux-9 | included: /var/lib/zuul/builds/180d1c5b7b914c7bb88383e967d3134a/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-02-14 00:46:37.076179 | 2026-02-14 00:46:37.076290 | TASK [add-build-sshkey : Create Temp SSH key] 2026-02-14 00:46:38.865606 | rockylinux-9 -> localhost | Generating public/private rsa key pair. 2026-02-14 00:46:38.865795 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/180d1c5b7b914c7bb88383e967d3134a/work/180d1c5b7b914c7bb88383e967d3134a_id_rsa 2026-02-14 00:46:38.865828 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/180d1c5b7b914c7bb88383e967d3134a/work/180d1c5b7b914c7bb88383e967d3134a_id_rsa.pub 2026-02-14 00:46:38.865853 | rockylinux-9 -> localhost | The key fingerprint is: 2026-02-14 00:46:38.865875 | rockylinux-9 -> localhost | SHA256:ZwAmUyFVIPV8rfT65hjntnaOmXrmt3Yt66MYlYMFO+Q zuul-build-sshkey 2026-02-14 00:46:38.865915 | rockylinux-9 -> localhost | The key's randomart image is: 2026-02-14 00:46:38.865946 | rockylinux-9 -> localhost | +---[RSA 3072]----+ 2026-02-14 00:46:38.866003 | rockylinux-9 -> localhost | | =+O+. o | 2026-02-14 00:46:38.866032 | rockylinux-9 -> localhost | | * + o + | 2026-02-14 00:46:38.866062 | rockylinux-9 -> localhost | | + E o | 2026-02-14 00:46:38.866085 | rockylinux-9 -> localhost | | + * . | 2026-02-14 00:46:38.866105 | rockylinux-9 -> localhost | | S = = | 2026-02-14 00:46:38.866123 | rockylinux-9 -> localhost | | o o . | 2026-02-14 00:46:38.866143 | rockylinux-9 -> localhost | | + . .| 2026-02-14 00:46:38.866162 | rockylinux-9 -> localhost | | O*+* o| 2026-02-14 00:46:38.866185 | rockylinux-9 -> localhost | | +X@O=* | 2026-02-14 00:46:38.866205 | rockylinux-9 -> localhost | +----[SHA256]-----+ 2026-02-14 00:46:38.866253 | rockylinux-9 -> localhost | ok: Runtime: 0:00:01.388803 2026-02-14 00:46:38.871143 | 2026-02-14 00:46:38.871219 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-02-14 00:46:38.899446 | rockylinux-9 | ok 2026-02-14 00:46:38.909583 | rockylinux-9 | included: /var/lib/zuul/builds/180d1c5b7b914c7bb88383e967d3134a/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-02-14 00:46:38.918215 | 2026-02-14 00:46:38.918297 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-02-14 00:46:38.933697 | rockylinux-9 | skipping: Conditional result was False 2026-02-14 00:46:38.941248 | 2026-02-14 00:46:38.941315 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-02-14 00:46:39.415028 | rockylinux-9 | changed 2026-02-14 00:46:39.422191 | 2026-02-14 00:46:39.422291 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-02-14 00:46:39.655230 | rockylinux-9 | ok 2026-02-14 00:46:39.659762 | 2026-02-14 00:46:39.659830 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-02-14 00:46:40.430534 | rockylinux-9 | changed 2026-02-14 00:46:40.435250 | 2026-02-14 00:46:40.435313 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-02-14 00:46:41.236433 | rockylinux-9 | changed 2026-02-14 00:46:41.242480 | 2026-02-14 00:46:41.242545 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-02-14 00:46:41.267442 | rockylinux-9 | skipping: Conditional result was False 2026-02-14 00:46:41.276535 | 2026-02-14 00:46:41.276624 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-02-14 00:46:41.650099 | rockylinux-9 -> localhost | changed 2026-02-14 00:46:41.661494 | 2026-02-14 00:46:41.661564 | TASK [add-build-sshkey : Add back temp key] 2026-02-14 00:46:41.965374 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/180d1c5b7b914c7bb88383e967d3134a/work/180d1c5b7b914c7bb88383e967d3134a_id_rsa (zuul-build-sshkey) 2026-02-14 00:46:41.965634 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.014876 2026-02-14 00:46:41.970561 | 2026-02-14 00:46:41.970625 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-02-14 00:46:42.313746 | rockylinux-9 | ok 2026-02-14 00:46:42.319466 | 2026-02-14 00:46:42.319535 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-02-14 00:46:42.347996 | rockylinux-9 | skipping: Conditional result was False 2026-02-14 00:46:42.374777 | 2026-02-14 00:46:42.375035 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-02-14 00:46:42.782594 | rockylinux-9 | ok 2026-02-14 00:46:42.789839 | 2026-02-14 00:46:42.789927 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-02-14 00:46:43.645066 | rockylinux-9 | Output suppressed because no_log was given 2026-02-14 00:46:43.664730 | 2026-02-14 00:46:43.665035 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-02-14 00:46:43.922369 | rockylinux-9 | ok: "logs" 2026-02-14 00:46:43.922672 | rockylinux-9 | ok: All items complete 2026-02-14 00:46:43.922707 | 2026-02-14 00:46:44.139729 | rockylinux-9 | ok: "artifacts" 2026-02-14 00:46:44.370513 | rockylinux-9 | ok: "docs" 2026-02-14 00:46:44.390239 | 2026-02-14 00:46:44.390384 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-02-14 00:46:44.653092 | rockylinux-9 | changed: "logs" 2026-02-14 00:46:44.876474 | rockylinux-9 | changed: "artifacts" 2026-02-14 00:46:45.088980 | rockylinux-9 | changed: "docs" 2026-02-14 00:46:45.109953 | 2026-02-14 00:46:45.110071 | PLAY RECAP 2026-02-14 00:46:45.110118 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-02-14 00:46:45.110147 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-02-14 00:46:45.110171 | 2026-02-14 00:46:45.237896 | PRE-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main] 2026-02-14 00:46:45.240412 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-02-14 00:46:45.867561 | 2026-02-14 00:46:45.867745 | PLAY [all] 2026-02-14 00:46:45.882388 | 2026-02-14 00:46:45.882540 | TASK [setup-uv : Extract archive] 2026-02-14 00:46:48.573459 | rockylinux-9 | changed 2026-02-14 00:46:48.579024 | 2026-02-14 00:46:48.579105 | TASK [setup-uv : Print version] 2026-02-14 00:46:48.603127 | [rockylinux-9] Waiting on logger 2026-02-14 00:46:49.117935 | rockylinux-9 | ok: Runtime: 0:00:00.013519 2026-02-14 00:46:49.123634 | 2026-02-14 00:46:49.123681 | PLAY RECAP 2026-02-14 00:46:49.123725 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-02-14 00:46:49.123749 | 2026-02-14 00:46:49.231273 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-02-14 00:46:49.233607 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-02-14 00:46:49.850313 | 2026-02-14 00:46:49.850480 | PLAY [all] 2026-02-14 00:46:49.862843 | 2026-02-14 00:46:49.862953 | TASK [Copy inventory file for Zuul] 2026-02-14 00:46:51.048194 | rockylinux-9 | changed 2026-02-14 00:46:51.053824 | 2026-02-14 00:46:51.053906 | TASK [Switch "ansible_host" to private IP] 2026-02-14 00:46:51.387698 | rockylinux-9 | changed: 1 replacements made 2026-02-14 00:46:51.394584 | 2026-02-14 00:46:51.394656 | TASK [Run Molecule scenario] 2026-02-14 00:46:51.416818 | [rockylinux-9] Waiting on logger 2026-02-14 00:47:00.581920 | [rockylinux-9] Waiting on logger 2026-02-14 00:47:09.734096 | [rockylinux-9] Waiting on logger 2026-02-14 00:47:19.909891 | [rockylinux-9] Waiting on logger 2026-02-14 00:47:30.085901 | [rockylinux-9] Waiting on logger 2026-02-14 00:47:40.261934 | [rockylinux-9] Waiting on logger 2026-02-14 00:47:50.442211 | [rockylinux-9] Waiting on logger 2026-02-14 00:47:59.631336 | [rockylinux-9] Waiting on logger 2026-02-14 00:48:09.801887 | [rockylinux-9] Waiting on logger 2026-02-14 00:48:19.974054 | [rockylinux-9] Waiting on logger 2026-02-14 00:48:30.149865 | [rockylinux-9] Waiting on logger 2026-02-14 00:48:40.325947 | [rockylinux-9] Waiting on logger 2026-02-14 00:48:50.501963 | [rockylinux-9] Waiting on logger 2026-02-14 00:48:59.659533 | [rockylinux-9] Waiting on logger 2026-02-14 00:49:09.829927 | [rockylinux-9] Waiting on logger 2026-02-14 00:49:20.005904 | [rockylinux-9] Waiting on logger 2026-02-14 00:49:24.495706 | rockylinux-9 | ok: Runtime: 0:02:32.365670 2026-02-14 00:49:24.504364 | 2026-02-14 00:49:24.504438 | PLAY RECAP 2026-02-14 00:49:24.504488 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-02-14 00:49:24.504510 | 2026-02-14 00:49:24.623550 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-02-14 00:49:24.625915 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main] 2026-02-14 00:49:25.200651 | 2026-02-14 00:49:25.200824 | PLAY [all] 2026-02-14 00:49:25.212963 | 2026-02-14 00:49:25.213082 | TASK [fetch-output : Set log path for multiple nodes] 2026-02-14 00:49:25.248650 | rockylinux-9 | skipping: Conditional result was False 2026-02-14 00:49:25.255796 | 2026-02-14 00:49:25.255917 | TASK [fetch-output : Set log path for single node] 2026-02-14 00:49:25.285466 | rockylinux-9 | ok 2026-02-14 00:49:25.290588 | 2026-02-14 00:49:25.290663 | LOOP [fetch-output : Ensure local output dirs] 2026-02-14 00:49:25.670290 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/180d1c5b7b914c7bb88383e967d3134a/work/logs" 2026-02-14 00:49:25.881061 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/180d1c5b7b914c7bb88383e967d3134a/work/artifacts" 2026-02-14 00:49:26.108655 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/180d1c5b7b914c7bb88383e967d3134a/work/docs" 2026-02-14 00:49:26.131736 | 2026-02-14 00:49:26.131841 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-02-14 00:49:26.816187 | rockylinux-9 | changed: .d..t...... ./ 2026-02-14 00:49:26.816431 | rockylinux-9 | changed: All items complete 2026-02-14 00:49:26.816471 | 2026-02-14 00:49:27.333067 | rockylinux-9 | changed: .d..t...... ./ 2026-02-14 00:49:27.817423 | rockylinux-9 | changed: .d..t...... ./ 2026-02-14 00:49:27.841724 | 2026-02-14 00:49:27.841881 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-02-14 00:49:28.255647 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.007630 2026-02-14 00:49:28.475235 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.008136 2026-02-14 00:49:28.484383 | 2026-02-14 00:49:28.484520 | PLAY [all] 2026-02-14 00:49:28.490428 | 2026-02-14 00:49:28.490496 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-02-14 00:49:28.937420 | rockylinux-9 | changed 2026-02-14 00:49:28.945061 | 2026-02-14 00:49:28.945160 | PLAY RECAP 2026-02-14 00:49:28.945217 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-02-14 00:49:28.945247 | 2026-02-14 00:49:29.089396 | POST-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main] 2026-02-14 00:49:29.091490 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post-logs.yaml@main] 2026-02-14 00:49:29.633200 | 2026-02-14 00:49:29.633342 | PLAY [localhost] 2026-02-14 00:49:29.643505 | 2026-02-14 00:49:29.643580 | TASK [Generate Zuul manifest] 2026-02-14 00:49:29.662757 | localhost | ok 2026-02-14 00:49:29.679167 | 2026-02-14 00:49:29.679285 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-02-14 00:49:29.994603 | localhost | changed 2026-02-14 00:49:30.006537 | 2026-02-14 00:49:30.006609 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-02-14 00:49:30.038235 | localhost | ok 2026-02-14 00:49:30.046754 | 2026-02-14 00:49:30.046876 | TASK [Upload logs] 2026-02-14 00:49:30.068029 | localhost | ok 2026-02-14 00:49:30.125791 | 2026-02-14 00:49:30.125941 | TASK [Set zuul-log-path fact] 2026-02-14 00:49:30.147296 | localhost | ok 2026-02-14 00:49:30.158555 | 2026-02-14 00:49:30.158623 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-02-14 00:49:30.189528 | localhost | ok 2026-02-14 00:49:30.198251 | 2026-02-14 00:49:30.198317 | TASK [upload-logs : Create log directories] 2026-02-14 00:49:30.583621 | localhost | changed 2026-02-14 00:49:30.590834 | 2026-02-14 00:49:30.590906 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-02-14 00:49:31.002266 | localhost -> localhost | ok: Runtime: 0:00:00.004814 2026-02-14 00:49:31.008365 | 2026-02-14 00:49:31.008459 | TASK [upload-logs : Upload logs to log server] 2026-02-14 00:49:31.485633 | localhost | Output suppressed because no_log was given 2026-02-14 00:49:31.490528 | 2026-02-14 00:49:31.490657 | LOOP [upload-logs : Compress console log and json output] 2026-02-14 00:49:31.534588 | localhost | skipping: Conditional result was False 2026-02-14 00:49:31.540599 | localhost | skipping: Conditional result was False 2026-02-14 00:49:31.556777 | 2026-02-14 00:49:31.557054 | LOOP [upload-logs : Upload compressed console log and json output] 2026-02-14 00:49:31.600460 | localhost | skipping: Conditional result was False 2026-02-14 00:49:31.600893 | 2026-02-14 00:49:31.603866 | localhost | skipping: Conditional result was False 2026-02-14 00:49:31.621561 | 2026-02-14 00:49:31.621753 | LOOP [upload-logs : Upload console log and json output]