2026-03-16 08:42:29.364626 | Job console starting
2026-03-16 08:42:29.465329 | Updating git repos
2026-03-16 08:42:29.498891 | Cloning repos into workspace
2026-03-16 08:42:29.710197 | Restoring repo states
2026-03-16 08:42:29.726286 | Merging changes
2026-03-16 08:42:30.387454 | Checking out repos
2026-03-16 08:42:30.746151 | Preparing playbooks
2026-03-16 08:42:39.718240 | Running Ansible setup
2026-03-16 08:42:44.277401 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-03-16 08:42:44.947920 | 
2026-03-16 08:42:45.271330 | PLAY [localhost]
2026-03-16 08:42:45.285497 | 
2026-03-16 08:42:45.285733 | TASK [Gathering Facts]
2026-03-16 08:42:46.470873 | localhost | ok
2026-03-16 08:42:46.481600 | 
2026-03-16 08:42:46.481755 | TASK [Setup log path fact]
2026-03-16 08:42:46.500555 | localhost | ok
2026-03-16 08:42:46.518217 | 
2026-03-16 08:42:46.518391 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-03-16 08:42:46.546997 | localhost | ok
2026-03-16 08:42:46.555696 | 
2026-03-16 08:42:46.555864 | TASK [emit-job-header : Print job information]
2026-03-16 08:42:46.596609 | # Job Information
2026-03-16 08:42:46.596918 | Ansible Version: 2.16.16
2026-03-16 08:42:46.597001 | Job: ansible-collection-kubernetes-molecule-aio-rockylinux-9
2026-03-16 08:42:46.597037 | Pipeline: check
2026-03-16 08:42:46.597065 | Executor: 0a8996d2b663
2026-03-16 08:42:46.597092 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/235
2026-03-16 08:42:46.597121 | Event ID: 967d5f10-2113-11f1-8d4c-9f55520a2587
2026-03-16 08:42:46.601364 | 
2026-03-16 08:42:46.601510 | LOOP [emit-job-header : Print node information]
2026-03-16 08:42:46.717458 | localhost | ok:
2026-03-16 08:42:46.717719 | localhost | # Node Information
2026-03-16 08:42:46.717767 | localhost | Inventory Hostname: controller
2026-03-16 08:42:46.717813 | localhost | Hostname: np0000158895
2026-03-16 08:42:46.717851 | localhost | Username: zuul
2026-03-16 08:42:46.717903 | localhost | Distro: Rocky 9.7
2026-03-16 08:42:46.717948 | localhost | Provider: yul1
2026-03-16 08:42:46.718142 | localhost | Region: ca-ymq-1
2026-03-16 08:42:46.718197 | localhost | Label: rockylinux-9
2026-03-16 08:42:46.718230 | localhost | Product Name: OpenStack Nova
2026-03-16 08:42:46.718260 | localhost | Interface IP: 199.204.45.74
2026-03-16 08:42:46.738272 | 
2026-03-16 08:42:46.738461 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-03-16 08:42:47.533763 | localhost -> localhost | changed
2026-03-16 08:42:47.543067 | 
2026-03-16 08:42:47.543183 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-03-16 08:42:48.597880 | localhost -> localhost | changed
2026-03-16 08:42:48.605648 | 
2026-03-16 08:42:48.605761 | PLAY [all]
2026-03-16 08:42:48.616512 | 
2026-03-16 08:42:48.616661 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-03-16 08:42:48.864040 | controller -> localhost | ok
2026-03-16 08:42:48.870004 | 
2026-03-16 08:42:48.870127 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-03-16 08:42:48.904851 | controller | ok
2026-03-16 08:42:48.920844 | controller | included: /var/lib/zuul/builds/570addb872594cdabe6bdc44e39506a8/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-03-16 08:42:48.926586 | 
2026-03-16 08:42:48.926705 | TASK [add-build-sshkey : Create Temp SSH key]
2026-03-16 08:42:49.556424 | controller -> localhost | Generating public/private rsa key pair.
2026-03-16 08:42:49.556621 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/570addb872594cdabe6bdc44e39506a8/work/570addb872594cdabe6bdc44e39506a8_id_rsa
2026-03-16 08:42:49.556660 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/570addb872594cdabe6bdc44e39506a8/work/570addb872594cdabe6bdc44e39506a8_id_rsa.pub
2026-03-16 08:42:49.556691 | controller -> localhost | The key fingerprint is:
2026-03-16 08:42:49.556723 | controller -> localhost | SHA256:wrfrZ2CyUDuxNu7Z2JZjptcje4lx5KkfQbs7M8+PWUA zuul-build-sshkey
2026-03-16 08:42:49.556770 | controller -> localhost | The key's randomart image is:
2026-03-16 08:42:49.556806 | controller -> localhost | +---[RSA 3072]----+
2026-03-16 08:42:49.556840 | controller -> localhost | |                 |
2026-03-16 08:42:49.556872 | controller -> localhost | |                 |
2026-03-16 08:42:49.556903 | controller -> localhost | |          . E    |
2026-03-16 08:42:49.556933 | controller -> localhost | |     .o  ..o     |
2026-03-16 08:42:49.557047 | controller -> localhost | |     .o+Soo..    |
2026-03-16 08:42:49.557085 | controller -> localhost | |    . Bo+.+o .   |
2026-03-16 08:42:49.557118 | controller -> localhost | |     + *.Oo.  .  |
2026-03-16 08:42:49.557151 | controller -> localhost | |      o=@.@+ +   |
2026-03-16 08:42:49.557184 | controller -> localhost | |     .=B*Oo*=..  |
2026-03-16 08:42:49.557216 | controller -> localhost | +----[SHA256]-----+
2026-03-16 08:42:49.557288 | controller -> localhost | ok: Runtime: 0:00:00.176355
2026-03-16 08:42:49.564158 | 
2026-03-16 08:42:49.564248 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-03-16 08:42:49.594896 | controller | ok
2026-03-16 08:42:49.607491 | controller | included: /var/lib/zuul/builds/570addb872594cdabe6bdc44e39506a8/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-03-16 08:42:49.619246 | 
2026-03-16 08:42:49.619403 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-03-16 08:42:49.643710 | controller | skipping: Conditional result was False
2026-03-16 08:42:49.650942 | 
2026-03-16 08:42:49.651069 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-03-16 08:42:50.136444 | controller | changed
2026-03-16 08:42:50.142235 | 
2026-03-16 08:42:50.142341 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-03-16 08:42:50.386544 | controller | ok
2026-03-16 08:42:50.394435 | 
2026-03-16 08:42:50.394606 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-03-16 08:42:51.298932 | controller | changed
2026-03-16 08:42:51.304047 | 
2026-03-16 08:42:51.304138 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-03-16 08:42:52.156035 | controller | changed
2026-03-16 08:42:52.160575 | 
2026-03-16 08:42:52.160640 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-03-16 08:42:52.184215 | controller | skipping: Conditional result was False
2026-03-16 08:42:52.193984 | 
2026-03-16 08:42:52.194111 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-03-16 08:42:52.577214 | controller -> localhost | changed
2026-03-16 08:42:52.591579 | 
2026-03-16 08:42:52.591696 | TASK [add-build-sshkey : Add back temp key]
2026-03-16 08:42:52.877222 | controller -> localhost | Identity added: /var/lib/zuul/builds/570addb872594cdabe6bdc44e39506a8/work/570addb872594cdabe6bdc44e39506a8_id_rsa (zuul-build-sshkey)
2026-03-16 08:42:52.877480 | controller -> localhost | ok: Runtime: 0:00:00.011085
2026-03-16 08:42:52.884099 | 
2026-03-16 08:42:52.884241 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-03-16 08:42:53.231250 | controller | ok
2026-03-16 08:42:53.237626 | 
2026-03-16 08:42:53.237736 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-03-16 08:42:53.261663 | controller | skipping: Conditional result was False
2026-03-16 08:42:53.274428 | 
2026-03-16 08:42:53.274592 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-03-16 08:42:53.699067 | controller | ok
2026-03-16 08:42:53.709528 | 
2026-03-16 08:42:53.709666 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-03-16 08:42:54.892197 | controller | Output suppressed because no_log was given
2026-03-16 08:42:54.904436 | 
2026-03-16 08:42:54.904776 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-03-16 08:42:55.180431 | controller | ok: "logs"
2026-03-16 08:42:55.180730 | controller | ok: All items complete
2026-03-16 08:42:55.180788 | 
2026-03-16 08:42:55.393483 | controller | ok: "artifacts"
2026-03-16 08:42:55.594582 | controller | ok: "docs"
2026-03-16 08:42:55.609092 | 
2026-03-16 08:42:55.609264 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-03-16 08:42:55.876784 | controller | changed: "logs"
2026-03-16 08:42:56.102128 | controller | changed: "artifacts"
2026-03-16 08:42:56.333636 | controller | changed: "docs"
2026-03-16 08:42:56.350959 | 
2026-03-16 08:42:56.351136 | PLAY RECAP
2026-03-16 08:42:56.351216 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-03-16 08:42:56.351263 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-03-16 08:42:56.351300 | 
2026-03-16 08:42:56.607007 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-03-16 08:42:56.609359 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-03-16 08:42:57.235452 | 
2026-03-16 08:42:57.318776 | PLAY [all]
2026-03-16 08:42:57.338723 | 
2026-03-16 08:42:57.338932 | TASK [setup-uv : Extract archive]
2026-03-16 08:43:00.089339 | controller | changed
2026-03-16 08:43:00.097977 | 
2026-03-16 08:43:00.098100 | TASK [setup-uv : Print version]
2026-03-16 08:43:00.122853 | [controller] Waiting on logger
2026-03-16 08:43:00.635102 | controller | ok: Runtime: 0:00:00.013888
2026-03-16 08:43:00.641519 | 
2026-03-16 08:43:00.641576 | PLAY RECAP
2026-03-16 08:43:00.641626 | controller | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-03-16 08:43:00.641651 | 
2026-03-16 08:43:00.757580 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-03-16 08:43:00.759465 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-03-16 08:43:01.393361 | 
2026-03-16 08:43:01.393935 | PLAY [all]
2026-03-16 08:43:01.407722 | 
2026-03-16 08:43:01.407918 | TASK [Copy inventory file for Zuul]
2026-03-16 08:43:02.744903 | controller | changed
2026-03-16 08:43:02.752390 | 
2026-03-16 08:43:02.752559 | TASK [Switch "ansible_host" to private IP]
2026-03-16 08:43:03.085970 | controller | changed: 1 replacements made
2026-03-16 08:43:03.092794 | 
2026-03-16 08:43:03.093161 | TASK [Run Molecule scenario]
2026-03-16 08:43:03.121721 | [controller] Waiting on logger
2026-03-16 08:43:11.270043 | [controller] Waiting on logger
2026-03-16 08:43:21.446251 | [controller] Waiting on logger
2026-03-16 08:43:31.623660 | [controller] Waiting on logger
2026-03-16 08:43:41.798188 | [controller] Waiting on logger
2026-03-16 08:47:36.468236 | controller | ok: Runtime: 0:04:32.896927
2026-03-16 08:47:36.489382 | 
2026-03-16 08:47:36.489493 | PLAY RECAP
2026-03-16 08:47:36.489543 | controller | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-03-16 08:47:36.489568 | 
2026-03-16 08:47:36.597854 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-03-16 08:47:36.600805 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-03-16 08:47:37.139512 | 
2026-03-16 08:47:37.139612 | PLAY [all]
2026-03-16 08:47:37.149763 | 
2026-03-16 08:47:37.149835 | TASK [fetch-output : Set log path for multiple nodes]
2026-03-16 08:47:37.184763 | controller | skipping: Conditional result was False
2026-03-16 08:47:37.192798 | 
2026-03-16 08:47:37.192902 | TASK [fetch-output : Set log path for single node]
2026-03-16 08:47:37.236493 | controller | ok
2026-03-16 08:47:37.240857 | 
2026-03-16 08:47:37.240923 | LOOP [fetch-output : Ensure local output dirs]
2026-03-16 08:47:37.607010 | controller -> localhost | ok: "/var/lib/zuul/builds/570addb872594cdabe6bdc44e39506a8/work/logs"
2026-03-16 08:47:37.806078 | controller -> localhost | changed: "/var/lib/zuul/builds/570addb872594cdabe6bdc44e39506a8/work/artifacts"
2026-03-16 08:47:38.003993 | controller -> localhost | changed: "/var/lib/zuul/builds/570addb872594cdabe6bdc44e39506a8/work/docs"
2026-03-16 08:47:38.022580 | 
2026-03-16 08:47:38.022786 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-03-16 08:47:38.711818 | controller | ok
2026-03-16 08:47:38.712072 | controller | changed: All items complete
2026-03-16 08:47:38.712120 | 
2026-03-16 08:47:39.215169 | controller | changed: .d..t...... ./
2026-03-16 08:47:39.726668 | controller | changed: .d..t...... ./
2026-03-16 08:47:39.739454 | 
2026-03-16 08:47:39.739522 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-03-16 08:47:40.187829 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.007754
2026-03-16 08:47:40.411502 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.008089
2026-03-16 08:47:40.431049 | 
2026-03-16 08:47:40.431105 | PLAY [all]
2026-03-16 08:47:40.436313 | 
2026-03-16 08:47:40.436372 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-03-16 08:47:40.923316 | controller | changed
2026-03-16 08:47:40.930961 | 
2026-03-16 08:47:40.931011 | PLAY RECAP
2026-03-16 08:47:40.931057 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-03-16 08:47:40.931079 | 
2026-03-16 08:47:41.027011 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-03-16 08:47:41.030941 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-03-16 08:47:41.563265 | 
2026-03-16 08:47:41.563373 | PLAY [localhost]
2026-03-16 08:47:41.572909 | 
2026-03-16 08:47:41.572981 | TASK [Generate Zuul manifest]
2026-03-16 08:47:41.596591 | localhost | ok
2026-03-16 08:47:41.611233 | 
2026-03-16 08:47:41.611314 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-03-16 08:47:41.912021 | localhost | changed
2026-03-16 08:47:41.923187 | 
2026-03-16 08:47:41.923261 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-03-16 08:47:41.956654 | localhost | ok
2026-03-16 08:47:41.963189 | 
2026-03-16 08:47:41.963253 | TASK [Upload logs]
2026-03-16 08:47:41.984858 | localhost | ok
2026-03-16 08:47:42.043259 | 
2026-03-16 08:47:42.043369 | TASK [Set zuul-log-path fact]
2026-03-16 08:47:42.065529 | localhost | ok
2026-03-16 08:47:42.077660 | 
2026-03-16 08:47:42.077725 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-03-16 08:47:42.111581 | localhost | ok
2026-03-16 08:47:42.119550 | 
2026-03-16 08:47:42.119613 | TASK [upload-logs : Create log directories]
2026-03-16 08:47:42.549363 | localhost | changed
2026-03-16 08:47:42.555266 | 
2026-03-16 08:47:42.555359 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-03-16 08:47:42.922342 | localhost -> localhost | ok: Runtime: 0:00:00.004937
2026-03-16 08:47:42.928431 | 
2026-03-16 08:47:42.928515 | TASK [upload-logs : Upload logs to log server]
2026-03-16 08:47:43.353489 | localhost | Output suppressed because no_log was given
2026-03-16 08:47:43.357628 | 
2026-03-16 08:47:43.357695 | LOOP [upload-logs : Compress console log and json output]
2026-03-16 08:47:43.405297 | localhost | skipping: Conditional result was False
2026-03-16 08:47:43.412598 | localhost | skipping: Conditional result was False
2026-03-16 08:47:43.431098 | 
2026-03-16 08:47:43.431338 | LOOP [upload-logs : Upload compressed console log and json output]
2026-03-16 08:47:43.476512 | localhost | skipping: Conditional result was False
2026-03-16 08:47:43.476878 | 
2026-03-16 08:47:43.480554 | localhost | skipping: Conditional result was False
2026-03-16 08:47:43.496535 | 
2026-03-16 08:47:43.496705 | LOOP [upload-logs : Upload console log and json output]