2026-04-10 00:53:59.741378 | Job console starting 2026-04-10 00:53:59.755091 | Updating git repos 2026-04-10 00:53:59.807312 | Cloning repos into workspace 2026-04-10 00:53:59.862050 | Restoring repo states 2026-04-10 00:53:59.868097 | Merging changes 2026-04-10 00:54:00.238944 | Checking out repos 2026-04-10 00:54:00.298635 | Preparing playbooks 2026-04-10 00:54:01.893590 | Running Ansible setup 2026-04-10 00:54:05.947844 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-10 00:54:06.676953 | 2026-04-10 00:54:06.698016 | PLAY [localhost] 2026-04-10 00:54:06.709497 | 2026-04-10 00:54:06.709610 | TASK [Gathering Facts] 2026-04-10 00:54:07.612231 | localhost | ok 2026-04-10 00:54:07.618940 | 2026-04-10 00:54:07.619022 | TASK [Setup log path fact] 2026-04-10 00:54:07.647461 | localhost | ok 2026-04-10 00:54:07.662170 | 2026-04-10 00:54:07.662248 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-10 00:54:07.693977 | localhost | ok 2026-04-10 00:54:07.701041 | 2026-04-10 00:54:07.701114 | TASK [emit-job-header : Print job information] 2026-04-10 00:54:07.741323 | # Job Information 2026-04-10 00:54:07.741545 | Ansible Version: 2.16.16 2026-04-10 00:54:07.741596 | Job: ansible-collection-containers-molecule-containerd-rockylinux-9 2026-04-10 00:54:07.741631 | Pipeline: check 2026-04-10 00:54:07.741663 | Executor: 0a8996d2b663 2026-04-10 00:54:07.741693 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/105 2026-04-10 00:54:07.741728 | Event ID: a2492050-3477-11f1-9e4c-c329e3910f5b 2026-04-10 00:54:07.746800 | 2026-04-10 00:54:07.747055 | LOOP [emit-job-header : Print node information] 2026-04-10 00:54:08.119085 | localhost | ok: 2026-04-10 00:54:08.119326 | localhost | # Node Information 2026-04-10 00:54:08.119357 | localhost | Inventory Hostname: rockylinux-9 2026-04-10 00:54:08.119379 | localhost | Hostname: np0000164777 2026-04-10 00:54:08.119400 | localhost | Username: zuul 2026-04-10 00:54:08.119422 | localhost | Distro: Rocky 9.7 2026-04-10 00:54:08.119442 | localhost | Provider: yul1 2026-04-10 00:54:08.119461 | localhost | Region: ca-ymq-1 2026-04-10 00:54:08.119480 | localhost | Label: rockylinux-9 2026-04-10 00:54:08.119499 | localhost | Product Name: OpenStack Nova 2026-04-10 00:54:08.119517 | localhost | Interface IP: 199.204.45.23 2026-04-10 00:54:08.135432 | 2026-04-10 00:54:08.135587 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-04-10 00:54:08.710108 | localhost -> localhost | changed 2026-04-10 00:54:08.716086 | 2026-04-10 00:54:08.716178 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-04-10 00:54:09.961321 | localhost -> localhost | changed 2026-04-10 00:54:09.969155 | 2026-04-10 00:54:10.172225 | PLAY [all] 2026-04-10 00:54:10.186848 | 2026-04-10 00:54:10.186984 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-04-10 00:54:10.476405 | rockylinux-9 -> localhost | ok 2026-04-10 00:54:10.482780 | 2026-04-10 00:54:10.482846 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-04-10 00:54:10.505123 | rockylinux-9 | ok 2026-04-10 00:54:10.522812 | rockylinux-9 | included: /var/lib/zuul/builds/f110d19513334944a70a18e468e0fbfa/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-04-10 00:54:10.535790 | 2026-04-10 00:54:10.535912 | TASK [add-build-sshkey : Create Temp SSH key] 2026-04-10 00:54:12.385019 | rockylinux-9 -> localhost | Generating public/private rsa key pair. 2026-04-10 00:54:12.385262 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/f110d19513334944a70a18e468e0fbfa/work/f110d19513334944a70a18e468e0fbfa_id_rsa 2026-04-10 00:54:12.385309 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/f110d19513334944a70a18e468e0fbfa/work/f110d19513334944a70a18e468e0fbfa_id_rsa.pub 2026-04-10 00:54:12.385434 | rockylinux-9 -> localhost | The key fingerprint is: 2026-04-10 00:54:12.385653 | rockylinux-9 -> localhost | SHA256:zsYIFHX7NpIhH06Th71GUOoCFd9PMM6DDS7V8zOmKM8 zuul-build-sshkey 2026-04-10 00:54:12.385778 | rockylinux-9 -> localhost | The key's randomart image is: 2026-04-10 00:54:12.385822 | rockylinux-9 -> localhost | +---[RSA 3072]----+ 2026-04-10 00:54:12.385860 | rockylinux-9 -> localhost | | ..+.=o+ | 2026-04-10 00:54:12.385892 | rockylinux-9 -> localhost | | o = #oo | 2026-04-10 00:54:12.385921 | rockylinux-9 -> localhost | | o o & Xo. | 2026-04-10 00:54:12.385950 | rockylinux-9 -> localhost | | . . B O == | 2026-04-10 00:54:12.386086 | rockylinux-9 -> localhost | | . . S.*o.o | 2026-04-10 00:54:12.386140 | rockylinux-9 -> localhost | | ..*.+.. | 2026-04-10 00:54:12.386173 | rockylinux-9 -> localhost | | .+= | 2026-04-10 00:54:12.386204 | rockylinux-9 -> localhost | | .E | 2026-04-10 00:54:12.386238 | rockylinux-9 -> localhost | | | 2026-04-10 00:54:12.386268 | rockylinux-9 -> localhost | +----[SHA256]-----+ 2026-04-10 00:54:12.386344 | rockylinux-9 -> localhost | ok: Runtime: 0:00:01.044218 2026-04-10 00:54:12.393820 | 2026-04-10 00:54:12.393901 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-04-10 00:54:12.426568 | rockylinux-9 | ok 2026-04-10 00:54:12.438732 | rockylinux-9 | included: /var/lib/zuul/builds/f110d19513334944a70a18e468e0fbfa/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-04-10 00:54:12.449664 | 2026-04-10 00:54:12.449738 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-04-10 00:54:12.474491 | rockylinux-9 | skipping: Conditional result was False 2026-04-10 00:54:12.483901 | 2026-04-10 00:54:12.484194 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-04-10 00:54:13.048672 | rockylinux-9 | changed 2026-04-10 00:54:13.054034 | 2026-04-10 00:54:13.054103 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-04-10 00:54:13.301674 | rockylinux-9 | ok 2026-04-10 00:54:13.316050 | 2026-04-10 00:54:13.316242 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-04-10 00:54:14.359257 | rockylinux-9 | changed 2026-04-10 00:54:14.393072 | 2026-04-10 00:54:14.393241 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-04-10 00:54:15.256270 | rockylinux-9 | changed 2026-04-10 00:54:15.263436 | 2026-04-10 00:54:15.263569 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-04-10 00:54:15.292715 | rockylinux-9 | skipping: Conditional result was False 2026-04-10 00:54:15.298967 | 2026-04-10 00:54:15.299079 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-04-10 00:54:15.882405 | rockylinux-9 -> localhost | changed 2026-04-10 00:54:15.891415 | 2026-04-10 00:54:15.891487 | TASK [add-build-sshkey : Add back temp key] 2026-04-10 00:54:16.236040 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/f110d19513334944a70a18e468e0fbfa/work/f110d19513334944a70a18e468e0fbfa_id_rsa (zuul-build-sshkey) 2026-04-10 00:54:16.236263 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.019053 2026-04-10 00:54:16.245071 | 2026-04-10 00:54:16.245170 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-04-10 00:54:16.650855 | rockylinux-9 | ok 2026-04-10 00:54:16.655350 | 2026-04-10 00:54:16.655414 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-04-10 00:54:16.680423 | rockylinux-9 | skipping: Conditional result was False 2026-04-10 00:54:16.689808 | 2026-04-10 00:54:16.689888 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-04-10 00:54:17.134927 | rockylinux-9 | ok 2026-04-10 00:54:17.143011 | 2026-04-10 00:54:17.143175 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-04-10 00:54:18.064586 | rockylinux-9 | Output suppressed because no_log was given 2026-04-10 00:54:18.074118 | 2026-04-10 00:54:18.074414 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-04-10 00:54:18.343832 | rockylinux-9 | ok: "logs" 2026-04-10 00:54:18.344849 | rockylinux-9 | ok: All items complete 2026-04-10 00:54:18.344892 | 2026-04-10 00:54:18.547852 | rockylinux-9 | ok: "artifacts" 2026-04-10 00:54:18.760966 | rockylinux-9 | ok: "docs" 2026-04-10 00:54:18.771440 | 2026-04-10 00:54:18.771586 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-04-10 00:54:19.025840 | rockylinux-9 | changed: "logs" 2026-04-10 00:54:19.250276 | rockylinux-9 | changed: "artifacts" 2026-04-10 00:54:19.484259 | rockylinux-9 | changed: "docs" 2026-04-10 00:54:19.492814 | 2026-04-10 00:54:19.492942 | PLAY RECAP 2026-04-10 00:54:19.492998 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-10 00:54:19.493043 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-04-10 00:54:19.493067 | 2026-04-10 00:54:19.663668 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-10 00:54:19.664772 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-10 00:54:20.409275 | 2026-04-10 00:54:20.528868 | PLAY [all] 2026-04-10 00:54:20.546063 | 2026-04-10 00:54:20.546212 | TASK [setup-uv : Extract archive] 2026-04-10 00:54:23.390894 | rockylinux-9 | changed 2026-04-10 00:54:23.396672 | 2026-04-10 00:54:23.396756 | TASK [setup-uv : Print version] 2026-04-10 00:54:23.436697 | [rockylinux-9] Waiting on logger 2026-04-10 00:54:23.952496 | rockylinux-9 | ok: Runtime: 0:00:00.009385 2026-04-10 00:54:23.959396 | 2026-04-10 00:54:23.959467 | PLAY RECAP 2026-04-10 00:54:23.959512 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-10 00:54:23.959537 | 2026-04-10 00:54:24.133980 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-10 00:54:24.135053 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-10 00:54:24.772890 | 2026-04-10 00:54:24.773064 | PLAY [all] 2026-04-10 00:54:24.784126 | 2026-04-10 00:54:24.784237 | TASK [Copy inventory file for Zuul] 2026-04-10 00:54:26.164321 | rockylinux-9 | changed 2026-04-10 00:54:26.172092 | 2026-04-10 00:54:26.172193 | TASK [Switch "ansible_host" to private IP] 2026-04-10 00:54:26.529615 | rockylinux-9 | changed: 1 replacements made 2026-04-10 00:54:26.536170 | 2026-04-10 00:54:26.536291 | TASK [Run Molecule scenario] 2026-04-10 00:54:26.575270 | [rockylinux-9] Waiting on logger 2026-04-10 00:54:34.732441 | [rockylinux-9] Waiting on logger 2026-04-10 00:54:44.902217 | [rockylinux-9] Waiting on logger 2026-04-10 00:54:55.178172 | [rockylinux-9] Waiting on logger 2026-04-10 00:55:05.354118 | [rockylinux-9] Waiting on logger 2026-04-10 00:55:14.507212 | [rockylinux-9] Waiting on logger 2026-04-10 00:55:24.678034 | [rockylinux-9] Waiting on logger 2026-04-10 00:55:34.854149 | [rockylinux-9] Waiting on logger 2026-04-10 00:55:45.029979 | [rockylinux-9] Waiting on logger 2026-04-10 00:55:55.206140 | [rockylinux-9] Waiting on logger 2026-04-10 00:56:05.382046 | [rockylinux-9] Waiting on logger 2026-04-10 00:56:14.539657 | [rockylinux-9] Waiting on logger 2026-04-10 00:56:24.710016 | [rockylinux-9] Waiting on logger 2026-04-10 00:56:34.885933 | [rockylinux-9] Waiting on logger 2026-04-10 00:56:45.062238 | [rockylinux-9] Waiting on logger 2026-04-10 00:56:55.237980 | [rockylinux-9] Waiting on logger 2026-04-10 00:57:05.414053 | [rockylinux-9] Waiting on logger 2026-04-10 00:57:14.566045 | [rockylinux-9] Waiting on logger 2026-04-10 00:57:24.742069 | [rockylinux-9] Waiting on logger 2026-04-10 00:57:34.922294 | [rockylinux-9] Waiting on logger 2026-04-10 00:57:45.094089 | [rockylinux-9] Waiting on logger 2026-04-10 00:57:55.269994 | [rockylinux-9] Waiting on logger 2026-04-10 00:58:05.446027 | [rockylinux-9] Waiting on logger 2026-04-10 00:58:14.598244 | [rockylinux-9] Waiting on logger 2026-04-10 00:58:24.774068 | [rockylinux-9] Waiting on logger 2026-04-10 00:58:34.949856 | [rockylinux-9] Waiting on logger 2026-04-10 00:58:45.134129 | rockylinux-9 | ok: Runtime: 0:04:17.878334 2026-04-10 00:58:45.139464 | 2026-04-10 00:58:45.139513 | PLAY RECAP 2026-04-10 00:58:45.139557 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-10 00:58:45.139582 | 2026-04-10 00:58:45.307627 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-10 00:58:45.310565 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-10 00:58:45.910220 | 2026-04-10 00:58:45.910407 | PLAY [all] 2026-04-10 00:58:45.922004 | 2026-04-10 00:58:45.922166 | TASK [fetch-output : Set log path for multiple nodes] 2026-04-10 00:58:45.978425 | rockylinux-9 | skipping: Conditional result was False 2026-04-10 00:58:45.987345 | 2026-04-10 00:58:45.987576 | TASK [fetch-output : Set log path for single node] 2026-04-10 00:58:46.031764 | rockylinux-9 | ok 2026-04-10 00:58:46.038093 | 2026-04-10 00:58:46.038179 | LOOP [fetch-output : Ensure local output dirs] 2026-04-10 00:58:46.470084 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/f110d19513334944a70a18e468e0fbfa/work/logs" 2026-04-10 00:58:46.700442 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/f110d19513334944a70a18e468e0fbfa/work/artifacts" 2026-04-10 00:58:46.911647 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/f110d19513334944a70a18e468e0fbfa/work/docs" 2026-04-10 00:58:46.930180 | 2026-04-10 00:58:46.930779 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-04-10 00:58:47.754595 | rockylinux-9 | changed: .d..t...... ./ 2026-04-10 00:58:47.795212 | rockylinux-9 | changed: All items complete 2026-04-10 00:58:47.795394 | 2026-04-10 00:58:48.346241 | rockylinux-9 | changed: .d..t...... ./ 2026-04-10 00:58:48.922176 | rockylinux-9 | changed: .d..t...... ./ 2026-04-10 00:58:48.938733 | 2026-04-10 00:58:48.938886 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-04-10 00:58:50.276371 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.007143 2026-04-10 00:58:50.692670 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.008210 2026-04-10 00:58:50.704554 | 2026-04-10 00:58:50.704691 | PLAY [all] 2026-04-10 00:58:50.712098 | 2026-04-10 00:58:50.712195 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-04-10 00:58:51.184852 | rockylinux-9 | changed 2026-04-10 00:58:51.285143 | 2026-04-10 00:58:51.285253 | PLAY RECAP 2026-04-10 00:58:51.285303 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-04-10 00:58:51.285352 | 2026-04-10 00:58:51.491958 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-10 00:58:51.493903 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-04-10 00:58:52.095283 | 2026-04-10 00:58:52.095587 | PLAY [localhost] 2026-04-10 00:58:52.106580 | 2026-04-10 00:58:52.106663 | TASK [Generate Zuul manifest] 2026-04-10 00:58:52.124793 | localhost | ok 2026-04-10 00:58:52.141058 | 2026-04-10 00:58:52.141136 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-04-10 00:58:52.598636 | localhost | changed 2026-04-10 00:58:52.631874 | 2026-04-10 00:58:52.632073 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-04-10 00:58:52.747728 | localhost | ok 2026-04-10 00:58:52.760537 | 2026-04-10 00:58:52.760714 | TASK [Upload logs] 2026-04-10 00:58:52.781516 | localhost | ok 2026-04-10 00:58:52.855168 | 2026-04-10 00:58:52.855344 | TASK [Set zuul-log-path fact] 2026-04-10 00:58:52.876741 | localhost | ok 2026-04-10 00:58:52.890656 | 2026-04-10 00:58:52.890823 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-10 00:58:52.922038 | localhost | ok 2026-04-10 00:58:52.931077 | 2026-04-10 00:58:52.931178 | TASK [upload-logs : Create log directories] 2026-04-10 00:58:53.740829 | localhost | changed 2026-04-10 00:58:53.748153 | 2026-04-10 00:58:53.748236 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-04-10 00:58:54.431477 | localhost -> localhost | ok: Runtime: 0:00:00.004742 2026-04-10 00:58:54.436935 | 2026-04-10 00:58:54.437015 | TASK [upload-logs : Upload logs to log server] 2026-04-10 00:58:55.032154 | localhost | Output suppressed because no_log was given 2026-04-10 00:58:55.036012 | 2026-04-10 00:58:55.036075 | LOOP [upload-logs : Compress console log and json output] 2026-04-10 00:58:55.086829 | localhost | skipping: Conditional result was False 2026-04-10 00:58:55.094400 | localhost | skipping: Conditional result was False 2026-04-10 00:58:55.106073 | 2026-04-10 00:58:55.106212 | LOOP [upload-logs : Upload compressed console log and json output] 2026-04-10 00:58:55.147351 | localhost | skipping: Conditional result was False 2026-04-10 00:58:55.147642 | 2026-04-10 00:58:55.151936 | localhost | skipping: Conditional result was False 2026-04-10 00:58:55.165757 | 2026-04-10 00:58:55.165905 | LOOP [upload-logs : Upload console log and json output]