2026-06-04 01:26:03.699592 | Job console starting 2026-06-04 01:26:03.709779 | Updating git repos 2026-06-04 01:26:03.736088 | Cloning repos into workspace 2026-06-04 01:26:03.781065 | Restoring repo states 2026-06-04 01:26:03.788628 | Merging changes 2026-06-04 01:26:04.040021 | Checking out repos 2026-06-04 01:26:04.064223 | Preparing playbooks 2026-06-04 01:26:05.126407 | Running Ansible setup 2026-06-04 01:26:09.124328 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-06-04 01:26:09.776202 | 2026-06-04 01:26:09.822540 | PLAY [localhost] 2026-06-04 01:26:09.835574 | 2026-06-04 01:26:09.835651 | TASK [Gathering Facts] 2026-06-04 01:26:10.919063 | localhost | ok 2026-06-04 01:26:10.931498 | 2026-06-04 01:26:10.931577 | TASK [Setup log path fact] 2026-06-04 01:26:10.954006 | localhost | ok 2026-06-04 01:26:10.979597 | 2026-06-04 01:26:10.979833 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-04 01:26:11.014983 | localhost | ok 2026-06-04 01:26:11.025245 | 2026-06-04 01:26:11.025341 | TASK [emit-job-header : Print job information] 2026-06-04 01:26:11.068268 | # Job Information 2026-06-04 01:26:11.068449 | Ansible Version: 2.16.18 2026-06-04 01:26:11.068504 | Job: ansible-collection-containers-molecule-docker-rockylinux-9 2026-06-04 01:26:11.068547 | Pipeline: check 2026-06-04 01:26:11.068586 | Executor: 2d72f0692154 2026-06-04 01:26:11.068618 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/128 2026-06-04 01:26:11.068651 | Event ID: 413217f0-5fb4-11f1-9626-f3f7c026e293 2026-06-04 01:26:11.076700 | 2026-06-04 01:26:11.076799 | LOOP [emit-job-header : Print node information] 2026-06-04 01:26:11.168103 | localhost | ok: 2026-06-04 01:26:11.168298 | localhost | # Node Information 2026-06-04 01:26:11.168332 | localhost | Inventory Hostname: rockylinux-9 2026-06-04 01:26:11.168359 | localhost | Hostname: np0000178458 2026-06-04 01:26:11.168383 | localhost | Username: zuul 2026-06-04 01:26:11.168410 | localhost | Distro: Rocky 9.7 2026-06-04 01:26:11.168430 | localhost | Provider: yul1 2026-06-04 01:26:11.168449 | localhost | Region: ca-ymq-1 2026-06-04 01:26:11.168467 | localhost | Label: rockylinux-9 2026-06-04 01:26:11.168486 | localhost | Product Name: OpenStack Nova 2026-06-04 01:26:11.168505 | localhost | Interface IP: 199.204.45.3 2026-06-04 01:26:11.180114 | 2026-06-04 01:26:11.180270 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-06-04 01:26:11.610984 | localhost -> localhost | changed 2026-06-04 01:26:11.617476 | 2026-06-04 01:26:11.617546 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-06-04 01:26:12.668173 | localhost -> localhost | changed 2026-06-04 01:26:12.678007 | 2026-06-04 01:26:12.678072 | PLAY [all] 2026-06-04 01:26:12.686902 | 2026-06-04 01:26:12.686977 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-06-04 01:26:13.011139 | rockylinux-9 -> localhost | ok 2026-06-04 01:26:13.019679 | 2026-06-04 01:26:13.019777 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-06-04 01:26:13.047060 | rockylinux-9 | ok 2026-06-04 01:26:13.063804 | rockylinux-9 | included: /var/lib/zuul/builds/3b62b04f40364fccb208adfe56b3e007/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-06-04 01:26:13.073549 | 2026-06-04 01:26:13.073618 | TASK [add-build-sshkey : Create Temp SSH key] 2026-06-04 01:26:14.569781 | rockylinux-9 -> localhost | Generating public/private rsa key pair. 2026-06-04 01:26:14.569954 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/3b62b04f40364fccb208adfe56b3e007/work/3b62b04f40364fccb208adfe56b3e007_id_rsa 2026-06-04 01:26:14.569984 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/3b62b04f40364fccb208adfe56b3e007/work/3b62b04f40364fccb208adfe56b3e007_id_rsa.pub 2026-06-04 01:26:14.570007 | rockylinux-9 -> localhost | The key fingerprint is: 2026-06-04 01:26:14.570030 | rockylinux-9 -> localhost | SHA256:F6HxJtIk/U5L1U9mVwm/2UABJaH/Iq4PQ6NBHpygoVI zuul-build-sshkey 2026-06-04 01:26:14.570061 | rockylinux-9 -> localhost | The key's randomart image is: 2026-06-04 01:26:14.570083 | rockylinux-9 -> localhost | +---[RSA 3072]----+ 2026-06-04 01:26:14.570107 | rockylinux-9 -> localhost | | E . ..o . +*=.+| 2026-06-04 01:26:14.570128 | rockylinux-9 -> localhost | | o o o =.+ o.oo.=| 2026-06-04 01:26:14.570149 | rockylinux-9 -> localhost | |o . * +.=. .*.| 2026-06-04 01:26:14.570169 | rockylinux-9 -> localhost | |. o o o+o .=| 2026-06-04 01:26:14.570189 | rockylinux-9 -> localhost | | o S+... o.| 2026-06-04 01:26:14.570209 | rockylinux-9 -> localhost | | + oo . | 2026-06-04 01:26:14.570229 | rockylinux-9 -> localhost | | . o . . . | 2026-06-04 01:26:14.570249 | rockylinux-9 -> localhost | | + . . | 2026-06-04 01:26:14.570272 | rockylinux-9 -> localhost | | .oo | 2026-06-04 01:26:14.570292 | rockylinux-9 -> localhost | +----[SHA256]-----+ 2026-06-04 01:26:14.570343 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.879304 2026-06-04 01:26:14.575570 | 2026-06-04 01:26:14.575634 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-06-04 01:26:14.609062 | rockylinux-9 | ok 2026-06-04 01:26:14.617436 | rockylinux-9 | included: /var/lib/zuul/builds/3b62b04f40364fccb208adfe56b3e007/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-06-04 01:26:14.624204 | 2026-06-04 01:26:14.624264 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-06-04 01:26:14.648416 | rockylinux-9 | skipping: Conditional result was False 2026-06-04 01:26:14.658353 | 2026-06-04 01:26:14.658441 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-06-04 01:26:15.139276 | rockylinux-9 | changed 2026-06-04 01:26:15.219279 | 2026-06-04 01:26:15.219417 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-06-04 01:26:15.497397 | rockylinux-9 | ok 2026-06-04 01:26:15.502234 | 2026-06-04 01:26:15.502302 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-06-04 01:26:16.445056 | rockylinux-9 | changed 2026-06-04 01:26:16.450654 | 2026-06-04 01:26:16.450725 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-06-04 01:26:17.356872 | rockylinux-9 | changed 2026-06-04 01:26:17.362372 | 2026-06-04 01:26:17.362442 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-06-04 01:26:17.387857 | rockylinux-9 | skipping: Conditional result was False 2026-06-04 01:26:17.396488 | 2026-06-04 01:26:17.396626 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-06-04 01:26:17.829531 | rockylinux-9 -> localhost | changed 2026-06-04 01:26:17.844108 | 2026-06-04 01:26:17.844175 | TASK [add-build-sshkey : Add back temp key] 2026-06-04 01:26:18.169129 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/3b62b04f40364fccb208adfe56b3e007/work/3b62b04f40364fccb208adfe56b3e007_id_rsa (zuul-build-sshkey) 2026-06-04 01:26:18.169357 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.018000 2026-06-04 01:26:18.176096 | 2026-06-04 01:26:18.176242 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-06-04 01:26:18.558029 | rockylinux-9 | ok 2026-06-04 01:26:18.736618 | 2026-06-04 01:26:18.736727 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-06-04 01:26:18.762100 | rockylinux-9 | skipping: Conditional result was False 2026-06-04 01:26:18.778577 | 2026-06-04 01:26:18.778706 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-06-04 01:26:19.171213 | rockylinux-9 | ok 2026-06-04 01:26:19.181869 | 2026-06-04 01:26:19.181934 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-06-04 01:26:20.218480 | rockylinux-9 | Output suppressed because no_log was given 2026-06-04 01:26:20.226484 | 2026-06-04 01:26:20.226549 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-06-04 01:26:20.479264 | rockylinux-9 | ok: "logs" 2026-06-04 01:26:20.479518 | rockylinux-9 | ok: All items complete 2026-06-04 01:26:20.479546 | 2026-06-04 01:26:20.691773 | rockylinux-9 | ok: "artifacts" 2026-06-04 01:26:20.915656 | rockylinux-9 | ok: "docs" 2026-06-04 01:26:20.934970 | 2026-06-04 01:26:20.935167 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-06-04 01:26:21.213842 | rockylinux-9 | changed: "logs" 2026-06-04 01:26:21.489978 | rockylinux-9 | changed: "artifacts" 2026-06-04 01:26:21.671908 | rockylinux-9 | changed: "docs" 2026-06-04 01:26:21.685152 | 2026-06-04 01:26:21.685322 | PLAY RECAP 2026-06-04 01:26:21.686255 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-06-04 01:26:21.686302 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-06-04 01:26:21.686332 | 2026-06-04 01:26:21.841419 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-06-04 01:26:21.843274 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-06-04 01:26:22.411708 | 2026-06-04 01:26:22.411835 | PLAY [all] 2026-06-04 01:26:22.422054 | 2026-06-04 01:26:22.422127 | TASK [setup-uv : Extract archive] 2026-06-04 01:26:25.302404 | rockylinux-9 | changed 2026-06-04 01:26:25.308914 | 2026-06-04 01:26:25.308994 | TASK [setup-uv : Print version] 2026-06-04 01:26:25.331605 | [rockylinux-9] Waiting on logger 2026-06-04 01:26:25.848735 | rockylinux-9 | ok: Runtime: 0:00:00.016530 2026-06-04 01:26:26.139538 | 2026-06-04 01:26:26.139670 | TASK [Install Ansible collection siblings] 2026-06-04 01:26:26.577671 | rockylinux-9 | ok: 2026-06-04 01:26:26.577872 | rockylinux-9 | Skipping ansible.posix: no checked-out sibling 2026-06-04 01:26:26.577913 | rockylinux-9 | Skipping community.general: no checked-out sibling 2026-06-04 01:26:26.580504 | 2026-06-04 01:26:26.580672 | PLAY RECAP 2026-06-04 01:26:26.580968 | rockylinux-9 | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-06-04 01:26:26.581025 | 2026-06-04 01:26:26.715405 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-06-04 01:26:26.767149 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-06-04 01:26:27.457248 | 2026-06-04 01:26:27.457409 | PLAY [all] 2026-06-04 01:26:27.468793 | 2026-06-04 01:26:27.468881 | TASK [Copy inventory file for Zuul] 2026-06-04 01:26:28.797622 | rockylinux-9 | changed 2026-06-04 01:26:28.893892 | 2026-06-04 01:26:28.894043 | TASK [Switch "ansible_host" to private IP] 2026-06-04 01:26:29.291104 | rockylinux-9 | changed: 1 replacements made 2026-06-04 01:26:29.537906 | 2026-06-04 01:26:29.538050 | TASK [Run Molecule scenario] 2026-06-04 01:26:29.561703 | [rockylinux-9] Waiting on logger 2026-06-04 01:26:36.682152 | [rockylinux-9] Waiting on logger 2026-06-04 01:26:46.854073 | [rockylinux-9] Waiting on logger 2026-06-04 01:26:57.032379 | [rockylinux-9] Waiting on logger 2026-06-04 01:27:07.206313 | [rockylinux-9] Waiting on logger 2026-06-04 01:27:16.363942 | [rockylinux-9] Waiting on logger 2026-06-04 01:27:26.534176 | [rockylinux-9] Waiting on logger 2026-06-04 01:27:36.710030 | [rockylinux-9] Waiting on logger 2026-06-04 01:27:46.889787 | [rockylinux-9] Waiting on logger 2026-06-04 01:27:57.062086 | [rockylinux-9] Waiting on logger 2026-06-04 01:28:07.238124 | [rockylinux-9] Waiting on logger 2026-06-04 01:28:16.395111 | [rockylinux-9] Waiting on logger 2026-06-04 01:28:26.566304 | [rockylinux-9] Waiting on logger 2026-06-04 01:28:36.742287 | [rockylinux-9] Waiting on logger 2026-06-04 01:28:41.228172 | rockylinux-9 | ok: Runtime: 0:02:11.102589 2026-06-04 01:28:41.236539 | 2026-06-04 01:28:41.236658 | PLAY RECAP 2026-06-04 01:28:41.236722 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-06-04 01:28:41.236755 | 2026-06-04 01:28:41.380008 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-06-04 01:28:41.382727 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-06-04 01:28:41.984322 | 2026-06-04 01:28:41.985001 | PLAY [all] 2026-06-04 01:28:41.995521 | 2026-06-04 01:28:41.995593 | TASK [fetch-output : Set log path for multiple nodes] 2026-06-04 01:28:42.040890 | rockylinux-9 | skipping: Conditional result was False 2026-06-04 01:28:42.050102 | 2026-06-04 01:28:42.050258 | TASK [fetch-output : Set log path for single node] 2026-06-04 01:28:42.093263 | rockylinux-9 | ok 2026-06-04 01:28:42.100337 | 2026-06-04 01:28:42.100402 | LOOP [fetch-output : Ensure local output dirs] 2026-06-04 01:28:42.480298 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/3b62b04f40364fccb208adfe56b3e007/work/logs" 2026-06-04 01:28:42.688109 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/3b62b04f40364fccb208adfe56b3e007/work/artifacts" 2026-06-04 01:28:42.894691 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/3b62b04f40364fccb208adfe56b3e007/work/docs" 2026-06-04 01:28:42.915543 | 2026-06-04 01:28:42.915717 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-06-04 01:28:43.626332 | rockylinux-9 | changed: .d..t...... ./ 2026-06-04 01:28:43.626595 | rockylinux-9 | changed: All items complete 2026-06-04 01:28:43.626634 | 2026-06-04 01:28:44.152868 | rockylinux-9 | changed: .d..t...... ./ 2026-06-04 01:28:44.671485 | rockylinux-9 | changed: .d..t...... ./ 2026-06-04 01:28:44.697431 | 2026-06-04 01:28:44.697566 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-06-04 01:28:45.123491 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.007993 2026-06-04 01:28:45.340759 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.007217 2026-06-04 01:28:45.354022 | 2026-06-04 01:28:45.354176 | PLAY [all] 2026-06-04 01:28:45.360315 | 2026-06-04 01:28:45.360384 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-06-04 01:28:45.850084 | rockylinux-9 | changed 2026-06-04 01:28:45.857211 | 2026-06-04 01:28:45.857282 | PLAY RECAP 2026-06-04 01:28:45.857342 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-06-04 01:28:45.857372 | 2026-06-04 01:28:45.980454 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-06-04 01:28:45.982942 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-06-04 01:28:46.550621 | 2026-06-04 01:28:46.551195 | PLAY [localhost] 2026-06-04 01:28:46.561657 | 2026-06-04 01:28:46.561732 | TASK [Generate Zuul manifest] 2026-06-04 01:28:46.580650 | localhost | ok 2026-06-04 01:28:46.598823 | 2026-06-04 01:28:46.598899 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-06-04 01:28:46.974006 | localhost | changed 2026-06-04 01:28:46.986995 | 2026-06-04 01:28:46.987083 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-06-04 01:28:47.022522 | localhost | ok 2026-06-04 01:28:47.031666 | 2026-06-04 01:28:47.031756 | TASK [Upload logs] 2026-06-04 01:28:47.054393 | localhost | ok 2026-06-04 01:28:47.114424 | 2026-06-04 01:28:47.114555 | TASK [Set zuul-log-path fact] 2026-06-04 01:28:47.134409 | localhost | ok 2026-06-04 01:28:47.144930 | 2026-06-04 01:28:47.145029 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-04 01:28:47.175511 | localhost | ok 2026-06-04 01:28:47.186607 | 2026-06-04 01:28:47.186731 | TASK [upload-logs : Create log directories] 2026-06-04 01:28:47.595604 | localhost | changed 2026-06-04 01:28:47.603737 | 2026-06-04 01:28:47.603852 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-06-04 01:28:48.009361 | localhost -> localhost | ok: Runtime: 0:00:00.005469 2026-06-04 01:28:48.016456 | 2026-06-04 01:28:48.016583 | TASK [upload-logs : Upload logs to log server] 2026-06-04 01:28:48.467588 | localhost | Output suppressed because no_log was given 2026-06-04 01:28:48.472728 | 2026-06-04 01:28:48.472818 | LOOP [upload-logs : Compress console log and json output] 2026-06-04 01:28:48.521370 | localhost | skipping: Conditional result was False 2026-06-04 01:28:48.529027 | localhost | skipping: Conditional result was False 2026-06-04 01:28:48.546250 | 2026-06-04 01:28:48.546397 | LOOP [upload-logs : Upload compressed console log and json output] 2026-06-04 01:28:48.595686 | localhost | skipping: Conditional result was False 2026-06-04 01:28:48.596059 | 2026-06-04 01:28:48.601329 | localhost | skipping: Conditional result was False 2026-06-04 01:28:48.610828 | 2026-06-04 01:28:48.611030 | LOOP [upload-logs : Upload console log and json output]