2026-04-18 16:35:16.542506 | Job console starting 2026-04-18 16:35:16.556732 | Updating git repos 2026-04-18 16:35:16.575613 | Cloning repos into workspace 2026-04-18 16:35:16.641065 | Restoring repo states 2026-04-18 16:35:16.647852 | Merging changes 2026-04-18 16:35:16.938987 | Checking out repos 2026-04-18 16:35:16.968513 | Preparing playbooks 2026-04-18 16:35:18.070902 | Running Ansible setup 2026-04-18 16:35:22.167696 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-18 16:35:22.851019 | 2026-04-18 16:35:22.851147 | PLAY [localhost] 2026-04-18 16:35:22.858438 | 2026-04-18 16:35:22.858508 | TASK [Gathering Facts] 2026-04-18 16:35:23.816238 | localhost | ok 2026-04-18 16:35:23.827191 | 2026-04-18 16:35:23.827567 | TASK [Setup log path fact] 2026-04-18 16:35:23.852903 | localhost | ok 2026-04-18 16:35:23.867882 | 2026-04-18 16:35:23.868040 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-18 16:35:23.901585 | localhost | ok 2026-04-18 16:35:23.911805 | 2026-04-18 16:35:23.911983 | TASK [emit-job-header : Print job information] 2026-04-18 16:35:23.957924 | # Job Information 2026-04-18 16:35:23.958323 | Ansible Version: 2.16.16 2026-04-18 16:35:23.958396 | Job: ansible-collection-containers-molecule-docker-rockylinux-9 2026-04-18 16:35:23.958434 | Pipeline: check 2026-04-18 16:35:23.958465 | Executor: 0a8996d2b663 2026-04-18 16:35:23.958494 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/104 2026-04-18 16:35:23.958527 | Event ID: 3b9743e0-3b44-11f1-8b36-e33dc4de3ce3 2026-04-18 16:35:23.964162 | 2026-04-18 16:35:23.964244 | LOOP [emit-job-header : Print node information] 2026-04-18 16:35:24.062575 | localhost | ok: 2026-04-18 16:35:24.063078 | localhost | # Node Information 2026-04-18 16:35:24.063136 | localhost | Inventory Hostname: rockylinux-9 2026-04-18 16:35:24.063169 | localhost | Hostname: np0000167387 2026-04-18 16:35:24.063196 | localhost | Username: zuul 2026-04-18 16:35:24.063227 | localhost | Distro: Rocky 9.7 2026-04-18 16:35:24.063254 | localhost | Provider: yul1 2026-04-18 16:35:24.063280 | localhost | Region: ca-ymq-1 2026-04-18 16:35:24.063305 | localhost | Label: rockylinux-9 2026-04-18 16:35:24.063330 | localhost | Product Name: OpenStack Nova 2026-04-18 16:35:24.063355 | localhost | Interface IP: 199.19.213.40 2026-04-18 16:35:24.080077 | 2026-04-18 16:35:24.080254 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-04-18 16:35:24.543714 | localhost -> localhost | changed 2026-04-18 16:35:24.552700 | 2026-04-18 16:35:24.552897 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-04-18 16:35:25.634447 | localhost -> localhost | changed 2026-04-18 16:35:25.642199 | 2026-04-18 16:35:25.642340 | PLAY [all] 2026-04-18 16:35:25.654710 | 2026-04-18 16:35:25.654861 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-04-18 16:35:26.036094 | rockylinux-9 -> localhost | ok 2026-04-18 16:35:26.044330 | 2026-04-18 16:35:26.044482 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-04-18 16:35:26.074821 | rockylinux-9 | ok 2026-04-18 16:35:26.098880 | rockylinux-9 | included: /var/lib/zuul/builds/94f115d4a23f4c40b60bc6f05b00df3f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-04-18 16:35:26.104853 | 2026-04-18 16:35:26.104935 | TASK [add-build-sshkey : Create Temp SSH key] 2026-04-18 16:35:27.055757 | rockylinux-9 -> localhost | Generating public/private rsa key pair. 2026-04-18 16:35:27.056193 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/94f115d4a23f4c40b60bc6f05b00df3f/work/94f115d4a23f4c40b60bc6f05b00df3f_id_rsa 2026-04-18 16:35:27.056241 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/94f115d4a23f4c40b60bc6f05b00df3f/work/94f115d4a23f4c40b60bc6f05b00df3f_id_rsa.pub 2026-04-18 16:35:27.056273 | rockylinux-9 -> localhost | The key fingerprint is: 2026-04-18 16:35:27.056304 | rockylinux-9 -> localhost | SHA256:ENtaA27Yeq9wdUXg2EPriLkb8N+kb967En62k1OD1ok zuul-build-sshkey 2026-04-18 16:35:27.056351 | rockylinux-9 -> localhost | The key's randomart image is: 2026-04-18 16:35:27.056444 | rockylinux-9 -> localhost | +---[RSA 3072]----+ 2026-04-18 16:35:27.056488 | rockylinux-9 -> localhost | | o o.. | 2026-04-18 16:35:27.056521 | rockylinux-9 -> localhost | | + = = o | 2026-04-18 16:35:27.056549 | rockylinux-9 -> localhost | | . * = = . | 2026-04-18 16:35:27.056577 | rockylinux-9 -> localhost | | o * + o | 2026-04-18 16:35:27.056604 | rockylinux-9 -> localhost | | o = S o + . | 2026-04-18 16:35:27.056713 | rockylinux-9 -> localhost | | + + .. E = | 2026-04-18 16:35:27.056751 | rockylinux-9 -> localhost | | . = ...o o . | 2026-04-18 16:35:27.056781 | rockylinux-9 -> localhost | | o = ++.* | 2026-04-18 16:35:27.056814 | rockylinux-9 -> localhost | | o o+++=* | 2026-04-18 16:35:27.056843 | rockylinux-9 -> localhost | +----[SHA256]-----+ 2026-04-18 16:35:27.056948 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.494530 2026-04-18 16:35:27.063030 | 2026-04-18 16:35:27.063112 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-04-18 16:35:27.096120 | rockylinux-9 | ok 2026-04-18 16:35:27.108322 | rockylinux-9 | included: /var/lib/zuul/builds/94f115d4a23f4c40b60bc6f05b00df3f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-04-18 16:35:27.119304 | 2026-04-18 16:35:27.119411 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-04-18 16:35:27.144390 | rockylinux-9 | skipping: Conditional result was False 2026-04-18 16:35:27.169251 | 2026-04-18 16:35:27.169388 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-04-18 16:35:27.668279 | rockylinux-9 | changed 2026-04-18 16:35:27.674278 | 2026-04-18 16:35:27.674367 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-04-18 16:35:27.933281 | rockylinux-9 | ok 2026-04-18 16:35:27.942649 | 2026-04-18 16:35:27.942734 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-04-18 16:35:28.910827 | rockylinux-9 | changed 2026-04-18 16:35:28.917725 | 2026-04-18 16:35:28.917850 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-04-18 16:35:29.852714 | rockylinux-9 | changed 2026-04-18 16:35:29.857171 | 2026-04-18 16:35:29.857234 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-04-18 16:35:29.880738 | rockylinux-9 | skipping: Conditional result was False 2026-04-18 16:35:29.890619 | 2026-04-18 16:35:29.890683 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-04-18 16:35:31.695898 | rockylinux-9 -> localhost | changed 2026-04-18 16:35:31.714176 | 2026-04-18 16:35:31.714294 | TASK [add-build-sshkey : Add back temp key] 2026-04-18 16:35:31.971270 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/94f115d4a23f4c40b60bc6f05b00df3f/work/94f115d4a23f4c40b60bc6f05b00df3f_id_rsa (zuul-build-sshkey) 2026-04-18 16:35:31.971450 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.012889 2026-04-18 16:35:31.978092 | 2026-04-18 16:35:31.978157 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-04-18 16:35:32.394162 | rockylinux-9 | ok 2026-04-18 16:35:32.398733 | 2026-04-18 16:35:32.398799 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-04-18 16:35:32.433147 | rockylinux-9 | skipping: Conditional result was False 2026-04-18 16:35:32.451266 | 2026-04-18 16:35:32.451404 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-04-18 16:35:32.859062 | rockylinux-9 | ok 2026-04-18 16:35:32.867045 | 2026-04-18 16:35:32.867139 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-04-18 16:35:33.791233 | rockylinux-9 | Output suppressed because no_log was given 2026-04-18 16:35:33.799262 | 2026-04-18 16:35:33.799326 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-04-18 16:35:34.070466 | rockylinux-9 | ok: "logs" 2026-04-18 16:35:34.070691 | rockylinux-9 | ok: All items complete 2026-04-18 16:35:34.070722 | 2026-04-18 16:35:34.285168 | rockylinux-9 | ok: "artifacts" 2026-04-18 16:35:34.519805 | rockylinux-9 | ok: "docs" 2026-04-18 16:35:34.534503 | 2026-04-18 16:35:34.534840 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-04-18 16:35:34.798890 | rockylinux-9 | changed: "logs" 2026-04-18 16:35:35.036583 | rockylinux-9 | changed: "artifacts" 2026-04-18 16:35:35.263137 | rockylinux-9 | changed: "docs" 2026-04-18 16:35:38.526488 | 2026-04-18 16:35:38.526646 | PLAY RECAP 2026-04-18 16:35:38.526717 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-18 16:35:38.526764 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-04-18 16:35:38.526792 | 2026-04-18 16:35:38.680448 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-18 16:35:38.686063 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-18 16:35:39.294161 | 2026-04-18 16:35:39.294289 | PLAY [all] 2026-04-18 16:35:39.305644 | 2026-04-18 16:35:39.305772 | TASK [setup-uv : Extract archive] 2026-04-18 16:35:42.235932 | rockylinux-9 | changed 2026-04-18 16:35:42.242023 | 2026-04-18 16:35:42.242135 | TASK [setup-uv : Print version] 2026-04-18 16:35:42.269729 | [rockylinux-9] Waiting on logger 2026-04-18 16:35:42.785158 | rockylinux-9 | ok: Runtime: 0:00:00.014222 2026-04-18 16:35:43.448867 | 2026-04-18 16:35:43.448984 | PLAY RECAP 2026-04-18 16:35:43.449059 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-18 16:35:43.449091 | 2026-04-18 16:35:43.555961 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-18 16:35:43.558536 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-18 16:35:44.151709 | 2026-04-18 16:35:44.151857 | PLAY [all] 2026-04-18 16:35:44.163162 | 2026-04-18 16:35:44.163296 | TASK [Copy inventory file for Zuul] 2026-04-18 16:35:45.484905 | rockylinux-9 | changed 2026-04-18 16:35:45.489905 | 2026-04-18 16:35:45.490024 | TASK [Switch "ansible_host" to private IP] 2026-04-18 16:35:45.843387 | rockylinux-9 | changed: 1 replacements made 2026-04-18 16:35:45.848323 | 2026-04-18 16:35:45.848387 | TASK [Run Molecule scenario] 2026-04-18 16:35:45.887489 | [rockylinux-9] Waiting on logger 2026-04-18 16:35:54.025736 | [rockylinux-9] Waiting on logger 2026-04-18 16:36:04.197618 | [rockylinux-9] Waiting on logger 2026-04-18 16:36:13.355750 | [rockylinux-9] Waiting on logger 2026-04-18 16:36:23.590011 | [rockylinux-9] Waiting on logger 2026-04-18 16:36:33.765804 | [rockylinux-9] Waiting on logger 2026-04-18 16:36:43.657848 | [rockylinux-9] Waiting on logger 2026-04-18 16:36:54.053826 | [rockylinux-9] Waiting on logger 2026-04-18 16:37:04.229921 | [rockylinux-9] Waiting on logger 2026-04-18 16:37:13.387042 | [rockylinux-9] Waiting on logger 2026-04-18 16:37:23.401742 | [rockylinux-9] Waiting on logger 2026-04-18 16:37:33.573853 | [rockylinux-9] Waiting on logger 2026-04-18 16:37:43.749918 | [rockylinux-9] Waiting on logger 2026-04-18 16:37:53.541892 | [rockylinux-9] Waiting on logger 2026-04-18 16:37:58.157985 | rockylinux-9 | ok: Runtime: 0:02:11.451319 2026-04-18 16:37:58.162857 | 2026-04-18 16:37:58.162908 | PLAY RECAP 2026-04-18 16:37:58.162952 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-18 16:37:58.162976 | 2026-04-18 16:37:58.251361 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-18 16:37:58.253710 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-18 16:37:58.840361 | 2026-04-18 16:37:58.887120 | PLAY [all] 2026-04-18 16:37:58.903038 | 2026-04-18 16:37:58.903141 | TASK [fetch-output : Set log path for multiple nodes] 2026-04-18 16:37:58.948037 | rockylinux-9 | skipping: Conditional result was False 2026-04-18 16:37:59.159887 | 2026-04-18 16:37:59.165017 | TASK [fetch-output : Set log path for single node] 2026-04-18 16:37:59.210997 | rockylinux-9 | ok 2026-04-18 16:37:59.215719 | 2026-04-18 16:37:59.215816 | LOOP [fetch-output : Ensure local output dirs] 2026-04-18 16:37:59.810239 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/94f115d4a23f4c40b60bc6f05b00df3f/work/logs" 2026-04-18 16:38:00.035807 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/94f115d4a23f4c40b60bc6f05b00df3f/work/artifacts" 2026-04-18 16:38:00.254273 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/94f115d4a23f4c40b60bc6f05b00df3f/work/docs" 2026-04-18 16:38:00.275912 | 2026-04-18 16:38:00.278040 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-04-18 16:38:00.979852 | rockylinux-9 | changed: .d..t...... ./ 2026-04-18 16:38:00.980068 | rockylinux-9 | changed: All items complete 2026-04-18 16:38:00.980099 | 2026-04-18 16:38:01.509858 | rockylinux-9 | changed: .d..t...... ./ 2026-04-18 16:38:02.051278 | rockylinux-9 | changed: .d..t...... ./ 2026-04-18 16:38:02.078434 | 2026-04-18 16:38:02.078613 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-04-18 16:38:02.545726 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.009733 2026-04-18 16:38:03.160869 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.336602 2026-04-18 16:38:03.171055 | 2026-04-18 16:38:03.171198 | PLAY [all] 2026-04-18 16:38:03.177195 | 2026-04-18 16:38:03.177268 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-04-18 16:38:03.692330 | rockylinux-9 | changed 2026-04-18 16:38:05.624818 | 2026-04-18 16:38:05.624916 | PLAY RECAP 2026-04-18 16:38:05.624978 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-04-18 16:38:05.625004 | 2026-04-18 16:38:05.798016 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-18 16:38:05.800406 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-04-18 16:38:06.423473 | 2026-04-18 16:38:06.423611 | PLAY [localhost] 2026-04-18 16:38:06.433736 | 2026-04-18 16:38:06.433828 | TASK [Generate Zuul manifest] 2026-04-18 16:38:06.454421 | localhost | ok 2026-04-18 16:38:06.471723 | 2026-04-18 16:38:06.471847 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-04-18 16:38:07.490137 | localhost | changed 2026-04-18 16:38:07.506761 | 2026-04-18 16:38:07.506934 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-04-18 16:38:07.537099 | localhost | ok 2026-04-18 16:38:07.546801 | 2026-04-18 16:38:07.546924 | TASK [Upload logs] 2026-04-18 16:38:07.569703 | localhost | ok 2026-04-18 16:38:07.653922 | 2026-04-18 16:38:07.654061 | TASK [Set zuul-log-path fact] 2026-04-18 16:38:07.683031 | localhost | ok 2026-04-18 16:38:07.694389 | 2026-04-18 16:38:07.694508 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-18 16:38:07.724210 | localhost | ok 2026-04-18 16:38:07.735115 | 2026-04-18 16:38:07.735209 | TASK [upload-logs : Create log directories] 2026-04-18 16:38:08.573705 | localhost | changed 2026-04-18 16:38:08.580061 | 2026-04-18 16:38:08.580208 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-04-18 16:38:09.179794 | localhost -> localhost | ok: Runtime: 0:00:00.008965 2026-04-18 16:38:09.252319 | 2026-04-18 16:38:09.252447 | TASK [upload-logs : Upload logs to log server] 2026-04-18 16:38:09.797157 | localhost | Output suppressed because no_log was given 2026-04-18 16:38:09.800944 | 2026-04-18 16:38:09.801150 | LOOP [upload-logs : Compress console log and json output] 2026-04-18 16:38:09.850870 | localhost | skipping: Conditional result was False 2026-04-18 16:38:09.857852 | localhost | skipping: Conditional result was False 2026-04-18 16:38:09.871642 | 2026-04-18 16:38:09.871752 | LOOP [upload-logs : Upload compressed console log and json output] 2026-04-18 16:38:09.917553 | localhost | skipping: Conditional result was False 2026-04-18 16:38:09.918306 | 2026-04-18 16:38:09.925218 | localhost | skipping: Conditional result was False 2026-04-18 16:38:09.930579 | 2026-04-18 16:38:09.930697 | LOOP [upload-logs : Upload console log and json output]