2026-06-03 01:26:14.859526 | Job console starting 2026-06-03 01:26:14.870944 | Updating git repos 2026-06-03 01:26:14.894028 | Cloning repos into workspace 2026-06-03 01:26:14.966456 | Restoring repo states 2026-06-03 01:26:14.973810 | Merging changes 2026-06-03 01:26:15.222281 | Checking out repos 2026-06-03 01:26:15.270032 | Preparing playbooks 2026-06-03 01:26:16.727220 | Running Ansible setup 2026-06-03 01:26:20.582482 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-06-03 01:26:21.266034 | 2026-06-03 01:26:21.341202 | PLAY [localhost] 2026-06-03 01:26:21.354441 | 2026-06-03 01:26:21.354581 | TASK [Gathering Facts] 2026-06-03 01:26:22.272557 | localhost | ok 2026-06-03 01:26:22.279569 | 2026-06-03 01:26:22.279651 | TASK [Setup log path fact] 2026-06-03 01:26:22.299735 | localhost | ok 2026-06-03 01:26:22.319860 | 2026-06-03 01:26:22.320281 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-03 01:26:22.354098 | localhost | ok 2026-06-03 01:26:22.360994 | 2026-06-03 01:26:22.361077 | TASK [emit-job-header : Print job information] 2026-06-03 01:26:22.403051 | # Job Information 2026-06-03 01:26:22.403313 | Ansible Version: 2.16.18 2026-06-03 01:26:22.403383 | Job: ansible-collection-containers-molecule-containerd-rockylinux-9 2026-06-03 01:26:22.403434 | Pipeline: check 2026-06-03 01:26:22.403481 | Executor: 2d72f0692154 2026-06-03 01:26:22.403525 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/128 2026-06-03 01:26:22.403575 | Event ID: 1d781310-5eeb-11f1-908b-3a529372ad09 2026-06-03 01:26:22.408047 | 2026-06-03 01:26:22.408118 | LOOP [emit-job-header : Print node information] 2026-06-03 01:26:22.598417 | localhost | ok: 2026-06-03 01:26:22.599045 | localhost | # Node Information 2026-06-03 01:26:22.599106 | localhost | Inventory Hostname: rockylinux-9 2026-06-03 01:26:22.599144 | localhost | Hostname: np0000178312 2026-06-03 01:26:22.599175 | localhost | Username: zuul 2026-06-03 01:26:22.599212 | localhost | Distro: Rocky 9.7 2026-06-03 01:26:22.599247 | localhost | Provider: yul1 2026-06-03 01:26:22.599278 | localhost | Region: ca-ymq-1 2026-06-03 01:26:22.599309 | localhost | Label: rockylinux-9 2026-06-03 01:26:22.599340 | localhost | Product Name: OpenStack Nova 2026-06-03 01:26:22.599370 | localhost | Interface IP: 199.204.45.132 2026-06-03 01:26:22.618352 | 2026-06-03 01:26:22.618582 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-06-03 01:26:23.190346 | localhost -> localhost | changed 2026-06-03 01:26:23.197538 | 2026-06-03 01:26:23.197650 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-06-03 01:26:24.239184 | localhost -> localhost | changed 2026-06-03 01:26:24.248318 | 2026-06-03 01:26:24.248464 | PLAY [all] 2026-06-03 01:26:24.255877 | 2026-06-03 01:26:24.255955 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-06-03 01:26:24.567494 | rockylinux-9 -> localhost | ok 2026-06-03 01:26:24.576141 | 2026-06-03 01:26:24.576314 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-06-03 01:26:24.605310 | rockylinux-9 | ok 2026-06-03 01:26:24.619357 | rockylinux-9 | included: /var/lib/zuul/builds/de61053c7a8a41358c7e02f38dfe9cff/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-06-03 01:26:24.626437 | 2026-06-03 01:26:24.626587 | TASK [add-build-sshkey : Create Temp SSH key] 2026-06-03 01:26:26.532854 | rockylinux-9 -> localhost | Generating public/private rsa key pair. 2026-06-03 01:26:26.533103 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/de61053c7a8a41358c7e02f38dfe9cff/work/de61053c7a8a41358c7e02f38dfe9cff_id_rsa 2026-06-03 01:26:26.533151 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/de61053c7a8a41358c7e02f38dfe9cff/work/de61053c7a8a41358c7e02f38dfe9cff_id_rsa.pub 2026-06-03 01:26:26.533188 | rockylinux-9 -> localhost | The key fingerprint is: 2026-06-03 01:26:26.533222 | rockylinux-9 -> localhost | SHA256:xHozQSW5jZ0SvFOZa4rQ6XqQKiUOTZkBnz806aaI5l0 zuul-build-sshkey 2026-06-03 01:26:26.533268 | rockylinux-9 -> localhost | The key's randomart image is: 2026-06-03 01:26:26.533301 | rockylinux-9 -> localhost | +---[RSA 3072]----+ 2026-06-03 01:26:26.533342 | rockylinux-9 -> localhost | |.. .oo.o | 2026-06-03 01:26:26.533377 | rockylinux-9 -> localhost | | ... . o+.+ | 2026-06-03 01:26:26.533411 | rockylinux-9 -> localhost | | o++. .+O o | 2026-06-03 01:26:26.533446 | rockylinux-9 -> localhost | | ++..oo*.* | 2026-06-03 01:26:26.533482 | rockylinux-9 -> localhost | | o =+..S= | 2026-06-03 01:26:26.533516 | rockylinux-9 -> localhost | |+.ooo.o..o | 2026-06-03 01:26:26.533550 | rockylinux-9 -> localhost | |=+.. E | 2026-06-03 01:26:26.533582 | rockylinux-9 -> localhost | |+.o o . | 2026-06-03 01:26:26.533616 | rockylinux-9 -> localhost | | o . . | 2026-06-03 01:26:26.533648 | rockylinux-9 -> localhost | +----[SHA256]-----+ 2026-06-03 01:26:26.533719 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.712176 2026-06-03 01:26:26.542102 | 2026-06-03 01:26:26.542218 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-06-03 01:26:26.584271 | rockylinux-9 | ok 2026-06-03 01:26:26.596341 | rockylinux-9 | included: /var/lib/zuul/builds/de61053c7a8a41358c7e02f38dfe9cff/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-06-03 01:26:26.604446 | 2026-06-03 01:26:26.607891 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-06-03 01:26:26.635470 | rockylinux-9 | skipping: Conditional result was False 2026-06-03 01:26:26.644034 | 2026-06-03 01:26:26.644164 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-06-03 01:26:27.147837 | rockylinux-9 | changed 2026-06-03 01:26:27.210032 | 2026-06-03 01:26:27.210200 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-06-03 01:26:27.457147 | rockylinux-9 | ok 2026-06-03 01:26:27.466179 | 2026-06-03 01:26:27.466283 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-06-03 01:26:28.784177 | rockylinux-9 | changed 2026-06-03 01:26:28.811986 | 2026-06-03 01:26:28.812133 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-06-03 01:26:29.711813 | rockylinux-9 | changed 2026-06-03 01:26:29.717101 | 2026-06-03 01:26:29.717234 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-06-03 01:26:29.732287 | rockylinux-9 | skipping: Conditional result was False 2026-06-03 01:26:29.740362 | 2026-06-03 01:26:29.740467 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-06-03 01:26:30.139071 | rockylinux-9 -> localhost | changed 2026-06-03 01:26:30.150024 | 2026-06-03 01:26:30.150141 | TASK [add-build-sshkey : Add back temp key] 2026-06-03 01:26:30.423876 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/de61053c7a8a41358c7e02f38dfe9cff/work/de61053c7a8a41358c7e02f38dfe9cff_id_rsa (zuul-build-sshkey) 2026-06-03 01:26:30.424092 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.013183 2026-06-03 01:26:30.429054 | 2026-06-03 01:26:30.429126 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-06-03 01:26:30.846280 | rockylinux-9 | ok 2026-06-03 01:26:30.851745 | 2026-06-03 01:26:30.851846 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-06-03 01:26:30.881173 | rockylinux-9 | skipping: Conditional result was False 2026-06-03 01:26:30.894663 | 2026-06-03 01:26:30.894772 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-06-03 01:26:31.303360 | rockylinux-9 | ok 2026-06-03 01:26:31.316263 | 2026-06-03 01:26:31.316399 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-06-03 01:26:32.293162 | rockylinux-9 | Output suppressed because no_log was given 2026-06-03 01:26:32.301849 | 2026-06-03 01:26:32.301914 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-06-03 01:26:32.547796 | rockylinux-9 | ok: "logs" 2026-06-03 01:26:32.603044 | rockylinux-9 | ok: All items complete 2026-06-03 01:26:32.603169 | 2026-06-03 01:26:32.758468 | rockylinux-9 | ok: "artifacts" 2026-06-03 01:26:32.972731 | rockylinux-9 | ok: "docs" 2026-06-03 01:26:32.985529 | 2026-06-03 01:26:32.985741 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-06-03 01:26:33.253913 | rockylinux-9 | changed: "logs" 2026-06-03 01:26:33.541345 | rockylinux-9 | changed: "artifacts" 2026-06-03 01:26:33.688920 | rockylinux-9 | changed: "docs" 2026-06-03 01:26:33.697677 | 2026-06-03 01:26:33.697862 | PLAY RECAP 2026-06-03 01:26:33.697919 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-06-03 01:26:33.697952 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-06-03 01:26:33.697973 | 2026-06-03 01:26:33.907292 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-06-03 01:26:33.908603 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-06-03 01:26:34.536044 | 2026-06-03 01:26:34.817444 | PLAY [all] 2026-06-03 01:26:34.835187 | 2026-06-03 01:26:34.835376 | TASK [setup-uv : Extract archive] 2026-06-03 01:26:37.729603 | rockylinux-9 | changed 2026-06-03 01:26:37.738682 | 2026-06-03 01:26:37.738814 | TASK [setup-uv : Print version] 2026-06-03 01:26:37.791879 | [rockylinux-9] Waiting on logger 2026-06-03 01:26:38.302292 | rockylinux-9 | ok: Runtime: 0:00:00.013880 2026-06-03 01:26:38.310323 | 2026-06-03 01:26:38.310388 | TASK [Install Ansible collection siblings] 2026-06-03 01:26:38.827632 | rockylinux-9 | ok: 2026-06-03 01:26:38.828231 | rockylinux-9 | Skipping ansible.posix: no checked-out sibling 2026-06-03 01:26:38.828268 | rockylinux-9 | Skipping community.general: no checked-out sibling 2026-06-03 01:26:38.830439 | 2026-06-03 01:26:39.136608 | PLAY RECAP 2026-06-03 01:26:39.136825 | rockylinux-9 | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-06-03 01:26:39.136881 | 2026-06-03 01:26:39.386142 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-06-03 01:26:39.388346 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-06-03 01:26:40.083629 | 2026-06-03 01:26:40.083758 | PLAY [all] 2026-06-03 01:26:40.098564 | 2026-06-03 01:26:40.098697 | TASK [Copy inventory file for Zuul] 2026-06-03 01:26:41.508412 | rockylinux-9 | changed 2026-06-03 01:26:41.535850 | 2026-06-03 01:26:41.547504 | TASK [Switch "ansible_host" to private IP] 2026-06-03 01:26:41.953713 | rockylinux-9 | changed: 1 replacements made 2026-06-03 01:26:41.959662 | 2026-06-03 01:26:41.959778 | TASK [Run Molecule scenario] 2026-06-03 01:26:41.999881 | [rockylinux-9] Waiting on logger 2026-06-03 01:26:49.127540 | [rockylinux-9] Waiting on logger 2026-06-03 01:26:59.301880 | [rockylinux-9] Waiting on logger 2026-06-03 01:27:09.481819 | [rockylinux-9] Waiting on logger 2026-06-03 01:27:19.782174 | [rockylinux-9] Waiting on logger 2026-06-03 01:27:28.933903 | [rockylinux-9] Waiting on logger 2026-06-03 01:27:39.210117 | [rockylinux-9] Waiting on logger 2026-06-03 01:27:49.381868 | [rockylinux-9] Waiting on logger 2026-06-03 01:27:59.558278 | [rockylinux-9] Waiting on logger 2026-06-03 01:28:09.766157 | [rockylinux-9] Waiting on logger 2026-06-03 01:28:18.917964 | [rockylinux-9] Waiting on logger 2026-06-03 01:28:29.098008 | [rockylinux-9] Waiting on logger 2026-06-03 01:28:39.270572 | [rockylinux-9] Waiting on logger 2026-06-03 01:28:49.445885 | [rockylinux-9] Waiting on logger 2026-06-03 01:28:59.621757 | [rockylinux-9] Waiting on logger 2026-06-03 01:29:10.310193 | [rockylinux-9] Waiting on logger 2026-06-03 01:29:20.549845 | [rockylinux-9] Waiting on logger 2026-06-03 01:29:30.726028 | [rockylinux-9] Waiting on logger 2026-06-03 01:29:39.883897 | [rockylinux-9] Waiting on logger 2026-06-03 01:29:50.058349 | [rockylinux-9] Waiting on logger 2026-06-03 01:30:00.230176 | [rockylinux-9] Waiting on logger 2026-06-03 01:30:10.406156 | [rockylinux-9] Waiting on logger 2026-06-03 01:30:20.586123 | [rockylinux-9] Waiting on logger 2026-06-03 01:30:30.758205 | [rockylinux-9] Waiting on logger 2026-06-03 01:30:39.914005 | [rockylinux-9] Waiting on logger 2026-06-03 01:30:50.086337 | [rockylinux-9] Waiting on logger 2026-06-03 01:31:00.266425 | [rockylinux-9] Waiting on logger 2026-06-03 01:31:10.438183 | [rockylinux-9] Waiting on logger 2026-06-03 01:31:20.614315 | [rockylinux-9] Waiting on logger 2026-06-03 01:31:30.790091 | [rockylinux-9] Waiting on logger 2026-06-03 01:31:39.948719 | rockylinux-9 | ok: Runtime: 0:04:57.012431 2026-06-03 01:31:39.956680 | 2026-06-03 01:31:39.956735 | PLAY RECAP 2026-06-03 01:31:39.956783 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-06-03 01:31:39.956804 | 2026-06-03 01:31:40.101143 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-06-03 01:31:40.104395 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-06-03 01:31:40.678012 | 2026-06-03 01:31:40.678143 | PLAY [all] 2026-06-03 01:31:40.688717 | 2026-06-03 01:31:40.688792 | TASK [fetch-output : Set log path for multiple nodes] 2026-06-03 01:31:40.734454 | rockylinux-9 | skipping: Conditional result was False 2026-06-03 01:31:40.743019 | 2026-06-03 01:31:40.743097 | TASK [fetch-output : Set log path for single node] 2026-06-03 01:31:40.785164 | rockylinux-9 | ok 2026-06-03 01:31:40.790727 | 2026-06-03 01:31:40.790816 | LOOP [fetch-output : Ensure local output dirs] 2026-06-03 01:31:41.194756 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/de61053c7a8a41358c7e02f38dfe9cff/work/logs" 2026-06-03 01:31:41.422059 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/de61053c7a8a41358c7e02f38dfe9cff/work/artifacts" 2026-06-03 01:31:41.623357 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/de61053c7a8a41358c7e02f38dfe9cff/work/docs" 2026-06-03 01:31:41.640403 | 2026-06-03 01:31:41.640542 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-06-03 01:31:42.313072 | rockylinux-9 | changed: .d..t...... ./ 2026-06-03 01:31:42.313673 | rockylinux-9 | changed: All items complete 2026-06-03 01:31:42.313720 | 2026-06-03 01:31:42.831190 | rockylinux-9 | changed: .d..t...... ./ 2026-06-03 01:31:43.358683 | rockylinux-9 | changed: .d..t...... ./ 2026-06-03 01:31:43.384511 | 2026-06-03 01:31:43.384657 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-06-03 01:31:43.887343 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.063677 2026-06-03 01:31:44.115190 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.007787 2026-06-03 01:31:44.136242 | 2026-06-03 01:31:44.136384 | PLAY [all] 2026-06-03 01:31:44.142035 | 2026-06-03 01:31:44.142106 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-06-03 01:31:44.601196 | rockylinux-9 | changed 2026-06-03 01:31:44.607838 | 2026-06-03 01:31:44.607890 | PLAY RECAP 2026-06-03 01:31:44.607937 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-06-03 01:31:44.607958 | 2026-06-03 01:31:44.736513 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-06-03 01:31:44.739113 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-06-03 01:31:45.349177 | 2026-06-03 01:31:45.349670 | PLAY [localhost] 2026-06-03 01:31:45.360417 | 2026-06-03 01:31:45.360486 | TASK [Generate Zuul manifest] 2026-06-03 01:31:45.382119 | localhost | ok 2026-06-03 01:31:45.397111 | 2026-06-03 01:31:45.397196 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-06-03 01:31:45.781890 | localhost | changed 2026-06-03 01:31:45.793841 | 2026-06-03 01:31:45.793914 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-06-03 01:31:45.824987 | localhost | ok 2026-06-03 01:31:45.832589 | 2026-06-03 01:31:45.832664 | TASK [Upload logs] 2026-06-03 01:31:45.851674 | localhost | ok 2026-06-03 01:31:45.908345 | 2026-06-03 01:31:45.908446 | TASK [Set zuul-log-path fact] 2026-06-03 01:31:45.937330 | localhost | ok 2026-06-03 01:31:45.950024 | 2026-06-03 01:31:45.950089 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-03 01:31:45.978517 | localhost | ok 2026-06-03 01:31:45.987083 | 2026-06-03 01:31:45.987144 | TASK [upload-logs : Create log directories] 2026-06-03 01:31:46.405421 | localhost | changed 2026-06-03 01:31:46.412261 | 2026-06-03 01:31:46.412332 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-06-03 01:31:46.830557 | localhost -> localhost | ok: Runtime: 0:00:00.007183 2026-06-03 01:31:46.836384 | 2026-06-03 01:31:46.836451 | TASK [upload-logs : Upload logs to log server] 2026-06-03 01:31:47.314202 | localhost | Output suppressed because no_log was given 2026-06-03 01:31:47.317944 | 2026-06-03 01:31:47.318010 | LOOP [upload-logs : Compress console log and json output] 2026-06-03 01:31:47.359077 | localhost | skipping: Conditional result was False 2026-06-03 01:31:47.366376 | localhost | skipping: Conditional result was False 2026-06-03 01:31:47.382462 | 2026-06-03 01:31:47.382619 | LOOP [upload-logs : Upload compressed console log and json output] 2026-06-03 01:31:47.425627 | localhost | skipping: Conditional result was False 2026-06-03 01:31:47.425884 | 2026-06-03 01:31:47.429970 | localhost | skipping: Conditional result was False 2026-06-03 01:31:47.445847 | 2026-06-03 01:31:47.446021 | LOOP [upload-logs : Upload console log and json output]