2026-03-14 00:45:35.183497 | Job console starting 2026-03-14 00:45:35.190812 | Updating git repos 2026-03-14 00:45:35.216984 | Cloning repos into workspace 2026-03-14 00:45:35.267100 | Restoring repo states 2026-03-14 00:45:35.274719 | Merging changes 2026-03-14 00:45:35.546115 | Checking out repos 2026-03-14 00:45:35.571805 | Preparing playbooks 2026-03-14 00:45:36.742687 | Running Ansible setup 2026-03-14 00:45:40.347339 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-03-14 00:45:40.936879 | 2026-03-14 00:45:40.937110 | PLAY [localhost] 2026-03-14 00:45:40.946857 | 2026-03-14 00:45:40.946932 | TASK [Gathering Facts] 2026-03-14 00:45:41.803957 | localhost | ok 2026-03-14 00:45:41.814113 | 2026-03-14 00:45:41.814225 | TASK [Setup log path fact] 2026-03-14 00:45:41.837576 | localhost | ok 2026-03-14 00:45:41.851170 | 2026-03-14 00:45:41.851240 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-14 00:45:41.882063 | localhost | ok 2026-03-14 00:45:41.891123 | 2026-03-14 00:45:41.891191 | TASK [emit-job-header : Print job information] 2026-03-14 00:45:41.934531 | # Job Information 2026-03-14 00:45:41.935068 | Ansible Version: 2.16.16 2026-03-14 00:45:41.935148 | Job: ansible-collection-containers-molecule-docker-rockylinux-9 2026-03-14 00:45:41.935189 | Pipeline: check 2026-03-14 00:45:41.935246 | Executor: 0a8996d2b663 2026-03-14 00:45:41.935276 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/100 2026-03-14 00:45:41.935336 | Event ID: ef854cc0-1f3e-11f1-85d0-e7c2c9645e81 2026-03-14 00:45:41.940010 | 2026-03-14 00:45:41.940106 | LOOP [emit-job-header : Print node information] 2026-03-14 00:45:42.026299 | localhost | ok: 2026-03-14 00:45:42.026580 | localhost | # Node Information 2026-03-14 00:45:42.026629 | localhost | Inventory Hostname: rockylinux-9 2026-03-14 00:45:42.026667 | localhost | Hostname: np0000158743 2026-03-14 00:45:42.026703 | localhost | Username: zuul 2026-03-14 00:45:42.026741 | localhost | Distro: Rocky 9.7 2026-03-14 00:45:42.026776 | localhost | Provider: yul1 2026-03-14 00:45:42.026809 | localhost | Region: ca-ymq-1 2026-03-14 00:45:42.026843 | localhost | Label: rockylinux-9 2026-03-14 00:45:42.026875 | localhost | Product Name: OpenStack Nova 2026-03-14 00:45:42.026908 | localhost | Interface IP: 162.253.55.110 2026-03-14 00:45:42.041051 | 2026-03-14 00:45:42.041286 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-03-14 00:45:42.419059 | localhost -> localhost | changed 2026-03-14 00:45:42.424394 | 2026-03-14 00:45:42.424463 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-03-14 00:45:43.216435 | localhost -> localhost | changed 2026-03-14 00:45:43.227281 | 2026-03-14 00:45:43.227343 | PLAY [all] 2026-03-14 00:45:43.235174 | 2026-03-14 00:45:43.235239 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-03-14 00:45:43.451175 | rockylinux-9 -> localhost | ok 2026-03-14 00:45:43.457125 | 2026-03-14 00:45:43.457195 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-03-14 00:45:43.485074 | rockylinux-9 | ok 2026-03-14 00:45:43.499840 | rockylinux-9 | included: /var/lib/zuul/builds/9eef5a75e1b4412cba56193bb13da044/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-03-14 00:45:43.505084 | 2026-03-14 00:45:43.505146 | TASK [add-build-sshkey : Create Temp SSH key] 2026-03-14 00:45:44.661762 | rockylinux-9 -> localhost | Generating public/private rsa key pair. 2026-03-14 00:45:44.662018 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/9eef5a75e1b4412cba56193bb13da044/work/9eef5a75e1b4412cba56193bb13da044_id_rsa 2026-03-14 00:45:44.662087 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/9eef5a75e1b4412cba56193bb13da044/work/9eef5a75e1b4412cba56193bb13da044_id_rsa.pub 2026-03-14 00:45:44.662140 | rockylinux-9 -> localhost | The key fingerprint is: 2026-03-14 00:45:44.662188 | rockylinux-9 -> localhost | SHA256:tq/5A0Mfp/rWfYfWG18V+3ALS2fogtqz1nJRc+6jw5I zuul-build-sshkey 2026-03-14 00:45:44.662332 | rockylinux-9 -> localhost | The key's randomart image is: 2026-03-14 00:45:44.662420 | rockylinux-9 -> localhost | +---[RSA 3072]----+ 2026-03-14 00:45:44.662486 | rockylinux-9 -> localhost | | | 2026-03-14 00:45:44.662536 | rockylinux-9 -> localhost | | | 2026-03-14 00:45:44.662580 | rockylinux-9 -> localhost | | . | 2026-03-14 00:45:44.662624 | rockylinux-9 -> localhost | | . . .o..o| 2026-03-14 00:45:44.662668 | rockylinux-9 -> localhost | | .S. +.+=+o| 2026-03-14 00:45:44.662710 | rockylinux-9 -> localhost | | .o.+.o =++| 2026-03-14 00:45:44.662752 | rockylinux-9 -> localhost | | .=.o++.=o| 2026-03-14 00:45:44.662795 | rockylinux-9 -> localhost | | +*oEoo+oB| 2026-03-14 00:45:44.662843 | rockylinux-9 -> localhost | | .+BO..ooo=| 2026-03-14 00:45:44.662886 | rockylinux-9 -> localhost | +----[SHA256]-----+ 2026-03-14 00:45:44.662977 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.771311 2026-03-14 00:45:44.668251 | 2026-03-14 00:45:44.668310 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-03-14 00:45:44.699723 | rockylinux-9 | ok 2026-03-14 00:45:44.709813 | rockylinux-9 | included: /var/lib/zuul/builds/9eef5a75e1b4412cba56193bb13da044/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-03-14 00:45:44.717742 | 2026-03-14 00:45:44.717800 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-03-14 00:45:44.741278 | rockylinux-9 | skipping: Conditional result was False 2026-03-14 00:45:44.750072 | 2026-03-14 00:45:44.750175 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-03-14 00:45:45.198169 | rockylinux-9 | changed 2026-03-14 00:45:45.202578 | 2026-03-14 00:45:45.202640 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-03-14 00:45:45.446549 | rockylinux-9 | ok 2026-03-14 00:45:45.453301 | 2026-03-14 00:45:45.453393 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-03-14 00:45:46.370109 | rockylinux-9 | changed 2026-03-14 00:45:46.374609 | 2026-03-14 00:45:46.374672 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-03-14 00:45:47.279632 | rockylinux-9 | changed 2026-03-14 00:45:47.287417 | 2026-03-14 00:45:47.287508 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-03-14 00:45:47.313506 | rockylinux-9 | skipping: Conditional result was False 2026-03-14 00:45:47.322070 | 2026-03-14 00:45:47.322149 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-03-14 00:45:47.678270 | rockylinux-9 -> localhost | changed 2026-03-14 00:45:47.691302 | 2026-03-14 00:45:47.691449 | TASK [add-build-sshkey : Add back temp key] 2026-03-14 00:45:48.018585 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/9eef5a75e1b4412cba56193bb13da044/work/9eef5a75e1b4412cba56193bb13da044_id_rsa (zuul-build-sshkey) 2026-03-14 00:45:48.018817 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.010668 2026-03-14 00:45:48.027204 | 2026-03-14 00:45:48.027310 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-03-14 00:45:48.419392 | rockylinux-9 | ok 2026-03-14 00:45:48.426194 | 2026-03-14 00:45:48.426262 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-03-14 00:45:48.451961 | rockylinux-9 | skipping: Conditional result was False 2026-03-14 00:45:48.471329 | 2026-03-14 00:45:48.471578 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-03-14 00:45:48.892285 | rockylinux-9 | ok 2026-03-14 00:45:48.898862 | 2026-03-14 00:45:48.898925 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-03-14 00:45:49.819915 | rockylinux-9 | Output suppressed because no_log was given 2026-03-14 00:45:49.831290 | 2026-03-14 00:45:49.831380 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-03-14 00:45:50.091502 | rockylinux-9 | ok: "logs" 2026-03-14 00:45:50.091814 | rockylinux-9 | ok: All items complete 2026-03-14 00:45:50.091854 | 2026-03-14 00:45:50.323813 | rockylinux-9 | ok: "artifacts" 2026-03-14 00:45:50.552831 | rockylinux-9 | ok: "docs" 2026-03-14 00:45:50.573300 | 2026-03-14 00:45:50.573480 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-03-14 00:45:50.829196 | rockylinux-9 | changed: "logs" 2026-03-14 00:45:51.053501 | rockylinux-9 | changed: "artifacts" 2026-03-14 00:45:51.285464 | rockylinux-9 | changed: "docs" 2026-03-14 00:45:51.301108 | 2026-03-14 00:45:51.301208 | PLAY RECAP 2026-03-14 00:45:51.301254 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-14 00:45:51.301285 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-03-14 00:45:51.301307 | 2026-03-14 00:45:51.445586 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-03-14 00:45:51.447266 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-03-14 00:45:52.023816 | 2026-03-14 00:45:52.023980 | PLAY [all] 2026-03-14 00:45:52.035489 | 2026-03-14 00:45:52.035568 | TASK [setup-uv : Extract archive] 2026-03-14 00:45:54.937642 | rockylinux-9 | changed 2026-03-14 00:45:54.945715 | 2026-03-14 00:45:54.946842 | TASK [setup-uv : Print version] 2026-03-14 00:45:54.971446 | [rockylinux-9] Waiting on logger 2026-03-14 00:45:55.486133 | rockylinux-9 | ok: Runtime: 0:00:00.015629 2026-03-14 00:45:55.492288 | 2026-03-14 00:45:55.492342 | PLAY RECAP 2026-03-14 00:45:55.492391 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-14 00:45:55.492416 | 2026-03-14 00:45:55.627024 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-03-14 00:45:55.631586 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-03-14 00:45:56.191302 | 2026-03-14 00:45:56.191424 | PLAY [all] 2026-03-14 00:45:56.201587 | 2026-03-14 00:45:56.201662 | TASK [Copy inventory file for Zuul] 2026-03-14 00:45:57.535007 | rockylinux-9 | changed 2026-03-14 00:45:57.542641 | 2026-03-14 00:45:57.542742 | TASK [Switch "ansible_host" to private IP] 2026-03-14 00:45:57.897943 | rockylinux-9 | changed: 1 replacements made 2026-03-14 00:45:57.904260 | 2026-03-14 00:45:57.904343 | TASK [Run Molecule scenario] 2026-03-14 00:45:57.925340 | [rockylinux-9] Waiting on logger 2026-03-14 00:46:06.086199 | [rockylinux-9] Waiting on logger 2026-03-14 00:46:16.265969 | [rockylinux-9] Waiting on logger 2026-03-14 00:46:26.441864 | [rockylinux-9] Waiting on logger 2026-03-14 00:46:36.618402 | [rockylinux-9] Waiting on logger 2026-03-14 00:46:46.794033 | [rockylinux-9] Waiting on logger 2026-03-14 00:46:56.966026 | [rockylinux-9] Waiting on logger 2026-03-14 00:47:06.117909 | [rockylinux-9] Waiting on logger 2026-03-14 00:47:16.293774 | [rockylinux-9] Waiting on logger 2026-03-14 00:47:26.470265 | [rockylinux-9] Waiting on logger 2026-03-14 00:47:36.645959 | [rockylinux-9] Waiting on logger 2026-03-14 00:47:46.822064 | [rockylinux-9] Waiting on logger 2026-03-14 00:47:55.982823 | [rockylinux-9] Waiting on logger 2026-03-14 00:47:58.225592 | rockylinux-9 | ok: Runtime: 0:01:58.967769 2026-03-14 00:47:58.418103 | 2026-03-14 00:47:58.418180 | PLAY RECAP 2026-03-14 00:47:58.418261 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-14 00:47:58.418330 | 2026-03-14 00:47:58.521237 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-03-14 00:47:58.522462 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-03-14 00:47:59.101467 | 2026-03-14 00:47:59.101592 | PLAY [all] 2026-03-14 00:47:59.111940 | 2026-03-14 00:47:59.298566 | TASK [fetch-output : Set log path for multiple nodes] 2026-03-14 00:47:59.347665 | rockylinux-9 | skipping: Conditional result was False 2026-03-14 00:47:59.356467 | 2026-03-14 00:47:59.356556 | TASK [fetch-output : Set log path for single node] 2026-03-14 00:47:59.402912 | rockylinux-9 | ok 2026-03-14 00:47:59.407165 | 2026-03-14 00:47:59.407231 | LOOP [fetch-output : Ensure local output dirs] 2026-03-14 00:48:00.277224 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/9eef5a75e1b4412cba56193bb13da044/work/logs" 2026-03-14 00:48:00.930620 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/9eef5a75e1b4412cba56193bb13da044/work/artifacts" 2026-03-14 00:48:01.124306 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/9eef5a75e1b4412cba56193bb13da044/work/docs" 2026-03-14 00:48:01.136946 | 2026-03-14 00:48:01.137080 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-03-14 00:48:01.814688 | rockylinux-9 | changed: .d..t...... ./ 2026-03-14 00:48:01.815020 | rockylinux-9 | changed: All items complete 2026-03-14 00:48:01.815092 | 2026-03-14 00:48:02.329824 | rockylinux-9 | changed: .d..t...... ./ 2026-03-14 00:48:02.841969 | rockylinux-9 | changed: .d..t...... ./ 2026-03-14 00:48:02.927361 | 2026-03-14 00:48:02.927520 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-03-14 00:48:03.728278 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.008401 2026-03-14 00:48:04.032623 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.092634 2026-03-14 00:48:04.044759 | 2026-03-14 00:48:04.044884 | PLAY [all] 2026-03-14 00:48:04.051072 | 2026-03-14 00:48:04.051134 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-03-14 00:48:04.495981 | rockylinux-9 | changed 2026-03-14 00:48:04.507235 | 2026-03-14 00:48:04.507476 | PLAY RECAP 2026-03-14 00:48:04.507601 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-03-14 00:48:04.507678 | 2026-03-14 00:48:04.618028 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-03-14 00:48:04.620178 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-03-14 00:48:05.159952 | 2026-03-14 00:48:05.160080 | PLAY [localhost] 2026-03-14 00:48:05.170480 | 2026-03-14 00:48:05.170556 | TASK [Generate Zuul manifest] 2026-03-14 00:48:05.191647 | localhost | ok 2026-03-14 00:48:05.207812 | 2026-03-14 00:48:05.207911 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-03-14 00:48:05.802449 | localhost | changed 2026-03-14 00:48:05.815370 | 2026-03-14 00:48:05.815448 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-03-14 00:48:06.303509 | localhost | ok 2026-03-14 00:48:06.373942 | 2026-03-14 00:48:06.374096 | TASK [Upload logs] 2026-03-14 00:48:06.392713 | localhost | ok 2026-03-14 00:48:06.451362 | 2026-03-14 00:48:06.451524 | TASK [Set zuul-log-path fact] 2026-03-14 00:48:06.473267 | localhost | ok 2026-03-14 00:48:06.488282 | 2026-03-14 00:48:06.488361 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-14 00:48:06.520615 | localhost | ok 2026-03-14 00:48:06.527727 | 2026-03-14 00:48:06.527792 | TASK [upload-logs : Create log directories] 2026-03-14 00:48:06.934754 | localhost | changed 2026-03-14 00:48:06.941363 | 2026-03-14 00:48:06.941461 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-03-14 00:48:07.663999 | localhost -> localhost | ok: Runtime: 0:00:00.004133 2026-03-14 00:48:07.670124 | 2026-03-14 00:48:07.670205 | TASK [upload-logs : Upload logs to log server] 2026-03-14 00:48:08.135858 | localhost | Output suppressed because no_log was given 2026-03-14 00:48:08.141514 | 2026-03-14 00:48:08.141582 | LOOP [upload-logs : Compress console log and json output] 2026-03-14 00:48:08.185838 | localhost | skipping: Conditional result was False 2026-03-14 00:48:08.192531 | localhost | skipping: Conditional result was False 2026-03-14 00:48:08.205544 | 2026-03-14 00:48:08.205731 | LOOP [upload-logs : Upload compressed console log and json output] 2026-03-14 00:48:08.250274 | localhost | skipping: Conditional result was False 2026-03-14 00:48:08.250813 | 2026-03-14 00:48:08.255070 | localhost | skipping: Conditional result was False 2026-03-14 00:48:08.268885 | 2026-03-14 00:48:08.269047 | LOOP [upload-logs : Upload console log and json output]