2026-04-17 10:43:55.242994 | Job console starting
2026-04-17 10:43:55.271323 | Updating git repos
2026-04-17 10:43:55.321749 | Cloning repos into workspace
2026-04-17 10:43:58.261618 | Restoring repo states
2026-04-17 10:43:58.271410 | Merging changes
2026-04-17 10:43:59.350278 | Checking out repos
2026-04-17 10:43:59.877092 | Preparing playbooks
2026-04-17 10:44:07.299440 | Running Ansible setup
2026-04-17 10:44:12.705939 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-17 10:44:13.380737 | 
2026-04-17 10:44:13.380900 | PLAY [localhost]
2026-04-17 10:44:13.391746 | 
2026-04-17 10:44:13.391870 | TASK [Gathering Facts]
2026-04-17 10:44:14.426602 | localhost | ok
2026-04-17 10:44:14.436092 | 
2026-04-17 10:44:14.436166 | TASK [Setup log path fact]
2026-04-17 10:44:14.464545 | localhost | ok
2026-04-17 10:44:14.474270 | 
2026-04-17 10:44:14.474350 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-17 10:44:14.503180 | localhost | ok
2026-04-17 10:44:14.511652 | 
2026-04-17 10:44:14.511733 | TASK [emit-job-header : Print job information]
2026-04-17 10:44:14.540821 | # Job Information
2026-04-17 10:44:14.541036 | Ansible Version: 2.16.16
2026-04-17 10:44:14.541075 | Job: ansible-collection-containers-molecule-docker-rockylinux-9
2026-04-17 10:44:14.541098 | Pipeline: check
2026-04-17 10:44:14.541120 | Executor: 0a8996d2b663
2026-04-17 10:44:14.541141 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/114
2026-04-17 10:44:14.541163 | Event ID: 34b67640-3a4a-11f1-9787-0a435e8cfe42
2026-04-17 10:44:14.544073 | 
2026-04-17 10:44:14.544142 | LOOP [emit-job-header : Print node information]
2026-04-17 10:44:14.632947 | localhost | ok:
2026-04-17 10:44:14.633131 | localhost | # Node Information
2026-04-17 10:44:14.633161 | localhost | Inventory Hostname: rockylinux-9
2026-04-17 10:44:14.633184 | localhost | Hostname: np0000167083
2026-04-17 10:44:14.633205 | localhost | Username: zuul
2026-04-17 10:44:14.633228 | localhost | Distro: Rocky 9.7
2026-04-17 10:44:14.633249 | localhost | Provider: yul1
2026-04-17 10:44:14.633268 | localhost | Region: ca-ymq-1
2026-04-17 10:44:14.633287 | localhost | Label: rockylinux-9
2026-04-17 10:44:14.633306 | localhost | Product Name: OpenStack Nova
2026-04-17 10:44:14.633324 | localhost | Interface IP: 199.19.213.200
2026-04-17 10:44:14.647469 | 
2026-04-17 10:44:14.647574 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-04-17 10:44:15.025519 | localhost -> localhost | changed
2026-04-17 10:44:15.050329 | 
2026-04-17 10:44:15.050461 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-04-17 10:44:15.926704 | localhost -> localhost | changed
2026-04-17 10:44:15.932858 | 
2026-04-17 10:44:15.932913 | PLAY [all]
2026-04-17 10:44:15.939906 | 
2026-04-17 10:44:15.939974 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-04-17 10:44:16.147312 | rockylinux-9 -> localhost | ok
2026-04-17 10:44:16.154736 | 
2026-04-17 10:44:16.156692 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-04-17 10:44:16.186882 | rockylinux-9 | ok
2026-04-17 10:44:16.209906 | rockylinux-9 | included: /var/lib/zuul/builds/b0abd72cc86f4c698137c2f08fd57d88/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-04-17 10:44:16.217124 | 
2026-04-17 10:44:16.217210 | TASK [add-build-sshkey : Create Temp SSH key]
2026-04-17 10:44:16.936721 | rockylinux-9 -> localhost | Generating public/private rsa key pair.
2026-04-17 10:44:16.937187 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/b0abd72cc86f4c698137c2f08fd57d88/work/b0abd72cc86f4c698137c2f08fd57d88_id_rsa
2026-04-17 10:44:16.937246 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/b0abd72cc86f4c698137c2f08fd57d88/work/b0abd72cc86f4c698137c2f08fd57d88_id_rsa.pub
2026-04-17 10:44:16.937303 | rockylinux-9 -> localhost | The key fingerprint is:
2026-04-17 10:44:16.937343 | rockylinux-9 -> localhost | SHA256:Mtjr/XrIfJAOWcwg+GF7B4Z/xiiaDExxz0Oqj7DrD/8 zuul-build-sshkey
2026-04-17 10:44:16.937395 | rockylinux-9 -> localhost | The key's randomart image is:
2026-04-17 10:44:16.937429 | rockylinux-9 -> localhost | +---[RSA 3072]----+
2026-04-17 10:44:16.937473 | rockylinux-9 -> localhost | | ..o o           |
2026-04-17 10:44:16.937514 | rockylinux-9 -> localhost | | .o X +          |
2026-04-17 10:44:16.937559 | rockylinux-9 -> localhost | |o  + X B         |
2026-04-17 10:44:16.937595 | rockylinux-9 -> localhost | |... +o= O        |
2026-04-17 10:44:16.937630 | rockylinux-9 -> localhost | |.+ o.o+*S.       |
2026-04-17 10:44:16.937662 | rockylinux-9 -> localhost | |..*   o+o        |
2026-04-17 10:44:16.937693 | rockylinux-9 -> localhost | |.o .  .= o       |
2026-04-17 10:44:16.937725 | rockylinux-9 -> localhost | | .o  . .= o      |
2026-04-17 10:44:16.937767 | rockylinux-9 -> localhost | |o..o.E. o=.      |
2026-04-17 10:44:16.937802 | rockylinux-9 -> localhost | +----[SHA256]-----+
2026-04-17 10:44:16.937880 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.320607
2026-04-17 10:44:16.944191 | 
2026-04-17 10:44:16.944253 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-04-17 10:44:16.972490 | rockylinux-9 | ok
2026-04-17 10:44:16.980825 | rockylinux-9 | included: /var/lib/zuul/builds/b0abd72cc86f4c698137c2f08fd57d88/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-04-17 10:44:16.988921 | 
2026-04-17 10:44:16.989002 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-04-17 10:44:17.027355 | rockylinux-9 | skipping: Conditional result was False
2026-04-17 10:44:17.033413 | 
2026-04-17 10:44:17.033484 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-04-17 10:44:17.590846 | rockylinux-9 | changed
2026-04-17 10:44:17.595249 | 
2026-04-17 10:44:17.595310 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-04-17 10:44:17.854131 | rockylinux-9 | ok
2026-04-17 10:44:17.867045 | 
2026-04-17 10:44:17.867219 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-04-17 10:44:18.809659 | rockylinux-9 | changed
2026-04-17 10:44:18.817316 | 
2026-04-17 10:44:18.817389 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-04-17 10:44:19.778680 | rockylinux-9 | changed
2026-04-17 10:44:19.786071 | 
2026-04-17 10:44:19.786171 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-04-17 10:44:19.813323 | rockylinux-9 | skipping: Conditional result was False
2026-04-17 10:44:19.823626 | 
2026-04-17 10:44:19.823767 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-04-17 10:44:20.226121 | rockylinux-9 -> localhost | changed
2026-04-17 10:44:20.240349 | 
2026-04-17 10:44:20.240502 | TASK [add-build-sshkey : Add back temp key]
2026-04-17 10:44:20.543277 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/b0abd72cc86f4c698137c2f08fd57d88/work/b0abd72cc86f4c698137c2f08fd57d88_id_rsa (zuul-build-sshkey)
2026-04-17 10:44:20.544015 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.022243
2026-04-17 10:44:20.551645 | 
2026-04-17 10:44:20.551773 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-04-17 10:44:20.914776 | rockylinux-9 | ok
2026-04-17 10:44:20.919141 | 
2026-04-17 10:44:20.919206 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-04-17 10:44:20.959439 | rockylinux-9 | skipping: Conditional result was False
2026-04-17 10:44:20.968816 | 
2026-04-17 10:44:20.968985 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-04-17 10:44:21.361457 | rockylinux-9 | ok
2026-04-17 10:44:21.366624 | 
2026-04-17 10:44:21.366691 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-04-17 10:44:22.301860 | rockylinux-9 | Output suppressed because no_log was given
2026-04-17 10:44:22.310533 | 
2026-04-17 10:44:22.310620 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-04-17 10:44:22.562356 | rockylinux-9 | ok: "logs"
2026-04-17 10:44:22.562708 | rockylinux-9 | ok: All items complete
2026-04-17 10:44:22.562750 | 
2026-04-17 10:44:22.799238 | rockylinux-9 | ok: "artifacts"
2026-04-17 10:44:23.033652 | rockylinux-9 | ok: "docs"
2026-04-17 10:44:23.040263 | 
2026-04-17 10:44:23.040335 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-04-17 10:44:23.292471 | rockylinux-9 | changed: "logs"
2026-04-17 10:44:23.522303 | rockylinux-9 | changed: "artifacts"
2026-04-17 10:44:23.745484 | rockylinux-9 | changed: "docs"
2026-04-17 10:44:23.758609 | 
2026-04-17 10:44:23.758746 | PLAY RECAP
2026-04-17 10:44:23.758796 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-17 10:44:23.758827 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-04-17 10:44:23.758847 | 
2026-04-17 10:44:23.935020 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-17 10:44:23.936201 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-17 10:44:24.503493 | 
2026-04-17 10:44:24.503619 | PLAY [all]
2026-04-17 10:44:24.514965 | 
2026-04-17 10:44:24.515088 | TASK [setup-uv : Extract archive]
2026-04-17 10:44:27.224008 | rockylinux-9 | changed
2026-04-17 10:44:27.229299 | 
2026-04-17 10:44:27.229376 | TASK [setup-uv : Print version]
2026-04-17 10:44:27.267899 | [rockylinux-9] Waiting on logger
2026-04-17 10:44:27.781728 | rockylinux-9 | ok: Runtime: 0:00:00.015078
2026-04-17 10:44:27.789384 | 
2026-04-17 10:44:27.789450 | PLAY RECAP
2026-04-17 10:44:27.789510 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-17 10:44:27.789542 | 
2026-04-17 10:44:27.891881 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-17 10:44:27.894093 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-04-17 10:44:28.444753 | 
2026-04-17 10:44:28.445358 | PLAY [all]
2026-04-17 10:44:28.456171 | 
2026-04-17 10:44:28.456243 | TASK [Copy inventory file for Zuul]
2026-04-17 10:44:29.886604 | rockylinux-9 | changed
2026-04-17 10:44:29.892003 | 
2026-04-17 10:44:29.892077 | TASK [Switch "ansible_host" to private IP]
2026-04-17 10:44:30.259873 | rockylinux-9 | changed: 1 replacements made
2026-04-17 10:44:30.266304 | 
2026-04-17 10:44:30.266394 | TASK [Run Molecule scenario]
2026-04-17 10:44:30.287272 | [rockylinux-9] Waiting on logger
2026-04-17 10:44:38.438207 | [rockylinux-9] Waiting on logger
2026-04-17 10:44:48.617763 | [rockylinux-9] Waiting on logger
2026-04-17 10:44:58.790404 | [rockylinux-9] Waiting on logger
2026-04-17 10:45:08.965764 | [rockylinux-9] Waiting on logger
2026-04-17 10:45:19.141789 | [rockylinux-9] Waiting on logger
2026-04-17 10:45:28.299388 | [rockylinux-9] Waiting on logger
2026-04-17 10:45:38.470023 | [rockylinux-9] Waiting on logger
2026-04-17 10:45:48.645990 | [rockylinux-9] Waiting on logger
2026-04-17 10:45:58.823561 | [rockylinux-9] Waiting on logger
2026-04-17 10:46:08.998020 | [rockylinux-9] Waiting on logger
2026-04-17 10:46:19.178088 | [rockylinux-9] Waiting on logger
2026-04-17 10:46:23.757345 | rockylinux-9 | ok: Runtime: 0:01:52.752039
2026-04-17 10:46:23.764543 | 
2026-04-17 10:46:23.764610 | PLAY RECAP
2026-04-17 10:46:23.764672 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-17 10:46:23.764705 | 
2026-04-17 10:46:23.869919 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-04-17 10:46:23.871954 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-17 10:46:24.457776 | 
2026-04-17 10:46:24.457905 | PLAY [all]
2026-04-17 10:46:24.468345 | 
2026-04-17 10:46:24.468420 | TASK [fetch-output : Set log path for multiple nodes]
2026-04-17 10:46:24.513795 | rockylinux-9 | skipping: Conditional result was False
2026-04-17 10:46:24.520179 | 
2026-04-17 10:46:24.520313 | TASK [fetch-output : Set log path for single node]
2026-04-17 10:46:24.564609 | rockylinux-9 | ok
2026-04-17 10:46:24.570354 | 
2026-04-17 10:46:24.570433 | LOOP [fetch-output : Ensure local output dirs]
2026-04-17 10:46:24.977924 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/b0abd72cc86f4c698137c2f08fd57d88/work/logs"
2026-04-17 10:46:25.202732 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/b0abd72cc86f4c698137c2f08fd57d88/work/artifacts"
2026-04-17 10:46:25.436036 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/b0abd72cc86f4c698137c2f08fd57d88/work/docs"
2026-04-17 10:46:25.449111 | 
2026-04-17 10:46:25.449256 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-04-17 10:46:26.163699 | rockylinux-9 | changed: .d..t...... ./
2026-04-17 10:46:26.163894 | rockylinux-9 | changed: All items complete
2026-04-17 10:46:26.163922 | 
2026-04-17 10:46:26.673400 | rockylinux-9 | changed: .d..t...... ./
2026-04-17 10:46:27.193921 | rockylinux-9 | changed: .d..t...... ./
2026-04-17 10:46:27.217443 | 
2026-04-17 10:46:27.217557 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-04-17 10:46:27.607203 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.007687
2026-04-17 10:46:27.810824 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.007185
2026-04-17 10:46:27.823112 | 
2026-04-17 10:46:27.823237 | PLAY [all]
2026-04-17 10:46:27.829225 | 
2026-04-17 10:46:27.829289 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-04-17 10:46:28.277630 | rockylinux-9 | changed
2026-04-17 10:46:28.283312 | 
2026-04-17 10:46:28.283359 | PLAY RECAP
2026-04-17 10:46:28.283401 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-04-17 10:46:28.283423 | 
2026-04-17 10:46:28.387719 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-17 10:46:28.389038 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-04-17 10:46:28.916197 | 
2026-04-17 10:46:28.916314 | PLAY [localhost]
2026-04-17 10:46:28.926336 | 
2026-04-17 10:46:28.926430 | TASK [Generate Zuul manifest]
2026-04-17 10:46:28.947863 | localhost | ok
2026-04-17 10:46:28.962654 | 
2026-04-17 10:46:28.962730 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-04-17 10:46:29.278844 | localhost | changed
2026-04-17 10:46:29.291320 | 
2026-04-17 10:46:29.291389 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-04-17 10:46:29.322313 | localhost | ok
2026-04-17 10:46:29.332306 | 
2026-04-17 10:46:29.332390 | TASK [Upload logs]
2026-04-17 10:46:29.350668 | localhost | ok
2026-04-17 10:46:29.406895 | 
2026-04-17 10:46:29.406990 | TASK [Set zuul-log-path fact]
2026-04-17 10:46:29.426402 | localhost | ok
2026-04-17 10:46:29.439198 | 
2026-04-17 10:46:29.439267 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-17 10:46:29.470351 | localhost | ok
2026-04-17 10:46:29.479124 | 
2026-04-17 10:46:29.479192 | TASK [upload-logs : Create log directories]
2026-04-17 10:46:29.854985 | localhost | changed
2026-04-17 10:46:29.861135 | 
2026-04-17 10:46:29.861199 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-04-17 10:46:30.258606 | localhost -> localhost | ok: Runtime: 0:00:00.006252
2026-04-17 10:46:30.266151 | 
2026-04-17 10:46:30.266229 | TASK [upload-logs : Upload logs to log server]
2026-04-17 10:46:30.694212 | localhost | Output suppressed because no_log was given
2026-04-17 10:46:30.698398 | 
2026-04-17 10:46:30.698468 | LOOP [upload-logs : Compress console log and json output]
2026-04-17 10:46:30.744272 | localhost | skipping: Conditional result was False
2026-04-17 10:46:30.751131 | localhost | skipping: Conditional result was False
2026-04-17 10:46:30.762409 | 
2026-04-17 10:46:30.762533 | LOOP [upload-logs : Upload compressed console log and json output]
2026-04-17 10:46:30.804747 | localhost | skipping: Conditional result was False
2026-04-17 10:46:30.805197 | 
2026-04-17 10:46:30.808870 | localhost | skipping: Conditional result was False
2026-04-17 10:46:30.825974 | 
2026-04-17 10:46:30.826145 | LOOP [upload-logs : Upload console log and json output]