2026-06-03 01:26:10.736450 | Job console starting 2026-06-03 01:26:10.745720 | Updating git repos 2026-06-03 01:26:10.767136 | Cloning repos into workspace 2026-06-03 01:26:10.836090 | Restoring repo states 2026-06-03 01:26:10.844277 | Merging changes 2026-06-03 01:26:11.127142 | Checking out repos 2026-06-03 01:26:11.156334 | Preparing playbooks 2026-06-03 01:26:12.230112 | Running Ansible setup 2026-06-03 01:26:15.828524 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-06-03 01:26:16.472481 | 2026-06-03 01:26:16.600148 | PLAY [localhost] 2026-06-03 01:26:16.611975 | 2026-06-03 01:26:16.612091 | TASK [Gathering Facts] 2026-06-03 01:26:17.698754 | localhost | ok 2026-06-03 01:26:17.708252 | 2026-06-03 01:26:17.732061 | TASK [Setup log path fact] 2026-06-03 01:26:17.755192 | localhost | ok 2026-06-03 01:26:17.766981 | 2026-06-03 01:26:17.767053 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-03 01:26:17.797827 | localhost | ok 2026-06-03 01:26:17.805952 | 2026-06-03 01:26:17.806019 | TASK [emit-job-header : Print job information] 2026-06-03 01:26:17.846091 | # Job Information 2026-06-03 01:26:17.846261 | Ansible Version: 2.16.18 2026-06-03 01:26:17.846305 | Job: ansible-collection-containers-molecule-docker-rockylinux-9 2026-06-03 01:26:17.846336 | Pipeline: check 2026-06-03 01:26:17.846364 | Executor: 2d72f0692154 2026-06-03 01:26:17.846392 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/128 2026-06-03 01:26:17.846422 | Event ID: 1d781310-5eeb-11f1-908b-3a529372ad09 2026-06-03 01:26:17.850167 | 2026-06-03 01:26:17.850253 | LOOP [emit-job-header : Print node information] 2026-06-03 01:26:17.947202 | localhost | ok: 2026-06-03 01:26:17.947385 | localhost | # Node Information 2026-06-03 01:26:17.947414 | localhost | Inventory Hostname: rockylinux-9 2026-06-03 01:26:17.947436 | localhost | Hostname: np0000178317 2026-06-03 01:26:17.947457 | localhost | Username: zuul 2026-06-03 01:26:17.947479 | localhost | Distro: Rocky 9.7 2026-06-03 01:26:17.947499 | localhost | Provider: yul1 2026-06-03 01:26:17.947519 | localhost | Region: ca-ymq-1 2026-06-03 01:26:17.947538 | localhost | Label: rockylinux-9 2026-06-03 01:26:17.947557 | localhost | Product Name: OpenStack Nova 2026-06-03 01:26:17.947594 | localhost | Interface IP: 199.204.45.129 2026-06-03 01:26:17.958583 | 2026-06-03 01:26:17.958846 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-06-03 01:26:18.401335 | localhost -> localhost | changed 2026-06-03 01:26:18.412781 | 2026-06-03 01:26:18.412854 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-06-03 01:26:19.855235 | localhost -> localhost | changed 2026-06-03 01:26:19.862735 | 2026-06-03 01:26:19.862810 | PLAY [all] 2026-06-03 01:26:19.872707 | 2026-06-03 01:26:19.872796 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-06-03 01:26:20.111258 | rockylinux-9 -> localhost | ok 2026-06-03 01:26:20.121371 | 2026-06-03 01:26:20.121449 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-06-03 01:26:20.158416 | rockylinux-9 | ok 2026-06-03 01:26:20.175536 | rockylinux-9 | included: /var/lib/zuul/builds/66a38e9cd6cd4d69ae1865a2895b0d75/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-06-03 01:26:20.182070 | 2026-06-03 01:26:20.182134 | TASK [add-build-sshkey : Create Temp SSH key] 2026-06-03 01:26:21.989494 | rockylinux-9 -> localhost | Generating public/private rsa key pair. 2026-06-03 01:26:21.989721 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/66a38e9cd6cd4d69ae1865a2895b0d75/work/66a38e9cd6cd4d69ae1865a2895b0d75_id_rsa 2026-06-03 01:26:21.989766 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/66a38e9cd6cd4d69ae1865a2895b0d75/work/66a38e9cd6cd4d69ae1865a2895b0d75_id_rsa.pub 2026-06-03 01:26:21.989799 | rockylinux-9 -> localhost | The key fingerprint is: 2026-06-03 01:26:21.989830 | rockylinux-9 -> localhost | SHA256:xy09dIV1znZTbNA0tP4zBIvhB2xXcWn0n0G5yjy33p0 zuul-build-sshkey 2026-06-03 01:26:21.989874 | rockylinux-9 -> localhost | The key's randomart image is: 2026-06-03 01:26:21.989905 | rockylinux-9 -> localhost | +---[RSA 3072]----+ 2026-06-03 01:26:21.989940 | rockylinux-9 -> localhost | | +#@| 2026-06-03 01:26:21.989972 | rockylinux-9 -> localhost | | . +B@| 2026-06-03 01:26:21.990003 | rockylinux-9 -> localhost | | =.oo=B| 2026-06-03 01:26:21.990032 | rockylinux-9 -> localhost | | .o+=.+o*| 2026-06-03 01:26:21.990061 | rockylinux-9 -> localhost | | S +o*o.+.| 2026-06-03 01:26:21.990090 | rockylinux-9 -> localhost | | . ..*...| 2026-06-03 01:26:21.990118 | rockylinux-9 -> localhost | | ooo| 2026-06-03 01:26:21.990146 | rockylinux-9 -> localhost | | .*| 2026-06-03 01:26:21.990179 | rockylinux-9 -> localhost | | .Eo| 2026-06-03 01:26:21.990208 | rockylinux-9 -> localhost | +----[SHA256]-----+ 2026-06-03 01:26:21.990278 | rockylinux-9 -> localhost | ok: Runtime: 0:00:01.332650 2026-06-03 01:26:21.995428 | 2026-06-03 01:26:21.995497 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-06-03 01:26:22.031552 | rockylinux-9 | ok 2026-06-03 01:26:22.176249 | rockylinux-9 | included: /var/lib/zuul/builds/66a38e9cd6cd4d69ae1865a2895b0d75/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-06-03 01:26:22.183973 | 2026-06-03 01:26:22.184042 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-06-03 01:26:22.208933 | rockylinux-9 | skipping: Conditional result was False 2026-06-03 01:26:22.219940 | 2026-06-03 01:26:22.220009 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-06-03 01:26:22.686180 | rockylinux-9 | changed 2026-06-03 01:26:22.692862 | 2026-06-03 01:26:22.692977 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-06-03 01:26:22.945426 | rockylinux-9 | ok 2026-06-03 01:26:22.952102 | 2026-06-03 01:26:22.952195 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-06-03 01:26:23.825771 | rockylinux-9 | changed 2026-06-03 01:26:23.830502 | 2026-06-03 01:26:23.830567 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-06-03 01:26:24.762726 | rockylinux-9 | changed 2026-06-03 01:26:24.778364 | 2026-06-03 01:26:24.778530 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-06-03 01:26:24.798773 | rockylinux-9 | skipping: Conditional result was False 2026-06-03 01:26:24.807959 | 2026-06-03 01:26:24.808115 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-06-03 01:26:25.758310 | rockylinux-9 -> localhost | changed 2026-06-03 01:26:25.769013 | 2026-06-03 01:26:25.769133 | TASK [add-build-sshkey : Add back temp key] 2026-06-03 01:26:26.693648 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/66a38e9cd6cd4d69ae1865a2895b0d75/work/66a38e9cd6cd4d69ae1865a2895b0d75_id_rsa (zuul-build-sshkey) 2026-06-03 01:26:26.693893 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.015265 2026-06-03 01:26:26.699559 | 2026-06-03 01:26:26.699642 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-06-03 01:26:27.112770 | rockylinux-9 | ok 2026-06-03 01:26:27.209675 | 2026-06-03 01:26:27.209858 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-06-03 01:26:27.236951 | rockylinux-9 | skipping: Conditional result was False 2026-06-03 01:26:27.249898 | 2026-06-03 01:26:27.250024 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-06-03 01:26:27.722116 | rockylinux-9 | ok 2026-06-03 01:26:28.137187 | 2026-06-03 01:26:28.137354 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-06-03 01:26:31.207091 | rockylinux-9 | Output suppressed because no_log was given 2026-06-03 01:26:31.219072 | 2026-06-03 01:26:31.219140 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-06-03 01:26:31.465159 | rockylinux-9 | ok: "logs" 2026-06-03 01:26:31.465838 | rockylinux-9 | ok: All items complete 2026-06-03 01:26:31.465878 | 2026-06-03 01:26:31.691174 | rockylinux-9 | ok: "artifacts" 2026-06-03 01:26:31.921373 | rockylinux-9 | ok: "docs" 2026-06-03 01:26:31.939864 | 2026-06-03 01:26:31.940046 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-06-03 01:26:32.215260 | rockylinux-9 | changed: "logs" 2026-06-03 01:26:32.472290 | rockylinux-9 | changed: "artifacts" 2026-06-03 01:26:32.653859 | rockylinux-9 | changed: "docs" 2026-06-03 01:26:32.670029 | 2026-06-03 01:26:32.670096 | PLAY RECAP 2026-06-03 01:26:32.670151 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-06-03 01:26:32.670192 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-06-03 01:26:32.670264 | 2026-06-03 01:26:32.806605 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-06-03 01:26:32.808519 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-06-03 01:26:33.463697 | 2026-06-03 01:26:33.463822 | PLAY [all] 2026-06-03 01:26:33.478354 | 2026-06-03 01:26:33.478450 | TASK [setup-uv : Extract archive] 2026-06-03 01:26:36.325517 | rockylinux-9 | changed 2026-06-03 01:26:36.333555 | 2026-06-03 01:26:36.333739 | TASK [setup-uv : Print version] 2026-06-03 01:26:36.356047 | [rockylinux-9] Waiting on logger 2026-06-03 01:26:36.866931 | rockylinux-9 | ok: Runtime: 0:00:00.014626 2026-06-03 01:26:36.876055 | 2026-06-03 01:26:36.876124 | TASK [Install Ansible collection siblings] 2026-06-03 01:26:37.423666 | rockylinux-9 | ok: 2026-06-03 01:26:37.653641 | rockylinux-9 | Skipping ansible.posix: no checked-out sibling 2026-06-03 01:26:37.653820 | rockylinux-9 | Skipping community.general: no checked-out sibling 2026-06-03 01:26:37.657542 | 2026-06-03 01:26:37.657644 | PLAY RECAP 2026-06-03 01:26:37.657740 | rockylinux-9 | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-06-03 01:26:37.657789 | 2026-06-03 01:26:37.911920 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-06-03 01:26:37.913953 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-06-03 01:26:38.671802 | 2026-06-03 01:26:38.671999 | PLAY [all] 2026-06-03 01:26:38.684558 | 2026-06-03 01:26:38.684736 | TASK [Copy inventory file for Zuul] 2026-06-03 01:26:40.025332 | rockylinux-9 | changed 2026-06-03 01:26:40.052401 | 2026-06-03 01:26:40.052557 | TASK [Switch "ansible_host" to private IP] 2026-06-03 01:26:40.532107 | rockylinux-9 | changed: 1 replacements made 2026-06-03 01:26:40.840894 | 2026-06-03 01:26:40.841052 | TASK [Run Molecule scenario] 2026-06-03 01:26:40.881226 | [rockylinux-9] Waiting on logger 2026-06-03 01:26:48.006006 | [rockylinux-9] Waiting on logger 2026-06-03 01:26:58.185775 | [rockylinux-9] Waiting on logger 2026-06-03 01:27:08.361896 | [rockylinux-9] Waiting on logger 2026-06-03 01:27:17.765837 | [rockylinux-9] Waiting on logger 2026-06-03 01:27:28.102038 | [rockylinux-9] Waiting on logger 2026-06-03 01:27:38.278223 | [rockylinux-9] Waiting on logger 2026-06-03 01:27:47.467810 | [rockylinux-9] Waiting on logger 2026-06-03 01:27:57.893961 | [rockylinux-9] Waiting on logger 2026-06-03 01:28:08.070037 | [rockylinux-9] Waiting on logger 2026-06-03 01:28:17.451364 | [rockylinux-9] Waiting on logger 2026-06-03 01:28:27.626006 | [rockylinux-9] Waiting on logger 2026-06-03 01:28:37.802009 | [rockylinux-9] Waiting on logger 2026-06-03 01:28:47.974080 | [rockylinux-9] Waiting on logger 2026-06-03 01:28:52.565419 | rockylinux-9 | ok: Runtime: 0:02:10.255301 2026-06-03 01:28:52.572238 | 2026-06-03 01:28:52.572292 | PLAY RECAP 2026-06-03 01:28:52.572340 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-06-03 01:28:52.572364 | 2026-06-03 01:28:52.747032 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-06-03 01:28:52.748914 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-06-03 01:28:53.347757 | 2026-06-03 01:28:53.347906 | PLAY [all] 2026-06-03 01:28:53.360683 | 2026-06-03 01:28:53.360774 | TASK [fetch-output : Set log path for multiple nodes] 2026-06-03 01:28:53.396548 | rockylinux-9 | skipping: Conditional result was False 2026-06-03 01:28:53.405527 | 2026-06-03 01:28:53.405634 | TASK [fetch-output : Set log path for single node] 2026-06-03 01:28:53.449414 | rockylinux-9 | ok 2026-06-03 01:28:53.453693 | 2026-06-03 01:28:53.453762 | LOOP [fetch-output : Ensure local output dirs] 2026-06-03 01:28:53.839290 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/66a38e9cd6cd4d69ae1865a2895b0d75/work/logs" 2026-06-03 01:28:54.048201 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/66a38e9cd6cd4d69ae1865a2895b0d75/work/artifacts" 2026-06-03 01:28:54.279186 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/66a38e9cd6cd4d69ae1865a2895b0d75/work/docs" 2026-06-03 01:28:54.297558 | 2026-06-03 01:28:54.297640 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-06-03 01:28:54.999611 | rockylinux-9 | changed: .d..t...... ./ 2026-06-03 01:28:54.999865 | rockylinux-9 | changed: All items complete 2026-06-03 01:28:54.999904 | 2026-06-03 01:28:55.531151 | rockylinux-9 | changed: .d..t...... ./ 2026-06-03 01:28:56.052737 | rockylinux-9 | changed: .d..t...... ./ 2026-06-03 01:28:56.076464 | 2026-06-03 01:28:56.076604 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-06-03 01:28:56.490510 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.008222 2026-06-03 01:28:56.715730 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.008493 2026-06-03 01:28:56.737556 | 2026-06-03 01:28:56.737682 | PLAY [all] 2026-06-03 01:28:56.743220 | 2026-06-03 01:28:56.743284 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-06-03 01:28:57.221527 | rockylinux-9 | changed 2026-06-03 01:28:57.228703 | 2026-06-03 01:28:57.228752 | PLAY RECAP 2026-06-03 01:28:57.228800 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-06-03 01:28:57.228822 | 2026-06-03 01:28:57.380321 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-06-03 01:28:57.381941 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-06-03 01:28:57.950981 | 2026-06-03 01:28:57.951114 | PLAY [localhost] 2026-06-03 01:28:57.961715 | 2026-06-03 01:28:57.961795 | TASK [Generate Zuul manifest] 2026-06-03 01:28:57.993567 | localhost | ok 2026-06-03 01:28:58.009578 | 2026-06-03 01:28:58.009658 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-06-03 01:28:58.403812 | localhost | changed 2026-06-03 01:28:58.416589 | 2026-06-03 01:28:58.416681 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-06-03 01:28:58.452867 | localhost | ok 2026-06-03 01:28:58.459617 | 2026-06-03 01:28:58.459717 | TASK [Upload logs] 2026-06-03 01:28:58.481604 | localhost | ok 2026-06-03 01:28:58.541539 | 2026-06-03 01:28:58.541695 | TASK [Set zuul-log-path fact] 2026-06-03 01:28:58.564233 | localhost | ok 2026-06-03 01:28:58.577378 | 2026-06-03 01:28:58.577454 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-03 01:28:58.609785 | localhost | ok 2026-06-03 01:28:58.618040 | 2026-06-03 01:28:58.618112 | TASK [upload-logs : Create log directories] 2026-06-03 01:28:59.057356 | localhost | changed 2026-06-03 01:28:59.063269 | 2026-06-03 01:28:59.063345 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-06-03 01:28:59.491336 | localhost -> localhost | ok: Runtime: 0:00:00.005855 2026-06-03 01:28:59.498316 | 2026-06-03 01:28:59.498412 | TASK [upload-logs : Upload logs to log server] 2026-06-03 01:29:00.029591 | localhost | Output suppressed because no_log was given 2026-06-03 01:29:00.035021 | 2026-06-03 01:29:00.035091 | LOOP [upload-logs : Compress console log and json output] 2026-06-03 01:29:00.085088 | localhost | skipping: Conditional result was False 2026-06-03 01:29:00.092558 | localhost | skipping: Conditional result was False 2026-06-03 01:29:00.110714 | 2026-06-03 01:29:00.110953 | LOOP [upload-logs : Upload compressed console log and json output] 2026-06-03 01:29:00.158038 | localhost | skipping: Conditional result was False 2026-06-03 01:29:00.158554 | 2026-06-03 01:29:00.163643 | localhost | skipping: Conditional result was False 2026-06-03 01:29:00.179069 | 2026-06-03 01:29:00.179229 | LOOP [upload-logs : Upload console log and json output]