2026-03-06 02:02:08.137725 | Job console starting 2026-03-06 02:02:08.146794 | Updating git repos 2026-03-06 02:02:08.174029 | Cloning repos into workspace 2026-03-06 02:02:08.369976 | Restoring repo states 2026-03-06 02:02:08.391940 | Merging changes 2026-03-06 02:02:08.916813 | Checking out repos 2026-03-06 02:02:09.225785 | Preparing playbooks 2026-03-06 02:02:10.215848 | Running Ansible setup 2026-03-06 02:02:13.645755 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-03-06 02:02:14.281795 | 2026-03-06 02:02:14.281939 | PLAY [localhost] 2026-03-06 02:02:14.289667 | 2026-03-06 02:02:14.289776 | TASK [Gathering Facts] 2026-03-06 02:02:15.135813 | localhost | ok 2026-03-06 02:02:15.145010 | 2026-03-06 02:02:15.145121 | TASK [Setup log path fact] 2026-03-06 02:02:15.165407 | localhost | ok 2026-03-06 02:02:15.176620 | 2026-03-06 02:02:15.176723 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-06 02:02:15.204512 | localhost | ok 2026-03-06 02:02:15.213553 | 2026-03-06 02:02:15.213660 | TASK [emit-job-header : Print job information] 2026-03-06 02:02:15.254119 | # Job Information 2026-03-06 02:02:15.254299 | Ansible Version: 2.16.16 2026-03-06 02:02:15.254347 | Job: ansible-collection-kubernetes-molecule-aio-rockylinux-9 2026-03-06 02:02:15.254380 | Pipeline: check 2026-03-06 02:02:15.254410 | Executor: 0a8996d2b663 2026-03-06 02:02:15.254439 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/235 2026-03-06 02:02:15.254472 | Event ID: 62655ae0-1900-11f1-9569-19afb6ddb422 2026-03-06 02:02:15.258430 | 2026-03-06 02:02:15.258527 | LOOP [emit-job-header : Print node information] 2026-03-06 02:02:15.352411 | localhost | ok: 2026-03-06 02:02:15.352641 | localhost | # Node Information 2026-03-06 02:02:15.352703 | localhost | Inventory Hostname: controller 2026-03-06 02:02:15.352751 | localhost | Hostname: np0000158015 2026-03-06 02:02:15.352795 | localhost | Username: zuul 2026-03-06 02:02:15.352843 | localhost | Distro: Rocky 9.7 2026-03-06 02:02:15.352887 | localhost | Provider: yul1 2026-03-06 02:02:15.352929 | localhost | Region: ca-ymq-1 2026-03-06 02:02:15.353004 | localhost | Label: rockylinux-9 2026-03-06 02:02:15.353047 | localhost | Product Name: OpenStack Nova 2026-03-06 02:02:15.353088 | localhost | Interface IP: 162.253.55.140 2026-03-06 02:02:15.359603 | 2026-03-06 02:02:15.359670 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-03-06 02:02:15.726043 | localhost -> localhost | changed 2026-03-06 02:02:15.730719 | 2026-03-06 02:02:15.730785 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-03-06 02:02:16.572119 | localhost -> localhost | changed 2026-03-06 02:02:16.578666 | 2026-03-06 02:02:16.578721 | PLAY [all] 2026-03-06 02:02:16.592290 | 2026-03-06 02:02:16.592446 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-03-06 02:02:16.813722 | controller -> localhost | ok 2026-03-06 02:02:16.820660 | 2026-03-06 02:02:16.820750 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-03-06 02:02:16.853365 | controller | ok 2026-03-06 02:02:16.868442 | controller | included: /var/lib/zuul/builds/729943c6cc2941e68427bf34a47f38d2/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-03-06 02:02:16.873473 | 2026-03-06 02:02:16.873531 | TASK [add-build-sshkey : Create Temp SSH key] 2026-03-06 02:02:17.706676 | controller -> localhost | Generating public/private rsa key pair. 2026-03-06 02:02:17.707020 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/729943c6cc2941e68427bf34a47f38d2/work/729943c6cc2941e68427bf34a47f38d2_id_rsa 2026-03-06 02:02:17.707076 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/729943c6cc2941e68427bf34a47f38d2/work/729943c6cc2941e68427bf34a47f38d2_id_rsa.pub 2026-03-06 02:02:17.707110 | controller -> localhost | The key fingerprint is: 2026-03-06 02:02:17.707141 | controller -> localhost | SHA256:ni495+Jr8cwC8gqQECwYEtNE7CbRmj0jmrNXc/mYe0g zuul-build-sshkey 2026-03-06 02:02:17.707186 | controller -> localhost | The key's randomart image is: 2026-03-06 02:02:17.707218 | controller -> localhost | +---[RSA 3072]----+ 2026-03-06 02:02:17.707256 | controller -> localhost | |OOo | 2026-03-06 02:02:17.707286 | controller -> localhost | |=++ | 2026-03-06 02:02:17.707323 | controller -> localhost | |o* | 2026-03-06 02:02:17.707365 | controller -> localhost | |*.* | 2026-03-06 02:02:17.707404 | controller -> localhost | |+= o .S | 2026-03-06 02:02:17.707442 | controller -> localhost | |+. + E... | 2026-03-06 02:02:17.707479 | controller -> localhost | | o.. * Bo= | 2026-03-06 02:02:17.707511 | controller -> localhost | |. .. *.O = | 2026-03-06 02:02:17.707543 | controller -> localhost | | . ...*+B. | 2026-03-06 02:02:17.707572 | controller -> localhost | +----[SHA256]-----+ 2026-03-06 02:02:17.707635 | controller -> localhost | ok: Runtime: 0:00:00.441897 2026-03-06 02:02:17.713752 | 2026-03-06 02:02:17.713824 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-03-06 02:02:17.757249 | controller | ok 2026-03-06 02:02:17.767062 | controller | included: /var/lib/zuul/builds/729943c6cc2941e68427bf34a47f38d2/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-03-06 02:02:17.774465 | 2026-03-06 02:02:17.774531 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-03-06 02:02:17.798365 | controller | skipping: Conditional result was False 2026-03-06 02:02:17.807511 | 2026-03-06 02:02:17.807608 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-03-06 02:02:18.260392 | controller | changed 2026-03-06 02:02:18.265974 | 2026-03-06 02:02:18.266041 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-03-06 02:02:18.526447 | controller | ok 2026-03-06 02:02:18.531047 | 2026-03-06 02:02:18.531110 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-03-06 02:02:19.428386 | controller | changed 2026-03-06 02:02:19.432796 | 2026-03-06 02:02:19.432858 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-03-06 02:02:20.339878 | controller | changed 2026-03-06 02:02:20.346681 | 2026-03-06 02:02:20.346746 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-03-06 02:02:20.370094 | controller | skipping: Conditional result was False 2026-03-06 02:02:20.374929 | 2026-03-06 02:02:20.374997 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-03-06 02:02:20.744087 | controller -> localhost | changed 2026-03-06 02:02:20.772135 | 2026-03-06 02:02:20.772233 | TASK [add-build-sshkey : Add back temp key] 2026-03-06 02:02:21.021515 | controller -> localhost | Identity added: /var/lib/zuul/builds/729943c6cc2941e68427bf34a47f38d2/work/729943c6cc2941e68427bf34a47f38d2_id_rsa (zuul-build-sshkey) 2026-03-06 02:02:21.021720 | controller -> localhost | ok: Runtime: 0:00:00.012783 2026-03-06 02:02:21.027711 | 2026-03-06 02:02:21.027794 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-03-06 02:02:21.394782 | controller | ok 2026-03-06 02:02:21.400115 | 2026-03-06 02:02:21.400181 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-03-06 02:02:21.423914 | controller | skipping: Conditional result was False 2026-03-06 02:02:21.438709 | 2026-03-06 02:02:21.438806 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-03-06 02:02:21.837736 | controller | ok 2026-03-06 02:02:21.845915 | 2026-03-06 02:02:21.845982 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-03-06 02:02:22.877542 | controller | Output suppressed because no_log was given 2026-03-06 02:02:22.885528 | 2026-03-06 02:02:22.885591 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-03-06 02:02:23.164678 | controller | ok: "logs" 2026-03-06 02:02:23.164933 | controller | ok: All items complete 2026-03-06 02:02:23.165007 | 2026-03-06 02:02:23.380195 | controller | ok: "artifacts" 2026-03-06 02:02:23.602591 | controller | ok: "docs" 2026-03-06 02:02:23.622326 | 2026-03-06 02:02:23.622542 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-03-06 02:02:23.874261 | controller | changed: "logs" 2026-03-06 02:02:24.093907 | controller | changed: "artifacts" 2026-03-06 02:02:24.311724 | controller | changed: "docs" 2026-03-06 02:02:24.327528 | 2026-03-06 02:02:24.327615 | PLAY RECAP 2026-03-06 02:02:24.327660 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-03-06 02:02:24.327688 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-06 02:02:24.327707 | 2026-03-06 02:02:24.436720 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-03-06 02:02:24.439472 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-03-06 02:02:24.979464 | 2026-03-06 02:02:24.979596 | PLAY [all] 2026-03-06 02:02:24.990789 | 2026-03-06 02:02:24.990867 | TASK [setup-uv : Extract archive] 2026-03-06 02:02:27.792513 | controller | changed 2026-03-06 02:02:27.840301 | 2026-03-06 02:02:27.840453 | TASK [setup-uv : Print version] 2026-03-06 02:02:27.865642 | [controller] Waiting on logger 2026-03-06 02:02:28.379919 | controller | ok: Runtime: 0:00:00.015701 2026-03-06 02:02:28.387133 | 2026-03-06 02:02:28.387189 | PLAY RECAP 2026-03-06 02:02:28.387238 | controller | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-06 02:02:28.387262 | 2026-03-06 02:02:28.506079 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-03-06 02:02:28.508091 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-03-06 02:02:29.110253 | 2026-03-06 02:02:29.122784 | PLAY [all] 2026-03-06 02:02:29.139885 | 2026-03-06 02:02:29.166253 | TASK [Copy inventory file for Zuul] 2026-03-06 02:02:30.504202 | controller | changed 2026-03-06 02:02:30.510867 | 2026-03-06 02:02:30.510970 | TASK [Switch "ansible_host" to private IP] 2026-03-06 02:02:30.864804 | controller | changed: 1 replacements made 2026-03-06 02:02:30.870915 | 2026-03-06 02:02:30.870980 | TASK [Run Molecule scenario] 2026-03-06 02:02:30.917464 | [controller] Waiting on logger 2026-03-06 02:02:39.050231 | [controller] Waiting on logger 2026-03-06 02:07:45.839454 | controller | ok: Runtime: 0:05:14.458862 2026-03-06 02:07:45.853149 | 2026-03-06 02:07:45.853208 | PLAY RECAP 2026-03-06 02:07:45.853257 | controller | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-06 02:07:45.853279 | 2026-03-06 02:07:46.003301 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-03-06 02:07:46.006253 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-03-06 02:07:46.618798 | 2026-03-06 02:07:46.618953 | PLAY [all] 2026-03-06 02:07:46.634358 | 2026-03-06 02:07:46.634470 | TASK [fetch-output : Set log path for multiple nodes] 2026-03-06 02:07:46.680468 | controller | skipping: Conditional result was False 2026-03-06 02:07:46.689265 | 2026-03-06 02:07:46.689349 | TASK [fetch-output : Set log path for single node] 2026-03-06 02:07:46.732774 | controller | ok 2026-03-06 02:07:46.737905 | 2026-03-06 02:07:46.737974 | LOOP [fetch-output : Ensure local output dirs] 2026-03-06 02:07:47.101466 | controller -> localhost | ok: "/var/lib/zuul/builds/729943c6cc2941e68427bf34a47f38d2/work/logs" 2026-03-06 02:07:47.303142 | controller -> localhost | changed: "/var/lib/zuul/builds/729943c6cc2941e68427bf34a47f38d2/work/artifacts" 2026-03-06 02:07:47.507920 | controller -> localhost | changed: "/var/lib/zuul/builds/729943c6cc2941e68427bf34a47f38d2/work/docs" 2026-03-06 02:07:47.527499 | 2026-03-06 02:07:47.527644 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-03-06 02:07:48.266186 | controller | changed: .d..t...... ./ 2026-03-06 02:07:48.266495 | controller | changed: All items complete 2026-03-06 02:07:48.266539 | 2026-03-06 02:07:48.804256 | controller | changed: .d..t...... ./ 2026-03-06 02:07:49.320437 | controller | changed: .d..t...... ./ 2026-03-06 02:07:49.342924 | 2026-03-06 02:07:49.343046 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-03-06 02:07:49.764385 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.006771 2026-03-06 02:07:49.981747 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.007042 2026-03-06 02:07:50.001749 | 2026-03-06 02:07:50.001926 | PLAY [all] 2026-03-06 02:07:50.008102 | 2026-03-06 02:07:50.008174 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-03-06 02:07:50.470469 | controller | changed 2026-03-06 02:07:50.541499 | 2026-03-06 02:07:50.541613 | PLAY RECAP 2026-03-06 02:07:50.541713 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-03-06 02:07:50.541761 | 2026-03-06 02:07:50.695225 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-03-06 02:07:50.697985 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-03-06 02:07:51.249479 | 2026-03-06 02:07:51.249608 | PLAY [localhost] 2026-03-06 02:07:51.259320 | 2026-03-06 02:07:51.259409 | TASK [Generate Zuul manifest] 2026-03-06 02:07:51.280237 | localhost | ok 2026-03-06 02:07:51.295377 | 2026-03-06 02:07:51.295568 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-03-06 02:07:51.591646 | localhost | changed 2026-03-06 02:07:51.601895 | 2026-03-06 02:07:51.601970 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-03-06 02:07:51.633168 | localhost | ok 2026-03-06 02:07:51.640364 | 2026-03-06 02:07:51.640427 | TASK [Upload logs] 2026-03-06 02:07:51.661425 | localhost | ok 2026-03-06 02:07:51.719649 | 2026-03-06 02:07:51.719852 | TASK [Set zuul-log-path fact] 2026-03-06 02:07:51.740216 | localhost | ok 2026-03-06 02:07:51.753276 | 2026-03-06 02:07:51.753346 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-06 02:07:51.782901 | localhost | ok 2026-03-06 02:07:51.790559 | 2026-03-06 02:07:51.790642 | TASK [upload-logs : Create log directories] 2026-03-06 02:07:52.195770 | localhost | changed 2026-03-06 02:07:52.203333 | 2026-03-06 02:07:52.203438 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-03-06 02:07:52.583760 | localhost -> localhost | ok: Runtime: 0:00:00.003726 2026-03-06 02:07:52.591151 | 2026-03-06 02:07:52.591228 | TASK [upload-logs : Upload logs to log server] 2026-03-06 02:07:53.040104 | localhost | Output suppressed because no_log was given 2026-03-06 02:07:53.047838 | 2026-03-06 02:07:53.048015 | LOOP [upload-logs : Compress console log and json output] 2026-03-06 02:07:53.096237 | localhost | skipping: Conditional result was False 2026-03-06 02:07:53.102865 | localhost | skipping: Conditional result was False 2026-03-06 02:07:53.114171 | 2026-03-06 02:07:53.114305 | LOOP [upload-logs : Upload compressed console log and json output] 2026-03-06 02:07:53.157341 | localhost | skipping: Conditional result was False 2026-03-06 02:07:53.157670 | 2026-03-06 02:07:53.161435 | localhost | skipping: Conditional result was False 2026-03-06 02:07:53.178667 | 2026-03-06 02:07:53.178891 | LOOP [upload-logs : Upload console log and json output]