2026-04-04 10:06:42.960209 | Job console starting
2026-04-04 10:06:43.366334 | Updating git repos
2026-04-04 10:06:43.498284 | Cloning repos into workspace
2026-04-04 10:06:44.935055 | Restoring repo states
2026-04-04 10:06:44.948189 | Merging changes
2026-04-04 10:06:45.984062 | Checking out repos
2026-04-04 10:06:46.536021 | Preparing playbooks
2026-04-04 10:06:57.086968 | Running Ansible setup
2026-04-04 10:07:01.494226 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-04 10:07:02.079448 | 
2026-04-04 10:07:02.079571 | PLAY [localhost]
2026-04-04 10:07:02.087232 | 
2026-04-04 10:07:02.087306 | TASK [Gathering Facts]
2026-04-04 10:07:03.058400 | localhost | ok
2026-04-04 10:07:03.067851 | 
2026-04-04 10:07:03.067958 | TASK [Setup log path fact]
2026-04-04 10:07:03.090266 | localhost | ok
2026-04-04 10:07:03.103966 | 
2026-04-04 10:07:03.104047 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-04 10:07:03.134709 | localhost | ok
2026-04-04 10:07:03.181373 | 
2026-04-04 10:07:03.181480 | TASK [emit-job-header : Print job information]
2026-04-04 10:07:03.223424 | # Job Information
2026-04-04 10:07:03.358075 | Ansible Version: 2.16.16
2026-04-04 10:07:03.358235 | Job: ansible-collection-containers-molecule-containerd-rockylinux-9
2026-04-04 10:07:03.358293 | Pipeline: check
2026-04-04 10:07:03.358339 | Executor: 0a8996d2b663
2026-04-04 10:07:03.358383 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/106
2026-04-04 10:07:03.358430 | Event ID: f6253a90-2fbf-11f1-9abc-054f17db168c
2026-04-04 10:07:03.363551 | 
2026-04-04 10:07:03.363649 | LOOP [emit-job-header : Print node information]
2026-04-04 10:07:03.464316 | localhost | ok:
2026-04-04 10:07:03.464687 | localhost | # Node Information
2026-04-04 10:07:03.464738 | localhost | Inventory Hostname: rockylinux-9
2026-04-04 10:07:03.464768 | localhost | Hostname: np0000163485
2026-04-04 10:07:03.464790 | localhost | Username: zuul
2026-04-04 10:07:03.464814 | localhost | Distro: Rocky 9.7
2026-04-04 10:07:03.464834 | localhost | Provider: yul1
2026-04-04 10:07:03.464852 | localhost | Region: ca-ymq-1
2026-04-04 10:07:03.464871 | localhost | Label: rockylinux-9
2026-04-04 10:07:03.464889 | localhost | Product Name: OpenStack Nova
2026-04-04 10:07:03.464907 | localhost | Interface IP: 199.204.45.138
2026-04-04 10:07:03.480068 | 
2026-04-04 10:07:03.542696 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-04-04 10:07:03.975932 | localhost -> localhost | changed
2026-04-04 10:07:03.982827 | 
2026-04-04 10:07:03.982922 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-04-04 10:07:05.609920 | localhost -> localhost | changed
2026-04-04 10:07:05.616594 | 
2026-04-04 10:07:05.616653 | PLAY [all]
2026-04-04 10:07:05.626557 | 
2026-04-04 10:07:05.626634 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-04-04 10:07:05.979192 | rockylinux-9 -> localhost | ok
2026-04-04 10:07:05.986559 | 
2026-04-04 10:07:05.986656 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-04-04 10:07:06.020436 | rockylinux-9 | ok
2026-04-04 10:07:06.047292 | rockylinux-9 | included: /var/lib/zuul/builds/773fc833ae03495b9b8d8719e152800e/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-04-04 10:07:06.053191 | 
2026-04-04 10:07:06.053260 | TASK [add-build-sshkey : Create Temp SSH key]
2026-04-04 10:07:06.824841 | rockylinux-9 -> localhost | Generating public/private rsa key pair.
2026-04-04 10:07:06.825444 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/773fc833ae03495b9b8d8719e152800e/work/773fc833ae03495b9b8d8719e152800e_id_rsa
2026-04-04 10:07:06.825486 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/773fc833ae03495b9b8d8719e152800e/work/773fc833ae03495b9b8d8719e152800e_id_rsa.pub
2026-04-04 10:07:06.825511 | rockylinux-9 -> localhost | The key fingerprint is:
2026-04-04 10:07:06.825534 | rockylinux-9 -> localhost | SHA256:N9o3GIzygWV54zYt3cHXDEP0jRyWypD0VTlwfJ+o3Pk zuul-build-sshkey
2026-04-04 10:07:06.825567 | rockylinux-9 -> localhost | The key's randomart image is:
2026-04-04 10:07:06.825590 | rockylinux-9 -> localhost | +---[RSA 3072]----+
2026-04-04 10:07:06.825617 | rockylinux-9 -> localhost | |         ... +@+o|
2026-04-04 10:07:06.825639 | rockylinux-9 -> localhost | |         .o. =o@+|
2026-04-04 10:07:06.825660 | rockylinux-9 -> localhost | |        + oo..* @|
2026-04-04 10:07:06.825680 | rockylinux-9 -> localhost | |       + = +oo +.|
2026-04-04 10:07:06.825701 | rockylinux-9 -> localhost | |      o S @ = o  |
2026-04-04 10:07:06.825721 | rockylinux-9 -> localhost | |       o * O o   |
2026-04-04 10:07:06.825741 | rockylinux-9 -> localhost | |        o o o .  |
2026-04-04 10:07:06.825762 | rockylinux-9 -> localhost | |           . . E |
2026-04-04 10:07:06.825785 | rockylinux-9 -> localhost | |                 |
2026-04-04 10:07:06.825805 | rockylinux-9 -> localhost | +----[SHA256]-----+
2026-04-04 10:07:06.825875 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.310102
2026-04-04 10:07:06.830974 | 
2026-04-04 10:07:06.831068 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-04-04 10:07:06.861886 | rockylinux-9 | ok
2026-04-04 10:07:06.874349 | rockylinux-9 | included: /var/lib/zuul/builds/773fc833ae03495b9b8d8719e152800e/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-04-04 10:07:06.888445 | 
2026-04-04 10:07:06.888618 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-04-04 10:07:06.913724 | rockylinux-9 | skipping: Conditional result was False
2026-04-04 10:07:06.987418 | 
2026-04-04 10:07:06.987602 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-04-04 10:07:07.474672 | rockylinux-9 | changed
2026-04-04 10:07:07.479944 | 
2026-04-04 10:07:07.480018 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-04-04 10:07:07.728052 | rockylinux-9 | ok
2026-04-04 10:07:07.732902 | 
2026-04-04 10:07:07.732991 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-04-04 10:07:08.653377 | rockylinux-9 | changed
2026-04-04 10:07:08.662014 | 
2026-04-04 10:07:08.691723 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-04-04 10:07:09.578360 | rockylinux-9 | changed
2026-04-04 10:07:09.586084 | 
2026-04-04 10:07:09.586153 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-04-04 10:07:09.611791 | rockylinux-9 | skipping: Conditional result was False
2026-04-04 10:07:09.623989 | 
2026-04-04 10:07:09.624096 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-04-04 10:07:10.035573 | rockylinux-9 -> localhost | changed
2026-04-04 10:07:10.052819 | 
2026-04-04 10:07:10.052903 | TASK [add-build-sshkey : Add back temp key]
2026-04-04 10:07:10.550326 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/773fc833ae03495b9b8d8719e152800e/work/773fc833ae03495b9b8d8719e152800e_id_rsa (zuul-build-sshkey)
2026-04-04 10:07:10.550532 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.025435
2026-04-04 10:07:10.557039 | 
2026-04-04 10:07:10.557104 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-04-04 10:07:10.944039 | rockylinux-9 | ok
2026-04-04 10:07:10.993025 | 
2026-04-04 10:07:10.993143 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-04-04 10:07:11.018566 | rockylinux-9 | skipping: Conditional result was False
2026-04-04 10:07:11.055593 | 
2026-04-04 10:07:11.055708 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-04-04 10:07:11.438445 | rockylinux-9 | ok
2026-04-04 10:07:11.578075 | 
2026-04-04 10:07:11.578202 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-04-04 10:07:16.785093 | rockylinux-9 | Output suppressed because no_log was given
2026-04-04 10:07:16.794451 | 
2026-04-04 10:07:16.794533 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-04-04 10:07:17.047953 | rockylinux-9 | ok: "logs"
2026-04-04 10:07:17.048324 | rockylinux-9 | ok: All items complete
2026-04-04 10:07:17.048367 | 
2026-04-04 10:07:17.278857 | rockylinux-9 | ok: "artifacts"
2026-04-04 10:07:17.497738 | rockylinux-9 | ok: "docs"
2026-04-04 10:07:17.521110 | 
2026-04-04 10:07:17.521295 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-04-04 10:07:17.768916 | rockylinux-9 | changed: "logs"
2026-04-04 10:07:17.984036 | rockylinux-9 | changed: "artifacts"
2026-04-04 10:07:18.200593 | rockylinux-9 | changed: "docs"
2026-04-04 10:07:18.225467 | 
2026-04-04 10:07:18.225696 | PLAY RECAP
2026-04-04 10:07:18.225806 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-04 10:07:18.225880 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-04-04 10:07:18.225928 | 
2026-04-04 10:07:18.359069 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-04 10:07:18.449309 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-04 10:07:19.068467 | 
2026-04-04 10:07:19.068615 | PLAY [all]
2026-04-04 10:07:19.079774 | 
2026-04-04 10:07:19.079853 | TASK [setup-uv : Extract archive]
2026-04-04 10:07:21.736004 | rockylinux-9 | changed
2026-04-04 10:07:21.766566 | 
2026-04-04 10:07:21.766754 | TASK [setup-uv : Print version]
2026-04-04 10:07:21.794612 | [rockylinux-9] Waiting on logger
2026-04-04 10:07:22.307591 | rockylinux-9 | ok: Runtime: 0:00:00.019154
2026-04-04 10:07:22.405394 | 
2026-04-04 10:07:22.405485 | PLAY RECAP
2026-04-04 10:07:22.405537 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-04 10:07:22.405566 | 
2026-04-04 10:07:22.621406 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-04 10:07:22.628324 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-04-04 10:07:23.213351 | 
2026-04-04 10:07:23.213507 | PLAY [all]
2026-04-04 10:07:23.224117 | 
2026-04-04 10:07:23.224203 | TASK [Copy inventory file for Zuul]
2026-04-04 10:07:24.602067 | rockylinux-9 | changed
2026-04-04 10:07:24.607169 | 
2026-04-04 10:07:24.607255 | TASK [Switch "ansible_host" to private IP]
2026-04-04 10:07:24.951086 | rockylinux-9 | changed: 1 replacements made
2026-04-04 10:07:24.960872 | 
2026-04-04 10:07:24.961126 | TASK [Run Molecule scenario]
2026-04-04 10:07:24.983129 | [rockylinux-9] Waiting on logger
2026-04-04 10:07:33.125969 | [rockylinux-9] Waiting on logger
2026-04-04 10:07:43.302034 | [rockylinux-9] Waiting on logger
2026-04-04 10:07:53.542052 | [rockylinux-9] Waiting on logger
2026-04-04 10:07:57.005508 | rockylinux-9 | ERROR
2026-04-04 10:07:57.020697 | rockylinux-9 | {
2026-04-04 10:07:57.020912 | rockylinux-9 |   "delta": "0:00:31.270541",
2026-04-04 10:07:57.021112 | rockylinux-9 |   "end": "2026-04-04 10:07:56.984706",
2026-04-04 10:07:57.021186 | rockylinux-9 |   "msg": "non-zero return code",
2026-04-04 10:07:57.021238 | rockylinux-9 |   "rc": 2,
2026-04-04 10:07:57.021288 | rockylinux-9 |   "start": "2026-04-04 10:07:25.714165"
2026-04-04 10:07:57.021331 | rockylinux-9 | }
failure
2026-04-04 10:07:57.027702 | 
2026-04-04 10:07:57.028612 | PLAY RECAP
2026-04-04 10:07:57.028677 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-04-04 10:07:57.028750 | 
2026-04-04 10:07:57.251095 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-04-04 10:07:57.252618 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-04 10:07:58.143739 | 
2026-04-04 10:07:58.143960 | PLAY [all]
2026-04-04 10:07:58.168281 | 
2026-04-04 10:07:58.168481 | TASK [fetch-output : Set log path for multiple nodes]
2026-04-04 10:07:58.232004 | rockylinux-9 | skipping: Conditional result was False
2026-04-04 10:07:58.237682 | 
2026-04-04 10:07:58.237770 | TASK [fetch-output : Set log path for single node]
2026-04-04 10:07:58.294673 | rockylinux-9 | ok
2026-04-04 10:07:58.306199 | 
2026-04-04 10:07:58.306289 | LOOP [fetch-output : Ensure local output dirs]
2026-04-04 10:07:58.833725 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/773fc833ae03495b9b8d8719e152800e/work/logs"
2026-04-04 10:07:59.073967 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/773fc833ae03495b9b8d8719e152800e/work/artifacts"
2026-04-04 10:07:59.308784 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/773fc833ae03495b9b8d8719e152800e/work/docs"
2026-04-04 10:07:59.323934 | 
2026-04-04 10:07:59.324077 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-04-04 10:08:00.105196 | rockylinux-9 | ok
2026-04-04 10:08:00.186084 | rockylinux-9 | changed: All items complete
2026-04-04 10:08:00.186245 | 
2026-04-04 10:08:00.785023 | rockylinux-9 | changed: .d..t...... ./
2026-04-04 10:08:01.349962 | rockylinux-9 | changed: .d..t...... ./
2026-04-04 10:08:01.364804 | 
2026-04-04 10:08:01.364971 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-04-04 10:08:02.135245 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.023300
2026-04-04 10:08:02.382645 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.009400
2026-04-04 10:08:02.395232 | 
2026-04-04 10:08:02.395372 | PLAY [all]
2026-04-04 10:08:02.410278 | 
2026-04-04 10:08:02.410471 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-04-04 10:08:02.906284 | rockylinux-9 | changed
2026-04-04 10:08:02.982078 | 
2026-04-04 10:08:02.982172 | PLAY RECAP
2026-04-04 10:08:02.982223 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-04-04 10:08:02.982247 | 
2026-04-04 10:08:03.091977 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-04 10:08:03.094299 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-04-04 10:08:03.935789 | 
2026-04-04 10:08:03.976443 | PLAY [localhost]
2026-04-04 10:08:03.999628 | 
2026-04-04 10:08:04.000010 | TASK [Generate Zuul manifest]
2026-04-04 10:08:04.021169 | localhost | ok
2026-04-04 10:08:04.150061 | 
2026-04-04 10:08:04.151792 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-04-04 10:08:04.722669 | localhost | changed
2026-04-04 10:08:04.734706 | 
2026-04-04 10:08:04.734802 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-04-04 10:08:04.779654 | localhost | ok
2026-04-04 10:08:04.788738 | 
2026-04-04 10:08:04.788806 | TASK [Upload logs]
2026-04-04 10:08:04.821671 | localhost | ok
2026-04-04 10:08:04.948276 | 
2026-04-04 10:08:04.948401 | TASK [Set zuul-log-path fact]
2026-04-04 10:08:04.980219 | localhost | ok
2026-04-04 10:08:04.995741 | 
2026-04-04 10:08:04.995861 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-04 10:08:05.039408 | localhost | ok
2026-04-04 10:08:05.057878 | 
2026-04-04 10:08:05.058247 | TASK [upload-logs : Create log directories]
2026-04-04 10:08:05.513276 | localhost | changed
2026-04-04 10:08:05.521742 | 
2026-04-04 10:08:05.521819 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-04-04 10:08:06.190303 | localhost -> localhost | ok: Runtime: 0:00:00.004576
2026-04-04 10:08:06.195238 | 
2026-04-04 10:08:06.195302 | TASK [upload-logs : Upload logs to log server]
2026-04-04 10:08:06.795456 | localhost | Output suppressed because no_log was given
2026-04-04 10:08:06.811420 | 
2026-04-04 10:08:06.811671 | LOOP [upload-logs : Compress console log and json output]
2026-04-04 10:08:06.870408 | localhost | skipping: Conditional result was False
2026-04-04 10:08:06.880557 | localhost | skipping: Conditional result was False
2026-04-04 10:08:06.886361 | 
2026-04-04 10:08:06.886533 | LOOP [upload-logs : Upload compressed console log and json output]
2026-04-04 10:08:06.953435 | localhost | skipping: Conditional result was False
2026-04-04 10:08:07.063703 | 
2026-04-04 10:08:07.065792 | localhost | skipping: Conditional result was False
2026-04-04 10:08:07.074211 | 
2026-04-04 10:08:07.074382 | LOOP [upload-logs : Upload console log and json output]