2026-03-11 16:40:49.946526 | Job console starting 2026-03-11 16:40:50.083933 | Updating git repos 2026-03-11 16:40:50.136539 | Cloning repos into workspace 2026-03-11 16:40:50.686680 | Restoring repo states 2026-03-11 16:40:50.693602 | Merging changes 2026-03-11 16:40:50.926239 | Checking out repos 2026-03-11 16:40:52.784752 | Preparing playbooks 2026-03-11 16:40:56.583205 | Running Ansible setup 2026-03-11 16:41:01.999552 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-03-11 16:41:02.563995 | 2026-03-11 16:41:02.564111 | PLAY [localhost] 2026-03-11 16:41:02.571239 | 2026-03-11 16:41:02.571306 | TASK [Gathering Facts] 2026-03-11 16:41:03.507877 | localhost | ok 2026-03-11 16:41:03.516810 | 2026-03-11 16:41:03.526939 | TASK [Setup log path fact] 2026-03-11 16:41:03.546395 | localhost | ok 2026-03-11 16:41:03.567077 | 2026-03-11 16:41:03.567224 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-11 16:41:03.595162 | localhost | ok 2026-03-11 16:41:03.601410 | 2026-03-11 16:41:03.601490 | TASK [emit-job-header : Print job information] 2026-03-11 16:41:03.638161 | # Job Information 2026-03-11 16:41:03.638304 | Ansible Version: 2.16.16 2026-03-11 16:41:03.638337 | Job: ansible-collection-containers-molecule-containerd-rockylinux-9 2026-03-11 16:41:03.638360 | Pipeline: check 2026-03-11 16:41:03.638380 | Executor: 0a8996d2b663 2026-03-11 16:41:03.638400 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/93 2026-03-11 16:41:03.638426 | Event ID: ee8f5090-1d68-11f1-817a-633f6d651ec7 2026-03-11 16:41:03.643053 | 2026-03-11 16:41:03.643171 | LOOP [emit-job-header : Print node information] 2026-03-11 16:41:03.727848 | localhost | ok: 2026-03-11 16:41:03.728098 | localhost | # Node Information 2026-03-11 16:41:03.728130 | localhost | Inventory Hostname: rockylinux-9 2026-03-11 16:41:03.728158 | localhost | Hostname: np0000158337 2026-03-11 16:41:03.728180 | localhost | Username: zuul 2026-03-11 16:41:03.728204 | localhost | Distro: Rocky 9.7 2026-03-11 16:41:03.728225 | localhost | Provider: yul1 2026-03-11 16:41:03.728245 | localhost | Region: ca-ymq-1 2026-03-11 16:41:03.728264 | localhost | Label: rockylinux-9 2026-03-11 16:41:03.728283 | localhost | Product Name: OpenStack Nova 2026-03-11 16:41:03.728302 | localhost | Interface IP: 199.204.45.129 2026-03-11 16:41:03.735244 | 2026-03-11 16:41:03.735360 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-03-11 16:41:04.624452 | localhost -> localhost | changed 2026-03-11 16:41:04.629427 | 2026-03-11 16:41:04.629497 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-03-11 16:41:05.737784 | localhost -> localhost | changed 2026-03-11 16:41:05.760064 | 2026-03-11 16:41:05.760151 | PLAY [all] 2026-03-11 16:41:05.770169 | 2026-03-11 16:41:05.770263 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-03-11 16:41:06.016048 | rockylinux-9 -> localhost | ok 2026-03-11 16:41:06.021311 | 2026-03-11 16:41:06.033928 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-03-11 16:41:06.063998 | rockylinux-9 | ok 2026-03-11 16:41:06.087472 | rockylinux-9 | included: /var/lib/zuul/builds/274704a9f46f4b0fb5cd04f2084f83ea/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-03-11 16:41:06.108418 | 2026-03-11 16:41:06.108550 | TASK [add-build-sshkey : Create Temp SSH key] 2026-03-11 16:41:07.220531 | rockylinux-9 -> localhost | Generating public/private rsa key pair. 2026-03-11 16:41:07.220940 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/274704a9f46f4b0fb5cd04f2084f83ea/work/274704a9f46f4b0fb5cd04f2084f83ea_id_rsa 2026-03-11 16:41:07.221197 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/274704a9f46f4b0fb5cd04f2084f83ea/work/274704a9f46f4b0fb5cd04f2084f83ea_id_rsa.pub 2026-03-11 16:41:07.221235 | rockylinux-9 -> localhost | The key fingerprint is: 2026-03-11 16:41:07.221267 | rockylinux-9 -> localhost | SHA256:aXWnQybxtN5c+EfzKvOK0Q4W45nMP3OR3uQpIe+ppU8 zuul-build-sshkey 2026-03-11 16:41:07.221524 | rockylinux-9 -> localhost | The key's randomart image is: 2026-03-11 16:41:07.221624 | rockylinux-9 -> localhost | +---[RSA 3072]----+ 2026-03-11 16:41:07.221670 | rockylinux-9 -> localhost | | . . | 2026-03-11 16:41:07.221706 | rockylinux-9 -> localhost | | + . . | 2026-03-11 16:41:07.221741 | rockylinux-9 -> localhost | | o * o.o| 2026-03-11 16:41:07.221777 | rockylinux-9 -> localhost | | o * = +o| 2026-03-11 16:41:07.221807 | rockylinux-9 -> localhost | | S o + o.+| 2026-03-11 16:41:07.221838 | rockylinux-9 -> localhost | | . + B oo.o| 2026-03-11 16:41:07.221868 | rockylinux-9 -> localhost | | X *Eo=.| 2026-03-11 16:41:07.221898 | rockylinux-9 -> localhost | | . ***+.o| 2026-03-11 16:41:07.221933 | rockylinux-9 -> localhost | | .oBOo | 2026-03-11 16:41:07.221964 | rockylinux-9 -> localhost | +----[SHA256]-----+ 2026-03-11 16:41:07.224376 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.627868 2026-03-11 16:41:07.230256 | 2026-03-11 16:41:07.230370 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-03-11 16:41:07.258269 | rockylinux-9 | ok 2026-03-11 16:41:07.266040 | rockylinux-9 | included: /var/lib/zuul/builds/274704a9f46f4b0fb5cd04f2084f83ea/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-03-11 16:41:07.274520 | 2026-03-11 16:41:07.274601 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-03-11 16:41:07.297955 | rockylinux-9 | skipping: Conditional result was False 2026-03-11 16:41:07.303282 | 2026-03-11 16:41:07.303373 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-03-11 16:41:07.781877 | rockylinux-9 | changed 2026-03-11 16:41:07.787262 | 2026-03-11 16:41:07.787346 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-03-11 16:41:08.030495 | rockylinux-9 | ok 2026-03-11 16:41:08.067325 | 2026-03-11 16:41:08.067437 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-03-11 16:41:08.962712 | rockylinux-9 | changed 2026-03-11 16:41:09.142038 | 2026-03-11 16:41:09.142132 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-03-11 16:41:10.006180 | rockylinux-9 | changed 2026-03-11 16:41:10.010568 | 2026-03-11 16:41:10.010636 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-03-11 16:41:10.034289 | rockylinux-9 | skipping: Conditional result was False 2026-03-11 16:41:10.039078 | 2026-03-11 16:41:10.039140 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-03-11 16:41:10.397468 | rockylinux-9 -> localhost | changed 2026-03-11 16:41:10.407342 | 2026-03-11 16:41:10.407409 | TASK [add-build-sshkey : Add back temp key] 2026-03-11 16:41:10.688870 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/274704a9f46f4b0fb5cd04f2084f83ea/work/274704a9f46f4b0fb5cd04f2084f83ea_id_rsa (zuul-build-sshkey) 2026-03-11 16:41:10.689571 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.009685 2026-03-11 16:41:10.695990 | 2026-03-11 16:41:10.696096 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-03-11 16:41:11.079004 | rockylinux-9 | ok 2026-03-11 16:41:11.083488 | 2026-03-11 16:41:11.083605 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-03-11 16:41:11.107390 | rockylinux-9 | skipping: Conditional result was False 2026-03-11 16:41:11.117969 | 2026-03-11 16:41:11.118067 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-03-11 16:41:11.504658 | rockylinux-9 | ok 2026-03-11 16:41:11.511711 | 2026-03-11 16:41:11.511817 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-03-11 16:41:12.443084 | rockylinux-9 | Output suppressed because no_log was given 2026-03-11 16:41:12.454849 | 2026-03-11 16:41:12.454951 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-03-11 16:41:12.699369 | rockylinux-9 | ok: "logs" 2026-03-11 16:41:12.699567 | rockylinux-9 | ok: All items complete 2026-03-11 16:41:12.699596 | 2026-03-11 16:41:12.912793 | rockylinux-9 | ok: "artifacts" 2026-03-11 16:41:13.138784 | rockylinux-9 | ok: "docs" 2026-03-11 16:41:13.153217 | 2026-03-11 16:41:13.153324 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-03-11 16:41:13.417116 | rockylinux-9 | changed: "logs" 2026-03-11 16:41:13.646011 | rockylinux-9 | changed: "artifacts" 2026-03-11 16:41:13.870358 | rockylinux-9 | changed: "docs" 2026-03-11 16:41:13.889619 | 2026-03-11 16:41:13.889723 | PLAY RECAP 2026-03-11 16:41:13.889786 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-11 16:41:13.889832 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-03-11 16:41:13.889865 | 2026-03-11 16:41:13.996817 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-03-11 16:41:13.998359 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-03-11 16:41:14.563964 | 2026-03-11 16:41:14.564182 | PLAY [all] 2026-03-11 16:41:14.582839 | 2026-03-11 16:41:14.583025 | TASK [setup-uv : Extract archive] 2026-03-11 16:41:17.374885 | rockylinux-9 | changed 2026-03-11 16:41:17.381703 | 2026-03-11 16:41:17.381784 | TASK [setup-uv : Print version] 2026-03-11 16:41:17.402303 | [rockylinux-9] Waiting on logger 2026-03-11 16:41:17.912020 | rockylinux-9 | ok: Runtime: 0:00:00.015214 2026-03-11 16:41:17.917538 | 2026-03-11 16:41:17.917598 | PLAY RECAP 2026-03-11 16:41:17.917638 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-11 16:41:17.917662 | 2026-03-11 16:41:18.039554 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-03-11 16:41:18.040594 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-03-11 16:41:18.638209 | 2026-03-11 16:41:18.638358 | PLAY [all] 2026-03-11 16:41:18.649089 | 2026-03-11 16:41:18.649166 | TASK [Copy inventory file for Zuul] 2026-03-11 16:41:19.970412 | rockylinux-9 | changed 2026-03-11 16:41:19.976241 | 2026-03-11 16:41:19.976333 | TASK [Switch "ansible_host" to private IP] 2026-03-11 16:41:20.322199 | rockylinux-9 | changed: 1 replacements made 2026-03-11 16:41:20.334324 | 2026-03-11 16:41:20.337462 | TASK [Run Molecule scenario] 2026-03-11 16:41:20.381304 | [rockylinux-9] Waiting on logger 2026-03-11 16:41:28.518000 | [rockylinux-9] Waiting on logger 2026-03-11 16:41:38.694604 | [rockylinux-9] Waiting on logger 2026-03-11 16:41:48.870303 | [rockylinux-9] Waiting on logger 2026-03-11 16:41:58.662398 | [rockylinux-9] Waiting on logger 2026-03-11 16:42:08.837947 | [rockylinux-9] Waiting on logger 2026-03-11 16:42:19.013983 | [rockylinux-9] Waiting on logger 2026-03-11 16:42:29.189948 | [rockylinux-9] Waiting on logger 2026-03-11 16:42:39.365914 | [rockylinux-9] Waiting on logger 2026-03-11 16:42:48.870043 | [rockylinux-9] Waiting on logger 2026-03-11 16:42:59.049955 | [rockylinux-9] Waiting on logger 2026-03-11 16:43:09.225782 | [rockylinux-9] Waiting on logger 2026-03-11 16:43:19.398090 | [rockylinux-9] Waiting on logger 2026-03-11 16:43:28.553880 | [rockylinux-9] Waiting on logger 2026-03-11 16:43:38.726103 | [rockylinux-9] Waiting on logger 2026-03-11 16:43:48.901928 | [rockylinux-9] Waiting on logger 2026-03-11 16:43:59.078000 | [rockylinux-9] Waiting on logger 2026-03-11 16:44:09.253933 | [rockylinux-9] Waiting on logger 2026-03-11 16:44:18.412570 | [rockylinux-9] Waiting on logger 2026-03-11 16:44:28.582126 | [rockylinux-9] Waiting on logger 2026-03-11 16:44:43.141681 | [rockylinux-9] Waiting on logger 2026-03-11 16:44:52.293816 | [rockylinux-9] Waiting on logger 2026-03-11 16:45:02.469945 | [rockylinux-9] Waiting on logger 2026-03-11 16:45:12.650044 | [rockylinux-9] Waiting on logger 2026-03-11 16:45:22.821911 | [rockylinux-9] Waiting on logger 2026-03-11 16:45:32.170994 | [rockylinux-9] Waiting on logger 2026-03-11 16:45:42.342350 | [rockylinux-9] Waiting on logger 2026-03-11 16:45:43.466312 | rockylinux-9 | ok: Runtime: 0:04:22.438237 2026-03-11 16:45:43.472890 | 2026-03-11 16:45:43.472993 | PLAY RECAP 2026-03-11 16:45:43.473050 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-11 16:45:43.473077 | 2026-03-11 16:45:43.619286 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-03-11 16:45:43.620405 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-03-11 16:45:44.211019 | 2026-03-11 16:45:44.211139 | PLAY [all] 2026-03-11 16:45:44.221499 | 2026-03-11 16:45:44.221689 | TASK [fetch-output : Set log path for multiple nodes] 2026-03-11 16:45:44.266523 | rockylinux-9 | skipping: Conditional result was False 2026-03-11 16:45:44.271183 | 2026-03-11 16:45:44.272758 | TASK [fetch-output : Set log path for single node] 2026-03-11 16:45:44.316253 | rockylinux-9 | ok 2026-03-11 16:45:44.323888 | 2026-03-11 16:45:44.323968 | LOOP [fetch-output : Ensure local output dirs] 2026-03-11 16:45:44.682553 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/274704a9f46f4b0fb5cd04f2084f83ea/work/logs" 2026-03-11 16:45:44.933259 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/274704a9f46f4b0fb5cd04f2084f83ea/work/artifacts" 2026-03-11 16:45:45.167253 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/274704a9f46f4b0fb5cd04f2084f83ea/work/docs" 2026-03-11 16:45:45.191621 | 2026-03-11 16:45:45.196179 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-03-11 16:45:45.895507 | rockylinux-9 | ok 2026-03-11 16:45:45.895818 | rockylinux-9 | changed: All items complete 2026-03-11 16:45:45.895858 | 2026-03-11 16:45:46.471335 | rockylinux-9 | changed: .d..t...... ./ 2026-03-11 16:45:47.008221 | rockylinux-9 | changed: .d..t...... ./ 2026-03-11 16:45:47.023121 | 2026-03-11 16:45:47.023274 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-03-11 16:45:47.836926 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.006836 2026-03-11 16:45:48.099940 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.007436 2026-03-11 16:45:48.115090 | 2026-03-11 16:45:48.119575 | PLAY [all] 2026-03-11 16:45:48.127731 | 2026-03-11 16:45:48.127804 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-03-11 16:45:48.615387 | rockylinux-9 | changed 2026-03-11 16:45:48.739264 | 2026-03-11 16:45:48.739417 | PLAY RECAP 2026-03-11 16:45:48.739480 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-03-11 16:45:48.739507 | 2026-03-11 16:45:48.870181 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-03-11 16:45:48.872326 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-03-11 16:45:49.548335 | 2026-03-11 16:45:49.548521 | PLAY [localhost] 2026-03-11 16:45:49.561272 | 2026-03-11 16:45:49.561361 | TASK [Generate Zuul manifest] 2026-03-11 16:45:49.604831 | localhost | ok 2026-03-11 16:45:49.640375 | 2026-03-11 16:45:49.640495 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-03-11 16:45:49.971112 | localhost | changed 2026-03-11 16:45:49.986953 | 2026-03-11 16:45:49.987086 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-03-11 16:45:50.021326 | localhost | ok 2026-03-11 16:45:50.028588 | 2026-03-11 16:45:50.028815 | TASK [Upload logs] 2026-03-11 16:45:50.050067 | localhost | ok 2026-03-11 16:45:50.159155 | 2026-03-11 16:45:50.159374 | TASK [Set zuul-log-path fact] 2026-03-11 16:45:50.189663 | localhost | ok 2026-03-11 16:45:50.203723 | 2026-03-11 16:45:50.203858 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-11 16:45:50.233592 | localhost | ok 2026-03-11 16:45:50.242408 | 2026-03-11 16:45:50.242480 | TASK [upload-logs : Create log directories] 2026-03-11 16:45:51.125245 | localhost | changed 2026-03-11 16:45:51.131362 | 2026-03-11 16:45:51.131430 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-03-11 16:45:51.503259 | localhost -> localhost | ok: Runtime: 0:00:00.005881 2026-03-11 16:45:51.508330 | 2026-03-11 16:45:51.508397 | TASK [upload-logs : Upload logs to log server] 2026-03-11 16:45:52.056447 | localhost | Output suppressed because no_log was given 2026-03-11 16:45:52.060136 | 2026-03-11 16:45:52.060203 | LOOP [upload-logs : Compress console log and json output] 2026-03-11 16:45:52.112939 | localhost | skipping: Conditional result was False 2026-03-11 16:45:52.120446 | localhost | skipping: Conditional result was False 2026-03-11 16:45:52.134849 | 2026-03-11 16:45:52.135176 | LOOP [upload-logs : Upload compressed console log and json output] 2026-03-11 16:45:52.176252 | localhost | skipping: Conditional result was False 2026-03-11 16:45:52.176647 | 2026-03-11 16:45:52.181452 | localhost | skipping: Conditional result was False 2026-03-11 16:45:52.199347 | 2026-03-11 16:45:52.199537 | LOOP [upload-logs : Upload console log and json output]