2026-02-28 19:25:37.016861 | Job console starting 2026-02-28 19:25:37.025824 | Updating git repos 2026-02-28 19:25:37.061143 | Cloning repos into workspace 2026-02-28 19:25:37.119817 | Restoring repo states 2026-02-28 19:25:37.133301 | Merging changes 2026-02-28 19:25:37.648315 | Checking out repos 2026-02-28 19:25:37.896845 | Preparing playbooks 2026-02-28 19:25:38.978910 | Running Ansible setup 2026-02-28 19:25:42.425959 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-02-28 19:25:43.015760 | 2026-02-28 19:25:43.015881 | PLAY [localhost] 2026-02-28 19:25:43.023199 | 2026-02-28 19:25:43.023268 | TASK [Gathering Facts] 2026-02-28 19:25:43.917091 | localhost | ok 2026-02-28 19:25:43.928906 | 2026-02-28 19:25:43.929009 | TASK [Setup log path fact] 2026-02-28 19:25:43.945896 | localhost | ok 2026-02-28 19:25:43.958552 | 2026-02-28 19:25:43.958615 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-02-28 19:25:43.986845 | localhost | ok 2026-02-28 19:25:43.994567 | 2026-02-28 19:25:43.994654 | TASK [emit-job-header : Print job information] 2026-02-28 19:25:44.045021 | # Job Information 2026-02-28 19:25:44.045224 | Ansible Version: 2.16.16 2026-02-28 19:25:44.045293 | Job: ansible-collection-kubernetes-molecule-aio-rockylinux-9 2026-02-28 19:25:44.045341 | Pipeline: check 2026-02-28 19:25:44.045412 | Executor: 0a8996d2b663 2026-02-28 19:25:44.045459 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/228 2026-02-28 19:25:44.045507 | Event ID: 26d2ab50-14db-11f1-80cc-4ed984950f1c 2026-02-28 19:25:44.051023 | 2026-02-28 19:25:44.051155 | LOOP [emit-job-header : Print node information] 2026-02-28 19:25:44.140273 | localhost | ok: 2026-02-28 19:25:44.140488 | localhost | # Node Information 2026-02-28 19:25:44.140533 | localhost | Inventory Hostname: controller 2026-02-28 19:25:44.140576 | localhost | Hostname: np0000157485 2026-02-28 19:25:44.140607 | localhost | Username: zuul 2026-02-28 19:25:44.140640 | localhost | Distro: Rocky 9.7 2026-02-28 19:25:44.140669 | localhost | Provider: yul1 2026-02-28 19:25:44.140696 | localhost | Region: ca-ymq-1 2026-02-28 19:25:44.140723 | localhost | Label: rockylinux-9 2026-02-28 19:25:44.140750 | localhost | Product Name: OpenStack Nova 2026-02-28 19:25:44.140778 | localhost | Interface IP: 162.253.55.204 2026-02-28 19:25:44.157545 | 2026-02-28 19:25:44.157654 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-02-28 19:25:44.586583 | localhost -> localhost | changed 2026-02-28 19:25:44.592765 | 2026-02-28 19:25:44.592855 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-02-28 19:25:45.502857 | localhost -> localhost | changed 2026-02-28 19:25:45.524036 | 2026-02-28 19:25:45.524119 | PLAY [all] 2026-02-28 19:25:45.532483 | 2026-02-28 19:25:45.532555 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-02-28 19:25:45.769455 | controller -> localhost | ok 2026-02-28 19:25:45.775593 | 2026-02-28 19:25:45.775659 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-02-28 19:25:45.811763 | controller | ok 2026-02-28 19:25:45.828642 | controller | included: /var/lib/zuul/builds/27b80feb9aa4445caf5ba4a729588145/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-02-28 19:25:45.836473 | 2026-02-28 19:25:45.836559 | TASK [add-build-sshkey : Create Temp SSH key] 2026-02-28 19:25:47.074231 | controller -> localhost | Generating public/private rsa key pair. 2026-02-28 19:25:47.074394 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/27b80feb9aa4445caf5ba4a729588145/work/27b80feb9aa4445caf5ba4a729588145_id_rsa 2026-02-28 19:25:47.074424 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/27b80feb9aa4445caf5ba4a729588145/work/27b80feb9aa4445caf5ba4a729588145_id_rsa.pub 2026-02-28 19:25:47.074448 | controller -> localhost | The key fingerprint is: 2026-02-28 19:25:47.074471 | controller -> localhost | SHA256:FoVt3lbRkrsU/6DmEwuR3X+Eqsj6nXh8Eht2YKoAX4s zuul-build-sshkey 2026-02-28 19:25:47.074501 | controller -> localhost | The key's randomart image is: 2026-02-28 19:25:47.074524 | controller -> localhost | +---[RSA 3072]----+ 2026-02-28 19:25:47.074548 | controller -> localhost | | o. .+ | 2026-02-28 19:25:47.074570 | controller -> localhost | | ..o = .| 2026-02-28 19:25:47.074591 | controller -> localhost | | .o + o * | 2026-02-28 19:25:47.074611 | controller -> localhost | | . . .* + * o| 2026-02-28 19:25:47.074631 | controller -> localhost | | o o .So + + =.| 2026-02-28 19:25:47.074651 | controller -> localhost | | E ... = * . +| 2026-02-28 19:25:47.074670 | controller -> localhost | | . o + X o .| 2026-02-28 19:25:47.074690 | controller -> localhost | | . oo*.= | 2026-02-28 19:25:47.074712 | controller -> localhost | | .oo.oo . | 2026-02-28 19:25:47.074732 | controller -> localhost | +----[SHA256]-----+ 2026-02-28 19:25:47.074778 | controller -> localhost | ok: Runtime: 0:00:00.773868 2026-02-28 19:25:47.079725 | 2026-02-28 19:25:47.079785 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-02-28 19:25:47.108109 | controller | ok 2026-02-28 19:25:47.117265 | controller | included: /var/lib/zuul/builds/27b80feb9aa4445caf5ba4a729588145/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-02-28 19:25:47.124973 | 2026-02-28 19:25:47.132758 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-02-28 19:25:47.163088 | controller | skipping: Conditional result was False 2026-02-28 19:25:47.181548 | 2026-02-28 19:25:47.181680 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-02-28 19:25:47.682375 | controller | changed 2026-02-28 19:25:47.689500 | 2026-02-28 19:25:47.689568 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-02-28 19:25:47.957236 | controller | ok 2026-02-28 19:25:47.962125 | 2026-02-28 19:25:47.962187 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-02-28 19:25:48.885421 | controller | changed 2026-02-28 19:25:48.907577 | 2026-02-28 19:25:48.907640 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-02-28 19:25:49.877948 | controller | changed 2026-02-28 19:25:49.884059 | 2026-02-28 19:25:49.884127 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-02-28 19:25:49.909977 | controller | skipping: Conditional result was False 2026-02-28 19:25:49.919588 | 2026-02-28 19:25:49.919703 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-02-28 19:25:50.273451 | controller -> localhost | changed 2026-02-28 19:25:50.286254 | 2026-02-28 19:25:50.286325 | TASK [add-build-sshkey : Add back temp key] 2026-02-28 19:25:50.601158 | controller -> localhost | Identity added: /var/lib/zuul/builds/27b80feb9aa4445caf5ba4a729588145/work/27b80feb9aa4445caf5ba4a729588145_id_rsa (zuul-build-sshkey) 2026-02-28 19:25:50.601365 | controller -> localhost | ok: Runtime: 0:00:00.015288 2026-02-28 19:25:50.606415 | 2026-02-28 19:25:50.606479 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-02-28 19:25:51.016838 | controller | ok 2026-02-28 19:25:51.021009 | 2026-02-28 19:25:51.021071 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-02-28 19:25:51.045474 | controller | skipping: Conditional result was False 2026-02-28 19:25:51.057822 | 2026-02-28 19:25:51.057912 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-02-28 19:25:51.483825 | controller | ok 2026-02-28 19:25:51.493030 | 2026-02-28 19:25:51.494121 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-02-28 19:25:52.605372 | controller | Output suppressed because no_log was given 2026-02-28 19:25:52.613342 | 2026-02-28 19:25:52.618284 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-02-28 19:25:52.899897 | controller | ok: "logs" 2026-02-28 19:25:52.900280 | controller | ok: All items complete 2026-02-28 19:25:52.900326 | 2026-02-28 19:25:53.131085 | controller | ok: "artifacts" 2026-02-28 19:25:53.359803 | controller | ok: "docs" 2026-02-28 19:25:53.369316 | 2026-02-28 19:25:53.369479 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-02-28 19:25:53.651000 | controller | changed: "logs" 2026-02-28 19:25:53.862859 | controller | changed: "artifacts" 2026-02-28 19:25:54.109405 | controller | changed: "docs" 2026-02-28 19:25:54.123852 | 2026-02-28 19:25:54.123950 | PLAY RECAP 2026-02-28 19:25:54.123996 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-02-28 19:25:54.124025 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-02-28 19:25:54.124048 | 2026-02-28 19:25:54.265202 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-02-28 19:25:54.267905 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-02-28 19:25:54.886329 | 2026-02-28 19:25:54.886486 | PLAY [all] 2026-02-28 19:25:54.898526 | 2026-02-28 19:25:54.898633 | TASK [setup-uv : Extract archive] 2026-02-28 19:25:57.670737 | controller | changed 2026-02-28 19:25:57.678045 | 2026-02-28 19:25:57.678149 | TASK [setup-uv : Print version] 2026-02-28 19:25:57.719025 | [controller] Waiting on logger 2026-02-28 19:25:58.233745 | controller | ok: Runtime: 0:00:00.016065 2026-02-28 19:25:58.245406 | 2026-02-28 19:25:58.245465 | PLAY RECAP 2026-02-28 19:25:58.245515 | controller | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-02-28 19:25:58.245540 | 2026-02-28 19:25:58.353579 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-02-28 19:25:58.355230 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-02-28 19:25:59.008367 | 2026-02-28 19:25:59.008560 | PLAY [all] 2026-02-28 19:25:59.020140 | 2026-02-28 19:25:59.020229 | TASK [Copy inventory file for Zuul] 2026-02-28 19:26:00.363051 | controller | changed 2026-02-28 19:26:00.368197 | 2026-02-28 19:26:00.368278 | TASK [Switch "ansible_host" to private IP] 2026-02-28 19:26:00.721570 | controller | changed: 1 replacements made 2026-02-28 19:26:00.732279 | 2026-02-28 19:26:00.732392 | TASK [Run Molecule scenario] 2026-02-28 19:26:00.758553 | [controller] Waiting on logger 2026-02-28 19:26:08.901871 | [controller] Waiting on logger 2026-02-28 19:26:19.078128 | [controller] Waiting on logger 2026-02-28 19:26:29.254115 | [controller] Waiting on logger 2026-02-28 19:26:39.430159 | [controller] Waiting on logger 2026-02-28 19:26:49.607637 | [controller] Waiting on logger 2026-02-28 19:26:58.767741 | [controller] Waiting on logger 2026-02-28 19:27:08.934218 | [controller] Waiting on logger 2026-02-28 19:27:19.110167 | [controller] Waiting on logger 2026-02-28 19:27:29.286191 | [controller] Waiting on logger 2026-02-28 19:27:39.462077 | [controller] Waiting on logger 2026-02-28 19:27:49.638100 | [controller] Waiting on logger 2026-02-28 19:27:58.795813 | [controller] Waiting on logger 2026-02-28 19:28:08.966522 | [controller] Waiting on logger 2026-02-28 19:28:19.146172 | [controller] Waiting on logger 2026-02-28 19:28:29.318101 | [controller] Waiting on logger 2026-02-28 19:28:39.494054 | [controller] Waiting on logger 2026-02-28 19:28:49.670675 | [controller] Waiting on logger 2026-02-28 19:28:58.827442 | [controller] Waiting on logger 2026-02-28 19:29:08.998159 | [controller] Waiting on logger 2026-02-28 19:29:19.174132 | [controller] Waiting on logger 2026-02-28 19:29:29.349939 | [controller] Waiting on logger 2026-02-28 19:29:39.526631 | [controller] Waiting on logger 2026-02-28 19:29:49.702154 | [controller] Waiting on logger 2026-02-28 19:29:58.854107 | [controller] Waiting on logger 2026-02-28 19:30:09.030122 | [controller] Waiting on logger 2026-02-28 19:30:19.206338 | [controller] Waiting on logger 2026-02-28 19:30:29.386103 | [controller] Waiting on logger 2026-02-28 19:30:39.558055 | [controller] Waiting on logger 2026-02-28 19:30:49.734088 | [controller] Waiting on logger 2026-02-28 19:30:58.886507 | [controller] Waiting on logger 2026-02-28 19:31:09.062881 | [controller] Waiting on logger 2026-02-28 19:31:19.242304 | [controller] Waiting on logger 2026-02-28 19:31:29.414204 | [controller] Waiting on logger 2026-02-28 19:31:34.028146 | controller | ok: Runtime: 0:05:31.905744 2026-02-28 19:31:34.044817 | 2026-02-28 19:31:34.044873 | PLAY RECAP 2026-02-28 19:31:34.044930 | controller | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-02-28 19:31:34.044952 | 2026-02-28 19:31:34.182056 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-02-28 19:31:34.184830 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-02-28 19:31:34.802530 | 2026-02-28 19:31:34.802675 | PLAY [all] 2026-02-28 19:31:34.821420 | 2026-02-28 19:31:34.822318 | TASK [fetch-output : Set log path for multiple nodes] 2026-02-28 19:31:34.869374 | controller | skipping: Conditional result was False 2026-02-28 19:31:34.879086 | 2026-02-28 19:31:34.879170 | TASK [fetch-output : Set log path for single node] 2026-02-28 19:31:34.923170 | controller | ok 2026-02-28 19:31:34.928436 | 2026-02-28 19:31:34.928504 | LOOP [fetch-output : Ensure local output dirs] 2026-02-28 19:31:35.304869 | controller -> localhost | ok: "/var/lib/zuul/builds/27b80feb9aa4445caf5ba4a729588145/work/logs" 2026-02-28 19:31:35.518053 | controller -> localhost | changed: "/var/lib/zuul/builds/27b80feb9aa4445caf5ba4a729588145/work/artifacts" 2026-02-28 19:31:35.711351 | controller -> localhost | changed: "/var/lib/zuul/builds/27b80feb9aa4445caf5ba4a729588145/work/docs" 2026-02-28 19:31:35.728833 | 2026-02-28 19:31:35.729032 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-02-28 19:31:36.407199 | controller | changed: .d..t...... ./ 2026-02-28 19:31:36.407430 | controller | changed: All items complete 2026-02-28 19:31:36.407470 | 2026-02-28 19:31:36.923517 | controller | changed: .d..t...... ./ 2026-02-28 19:31:37.441797 | controller | changed: .d..t...... ./ 2026-02-28 19:31:37.465606 | 2026-02-28 19:31:37.465731 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-02-28 19:31:37.838896 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.007317 2026-02-28 19:31:38.050768 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.006551 2026-02-28 19:31:38.062602 | 2026-02-28 19:31:38.062657 | PLAY [all] 2026-02-28 19:31:38.067738 | 2026-02-28 19:31:38.067797 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-02-28 19:31:38.557168 | controller | changed 2026-02-28 19:31:38.563662 | 2026-02-28 19:31:38.563710 | PLAY RECAP 2026-02-28 19:31:38.563757 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-02-28 19:31:38.563780 | 2026-02-28 19:31:38.680389 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-02-28 19:31:38.683151 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-02-28 19:31:39.261099 | 2026-02-28 19:31:39.261283 | PLAY [localhost] 2026-02-28 19:31:39.271981 | 2026-02-28 19:31:39.272056 | TASK [Generate Zuul manifest] 2026-02-28 19:31:39.296057 | localhost | ok 2026-02-28 19:31:39.312660 | 2026-02-28 19:31:39.312743 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-02-28 19:31:39.621945 | localhost | changed 2026-02-28 19:31:39.633341 | 2026-02-28 19:31:39.633424 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-02-28 19:31:39.665944 | localhost | ok 2026-02-28 19:31:39.674240 | 2026-02-28 19:31:39.674301 | TASK [Upload logs] 2026-02-28 19:31:39.697338 | localhost | ok 2026-02-28 19:31:39.760034 | 2026-02-28 19:31:39.760184 | TASK [Set zuul-log-path fact] 2026-02-28 19:31:39.782618 | localhost | ok 2026-02-28 19:31:39.794567 | 2026-02-28 19:31:39.794636 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-02-28 19:31:39.827537 | localhost | ok 2026-02-28 19:31:39.835492 | 2026-02-28 19:31:39.835554 | TASK [upload-logs : Create log directories] 2026-02-28 19:31:40.212844 | localhost | changed 2026-02-28 19:31:40.219439 | 2026-02-28 19:31:40.219511 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-02-28 19:31:40.598961 | localhost -> localhost | ok: Runtime: 0:00:00.006184 2026-02-28 19:31:40.606409 | 2026-02-28 19:31:40.606507 | TASK [upload-logs : Upload logs to log server] 2026-02-28 19:31:41.046703 | localhost | Output suppressed because no_log was given 2026-02-28 19:31:41.051891 | 2026-02-28 19:31:41.051980 | LOOP [upload-logs : Compress console log and json output] 2026-02-28 19:31:41.100869 | localhost | skipping: Conditional result was False 2026-02-28 19:31:41.108000 | localhost | skipping: Conditional result was False 2026-02-28 19:31:41.118453 | 2026-02-28 19:31:41.118688 | LOOP [upload-logs : Upload compressed console log and json output] 2026-02-28 19:31:41.170650 | localhost | skipping: Conditional result was False 2026-02-28 19:31:41.170873 | 2026-02-28 19:31:41.171556 | localhost | skipping: Conditional result was False 2026-02-28 19:31:41.181895 | 2026-02-28 19:31:41.182143 | LOOP [upload-logs : Upload console log and json output]