2026-02-16 21:11:14.381074 | Job console starting 2026-02-16 21:11:14.389181 | Updating git repos 2026-02-16 21:11:14.420569 | Cloning repos into workspace 2026-02-16 21:11:14.484994 | Restoring repo states 2026-02-16 21:11:14.501219 | Merging changes 2026-02-16 21:11:15.163081 | Checking out repos 2026-02-16 21:11:15.348112 | Preparing playbooks 2026-02-16 21:11:16.357324 | Running Ansible setup 2026-02-16 21:11:19.931311 | PRE-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main] 2026-02-16 21:11:20.515632 | 2026-02-16 21:11:20.515775 | PLAY [localhost] 2026-02-16 21:11:20.523228 | 2026-02-16 21:11:20.523297 | TASK [Gathering Facts] 2026-02-16 21:11:21.421148 | localhost | ok 2026-02-16 21:11:21.427796 | 2026-02-16 21:11:21.427882 | TASK [Setup log path fact] 2026-02-16 21:11:21.455812 | localhost | ok 2026-02-16 21:11:21.467759 | 2026-02-16 21:11:21.467862 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-02-16 21:11:21.495655 | localhost | ok 2026-02-16 21:11:21.502262 | 2026-02-16 21:11:21.502342 | TASK [emit-job-header : Print job information] 2026-02-16 21:11:21.541909 | # Job Information 2026-02-16 21:11:21.542101 | Ansible Version: 2.16.15 2026-02-16 21:11:21.542132 | Job: ansible-collection-kubernetes-molecule-aio-rockylinux-9 2026-02-16 21:11:21.542155 | Pipeline: check 2026-02-16 21:11:21.542174 | Executor: 3a2793d2bd32 2026-02-16 21:11:21.542194 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/228 2026-02-16 21:11:21.542218 | Event ID: cd016660-0b7b-11f1-84fa-182493e0db12 2026-02-16 21:11:21.545437 | 2026-02-16 21:11:21.545509 | LOOP [emit-job-header : Print node information] 2026-02-16 21:11:21.635406 | localhost | ok: 2026-02-16 21:11:21.635666 | localhost | # Node Information 2026-02-16 21:11:21.635716 | localhost | Inventory Hostname: controller 2026-02-16 21:11:21.635748 | localhost | Hostname: np0000155798 2026-02-16 21:11:21.635775 | localhost | Username: zuul 2026-02-16 21:11:21.635806 | localhost | Distro: Rocky 9.7 2026-02-16 21:11:21.635832 | localhost | Provider: yul1 2026-02-16 21:11:21.635859 | localhost | Region: ca-ymq-1 2026-02-16 21:11:21.635885 | localhost | Label: rockylinux-9 2026-02-16 21:11:21.635910 | localhost | Product Name: OpenStack Nova 2026-02-16 21:11:21.635935 | localhost | Interface IP: 199.204.45.138 2026-02-16 21:11:21.653343 | 2026-02-16 21:11:21.653501 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-02-16 21:11:22.100849 | localhost -> localhost | changed 2026-02-16 21:11:22.106277 | 2026-02-16 21:11:22.106468 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-02-16 21:11:22.991820 | localhost -> localhost | changed 2026-02-16 21:11:22.999274 | 2026-02-16 21:11:22.999329 | PLAY [all] 2026-02-16 21:11:23.006682 | 2026-02-16 21:11:23.006743 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-02-16 21:11:23.274771 | controller -> localhost | ok 2026-02-16 21:11:23.280771 | 2026-02-16 21:11:23.280849 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-02-16 21:11:23.312581 | controller | ok 2026-02-16 21:11:23.334918 | controller | included: /var/lib/zuul/builds/318a3b6ae8d54a8ebb8a689d80a7da64/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-02-16 21:11:23.340313 | 2026-02-16 21:11:23.340375 | TASK [add-build-sshkey : Create Temp SSH key] 2026-02-16 21:11:24.315470 | controller -> localhost | Generating public/private rsa key pair. 2026-02-16 21:11:24.315676 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/318a3b6ae8d54a8ebb8a689d80a7da64/work/318a3b6ae8d54a8ebb8a689d80a7da64_id_rsa 2026-02-16 21:11:24.315707 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/318a3b6ae8d54a8ebb8a689d80a7da64/work/318a3b6ae8d54a8ebb8a689d80a7da64_id_rsa.pub 2026-02-16 21:11:24.315733 | controller -> localhost | The key fingerprint is: 2026-02-16 21:11:24.315755 | controller -> localhost | SHA256:QCZGVA2GANPtwNsY/NQf0GLy3QxEdUrwY0Nm/+VhgUA zuul-build-sshkey 2026-02-16 21:11:24.315788 | controller -> localhost | The key's randomart image is: 2026-02-16 21:11:24.315809 | controller -> localhost | +---[RSA 3072]----+ 2026-02-16 21:11:24.315835 | controller -> localhost | |o=.==+BB+oBEo .. | 2026-02-16 21:11:24.315856 | controller -> localhost | | .*.==+ =* + . .| 2026-02-16 21:11:24.315876 | controller -> localhost | | X +.+ =* . o.| 2026-02-16 21:11:24.315895 | controller -> localhost | | o + ..o.oo ..o.| 2026-02-16 21:11:24.315914 | controller -> localhost | | S . .| 2026-02-16 21:11:24.315933 | controller -> localhost | | | 2026-02-16 21:11:24.315952 | controller -> localhost | | | 2026-02-16 21:11:24.315971 | controller -> localhost | | | 2026-02-16 21:11:24.315992 | controller -> localhost | | | 2026-02-16 21:11:24.316011 | controller -> localhost | +----[SHA256]-----+ 2026-02-16 21:11:24.316072 | controller -> localhost | ok: Runtime: 0:00:00.538705 2026-02-16 21:11:24.322242 | 2026-02-16 21:11:24.322346 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-02-16 21:11:24.353477 | controller | ok 2026-02-16 21:11:24.361912 | controller | included: /var/lib/zuul/builds/318a3b6ae8d54a8ebb8a689d80a7da64/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-02-16 21:11:24.370206 | 2026-02-16 21:11:24.370280 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-02-16 21:11:24.395827 | controller | skipping: Conditional result was False 2026-02-16 21:11:24.403803 | 2026-02-16 21:11:24.403923 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-02-16 21:11:24.967383 | controller | changed 2026-02-16 21:11:24.972018 | 2026-02-16 21:11:24.972081 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-02-16 21:11:25.237814 | controller | ok 2026-02-16 21:11:25.244514 | 2026-02-16 21:11:25.244604 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-02-16 21:11:26.073988 | controller | changed 2026-02-16 21:11:26.095018 | 2026-02-16 21:11:26.095192 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-02-16 21:11:26.906713 | controller | changed 2026-02-16 21:11:26.911318 | 2026-02-16 21:11:26.911380 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-02-16 21:11:26.935877 | controller | skipping: Conditional result was False 2026-02-16 21:11:26.941406 | 2026-02-16 21:11:26.941473 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-02-16 21:11:27.352664 | controller -> localhost | changed 2026-02-16 21:11:27.366137 | 2026-02-16 21:11:27.366209 | TASK [add-build-sshkey : Add back temp key] 2026-02-16 21:11:27.643478 | controller -> localhost | Identity added: /var/lib/zuul/builds/318a3b6ae8d54a8ebb8a689d80a7da64/work/318a3b6ae8d54a8ebb8a689d80a7da64_id_rsa (zuul-build-sshkey) 2026-02-16 21:11:27.643860 | controller -> localhost | ok: Runtime: 0:00:00.013928 2026-02-16 21:11:27.652162 | 2026-02-16 21:11:27.652228 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-02-16 21:11:28.020851 | controller | ok 2026-02-16 21:11:28.025105 | 2026-02-16 21:11:28.025166 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-02-16 21:11:28.049866 | controller | skipping: Conditional result was False 2026-02-16 21:11:28.062282 | 2026-02-16 21:11:28.062359 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-02-16 21:11:28.450119 | controller | ok 2026-02-16 21:11:28.457280 | 2026-02-16 21:11:28.457341 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-02-16 21:11:29.524401 | controller | Output suppressed because no_log was given 2026-02-16 21:11:29.533337 | 2026-02-16 21:11:29.533399 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-02-16 21:11:29.763565 | controller | ok: "logs" 2026-02-16 21:11:29.763910 | controller | ok: All items complete 2026-02-16 21:11:29.763961 | 2026-02-16 21:11:29.965206 | controller | ok: "artifacts" 2026-02-16 21:11:30.154480 | controller | ok: "docs" 2026-02-16 21:11:30.171186 | 2026-02-16 21:11:30.171351 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-02-16 21:11:30.408931 | controller | changed: "logs" 2026-02-16 21:11:30.604788 | controller | changed: "artifacts" 2026-02-16 21:11:30.806284 | controller | changed: "docs" 2026-02-16 21:11:30.821167 | 2026-02-16 21:11:30.821337 | PLAY RECAP 2026-02-16 21:11:30.821437 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-02-16 21:11:30.821504 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-02-16 21:11:30.821555 | 2026-02-16 21:11:30.944442 | PRE-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main] 2026-02-16 21:11:30.946727 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-02-16 21:11:31.519857 | 2026-02-16 21:11:31.519987 | PLAY [all] 2026-02-16 21:11:31.530672 | 2026-02-16 21:11:31.530751 | TASK [setup-uv : Extract archive] 2026-02-16 21:11:34.328127 | controller | changed 2026-02-16 21:11:34.335568 | 2026-02-16 21:11:34.335714 | TASK [setup-uv : Print version] 2026-02-16 21:11:34.358841 | [controller] Waiting on logger 2026-02-16 21:11:34.775758 | controller | ok: Runtime: 0:00:00.012972 2026-02-16 21:11:34.782202 | 2026-02-16 21:11:34.782250 | PLAY RECAP 2026-02-16 21:11:34.782298 | controller | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-02-16 21:11:34.782323 | 2026-02-16 21:11:34.895822 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-02-16 21:11:34.899277 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-02-16 21:11:35.449505 | 2026-02-16 21:11:35.449638 | PLAY [all] 2026-02-16 21:11:35.460105 | 2026-02-16 21:11:35.460200 | TASK [Copy inventory file for Zuul] 2026-02-16 21:11:36.679457 | controller | changed 2026-02-16 21:11:36.686413 | 2026-02-16 21:11:36.686535 | TASK [Switch "ansible_host" to private IP] 2026-02-16 21:11:37.055298 | controller | changed: 1 replacements made 2026-02-16 21:11:37.062655 | 2026-02-16 21:11:37.062725 | TASK [Run Molecule scenario] 2026-02-16 21:11:37.088887 | [controller] Waiting on logger 2026-02-16 21:11:45.285816 | [controller] Waiting on logger 2026-02-16 21:11:54.443042 | [controller] Waiting on logger 2026-02-16 21:12:04.613877 | [controller] Waiting on logger 2026-02-16 21:12:14.790221 | [controller] Waiting on logger 2026-02-16 21:12:24.965990 | [controller] Waiting on logger 2026-02-16 21:12:35.146031 | [controller] Waiting on logger 2026-02-16 21:12:45.321878 | [controller] Waiting on logger 2026-02-16 21:12:54.479234 | [controller] Waiting on logger 2026-02-16 21:13:04.646060 | [controller] Waiting on logger 2026-02-16 21:13:14.822165 | [controller] Waiting on logger 2026-02-16 21:13:24.997956 | [controller] Waiting on logger 2026-02-16 21:13:35.174098 | [controller] Waiting on logger 2026-02-16 21:13:45.349969 | [controller] Waiting on logger 2026-02-16 21:13:54.506064 | [controller] Waiting on logger 2026-02-16 21:14:04.678035 | [controller] Waiting on logger 2026-02-16 21:14:14.854444 | [controller] Waiting on logger 2026-02-16 21:14:25.029745 | [controller] Waiting on logger 2026-02-16 21:14:35.205979 | [controller] Waiting on logger 2026-02-16 21:14:44.363479 | [controller] Waiting on logger 2026-02-16 21:14:54.534139 | [controller] Waiting on logger 2026-02-16 21:15:04.713962 | [controller] Waiting on logger 2026-02-16 21:15:14.885829 | [controller] Waiting on logger 2026-02-16 21:15:25.061786 | [controller] Waiting on logger 2026-02-16 21:15:35.237960 | [controller] Waiting on logger 2026-02-16 21:15:44.395217 | [controller] Waiting on logger 2026-02-16 21:15:54.565990 | [controller] Waiting on logger 2026-02-16 21:16:04.741982 | [controller] Waiting on logger 2026-02-16 21:16:14.917781 | [controller] Waiting on logger 2026-02-16 21:16:25.094067 | [controller] Waiting on logger 2026-02-16 21:16:35.270122 | [controller] Waiting on logger 2026-02-16 21:16:39.852744 | controller | ok: Runtime: 0:05:02.065315 2026-02-16 21:16:39.867368 | 2026-02-16 21:16:39.867430 | PLAY RECAP 2026-02-16 21:16:39.867479 | controller | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-02-16 21:16:39.867500 | 2026-02-16 21:16:39.979472 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-02-16 21:16:39.982218 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main] 2026-02-16 21:16:40.578179 | 2026-02-16 21:16:40.578335 | PLAY [all] 2026-02-16 21:16:40.589038 | 2026-02-16 21:16:40.589118 | TASK [fetch-output : Set log path for multiple nodes] 2026-02-16 21:16:40.645023 | controller | skipping: Conditional result was False 2026-02-16 21:16:40.649758 | 2026-02-16 21:16:40.649835 | TASK [fetch-output : Set log path for single node] 2026-02-16 21:16:40.689209 | controller | ok 2026-02-16 21:16:40.694988 | 2026-02-16 21:16:40.695057 | LOOP [fetch-output : Ensure local output dirs] 2026-02-16 21:16:41.125340 | controller -> localhost | ok: "/var/lib/zuul/builds/318a3b6ae8d54a8ebb8a689d80a7da64/work/logs" 2026-02-16 21:16:41.368585 | controller -> localhost | changed: "/var/lib/zuul/builds/318a3b6ae8d54a8ebb8a689d80a7da64/work/artifacts" 2026-02-16 21:16:41.618023 | controller -> localhost | changed: "/var/lib/zuul/builds/318a3b6ae8d54a8ebb8a689d80a7da64/work/docs" 2026-02-16 21:16:41.634698 | 2026-02-16 21:16:41.634817 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-02-16 21:16:42.366062 | controller | changed: .d..t...... ./ 2026-02-16 21:16:42.366361 | controller | changed: All items complete 2026-02-16 21:16:42.366402 | 2026-02-16 21:16:42.868776 | controller | changed: .d..t...... ./ 2026-02-16 21:16:43.384642 | controller | changed: .d..t...... ./ 2026-02-16 21:16:43.400615 | 2026-02-16 21:16:43.400759 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-02-16 21:16:43.819968 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.007096 2026-02-16 21:16:44.128540 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.007647 2026-02-16 21:16:44.145664 | 2026-02-16 21:16:44.145814 | PLAY [all] 2026-02-16 21:16:44.157824 | 2026-02-16 21:16:44.158032 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-02-16 21:16:44.662258 | controller | changed 2026-02-16 21:16:44.668008 | 2026-02-16 21:16:44.668065 | PLAY RECAP 2026-02-16 21:16:44.668118 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-02-16 21:16:44.668141 | 2026-02-16 21:16:44.819630 | POST-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main] 2026-02-16 21:16:44.821248 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post-logs.yaml@main] 2026-02-16 21:16:45.391672 | 2026-02-16 21:16:45.391813 | PLAY [localhost] 2026-02-16 21:16:45.402352 | 2026-02-16 21:16:45.402426 | TASK [Generate Zuul manifest] 2026-02-16 21:16:45.426450 | localhost | ok 2026-02-16 21:16:45.443169 | 2026-02-16 21:16:45.443257 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-02-16 21:16:45.763078 | localhost | changed 2026-02-16 21:16:45.776061 | 2026-02-16 21:16:45.776142 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-02-16 21:16:45.807445 | localhost | ok 2026-02-16 21:16:45.816394 | 2026-02-16 21:16:45.816457 | TASK [Upload logs] 2026-02-16 21:16:45.838733 | localhost | ok 2026-02-16 21:16:45.899865 | 2026-02-16 21:16:45.900011 | TASK [Set zuul-log-path fact] 2026-02-16 21:16:45.920125 | localhost | ok 2026-02-16 21:16:45.932718 | 2026-02-16 21:16:45.932790 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-02-16 21:16:45.974134 | localhost | ok 2026-02-16 21:16:45.982925 | 2026-02-16 21:16:45.982991 | TASK [upload-logs : Create log directories] 2026-02-16 21:16:46.442518 | localhost | changed 2026-02-16 21:16:46.448746 | 2026-02-16 21:16:46.448820 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-02-16 21:16:46.857100 | localhost -> localhost | ok: Runtime: 0:00:00.006272 2026-02-16 21:16:46.863978 | 2026-02-16 21:16:46.864056 | TASK [upload-logs : Upload logs to log server] 2026-02-16 21:16:47.374110 | localhost | Output suppressed because no_log was given 2026-02-16 21:16:47.379053 | 2026-02-16 21:16:47.379120 | LOOP [upload-logs : Compress console log and json output] 2026-02-16 21:16:47.424614 | localhost | skipping: Conditional result was False 2026-02-16 21:16:47.430674 | localhost | skipping: Conditional result was False 2026-02-16 21:16:47.445008 | 2026-02-16 21:16:47.445213 | LOOP [upload-logs : Upload compressed console log and json output] 2026-02-16 21:16:47.488079 | localhost | skipping: Conditional result was False 2026-02-16 21:16:47.488529 | 2026-02-16 21:16:47.491322 | localhost | skipping: Conditional result was False 2026-02-16 21:16:47.502255 | 2026-02-16 21:16:47.502396 | LOOP [upload-logs : Upload console log and json output]