2026-04-20 02:15:10.912352 | Job console starting
2026-04-20 02:15:10.926481 | Updating git repos
2026-04-20 02:15:10.956293 | Cloning repos into workspace
2026-04-20 02:15:11.117121 | Restoring repo states
2026-04-20 02:15:11.127049 | Merging changes
2026-04-20 02:15:11.411928 | Checking out repos
2026-04-20 02:15:11.447890 | Preparing playbooks
2026-04-20 02:15:12.618330 | Running Ansible setup
2026-04-20 02:15:16.714751 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-20 02:15:17.420780 | 
2026-04-20 02:15:17.420931 | PLAY [localhost]
2026-04-20 02:15:17.429539 | 
2026-04-20 02:15:17.429611 | TASK [Gathering Facts]
2026-04-20 02:15:18.494770 | localhost | ok
2026-04-20 02:15:18.505437 | 
2026-04-20 02:15:18.505600 | TASK [Setup log path fact]
2026-04-20 02:15:18.529170 | localhost | ok
2026-04-20 02:15:18.545760 | 
2026-04-20 02:15:18.545924 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-20 02:15:18.577205 | localhost | ok
2026-04-20 02:15:18.586584 | 
2026-04-20 02:15:18.586754 | TASK [emit-job-header : Print job information]
2026-04-20 02:15:18.630019 | # Job Information
2026-04-20 02:15:18.630220 | Ansible Version: 2.16.16
2026-04-20 02:15:18.630255 | Job: ansible-collection-containers-molecule-containerd-rockylinux-9
2026-04-20 02:15:18.630278 | Pipeline: check
2026-04-20 02:15:18.630299 | Executor: 0a8996d2b663
2026-04-20 02:15:18.630320 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/108
2026-04-20 02:15:18.630342 | Event ID: d8bdab90-3c5d-11f1-9e83-70ae08c54185
2026-04-20 02:15:18.633346 | 
2026-04-20 02:15:18.633421 | LOOP [emit-job-header : Print node information]
2026-04-20 02:15:18.750902 | localhost | ok:
2026-04-20 02:15:18.751097 | localhost | # Node Information
2026-04-20 02:15:18.751126 | localhost | Inventory Hostname: rockylinux-9
2026-04-20 02:15:18.751151 | localhost | Hostname: np0000167940
2026-04-20 02:15:18.751172 | localhost | Username: zuul
2026-04-20 02:15:18.751194 | localhost | Distro: Rocky 9.7
2026-04-20 02:15:18.751214 | localhost | Provider: yul1
2026-04-20 02:15:18.751234 | localhost | Region: ca-ymq-1
2026-04-20 02:15:18.751252 | localhost | Label: rockylinux-9
2026-04-20 02:15:18.751271 | localhost | Product Name: OpenStack Nova
2026-04-20 02:15:18.751289 | localhost | Interface IP: 199.204.45.72
2026-04-20 02:15:18.767805 | 
2026-04-20 02:15:18.768017 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-04-20 02:15:19.211726 | localhost -> localhost | changed
2026-04-20 02:15:19.217363 | 
2026-04-20 02:15:19.217452 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-04-20 02:15:20.339363 | localhost -> localhost | changed
2026-04-20 02:15:20.349060 | 
2026-04-20 02:15:20.349146 | PLAY [all]
2026-04-20 02:15:20.357173 | 
2026-04-20 02:15:20.357242 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-04-20 02:15:20.638906 | rockylinux-9 -> localhost | ok
2026-04-20 02:15:20.651332 | 
2026-04-20 02:15:20.651507 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-04-20 02:15:20.692660 | rockylinux-9 | ok
2026-04-20 02:15:20.714973 | rockylinux-9 | included: /var/lib/zuul/builds/692be796565f43c7a3b7a961112ae839/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-04-20 02:15:20.723844 | 
2026-04-20 02:15:20.724148 | TASK [add-build-sshkey : Create Temp SSH key]
2026-04-20 02:15:21.573103 | rockylinux-9 -> localhost | Generating public/private rsa key pair.
2026-04-20 02:15:21.573290 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/692be796565f43c7a3b7a961112ae839/work/692be796565f43c7a3b7a961112ae839_id_rsa
2026-04-20 02:15:21.573324 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/692be796565f43c7a3b7a961112ae839/work/692be796565f43c7a3b7a961112ae839_id_rsa.pub
2026-04-20 02:15:21.573349 | rockylinux-9 -> localhost | The key fingerprint is:
2026-04-20 02:15:21.573371 | rockylinux-9 -> localhost | SHA256:cw2ESThfbkVOvKEphDaMB4QogjjHqT5Wj/bFsT63GH0 zuul-build-sshkey
2026-04-20 02:15:21.573410 | rockylinux-9 -> localhost | The key's randomart image is:
2026-04-20 02:15:21.573436 | rockylinux-9 -> localhost | +---[RSA 3072]----+
2026-04-20 02:15:21.573488 | rockylinux-9 -> localhost | |oo +o+ +.o.oo    |
2026-04-20 02:15:21.573515 | rockylinux-9 -> localhost | |B = . O +..o+    |
2026-04-20 02:15:21.573535 | rockylinux-9 -> localhost | |o+   o = o.+.o   |
2026-04-20 02:15:21.573556 | rockylinux-9 -> localhost | |.  .   .o =o.    |
2026-04-20 02:15:21.573575 | rockylinux-9 -> localhost | |. . o . So. .    |
2026-04-20 02:15:21.573595 | rockylinux-9 -> localhost | | + o . +.o       |
2026-04-20 02:15:21.573615 | rockylinux-9 -> localhost | |. o . o. . E     |
2026-04-20 02:15:21.573634 | rockylinux-9 -> localhost | |     . oo..      |
2026-04-20 02:15:21.573656 | rockylinux-9 -> localhost | |       .o..      |
2026-04-20 02:15:21.573677 | rockylinux-9 -> localhost | +----[SHA256]-----+
2026-04-20 02:15:21.573725 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.383508
2026-04-20 02:15:21.578612 | 
2026-04-20 02:15:21.578674 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-04-20 02:15:21.610854 | rockylinux-9 | ok
2026-04-20 02:15:21.620525 | rockylinux-9 | included: /var/lib/zuul/builds/692be796565f43c7a3b7a961112ae839/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-04-20 02:15:21.628640 | 
2026-04-20 02:15:21.628704 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-04-20 02:15:21.673497 | rockylinux-9 | skipping: Conditional result was False
2026-04-20 02:15:21.684339 | 
2026-04-20 02:15:21.684546 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-04-20 02:15:22.224705 | rockylinux-9 | changed
2026-04-20 02:15:22.230625 | 
2026-04-20 02:15:22.230692 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-04-20 02:15:22.497689 | rockylinux-9 | ok
2026-04-20 02:15:22.504490 | 
2026-04-20 02:15:22.504643 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-04-20 02:15:23.432870 | rockylinux-9 | changed
2026-04-20 02:15:23.439918 | 
2026-04-20 02:15:23.440015 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-04-20 02:15:24.364387 | rockylinux-9 | changed
2026-04-20 02:15:24.369145 | 
2026-04-20 02:15:24.369226 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-04-20 02:15:24.394387 | rockylinux-9 | skipping: Conditional result was False
2026-04-20 02:15:24.406512 | 
2026-04-20 02:15:24.406652 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-04-20 02:15:24.865392 | rockylinux-9 -> localhost | changed
2026-04-20 02:15:24.874983 | 
2026-04-20 02:15:24.875070 | TASK [add-build-sshkey : Add back temp key]
2026-04-20 02:15:25.191715 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/692be796565f43c7a3b7a961112ae839/work/692be796565f43c7a3b7a961112ae839_id_rsa (zuul-build-sshkey)
2026-04-20 02:15:25.192132 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.013995
2026-04-20 02:15:25.200011 | 
2026-04-20 02:15:25.200161 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-04-20 02:15:25.597316 | rockylinux-9 | ok
2026-04-20 02:15:25.602122 | 
2026-04-20 02:15:25.602196 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-04-20 02:15:25.627309 | rockylinux-9 | skipping: Conditional result was False
2026-04-20 02:15:25.650151 | 
2026-04-20 02:15:25.650249 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-04-20 02:15:26.074786 | rockylinux-9 | ok
2026-04-20 02:15:26.080464 | 
2026-04-20 02:15:26.080531 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-04-20 02:15:27.100331 | rockylinux-9 | Output suppressed because no_log was given
2026-04-20 02:15:27.130540 | 
2026-04-20 02:15:27.130710 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-04-20 02:15:27.429660 | rockylinux-9 | ok: "logs"
2026-04-20 02:15:27.430494 | rockylinux-9 | ok: All items complete
2026-04-20 02:15:27.430548 | 
2026-04-20 02:15:27.652640 | rockylinux-9 | ok: "artifacts"
2026-04-20 02:15:27.892408 | rockylinux-9 | ok: "docs"
2026-04-20 02:15:27.917557 | 
2026-04-20 02:15:27.917707 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-04-20 02:15:28.180702 | rockylinux-9 | changed: "logs"
2026-04-20 02:15:28.406524 | rockylinux-9 | changed: "artifacts"
2026-04-20 02:15:28.660087 | rockylinux-9 | changed: "docs"
2026-04-20 02:15:28.692532 | 
2026-04-20 02:15:28.692685 | PLAY RECAP
2026-04-20 02:15:28.692782 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-20 02:15:28.692836 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-04-20 02:15:28.692870 | 
2026-04-20 02:15:28.939850 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-20 02:15:28.941552 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-20 02:15:29.769255 | 
2026-04-20 02:15:29.769620 | PLAY [all]
2026-04-20 02:15:29.786752 | 
2026-04-20 02:15:29.786982 | TASK [setup-uv : Extract archive]
2026-04-20 02:15:33.890002 | rockylinux-9 | changed
2026-04-20 02:15:33.899432 | 
2026-04-20 02:15:33.899624 | TASK [setup-uv : Print version]
2026-04-20 02:15:33.933660 | [rockylinux-9] Waiting on logger
2026-04-20 02:15:34.451504 | rockylinux-9 | ok: Runtime: 0:00:00.016390
2026-04-20 02:15:34.460410 | 
2026-04-20 02:15:34.460534 | PLAY RECAP
2026-04-20 02:15:34.460616 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-20 02:15:34.460662 | 
2026-04-20 02:15:34.671784 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-20 02:15:34.675375 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-04-20 02:15:35.345282 | 
2026-04-20 02:15:35.345450 | PLAY [all]
2026-04-20 02:15:35.357085 | 
2026-04-20 02:15:35.357193 | TASK [Copy inventory file for Zuul]
2026-04-20 02:15:36.754461 | rockylinux-9 | changed
2026-04-20 02:15:36.760091 | 
2026-04-20 02:15:36.760201 | TASK [Switch "ansible_host" to private IP]
2026-04-20 02:15:37.105262 | rockylinux-9 | changed: 1 replacements made
2026-04-20 02:15:37.111191 | 
2026-04-20 02:15:37.111269 | TASK [Run Molecule scenario]
2026-04-20 02:15:37.133251 | [rockylinux-9] Waiting on logger
2026-04-20 02:15:45.286084 | [rockylinux-9] Waiting on logger
2026-04-20 02:15:55.461729 | [rockylinux-9] Waiting on logger
2026-04-20 02:16:05.637780 | [rockylinux-9] Waiting on logger
2026-04-20 02:16:15.813799 | [rockylinux-9] Waiting on logger
2026-04-20 02:16:25.253975 | [rockylinux-9] Waiting on logger
2026-04-20 02:16:35.461870 | [rockylinux-9] Waiting on logger
2026-04-20 02:16:45.642353 | [rockylinux-9] Waiting on logger
2026-04-20 02:16:55.814048 | [rockylinux-9] Waiting on logger
2026-04-20 02:17:04.971003 | [rockylinux-9] Waiting on logger
2026-04-20 02:17:15.461931 | [rockylinux-9] Waiting on logger
2026-04-20 02:17:25.637989 | [rockylinux-9] Waiting on logger
2026-04-20 02:17:35.850109 | [rockylinux-9] Waiting on logger
2026-04-20 02:17:45.733783 | [rockylinux-9] Waiting on logger
2026-04-20 02:17:55.910149 | [rockylinux-9] Waiting on logger
2026-04-20 02:18:05.065945 | [rockylinux-9] Waiting on logger
2026-04-20 02:18:15.237814 | [rockylinux-9] Waiting on logger
2026-04-20 02:18:25.413894 | [rockylinux-9] Waiting on logger
2026-04-20 02:18:35.590016 | [rockylinux-9] Waiting on logger
2026-04-20 02:18:45.765956 | [rockylinux-9] Waiting on logger
2026-04-20 02:18:55.941951 | [rockylinux-9] Waiting on logger
2026-04-20 02:19:05.094200 | [rockylinux-9] Waiting on logger
2026-04-20 02:19:15.274111 | [rockylinux-9] Waiting on logger
2026-04-20 02:19:25.445969 | [rockylinux-9] Waiting on logger
2026-04-20 02:19:35.625994 | [rockylinux-9] Waiting on logger
2026-04-20 02:19:45.802008 | [rockylinux-9] Waiting on logger
2026-04-20 02:19:54.955086 | [rockylinux-9] Waiting on logger
2026-04-20 02:20:05.125954 | [rockylinux-9] Waiting on logger
2026-04-20 02:20:15.301898 | [rockylinux-9] Waiting on logger
2026-04-20 02:20:25.477815 | [rockylinux-9] Waiting on logger
2026-04-20 02:20:26.606205 | rockylinux-9 | ok: Runtime: 0:04:48.057862
2026-04-20 02:20:26.613767 | 
2026-04-20 02:20:26.613851 | PLAY RECAP
2026-04-20 02:20:26.613901 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-20 02:20:26.613924 | 
2026-04-20 02:20:26.752396 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-04-20 02:20:26.754560 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-20 02:20:27.405201 | 
2026-04-20 02:20:27.405354 | PLAY [all]
2026-04-20 02:20:27.416526 | 
2026-04-20 02:20:27.416674 | TASK [fetch-output : Set log path for multiple nodes]
2026-04-20 02:20:27.462145 | rockylinux-9 | skipping: Conditional result was False
2026-04-20 02:20:27.469692 | 
2026-04-20 02:20:27.469879 | TASK [fetch-output : Set log path for single node]
2026-04-20 02:20:27.514360 | rockylinux-9 | ok
2026-04-20 02:20:27.519947 | 
2026-04-20 02:20:27.520028 | LOOP [fetch-output : Ensure local output dirs]
2026-04-20 02:20:27.915344 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/692be796565f43c7a3b7a961112ae839/work/logs"
2026-04-20 02:20:28.132612 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/692be796565f43c7a3b7a961112ae839/work/artifacts"
2026-04-20 02:20:28.340368 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/692be796565f43c7a3b7a961112ae839/work/docs"
2026-04-20 02:20:28.358046 | 
2026-04-20 02:20:28.358196 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-04-20 02:20:29.080328 | rockylinux-9 | changed: .d..t...... ./
2026-04-20 02:20:29.080828 | rockylinux-9 | changed: All items complete
2026-04-20 02:20:29.080871 | 
2026-04-20 02:20:29.631464 | rockylinux-9 | changed: .d..t...... ./
2026-04-20 02:20:30.149665 | rockylinux-9 | changed: .d..t...... ./
2026-04-20 02:20:30.172857 | 
2026-04-20 02:20:30.173064 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-04-20 02:20:30.601434 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.007700
2026-04-20 02:20:30.843752 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.007884
2026-04-20 02:20:30.858332 | 
2026-04-20 02:20:30.858502 | PLAY [all]
2026-04-20 02:20:30.865578 | 
2026-04-20 02:20:30.865671 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-04-20 02:20:31.357375 | rockylinux-9 | changed
2026-04-20 02:20:31.364402 | 
2026-04-20 02:20:31.364481 | PLAY RECAP
2026-04-20 02:20:31.364527 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-04-20 02:20:31.364551 | 
2026-04-20 02:20:31.505054 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-20 02:20:31.506093 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-04-20 02:20:32.068862 | 
2026-04-20 02:20:32.069332 | PLAY [localhost]
2026-04-20 02:20:32.079322 | 
2026-04-20 02:20:32.079396 | TASK [Generate Zuul manifest]
2026-04-20 02:20:32.100466 | localhost | ok
2026-04-20 02:20:32.116436 | 
2026-04-20 02:20:32.116516 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-04-20 02:20:32.451041 | localhost | changed
2026-04-20 02:20:32.463461 | 
2026-04-20 02:20:32.463575 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-04-20 02:20:32.495038 | localhost | ok
2026-04-20 02:20:32.503243 | 
2026-04-20 02:20:32.503314 | TASK [Upload logs]
2026-04-20 02:20:32.524454 | localhost | ok
2026-04-20 02:20:32.580310 | 
2026-04-20 02:20:32.580419 | TASK [Set zuul-log-path fact]
2026-04-20 02:20:32.600134 | localhost | ok
2026-04-20 02:20:32.613557 | 
2026-04-20 02:20:32.613631 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-20 02:20:32.645365 | localhost | ok
2026-04-20 02:20:32.654390 | 
2026-04-20 02:20:32.654454 | TASK [upload-logs : Create log directories]
2026-04-20 02:20:33.083982 | localhost | changed
2026-04-20 02:20:33.089132 | 
2026-04-20 02:20:33.089204 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-04-20 02:20:33.479370 | localhost -> localhost | ok: Runtime: 0:00:00.004174
2026-04-20 02:20:33.487226 | 
2026-04-20 02:20:33.487316 | TASK [upload-logs : Upload logs to log server]
2026-04-20 02:20:33.975587 | localhost | Output suppressed because no_log was given
2026-04-20 02:20:33.979704 | 
2026-04-20 02:20:33.979781 | LOOP [upload-logs : Compress console log and json output]
2026-04-20 02:20:34.029879 | localhost | skipping: Conditional result was False
2026-04-20 02:20:34.037249 | localhost | skipping: Conditional result was False
2026-04-20 02:20:34.054143 | 
2026-04-20 02:20:34.054290 | LOOP [upload-logs : Upload compressed console log and json output]
2026-04-20 02:20:34.097552 | localhost | skipping: Conditional result was False
2026-04-20 02:20:34.098045 | 
2026-04-20 02:20:34.101371 | localhost | skipping: Conditional result was False
2026-04-20 02:20:34.118248 | 
2026-04-20 02:20:34.118640 | LOOP [upload-logs : Upload console log and json output]