2026-04-18 17:52:46.354859 | Job console starting 2026-04-18 17:52:46.363776 | Updating git repos 2026-04-18 17:52:46.406905 | Cloning repos into workspace 2026-04-18 17:52:46.453883 | Restoring repo states 2026-04-18 17:52:46.466140 | Merging changes 2026-04-18 17:52:47.052735 | Checking out repos 2026-04-18 17:52:47.248649 | Preparing playbooks 2026-04-18 17:52:48.949387 | Running Ansible setup 2026-04-18 17:52:52.567894 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-18 17:52:53.208466 | 2026-04-18 17:52:53.249699 | PLAY [localhost] 2026-04-18 17:52:53.263929 | 2026-04-18 17:52:53.264042 | TASK [Gathering Facts] 2026-04-18 17:52:54.533379 | localhost | ok 2026-04-18 17:52:54.540534 | 2026-04-18 17:52:54.540623 | TASK [Setup log path fact] 2026-04-18 17:52:54.562525 | localhost | ok 2026-04-18 17:52:54.575210 | 2026-04-18 17:52:54.575355 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-18 17:52:54.607739 | localhost | ok 2026-04-18 17:52:54.615395 | 2026-04-18 17:52:54.615520 | TASK [emit-job-header : Print job information] 2026-04-18 17:52:54.655930 | # Job Information 2026-04-18 17:52:54.656122 | Ansible Version: 2.16.16 2026-04-18 17:52:54.656169 | Job: ansible-collection-kubernetes-molecule-bgp 2026-04-18 17:52:54.656250 | Pipeline: check 2026-04-18 17:52:54.656298 | Executor: 0a8996d2b663 2026-04-18 17:52:54.656333 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/260 2026-04-18 17:52:54.656367 | Event ID: 63431510-3b4c-11f1-9dcc-f0b7af1855b2 2026-04-18 17:52:54.660739 | 2026-04-18 17:52:54.660835 | LOOP [emit-job-header : Print node information] 2026-04-18 17:52:54.738947 | localhost | ok: 2026-04-18 17:52:54.739163 | localhost | # Node Information 2026-04-18 17:52:54.739204 | localhost | Inventory Hostname: controller 2026-04-18 17:52:54.739237 | localhost | Hostname: np0000167615 2026-04-18 17:52:54.739266 | localhost | Username: zuul 2026-04-18 17:52:54.739299 | localhost | Distro: Debian 13.4 2026-04-18 17:52:54.739329 | localhost | Provider: yul1 2026-04-18 17:52:54.739356 | localhost | Region: ca-ymq-1 2026-04-18 17:52:54.739381 | localhost | Label: debian-trixie 2026-04-18 17:52:54.739408 | localhost | Product Name: OpenStack Nova 2026-04-18 17:52:54.739433 | localhost | Interface IP: 199.204.45.38 2026-04-18 17:52:54.756459 | 2026-04-18 17:52:54.756622 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-04-18 17:52:55.157208 | localhost -> localhost | changed 2026-04-18 17:52:55.165529 | 2026-04-18 17:52:55.165652 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-04-18 17:52:56.153757 | localhost -> localhost | changed 2026-04-18 17:52:56.163781 | 2026-04-18 17:52:56.163856 | PLAY [all] 2026-04-18 17:52:56.171412 | 2026-04-18 17:52:56.171502 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-04-18 17:52:56.430374 | controller -> localhost | ok 2026-04-18 17:52:56.439615 | 2026-04-18 17:52:56.439699 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-04-18 17:52:56.474768 | controller | ok 2026-04-18 17:52:56.489257 | controller | included: /var/lib/zuul/builds/2a646a7471f24eda9a75d78615e47f37/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-04-18 17:52:56.494795 | 2026-04-18 17:52:56.494876 | TASK [add-build-sshkey : Create Temp SSH key] 2026-04-18 17:52:57.443697 | controller -> localhost | Generating public/private rsa key pair. 2026-04-18 17:52:57.443889 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/2a646a7471f24eda9a75d78615e47f37/work/2a646a7471f24eda9a75d78615e47f37_id_rsa 2026-04-18 17:52:57.443932 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/2a646a7471f24eda9a75d78615e47f37/work/2a646a7471f24eda9a75d78615e47f37_id_rsa.pub 2026-04-18 17:52:57.443964 | controller -> localhost | The key fingerprint is: 2026-04-18 17:52:57.443994 | controller -> localhost | SHA256:3bRSNEprjH0nTEcuIgTOQZORQQhZ1t0seEhN80PVGjs zuul-build-sshkey 2026-04-18 17:52:57.444039 | controller -> localhost | The key's randomart image is: 2026-04-18 17:52:57.444071 | controller -> localhost | +---[RSA 3072]----+ 2026-04-18 17:52:57.444106 | controller -> localhost | | .+o*X%+oo.=oo | 2026-04-18 17:52:57.444135 | controller -> localhost | | ...o*+=Oo*.+. | 2026-04-18 17:52:57.444163 | controller -> localhost | | o.o.X B+o | 2026-04-18 17:52:57.444190 | controller -> localhost | | + BE= | 2026-04-18 17:52:57.444216 | controller -> localhost | | S o o. | 2026-04-18 17:52:57.444242 | controller -> localhost | | . | 2026-04-18 17:52:57.444268 | controller -> localhost | | | 2026-04-18 17:52:57.444295 | controller -> localhost | | | 2026-04-18 17:52:57.444326 | controller -> localhost | | | 2026-04-18 17:52:57.444354 | controller -> localhost | +----[SHA256]-----+ 2026-04-18 17:52:57.444416 | controller -> localhost | ok: Runtime: 0:00:00.465958 2026-04-18 17:52:57.449217 | 2026-04-18 17:52:57.449301 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-04-18 17:52:57.480053 | controller | ok 2026-04-18 17:52:57.491331 | controller | included: /var/lib/zuul/builds/2a646a7471f24eda9a75d78615e47f37/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-04-18 17:52:57.499999 | 2026-04-18 17:52:57.500111 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-04-18 17:52:57.524458 | controller | skipping: Conditional result was False 2026-04-18 17:52:57.533043 | 2026-04-18 17:52:57.533152 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-04-18 17:52:58.050358 | controller | changed 2026-04-18 17:52:58.056328 | 2026-04-18 17:52:58.056453 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-04-18 17:52:58.266826 | controller | ok 2026-04-18 17:52:58.271702 | 2026-04-18 17:52:58.271779 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-04-18 17:52:58.819927 | controller | changed 2026-04-18 17:52:58.824036 | 2026-04-18 17:52:58.824123 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-04-18 17:52:59.373425 | controller | changed 2026-04-18 17:52:59.379335 | 2026-04-18 17:52:59.379405 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-04-18 17:52:59.413573 | controller | skipping: Conditional result was False 2026-04-18 17:52:59.422678 | 2026-04-18 17:52:59.422817 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-04-18 17:52:59.792701 | controller -> localhost | changed 2026-04-18 17:52:59.801316 | 2026-04-18 17:52:59.801403 | TASK [add-build-sshkey : Add back temp key] 2026-04-18 17:53:00.073239 | controller -> localhost | Identity added: /var/lib/zuul/builds/2a646a7471f24eda9a75d78615e47f37/work/2a646a7471f24eda9a75d78615e47f37_id_rsa (zuul-build-sshkey) 2026-04-18 17:53:00.073412 | controller -> localhost | ok: Runtime: 0:00:00.010784 2026-04-18 17:53:00.077194 | 2026-04-18 17:53:00.077254 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-04-18 17:53:00.405737 | controller | ok 2026-04-18 17:53:00.411306 | 2026-04-18 17:53:00.411399 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-04-18 17:53:00.437583 | controller | skipping: Conditional result was False 2026-04-18 17:53:00.451405 | 2026-04-18 17:53:00.451510 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-04-18 17:53:00.810816 | controller | ok 2026-04-18 17:53:00.817506 | 2026-04-18 17:53:00.817575 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-04-18 17:53:01.856398 | controller | Output suppressed because no_log was given 2026-04-18 17:53:01.905735 | 2026-04-18 17:53:01.905891 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-04-18 17:53:02.110690 | controller | ok: "logs" 2026-04-18 17:53:02.110976 | controller | ok: All items complete 2026-04-18 17:53:02.111016 | 2026-04-18 17:53:02.294632 | controller | ok: "artifacts" 2026-04-18 17:53:02.473647 | controller | ok: "docs" 2026-04-18 17:53:02.492265 | 2026-04-18 17:53:02.492576 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-04-18 17:53:02.705792 | controller | changed: "logs" 2026-04-18 17:53:02.883435 | controller | changed: "artifacts" 2026-04-18 17:53:03.069589 | controller | changed: "docs" 2026-04-18 17:53:03.081063 | 2026-04-18 17:53:03.081170 | PLAY RECAP 2026-04-18 17:53:03.081227 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-04-18 17:53:03.081258 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-18 17:53:03.081282 | 2026-04-18 17:53:03.212626 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-18 17:53:03.215600 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-18 17:53:03.861578 | 2026-04-18 17:53:03.861817 | PLAY [all] 2026-04-18 17:53:03.879320 | 2026-04-18 17:53:03.879482 | TASK [setup-uv : Extract archive] 2026-04-18 17:53:04.959102 | controller | ERROR 2026-04-18 17:53:04.959605 | controller | { 2026-04-18 17:53:04.959662 | controller | "msg": "Invalid archive '/home/zuul/.ansible/tmp/ansible-tmp-1776534783.9196582-5-193777303721670/uv-x86_64-unknown-linux-gnu4sn3qtow.tar.gz', the file is 0 bytes" 2026-04-18 17:53:04.959707 | controller | } failure 2026-04-18 17:53:04.961511 | 2026-04-18 17:53:04.961575 | PLAY RECAP 2026-04-18 17:53:04.961636 | controller | ok: 0 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-04-18 17:53:04.961669 | 2026-04-18 17:53:05.096269 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-18 17:53:05.099224 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-18 17:53:05.703608 | 2026-04-18 17:53:05.703792 | PLAY [all] 2026-04-18 17:53:05.714841 | 2026-04-18 17:53:05.714927 | TASK [fetch-output : Set log path for multiple nodes] 2026-04-18 17:53:05.761150 | controller | skipping: Conditional result was False 2026-04-18 17:53:05.771764 | 2026-04-18 17:53:05.771972 | TASK [fetch-output : Set log path for single node] 2026-04-18 17:53:05.816864 | controller | ok 2026-04-18 17:53:05.821791 | 2026-04-18 17:53:05.821861 | LOOP [fetch-output : Ensure local output dirs] 2026-04-18 17:53:06.221249 | controller -> localhost | ok: "/var/lib/zuul/builds/2a646a7471f24eda9a75d78615e47f37/work/logs" 2026-04-18 17:53:08.007994 | controller -> localhost | changed: "/var/lib/zuul/builds/2a646a7471f24eda9a75d78615e47f37/work/artifacts" 2026-04-18 17:53:08.260267 | controller -> localhost | changed: "/var/lib/zuul/builds/2a646a7471f24eda9a75d78615e47f37/work/docs" 2026-04-18 17:53:08.280394 | 2026-04-18 17:53:08.281208 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-04-18 17:53:08.947889 | controller | changed: .d..t...... ./ 2026-04-18 17:53:08.948324 | controller | changed: All items complete 2026-04-18 17:53:08.948461 | 2026-04-18 17:53:09.438095 | controller | changed: .d..t...... ./ 2026-04-18 17:53:09.943950 | controller | changed: .d..t...... ./ 2026-04-18 17:53:10.519292 | 2026-04-18 17:53:10.519416 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-04-18 17:53:11.669065 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.007478 2026-04-18 17:53:11.902180 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.008294 2026-04-18 17:53:11.921598 | 2026-04-18 17:53:11.921889 | PLAY [all] 2026-04-18 17:53:11.926892 | 2026-04-18 17:53:11.926957 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-04-18 17:53:12.383708 | controller | changed 2026-04-18 17:53:12.390680 | 2026-04-18 17:53:12.390806 | PLAY RECAP 2026-04-18 17:53:12.390868 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-04-18 17:53:12.390902 | 2026-04-18 17:53:12.548529 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-18 17:53:12.550446 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-04-18 17:53:13.210623 | 2026-04-18 17:53:13.210781 | PLAY [localhost] 2026-04-18 17:53:13.222754 | 2026-04-18 17:53:13.222872 | TASK [Generate Zuul manifest] 2026-04-18 17:53:13.242298 | localhost | ok 2026-04-18 17:53:13.262723 | 2026-04-18 17:53:13.262871 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-04-18 17:53:13.587084 | localhost | changed 2026-04-18 17:53:13.598666 | 2026-04-18 17:53:13.598745 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-04-18 17:53:13.650357 | localhost | ok 2026-04-18 17:53:14.188135 | 2026-04-18 17:53:14.188258 | TASK [Upload logs] 2026-04-18 17:53:14.207166 | localhost | ok 2026-04-18 17:53:14.284969 | 2026-04-18 17:53:14.285146 | TASK [Set zuul-log-path fact] 2026-04-18 17:53:14.307102 | localhost | ok 2026-04-18 17:53:14.322571 | 2026-04-18 17:53:14.322707 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-18 17:53:14.355084 | localhost | ok 2026-04-18 17:53:14.363709 | 2026-04-18 17:53:14.363813 | TASK [upload-logs : Create log directories] 2026-04-18 17:53:14.813867 | localhost | changed 2026-04-18 17:53:14.820530 | 2026-04-18 17:53:14.820623 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-04-18 17:53:15.227028 | localhost -> localhost | ok: Runtime: 0:00:00.005462 2026-04-18 17:53:15.236826 | 2026-04-18 17:53:15.237268 | TASK [upload-logs : Upload logs to log server] 2026-04-18 17:53:16.581281 | localhost | Output suppressed because no_log was given 2026-04-18 17:53:16.586807 | 2026-04-18 17:53:16.586918 | LOOP [upload-logs : Compress console log and json output] 2026-04-18 17:53:16.632063 | localhost | skipping: Conditional result was False 2026-04-18 17:53:16.641490 | localhost | skipping: Conditional result was False 2026-04-18 17:53:16.653153 | 2026-04-18 17:53:16.653301 | LOOP [upload-logs : Upload compressed console log and json output] 2026-04-18 17:53:16.696014 | localhost | skipping: Conditional result was False 2026-04-18 17:53:16.696398 | 2026-04-18 17:53:16.700812 | localhost | skipping: Conditional result was False 2026-04-18 17:53:16.717602 | 2026-04-18 17:53:16.717733 | LOOP [upload-logs : Upload console log and json output]