2026-04-27 11:42:54.269627 | Job console starting 2026-04-27 11:42:54.280564 | Updating git repos 2026-04-27 11:42:54.306514 | Cloning repos into workspace 2026-04-27 11:42:54.437663 | Restoring repo states 2026-04-27 11:42:54.447752 | Merging changes 2026-04-27 11:42:54.731968 | Checking out repos 2026-04-27 11:42:54.782529 | Preparing playbooks 2026-04-27 11:42:57.564951 | Running Ansible setup 2026-04-27 11:43:02.514770 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-27 11:43:03.413493 | 2026-04-27 11:43:03.413630 | PLAY [localhost] 2026-04-27 11:43:03.421646 | 2026-04-27 11:43:03.421743 | TASK [Gathering Facts] 2026-04-27 11:43:04.597466 | localhost | ok 2026-04-27 11:43:04.609862 | 2026-04-27 11:43:04.610015 | TASK [Setup log path fact] 2026-04-27 11:43:04.634064 | localhost | ok 2026-04-27 11:43:04.649963 | 2026-04-27 11:43:04.650293 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-27 11:43:04.696733 | localhost | ok 2026-04-27 11:43:04.721243 | 2026-04-27 11:43:04.721395 | TASK [emit-job-header : Print job information] 2026-04-27 11:43:04.750714 | # Job Information 2026-04-27 11:43:04.750906 | Ansible Version: 2.16.16 2026-04-27 11:43:04.750955 | Job: ansible-collection-containers-molecule-containerd-rockylinux-9 2026-04-27 11:43:04.750990 | Pipeline: check 2026-04-27 11:43:04.751022 | Executor: 0a8996d2b663 2026-04-27 11:43:04.751052 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/124 2026-04-27 11:43:04.751086 | Event ID: cd948420-422d-11f1-9e04-109c29d93f2e 2026-04-27 11:43:04.766258 | 2026-04-27 11:43:04.769100 | LOOP [emit-job-header : Print node information] 2026-04-27 11:43:04.886967 | localhost | ok: 2026-04-27 11:43:04.887260 | localhost | # Node Information 2026-04-27 11:43:04.887313 | localhost | Inventory Hostname: rockylinux-9 2026-04-27 11:43:04.887346 | localhost | Hostname: np0000170487 2026-04-27 11:43:04.887376 | localhost | Username: zuul 2026-04-27 11:43:04.887408 | localhost | Distro: Rocky 9.7 2026-04-27 11:43:04.887438 | localhost | Provider: yul1 2026-04-27 11:43:04.887467 | localhost | Region: ca-ymq-1 2026-04-27 11:43:04.887495 | localhost | Label: rockylinux-9 2026-04-27 11:43:04.887522 | localhost | Product Name: OpenStack Nova 2026-04-27 11:43:04.887549 | localhost | Interface IP: 199.204.45.116 2026-04-27 11:43:04.906325 | 2026-04-27 11:43:04.906495 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-04-27 11:43:05.444989 | localhost -> localhost | changed 2026-04-27 11:43:05.450050 | 2026-04-27 11:43:05.450136 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-04-27 11:43:07.064057 | localhost -> localhost | changed 2026-04-27 11:43:07.075433 | 2026-04-27 11:43:07.075544 | PLAY [all] 2026-04-27 11:43:07.098114 | 2026-04-27 11:43:07.098269 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-04-27 11:43:07.378241 | rockylinux-9 -> localhost | ok 2026-04-27 11:43:07.385681 | 2026-04-27 11:43:07.385836 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-04-27 11:43:07.425357 | rockylinux-9 | ok 2026-04-27 11:43:07.452392 | rockylinux-9 | included: /var/lib/zuul/builds/a2b202381f494f59a72691b606f6bdee/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-04-27 11:43:07.462061 | 2026-04-27 11:43:07.462211 | TASK [add-build-sshkey : Create Temp SSH key] 2026-04-27 11:43:08.586120 | rockylinux-9 -> localhost | Generating public/private rsa key pair. 2026-04-27 11:43:08.586319 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/a2b202381f494f59a72691b606f6bdee/work/a2b202381f494f59a72691b606f6bdee_id_rsa 2026-04-27 11:43:08.586368 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/a2b202381f494f59a72691b606f6bdee/work/a2b202381f494f59a72691b606f6bdee_id_rsa.pub 2026-04-27 11:43:08.586403 | rockylinux-9 -> localhost | The key fingerprint is: 2026-04-27 11:43:08.586437 | rockylinux-9 -> localhost | SHA256:KIlOFqrpaMWpCMun1oX2Jb4IqGBIiXhXnYzsiDC1np8 zuul-build-sshkey 2026-04-27 11:43:08.586485 | rockylinux-9 -> localhost | The key's randomart image is: 2026-04-27 11:43:08.586518 | rockylinux-9 -> localhost | +---[RSA 3072]----+ 2026-04-27 11:43:08.586560 | rockylinux-9 -> localhost | | . | 2026-04-27 11:43:08.586596 | rockylinux-9 -> localhost | | . . . + . | 2026-04-27 11:43:08.586627 | rockylinux-9 -> localhost | |o o + + | 2026-04-27 11:43:08.586657 | rockylinux-9 -> localhost | |o=.= = . | 2026-04-27 11:43:08.586687 | rockylinux-9 -> localhost | |+oX B o S | 2026-04-27 11:43:08.586716 | rockylinux-9 -> localhost | |** O = . | 2026-04-27 11:43:08.586745 | rockylinux-9 -> localhost | |X+* E o | 2026-04-27 11:43:08.586775 | rockylinux-9 -> localhost | |O=.o.o | 2026-04-27 11:43:08.586808 | rockylinux-9 -> localhost | |=oo. .. | 2026-04-27 11:43:08.586838 | rockylinux-9 -> localhost | +----[SHA256]-----+ 2026-04-27 11:43:08.586907 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.597656 2026-04-27 11:43:08.593400 | 2026-04-27 11:43:08.593485 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-04-27 11:43:08.625974 | rockylinux-9 | ok 2026-04-27 11:43:08.639137 | rockylinux-9 | included: /var/lib/zuul/builds/a2b202381f494f59a72691b606f6bdee/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-04-27 11:43:08.652340 | 2026-04-27 11:43:08.652482 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-04-27 11:43:08.677183 | rockylinux-9 | skipping: Conditional result was False 2026-04-27 11:43:08.695840 | 2026-04-27 11:43:08.696042 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-04-27 11:43:09.215895 | rockylinux-9 | changed 2026-04-27 11:43:09.221410 | 2026-04-27 11:43:09.221550 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-04-27 11:43:09.465360 | rockylinux-9 | ok 2026-04-27 11:43:09.470113 | 2026-04-27 11:43:09.470223 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-04-27 11:43:10.450365 | rockylinux-9 | changed 2026-04-27 11:43:10.457487 | 2026-04-27 11:43:10.457633 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-04-27 11:43:11.397353 | rockylinux-9 | changed 2026-04-27 11:43:11.402756 | 2026-04-27 11:43:11.402844 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-04-27 11:43:11.430292 | rockylinux-9 | skipping: Conditional result was False 2026-04-27 11:43:11.438313 | 2026-04-27 11:43:11.438494 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-04-27 11:43:11.921933 | rockylinux-9 -> localhost | changed 2026-04-27 11:43:11.938297 | 2026-04-27 11:43:11.938490 | TASK [add-build-sshkey : Add back temp key] 2026-04-27 11:43:12.353165 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/a2b202381f494f59a72691b606f6bdee/work/a2b202381f494f59a72691b606f6bdee_id_rsa (zuul-build-sshkey) 2026-04-27 11:43:12.353399 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.011708 2026-04-27 11:43:12.365351 | 2026-04-27 11:43:12.365510 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-04-27 11:43:12.774752 | rockylinux-9 | ok 2026-04-27 11:43:12.789284 | 2026-04-27 11:43:12.789455 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-04-27 11:43:12.815078 | rockylinux-9 | skipping: Conditional result was False 2026-04-27 11:43:12.827443 | 2026-04-27 11:43:12.827605 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-04-27 11:43:13.255849 | rockylinux-9 | ok 2026-04-27 11:43:13.269149 | 2026-04-27 11:43:13.269300 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-04-27 11:43:14.462938 | rockylinux-9 | Output suppressed because no_log was given 2026-04-27 11:43:14.471281 | 2026-04-27 11:43:14.471405 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-04-27 11:43:14.758450 | rockylinux-9 | ok: "logs" 2026-04-27 11:43:14.809159 | rockylinux-9 | ok: All items complete 2026-04-27 11:43:14.809279 | 2026-04-27 11:43:14.986298 | rockylinux-9 | ok: "artifacts" 2026-04-27 11:43:15.217413 | rockylinux-9 | ok: "docs" 2026-04-27 11:43:15.420992 | 2026-04-27 11:43:15.421097 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-04-27 11:43:15.699764 | rockylinux-9 | changed: "logs" 2026-04-27 11:43:16.004021 | rockylinux-9 | changed: "artifacts" 2026-04-27 11:43:16.235237 | rockylinux-9 | changed: "docs" 2026-04-27 11:43:16.243818 | 2026-04-27 11:43:16.243921 | PLAY RECAP 2026-04-27 11:43:16.243975 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-27 11:43:16.244009 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-04-27 11:43:16.244032 | 2026-04-27 11:43:16.802134 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-27 11:43:17.189152 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-27 11:43:17.902383 | 2026-04-27 11:43:17.902799 | PLAY [all] 2026-04-27 11:43:17.916433 | 2026-04-27 11:43:17.916623 | TASK [setup-uv : Extract archive] 2026-04-27 11:43:21.132029 | rockylinux-9 | changed 2026-04-27 11:43:21.138128 | 2026-04-27 11:43:21.138209 | TASK [setup-uv : Print version] 2026-04-27 11:43:21.162446 | [rockylinux-9] Waiting on logger 2026-04-27 11:43:21.617994 | rockylinux-9 | ok: Runtime: 0:00:00.019145 2026-04-27 11:43:21.623998 | 2026-04-27 11:43:21.624068 | PLAY RECAP 2026-04-27 11:43:21.624114 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-27 11:43:21.624137 | 2026-04-27 11:43:21.852802 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-27 11:43:21.854680 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-27 11:43:22.848251 | 2026-04-27 11:43:22.848457 | PLAY [all] 2026-04-27 11:43:22.863743 | 2026-04-27 11:43:22.863996 | TASK [Copy inventory file for Zuul] 2026-04-27 11:43:24.421204 | rockylinux-9 | changed 2026-04-27 11:43:24.426860 | 2026-04-27 11:43:24.426960 | TASK [Switch "ansible_host" to private IP] 2026-04-27 11:43:24.898312 | rockylinux-9 | changed: 1 replacements made 2026-04-27 11:43:24.906627 | 2026-04-27 11:43:24.906779 | TASK [Run Molecule scenario] 2026-04-27 11:43:24.935556 | [rockylinux-9] Waiting on logger 2026-04-27 11:43:32.070177 | [rockylinux-9] Waiting on logger 2026-04-27 11:43:41.227406 | [rockylinux-9] Waiting on logger 2026-04-27 11:43:51.397953 | [rockylinux-9] Waiting on logger 2026-04-27 11:44:01.574098 | [rockylinux-9] Waiting on logger 2026-04-27 11:44:11.750416 | [rockylinux-9] Waiting on logger 2026-04-27 11:44:21.926251 | [rockylinux-9] Waiting on logger 2026-04-27 11:44:32.102137 | [rockylinux-9] Waiting on logger 2026-04-27 11:44:41.259774 | [rockylinux-9] Waiting on logger 2026-04-27 11:44:51.462394 | [rockylinux-9] Waiting on logger 2026-04-27 11:45:01.641946 | [rockylinux-9] Waiting on logger 2026-04-27 11:45:11.817985 | [rockylinux-9] Waiting on logger 2026-04-27 11:45:21.989982 | [rockylinux-9] Waiting on logger 2026-04-27 11:45:32.166251 | [rockylinux-9] Waiting on logger 2026-04-27 11:45:41.318097 | [rockylinux-9] Waiting on logger 2026-04-27 11:45:51.498079 | [rockylinux-9] Waiting on logger 2026-04-27 11:46:01.670152 | [rockylinux-9] Waiting on logger 2026-04-27 11:46:11.846172 | [rockylinux-9] Waiting on logger 2026-04-27 11:46:22.021983 | [rockylinux-9] Waiting on logger 2026-04-27 11:46:31.179432 | [rockylinux-9] Waiting on logger 2026-04-27 11:46:41.350222 | [rockylinux-9] Waiting on logger 2026-04-27 11:46:51.527927 | [rockylinux-9] Waiting on logger 2026-04-27 11:47:01.701973 | [rockylinux-9] Waiting on logger 2026-04-27 11:47:11.877957 | [rockylinux-9] Waiting on logger 2026-04-27 11:47:22.058150 | [rockylinux-9] Waiting on logger 2026-04-27 11:47:31.210845 | [rockylinux-9] Waiting on logger 2026-04-27 11:47:41.382225 | [rockylinux-9] Waiting on logger 2026-04-27 11:47:51.558105 | [rockylinux-9] Waiting on logger 2026-04-27 11:48:01.734210 | [rockylinux-9] Waiting on logger 2026-04-27 11:48:11.910024 | [rockylinux-9] Waiting on logger 2026-04-27 11:48:22.094340 | rockylinux-9 | ok: Runtime: 0:04:55.743893 2026-04-27 11:48:22.102592 | 2026-04-27 11:48:22.102651 | PLAY RECAP 2026-04-27 11:48:22.102699 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-27 11:48:22.102722 | 2026-04-27 11:48:22.288997 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-27 11:48:22.292244 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-27 11:48:22.946271 | 2026-04-27 11:48:22.946417 | PLAY [all] 2026-04-27 11:48:22.958188 | 2026-04-27 11:48:22.958290 | TASK [fetch-output : Set log path for multiple nodes] 2026-04-27 11:48:23.004020 | rockylinux-9 | skipping: Conditional result was False 2026-04-27 11:48:23.013381 | 2026-04-27 11:48:23.013538 | TASK [fetch-output : Set log path for single node] 2026-04-27 11:48:23.058033 | rockylinux-9 | ok 2026-04-27 11:48:23.064333 | 2026-04-27 11:48:23.064412 | LOOP [fetch-output : Ensure local output dirs] 2026-04-27 11:48:23.480705 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/a2b202381f494f59a72691b606f6bdee/work/logs" 2026-04-27 11:48:23.692998 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/a2b202381f494f59a72691b606f6bdee/work/artifacts" 2026-04-27 11:48:23.909395 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/a2b202381f494f59a72691b606f6bdee/work/docs" 2026-04-27 11:48:23.926989 | 2026-04-27 11:48:23.927147 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-04-27 11:48:24.666270 | rockylinux-9 | changed: .d..t...... ./ 2026-04-27 11:48:24.666486 | rockylinux-9 | changed: All items complete 2026-04-27 11:48:24.666517 | 2026-04-27 11:48:25.200439 | rockylinux-9 | changed: .d..t...... ./ 2026-04-27 11:48:25.721569 | rockylinux-9 | changed: .d..t...... ./ 2026-04-27 11:48:25.745960 | 2026-04-27 11:48:25.746244 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-04-27 11:48:26.219265 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.005603 2026-04-27 11:48:26.467271 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.009194 2026-04-27 11:48:26.485807 | 2026-04-27 11:48:26.485958 | PLAY [all] 2026-04-27 11:48:26.493908 | 2026-04-27 11:48:26.494048 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-04-27 11:48:27.031309 | rockylinux-9 | changed 2026-04-27 11:48:27.037852 | 2026-04-27 11:48:27.037942 | PLAY RECAP 2026-04-27 11:48:27.038034 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-04-27 11:48:27.038083 | 2026-04-27 11:48:27.179266 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-27 11:48:27.181112 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-04-27 11:48:27.789276 | 2026-04-27 11:48:27.789455 | PLAY [localhost] 2026-04-27 11:48:27.800791 | 2026-04-27 11:48:27.800888 | TASK [Generate Zuul manifest] 2026-04-27 11:48:27.822474 | localhost | ok 2026-04-27 11:48:27.840077 | 2026-04-27 11:48:27.840199 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-04-27 11:48:28.317963 | localhost | changed 2026-04-27 11:48:28.334249 | 2026-04-27 11:48:28.334391 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-04-27 11:48:28.365677 | localhost | ok 2026-04-27 11:48:28.374034 | 2026-04-27 11:48:28.374150 | TASK [Upload logs] 2026-04-27 11:48:28.395643 | localhost | ok 2026-04-27 11:48:28.454682 | 2026-04-27 11:48:28.454829 | TASK [Set zuul-log-path fact] 2026-04-27 11:48:28.476723 | localhost | ok 2026-04-27 11:48:28.489271 | 2026-04-27 11:48:28.489389 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-27 11:48:28.521489 | localhost | ok 2026-04-27 11:48:28.532356 | 2026-04-27 11:48:28.532479 | TASK [upload-logs : Create log directories] 2026-04-27 11:48:28.969286 | localhost | changed 2026-04-27 11:48:28.976146 | 2026-04-27 11:48:28.976286 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-04-27 11:48:29.392275 | localhost -> localhost | ok: Runtime: 0:00:00.006429 2026-04-27 11:48:29.399776 | 2026-04-27 11:48:29.399937 | TASK [upload-logs : Upload logs to log server] 2026-04-27 11:48:29.879926 | localhost | Output suppressed because no_log was given 2026-04-27 11:48:29.883591 | 2026-04-27 11:48:29.883660 | LOOP [upload-logs : Compress console log and json output] 2026-04-27 11:48:29.939763 | localhost | skipping: Conditional result was False 2026-04-27 11:48:29.948759 | localhost | skipping: Conditional result was False 2026-04-27 11:48:29.956703 | 2026-04-27 11:48:29.956870 | LOOP [upload-logs : Upload compressed console log and json output] 2026-04-27 11:48:30.012025 | localhost | skipping: Conditional result was False 2026-04-27 11:48:30.012358 | 2026-04-27 11:48:30.018617 | localhost | skipping: Conditional result was False 2026-04-27 11:48:30.031887 | 2026-04-27 11:48:30.032054 | LOOP [upload-logs : Upload console log and json output]