2026-02-16 21:12:55.255600 | Job console starting 2026-02-16 21:12:55.263084 | Updating git repos 2026-02-16 21:12:55.316778 | Cloning repos into workspace 2026-02-16 21:12:55.415898 | Restoring repo states 2026-02-16 21:12:55.429801 | Merging changes 2026-02-16 21:12:56.018256 | Checking out repos 2026-02-16 21:12:56.242260 | Preparing playbooks 2026-02-16 21:12:57.387221 | Running Ansible setup 2026-02-16 21:13:00.900006 | PRE-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main] 2026-02-16 21:13:01.530508 | 2026-02-16 21:13:01.530652 | PLAY [localhost] 2026-02-16 21:13:01.538900 | 2026-02-16 21:13:01.539006 | TASK [Gathering Facts] 2026-02-16 21:13:02.464296 | localhost | ok 2026-02-16 21:13:02.471793 | 2026-02-16 21:13:02.471873 | TASK [Setup log path fact] 2026-02-16 21:13:02.494298 | localhost | ok 2026-02-16 21:13:02.507345 | 2026-02-16 21:13:02.507413 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-02-16 21:13:02.537422 | localhost | ok 2026-02-16 21:13:02.546712 | 2026-02-16 21:13:02.546783 | TASK [emit-job-header : Print job information] 2026-02-16 21:13:02.588107 | # Job Information 2026-02-16 21:13:02.588475 | Ansible Version: 2.16.15 2026-02-16 21:13:02.588527 | Job: ansible-collection-kubernetes-molecule-aio-rockylinux-9 2026-02-16 21:13:02.588559 | Pipeline: check 2026-02-16 21:13:02.588588 | Executor: 3a2793d2bd32 2026-02-16 21:13:02.588619 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/232 2026-02-16 21:13:02.588650 | Event ID: dd7fa8d0-0b7b-11f1-80a0-1b5177b5933d 2026-02-16 21:13:02.592652 | 2026-02-16 21:13:02.592741 | LOOP [emit-job-header : Print node information] 2026-02-16 21:13:02.682015 | localhost | ok: 2026-02-16 21:13:02.682343 | localhost | # Node Information 2026-02-16 21:13:02.682396 | localhost | Inventory Hostname: controller 2026-02-16 21:13:02.682433 | localhost | Hostname: np0000155816 2026-02-16 21:13:02.682466 | localhost | Username: zuul 2026-02-16 21:13:02.682502 | localhost | Distro: Rocky 9.7 2026-02-16 21:13:02.682534 | localhost | Provider: yul1 2026-02-16 21:13:02.682566 | localhost | Region: ca-ymq-1 2026-02-16 21:13:02.682596 | localhost | Label: rockylinux-9 2026-02-16 21:13:02.682627 | localhost | Product Name: OpenStack Nova 2026-02-16 21:13:02.682659 | localhost | Interface IP: 199.204.45.78 2026-02-16 21:13:02.703490 | 2026-02-16 21:13:02.703644 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-02-16 21:13:03.168906 | localhost -> localhost | changed 2026-02-16 21:13:03.185391 | 2026-02-16 21:13:03.185475 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-02-16 21:13:04.106260 | localhost -> localhost | changed 2026-02-16 21:13:04.114790 | 2026-02-16 21:13:04.114864 | PLAY [all] 2026-02-16 21:13:04.124099 | 2026-02-16 21:13:04.124180 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-02-16 21:13:04.351362 | controller -> localhost | ok 2026-02-16 21:13:04.360349 | 2026-02-16 21:13:04.360428 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-02-16 21:13:04.391235 | controller | ok 2026-02-16 21:13:04.408058 | controller | included: /var/lib/zuul/builds/179fe183dbb84bf59dc7cca5672da655/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-02-16 21:13:04.413671 | 2026-02-16 21:13:04.413737 | TASK [add-build-sshkey : Create Temp SSH key] 2026-02-16 21:13:05.327178 | controller -> localhost | Generating public/private rsa key pair. 2026-02-16 21:13:05.327383 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/179fe183dbb84bf59dc7cca5672da655/work/179fe183dbb84bf59dc7cca5672da655_id_rsa 2026-02-16 21:13:05.327413 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/179fe183dbb84bf59dc7cca5672da655/work/179fe183dbb84bf59dc7cca5672da655_id_rsa.pub 2026-02-16 21:13:05.327435 | controller -> localhost | The key fingerprint is: 2026-02-16 21:13:05.327457 | controller -> localhost | SHA256:ZXopBqmQiswDKTZs88cxggy9jMyHDPtafOaRjH97tMU zuul-build-sshkey 2026-02-16 21:13:05.327497 | controller -> localhost | The key's randomart image is: 2026-02-16 21:13:05.327519 | controller -> localhost | +---[RSA 3072]----+ 2026-02-16 21:13:05.327545 | controller -> localhost | |.. | 2026-02-16 21:13:05.327567 | controller -> localhost | |=.o. . | 2026-02-16 21:13:05.327587 | controller -> localhost | |O%+o oo o | 2026-02-16 21:13:05.327608 | controller -> localhost | |@**oo.o. + . | 2026-02-16 21:13:05.327628 | controller -> localhost | |o*.+.+ S.o | 2026-02-16 21:13:05.327647 | controller -> localhost | | * B ..oE | 2026-02-16 21:13:05.327667 | controller -> localhost | | o = . . o | 2026-02-16 21:13:05.327686 | controller -> localhost | |. o . o | 2026-02-16 21:13:05.327744 | controller -> localhost | | ..o | 2026-02-16 21:13:05.327766 | controller -> localhost | +----[SHA256]-----+ 2026-02-16 21:13:05.327818 | controller -> localhost | ok: Runtime: 0:00:00.518613 2026-02-16 21:13:05.332491 | 2026-02-16 21:13:05.332556 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-02-16 21:13:05.361957 | controller | ok 2026-02-16 21:13:05.370203 | controller | included: /var/lib/zuul/builds/179fe183dbb84bf59dc7cca5672da655/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-02-16 21:13:05.377964 | 2026-02-16 21:13:05.378023 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-02-16 21:13:05.402606 | controller | skipping: Conditional result was False 2026-02-16 21:13:05.407627 | 2026-02-16 21:13:05.407690 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-02-16 21:13:05.845861 | controller | changed 2026-02-16 21:13:05.850738 | 2026-02-16 21:13:05.850800 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-02-16 21:13:06.073641 | controller | ok 2026-02-16 21:13:06.080002 | 2026-02-16 21:13:06.080089 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-02-16 21:13:06.836743 | controller | changed 2026-02-16 21:13:06.844321 | 2026-02-16 21:13:06.844384 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-02-16 21:13:07.609245 | controller | changed 2026-02-16 21:13:07.614769 | 2026-02-16 21:13:07.614841 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-02-16 21:13:07.640031 | controller | skipping: Conditional result was False 2026-02-16 21:13:07.646723 | 2026-02-16 21:13:07.646792 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-02-16 21:13:08.023462 | controller -> localhost | changed 2026-02-16 21:13:08.038142 | 2026-02-16 21:13:08.038214 | TASK [add-build-sshkey : Add back temp key] 2026-02-16 21:13:08.334948 | controller -> localhost | Identity added: /var/lib/zuul/builds/179fe183dbb84bf59dc7cca5672da655/work/179fe183dbb84bf59dc7cca5672da655_id_rsa (zuul-build-sshkey) 2026-02-16 21:13:08.335209 | controller -> localhost | ok: Runtime: 0:00:00.012677 2026-02-16 21:13:08.343084 | 2026-02-16 21:13:08.343156 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-02-16 21:13:08.716807 | controller | ok 2026-02-16 21:13:08.721304 | 2026-02-16 21:13:08.721370 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-02-16 21:13:08.746760 | controller | skipping: Conditional result was False 2026-02-16 21:13:08.759996 | 2026-02-16 21:13:08.760064 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-02-16 21:13:09.137769 | controller | ok 2026-02-16 21:13:09.144300 | 2026-02-16 21:13:09.144370 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-02-16 21:13:10.202073 | controller | Output suppressed because no_log was given 2026-02-16 21:13:10.210051 | 2026-02-16 21:13:10.210129 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-02-16 21:13:10.432869 | controller | ok: "logs" 2026-02-16 21:13:10.433346 | controller | ok: All items complete 2026-02-16 21:13:10.433406 | 2026-02-16 21:13:10.633322 | controller | ok: "artifacts" 2026-02-16 21:13:10.839786 | controller | ok: "docs" 2026-02-16 21:13:10.851336 | 2026-02-16 21:13:10.851512 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-02-16 21:13:11.092322 | controller | changed: "logs" 2026-02-16 21:13:11.303190 | controller | changed: "artifacts" 2026-02-16 21:13:11.510351 | controller | changed: "docs" 2026-02-16 21:13:11.529685 | 2026-02-16 21:13:11.529818 | PLAY RECAP 2026-02-16 21:13:11.529884 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-02-16 21:13:11.529923 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-02-16 21:13:11.529954 | 2026-02-16 21:13:11.667322 | PRE-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main] 2026-02-16 21:13:11.669951 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-02-16 21:13:12.233682 | 2026-02-16 21:13:12.234441 | PLAY [all] 2026-02-16 21:13:12.245793 | 2026-02-16 21:13:12.245872 | TASK [setup-uv : Extract archive] 2026-02-16 21:13:14.846494 | controller | changed 2026-02-16 21:13:14.853916 | 2026-02-16 21:13:14.854026 | TASK [setup-uv : Print version] 2026-02-16 21:13:14.877327 | [controller] Waiting on logger 2026-02-16 21:13:15.390065 | controller | ok: Runtime: 0:00:00.014116 2026-02-16 21:13:15.397260 | 2026-02-16 21:13:15.397324 | PLAY RECAP 2026-02-16 21:13:15.397380 | controller | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-02-16 21:13:15.397404 | 2026-02-16 21:13:15.508929 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-02-16 21:13:15.511692 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-02-16 21:13:16.099579 | 2026-02-16 21:13:16.099792 | PLAY [all] 2026-02-16 21:13:16.111564 | 2026-02-16 21:13:16.111639 | TASK [Copy inventory file for Zuul] 2026-02-16 21:13:17.316891 | controller | changed 2026-02-16 21:13:17.321851 | 2026-02-16 21:13:17.321927 | TASK [Switch "ansible_host" to private IP] 2026-02-16 21:13:17.636451 | controller | changed: 1 replacements made 2026-02-16 21:13:17.644049 | 2026-02-16 21:13:17.644139 | TASK [Run Molecule scenario] 2026-02-16 21:13:17.665757 | [controller] Waiting on logger 2026-02-16 21:13:26.825896 | [controller] Waiting on logger 2026-02-16 21:13:35.979084 | [controller] Waiting on logger 2026-02-16 21:13:46.149971 | [controller] Waiting on logger 2026-02-16 21:13:56.325896 | [controller] Waiting on logger 2026-02-16 21:14:06.501868 | [controller] Waiting on logger 2026-02-16 21:14:16.677987 | [controller] Waiting on logger 2026-02-16 21:14:26.854091 | [controller] Waiting on logger 2026-02-16 21:14:36.006227 | [controller] Waiting on logger 2026-02-16 21:14:46.181897 | [controller] Waiting on logger 2026-02-16 21:14:56.357850 | [controller] Waiting on logger 2026-02-16 21:15:06.533847 | [controller] Waiting on logger 2026-02-16 21:15:16.709883 | [controller] Waiting on logger 2026-02-16 21:15:26.885722 | [controller] Waiting on logger 2026-02-16 21:15:36.037848 | [controller] Waiting on logger 2026-02-16 21:15:46.213826 | [controller] Waiting on logger 2026-02-16 21:15:56.390914 | [controller] Waiting on logger 2026-02-16 21:16:06.565845 | [controller] Waiting on logger 2026-02-16 21:16:16.746018 | [controller] Waiting on logger 2026-02-16 21:16:25.903047 | [controller] Waiting on logger 2026-02-16 21:16:36.069653 | [controller] Waiting on logger 2026-02-16 21:16:46.246115 | [controller] Waiting on logger 2026-02-16 21:16:56.425811 | [controller] Waiting on logger 2026-02-16 21:17:06.598014 | [controller] Waiting on logger 2026-02-16 21:17:16.773888 | [controller] Waiting on logger 2026-02-16 21:17:25.930939 | [controller] Waiting on logger 2026-02-16 21:17:36.101742 | [controller] Waiting on logger 2026-02-16 21:17:46.277749 | [controller] Waiting on logger 2026-02-16 21:17:47.405824 | controller | ok: Runtime: 0:04:28.872118 2026-02-16 21:17:47.420041 | 2026-02-16 21:17:47.420094 | PLAY RECAP 2026-02-16 21:17:47.420141 | controller | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-02-16 21:17:47.420163 | 2026-02-16 21:17:47.536941 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-02-16 21:17:47.539895 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main] 2026-02-16 21:17:48.097886 | 2026-02-16 21:17:48.098014 | PLAY [all] 2026-02-16 21:17:48.108871 | 2026-02-16 21:17:48.108974 | TASK [fetch-output : Set log path for multiple nodes] 2026-02-16 21:17:48.154763 | controller | skipping: Conditional result was False 2026-02-16 21:17:48.164251 | 2026-02-16 21:17:48.164424 | TASK [fetch-output : Set log path for single node] 2026-02-16 21:17:48.208050 | controller | ok 2026-02-16 21:17:48.213819 | 2026-02-16 21:17:48.213890 | LOOP [fetch-output : Ensure local output dirs] 2026-02-16 21:17:48.611306 | controller -> localhost | ok: "/var/lib/zuul/builds/179fe183dbb84bf59dc7cca5672da655/work/logs" 2026-02-16 21:17:48.842097 | controller -> localhost | changed: "/var/lib/zuul/builds/179fe183dbb84bf59dc7cca5672da655/work/artifacts" 2026-02-16 21:17:49.056497 | controller -> localhost | changed: "/var/lib/zuul/builds/179fe183dbb84bf59dc7cca5672da655/work/docs" 2026-02-16 21:17:49.073050 | 2026-02-16 21:17:49.073239 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-02-16 21:17:49.736761 | controller | ok 2026-02-16 21:17:49.737092 | controller | changed: All items complete 2026-02-16 21:17:49.737137 | 2026-02-16 21:17:50.250507 | controller | changed: .d..t...... ./ 2026-02-16 21:17:50.736896 | controller | changed: .d..t...... ./ 2026-02-16 21:17:50.764418 | 2026-02-16 21:17:50.764624 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-02-16 21:17:51.199466 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.008216 2026-02-16 21:17:51.462367 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.008851 2026-02-16 21:17:51.483182 | 2026-02-16 21:17:51.483344 | PLAY [all] 2026-02-16 21:17:51.489860 | 2026-02-16 21:17:51.489931 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-02-16 21:17:51.966931 | controller | changed 2026-02-16 21:17:51.973272 | 2026-02-16 21:17:51.973325 | PLAY RECAP 2026-02-16 21:17:51.973375 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-02-16 21:17:51.973397 | 2026-02-16 21:17:52.132356 | POST-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main] 2026-02-16 21:17:52.135289 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post-logs.yaml@main] 2026-02-16 21:17:52.743204 | 2026-02-16 21:17:52.743335 | PLAY [localhost] 2026-02-16 21:17:52.753765 | 2026-02-16 21:17:52.753833 | TASK [Generate Zuul manifest] 2026-02-16 21:17:52.776406 | localhost | ok 2026-02-16 21:17:52.794552 | 2026-02-16 21:17:52.794630 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-02-16 21:17:53.110740 | localhost | changed 2026-02-16 21:17:53.124594 | 2026-02-16 21:17:53.124750 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-02-16 21:17:53.158724 | localhost | ok 2026-02-16 21:17:53.167183 | 2026-02-16 21:17:53.167287 | TASK [Upload logs] 2026-02-16 21:17:53.189037 | localhost | ok 2026-02-16 21:17:53.253508 | 2026-02-16 21:17:53.253715 | TASK [Set zuul-log-path fact] 2026-02-16 21:17:53.276804 | localhost | ok 2026-02-16 21:17:53.289515 | 2026-02-16 21:17:53.289586 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-02-16 21:17:53.321677 | localhost | ok 2026-02-16 21:17:53.331549 | 2026-02-16 21:17:53.331639 | TASK [upload-logs : Create log directories] 2026-02-16 21:17:53.748687 | localhost | changed 2026-02-16 21:17:53.755380 | 2026-02-16 21:17:53.755473 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-02-16 21:17:54.174566 | localhost -> localhost | ok: Runtime: 0:00:00.006075 2026-02-16 21:17:54.180031 | 2026-02-16 21:17:54.180100 | TASK [upload-logs : Upload logs to log server] 2026-02-16 21:17:54.667773 | localhost | Output suppressed because no_log was given 2026-02-16 21:17:54.673003 | 2026-02-16 21:17:54.673099 | LOOP [upload-logs : Compress console log and json output] 2026-02-16 21:17:54.721953 | localhost | skipping: Conditional result was False 2026-02-16 21:17:54.728030 | localhost | skipping: Conditional result was False 2026-02-16 21:17:54.738762 | 2026-02-16 21:17:54.738835 | LOOP [upload-logs : Upload compressed console log and json output] 2026-02-16 21:17:54.783223 | localhost | skipping: Conditional result was False 2026-02-16 21:17:54.783591 | 2026-02-16 21:17:54.786628 | localhost | skipping: Conditional result was False 2026-02-16 21:17:54.802622 | 2026-02-16 21:17:54.802716 | LOOP [upload-logs : Upload console log and json output]