2026-02-11 00:44:49.573792 | Job console starting 2026-02-11 00:44:49.582435 | Updating git repos 2026-02-11 00:44:49.626941 | Cloning repos into workspace 2026-02-11 00:44:49.679408 | Restoring repo states 2026-02-11 00:44:49.695989 | Merging changes 2026-02-11 00:44:50.166561 | Checking out repos 2026-02-11 00:44:50.206624 | Preparing playbooks 2026-02-11 00:44:51.493933 | Running Ansible setup 2026-02-11 00:44:55.164554 | PRE-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main] 2026-02-11 00:44:55.857164 | 2026-02-11 00:44:55.857299 | PLAY [localhost] 2026-02-11 00:44:55.864727 | 2026-02-11 00:44:55.864797 | TASK [Gathering Facts] 2026-02-11 00:44:56.743521 | localhost | ok 2026-02-11 00:44:56.750101 | 2026-02-11 00:44:56.750176 | TASK [Setup log path fact] 2026-02-11 00:44:56.769552 | localhost | ok 2026-02-11 00:44:56.785793 | 2026-02-11 00:44:56.785866 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-02-11 00:44:56.815657 | localhost | ok 2026-02-11 00:44:56.823412 | 2026-02-11 00:44:56.823481 | TASK [emit-job-header : Print job information] 2026-02-11 00:44:56.865522 | # Job Information 2026-02-11 00:44:56.865757 | Ansible Version: 2.16.15 2026-02-11 00:44:56.865833 | Job: ansible-collection-kubernetes-molecule-aio-rockylinux-9 2026-02-11 00:44:56.865885 | Pipeline: check 2026-02-11 00:44:56.865931 | Executor: 3a2793d2bd32 2026-02-11 00:44:56.865976 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/230 2026-02-11 00:44:56.866026 | Event ID: c1003d70-06e2-11f1-9860-b25bf9d18ce0 2026-02-11 00:44:56.869713 | 2026-02-11 00:44:56.869783 | LOOP [emit-job-header : Print node information] 2026-02-11 00:44:56.970173 | localhost | ok: 2026-02-11 00:44:56.970411 | localhost | # Node Information 2026-02-11 00:44:56.970442 | localhost | Inventory Hostname: controller 2026-02-11 00:44:56.970464 | localhost | Hostname: np0000155222 2026-02-11 00:44:56.970484 | localhost | Username: zuul 2026-02-11 00:44:56.970511 | localhost | Distro: Rocky 9.7 2026-02-11 00:44:56.970531 | localhost | Provider: yul1 2026-02-11 00:44:56.970550 | localhost | Region: ca-ymq-1 2026-02-11 00:44:56.970568 | localhost | Label: rockylinux-9 2026-02-11 00:44:56.970586 | localhost | Product Name: OpenStack Nova 2026-02-11 00:44:56.970603 | localhost | Interface IP: 199.204.45.227 2026-02-11 00:44:56.989622 | 2026-02-11 00:44:56.989819 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-02-11 00:44:57.475176 | localhost -> localhost | changed 2026-02-11 00:44:57.485763 | 2026-02-11 00:44:57.486011 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-02-11 00:44:58.358662 | localhost -> localhost | changed 2026-02-11 00:44:58.366596 | 2026-02-11 00:44:58.366674 | PLAY [all] 2026-02-11 00:44:58.374785 | 2026-02-11 00:44:58.374851 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-02-11 00:44:58.598846 | controller -> localhost | ok 2026-02-11 00:44:58.608296 | 2026-02-11 00:44:58.608411 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-02-11 00:44:58.640649 | controller | ok 2026-02-11 00:44:58.656116 | controller | included: /var/lib/zuul/builds/74357a8cd00044bcaa36f8d26d2d32e7/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-02-11 00:44:58.661279 | 2026-02-11 00:44:58.661345 | TASK [add-build-sshkey : Create Temp SSH key] 2026-02-11 00:45:00.290030 | controller -> localhost | Generating public/private rsa key pair. 2026-02-11 00:45:00.290266 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/74357a8cd00044bcaa36f8d26d2d32e7/work/74357a8cd00044bcaa36f8d26d2d32e7_id_rsa 2026-02-11 00:45:00.290307 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/74357a8cd00044bcaa36f8d26d2d32e7/work/74357a8cd00044bcaa36f8d26d2d32e7_id_rsa.pub 2026-02-11 00:45:00.290339 | controller -> localhost | The key fingerprint is: 2026-02-11 00:45:00.290367 | controller -> localhost | SHA256:OeC0sYHRFT0vkzgbijmBFp2nwoAymFjJ/S9EkGNf35c zuul-build-sshkey 2026-02-11 00:45:00.290412 | controller -> localhost | The key's randomart image is: 2026-02-11 00:45:00.290442 | controller -> localhost | +---[RSA 3072]----+ 2026-02-11 00:45:00.290477 | controller -> localhost | |++.+o= .oo | 2026-02-11 00:45:00.290507 | controller -> localhost | |O + Bo+ . o | 2026-02-11 00:45:00.290535 | controller -> localhost | |.+ +.B=. o = . | 2026-02-11 00:45:00.290562 | controller -> localhost | | = oo+*+.= o E | 2026-02-11 00:45:00.290589 | controller -> localhost | | . . =+oS+ o . | 2026-02-11 00:45:00.290616 | controller -> localhost | | + o o. | 2026-02-11 00:45:00.290643 | controller -> localhost | | . . | 2026-02-11 00:45:00.290670 | controller -> localhost | | | 2026-02-11 00:45:00.290701 | controller -> localhost | | | 2026-02-11 00:45:00.290729 | controller -> localhost | +----[SHA256]-----+ 2026-02-11 00:45:00.290793 | controller -> localhost | ok: Runtime: 0:00:01.161733 2026-02-11 00:45:00.296210 | 2026-02-11 00:45:00.296313 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-02-11 00:45:00.328951 | controller | ok 2026-02-11 00:45:00.345708 | controller | included: /var/lib/zuul/builds/74357a8cd00044bcaa36f8d26d2d32e7/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-02-11 00:45:00.353466 | 2026-02-11 00:45:00.353527 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-02-11 00:45:00.379078 | controller | skipping: Conditional result was False 2026-02-11 00:45:00.388747 | 2026-02-11 00:45:00.388834 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-02-11 00:45:00.882160 | controller | changed 2026-02-11 00:45:00.888241 | 2026-02-11 00:45:00.888328 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-02-11 00:45:01.155135 | controller | ok 2026-02-11 00:45:01.161413 | 2026-02-11 00:45:01.161508 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-02-11 00:45:02.022731 | controller | changed 2026-02-11 00:45:02.030455 | 2026-02-11 00:45:02.030527 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-02-11 00:45:02.865250 | controller | changed 2026-02-11 00:45:02.869949 | 2026-02-11 00:45:02.870012 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-02-11 00:45:02.895410 | controller | skipping: Conditional result was False 2026-02-11 00:45:02.904128 | 2026-02-11 00:45:02.904219 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-02-11 00:45:03.263554 | controller -> localhost | changed 2026-02-11 00:45:03.281446 | 2026-02-11 00:45:03.281559 | TASK [add-build-sshkey : Add back temp key] 2026-02-11 00:45:03.579352 | controller -> localhost | Identity added: /var/lib/zuul/builds/74357a8cd00044bcaa36f8d26d2d32e7/work/74357a8cd00044bcaa36f8d26d2d32e7_id_rsa (zuul-build-sshkey) 2026-02-11 00:45:03.579585 | controller -> localhost | ok: Runtime: 0:00:00.016443 2026-02-11 00:45:03.588733 | 2026-02-11 00:45:03.588797 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-02-11 00:45:03.975756 | controller | ok 2026-02-11 00:45:03.981928 | 2026-02-11 00:45:03.982091 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-02-11 00:45:04.007960 | controller | skipping: Conditional result was False 2026-02-11 00:45:04.024615 | 2026-02-11 00:45:04.024737 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-02-11 00:45:04.408631 | controller | ok 2026-02-11 00:45:04.414265 | 2026-02-11 00:45:04.414336 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-02-11 00:45:05.459257 | controller | Output suppressed because no_log was given 2026-02-11 00:45:05.467982 | 2026-02-11 00:45:05.468049 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-02-11 00:45:05.728337 | controller | ok: "logs" 2026-02-11 00:45:05.728718 | controller | ok: All items complete 2026-02-11 00:45:05.728784 | 2026-02-11 00:45:05.949629 | controller | ok: "artifacts" 2026-02-11 00:45:06.185615 | controller | ok: "docs" 2026-02-11 00:45:06.202883 | 2026-02-11 00:45:06.203053 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-02-11 00:45:06.453056 | controller | changed: "logs" 2026-02-11 00:45:06.674287 | controller | changed: "artifacts" 2026-02-11 00:45:06.907466 | controller | changed: "docs" 2026-02-11 00:45:06.922837 | 2026-02-11 00:45:06.922986 | PLAY RECAP 2026-02-11 00:45:06.923059 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-02-11 00:45:06.923094 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-02-11 00:45:06.923137 | 2026-02-11 00:45:07.078035 | PRE-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main] 2026-02-11 00:45:07.081385 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-02-11 00:45:07.679223 | 2026-02-11 00:45:07.679351 | PLAY [all] 2026-02-11 00:45:07.690613 | 2026-02-11 00:45:07.690691 | TASK [setup-uv : Extract archive] 2026-02-11 00:45:10.421349 | controller | changed 2026-02-11 00:45:10.426738 | 2026-02-11 00:45:10.426816 | TASK [setup-uv : Print version] 2026-02-11 00:45:10.450154 | [controller] Waiting on logger 2026-02-11 00:45:10.969549 | controller | ok: Runtime: 0:00:00.013152 2026-02-11 00:45:10.997461 | 2026-02-11 00:45:10.997692 | PLAY RECAP 2026-02-11 00:45:10.997796 | controller | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-02-11 00:45:10.997851 | 2026-02-11 00:45:11.123619 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-02-11 00:45:11.125714 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-02-11 00:45:11.726827 | 2026-02-11 00:45:11.727066 | PLAY [all] 2026-02-11 00:45:11.738573 | 2026-02-11 00:45:11.738646 | TASK [Copy inventory file for Zuul] 2026-02-11 00:45:12.945993 | controller | changed 2026-02-11 00:45:12.952441 | 2026-02-11 00:45:12.952516 | TASK [Switch "ansible_host" to private IP] 2026-02-11 00:45:13.275421 | controller | changed: 1 replacements made 2026-02-11 00:45:13.281877 | 2026-02-11 00:45:13.281954 | TASK [Run Molecule scenario] 2026-02-11 00:45:13.304868 | [controller] Waiting on logger 2026-02-11 00:45:21.450846 | [controller] Waiting on logger 2026-02-11 00:45:31.621942 | [controller] Waiting on logger 2026-02-11 00:45:41.801906 | [controller] Waiting on logger 2026-02-11 00:45:51.974163 | [controller] Waiting on logger 2026-02-11 00:46:02.149936 | [controller] Waiting on logger 2026-02-11 00:46:12.325811 | [controller] Waiting on logger 2026-02-11 00:46:21.483018 | [controller] Waiting on logger 2026-02-11 00:46:31.653975 | [controller] Waiting on logger 2026-02-11 00:46:41.829923 | [controller] Waiting on logger 2026-02-11 00:46:52.005980 | [controller] Waiting on logger 2026-02-11 00:47:02.181871 | [controller] Waiting on logger 2026-02-11 00:47:12.358144 | [controller] Waiting on logger 2026-02-11 00:47:21.515080 | [controller] Waiting on logger 2026-02-11 00:47:31.685904 | [controller] Waiting on logger 2026-02-11 00:47:41.861834 | [controller] Waiting on logger 2026-02-11 00:47:52.037862 | [controller] Waiting on logger 2026-02-11 00:48:02.214041 | [controller] Waiting on logger 2026-02-11 00:48:12.391112 | [controller] Waiting on logger 2026-02-11 00:48:21.862098 | [controller] Waiting on logger 2026-02-11 00:48:33.350086 | [controller] Waiting on logger 2026-02-11 00:48:44.554334 | [controller] Waiting on logger 2026-02-11 00:48:54.117919 | [controller] Waiting on logger 2026-02-11 00:49:04.294004 | [controller] Waiting on logger 2026-02-11 00:49:14.469912 | [controller] Waiting on logger 2026-02-11 00:49:23.653885 | [controller] Waiting on logger 2026-02-11 00:49:33.830072 | [controller] Waiting on logger 2026-02-11 00:49:44.006044 | [controller] Waiting on logger 2026-02-11 00:49:54.182030 | [controller] Waiting on logger 2026-02-11 00:50:04.359836 | [controller] Waiting on logger 2026-02-11 00:50:13.514788 | [controller] Waiting on logger 2026-02-11 00:50:23.717994 | [controller] Waiting on logger 2026-02-11 00:50:32.781717 | controller | ok: Runtime: 0:05:18.103780 2026-02-11 00:50:32.796289 | 2026-02-11 00:50:32.796348 | PLAY RECAP 2026-02-11 00:50:32.796395 | controller | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-02-11 00:50:32.796416 | 2026-02-11 00:50:32.937073 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-02-11 00:50:32.939940 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main] 2026-02-11 00:50:33.533373 | 2026-02-11 00:50:33.533517 | PLAY [all] 2026-02-11 00:50:33.544083 | 2026-02-11 00:50:33.544158 | TASK [fetch-output : Set log path for multiple nodes] 2026-02-11 00:50:33.590007 | controller | skipping: Conditional result was False 2026-02-11 00:50:33.599157 | 2026-02-11 00:50:33.599236 | TASK [fetch-output : Set log path for single node] 2026-02-11 00:50:33.641243 | controller | ok 2026-02-11 00:50:33.648529 | 2026-02-11 00:50:33.648644 | LOOP [fetch-output : Ensure local output dirs] 2026-02-11 00:50:34.083650 | controller -> localhost | ok: "/var/lib/zuul/builds/74357a8cd00044bcaa36f8d26d2d32e7/work/logs" 2026-02-11 00:50:34.292601 | controller -> localhost | changed: "/var/lib/zuul/builds/74357a8cd00044bcaa36f8d26d2d32e7/work/artifacts" 2026-02-11 00:50:34.500743 | controller -> localhost | changed: "/var/lib/zuul/builds/74357a8cd00044bcaa36f8d26d2d32e7/work/docs" 2026-02-11 00:50:34.519807 | 2026-02-11 00:50:34.520009 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-02-11 00:50:35.207101 | controller | changed: .d..t...... ./ 2026-02-11 00:50:35.207318 | controller | changed: All items complete 2026-02-11 00:50:35.207346 | 2026-02-11 00:50:35.714215 | controller | changed: .d..t...... ./ 2026-02-11 00:50:36.236707 | controller | changed: .d..t...... ./ 2026-02-11 00:50:36.266751 | 2026-02-11 00:50:36.266916 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-02-11 00:50:36.704337 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.008326 2026-02-11 00:50:36.932194 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.009621 2026-02-11 00:50:36.953273 | 2026-02-11 00:50:36.953423 | PLAY [all] 2026-02-11 00:50:36.959995 | 2026-02-11 00:50:36.960065 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-02-11 00:50:37.487425 | controller | changed 2026-02-11 00:50:37.493804 | 2026-02-11 00:50:37.493858 | PLAY RECAP 2026-02-11 00:50:37.493907 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-02-11 00:50:37.493929 | 2026-02-11 00:50:37.615584 | POST-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main] 2026-02-11 00:50:37.618277 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post-logs.yaml@main] 2026-02-11 00:50:38.158866 | 2026-02-11 00:50:38.158992 | PLAY [localhost] 2026-02-11 00:50:38.168778 | 2026-02-11 00:50:38.168850 | TASK [Generate Zuul manifest] 2026-02-11 00:50:38.188452 | localhost | ok 2026-02-11 00:50:38.202320 | 2026-02-11 00:50:38.202396 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-02-11 00:50:38.527358 | localhost | changed 2026-02-11 00:50:38.542158 | 2026-02-11 00:50:38.542234 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-02-11 00:50:38.569060 | localhost | ok 2026-02-11 00:50:38.577076 | 2026-02-11 00:50:38.577207 | TASK [Upload logs] 2026-02-11 00:50:38.597738 | localhost | ok 2026-02-11 00:50:38.661203 | 2026-02-11 00:50:38.661352 | TASK [Set zuul-log-path fact] 2026-02-11 00:50:38.679221 | localhost | ok 2026-02-11 00:50:38.690433 | 2026-02-11 00:50:38.690506 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-02-11 00:50:38.719482 | localhost | ok 2026-02-11 00:50:38.727483 | 2026-02-11 00:50:38.727548 | TASK [upload-logs : Create log directories] 2026-02-11 00:50:39.125026 | localhost | changed 2026-02-11 00:50:39.131235 | 2026-02-11 00:50:39.131328 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-02-11 00:50:39.538801 | localhost -> localhost | ok: Runtime: 0:00:00.009658 2026-02-11 00:50:39.545885 | 2026-02-11 00:50:39.545981 | TASK [upload-logs : Upload logs to log server] 2026-02-11 00:50:40.015632 | localhost | Output suppressed because no_log was given 2026-02-11 00:50:40.019611 | 2026-02-11 00:50:40.019682 | LOOP [upload-logs : Compress console log and json output] 2026-02-11 00:50:40.066765 | localhost | skipping: Conditional result was False 2026-02-11 00:50:40.072862 | localhost | skipping: Conditional result was False 2026-02-11 00:50:40.084027 | 2026-02-11 00:50:40.084192 | LOOP [upload-logs : Upload compressed console log and json output] 2026-02-11 00:50:40.127213 | localhost | skipping: Conditional result was False 2026-02-11 00:50:40.127643 | 2026-02-11 00:50:40.130208 | localhost | skipping: Conditional result was False 2026-02-11 00:50:40.148108 | 2026-02-11 00:50:40.148213 | LOOP [upload-logs : Upload console log and json output]