2026-04-29 21:40:18.950555 | Job console starting 2026-04-29 21:40:18.966051 | Updating git repos 2026-04-29 21:40:19.018414 | Cloning repos into workspace 2026-04-29 21:40:19.052919 | Restoring repo states 2026-04-29 21:40:19.065752 | Merging changes 2026-04-29 21:40:20.262273 | Checking out repos 2026-04-29 21:40:20.758972 | Preparing playbooks 2026-04-29 21:40:24.619815 | Running Ansible setup 2026-04-29 21:40:29.126008 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-29 21:40:29.792343 | 2026-04-29 21:40:29.792486 | PLAY [localhost] 2026-04-29 21:40:29.800324 | 2026-04-29 21:40:29.800405 | TASK [Gathering Facts] 2026-04-29 21:40:30.717647 | localhost | ok 2026-04-29 21:40:30.728663 | 2026-04-29 21:40:30.728749 | TASK [Setup log path fact] 2026-04-29 21:40:30.749784 | localhost | ok 2026-04-29 21:40:30.766934 | 2026-04-29 21:40:30.767015 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-29 21:40:30.797311 | localhost | ok 2026-04-29 21:40:30.809047 | 2026-04-29 21:40:30.813939 | TASK [emit-job-header : Print job information] 2026-04-29 21:40:30.852183 | # Job Information 2026-04-29 21:40:30.852313 | Ansible Version: 2.16.18 2026-04-29 21:40:30.852345 | Job: ansible-collection-kubernetes-molecule-cluster-api 2026-04-29 21:40:30.852367 | Pipeline: check 2026-04-29 21:40:30.852387 | Executor: 2d72f0692154 2026-04-29 21:40:30.852407 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/271 2026-04-29 21:40:30.852429 | Event ID: d5e5a360-4413-11f1-919f-4bd0ddffef59 2026-04-29 21:40:30.855461 | 2026-04-29 21:40:30.855526 | LOOP [emit-job-header : Print node information] 2026-04-29 21:40:30.940881 | localhost | ok: 2026-04-29 21:40:30.941301 | localhost | # Node Information 2026-04-29 21:40:30.941347 | localhost | Inventory Hostname: controller 2026-04-29 21:40:30.941377 | localhost | Hostname: np0000171329 2026-04-29 21:40:30.941404 | localhost | Username: zuul 2026-04-29 21:40:30.941434 | localhost | Distro: Debian 13.4 2026-04-29 21:40:30.941461 | localhost | Provider: yul1 2026-04-29 21:40:30.941486 | localhost | Region: ca-ymq-1 2026-04-29 21:40:30.941510 | localhost | Label: debian-trixie 2026-04-29 21:40:30.941535 | localhost | Product Name: OpenStack Nova 2026-04-29 21:40:30.941559 | localhost | Interface IP: 199.204.45.227 2026-04-29 21:40:30.948792 | 2026-04-29 21:40:30.949164 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-04-29 21:40:31.409189 | localhost -> localhost | changed 2026-04-29 21:40:31.417752 | 2026-04-29 21:40:31.417849 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-04-29 21:40:32.732898 | localhost -> localhost | changed 2026-04-29 21:40:32.739512 | 2026-04-29 21:40:32.739569 | PLAY [all] 2026-04-29 21:40:32.746266 | 2026-04-29 21:40:32.746337 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-04-29 21:40:32.993217 | controller -> localhost | ok 2026-04-29 21:40:32.997957 | 2026-04-29 21:40:32.998019 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-04-29 21:40:33.030855 | controller | ok 2026-04-29 21:40:33.051569 | controller | included: /var/lib/zuul/builds/fabc142c44494175b8b6aae43ea9333a/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-04-29 21:40:33.056270 | 2026-04-29 21:40:33.056334 | TASK [add-build-sshkey : Create Temp SSH key] 2026-04-29 21:40:33.844286 | controller -> localhost | Generating public/private rsa key pair. 2026-04-29 21:40:33.844562 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/fabc142c44494175b8b6aae43ea9333a/work/fabc142c44494175b8b6aae43ea9333a_id_rsa 2026-04-29 21:40:33.844820 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/fabc142c44494175b8b6aae43ea9333a/work/fabc142c44494175b8b6aae43ea9333a_id_rsa.pub 2026-04-29 21:40:33.844869 | controller -> localhost | The key fingerprint is: 2026-04-29 21:40:33.844902 | controller -> localhost | SHA256:ZfCPz90T4Gz54BWb3mKU3FVa3JHbtvWjxaRZcXPHiNU zuul-build-sshkey 2026-04-29 21:40:33.844976 | controller -> localhost | The key's randomart image is: 2026-04-29 21:40:33.845012 | controller -> localhost | +---[RSA 3072]----+ 2026-04-29 21:40:33.845049 | controller -> localhost | | . o.**| 2026-04-29 21:40:33.845081 | controller -> localhost | | o . .*E| 2026-04-29 21:40:33.845118 | controller -> localhost | | + ...X| 2026-04-29 21:40:33.845158 | controller -> localhost | | o oo.o*O| 2026-04-29 21:40:33.845196 | controller -> localhost | | S . .*X*=| 2026-04-29 21:40:33.845228 | controller -> localhost | | oo==*+| 2026-04-29 21:40:33.845256 | controller -> localhost | | o.*++| 2026-04-29 21:40:33.845285 | controller -> localhost | | o ..| 2026-04-29 21:40:33.845319 | controller -> localhost | | | 2026-04-29 21:40:33.845349 | controller -> localhost | +----[SHA256]-----+ 2026-04-29 21:40:33.845418 | controller -> localhost | ok: Runtime: 0:00:00.330796 2026-04-29 21:40:33.851356 | 2026-04-29 21:40:33.851480 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-04-29 21:40:33.885060 | controller | ok 2026-04-29 21:40:33.897068 | controller | included: /var/lib/zuul/builds/fabc142c44494175b8b6aae43ea9333a/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-04-29 21:40:33.903425 | 2026-04-29 21:40:33.903488 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-04-29 21:40:33.938391 | controller | skipping: Conditional result was False 2026-04-29 21:40:33.948295 | 2026-04-29 21:40:33.948362 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-04-29 21:40:34.521121 | controller | changed 2026-04-29 21:40:34.526200 | 2026-04-29 21:40:34.526278 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-04-29 21:40:34.747286 | controller | ok 2026-04-29 21:40:34.758462 | 2026-04-29 21:40:34.758557 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-04-29 21:40:35.319929 | controller | changed 2026-04-29 21:40:35.324808 | 2026-04-29 21:40:35.324873 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-04-29 21:40:35.895899 | controller | changed 2026-04-29 21:40:35.900069 | 2026-04-29 21:40:35.900132 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-04-29 21:40:35.924804 | controller | skipping: Conditional result was False 2026-04-29 21:40:35.932026 | 2026-04-29 21:40:35.932133 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-04-29 21:40:36.302101 | controller -> localhost | changed 2026-04-29 21:40:36.314216 | 2026-04-29 21:40:36.314319 | TASK [add-build-sshkey : Add back temp key] 2026-04-29 21:40:36.658326 | controller -> localhost | Identity added: /var/lib/zuul/builds/fabc142c44494175b8b6aae43ea9333a/work/fabc142c44494175b8b6aae43ea9333a_id_rsa (zuul-build-sshkey) 2026-04-29 21:40:36.658547 | controller -> localhost | ok: Runtime: 0:00:00.012929 2026-04-29 21:40:36.662436 | 2026-04-29 21:40:36.662498 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-04-29 21:40:37.040821 | controller | ok 2026-04-29 21:40:37.048105 | 2026-04-29 21:40:37.048204 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-04-29 21:40:37.077368 | controller | skipping: Conditional result was False 2026-04-29 21:40:37.091045 | 2026-04-29 21:40:37.091225 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-04-29 21:40:37.505802 | controller | ok 2026-04-29 21:40:37.516647 | 2026-04-29 21:40:37.516831 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-04-29 21:40:38.618759 | controller | Output suppressed because no_log was given 2026-04-29 21:40:38.631401 | 2026-04-29 21:40:38.631606 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-04-29 21:40:38.862813 | controller | ok: "logs" 2026-04-29 21:40:38.863119 | controller | ok: All items complete 2026-04-29 21:40:38.863168 | 2026-04-29 21:40:39.053690 | controller | ok: "artifacts" 2026-04-29 21:40:39.269262 | controller | ok: "docs" 2026-04-29 21:40:39.285176 | 2026-04-29 21:40:39.285326 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-04-29 21:40:39.560486 | controller | changed: "logs" 2026-04-29 21:40:39.769293 | controller | changed: "artifacts" 2026-04-29 21:40:39.947944 | controller | changed: "docs" 2026-04-29 21:40:39.962952 | 2026-04-29 21:40:39.963091 | PLAY RECAP 2026-04-29 21:40:39.963148 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-04-29 21:40:39.963178 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-29 21:40:39.963201 | 2026-04-29 21:40:40.150728 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-29 21:40:40.758263 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-29 21:40:41.452622 | 2026-04-29 21:40:41.452746 | PLAY [all] 2026-04-29 21:40:41.463373 | 2026-04-29 21:40:41.463452 | TASK [setup-uv : Extract archive] 2026-04-29 21:40:42.574237 | controller | ERROR 2026-04-29 21:40:42.574585 | controller | { 2026-04-29 21:40:42.574645 | controller | "msg": "Invalid archive '/home/zuul/.ansible/tmp/ansible-tmp-1777498841.5065243-5-5103071469750/uv-x86_64-unknown-linux-gnuntmn7lbo.tar.gz', the file is 0 bytes" 2026-04-29 21:40:42.574692 | controller | } failure 2026-04-29 21:40:42.576466 | 2026-04-29 21:40:42.576528 | PLAY RECAP 2026-04-29 21:40:42.576586 | controller | ok: 0 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-04-29 21:40:42.576620 | 2026-04-29 21:40:42.777604 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-29 21:40:42.781594 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-29 21:40:43.449230 | 2026-04-29 21:40:43.449370 | PLAY [all] 2026-04-29 21:40:43.460325 | 2026-04-29 21:40:43.460417 | TASK [fetch-output : Set log path for multiple nodes] 2026-04-29 21:40:43.506541 | controller | skipping: Conditional result was False 2026-04-29 21:40:43.513795 | 2026-04-29 21:40:43.513971 | TASK [fetch-output : Set log path for single node] 2026-04-29 21:40:43.557662 | controller | ok 2026-04-29 21:40:43.563109 | 2026-04-29 21:40:43.563196 | LOOP [fetch-output : Ensure local output dirs] 2026-04-29 21:40:44.020492 | controller -> localhost | ok: "/var/lib/zuul/builds/fabc142c44494175b8b6aae43ea9333a/work/logs" 2026-04-29 21:40:44.280764 | controller -> localhost | changed: "/var/lib/zuul/builds/fabc142c44494175b8b6aae43ea9333a/work/artifacts" 2026-04-29 21:40:44.512801 | controller -> localhost | changed: "/var/lib/zuul/builds/fabc142c44494175b8b6aae43ea9333a/work/docs" 2026-04-29 21:40:44.528507 | 2026-04-29 21:40:44.528668 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-04-29 21:40:45.241744 | controller | ok 2026-04-29 21:40:45.242123 | controller | changed: All items complete 2026-04-29 21:40:45.242187 | 2026-04-29 21:40:45.880136 | controller | changed: .d..t...... ./ 2026-04-29 21:40:46.374475 | controller | changed: .d..t...... ./ 2026-04-29 21:40:46.387436 | 2026-04-29 21:40:46.387576 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-04-29 21:40:47.076043 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.267096 2026-04-29 21:40:47.300906 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.008971 2026-04-29 21:40:47.316846 | 2026-04-29 21:40:47.316912 | PLAY [all] 2026-04-29 21:40:47.321750 | 2026-04-29 21:40:47.321813 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-04-29 21:40:47.852238 | controller | changed 2026-04-29 21:40:47.857048 | 2026-04-29 21:40:47.857101 | PLAY RECAP 2026-04-29 21:40:47.857155 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-04-29 21:40:47.857177 | 2026-04-29 21:40:47.998508 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-29 21:40:48.001916 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-04-29 21:40:48.590760 | 2026-04-29 21:40:48.590900 | PLAY [localhost] 2026-04-29 21:40:48.601012 | 2026-04-29 21:40:48.601087 | TASK [Generate Zuul manifest] 2026-04-29 21:40:48.618713 | localhost | ok 2026-04-29 21:40:48.632977 | 2026-04-29 21:40:48.633059 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-04-29 21:40:49.057382 | localhost | changed 2026-04-29 21:40:49.386527 | 2026-04-29 21:40:49.386707 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-04-29 21:40:49.461229 | localhost | ok 2026-04-29 21:40:49.471070 | 2026-04-29 21:40:49.471189 | TASK [Upload logs] 2026-04-29 21:40:49.498065 | localhost | ok 2026-04-29 21:40:49.585067 | 2026-04-29 21:40:49.585229 | TASK [Set zuul-log-path fact] 2026-04-29 21:40:49.607558 | localhost | ok 2026-04-29 21:40:49.618987 | 2026-04-29 21:40:49.619061 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-29 21:40:49.652420 | localhost | ok 2026-04-29 21:40:49.663352 | 2026-04-29 21:40:49.663497 | TASK [upload-logs : Create log directories] 2026-04-29 21:40:50.144526 | localhost | changed 2026-04-29 21:40:50.149556 | 2026-04-29 21:40:50.149639 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-04-29 21:40:50.573763 | localhost -> localhost | ok: Runtime: 0:00:00.005535 2026-04-29 21:40:50.580848 | 2026-04-29 21:40:50.580918 | TASK [upload-logs : Upload logs to log server] 2026-04-29 21:40:51.166502 | localhost | Output suppressed because no_log was given 2026-04-29 21:40:51.172282 | 2026-04-29 21:40:51.172424 | LOOP [upload-logs : Compress console log and json output] 2026-04-29 21:40:51.226316 | localhost | skipping: Conditional result was False 2026-04-29 21:40:51.277836 | localhost | skipping: Conditional result was False 2026-04-29 21:40:51.289055 | 2026-04-29 21:40:51.289206 | LOOP [upload-logs : Upload compressed console log and json output] 2026-04-29 21:40:51.339377 | localhost | skipping: Conditional result was False 2026-04-29 21:40:51.339702 | 2026-04-29 21:40:51.343608 | localhost | skipping: Conditional result was False 2026-04-29 21:40:51.355217 | 2026-04-29 21:40:51.355376 | LOOP [upload-logs : Upload console log and json output]