2026-03-11 20:34:18.015009 | Job console starting 2026-03-11 20:34:18.023391 | Updating git repos 2026-03-11 20:34:18.053992 | Cloning repos into workspace 2026-03-11 20:34:18.105860 | Restoring repo states 2026-03-11 20:34:18.120160 | Merging changes 2026-03-11 20:34:18.676213 | Checking out repos 2026-03-11 20:34:18.957879 | Preparing playbooks 2026-03-11 20:34:19.862510 | Running Ansible setup 2026-03-11 20:34:23.382812 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-03-11 20:34:23.974534 | 2026-03-11 20:34:23.974674 | PLAY [localhost] 2026-03-11 20:34:23.982417 | 2026-03-11 20:34:23.982487 | TASK [Gathering Facts] 2026-03-11 20:34:24.809743 | localhost | ok 2026-03-11 20:34:24.821725 | 2026-03-11 20:34:24.821799 | TASK [Setup log path fact] 2026-03-11 20:34:24.841209 | localhost | ok 2026-03-11 20:34:24.853707 | 2026-03-11 20:34:24.853772 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-11 20:34:24.882891 | localhost | ok 2026-03-11 20:34:24.889971 | 2026-03-11 20:34:24.890036 | TASK [emit-job-header : Print job information] 2026-03-11 20:34:24.930662 | # Job Information 2026-03-11 20:34:24.930907 | Ansible Version: 2.16.16 2026-03-11 20:34:24.930982 | Job: ansible-collection-kubernetes-molecule-aio-rockylinux-9 2026-03-11 20:34:24.931032 | Pipeline: check 2026-03-11 20:34:24.931077 | Executor: 0a8996d2b663 2026-03-11 20:34:24.931121 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/235 2026-03-11 20:34:24.931170 | Event ID: 86d4bcd0-1d89-11f1-9c28-fe1984ae5bcd 2026-03-11 20:34:24.935221 | 2026-03-11 20:34:24.935297 | LOOP [emit-job-header : Print node information] 2026-03-11 20:34:25.025878 | localhost | ok: 2026-03-11 20:34:25.026115 | localhost | # Node Information 2026-03-11 20:34:25.026143 | localhost | Inventory Hostname: controller 2026-03-11 20:34:25.026180 | localhost | Hostname: np0000158472 2026-03-11 20:34:25.026202 | localhost | Username: zuul 2026-03-11 20:34:25.026226 | localhost | Distro: Rocky 9.7 2026-03-11 20:34:25.026246 | localhost | Provider: yul1 2026-03-11 20:34:25.026265 | localhost | Region: ca-ymq-1 2026-03-11 20:34:25.026289 | localhost | Label: rockylinux-9 2026-03-11 20:34:25.026309 | localhost | Product Name: OpenStack Nova 2026-03-11 20:34:25.026334 | localhost | Interface IP: 162.253.55.49 2026-03-11 20:34:25.040592 | 2026-03-11 20:34:25.040750 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-03-11 20:34:25.452367 | localhost -> localhost | changed 2026-03-11 20:34:25.461021 | 2026-03-11 20:34:25.461112 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-03-11 20:34:26.235648 | localhost -> localhost | changed 2026-03-11 20:34:26.244337 | 2026-03-11 20:34:26.244414 | PLAY [all] 2026-03-11 20:34:26.253131 | 2026-03-11 20:34:26.253195 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-03-11 20:34:26.469078 | controller -> localhost | ok 2026-03-11 20:34:26.479518 | 2026-03-11 20:34:26.479652 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-03-11 20:34:26.517236 | controller | ok 2026-03-11 20:34:26.533482 | controller | included: /var/lib/zuul/builds/731a1ef87c17425e8e7f94f683b02808/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-03-11 20:34:26.538740 | 2026-03-11 20:34:26.538800 | TASK [add-build-sshkey : Create Temp SSH key] 2026-03-11 20:34:27.423476 | controller -> localhost | Generating public/private rsa key pair. 2026-03-11 20:34:27.423753 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/731a1ef87c17425e8e7f94f683b02808/work/731a1ef87c17425e8e7f94f683b02808_id_rsa 2026-03-11 20:34:27.423817 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/731a1ef87c17425e8e7f94f683b02808/work/731a1ef87c17425e8e7f94f683b02808_id_rsa.pub 2026-03-11 20:34:27.423867 | controller -> localhost | The key fingerprint is: 2026-03-11 20:34:27.423913 | controller -> localhost | SHA256:+eNI+AodokDaqvJLzCOfyTbF6lYZst2rlDL8j3nhPtY zuul-build-sshkey 2026-03-11 20:34:27.423974 | controller -> localhost | The key's randomart image is: 2026-03-11 20:34:27.424021 | controller -> localhost | +---[RSA 3072]----+ 2026-03-11 20:34:27.424078 | controller -> localhost | | | 2026-03-11 20:34:27.424125 | controller -> localhost | | | 2026-03-11 20:34:27.424170 | controller -> localhost | | . | 2026-03-11 20:34:27.424214 | controller -> localhost | |o.. . . | 2026-03-11 20:34:27.424258 | controller -> localhost | |o .=.+. S | 2026-03-11 20:34:27.424301 | controller -> localhost | | *..*+oo . | 2026-03-11 20:34:27.424343 | controller -> localhost | |o X++.o+. o | 2026-03-11 20:34:27.424386 | controller -> localhost | |o==O +*oEo . | 2026-03-11 20:34:27.424435 | controller -> localhost | |o=Oo=*+oo . | 2026-03-11 20:34:27.424480 | controller -> localhost | +----[SHA256]-----+ 2026-03-11 20:34:27.424569 | controller -> localhost | ok: Runtime: 0:00:00.475168 2026-03-11 20:34:27.429968 | 2026-03-11 20:34:27.430033 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-03-11 20:34:27.465539 | controller | ok 2026-03-11 20:34:27.476765 | controller | included: /var/lib/zuul/builds/731a1ef87c17425e8e7f94f683b02808/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-03-11 20:34:27.483817 | 2026-03-11 20:34:27.483884 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-03-11 20:34:27.509521 | controller | skipping: Conditional result was False 2026-03-11 20:34:27.519418 | 2026-03-11 20:34:27.519484 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-03-11 20:34:28.002583 | controller | changed 2026-03-11 20:34:28.007318 | 2026-03-11 20:34:28.007388 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-03-11 20:34:28.276271 | controller | ok 2026-03-11 20:34:28.282380 | 2026-03-11 20:34:28.282525 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-03-11 20:34:29.208774 | controller | changed 2026-03-11 20:34:29.214272 | 2026-03-11 20:34:29.214338 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-03-11 20:34:30.128929 | controller | changed 2026-03-11 20:34:30.135458 | 2026-03-11 20:34:30.135521 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-03-11 20:34:30.159943 | controller | skipping: Conditional result was False 2026-03-11 20:34:30.169492 | 2026-03-11 20:34:30.169584 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-03-11 20:34:30.559371 | controller -> localhost | changed 2026-03-11 20:34:30.574982 | 2026-03-11 20:34:30.575066 | TASK [add-build-sshkey : Add back temp key] 2026-03-11 20:34:30.870277 | controller -> localhost | Identity added: /var/lib/zuul/builds/731a1ef87c17425e8e7f94f683b02808/work/731a1ef87c17425e8e7f94f683b02808_id_rsa (zuul-build-sshkey) 2026-03-11 20:34:30.882696 | controller -> localhost | ok: Runtime: 0:00:00.015196 2026-03-11 20:34:30.890000 | 2026-03-11 20:34:30.890089 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-03-11 20:34:31.265483 | controller | ok 2026-03-11 20:34:31.271421 | 2026-03-11 20:34:31.271488 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-03-11 20:34:31.296601 | controller | skipping: Conditional result was False 2026-03-11 20:34:31.310821 | 2026-03-11 20:34:31.310886 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-03-11 20:34:31.724962 | controller | ok 2026-03-11 20:34:31.735207 | 2026-03-11 20:34:31.735300 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-03-11 20:34:32.902088 | controller | Output suppressed because no_log was given 2026-03-11 20:34:32.912811 | 2026-03-11 20:34:32.912878 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-03-11 20:34:33.178528 | controller | ok: "logs" 2026-03-11 20:34:33.178775 | controller | ok: All items complete 2026-03-11 20:34:33.178822 | 2026-03-11 20:34:33.422805 | controller | ok: "artifacts" 2026-03-11 20:34:33.654924 | controller | ok: "docs" 2026-03-11 20:34:33.675898 | 2026-03-11 20:34:33.676120 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-03-11 20:34:33.934121 | controller | changed: "logs" 2026-03-11 20:34:34.158757 | controller | changed: "artifacts" 2026-03-11 20:34:34.383186 | controller | changed: "docs" 2026-03-11 20:34:34.397151 | 2026-03-11 20:34:34.397282 | PLAY RECAP 2026-03-11 20:34:34.397332 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-03-11 20:34:34.397360 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-11 20:34:34.397383 | 2026-03-11 20:34:34.561263 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-03-11 20:34:34.563037 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-03-11 20:34:35.120219 | 2026-03-11 20:34:35.120351 | PLAY [all] 2026-03-11 20:34:35.130917 | 2026-03-11 20:34:35.130989 | TASK [setup-uv : Extract archive] 2026-03-11 20:34:37.919458 | controller | changed 2026-03-11 20:34:37.926876 | 2026-03-11 20:34:37.926983 | TASK [setup-uv : Print version] 2026-03-11 20:34:37.949260 | [controller] Waiting on logger 2026-03-11 20:34:38.465995 | controller | ok: Runtime: 0:00:00.014670 2026-03-11 20:34:38.474900 | 2026-03-11 20:34:38.474966 | PLAY RECAP 2026-03-11 20:34:38.475027 | controller | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-11 20:34:38.475058 | 2026-03-11 20:34:38.583350 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-03-11 20:34:38.584918 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-03-11 20:34:39.195285 | 2026-03-11 20:34:39.195519 | PLAY [all] 2026-03-11 20:34:39.206775 | 2026-03-11 20:34:39.206853 | TASK [Copy inventory file for Zuul] 2026-03-11 20:34:40.581619 | controller | changed 2026-03-11 20:34:40.588242 | 2026-03-11 20:34:40.588345 | TASK [Switch "ansible_host" to private IP] 2026-03-11 20:34:40.935290 | controller | changed: 1 replacements made 2026-03-11 20:34:40.940598 | 2026-03-11 20:34:40.940670 | TASK [Run Molecule scenario] 2026-03-11 20:34:40.962173 | [controller] Waiting on logger 2026-03-11 20:34:49.094066 | [controller] Waiting on logger 2026-03-11 20:34:59.270007 | [controller] Waiting on logger 2026-03-11 20:35:09.449744 | [controller] Waiting on logger 2026-03-11 20:35:19.622215 | [controller] Waiting on logger 2026-03-11 20:35:29.798546 | [controller] Waiting on logger 2026-03-11 20:35:38.959114 | [controller] Waiting on logger 2026-03-11 20:35:49.126050 | [controller] Waiting on logger 2026-03-11 20:35:59.306006 | [controller] Waiting on logger 2026-03-11 20:36:09.477988 | [controller] Waiting on logger 2026-03-11 20:36:19.654041 | [controller] Waiting on logger 2026-03-11 20:36:29.830336 | [controller] Waiting on logger 2026-03-11 20:36:38.988284 | [controller] Waiting on logger 2026-03-11 20:36:49.158183 | [controller] Waiting on logger 2026-03-11 20:36:59.337792 | [controller] Waiting on logger 2026-03-11 20:37:09.510567 | [controller] Waiting on logger 2026-03-11 20:37:19.690055 | [controller] Waiting on logger 2026-03-11 20:37:29.862139 | [controller] Waiting on logger 2026-03-11 20:37:39.019553 | [controller] Waiting on logger 2026-03-11 20:37:49.190159 | [controller] Waiting on logger 2026-03-11 20:37:59.366094 | [controller] Waiting on logger 2026-03-11 20:38:09.546122 | [controller] Waiting on logger 2026-03-11 20:38:19.722084 | [controller] Waiting on logger 2026-03-11 20:38:29.894145 | [controller] Waiting on logger 2026-03-11 20:38:39.051415 | [controller] Waiting on logger 2026-03-11 20:38:49.222126 | [controller] Waiting on logger 2026-03-11 20:38:59.398289 | [controller] Waiting on logger 2026-03-11 20:39:09.573966 | [controller] Waiting on logger 2026-03-11 20:39:19.749961 | [controller] Waiting on logger 2026-03-11 20:39:29.930066 | [controller] Waiting on logger 2026-03-11 20:39:32.269691 | controller | ok: Runtime: 0:04:49.956154 2026-03-11 20:39:32.285399 | 2026-03-11 20:39:32.285444 | PLAY RECAP 2026-03-11 20:39:32.285489 | controller | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-11 20:39:32.285509 | 2026-03-11 20:39:32.382611 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-03-11 20:39:32.384382 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-03-11 20:39:32.939159 | 2026-03-11 20:39:32.939341 | PLAY [all] 2026-03-11 20:39:32.951112 | 2026-03-11 20:39:32.951186 | TASK [fetch-output : Set log path for multiple nodes] 2026-03-11 20:39:32.997864 | controller | skipping: Conditional result was False 2026-03-11 20:39:33.005516 | 2026-03-11 20:39:33.005619 | TASK [fetch-output : Set log path for single node] 2026-03-11 20:39:33.049633 | controller | ok 2026-03-11 20:39:33.055285 | 2026-03-11 20:39:33.055371 | LOOP [fetch-output : Ensure local output dirs] 2026-03-11 20:39:33.414109 | controller -> localhost | ok: "/var/lib/zuul/builds/731a1ef87c17425e8e7f94f683b02808/work/logs" 2026-03-11 20:39:33.643438 | controller -> localhost | changed: "/var/lib/zuul/builds/731a1ef87c17425e8e7f94f683b02808/work/artifacts" 2026-03-11 20:39:33.858739 | controller -> localhost | changed: "/var/lib/zuul/builds/731a1ef87c17425e8e7f94f683b02808/work/docs" 2026-03-11 20:39:33.875517 | 2026-03-11 20:39:33.875695 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-03-11 20:39:34.576864 | controller | changed: .d..t...... ./ 2026-03-11 20:39:34.577263 | controller | changed: All items complete 2026-03-11 20:39:34.577305 | 2026-03-11 20:39:35.090848 | controller | changed: .d..t...... ./ 2026-03-11 20:39:35.614915 | controller | changed: .d..t...... ./ 2026-03-11 20:39:35.638128 | 2026-03-11 20:39:35.638297 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-03-11 20:39:36.036453 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.006664 2026-03-11 20:39:36.249323 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.006690 2026-03-11 20:39:36.262882 | 2026-03-11 20:39:36.262935 | PLAY [all] 2026-03-11 20:39:36.268264 | 2026-03-11 20:39:36.268322 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-03-11 20:39:36.725493 | controller | changed 2026-03-11 20:39:36.733045 | 2026-03-11 20:39:36.733097 | PLAY RECAP 2026-03-11 20:39:36.733142 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-03-11 20:39:36.733163 | 2026-03-11 20:39:36.855290 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-03-11 20:39:36.858013 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-03-11 20:39:37.386348 | 2026-03-11 20:39:37.387014 | PLAY [localhost] 2026-03-11 20:39:37.396602 | 2026-03-11 20:39:37.396672 | TASK [Generate Zuul manifest] 2026-03-11 20:39:37.428546 | localhost | ok 2026-03-11 20:39:37.444809 | 2026-03-11 20:39:37.444886 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-03-11 20:39:37.762003 | localhost | changed 2026-03-11 20:39:37.772571 | 2026-03-11 20:39:37.772642 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-03-11 20:39:37.803465 | localhost | ok 2026-03-11 20:39:37.812558 | 2026-03-11 20:39:37.812644 | TASK [Upload logs] 2026-03-11 20:39:37.835739 | localhost | ok 2026-03-11 20:39:37.894218 | 2026-03-11 20:39:37.894315 | TASK [Set zuul-log-path fact] 2026-03-11 20:39:37.914944 | localhost | ok 2026-03-11 20:39:37.928892 | 2026-03-11 20:39:37.928971 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-11 20:39:37.962210 | localhost | ok 2026-03-11 20:39:37.969573 | 2026-03-11 20:39:37.969636 | TASK [upload-logs : Create log directories] 2026-03-11 20:39:38.398983 | localhost | changed 2026-03-11 20:39:38.405756 | 2026-03-11 20:39:38.405851 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-03-11 20:39:38.833198 | localhost -> localhost | ok: Runtime: 0:00:00.004228 2026-03-11 20:39:38.839958 | 2026-03-11 20:39:38.840047 | TASK [upload-logs : Upload logs to log server] 2026-03-11 20:39:39.301126 | localhost | Output suppressed because no_log was given 2026-03-11 20:39:39.306002 | 2026-03-11 20:39:39.306071 | LOOP [upload-logs : Compress console log and json output] 2026-03-11 20:39:39.354725 | localhost | skipping: Conditional result was False 2026-03-11 20:39:39.361632 | localhost | skipping: Conditional result was False 2026-03-11 20:39:39.370045 | 2026-03-11 20:39:39.370182 | LOOP [upload-logs : Upload compressed console log and json output] 2026-03-11 20:39:39.415914 | localhost | skipping: Conditional result was False 2026-03-11 20:39:39.416326 | 2026-03-11 20:39:39.419808 | localhost | skipping: Conditional result was False 2026-03-11 20:39:39.435918 | 2026-03-11 20:39:39.436060 | LOOP [upload-logs : Upload console log and json output]