2026-04-26 13:38:31.080986 | Job console starting
2026-04-26 13:38:31.105853 | Updating git repos
2026-04-26 13:38:31.153594 | Cloning repos into workspace
2026-04-26 13:38:31.447079 | Restoring repo states
2026-04-26 13:38:31.463683 | Merging changes
2026-04-26 13:38:31.782757 | Checking out repos
2026-04-26 13:38:31.840326 | Preparing playbooks
2026-04-26 13:38:35.492919 | Running Ansible setup
2026-04-26 13:38:39.625176 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-26 13:38:40.358929 | 
2026-04-26 13:38:40.359092 | PLAY [localhost]
2026-04-26 13:38:40.369803 | 
2026-04-26 13:38:40.370065 | TASK [Gathering Facts]
2026-04-26 13:38:41.302067 | localhost | ok
2026-04-26 13:38:41.308881 | 
2026-04-26 13:38:41.308998 | TASK [Setup log path fact]
2026-04-26 13:38:41.350159 | localhost | ok
2026-04-26 13:38:41.363956 | 
2026-04-26 13:38:41.364092 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-26 13:38:41.393385 | localhost | ok
2026-04-26 13:38:41.402246 | 
2026-04-26 13:38:41.402349 | TASK [emit-job-header : Print job information]
2026-04-26 13:38:41.434544 | # Job Information
2026-04-26 13:38:41.607549 | Ansible Version: 2.16.16
2026-04-26 13:38:41.607793 | Job: ansible-collection-containers-molecule-docker-rockylinux-9
2026-04-26 13:38:41.607859 | Pipeline: check
2026-04-26 13:38:41.607907 | Executor: 0a8996d2b663
2026-04-26 13:38:41.607951 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/122
2026-04-26 13:38:41.608002 | Event ID: 1b10a210-4175-11f1-9d04-fb4ed4f3c7b1
2026-04-26 13:38:41.614814 | 
2026-04-26 13:38:41.614963 | LOOP [emit-job-header : Print node information]
2026-04-26 13:38:41.705375 | localhost | ok:
2026-04-26 13:38:41.705572 | localhost | # Node Information
2026-04-26 13:38:41.705602 | localhost | Inventory Hostname: rockylinux-9
2026-04-26 13:38:41.705623 | localhost | Hostname: np0000170298
2026-04-26 13:38:41.705643 | localhost | Username: zuul
2026-04-26 13:38:41.705667 | localhost | Distro: Rocky 9.7
2026-04-26 13:38:41.705688 | localhost | Provider: yul1
2026-04-26 13:38:41.705707 | localhost | Region: ca-ymq-1
2026-04-26 13:38:41.705726 | localhost | Label: rockylinux-9
2026-04-26 13:38:41.705744 | localhost | Product Name: OpenStack Nova
2026-04-26 13:38:41.705765 | localhost | Interface IP: 199.204.45.30
2026-04-26 13:38:41.723102 | 
2026-04-26 13:38:41.723267 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-04-26 13:38:42.164653 | localhost -> localhost | changed
2026-04-26 13:38:42.170751 | 
2026-04-26 13:38:42.170873 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-04-26 13:38:43.133492 | localhost -> localhost | changed
2026-04-26 13:38:43.140662 | 
2026-04-26 13:38:43.140721 | PLAY [all]
2026-04-26 13:38:43.149324 | 
2026-04-26 13:38:43.149395 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-04-26 13:38:43.410409 | rockylinux-9 -> localhost | ok
2026-04-26 13:38:43.425352 | 
2026-04-26 13:38:43.425512 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-04-26 13:38:43.462673 | rockylinux-9 | ok
2026-04-26 13:38:43.483948 | rockylinux-9 | included: /var/lib/zuul/builds/7eb1e6a3495745408d9c88f6ed2fecaa/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-04-26 13:38:43.489360 | 
2026-04-26 13:38:43.489423 | TASK [add-build-sshkey : Create Temp SSH key]
2026-04-26 13:38:44.739508 | rockylinux-9 -> localhost | Generating public/private rsa key pair.
2026-04-26 13:38:44.739721 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/7eb1e6a3495745408d9c88f6ed2fecaa/work/7eb1e6a3495745408d9c88f6ed2fecaa_id_rsa
2026-04-26 13:38:44.739764 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/7eb1e6a3495745408d9c88f6ed2fecaa/work/7eb1e6a3495745408d9c88f6ed2fecaa_id_rsa.pub
2026-04-26 13:38:44.739796 | rockylinux-9 -> localhost | The key fingerprint is:
2026-04-26 13:38:44.739824 | rockylinux-9 -> localhost | SHA256:us8/yLcDMsc4vF0G7yEjk0ts3jndadfKA84zNcTZkcs zuul-build-sshkey
2026-04-26 13:38:44.739864 | rockylinux-9 -> localhost | The key's randomart image is:
2026-04-26 13:38:44.739895 | rockylinux-9 -> localhost | +---[RSA 3072]----+
2026-04-26 13:38:44.739925 | rockylinux-9 -> localhost | |                .|
2026-04-26 13:38:44.739955 | rockylinux-9 -> localhost | |               o |
2026-04-26 13:38:44.739982 | rockylinux-9 -> localhost | |             ..oo|
2026-04-26 13:38:44.740009 | rockylinux-9 -> localhost | |        .     +E.|
2026-04-26 13:38:44.740037 | rockylinux-9 -> localhost | |     o +So   .   |
2026-04-26 13:38:44.740064 | rockylinux-9 -> localhost | |      &.* = . o  |
2026-04-26 13:38:44.740091 | rockylinux-9 -> localhost | |     +.& @ = + o |
2026-04-26 13:38:44.740118 | rockylinux-9 -> localhost | |      +oB * O.o .|
2026-04-26 13:38:44.740148 | rockylinux-9 -> localhost | |      ..o+o= +oo |
2026-04-26 13:38:44.740176 | rockylinux-9 -> localhost | +----[SHA256]-----+
2026-04-26 13:38:44.740245 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.745942
2026-04-26 13:38:44.746570 | 
2026-04-26 13:38:44.746656 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-04-26 13:38:44.776413 | rockylinux-9 | ok
2026-04-26 13:38:44.784483 | rockylinux-9 | included: /var/lib/zuul/builds/7eb1e6a3495745408d9c88f6ed2fecaa/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-04-26 13:38:44.792386 | 
2026-04-26 13:38:44.792449 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-04-26 13:38:44.817508 | rockylinux-9 | skipping: Conditional result was False
2026-04-26 13:38:44.825221 | 
2026-04-26 13:38:44.825345 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-04-26 13:38:45.345721 | rockylinux-9 | changed
2026-04-26 13:38:45.351197 | 
2026-04-26 13:38:45.351366 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-04-26 13:38:45.605802 | rockylinux-9 | ok
2026-04-26 13:38:45.610380 | 
2026-04-26 13:38:45.610448 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-04-26 13:38:46.484501 | rockylinux-9 | changed
2026-04-26 13:38:46.489938 | 
2026-04-26 13:38:46.490020 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-04-26 13:38:47.378025 | rockylinux-9 | changed
2026-04-26 13:38:47.384821 | 
2026-04-26 13:38:47.384909 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-04-26 13:38:47.410181 | rockylinux-9 | skipping: Conditional result was False
2026-04-26 13:38:47.418082 | 
2026-04-26 13:38:47.418164 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-04-26 13:38:47.777333 | rockylinux-9 -> localhost | changed
2026-04-26 13:38:47.787286 | 
2026-04-26 13:38:47.787407 | TASK [add-build-sshkey : Add back temp key]
2026-04-26 13:38:48.100240 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/7eb1e6a3495745408d9c88f6ed2fecaa/work/7eb1e6a3495745408d9c88f6ed2fecaa_id_rsa (zuul-build-sshkey)
2026-04-26 13:38:48.100455 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.014837
2026-04-26 13:38:48.107189 | 
2026-04-26 13:38:48.107258 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-04-26 13:38:48.500580 | rockylinux-9 | ok
2026-04-26 13:38:48.504805 | 
2026-04-26 13:38:48.504997 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-04-26 13:38:48.540174 | rockylinux-9 | skipping: Conditional result was False
2026-04-26 13:38:48.549278 | 
2026-04-26 13:38:48.549356 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-04-26 13:38:48.954146 | rockylinux-9 | ok
2026-04-26 13:38:48.959525 | 
2026-04-26 13:38:48.959594 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-04-26 13:38:49.969801 | rockylinux-9 | Output suppressed because no_log was given
2026-04-26 13:38:49.980927 | 
2026-04-26 13:38:49.981048 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-04-26 13:38:50.229973 | rockylinux-9 | ok: "logs"
2026-04-26 13:38:50.230231 | rockylinux-9 | ok: All items complete
2026-04-26 13:38:50.230260 | 
2026-04-26 13:38:50.449949 | rockylinux-9 | ok: "artifacts"
2026-04-26 13:38:50.676398 | rockylinux-9 | ok: "docs"
2026-04-26 13:38:50.691448 | 
2026-04-26 13:38:50.691592 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-04-26 13:38:50.956502 | rockylinux-9 | changed: "logs"
2026-04-26 13:38:51.192770 | rockylinux-9 | changed: "artifacts"
2026-04-26 13:38:51.415883 | rockylinux-9 | changed: "docs"
2026-04-26 13:38:51.424067 | 
2026-04-26 13:38:51.424177 | PLAY RECAP
2026-04-26 13:38:51.424255 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-26 13:38:51.424289 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-04-26 13:38:51.424314 | 
2026-04-26 13:38:51.571979 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-26 13:38:51.573090 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-26 13:38:52.265538 | 
2026-04-26 13:38:52.265754 | PLAY [all]
2026-04-26 13:38:52.285388 | 
2026-04-26 13:38:52.285572 | TASK [setup-uv : Extract archive]
2026-04-26 13:38:55.045737 | rockylinux-9 | changed
2026-04-26 13:38:55.051234 | 
2026-04-26 13:38:55.051317 | TASK [setup-uv : Print version]
2026-04-26 13:38:55.085137 | [rockylinux-9] Waiting on logger
2026-04-26 13:38:55.601953 | rockylinux-9 | ok: Runtime: 0:00:00.017598
2026-04-26 13:38:55.610376 | 
2026-04-26 13:38:55.610462 | PLAY RECAP
2026-04-26 13:38:55.610525 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-26 13:38:55.610560 | 
2026-04-26 13:38:55.812412 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-26 13:38:55.814056 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-04-26 13:38:56.461745 | 
2026-04-26 13:38:56.461953 | PLAY [all]
2026-04-26 13:38:56.473655 | 
2026-04-26 13:38:56.506134 | TASK [Copy inventory file for Zuul]
2026-04-26 13:38:57.860076 | rockylinux-9 | changed
2026-04-26 13:38:57.867356 | 
2026-04-26 13:38:57.867461 | TASK [Switch "ansible_host" to private IP]
2026-04-26 13:38:58.237495 | rockylinux-9 | changed: 1 replacements made
2026-04-26 13:38:58.243275 | 
2026-04-26 13:38:58.243427 | TASK [Run Molecule scenario]
2026-04-26 13:38:58.263329 | [rockylinux-9] Waiting on logger
2026-04-26 13:39:06.406156 | [rockylinux-9] Waiting on logger
2026-04-26 13:39:16.582120 | [rockylinux-9] Waiting on logger
2026-04-26 13:39:26.758102 | [rockylinux-9] Waiting on logger
2026-04-26 13:39:36.934118 | [rockylinux-9] Waiting on logger
2026-04-26 13:39:46.123781 | [rockylinux-9] Waiting on logger
2026-04-26 13:39:56.294201 | [rockylinux-9] Waiting on logger
2026-04-26 13:40:06.470166 | [rockylinux-9] Waiting on logger
2026-04-26 13:40:16.649936 | [rockylinux-9] Waiting on logger
2026-04-26 13:40:26.822027 | [rockylinux-9] Waiting on logger
2026-04-26 13:40:36.998174 | [rockylinux-9] Waiting on logger
2026-04-26 13:40:46.277915 | [rockylinux-9] Waiting on logger
2026-04-26 13:40:55.341784 | rockylinux-9 | ok: Runtime: 0:01:55.774144
2026-04-26 13:40:55.347293 | 
2026-04-26 13:40:55.347339 | PLAY RECAP
2026-04-26 13:40:55.347382 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-26 13:40:55.347403 | 
2026-04-26 13:40:55.496068 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-04-26 13:40:55.498474 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-26 13:40:56.087237 | 
2026-04-26 13:40:56.087390 | PLAY [all]
2026-04-26 13:40:56.098244 | 
2026-04-26 13:40:56.098319 | TASK [fetch-output : Set log path for multiple nodes]
2026-04-26 13:40:56.154264 | rockylinux-9 | skipping: Conditional result was False
2026-04-26 13:40:56.159753 | 
2026-04-26 13:40:56.159849 | TASK [fetch-output : Set log path for single node]
2026-04-26 13:40:56.195776 | rockylinux-9 | ok
2026-04-26 13:40:56.200009 | 
2026-04-26 13:40:56.200078 | LOOP [fetch-output : Ensure local output dirs]
2026-04-26 13:40:57.105992 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/7eb1e6a3495745408d9c88f6ed2fecaa/work/logs"
2026-04-26 13:40:57.323182 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/7eb1e6a3495745408d9c88f6ed2fecaa/work/artifacts"
2026-04-26 13:40:57.568424 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/7eb1e6a3495745408d9c88f6ed2fecaa/work/docs"
2026-04-26 13:40:57.588146 | 
2026-04-26 13:40:57.588312 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-04-26 13:40:58.303385 | rockylinux-9 | ok
2026-04-26 13:40:58.474173 | rockylinux-9 | changed: All items complete
2026-04-26 13:40:58.474303 | 
2026-04-26 13:40:58.804369 | rockylinux-9 | changed: .d..t...... ./
2026-04-26 13:40:59.309555 | rockylinux-9 | changed: .d..t...... ./
2026-04-26 13:40:59.327763 | 
2026-04-26 13:40:59.327977 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-04-26 13:40:59.849949 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.008175
2026-04-26 13:41:00.071666 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.008093
2026-04-26 13:41:00.086552 | 
2026-04-26 13:41:00.086663 | PLAY [all]
2026-04-26 13:41:00.092093 | 
2026-04-26 13:41:00.092154 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-04-26 13:41:00.582356 | rockylinux-9 | changed
2026-04-26 13:41:00.590749 | 
2026-04-26 13:41:00.590822 | PLAY RECAP
2026-04-26 13:41:00.590893 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-04-26 13:41:00.590927 | 
2026-04-26 13:41:00.721802 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-26 13:41:00.724017 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-04-26 13:41:01.284731 | 
2026-04-26 13:41:01.284883 | PLAY [localhost]
2026-04-26 13:41:01.294925 | 
2026-04-26 13:41:01.294997 | TASK [Generate Zuul manifest]
2026-04-26 13:41:01.314810 | localhost | ok
2026-04-26 13:41:01.330496 | 
2026-04-26 13:41:01.330577 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-04-26 13:41:01.656866 | localhost | changed
2026-04-26 13:41:01.670449 | 
2026-04-26 13:41:01.670525 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-04-26 13:41:01.717847 | localhost | ok
2026-04-26 13:41:01.724317 | 
2026-04-26 13:41:01.724383 | TASK [Upload logs]
2026-04-26 13:41:01.742740 | localhost | ok
2026-04-26 13:41:01.798274 | 
2026-04-26 13:41:01.844534 | TASK [Set zuul-log-path fact]
2026-04-26 13:41:01.863035 | localhost | ok
2026-04-26 13:41:01.874002 | 
2026-04-26 13:41:01.874124 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-26 13:41:01.903983 | localhost | ok
2026-04-26 13:41:01.913365 | 
2026-04-26 13:41:01.913529 | TASK [upload-logs : Create log directories]
2026-04-26 13:41:02.342150 | localhost | changed
2026-04-26 13:41:02.348840 | 
2026-04-26 13:41:02.348934 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-04-26 13:41:03.181854 | localhost -> localhost | ok: Runtime: 0:00:00.005732
2026-04-26 13:41:03.190568 | 
2026-04-26 13:41:03.190740 | TASK [upload-logs : Upload logs to log server]
2026-04-26 13:41:03.741741 | localhost | Output suppressed because no_log was given
2026-04-26 13:41:03.747858 | 
2026-04-26 13:41:03.747977 | LOOP [upload-logs : Compress console log and json output]
2026-04-26 13:41:03.796051 | localhost | skipping: Conditional result was False
2026-04-26 13:41:03.802607 | localhost | skipping: Conditional result was False
2026-04-26 13:41:03.815631 | 
2026-04-26 13:41:03.815786 | LOOP [upload-logs : Upload compressed console log and json output]
2026-04-26 13:41:03.866060 | localhost | skipping: Conditional result was False
2026-04-26 13:41:03.866556 | 
2026-04-26 13:41:03.869837 | localhost | skipping: Conditional result was False
2026-04-26 13:41:03.880675 | 
2026-04-26 13:41:03.880870 | LOOP [upload-logs : Upload console log and json output]