2026-02-04 13:52:17.528401 | Job console starting 2026-02-04 13:52:17.538455 | Updating git repos 2026-02-04 13:52:17.582806 | Cloning repos into workspace 2026-02-04 13:52:17.656681 | Restoring repo states 2026-02-04 13:52:17.664459 | Merging changes 2026-02-04 13:52:18.552670 | Checking out repos 2026-02-04 13:52:18.605704 | Preparing playbooks 2026-02-04 13:52:20.142134 | Running Ansible setup 2026-02-04 13:52:23.881190 | PRE-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main] 2026-02-04 13:52:24.555396 | 2026-02-04 13:52:24.555531 | PLAY [localhost] 2026-02-04 13:52:24.563833 | 2026-02-04 13:52:24.563910 | TASK [Gathering Facts] 2026-02-04 13:52:25.473323 | localhost | ok 2026-02-04 13:52:25.480472 | 2026-02-04 13:52:25.480552 | TASK [Setup log path fact] 2026-02-04 13:52:25.501600 | localhost | ok 2026-02-04 13:52:25.515005 | 2026-02-04 13:52:25.515150 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-02-04 13:52:25.544590 | localhost | ok 2026-02-04 13:52:25.553313 | 2026-02-04 13:52:25.553492 | TASK [emit-job-header : Print job information] 2026-02-04 13:52:25.597379 | # Job Information 2026-02-04 13:52:25.597777 | Ansible Version: 2.16.15 2026-02-04 13:52:25.597819 | Job: ansible-collection-containers-molecule-docker-rockylinux-9 2026-02-04 13:52:25.597843 | Pipeline: check 2026-02-04 13:52:25.597863 | Executor: 3a2793d2bd32 2026-02-04 13:52:25.597884 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/93 2026-02-04 13:52:25.597908 | Event ID: 8df6747e-01d0-11f1-93f2-82fc7d812c5c 2026-02-04 13:52:25.601348 | 2026-02-04 13:52:25.601428 | LOOP [emit-job-header : Print node information] 2026-02-04 13:52:25.697575 | localhost | ok: 2026-02-04 13:52:25.697800 | localhost | # Node Information 2026-02-04 13:52:25.697838 | localhost | Inventory Hostname: rockylinux-9 2026-02-04 13:52:25.698015 | localhost | Hostname: np0000154851 2026-02-04 13:52:25.698055 | localhost | Username: zuul 2026-02-04 13:52:25.698094 | localhost | Distro: Rocky 9.7 2026-02-04 13:52:25.698124 | localhost | Provider: yul1 2026-02-04 13:52:25.698149 | localhost | Region: ca-ymq-1 2026-02-04 13:52:25.698174 | localhost | Label: rockylinux-9 2026-02-04 13:52:25.698197 | localhost | Product Name: OpenStack Nova 2026-02-04 13:52:25.698220 | localhost | Interface IP: 199.19.213.83 2026-02-04 13:52:25.705722 | 2026-02-04 13:52:25.705889 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-02-04 13:52:26.125979 | localhost -> localhost | changed 2026-02-04 13:52:26.131185 | 2026-02-04 13:52:26.131261 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-02-04 13:52:27.021892 | localhost -> localhost | changed 2026-02-04 13:52:27.032276 | 2026-02-04 13:52:27.032358 | PLAY [all] 2026-02-04 13:52:27.043864 | 2026-02-04 13:52:27.043939 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-02-04 13:52:27.271948 | rockylinux-9 -> localhost | ok 2026-02-04 13:52:27.283995 | 2026-02-04 13:52:27.284228 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-02-04 13:52:27.319835 | rockylinux-9 | ok 2026-02-04 13:52:27.334923 | rockylinux-9 | included: /var/lib/zuul/builds/e671b545d82f4c1faafd99dc1a589426/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-02-04 13:52:27.342082 | 2026-02-04 13:52:27.342157 | TASK [add-build-sshkey : Create Temp SSH key] 2026-02-04 13:52:28.154501 | rockylinux-9 -> localhost | Generating public/private rsa key pair. 2026-02-04 13:52:28.154683 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/e671b545d82f4c1faafd99dc1a589426/work/e671b545d82f4c1faafd99dc1a589426_id_rsa 2026-02-04 13:52:28.154718 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/e671b545d82f4c1faafd99dc1a589426/work/e671b545d82f4c1faafd99dc1a589426_id_rsa.pub 2026-02-04 13:52:28.154742 | rockylinux-9 -> localhost | The key fingerprint is: 2026-02-04 13:52:28.154764 | rockylinux-9 -> localhost | SHA256:+APftuq7I0CsYriWtqrLbJ20X+a9nfpn3ZtscTdoBE8 zuul-build-sshkey 2026-02-04 13:52:28.154801 | rockylinux-9 -> localhost | The key's randomart image is: 2026-02-04 13:52:28.154823 | rockylinux-9 -> localhost | +---[RSA 3072]----+ 2026-02-04 13:52:28.154850 | rockylinux-9 -> localhost | | | 2026-02-04 13:52:28.154871 | rockylinux-9 -> localhost | | . E | 2026-02-04 13:52:28.154891 | rockylinux-9 -> localhost | | . + | 2026-02-04 13:52:28.154911 | rockylinux-9 -> localhost | | o . o | 2026-02-04 13:52:28.154930 | rockylinux-9 -> localhost | |. o o S . . | 2026-02-04 13:52:28.154949 | rockylinux-9 -> localhost | |o.... + . o oo| 2026-02-04 13:52:28.154968 | rockylinux-9 -> localhost | |.o+ o. o+ o . . *| 2026-02-04 13:52:28.154987 | rockylinux-9 -> localhost | |+= + = oo...o.oo| 2026-02-04 13:52:28.155008 | rockylinux-9 -> localhost | |X=. .. +=O=+o .+.| 2026-02-04 13:52:28.155028 | rockylinux-9 -> localhost | +----[SHA256]-----+ 2026-02-04 13:52:28.155081 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.345228 2026-02-04 13:52:28.160055 | 2026-02-04 13:52:28.160121 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-02-04 13:52:28.188917 | rockylinux-9 | ok 2026-02-04 13:52:28.196948 | rockylinux-9 | included: /var/lib/zuul/builds/e671b545d82f4c1faafd99dc1a589426/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-02-04 13:52:28.203919 | 2026-02-04 13:52:28.203982 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-02-04 13:52:28.228740 | rockylinux-9 | skipping: Conditional result was False 2026-02-04 13:52:28.235459 | 2026-02-04 13:52:28.235535 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-02-04 13:52:28.709864 | rockylinux-9 | changed 2026-02-04 13:52:28.714462 | 2026-02-04 13:52:28.714525 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-02-04 13:52:28.946892 | rockylinux-9 | ok 2026-02-04 13:52:28.951505 | 2026-02-04 13:52:28.951569 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-02-04 13:52:29.766741 | rockylinux-9 | changed 2026-02-04 13:52:29.773468 | 2026-02-04 13:52:29.773564 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-02-04 13:52:30.563083 | rockylinux-9 | changed 2026-02-04 13:52:30.568926 | 2026-02-04 13:52:30.569017 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-02-04 13:52:30.590152 | rockylinux-9 | skipping: Conditional result was False 2026-02-04 13:52:30.596395 | 2026-02-04 13:52:30.596464 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-02-04 13:52:31.006507 | rockylinux-9 -> localhost | changed 2026-02-04 13:52:31.028603 | 2026-02-04 13:52:31.028788 | TASK [add-build-sshkey : Add back temp key] 2026-02-04 13:52:31.306788 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/e671b545d82f4c1faafd99dc1a589426/work/e671b545d82f4c1faafd99dc1a589426_id_rsa (zuul-build-sshkey) 2026-02-04 13:52:31.306991 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.013198 2026-02-04 13:52:31.311654 | 2026-02-04 13:52:31.311717 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-02-04 13:52:31.666812 | rockylinux-9 | ok 2026-02-04 13:52:31.673175 | 2026-02-04 13:52:31.673245 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-02-04 13:52:31.700267 | rockylinux-9 | skipping: Conditional result was False 2026-02-04 13:52:31.712628 | 2026-02-04 13:52:31.712731 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-02-04 13:52:32.143472 | rockylinux-9 | ok 2026-02-04 13:52:32.151525 | 2026-02-04 13:52:32.151677 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-02-04 13:52:33.014502 | rockylinux-9 | Output suppressed because no_log was given 2026-02-04 13:52:33.025859 | 2026-02-04 13:52:33.025924 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-02-04 13:52:33.276608 | rockylinux-9 | ok: "logs" 2026-02-04 13:52:33.277018 | rockylinux-9 | ok: All items complete 2026-02-04 13:52:33.277050 | 2026-02-04 13:52:33.481438 | rockylinux-9 | ok: "artifacts" 2026-02-04 13:52:33.679042 | rockylinux-9 | ok: "docs" 2026-02-04 13:52:33.696291 | 2026-02-04 13:52:33.696515 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-02-04 13:52:33.945919 | rockylinux-9 | changed: "logs" 2026-02-04 13:52:34.148030 | rockylinux-9 | changed: "artifacts" 2026-02-04 13:52:34.359652 | rockylinux-9 | changed: "docs" 2026-02-04 13:52:34.383480 | 2026-02-04 13:52:34.383666 | PLAY RECAP 2026-02-04 13:52:34.383749 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-02-04 13:52:34.383799 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-02-04 13:52:34.383829 | 2026-02-04 13:52:34.559704 | PRE-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main] 2026-02-04 13:52:34.562275 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-02-04 13:52:35.147990 | 2026-02-04 13:52:35.148118 | PLAY [all] 2026-02-04 13:52:35.159766 | 2026-02-04 13:52:35.159849 | TASK [setup-uv : Extract archive] 2026-02-04 13:52:37.883398 | rockylinux-9 | changed 2026-02-04 13:52:37.889494 | 2026-02-04 13:52:37.889636 | TASK [setup-uv : Print version] 2026-02-04 13:52:37.912171 | [rockylinux-9] Waiting on logger 2026-02-04 13:52:38.429289 | rockylinux-9 | ok: Runtime: 0:00:00.017557 2026-02-04 13:52:38.435966 | 2026-02-04 13:52:38.436022 | PLAY RECAP 2026-02-04 13:52:38.436066 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-02-04 13:52:38.436090 | 2026-02-04 13:52:38.562281 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-02-04 13:52:38.564729 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-02-04 13:52:39.166307 | 2026-02-04 13:52:39.166467 | PLAY [all] 2026-02-04 13:52:39.178831 | 2026-02-04 13:52:39.178989 | TASK [Copy inventory file for Zuul] 2026-02-04 13:52:40.438226 | rockylinux-9 | changed 2026-02-04 13:52:40.444651 | 2026-02-04 13:52:40.444759 | TASK [Switch "ansible_host" to private IP] 2026-02-04 13:52:40.774431 | rockylinux-9 | changed: 1 replacements made 2026-02-04 13:52:40.781339 | 2026-02-04 13:52:40.781437 | TASK [Run Molecule scenario] 2026-02-04 13:52:40.804787 | [rockylinux-9] Waiting on logger 2026-02-04 13:52:48.941274 | [rockylinux-9] Waiting on logger 2026-02-04 13:52:59.141930 | [rockylinux-9] Waiting on logger 2026-02-04 13:53:09.317960 | [rockylinux-9] Waiting on logger 2026-02-04 13:53:19.493908 | [rockylinux-9] Waiting on logger 2026-02-04 13:53:29.669994 | [rockylinux-9] Waiting on logger 2026-02-04 13:53:39.849845 | [rockylinux-9] Waiting on logger 2026-02-04 13:53:49.003388 | [rockylinux-9] Waiting on logger 2026-02-04 13:53:59.173828 | [rockylinux-9] Waiting on logger 2026-02-04 13:54:09.349936 | [rockylinux-9] Waiting on logger 2026-02-04 13:54:19.525854 | [rockylinux-9] Waiting on logger 2026-02-04 13:54:29.734042 | [rockylinux-9] Waiting on logger 2026-02-04 13:54:38.923715 | [rockylinux-9] Waiting on logger 2026-02-04 13:54:49.094037 | [rockylinux-9] Waiting on logger 2026-02-04 13:54:59.270204 | [rockylinux-9] Waiting on logger 2026-02-04 13:55:04.876663 | rockylinux-9 | ok: Runtime: 0:02:22.958474 2026-02-04 13:55:04.884236 | 2026-02-04 13:55:04.884288 | PLAY RECAP 2026-02-04 13:55:04.884335 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-02-04 13:55:04.884357 | 2026-02-04 13:55:04.990622 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-02-04 13:55:04.992174 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main] 2026-02-04 13:55:05.595661 | 2026-02-04 13:55:05.595799 | PLAY [all] 2026-02-04 13:55:05.615565 | 2026-02-04 13:55:05.615781 | TASK [fetch-output : Set log path for multiple nodes] 2026-02-04 13:55:05.662002 | rockylinux-9 | skipping: Conditional result was False 2026-02-04 13:55:05.670083 | 2026-02-04 13:55:05.670196 | TASK [fetch-output : Set log path for single node] 2026-02-04 13:55:05.713321 | rockylinux-9 | ok 2026-02-04 13:55:05.719037 | 2026-02-04 13:55:05.719110 | LOOP [fetch-output : Ensure local output dirs] 2026-02-04 13:55:06.124731 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/e671b545d82f4c1faafd99dc1a589426/work/logs" 2026-02-04 13:55:06.332510 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/e671b545d82f4c1faafd99dc1a589426/work/artifacts" 2026-02-04 13:55:06.552741 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/e671b545d82f4c1faafd99dc1a589426/work/docs" 2026-02-04 13:55:06.572018 | 2026-02-04 13:55:06.572220 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-02-04 13:55:07.228252 | rockylinux-9 | changed: .d..t...... ./ 2026-02-04 13:55:07.228513 | rockylinux-9 | changed: All items complete 2026-02-04 13:55:07.228550 | 2026-02-04 13:55:07.712880 | rockylinux-9 | changed: .d..t...... ./ 2026-02-04 13:55:08.211199 | rockylinux-9 | changed: .d..t...... ./ 2026-02-04 13:55:08.240815 | 2026-02-04 13:55:08.241020 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-02-04 13:55:08.691621 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.008971 2026-02-04 13:55:08.916615 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.009230 2026-02-04 13:55:08.937598 | 2026-02-04 13:55:08.937778 | PLAY [all] 2026-02-04 13:55:08.944857 | 2026-02-04 13:55:08.944972 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-02-04 13:55:09.394273 | rockylinux-9 | changed 2026-02-04 13:55:09.400993 | 2026-02-04 13:55:09.401043 | PLAY RECAP 2026-02-04 13:55:09.401088 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-02-04 13:55:09.401110 | 2026-02-04 13:55:09.512826 | POST-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main] 2026-02-04 13:55:09.514923 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post-logs.yaml@main] 2026-02-04 13:55:10.082384 | 2026-02-04 13:55:10.082538 | PLAY [localhost] 2026-02-04 13:55:10.093214 | 2026-02-04 13:55:10.093319 | TASK [Generate Zuul manifest] 2026-02-04 13:55:10.113764 | localhost | ok 2026-02-04 13:55:10.130188 | 2026-02-04 13:55:10.130295 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-02-04 13:55:10.447904 | localhost | changed 2026-02-04 13:55:10.462145 | 2026-02-04 13:55:10.462403 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-02-04 13:55:10.495653 | localhost | ok 2026-02-04 13:55:10.505699 | 2026-02-04 13:55:10.505780 | TASK [Upload logs] 2026-02-04 13:55:10.528312 | localhost | ok 2026-02-04 13:55:10.592309 | 2026-02-04 13:55:10.592524 | TASK [Set zuul-log-path fact] 2026-02-04 13:55:10.613095 | localhost | ok 2026-02-04 13:55:10.626834 | 2026-02-04 13:55:10.626902 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-02-04 13:55:10.658689 | localhost | ok 2026-02-04 13:55:10.667707 | 2026-02-04 13:55:10.667799 | TASK [upload-logs : Create log directories] 2026-02-04 13:55:11.066877 | localhost | changed 2026-02-04 13:55:11.073149 | 2026-02-04 13:55:11.073229 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-02-04 13:55:11.509049 | localhost -> localhost | ok: Runtime: 0:00:00.005778 2026-02-04 13:55:11.514104 | 2026-02-04 13:55:11.514171 | TASK [upload-logs : Upload logs to log server] 2026-02-04 13:55:11.991713 | localhost | Output suppressed because no_log was given 2026-02-04 13:55:11.996892 | 2026-02-04 13:55:11.997008 | LOOP [upload-logs : Compress console log and json output] 2026-02-04 13:55:12.040810 | localhost | skipping: Conditional result was False 2026-02-04 13:55:12.047340 | localhost | skipping: Conditional result was False 2026-02-04 13:55:12.061344 | 2026-02-04 13:55:12.061517 | LOOP [upload-logs : Upload compressed console log and json output] 2026-02-04 13:55:12.101944 | localhost | skipping: Conditional result was False 2026-02-04 13:55:12.102306 | 2026-02-04 13:55:12.105477 | localhost | skipping: Conditional result was False 2026-02-04 13:55:12.116390 | 2026-02-04 13:55:12.116612 | LOOP [upload-logs : Upload console log and json output]