2026-04-09 16:00:19.569431 | Job console starting
2026-04-09 16:00:19.583706 | Updating git repos
2026-04-09 16:00:19.607775 | Cloning repos into workspace
2026-04-09 16:00:19.665821 | Restoring repo states
2026-04-09 16:00:19.673534 | Merging changes
2026-04-09 16:00:19.941459 | Checking out repos
2026-04-09 16:00:19.985560 | Preparing playbooks
2026-04-09 16:00:21.561635 | Running Ansible setup
2026-04-09 16:00:25.696475 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-09 16:00:26.337424 | 
2026-04-09 16:00:26.337577 | PLAY [localhost]
2026-04-09 16:00:26.345673 | 
2026-04-09 16:00:26.345937 | TASK [Gathering Facts]
2026-04-09 16:00:27.384410 | localhost | ok
2026-04-09 16:00:27.391271 | 
2026-04-09 16:00:27.391349 | TASK [Setup log path fact]
2026-04-09 16:00:27.412186 | localhost | ok
2026-04-09 16:00:27.424924 | 
2026-04-09 16:00:27.425077 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-09 16:00:27.472950 | localhost | ok
2026-04-09 16:00:27.479206 | 
2026-04-09 16:00:27.479283 | TASK [emit-job-header : Print job information]
2026-04-09 16:00:27.521931 | # Job Information
2026-04-09 16:00:27.522150 | Ansible Version: 2.16.16
2026-04-09 16:00:27.522196 | Job: ansible-collection-containers-molecule-docker-rockylinux-9
2026-04-09 16:00:27.522228 | Pipeline: check
2026-04-09 16:00:27.522257 | Executor: 0a8996d2b663
2026-04-09 16:00:27.522285 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/93
2026-04-09 16:00:27.522315 | Event ID: 592c8010-342c-11f1-8e5e-2b2d0b84186e
2026-04-09 16:00:27.526564 | 
2026-04-09 16:00:27.526662 | LOOP [emit-job-header : Print node information]
2026-04-09 16:00:27.629068 | localhost | ok:
2026-04-09 16:00:27.629241 | localhost | # Node Information
2026-04-09 16:00:27.629269 | localhost | Inventory Hostname: rockylinux-9
2026-04-09 16:00:27.629290 | localhost | Hostname: np0000164572
2026-04-09 16:00:27.629310 | localhost | Username: zuul
2026-04-09 16:00:27.629332 | localhost | Distro: Rocky 9.7
2026-04-09 16:00:27.629352 | localhost | Provider: yul1
2026-04-09 16:00:27.629370 | localhost | Region: ca-ymq-1
2026-04-09 16:00:27.629388 | localhost | Label: rockylinux-9
2026-04-09 16:00:27.629407 | localhost | Product Name: OpenStack Nova
2026-04-09 16:00:27.629428 | localhost | Interface IP: 199.19.213.175
2026-04-09 16:00:27.640091 | 
2026-04-09 16:00:27.640234 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-04-09 16:00:28.361560 | localhost -> localhost | changed
2026-04-09 16:00:28.367775 | 
2026-04-09 16:00:28.367853 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-04-09 16:00:29.558754 | localhost -> localhost | changed
2026-04-09 16:00:29.565128 | 
2026-04-09 16:00:29.565210 | PLAY [all]
2026-04-09 16:00:29.573265 | 
2026-04-09 16:00:29.573369 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-04-09 16:00:29.903202 | rockylinux-9 -> localhost | ok
2026-04-09 16:00:29.912888 | 
2026-04-09 16:00:29.912974 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-04-09 16:00:29.947356 | rockylinux-9 | ok
2026-04-09 16:00:29.962919 | rockylinux-9 | included: /var/lib/zuul/builds/c76b36f676094a7bb2707f50168e9d89/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-04-09 16:00:29.968088 | 
2026-04-09 16:00:29.968146 | TASK [add-build-sshkey : Create Temp SSH key]
2026-04-09 16:00:32.635729 | rockylinux-9 -> localhost | Generating public/private rsa key pair.
2026-04-09 16:00:32.636028 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/c76b36f676094a7bb2707f50168e9d89/work/c76b36f676094a7bb2707f50168e9d89_id_rsa
2026-04-09 16:00:32.636070 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/c76b36f676094a7bb2707f50168e9d89/work/c76b36f676094a7bb2707f50168e9d89_id_rsa.pub
2026-04-09 16:00:32.636095 | rockylinux-9 -> localhost | The key fingerprint is:
2026-04-09 16:00:32.636117 | rockylinux-9 -> localhost | SHA256:ANlc/6th7qC2+Cf+7PO78DZBf6eqnNXdcUoqJjkIIdo zuul-build-sshkey
2026-04-09 16:00:32.636151 | rockylinux-9 -> localhost | The key's randomart image is:
2026-04-09 16:00:32.636173 | rockylinux-9 -> localhost | +---[RSA 3072]----+
2026-04-09 16:00:32.636198 | rockylinux-9 -> localhost | |    .+ ..        |
2026-04-09 16:00:32.636220 | rockylinux-9 -> localhost | |    ..o  .       |
2026-04-09 16:00:32.636240 | rockylinux-9 -> localhost | |  . . .   .      |
2026-04-09 16:00:32.636260 | rockylinux-9 -> localhost | | o . . .  ..     |
2026-04-09 16:00:32.636280 | rockylinux-9 -> localhost | |. E .   S. .. ...|
2026-04-09 16:00:32.636299 | rockylinux-9 -> localhost | |     . . .. .=.o=|
2026-04-09 16:00:32.636319 | rockylinux-9 -> localhost | |      . * =.+.ooo|
2026-04-09 16:00:32.636339 | rockylinux-9 -> localhost | |     .ooo@o*  .  |
2026-04-09 16:00:32.636362 | rockylinux-9 -> localhost | |    .+=*+=%=..   |
2026-04-09 16:00:32.636382 | rockylinux-9 -> localhost | +----[SHA256]-----+
2026-04-09 16:00:32.636432 | rockylinux-9 -> localhost | ok: Runtime: 0:00:02.185452
2026-04-09 16:00:32.642093 | 
2026-04-09 16:00:32.642168 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-04-09 16:00:32.673971 | rockylinux-9 | ok
2026-04-09 16:00:32.686870 | rockylinux-9 | included: /var/lib/zuul/builds/c76b36f676094a7bb2707f50168e9d89/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-04-09 16:00:32.695707 | 
2026-04-09 16:00:32.695780 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-04-09 16:00:32.709976 | rockylinux-9 | skipping: Conditional result was False
2026-04-09 16:00:32.716564 | 
2026-04-09 16:00:32.716652 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-04-09 16:00:33.210432 | rockylinux-9 | changed
2026-04-09 16:00:33.215334 | 
2026-04-09 16:00:33.215415 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-04-09 16:00:33.476544 | rockylinux-9 | ok
2026-04-09 16:00:33.481076 | 
2026-04-09 16:00:33.481144 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-04-09 16:00:34.539345 | rockylinux-9 | changed
2026-04-09 16:00:34.544465 | 
2026-04-09 16:00:34.544530 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-04-09 16:00:35.462709 | rockylinux-9 | changed
2026-04-09 16:00:35.595217 | 
2026-04-09 16:00:35.595388 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-04-09 16:00:35.621309 | rockylinux-9 | skipping: Conditional result was False
2026-04-09 16:00:35.627493 | 
2026-04-09 16:00:35.627620 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-04-09 16:00:36.079251 | rockylinux-9 -> localhost | changed
2026-04-09 16:00:36.090130 | 
2026-04-09 16:00:36.090248 | TASK [add-build-sshkey : Add back temp key]
2026-04-09 16:00:36.421694 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/c76b36f676094a7bb2707f50168e9d89/work/c76b36f676094a7bb2707f50168e9d89_id_rsa (zuul-build-sshkey)
2026-04-09 16:00:36.421875 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.015624
2026-04-09 16:00:36.432702 | 
2026-04-09 16:00:36.432834 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-04-09 16:00:36.888313 | rockylinux-9 | ok
2026-04-09 16:00:36.892586 | 
2026-04-09 16:00:36.892683 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-04-09 16:00:36.918293 | rockylinux-9 | skipping: Conditional result was False
2026-04-09 16:00:36.931201 | 
2026-04-09 16:00:36.931293 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-04-09 16:00:37.338009 | rockylinux-9 | ok
2026-04-09 16:00:37.343431 | 
2026-04-09 16:00:37.343496 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-04-09 16:00:38.246772 | rockylinux-9 | Output suppressed because no_log was given
2026-04-09 16:00:38.256116 | 
2026-04-09 16:00:38.256187 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-04-09 16:00:38.498334 | rockylinux-9 | ok: "logs"
2026-04-09 16:00:38.674903 | rockylinux-9 | ok: All items complete
2026-04-09 16:00:38.675071 | 
2026-04-09 16:00:38.721514 | rockylinux-9 | ok: "artifacts"
2026-04-09 16:00:38.937748 | rockylinux-9 | ok: "docs"
2026-04-09 16:00:38.953383 | 
2026-04-09 16:00:38.953598 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-04-09 16:00:39.198497 | rockylinux-9 | changed: "logs"
2026-04-09 16:00:39.414346 | rockylinux-9 | changed: "artifacts"
2026-04-09 16:00:39.633139 | rockylinux-9 | changed: "docs"
2026-04-09 16:00:39.647114 | 
2026-04-09 16:00:39.647231 | PLAY RECAP
2026-04-09 16:00:39.647291 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-09 16:00:39.647334 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-04-09 16:00:39.647366 | 
2026-04-09 16:00:39.777387 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-09 16:00:39.778975 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-09 16:00:40.350963 | 
2026-04-09 16:00:40.351078 | PLAY [all]
2026-04-09 16:00:40.361577 | 
2026-04-09 16:00:40.361666 | TASK [setup-uv : Extract archive]
2026-04-09 16:00:43.067971 | rockylinux-9 | changed
2026-04-09 16:00:43.078672 | 
2026-04-09 16:00:43.078894 | TASK [setup-uv : Print version]
2026-04-09 16:00:43.105919 | [rockylinux-9] Waiting on logger
2026-04-09 16:00:43.620416 | rockylinux-9 | ok: Runtime: 0:00:00.015345
2026-04-09 16:00:43.626927 | 
2026-04-09 16:00:43.626991 | PLAY RECAP
2026-04-09 16:00:43.627036 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-09 16:00:43.627060 | 
2026-04-09 16:00:43.749103 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-09 16:00:43.751364 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-04-09 16:00:44.449491 | 
2026-04-09 16:00:44.449613 | PLAY [all]
2026-04-09 16:00:44.459895 | 
2026-04-09 16:00:44.459998 | TASK [Copy inventory file for Zuul]
2026-04-09 16:00:45.811965 | rockylinux-9 | changed
2026-04-09 16:00:45.816590 | 
2026-04-09 16:00:45.816667 | TASK [Switch "ansible_host" to private IP]
2026-04-09 16:00:46.191955 | rockylinux-9 | changed: 1 replacements made
2026-04-09 16:00:46.197451 | 
2026-04-09 16:00:46.197530 | TASK [Run Molecule scenario]
2026-04-09 16:00:46.219522 | [rockylinux-9] Waiting on logger
2026-04-09 16:00:54.342208 | [rockylinux-9] Waiting on logger
2026-04-09 16:01:04.521992 | [rockylinux-9] Waiting on logger
2026-04-09 16:01:14.693927 | [rockylinux-9] Waiting on logger
2026-04-09 16:01:24.870303 | [rockylinux-9] Waiting on logger
2026-04-09 16:01:35.046683 | [rockylinux-9] Waiting on logger
2026-04-09 16:01:44.202905 | [rockylinux-9] Waiting on logger
2026-04-09 16:01:54.378218 | [rockylinux-9] Waiting on logger
2026-04-09 16:02:04.549957 | [rockylinux-9] Waiting on logger
2026-04-09 16:02:14.725986 | [rockylinux-9] Waiting on logger
2026-04-09 16:02:24.901976 | [rockylinux-9] Waiting on logger
2026-04-09 16:02:35.082034 | [rockylinux-9] Waiting on logger
2026-04-09 16:02:44.230081 | [rockylinux-9] Waiting on logger
2026-04-09 16:02:54.410180 | [rockylinux-9] Waiting on logger
2026-04-09 16:03:02.349982 | rockylinux-9 | ok: Runtime: 0:02:15.230157
2026-04-09 16:03:02.355071 | 
2026-04-09 16:03:02.355118 | PLAY RECAP
2026-04-09 16:03:02.355159 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-09 16:03:02.355181 | 
2026-04-09 16:03:02.452186 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-04-09 16:03:02.455187 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-09 16:03:03.012717 | 
2026-04-09 16:03:03.012830 | PLAY [all]
2026-04-09 16:03:03.023365 | 
2026-04-09 16:03:03.023443 | TASK [fetch-output : Set log path for multiple nodes]
2026-04-09 16:03:03.068407 | rockylinux-9 | skipping: Conditional result was False
2026-04-09 16:03:03.077906 | 
2026-04-09 16:03:03.077988 | TASK [fetch-output : Set log path for single node]
2026-04-09 16:03:03.131912 | rockylinux-9 | ok
2026-04-09 16:03:03.137556 | 
2026-04-09 16:03:03.137622 | LOOP [fetch-output : Ensure local output dirs]
2026-04-09 16:03:03.519320 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/c76b36f676094a7bb2707f50168e9d89/work/logs"
2026-04-09 16:03:03.736687 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/c76b36f676094a7bb2707f50168e9d89/work/artifacts"
2026-04-09 16:03:03.947807 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/c76b36f676094a7bb2707f50168e9d89/work/docs"
2026-04-09 16:03:03.962922 | 
2026-04-09 16:03:03.963086 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-04-09 16:03:04.699004 | rockylinux-9 | ok
2026-04-09 16:03:04.699273 | rockylinux-9 | changed: All items complete
2026-04-09 16:03:04.699308 | 
2026-04-09 16:03:05.221691 | rockylinux-9 | changed: .d..t...... ./
2026-04-09 16:03:05.739476 | rockylinux-9 | changed: .d..t...... ./
2026-04-09 16:03:05.758990 | 
2026-04-09 16:03:05.759164 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-04-09 16:03:06.161954 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.008814
2026-04-09 16:03:06.384059 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.009747
2026-04-09 16:03:06.396168 | 
2026-04-09 16:03:06.396287 | PLAY [all]
2026-04-09 16:03:06.402197 | 
2026-04-09 16:03:06.402260 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-04-09 16:03:06.879368 | rockylinux-9 | changed
2026-04-09 16:03:06.886520 | 
2026-04-09 16:03:06.886573 | PLAY RECAP
2026-04-09 16:03:06.886649 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-04-09 16:03:06.886676 | 
2026-04-09 16:03:07.015892 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-09 16:03:07.021057 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-04-09 16:03:07.571453 | 
2026-04-09 16:03:07.571569 | PLAY [localhost]
2026-04-09 16:03:07.580853 | 
2026-04-09 16:03:07.580929 | TASK [Generate Zuul manifest]
2026-04-09 16:03:07.602146 | localhost | ok
2026-04-09 16:03:07.617702 | 
2026-04-09 16:03:07.617794 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-04-09 16:03:07.933502 | localhost | changed
2026-04-09 16:03:07.946340 | 
2026-04-09 16:03:07.946420 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-04-09 16:03:07.977415 | localhost | ok
2026-04-09 16:03:07.985995 | 
2026-04-09 16:03:07.986068 | TASK [Upload logs]
2026-04-09 16:03:08.007243 | localhost | ok
2026-04-09 16:03:08.064721 | 
2026-04-09 16:03:08.064884 | TASK [Set zuul-log-path fact]
2026-04-09 16:03:08.082374 | localhost | ok
2026-04-09 16:03:08.092548 | 
2026-04-09 16:03:08.092618 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-09 16:03:08.124072 | localhost | ok
2026-04-09 16:03:08.134269 | 
2026-04-09 16:03:08.134337 | TASK [upload-logs : Create log directories]
2026-04-09 16:03:08.542396 | localhost | changed
2026-04-09 16:03:08.548670 | 
2026-04-09 16:03:08.548772 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-04-09 16:03:08.957404 | localhost -> localhost | ok: Runtime: 0:00:00.005733
2026-04-09 16:03:08.963592 | 
2026-04-09 16:03:08.963666 | TASK [upload-logs : Upload logs to log server]
2026-04-09 16:03:09.413081 | localhost | Output suppressed because no_log was given
2026-04-09 16:03:09.418307 | 
2026-04-09 16:03:09.418381 | LOOP [upload-logs : Compress console log and json output]
2026-04-09 16:03:09.465723 | localhost | skipping: Conditional result was False
2026-04-09 16:03:09.472449 | localhost | skipping: Conditional result was False
2026-04-09 16:03:09.484574 | 
2026-04-09 16:03:09.484798 | LOOP [upload-logs : Upload compressed console log and json output]
2026-04-09 16:03:09.533230 | localhost | skipping: Conditional result was False
2026-04-09 16:03:09.533648 | 
2026-04-09 16:03:09.537516 | localhost | skipping: Conditional result was False
2026-04-09 16:03:09.550763 | 
2026-04-09 16:03:09.550995 | LOOP [upload-logs : Upload console log and json output]