2026-06-16 00:51:26.931680 | Job console starting 2026-06-16 00:51:26.946931 | Updating git repos 2026-06-16 00:51:26.970746 | Cloning repos into workspace 2026-06-16 00:51:27.471266 | Restoring repo states 2026-06-16 00:51:27.481312 | Merging changes 2026-06-16 00:51:27.987296 | Checking out repos 2026-06-16 00:51:28.054737 | Preparing playbooks 2026-06-16 00:51:30.447118 | Running Ansible setup 2026-06-16 00:51:35.519634 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-06-16 00:51:36.287287 | 2026-06-16 00:51:36.287411 | PLAY [localhost] 2026-06-16 00:51:36.294934 | 2026-06-16 00:51:36.295006 | TASK [Gathering Facts] 2026-06-16 00:51:37.271913 | localhost | ok 2026-06-16 00:51:37.283082 | 2026-06-16 00:51:37.283211 | TASK [Setup log path fact] 2026-06-16 00:51:37.306868 | localhost | ok 2026-06-16 00:51:37.317505 | 2026-06-16 00:51:37.317580 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-16 00:51:37.346285 | localhost | ok 2026-06-16 00:51:37.352576 | 2026-06-16 00:51:37.352655 | TASK [emit-job-header : Print job information] 2026-06-16 00:51:37.396422 | # Job Information 2026-06-16 00:51:37.396589 | Ansible Version: 2.16.18 2026-06-16 00:51:37.396622 | Job: ansible-collection-containers-molecule-containerd-rockylinux-9 2026-06-16 00:51:37.396646 | Pipeline: check 2026-06-16 00:51:37.396667 | Executor: 2d72f0692154 2026-06-16 00:51:37.396687 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/118 2026-06-16 00:51:37.396710 | Event ID: 69d4f1c0-691c-11f1-9d92-6a0f5f17a546 2026-06-16 00:51:37.400164 | 2026-06-16 00:51:37.400254 | LOOP [emit-job-header : Print node information] 2026-06-16 00:51:37.494679 | localhost | ok: 2026-06-16 00:51:37.494864 | localhost | # Node Information 2026-06-16 00:51:37.494904 | localhost | Inventory Hostname: rockylinux-9 2026-06-16 00:51:37.494935 | localhost | Hostname: np0000180566 2026-06-16 00:51:37.494963 | localhost | Username: zuul 2026-06-16 00:51:37.494995 | localhost | Distro: Rocky 9.7 2026-06-16 00:51:37.495032 | localhost | Provider: yul1 2026-06-16 00:51:37.495061 | localhost | Region: ca-ymq-1 2026-06-16 00:51:37.495087 | localhost | Label: rockylinux-9 2026-06-16 00:51:37.495113 | localhost | Product Name: OpenStack Nova 2026-06-16 00:51:37.495152 | localhost | Interface IP: 199.19.213.20 2026-06-16 00:51:37.514758 | 2026-06-16 00:51:37.514954 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-06-16 00:51:37.980254 | localhost -> localhost | changed 2026-06-16 00:51:37.987822 | 2026-06-16 00:51:37.987945 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-06-16 00:51:39.212978 | localhost -> localhost | changed 2026-06-16 00:51:39.224236 | 2026-06-16 00:51:39.224313 | PLAY [all] 2026-06-16 00:51:39.233805 | 2026-06-16 00:51:39.233877 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-06-16 00:51:39.471173 | rockylinux-9 -> localhost | ok 2026-06-16 00:51:39.481507 | 2026-06-16 00:51:39.481602 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-06-16 00:51:39.514766 | rockylinux-9 | ok 2026-06-16 00:51:39.528828 | rockylinux-9 | included: /var/lib/zuul/builds/e62305848284481e87ed50603bfe7fff/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-06-16 00:51:39.535699 | 2026-06-16 00:51:39.535765 | TASK [add-build-sshkey : Create Temp SSH key] 2026-06-16 00:51:40.884479 | rockylinux-9 -> localhost | Generating public/private rsa key pair. 2026-06-16 00:51:40.884738 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/e62305848284481e87ed50603bfe7fff/work/e62305848284481e87ed50603bfe7fff_id_rsa 2026-06-16 00:51:40.884803 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/e62305848284481e87ed50603bfe7fff/work/e62305848284481e87ed50603bfe7fff_id_rsa.pub 2026-06-16 00:51:40.884855 | rockylinux-9 -> localhost | The key fingerprint is: 2026-06-16 00:51:40.884904 | rockylinux-9 -> localhost | SHA256:+BA0cWEYftizCSD5QDrtVmF9WlAM23QaLMkQxvt6nnU zuul-build-sshkey 2026-06-16 00:51:40.885003 | rockylinux-9 -> localhost | The key's randomart image is: 2026-06-16 00:51:40.885057 | rockylinux-9 -> localhost | +---[RSA 3072]----+ 2026-06-16 00:51:40.885114 | rockylinux-9 -> localhost | | .ooB=B@B.. | 2026-06-16 00:51:40.885163 | rockylinux-9 -> localhost | | oo+.=*X=+ | 2026-06-16 00:51:40.885208 | rockylinux-9 -> localhost | |o .o..*=* | 2026-06-16 00:51:40.885253 | rockylinux-9 -> localhost | | o .o .= + | 2026-06-16 00:51:40.885298 | rockylinux-9 -> localhost | | o .o S | 2026-06-16 00:51:40.885343 | rockylinux-9 -> localhost | | . .o | 2026-06-16 00:51:40.885386 | rockylinux-9 -> localhost | | . o E | 2026-06-16 00:51:40.885435 | rockylinux-9 -> localhost | | . .o . | 2026-06-16 00:51:40.885484 | rockylinux-9 -> localhost | | oo | 2026-06-16 00:51:40.885530 | rockylinux-9 -> localhost | +----[SHA256]-----+ 2026-06-16 00:51:40.885621 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.841045 2026-06-16 00:51:40.891120 | 2026-06-16 00:51:40.891185 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-06-16 00:51:40.923197 | rockylinux-9 | ok 2026-06-16 00:51:40.934676 | rockylinux-9 | included: /var/lib/zuul/builds/e62305848284481e87ed50603bfe7fff/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-06-16 00:51:40.945148 | 2026-06-16 00:51:40.945224 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-06-16 00:51:40.970508 | rockylinux-9 | skipping: Conditional result was False 2026-06-16 00:51:40.980041 | 2026-06-16 00:51:40.980109 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-06-16 00:51:41.460264 | rockylinux-9 | changed 2026-06-16 00:51:41.467421 | 2026-06-16 00:51:41.467549 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-06-16 00:51:41.761980 | rockylinux-9 | ok 2026-06-16 00:51:41.768173 | 2026-06-16 00:51:41.768258 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-06-16 00:51:42.804362 | rockylinux-9 | changed 2026-06-16 00:51:42.811146 | 2026-06-16 00:51:42.811269 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-06-16 00:51:43.786950 | rockylinux-9 | changed 2026-06-16 00:51:43.793065 | 2026-06-16 00:51:43.793154 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-06-16 00:51:43.824305 | rockylinux-9 | skipping: Conditional result was False 2026-06-16 00:51:43.832102 | 2026-06-16 00:51:43.832509 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-06-16 00:51:44.231587 | rockylinux-9 -> localhost | changed 2026-06-16 00:51:44.245328 | 2026-06-16 00:51:44.245419 | TASK [add-build-sshkey : Add back temp key] 2026-06-16 00:51:44.613997 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/e62305848284481e87ed50603bfe7fff/work/e62305848284481e87ed50603bfe7fff_id_rsa (zuul-build-sshkey) 2026-06-16 00:51:44.614174 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.014240 2026-06-16 00:51:44.618724 | 2026-06-16 00:51:44.618786 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-06-16 00:51:45.059674 | rockylinux-9 | ok 2026-06-16 00:51:45.065271 | 2026-06-16 00:51:45.065359 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-06-16 00:51:45.100679 | rockylinux-9 | skipping: Conditional result was False 2026-06-16 00:51:45.115545 | 2026-06-16 00:51:45.115668 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-06-16 00:51:45.607566 | rockylinux-9 | ok 2026-06-16 00:51:45.614872 | 2026-06-16 00:51:45.614959 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-06-16 00:51:46.695091 | rockylinux-9 | Output suppressed because no_log was given 2026-06-16 00:51:46.703426 | 2026-06-16 00:51:46.703511 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-06-16 00:51:46.969521 | rockylinux-9 | ok: "logs" 2026-06-16 00:51:46.969827 | rockylinux-9 | ok: All items complete 2026-06-16 00:51:46.969862 | 2026-06-16 00:51:47.191017 | rockylinux-9 | ok: "artifacts" 2026-06-16 00:51:47.419048 | rockylinux-9 | ok: "docs" 2026-06-16 00:51:47.437101 | 2026-06-16 00:51:47.437232 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-06-16 00:51:47.705687 | rockylinux-9 | changed: "logs" 2026-06-16 00:51:48.031832 | rockylinux-9 | changed: "artifacts" 2026-06-16 00:51:48.205967 | rockylinux-9 | changed: "docs" 2026-06-16 00:51:48.222000 | 2026-06-16 00:51:48.222121 | PLAY RECAP 2026-06-16 00:51:48.222249 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-06-16 00:51:48.222307 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-06-16 00:51:48.222341 | 2026-06-16 00:51:48.376424 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-06-16 00:51:48.378815 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-06-16 00:51:49.010012 | 2026-06-16 00:51:49.010130 | PLAY [all] 2026-06-16 00:51:49.025259 | 2026-06-16 00:51:49.025389 | TASK [setup-uv : Extract archive] 2026-06-16 00:51:51.938084 | rockylinux-9 | changed 2026-06-16 00:51:51.945494 | 2026-06-16 00:51:51.945685 | TASK [setup-uv : Print version] 2026-06-16 00:51:51.968791 | [rockylinux-9] Waiting on logger 2026-06-16 00:51:52.481884 | rockylinux-9 | ok: Runtime: 0:00:00.015165 2026-06-16 00:51:52.494547 | 2026-06-16 00:51:52.494644 | TASK [Install Ansible collection siblings] 2026-06-16 00:51:52.947735 | rockylinux-9 | ok: 2026-06-16 00:51:52.947972 | rockylinux-9 | Skipping ansible.posix: no checked-out sibling 2026-06-16 00:51:52.948013 | rockylinux-9 | Skipping community.general: no checked-out sibling 2026-06-16 00:51:52.950877 | 2026-06-16 00:51:52.951006 | PLAY RECAP 2026-06-16 00:51:52.951098 | rockylinux-9 | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-06-16 00:51:52.951133 | 2026-06-16 00:51:53.086156 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-06-16 00:51:53.121228 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-06-16 00:51:53.806973 | 2026-06-16 00:51:53.840748 | PLAY [all] 2026-06-16 00:51:53.862202 | 2026-06-16 00:51:53.862322 | TASK [Copy inventory file for Zuul] 2026-06-16 00:51:55.260404 | rockylinux-9 | changed 2026-06-16 00:51:55.268088 | 2026-06-16 00:51:55.268224 | TASK [Switch "ansible_host" to private IP] 2026-06-16 00:51:55.765764 | rockylinux-9 | changed: 1 replacements made 2026-06-16 00:51:56.097872 | 2026-06-16 00:51:56.098075 | TASK [Run Molecule scenario] 2026-06-16 00:51:56.124499 | [rockylinux-9] Waiting on logger 2026-06-16 00:52:03.237894 | [rockylinux-9] Waiting on logger 2026-06-16 00:52:13.418280 | [rockylinux-9] Waiting on logger 2026-06-16 00:52:23.622316 | [rockylinux-9] Waiting on logger 2026-06-16 00:52:33.801991 | [rockylinux-9] Waiting on logger 2026-06-16 00:52:42.991064 | [rockylinux-9] Waiting on logger 2026-06-16 00:52:53.190039 | [rockylinux-9] Waiting on logger 2026-06-16 00:53:03.384921 | [rockylinux-9] Waiting on logger 2026-06-16 00:53:13.574234 | [rockylinux-9] Waiting on logger 2026-06-16 00:53:23.750122 | [rockylinux-9] Waiting on logger 2026-06-16 00:53:33.928479 | [rockylinux-9] Waiting on logger 2026-06-16 00:53:43.083275 | [rockylinux-9] Waiting on logger 2026-06-16 00:53:53.253916 | [rockylinux-9] Waiting on logger 2026-06-16 00:54:03.430189 | [rockylinux-9] Waiting on logger 2026-06-16 00:54:13.605814 | [rockylinux-9] Waiting on logger 2026-06-16 00:54:23.782190 | [rockylinux-9] Waiting on logger 2026-06-16 00:54:33.958195 | [rockylinux-9] Waiting on logger 2026-06-16 00:54:43.110251 | [rockylinux-9] Waiting on logger 2026-06-16 00:54:53.285975 | [rockylinux-9] Waiting on logger 2026-06-16 00:55:03.493958 | [rockylinux-9] Waiting on logger 2026-06-16 00:55:13.673856 | [rockylinux-9] Waiting on logger 2026-06-16 00:55:23.845853 | [rockylinux-9] Waiting on logger 2026-06-16 00:55:33.006865 | [rockylinux-9] Waiting on logger 2026-06-16 00:55:43.173913 | [rockylinux-9] Waiting on logger 2026-06-16 00:55:53.349917 | [rockylinux-9] Waiting on logger 2026-06-16 00:56:03.529946 | [rockylinux-9] Waiting on logger 2026-06-16 00:56:13.702165 | [rockylinux-9] Waiting on logger 2026-06-16 00:56:23.878009 | [rockylinux-9] Waiting on logger 2026-06-16 00:56:33.035885 | [rockylinux-9] Waiting on logger 2026-06-16 00:56:43.206059 | [rockylinux-9] Waiting on logger 2026-06-16 00:56:53.381960 | [rockylinux-9] Waiting on logger 2026-06-16 00:57:03.593961 | [rockylinux-9] Waiting on logger 2026-06-16 00:57:13.766301 | [rockylinux-9] Waiting on logger 2026-06-16 00:57:16.108894 | rockylinux-9 | ok: Runtime: 0:05:18.586057 2026-06-16 00:57:16.116770 | 2026-06-16 00:57:16.116850 | PLAY RECAP 2026-06-16 00:57:16.116907 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-06-16 00:57:16.116937 | 2026-06-16 00:57:16.257274 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-06-16 00:57:16.260257 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-06-16 00:57:16.906165 | 2026-06-16 00:57:16.906301 | PLAY [all] 2026-06-16 00:57:16.916871 | 2026-06-16 00:57:16.916995 | TASK [fetch-output : Set log path for multiple nodes] 2026-06-16 00:57:16.961931 | rockylinux-9 | skipping: Conditional result was False 2026-06-16 00:57:16.970575 | 2026-06-16 00:57:16.970688 | TASK [fetch-output : Set log path for single node] 2026-06-16 00:57:17.014051 | rockylinux-9 | ok 2026-06-16 00:57:17.019661 | 2026-06-16 00:57:17.019769 | LOOP [fetch-output : Ensure local output dirs] 2026-06-16 00:57:17.449522 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/e62305848284481e87ed50603bfe7fff/work/logs" 2026-06-16 00:57:17.682635 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/e62305848284481e87ed50603bfe7fff/work/artifacts" 2026-06-16 00:57:17.907261 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/e62305848284481e87ed50603bfe7fff/work/docs" 2026-06-16 00:57:17.924081 | 2026-06-16 00:57:17.924303 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-06-16 00:57:18.652603 | rockylinux-9 | changed: .d..t...... ./ 2026-06-16 00:57:18.652834 | rockylinux-9 | changed: All items complete 2026-06-16 00:57:18.652873 | 2026-06-16 00:57:19.187526 | rockylinux-9 | changed: .d..t...... ./ 2026-06-16 00:57:19.724187 | rockylinux-9 | changed: .d..t...... ./ 2026-06-16 00:57:19.747870 | 2026-06-16 00:57:19.748048 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-06-16 00:57:20.195097 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.007466 2026-06-16 00:57:20.416160 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.007533 2026-06-16 00:57:20.437745 | 2026-06-16 00:57:20.437870 | PLAY [all] 2026-06-16 00:57:20.445330 | 2026-06-16 00:57:20.445419 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-06-16 00:57:20.925143 | rockylinux-9 | changed 2026-06-16 00:57:20.930950 | 2026-06-16 00:57:20.931005 | PLAY RECAP 2026-06-16 00:57:20.931077 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-06-16 00:57:20.931122 | 2026-06-16 00:57:21.085568 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-06-16 00:57:21.087306 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-06-16 00:57:21.778759 | 2026-06-16 00:57:21.778925 | PLAY [localhost] 2026-06-16 00:57:21.796888 | 2026-06-16 00:57:21.797295 | TASK [Generate Zuul manifest] 2026-06-16 00:57:21.825852 | localhost | ok 2026-06-16 00:57:21.849813 | 2026-06-16 00:57:21.850093 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-06-16 00:57:22.264859 | localhost | changed 2026-06-16 00:57:22.277101 | 2026-06-16 00:57:22.277189 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-06-16 00:57:22.321841 | localhost | ok 2026-06-16 00:57:22.328698 | 2026-06-16 00:57:22.328763 | TASK [Upload logs] 2026-06-16 00:57:22.350061 | localhost | ok 2026-06-16 00:57:22.417857 | 2026-06-16 00:57:22.417980 | TASK [Set zuul-log-path fact] 2026-06-16 00:57:22.438512 | localhost | ok 2026-06-16 00:57:22.455134 | 2026-06-16 00:57:22.455237 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-16 00:57:22.485067 | localhost | ok 2026-06-16 00:57:22.492058 | 2026-06-16 00:57:22.492125 | TASK [upload-logs : Create log directories] 2026-06-16 00:57:23.016218 | localhost | changed 2026-06-16 00:57:23.022999 | 2026-06-16 00:57:23.023091 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-06-16 00:57:23.480729 | localhost -> localhost | ok: Runtime: 0:00:00.006004 2026-06-16 00:57:23.487977 | 2026-06-16 00:57:23.488098 | TASK [upload-logs : Upload logs to log server] 2026-06-16 00:57:24.121313 | localhost | Output suppressed because no_log was given 2026-06-16 00:57:24.126166 | 2026-06-16 00:57:24.126230 | LOOP [upload-logs : Compress console log and json output] 2026-06-16 00:57:24.171443 | localhost | skipping: Conditional result was False 2026-06-16 00:57:24.178412 | localhost | skipping: Conditional result was False 2026-06-16 00:57:24.192618 | 2026-06-16 00:57:24.192777 | LOOP [upload-logs : Upload compressed console log and json output] 2026-06-16 00:57:24.236700 | localhost | skipping: Conditional result was False 2026-06-16 00:57:24.237196 | 2026-06-16 00:57:24.240444 | localhost | skipping: Conditional result was False 2026-06-16 00:57:24.258385 | 2026-06-16 00:57:24.258537 | LOOP [upload-logs : Upload console log and json output]