2026-04-14 02:24:00.322783 | Job console starting 2026-04-14 02:24:00.346894 | Updating git repos 2026-04-14 02:24:00.382440 | Cloning repos into workspace 2026-04-14 02:24:00.460509 | Restoring repo states 2026-04-14 02:24:00.473311 | Merging changes 2026-04-14 02:24:02.113511 | Checking out repos 2026-04-14 02:24:02.289534 | Preparing playbooks 2026-04-14 02:24:09.152540 | Running Ansible setup 2026-04-14 02:24:17.063997 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-14 02:24:17.686297 | 2026-04-14 02:24:17.686468 | PLAY [localhost] 2026-04-14 02:24:17.693830 | 2026-04-14 02:24:17.693898 | TASK [Gathering Facts] 2026-04-14 02:24:20.333928 | localhost | ok 2026-04-14 02:24:20.344700 | 2026-04-14 02:24:20.344787 | TASK [Setup log path fact] 2026-04-14 02:24:20.366251 | localhost | ok 2026-04-14 02:24:20.385837 | 2026-04-14 02:24:20.385976 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-14 02:24:20.415952 | localhost | ok 2026-04-14 02:24:20.423756 | 2026-04-14 02:24:20.423854 | TASK [emit-job-header : Print job information] 2026-04-14 02:24:20.465739 | # Job Information 2026-04-14 02:24:20.465950 | Ansible Version: 2.16.16 2026-04-14 02:24:20.465985 | Job: ansible-collection-containers-molecule-containerd-rockylinux-9 2026-04-14 02:24:20.466008 | Pipeline: check 2026-04-14 02:24:20.466028 | Executor: 0a8996d2b663 2026-04-14 02:24:20.466047 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/107 2026-04-14 02:24:20.466069 | Event ID: cf0dfd70-37a8-11f1-93e5-fb954d9f646c 2026-04-14 02:24:20.469112 | 2026-04-14 02:24:20.469183 | LOOP [emit-job-header : Print node information] 2026-04-14 02:24:20.561818 | localhost | ok: 2026-04-14 02:24:20.562289 | localhost | # Node Information 2026-04-14 02:24:20.562375 | localhost | Inventory Hostname: rockylinux-9 2026-04-14 02:24:20.562435 | localhost | Hostname: np0000165935 2026-04-14 02:24:20.562479 | localhost | Username: zuul 2026-04-14 02:24:20.562530 | localhost | Distro: Rocky 9.7 2026-04-14 02:24:20.562574 | localhost | Provider: yul1 2026-04-14 02:24:20.562616 | localhost | Region: ca-ymq-1 2026-04-14 02:24:20.562656 | localhost | Label: rockylinux-9 2026-04-14 02:24:20.562695 | localhost | Product Name: OpenStack Nova 2026-04-14 02:24:20.562734 | localhost | Interface IP: 199.19.213.221 2026-04-14 02:24:20.572360 | 2026-04-14 02:24:20.572538 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-04-14 02:24:21.035335 | localhost -> localhost | changed 2026-04-14 02:24:21.041934 | 2026-04-14 02:24:21.042040 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-04-14 02:24:22.018355 | localhost -> localhost | changed 2026-04-14 02:24:22.028268 | 2026-04-14 02:24:22.028343 | PLAY [all] 2026-04-14 02:24:22.036447 | 2026-04-14 02:24:22.036512 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-04-14 02:24:22.261968 | rockylinux-9 -> localhost | ok 2026-04-14 02:24:22.271871 | 2026-04-14 02:24:22.271941 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-04-14 02:24:22.304128 | rockylinux-9 | ok 2026-04-14 02:24:22.320824 | rockylinux-9 | included: /var/lib/zuul/builds/1116f35c4eec4ad69f35520154486534/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-04-14 02:24:22.327017 | 2026-04-14 02:24:22.327103 | TASK [add-build-sshkey : Create Temp SSH key] 2026-04-14 02:24:23.412471 | rockylinux-9 -> localhost | Generating public/private rsa key pair. 2026-04-14 02:24:23.412662 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/1116f35c4eec4ad69f35520154486534/work/1116f35c4eec4ad69f35520154486534_id_rsa 2026-04-14 02:24:23.412694 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/1116f35c4eec4ad69f35520154486534/work/1116f35c4eec4ad69f35520154486534_id_rsa.pub 2026-04-14 02:24:23.412717 | rockylinux-9 -> localhost | The key fingerprint is: 2026-04-14 02:24:23.412738 | rockylinux-9 -> localhost | SHA256:wR2euI9NZ5EYJ6zxcKT6IWY27/BmhUCnfYi1WKum3TQ zuul-build-sshkey 2026-04-14 02:24:23.412781 | rockylinux-9 -> localhost | The key's randomart image is: 2026-04-14 02:24:23.412803 | rockylinux-9 -> localhost | +---[RSA 3072]----+ 2026-04-14 02:24:23.412826 | rockylinux-9 -> localhost | | o= . | 2026-04-14 02:24:23.412846 | rockylinux-9 -> localhost | | ..*=oB . | 2026-04-14 02:24:23.412866 | rockylinux-9 -> localhost | | . O=X= o | 2026-04-14 02:24:23.412886 | rockylinux-9 -> localhost | | =.*oo . | 2026-04-14 02:24:23.412905 | rockylinux-9 -> localhost | | BoSo. o | 2026-04-14 02:24:23.412924 | rockylinux-9 -> localhost | | +o=E*.o | 2026-04-14 02:24:23.412943 | rockylinux-9 -> localhost | | +.o+oo | 2026-04-14 02:24:23.412989 | rockylinux-9 -> localhost | | . .++ | 2026-04-14 02:24:23.413020 | rockylinux-9 -> localhost | | oo | 2026-04-14 02:24:23.413041 | rockylinux-9 -> localhost | +----[SHA256]-----+ 2026-04-14 02:24:23.413098 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.605761 2026-04-14 02:24:23.418327 | 2026-04-14 02:24:23.418395 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-04-14 02:24:23.457289 | rockylinux-9 | ok 2026-04-14 02:24:23.468347 | rockylinux-9 | included: /var/lib/zuul/builds/1116f35c4eec4ad69f35520154486534/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-04-14 02:24:23.477827 | 2026-04-14 02:24:23.477920 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-04-14 02:24:23.503518 | rockylinux-9 | skipping: Conditional result was False 2026-04-14 02:24:23.510782 | 2026-04-14 02:24:23.510855 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-04-14 02:24:23.998460 | rockylinux-9 | changed 2026-04-14 02:24:24.003955 | 2026-04-14 02:24:24.004019 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-04-14 02:24:24.254680 | rockylinux-9 | ok 2026-04-14 02:24:24.261167 | 2026-04-14 02:24:24.261255 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-04-14 02:24:25.198191 | rockylinux-9 | changed 2026-04-14 02:24:25.207873 | 2026-04-14 02:24:25.207984 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-04-14 02:24:26.099679 | rockylinux-9 | changed 2026-04-14 02:24:26.107241 | 2026-04-14 02:24:26.107358 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-04-14 02:24:26.132208 | rockylinux-9 | skipping: Conditional result was False 2026-04-14 02:24:26.140136 | 2026-04-14 02:24:26.140217 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-04-14 02:24:26.564080 | rockylinux-9 -> localhost | changed 2026-04-14 02:24:26.578605 | 2026-04-14 02:24:26.578714 | TASK [add-build-sshkey : Add back temp key] 2026-04-14 02:24:26.888891 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/1116f35c4eec4ad69f35520154486534/work/1116f35c4eec4ad69f35520154486534_id_rsa (zuul-build-sshkey) 2026-04-14 02:24:26.889139 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.015745 2026-04-14 02:24:26.896503 | 2026-04-14 02:24:26.896578 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-04-14 02:24:27.320820 | rockylinux-9 | ok 2026-04-14 02:24:27.566455 | 2026-04-14 02:24:27.566625 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-04-14 02:24:27.613475 | rockylinux-9 | skipping: Conditional result was False 2026-04-14 02:24:27.627261 | 2026-04-14 02:24:27.627422 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-04-14 02:24:28.032845 | rockylinux-9 | ok 2026-04-14 02:24:28.039536 | 2026-04-14 02:24:28.039632 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-04-14 02:24:29.831790 | rockylinux-9 | Output suppressed because no_log was given 2026-04-14 02:24:29.846338 | 2026-04-14 02:24:29.846537 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-04-14 02:24:30.114162 | rockylinux-9 | ok: "logs" 2026-04-14 02:24:30.114509 | rockylinux-9 | ok: All items complete 2026-04-14 02:24:30.114539 | 2026-04-14 02:24:30.329583 | rockylinux-9 | ok: "artifacts" 2026-04-14 02:24:30.926853 | rockylinux-9 | ok: "docs" 2026-04-14 02:24:30.944297 | 2026-04-14 02:24:30.944433 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-04-14 02:24:31.199981 | rockylinux-9 | changed: "logs" 2026-04-14 02:24:31.425684 | rockylinux-9 | changed: "artifacts" 2026-04-14 02:24:31.675110 | rockylinux-9 | changed: "docs" 2026-04-14 02:24:31.705227 | 2026-04-14 02:24:31.705370 | PLAY RECAP 2026-04-14 02:24:31.705419 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-14 02:24:31.705451 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-04-14 02:24:31.705472 | 2026-04-14 02:24:31.834927 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-14 02:24:31.842763 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-14 02:24:32.525215 | 2026-04-14 02:24:32.641056 | PLAY [all] 2026-04-14 02:24:32.667495 | 2026-04-14 02:24:32.667689 | TASK [setup-uv : Extract archive] 2026-04-14 02:24:35.386476 | rockylinux-9 | changed 2026-04-14 02:24:35.393434 | 2026-04-14 02:24:35.393532 | TASK [setup-uv : Print version] 2026-04-14 02:24:35.415020 | [rockylinux-9] Waiting on logger 2026-04-14 02:24:35.927319 | rockylinux-9 | ok: Runtime: 0:00:00.014904 2026-04-14 02:24:35.935031 | 2026-04-14 02:24:35.935085 | PLAY RECAP 2026-04-14 02:24:35.935134 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-14 02:24:35.935158 | 2026-04-14 02:24:36.070293 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-14 02:24:36.071816 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-14 02:24:36.687752 | 2026-04-14 02:24:36.687981 | PLAY [all] 2026-04-14 02:24:36.700156 | 2026-04-14 02:24:36.700307 | TASK [Copy inventory file for Zuul] 2026-04-14 02:24:38.025475 | rockylinux-9 | changed 2026-04-14 02:24:38.030815 | 2026-04-14 02:24:38.030894 | TASK [Switch "ansible_host" to private IP] 2026-04-14 02:24:38.355876 | rockylinux-9 | changed: 1 replacements made 2026-04-14 02:24:38.461577 | 2026-04-14 02:24:38.461714 | TASK [Run Molecule scenario] 2026-04-14 02:24:38.491525 | [rockylinux-9] Waiting on logger 2026-04-14 02:24:46.630354 | [rockylinux-9] Waiting on logger 2026-04-14 02:24:56.806007 | [rockylinux-9] Waiting on logger 2026-04-14 02:25:06.981922 | [rockylinux-9] Waiting on logger 2026-04-14 02:25:17.158103 | [rockylinux-9] Waiting on logger 2026-04-14 02:25:27.334063 | [rockylinux-9] Waiting on logger 2026-04-14 02:25:36.491934 | [rockylinux-9] Waiting on logger 2026-04-14 02:25:46.666068 | [rockylinux-9] Waiting on logger 2026-04-14 02:25:56.838186 | [rockylinux-9] Waiting on logger 2026-04-14 02:26:07.014151 | [rockylinux-9] Waiting on logger 2026-04-14 02:26:17.290049 | [rockylinux-9] Waiting on logger 2026-04-14 02:26:26.451265 | [rockylinux-9] Waiting on logger 2026-04-14 02:26:36.618202 | [rockylinux-9] Waiting on logger 2026-04-14 02:26:46.822152 | [rockylinux-9] Waiting on logger 2026-04-14 02:26:56.997793 | [rockylinux-9] Waiting on logger 2026-04-14 02:27:07.177933 | [rockylinux-9] Waiting on logger 2026-04-14 02:27:17.349963 | [rockylinux-9] Waiting on logger 2026-04-14 02:27:26.507255 | [rockylinux-9] Waiting on logger 2026-04-14 02:27:37.029929 | [rockylinux-9] Waiting on logger 2026-04-14 02:27:47.370134 | [rockylinux-9] Waiting on logger 2026-04-14 02:27:56.523223 | [rockylinux-9] Waiting on logger 2026-04-14 02:28:06.694347 | [rockylinux-9] Waiting on logger 2026-04-14 02:28:16.870096 | [rockylinux-9] Waiting on logger 2026-04-14 02:28:27.078187 | [rockylinux-9] Waiting on logger 2026-04-14 02:28:37.254112 | [rockylinux-9] Waiting on logger 2026-04-14 02:28:46.790216 | [rockylinux-9] Waiting on logger 2026-04-14 02:28:56.966405 | [rockylinux-9] Waiting on logger 2026-04-14 02:29:07.142393 | [rockylinux-9] Waiting on logger 2026-04-14 02:29:13.970920 | rockylinux-9 | ok: Runtime: 0:04:34.275665 2026-04-14 02:29:13.978657 | 2026-04-14 02:29:13.978711 | PLAY RECAP 2026-04-14 02:29:13.978756 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-14 02:29:13.978779 | 2026-04-14 02:29:14.087517 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-14 02:29:14.089477 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-14 02:29:14.675620 | 2026-04-14 02:29:14.675746 | PLAY [all] 2026-04-14 02:29:14.686273 | 2026-04-14 02:29:14.686355 | TASK [fetch-output : Set log path for multiple nodes] 2026-04-14 02:29:14.741697 | rockylinux-9 | skipping: Conditional result was False 2026-04-14 02:29:14.767095 | 2026-04-14 02:29:14.767311 | TASK [fetch-output : Set log path for single node] 2026-04-14 02:29:14.811984 | rockylinux-9 | ok 2026-04-14 02:29:14.819766 | 2026-04-14 02:29:14.819862 | LOOP [fetch-output : Ensure local output dirs] 2026-04-14 02:29:15.218846 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/1116f35c4eec4ad69f35520154486534/work/logs" 2026-04-14 02:29:15.534314 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/1116f35c4eec4ad69f35520154486534/work/artifacts" 2026-04-14 02:29:15.747528 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/1116f35c4eec4ad69f35520154486534/work/docs" 2026-04-14 02:29:15.762485 | 2026-04-14 02:29:15.763413 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-04-14 02:29:16.509999 | rockylinux-9 | changed: .d..t...... ./ 2026-04-14 02:29:16.510239 | rockylinux-9 | changed: All items complete 2026-04-14 02:29:16.510276 | 2026-04-14 02:29:17.045686 | rockylinux-9 | changed: .d..t...... ./ 2026-04-14 02:29:17.543041 | rockylinux-9 | changed: .d..t...... ./ 2026-04-14 02:29:17.569986 | 2026-04-14 02:29:17.570127 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-04-14 02:29:18.018177 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.008343 2026-04-14 02:29:18.299218 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.009396 2026-04-14 02:29:18.313625 | 2026-04-14 02:29:18.313751 | PLAY [all] 2026-04-14 02:29:18.319344 | 2026-04-14 02:29:18.319410 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-04-14 02:29:18.817395 | rockylinux-9 | changed 2026-04-14 02:29:18.824572 | 2026-04-14 02:29:18.824641 | PLAY RECAP 2026-04-14 02:29:18.824689 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-04-14 02:29:18.824712 | 2026-04-14 02:29:18.953211 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-14 02:29:18.955450 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-04-14 02:29:19.575980 | 2026-04-14 02:29:19.576145 | PLAY [localhost] 2026-04-14 02:29:19.587132 | 2026-04-14 02:29:19.587209 | TASK [Generate Zuul manifest] 2026-04-14 02:29:19.608117 | localhost | ok 2026-04-14 02:29:19.625431 | 2026-04-14 02:29:19.625518 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-04-14 02:29:19.950673 | localhost | changed 2026-04-14 02:29:19.964141 | 2026-04-14 02:29:19.964216 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-04-14 02:29:19.993806 | localhost | ok 2026-04-14 02:29:20.002940 | 2026-04-14 02:29:20.003035 | TASK [Upload logs] 2026-04-14 02:29:20.025893 | localhost | ok 2026-04-14 02:29:20.086689 | 2026-04-14 02:29:20.086865 | TASK [Set zuul-log-path fact] 2026-04-14 02:29:20.110493 | localhost | ok 2026-04-14 02:29:20.123020 | 2026-04-14 02:29:20.123094 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-14 02:29:20.153300 | localhost | ok 2026-04-14 02:29:20.162804 | 2026-04-14 02:29:20.162911 | TASK [upload-logs : Create log directories] 2026-04-14 02:29:20.586972 | localhost | changed 2026-04-14 02:29:20.592212 | 2026-04-14 02:29:20.592285 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-04-14 02:29:21.001058 | localhost -> localhost | ok: Runtime: 0:00:00.004907 2026-04-14 02:29:21.007687 | 2026-04-14 02:29:21.007775 | TASK [upload-logs : Upload logs to log server] 2026-04-14 02:29:21.505467 | localhost | Output suppressed because no_log was given 2026-04-14 02:29:21.511402 | 2026-04-14 02:29:21.511493 | LOOP [upload-logs : Compress console log and json output] 2026-04-14 02:29:21.560784 | localhost | skipping: Conditional result was False 2026-04-14 02:29:21.567520 | localhost | skipping: Conditional result was False 2026-04-14 02:29:21.577267 | 2026-04-14 02:29:21.577414 | LOOP [upload-logs : Upload compressed console log and json output] 2026-04-14 02:29:21.623754 | localhost | skipping: Conditional result was False 2026-04-14 02:29:21.624162 | 2026-04-14 02:29:21.628229 | localhost | skipping: Conditional result was False 2026-04-14 02:29:21.642436 | 2026-04-14 02:29:21.642568 | LOOP [upload-logs : Upload console log and json output]