2026-04-29 20:48:37.322729 | Job console starting 2026-04-29 20:48:37.339721 | Updating git repos 2026-04-29 20:48:37.372695 | Cloning repos into workspace 2026-04-29 20:48:37.950438 | Restoring repo states 2026-04-29 20:48:37.956747 | Merging changes 2026-04-29 20:48:38.420213 | Checking out repos 2026-04-29 20:48:38.453774 | Preparing playbooks 2026-04-29 20:48:41.693705 | Running Ansible setup 2026-04-29 20:48:45.507980 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-29 20:48:46.247179 | 2026-04-29 20:48:46.247323 | PLAY [localhost] 2026-04-29 20:48:46.257633 | 2026-04-29 20:48:46.257787 | TASK [Gathering Facts] 2026-04-29 20:48:47.144413 | localhost | ok 2026-04-29 20:48:47.152159 | 2026-04-29 20:48:47.152281 | TASK [Setup log path fact] 2026-04-29 20:48:47.171063 | localhost | ok 2026-04-29 20:48:47.187779 | 2026-04-29 20:48:47.187956 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-29 20:48:47.219194 | localhost | ok 2026-04-29 20:48:47.227995 | 2026-04-29 20:48:47.228075 | TASK [emit-job-header : Print job information] 2026-04-29 20:48:47.270536 | # Job Information 2026-04-29 20:48:47.270767 | Ansible Version: 2.16.18 2026-04-29 20:48:47.270836 | Job: ansible-collection-containers-molecule-containerd-rockylinux-9 2026-04-29 20:48:47.270884 | Pipeline: check 2026-04-29 20:48:47.270929 | Executor: 2d72f0692154 2026-04-29 20:48:47.270972 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/126 2026-04-29 20:48:47.271019 | Event ID: 9b4e1cc0-440c-11f1-90ca-0e5250fcd001 2026-04-29 20:48:47.275617 | 2026-04-29 20:48:47.275731 | LOOP [emit-job-header : Print node information] 2026-04-29 20:48:47.364939 | localhost | ok: 2026-04-29 20:48:47.365557 | localhost | # Node Information 2026-04-29 20:48:47.365620 | localhost | Inventory Hostname: rockylinux-9 2026-04-29 20:48:47.365658 | localhost | Hostname: np0000171273 2026-04-29 20:48:47.365690 | localhost | Username: zuul 2026-04-29 20:48:47.365728 | localhost | Distro: Rocky 9.7 2026-04-29 20:48:47.365760 | localhost | Provider: yul1 2026-04-29 20:48:47.365791 | localhost | Region: ca-ymq-1 2026-04-29 20:48:47.365820 | localhost | Label: rockylinux-9 2026-04-29 20:48:47.365850 | localhost | Product Name: OpenStack Nova 2026-04-29 20:48:47.365880 | localhost | Interface IP: 199.204.45.89 2026-04-29 20:48:47.372467 | 2026-04-29 20:48:47.372595 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-04-29 20:48:47.805132 | localhost -> localhost | changed 2026-04-29 20:48:47.814473 | 2026-04-29 20:48:47.814576 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-04-29 20:48:48.705633 | localhost -> localhost | changed 2026-04-29 20:48:48.714880 | 2026-04-29 20:48:48.714986 | PLAY [all] 2026-04-29 20:48:48.722940 | 2026-04-29 20:48:48.723071 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-04-29 20:48:48.985755 | rockylinux-9 -> localhost | ok 2026-04-29 20:48:48.991374 | 2026-04-29 20:48:48.991443 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-04-29 20:48:49.021117 | rockylinux-9 | ok 2026-04-29 20:48:49.037280 | rockylinux-9 | included: /var/lib/zuul/builds/2303c6d0f6c64e339242da735842094a/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-04-29 20:48:49.043770 | 2026-04-29 20:48:49.043835 | TASK [add-build-sshkey : Create Temp SSH key] 2026-04-29 20:48:49.774754 | rockylinux-9 -> localhost | Generating public/private rsa key pair. 2026-04-29 20:48:49.775046 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/2303c6d0f6c64e339242da735842094a/work/2303c6d0f6c64e339242da735842094a_id_rsa 2026-04-29 20:48:49.775112 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/2303c6d0f6c64e339242da735842094a/work/2303c6d0f6c64e339242da735842094a_id_rsa.pub 2026-04-29 20:48:49.775162 | rockylinux-9 -> localhost | The key fingerprint is: 2026-04-29 20:48:49.775209 | rockylinux-9 -> localhost | SHA256:ZWfo2/aMRjLkYWeB0H5vT1mG1lX0aNvF4xTA/doAfps zuul-build-sshkey 2026-04-29 20:48:49.775277 | rockylinux-9 -> localhost | The key's randomart image is: 2026-04-29 20:48:49.775323 | rockylinux-9 -> localhost | +---[RSA 3072]----+ 2026-04-29 20:48:49.775354 | rockylinux-9 -> localhost | | .o ...oo+| 2026-04-29 20:48:49.775376 | rockylinux-9 -> localhost | | o.o. ++| 2026-04-29 20:48:49.775397 | rockylinux-9 -> localhost | | .+.ooo=*| 2026-04-29 20:48:49.775417 | rockylinux-9 -> localhost | | +=o=.BoB| 2026-04-29 20:48:49.775437 | rockylinux-9 -> localhost | | S+.= +.O+| 2026-04-29 20:48:49.775456 | rockylinux-9 -> localhost | | +o. E.o| 2026-04-29 20:48:49.775476 | rockylinux-9 -> localhost | | .+o. o | 2026-04-29 20:48:49.775495 | rockylinux-9 -> localhost | | ..+ .| 2026-04-29 20:48:49.775517 | rockylinux-9 -> localhost | | .. o | 2026-04-29 20:48:49.775538 | rockylinux-9 -> localhost | +----[SHA256]-----+ 2026-04-29 20:48:49.775587 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.274713 2026-04-29 20:48:49.780216 | 2026-04-29 20:48:49.780279 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-04-29 20:48:49.814036 | rockylinux-9 | ok 2026-04-29 20:48:49.822885 | rockylinux-9 | included: /var/lib/zuul/builds/2303c6d0f6c64e339242da735842094a/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-04-29 20:48:49.829860 | 2026-04-29 20:48:49.829921 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-04-29 20:48:49.848818 | rockylinux-9 | skipping: Conditional result was False 2026-04-29 20:48:49.854404 | 2026-04-29 20:48:49.854482 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-04-29 20:48:50.350352 | rockylinux-9 | changed 2026-04-29 20:48:50.354853 | 2026-04-29 20:48:50.354919 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-04-29 20:48:50.619454 | rockylinux-9 | ok 2026-04-29 20:48:50.624568 | 2026-04-29 20:48:50.624647 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-04-29 20:48:51.570078 | rockylinux-9 | changed 2026-04-29 20:48:51.574757 | 2026-04-29 20:48:51.574882 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-04-29 20:48:52.470692 | rockylinux-9 | changed 2026-04-29 20:48:52.638236 | 2026-04-29 20:48:52.638345 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-04-29 20:48:52.663463 | rockylinux-9 | skipping: Conditional result was False 2026-04-29 20:48:52.682260 | 2026-04-29 20:48:52.682352 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-04-29 20:48:53.054465 | rockylinux-9 -> localhost | changed 2026-04-29 20:48:53.068725 | 2026-04-29 20:48:53.068797 | TASK [add-build-sshkey : Add back temp key] 2026-04-29 20:48:53.361368 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/2303c6d0f6c64e339242da735842094a/work/2303c6d0f6c64e339242da735842094a_id_rsa (zuul-build-sshkey) 2026-04-29 20:48:53.361632 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.014232 2026-04-29 20:48:53.366595 | 2026-04-29 20:48:53.366687 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-04-29 20:48:53.749087 | rockylinux-9 | ok 2026-04-29 20:48:53.756861 | 2026-04-29 20:48:53.756932 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-04-29 20:48:53.786710 | rockylinux-9 | skipping: Conditional result was False 2026-04-29 20:48:53.807539 | 2026-04-29 20:48:53.807625 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-04-29 20:48:54.222882 | rockylinux-9 | ok 2026-04-29 20:48:54.228177 | 2026-04-29 20:48:54.228252 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-04-29 20:48:55.172582 | rockylinux-9 | Output suppressed because no_log was given 2026-04-29 20:48:55.188331 | 2026-04-29 20:48:55.188472 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-04-29 20:48:55.457251 | rockylinux-9 | ok: "logs" 2026-04-29 20:48:55.457571 | rockylinux-9 | ok: All items complete 2026-04-29 20:48:55.457613 | 2026-04-29 20:48:55.687799 | rockylinux-9 | ok: "artifacts" 2026-04-29 20:48:55.903554 | rockylinux-9 | ok: "docs" 2026-04-29 20:48:55.917451 | 2026-04-29 20:48:55.917631 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-04-29 20:48:56.171290 | rockylinux-9 | changed: "logs" 2026-04-29 20:48:56.396489 | rockylinux-9 | changed: "artifacts" 2026-04-29 20:48:56.670477 | rockylinux-9 | changed: "docs" 2026-04-29 20:48:56.689023 | 2026-04-29 20:48:56.689139 | PLAY RECAP 2026-04-29 20:48:56.689187 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-29 20:48:56.689217 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-04-29 20:48:56.689240 | 2026-04-29 20:48:56.906647 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-29 20:48:56.908124 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-29 20:48:57.482924 | 2026-04-29 20:48:57.483071 | PLAY [all] 2026-04-29 20:48:57.495110 | 2026-04-29 20:48:57.495220 | TASK [setup-uv : Extract archive] 2026-04-29 20:49:00.271578 | rockylinux-9 | changed 2026-04-29 20:49:00.709887 | 2026-04-29 20:49:00.710030 | TASK [setup-uv : Print version] 2026-04-29 20:49:00.731471 | [rockylinux-9] Waiting on logger 2026-04-29 20:49:01.246698 | rockylinux-9 | ok: Runtime: 0:00:00.012881 2026-04-29 20:49:01.254171 | 2026-04-29 20:49:01.254225 | PLAY RECAP 2026-04-29 20:49:01.254271 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-29 20:49:01.254294 | 2026-04-29 20:49:01.397640 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-29 20:49:01.399951 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-29 20:49:01.982351 | 2026-04-29 20:49:01.982481 | PLAY [all] 2026-04-29 20:49:01.993688 | 2026-04-29 20:49:01.993776 | TASK [Copy inventory file for Zuul] 2026-04-29 20:49:03.342433 | rockylinux-9 | changed 2026-04-29 20:49:03.347356 | 2026-04-29 20:49:03.347458 | TASK [Switch "ansible_host" to private IP] 2026-04-29 20:49:03.747816 | rockylinux-9 | changed: 1 replacements made 2026-04-29 20:49:03.757497 | 2026-04-29 20:49:03.757571 | TASK [Run Molecule scenario] 2026-04-29 20:49:03.780690 | [rockylinux-9] Waiting on logger 2026-04-29 20:49:11.910237 | [rockylinux-9] Waiting on logger 2026-04-29 20:49:22.089841 | [rockylinux-9] Waiting on logger 2026-04-29 20:49:32.262047 | [rockylinux-9] Waiting on logger 2026-04-29 20:49:42.441961 | [rockylinux-9] Waiting on logger 2026-04-29 20:49:52.618374 | [rockylinux-9] Waiting on logger 2026-04-29 20:50:01.775409 | [rockylinux-9] Waiting on logger 2026-04-29 20:50:11.942096 | [rockylinux-9] Waiting on logger 2026-04-29 20:50:22.117869 | [rockylinux-9] Waiting on logger 2026-04-29 20:50:31.755343 | [rockylinux-9] Waiting on logger 2026-04-29 20:50:41.926702 | [rockylinux-9] Waiting on logger 2026-04-29 20:50:52.102079 | [rockylinux-9] Waiting on logger 2026-04-29 20:51:02.277986 | [rockylinux-9] Waiting on logger 2026-04-29 20:51:12.454024 | [rockylinux-9] Waiting on logger 2026-04-29 20:51:22.630710 | [rockylinux-9] Waiting on logger 2026-04-29 20:51:32.101855 | [rockylinux-9] Waiting on logger 2026-04-29 20:51:42.278168 | [rockylinux-9] Waiting on logger 2026-04-29 20:51:52.454183 | [rockylinux-9] Waiting on logger 2026-04-29 20:52:02.630321 | [rockylinux-9] Waiting on logger 2026-04-29 20:52:11.787190 | [rockylinux-9] Waiting on logger 2026-04-29 20:52:21.958278 | [rockylinux-9] Waiting on logger 2026-04-29 20:52:32.134093 | [rockylinux-9] Waiting on logger 2026-04-29 20:52:42.310147 | [rockylinux-9] Waiting on logger 2026-04-29 20:52:52.486172 | [rockylinux-9] Waiting on logger 2026-04-29 20:53:02.661990 | [rockylinux-9] Waiting on logger 2026-04-29 20:53:11.819612 | [rockylinux-9] Waiting on logger 2026-04-29 20:53:21.990030 | [rockylinux-9] Waiting on logger 2026-04-29 20:53:32.165949 | [rockylinux-9] Waiting on logger 2026-04-29 20:53:42.342100 | [rockylinux-9] Waiting on logger 2026-04-29 20:53:52.518181 | [rockylinux-9] Waiting on logger 2026-04-29 20:54:02.693946 | [rockylinux-9] Waiting on logger 2026-04-29 20:54:11.851032 | [rockylinux-9] Waiting on logger 2026-04-29 20:54:12.973241 | rockylinux-9 | ok: Runtime: 0:05:08.465321 2026-04-29 20:54:12.980972 | 2026-04-29 20:54:12.981051 | PLAY RECAP 2026-04-29 20:54:12.981114 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-29 20:54:12.981146 | 2026-04-29 20:54:13.097407 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-29 20:54:13.098711 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-29 20:54:13.674157 | 2026-04-29 20:54:13.674280 | PLAY [all] 2026-04-29 20:54:13.685079 | 2026-04-29 20:54:13.685154 | TASK [fetch-output : Set log path for multiple nodes] 2026-04-29 20:54:13.730690 | rockylinux-9 | skipping: Conditional result was False 2026-04-29 20:54:13.740279 | 2026-04-29 20:54:13.740445 | TASK [fetch-output : Set log path for single node] 2026-04-29 20:54:13.783963 | rockylinux-9 | ok 2026-04-29 20:54:13.788635 | 2026-04-29 20:54:13.788704 | LOOP [fetch-output : Ensure local output dirs] 2026-04-29 20:54:14.154175 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/2303c6d0f6c64e339242da735842094a/work/logs" 2026-04-29 20:54:14.360522 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/2303c6d0f6c64e339242da735842094a/work/artifacts" 2026-04-29 20:54:14.561231 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/2303c6d0f6c64e339242da735842094a/work/docs" 2026-04-29 20:54:14.576738 | 2026-04-29 20:54:14.576895 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-04-29 20:54:15.266575 | rockylinux-9 | ok 2026-04-29 20:54:15.266867 | rockylinux-9 | changed: All items complete 2026-04-29 20:54:15.266906 | 2026-04-29 20:54:15.784351 | rockylinux-9 | changed: .d..t...... ./ 2026-04-29 20:54:16.283010 | rockylinux-9 | changed: .d..t...... ./ 2026-04-29 20:54:16.308610 | 2026-04-29 20:54:16.308783 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-04-29 20:54:16.724068 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.008412 2026-04-29 20:54:16.946799 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.007517 2026-04-29 20:54:16.959881 | 2026-04-29 20:54:16.959960 | PLAY [all] 2026-04-29 20:54:16.965900 | 2026-04-29 20:54:16.965964 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-04-29 20:54:17.418030 | rockylinux-9 | changed 2026-04-29 20:54:17.424033 | 2026-04-29 20:54:17.424091 | PLAY RECAP 2026-04-29 20:54:17.424139 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-04-29 20:54:17.424161 | 2026-04-29 20:54:17.580788 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-29 20:54:17.582297 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-04-29 20:54:18.157479 | 2026-04-29 20:54:18.157619 | PLAY [localhost] 2026-04-29 20:54:18.168186 | 2026-04-29 20:54:18.168261 | TASK [Generate Zuul manifest] 2026-04-29 20:54:18.187365 | localhost | ok 2026-04-29 20:54:18.204225 | 2026-04-29 20:54:18.204317 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-04-29 20:54:18.600551 | localhost | changed 2026-04-29 20:54:18.615126 | 2026-04-29 20:54:18.615208 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-04-29 20:54:18.647023 | localhost | ok 2026-04-29 20:54:18.656509 | 2026-04-29 20:54:18.656585 | TASK [Upload logs] 2026-04-29 20:54:18.679697 | localhost | ok 2026-04-29 20:54:18.741011 | 2026-04-29 20:54:18.741180 | TASK [Set zuul-log-path fact] 2026-04-29 20:54:18.762915 | localhost | ok 2026-04-29 20:54:18.776220 | 2026-04-29 20:54:18.776288 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-29 20:54:18.807510 | localhost | ok 2026-04-29 20:54:18.816807 | 2026-04-29 20:54:18.816895 | TASK [upload-logs : Create log directories] 2026-04-29 20:54:19.214552 | localhost | changed 2026-04-29 20:54:19.219500 | 2026-04-29 20:54:19.219569 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-04-29 20:54:19.639825 | localhost -> localhost | ok: Runtime: 0:00:00.005733 2026-04-29 20:54:19.645011 | 2026-04-29 20:54:19.645094 | TASK [upload-logs : Upload logs to log server] 2026-04-29 20:54:20.113905 | localhost | Output suppressed because no_log was given 2026-04-29 20:54:20.119942 | 2026-04-29 20:54:20.120012 | LOOP [upload-logs : Compress console log and json output] 2026-04-29 20:54:20.166136 | localhost | skipping: Conditional result was False 2026-04-29 20:54:20.172652 | localhost | skipping: Conditional result was False 2026-04-29 20:54:20.186469 | 2026-04-29 20:54:20.186847 | LOOP [upload-logs : Upload compressed console log and json output] 2026-04-29 20:54:20.228386 | localhost | skipping: Conditional result was False 2026-04-29 20:54:20.228790 | 2026-04-29 20:54:20.232077 | localhost | skipping: Conditional result was False 2026-04-29 20:54:20.242514 | 2026-04-29 20:54:20.242637 | LOOP [upload-logs : Upload console log and json output]