2026-03-05 16:13:43.534808 | Job console starting 2026-03-05 16:13:43.542749 | Updating git repos 2026-03-05 16:13:43.577038 | Cloning repos into workspace 2026-03-05 16:13:43.630539 | Restoring repo states 2026-03-05 16:13:43.645576 | Merging changes 2026-03-05 16:13:44.258915 | Checking out repos 2026-03-05 16:13:44.566101 | Preparing playbooks 2026-03-05 16:13:45.549731 | Running Ansible setup 2026-03-05 16:13:49.109653 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-03-05 16:13:49.779967 | 2026-03-05 16:13:49.780140 | PLAY [localhost] 2026-03-05 16:13:49.788582 | 2026-03-05 16:13:49.788669 | TASK [Gathering Facts] 2026-03-05 16:13:50.746445 | localhost | ok 2026-03-05 16:13:50.757136 | 2026-03-05 16:13:50.757247 | TASK [Setup log path fact] 2026-03-05 16:13:50.777649 | localhost | ok 2026-03-05 16:13:50.792158 | 2026-03-05 16:13:50.792233 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-05 16:13:50.822154 | localhost | ok 2026-03-05 16:13:50.842514 | 2026-03-05 16:13:50.842661 | TASK [emit-job-header : Print job information] 2026-03-05 16:13:50.883158 | # Job Information 2026-03-05 16:13:50.883376 | Ansible Version: 2.16.16 2026-03-05 16:13:50.883431 | Job: ansible-collection-kubernetes-molecule-aio-rockylinux-9 2026-03-05 16:13:50.883465 | Pipeline: check 2026-03-05 16:13:50.883493 | Executor: 0a8996d2b663 2026-03-05 16:13:50.883521 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/234 2026-03-05 16:13:50.883551 | Event ID: f483c040-18ad-11f1-82de-983357ded420 2026-03-05 16:13:50.890260 | 2026-03-05 16:13:50.890412 | LOOP [emit-job-header : Print node information] 2026-03-05 16:13:50.982615 | localhost | ok: 2026-03-05 16:13:50.982869 | localhost | # Node Information 2026-03-05 16:13:50.982909 | localhost | Inventory Hostname: controller 2026-03-05 16:13:50.982939 | localhost | Hostname: np0000157873 2026-03-05 16:13:50.982966 | localhost | Username: zuul 2026-03-05 16:13:50.982997 | localhost | Distro: Rocky 9.7 2026-03-05 16:13:50.983024 | localhost | Provider: yul1 2026-03-05 16:13:50.983049 | localhost | Region: ca-ymq-1 2026-03-05 16:13:50.983075 | localhost | Label: rockylinux-9 2026-03-05 16:13:50.983100 | localhost | Product Name: OpenStack Nova 2026-03-05 16:13:50.983125 | localhost | Interface IP: 162.253.55.140 2026-03-05 16:13:51.000903 | 2026-03-05 16:13:51.001470 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-03-05 16:13:51.399506 | localhost -> localhost | changed 2026-03-05 16:13:51.405771 | 2026-03-05 16:13:51.405859 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-03-05 16:13:52.273603 | localhost -> localhost | changed 2026-03-05 16:13:52.285528 | 2026-03-05 16:13:52.285652 | PLAY [all] 2026-03-05 16:13:52.294678 | 2026-03-05 16:13:52.294780 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-03-05 16:13:52.543703 | controller -> localhost | ok 2026-03-05 16:13:52.552834 | 2026-03-05 16:13:52.552943 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-03-05 16:13:52.589952 | controller | ok 2026-03-05 16:13:52.606027 | controller | included: /var/lib/zuul/builds/5811ad9fb59b4a59b8d4ae932ef7a1df/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-03-05 16:13:52.611576 | 2026-03-05 16:13:52.611638 | TASK [add-build-sshkey : Create Temp SSH key] 2026-03-05 16:13:54.407486 | controller -> localhost | Generating public/private rsa key pair. 2026-03-05 16:13:54.407731 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/5811ad9fb59b4a59b8d4ae932ef7a1df/work/5811ad9fb59b4a59b8d4ae932ef7a1df_id_rsa 2026-03-05 16:13:54.407771 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/5811ad9fb59b4a59b8d4ae932ef7a1df/work/5811ad9fb59b4a59b8d4ae932ef7a1df_id_rsa.pub 2026-03-05 16:13:54.407804 | controller -> localhost | The key fingerprint is: 2026-03-05 16:13:54.407834 | controller -> localhost | SHA256:Bast3JFdLZp7dHKsvLiw6+BQynSzGUdPFB+i3cj9Esw zuul-build-sshkey 2026-03-05 16:13:54.407889 | controller -> localhost | The key's randomart image is: 2026-03-05 16:13:54.407918 | controller -> localhost | +---[RSA 3072]----+ 2026-03-05 16:13:54.407956 | controller -> localhost | | . +.o. | 2026-03-05 16:13:54.407986 | controller -> localhost | | X X... | 2026-03-05 16:13:54.408014 | controller -> localhost | | * BoEo | 2026-03-05 16:13:54.408043 | controller -> localhost | | . = =o oo+ | 2026-03-05 16:13:54.408070 | controller -> localhost | | . O S .+.=. | 2026-03-05 16:13:54.408098 | controller -> localhost | | o + B . +. | 2026-03-05 16:13:54.408125 | controller -> localhost | | + + . o . | 2026-03-05 16:13:54.408153 | controller -> localhost | | o . o. . | 2026-03-05 16:13:54.408183 | controller -> localhost | | ..+... | 2026-03-05 16:13:54.408212 | controller -> localhost | +----[SHA256]-----+ 2026-03-05 16:13:54.408276 | controller -> localhost | ok: Runtime: 0:00:01.366681 2026-03-05 16:13:54.414708 | 2026-03-05 16:13:54.416163 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-03-05 16:13:54.449855 | controller | ok 2026-03-05 16:13:54.463363 | controller | included: /var/lib/zuul/builds/5811ad9fb59b4a59b8d4ae932ef7a1df/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-03-05 16:13:54.470345 | 2026-03-05 16:13:54.470406 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-03-05 16:13:54.495725 | controller | skipping: Conditional result was False 2026-03-05 16:13:54.503981 | 2026-03-05 16:13:54.504083 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-03-05 16:13:54.971518 | controller | changed 2026-03-05 16:13:54.978725 | 2026-03-05 16:13:54.978819 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-03-05 16:13:55.242849 | controller | ok 2026-03-05 16:13:55.250615 | 2026-03-05 16:13:55.250685 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-03-05 16:13:56.166236 | controller | changed 2026-03-05 16:13:56.216549 | 2026-03-05 16:13:56.216694 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-03-05 16:13:57.122352 | controller | changed 2026-03-05 16:13:57.129349 | 2026-03-05 16:13:57.129439 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-03-05 16:13:57.152847 | controller | skipping: Conditional result was False 2026-03-05 16:13:57.161495 | 2026-03-05 16:13:57.161580 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-03-05 16:13:57.541403 | controller -> localhost | changed 2026-03-05 16:13:57.554249 | 2026-03-05 16:13:57.554893 | TASK [add-build-sshkey : Add back temp key] 2026-03-05 16:13:57.953184 | controller -> localhost | Identity added: /var/lib/zuul/builds/5811ad9fb59b4a59b8d4ae932ef7a1df/work/5811ad9fb59b4a59b8d4ae932ef7a1df_id_rsa (zuul-build-sshkey) 2026-03-05 16:13:57.953471 | controller -> localhost | ok: Runtime: 0:00:00.013324 2026-03-05 16:13:57.959768 | 2026-03-05 16:13:57.959837 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-03-05 16:13:58.326326 | controller | ok 2026-03-05 16:13:58.331353 | 2026-03-05 16:13:58.331422 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-03-05 16:13:58.359062 | controller | skipping: Conditional result was False 2026-03-05 16:13:58.373316 | 2026-03-05 16:13:58.373433 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-03-05 16:13:58.770736 | controller | ok 2026-03-05 16:13:58.777207 | 2026-03-05 16:13:58.777272 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-03-05 16:13:59.849434 | controller | Output suppressed because no_log was given 2026-03-05 16:13:59.875342 | 2026-03-05 16:13:59.875515 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-03-05 16:14:00.141170 | controller | ok: "logs" 2026-03-05 16:14:00.141877 | controller | ok: All items complete 2026-03-05 16:14:00.141917 | 2026-03-05 16:14:00.354907 | controller | ok: "artifacts" 2026-03-05 16:14:00.584502 | controller | ok: "docs" 2026-03-05 16:14:00.598725 | 2026-03-05 16:14:00.598817 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-03-05 16:14:00.865552 | controller | changed: "logs" 2026-03-05 16:14:01.088417 | controller | changed: "artifacts" 2026-03-05 16:14:01.317779 | controller | changed: "docs" 2026-03-05 16:14:01.401928 | 2026-03-05 16:14:01.402012 | PLAY RECAP 2026-03-05 16:14:01.402053 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-03-05 16:14:01.402119 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-05 16:14:01.402143 | 2026-03-05 16:14:01.544933 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-03-05 16:14:01.546415 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-03-05 16:14:02.118694 | 2026-03-05 16:14:02.119382 | PLAY [all] 2026-03-05 16:14:02.130167 | 2026-03-05 16:14:02.130247 | TASK [setup-uv : Extract archive] 2026-03-05 16:14:04.890002 | controller | changed 2026-03-05 16:14:04.897672 | 2026-03-05 16:14:04.897768 | TASK [setup-uv : Print version] 2026-03-05 16:14:04.921340 | [controller] Waiting on logger 2026-03-05 16:14:05.437856 | controller | ok: Runtime: 0:00:00.014386 2026-03-05 16:14:05.456662 | 2026-03-05 16:14:05.456730 | PLAY RECAP 2026-03-05 16:14:05.456792 | controller | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-05 16:14:05.456825 | 2026-03-05 16:14:05.621207 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-03-05 16:14:05.624136 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-03-05 16:14:06.206475 | 2026-03-05 16:14:06.206591 | PLAY [all] 2026-03-05 16:14:06.216509 | 2026-03-05 16:14:06.216580 | TASK [Copy inventory file for Zuul] 2026-03-05 16:14:07.600043 | controller | changed 2026-03-05 16:14:07.609707 | 2026-03-05 16:14:07.609824 | TASK [Switch "ansible_host" to private IP] 2026-03-05 16:14:07.973299 | controller | changed: 1 replacements made 2026-03-05 16:14:07.979069 | 2026-03-05 16:14:07.979143 | TASK [Run Molecule scenario] 2026-03-05 16:14:08.017479 | [controller] Waiting on logger 2026-03-05 16:14:16.165945 | [controller] Waiting on logger 2026-03-05 16:14:26.377780 | [controller] Waiting on logger 2026-03-05 16:14:36.550028 | [controller] Waiting on logger 2026-03-05 16:14:46.726072 | [controller] Waiting on logger 2026-03-05 16:14:56.902051 | [controller] Waiting on logger 2026-03-05 16:15:06.054037 | [controller] Waiting on logger 2026-03-05 16:15:16.229904 | [controller] Waiting on logger 2026-03-05 16:15:26.406019 | [controller] Waiting on logger 2026-03-05 16:15:36.585826 | [controller] Waiting on logger 2026-03-05 16:15:46.761946 | [controller] Waiting on logger 2026-03-05 16:15:56.933863 | [controller] Waiting on logger 2026-03-05 16:16:06.086026 | [controller] Waiting on logger 2026-03-05 16:16:16.265952 | [controller] Waiting on logger 2026-03-05 16:16:26.438083 | [controller] Waiting on logger 2026-03-05 16:16:36.617814 | [controller] Waiting on logger 2026-03-05 16:16:46.790097 | [controller] Waiting on logger 2026-03-05 16:16:55.947005 | [controller] Waiting on logger 2026-03-05 16:17:06.117728 | [controller] Waiting on logger 2026-03-05 16:17:16.298680 | [controller] Waiting on logger 2026-03-05 16:17:26.470119 | [controller] Waiting on logger 2026-03-05 16:17:36.650415 | [controller] Waiting on logger 2026-03-05 16:17:46.825833 | [controller] Waiting on logger 2026-03-05 16:17:55.982778 | [controller] Waiting on logger 2026-03-05 16:18:06.149848 | [controller] Waiting on logger 2026-03-05 16:18:16.326023 | [controller] Waiting on logger 2026-03-05 16:18:26.502270 | [controller] Waiting on logger 2026-03-05 16:18:36.678103 | [controller] Waiting on logger 2026-03-05 16:18:46.854018 | [controller] Waiting on logger 2026-03-05 16:18:56.011346 | [controller] Waiting on logger 2026-03-05 16:19:05.071471 | controller | ok: Runtime: 0:04:56.005969 2026-03-05 16:19:05.094063 | 2026-03-05 16:19:05.094170 | PLAY RECAP 2026-03-05 16:19:05.094227 | controller | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-05 16:19:05.094250 | 2026-03-05 16:19:05.232637 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-03-05 16:19:05.235419 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-03-05 16:19:05.888121 | 2026-03-05 16:19:05.888308 | PLAY [all] 2026-03-05 16:19:05.900643 | 2026-03-05 16:19:05.900730 | TASK [fetch-output : Set log path for multiple nodes] 2026-03-05 16:19:05.946851 | controller | skipping: Conditional result was False 2026-03-05 16:19:05.954412 | 2026-03-05 16:19:05.954532 | TASK [fetch-output : Set log path for single node] 2026-03-05 16:19:05.999561 | controller | ok 2026-03-05 16:19:06.005198 | 2026-03-05 16:19:06.005266 | LOOP [fetch-output : Ensure local output dirs] 2026-03-05 16:19:06.527153 | controller -> localhost | ok: "/var/lib/zuul/builds/5811ad9fb59b4a59b8d4ae932ef7a1df/work/logs" 2026-03-05 16:19:06.737498 | controller -> localhost | changed: "/var/lib/zuul/builds/5811ad9fb59b4a59b8d4ae932ef7a1df/work/artifacts" 2026-03-05 16:19:06.954796 | controller -> localhost | changed: "/var/lib/zuul/builds/5811ad9fb59b4a59b8d4ae932ef7a1df/work/docs" 2026-03-05 16:19:06.974739 | 2026-03-05 16:19:06.974828 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-03-05 16:19:07.736237 | controller | changed: .d..t...... ./ 2026-03-05 16:19:07.736471 | controller | changed: All items complete 2026-03-05 16:19:07.736511 | 2026-03-05 16:19:08.288174 | controller | changed: .d..t...... ./ 2026-03-05 16:19:08.791735 | controller | changed: .d..t...... ./ 2026-03-05 16:19:08.815198 | 2026-03-05 16:19:08.815325 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-03-05 16:19:09.276429 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.006853 2026-03-05 16:19:09.532941 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.021139 2026-03-05 16:19:09.562184 | 2026-03-05 16:19:09.562379 | PLAY [all] 2026-03-05 16:19:09.573179 | 2026-03-05 16:19:09.573306 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-03-05 16:19:10.074251 | controller | changed 2026-03-05 16:19:10.082097 | 2026-03-05 16:19:10.082153 | PLAY RECAP 2026-03-05 16:19:10.082199 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-03-05 16:19:10.082220 | 2026-03-05 16:19:10.184105 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-03-05 16:19:10.185451 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-03-05 16:19:10.824817 | 2026-03-05 16:19:10.824979 | PLAY [localhost] 2026-03-05 16:19:10.834969 | 2026-03-05 16:19:10.835040 | TASK [Generate Zuul manifest] 2026-03-05 16:19:10.855538 | localhost | ok 2026-03-05 16:19:10.870894 | 2026-03-05 16:19:10.870975 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-03-05 16:19:11.185527 | localhost | changed 2026-03-05 16:19:11.197302 | 2026-03-05 16:19:11.197382 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-03-05 16:19:11.407585 | localhost | ok 2026-03-05 16:19:11.414293 | 2026-03-05 16:19:11.414378 | TASK [Upload logs] 2026-03-05 16:19:11.435682 | localhost | ok 2026-03-05 16:19:11.516188 | 2026-03-05 16:19:11.516296 | TASK [Set zuul-log-path fact] 2026-03-05 16:19:11.536517 | localhost | ok 2026-03-05 16:19:11.556398 | 2026-03-05 16:19:11.556588 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-05 16:19:11.588599 | localhost | ok 2026-03-05 16:19:11.597561 | 2026-03-05 16:19:11.597627 | TASK [upload-logs : Create log directories] 2026-03-05 16:19:12.085349 | localhost | changed 2026-03-05 16:19:12.092169 | 2026-03-05 16:19:12.092263 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-03-05 16:19:12.526787 | localhost -> localhost | ok: Runtime: 0:00:00.004457 2026-03-05 16:19:12.533634 | 2026-03-05 16:19:12.533724 | TASK [upload-logs : Upload logs to log server] 2026-03-05 16:19:12.979527 | localhost | Output suppressed because no_log was given 2026-03-05 16:19:12.984562 | 2026-03-05 16:19:12.984649 | LOOP [upload-logs : Compress console log and json output] 2026-03-05 16:19:13.030378 | localhost | skipping: Conditional result was False 2026-03-05 16:19:13.038053 | localhost | skipping: Conditional result was False 2026-03-05 16:19:13.049724 | 2026-03-05 16:19:13.049898 | LOOP [upload-logs : Upload compressed console log and json output] 2026-03-05 16:19:13.097738 | localhost | skipping: Conditional result was False 2026-03-05 16:19:13.098084 | 2026-03-05 16:19:13.104607 | localhost | skipping: Conditional result was False 2026-03-05 16:19:13.114669 | 2026-03-05 16:19:13.114852 | LOOP [upload-logs : Upload console log and json output]