2026-06-03 01:27:43.211168 | Job console starting 2026-06-03 01:27:43.221635 | Updating git repos 2026-06-03 01:27:43.249554 | Cloning repos into workspace 2026-06-03 01:27:48.072817 | Restoring repo states 2026-06-03 01:27:48.079858 | Merging changes 2026-06-03 01:27:48.679415 | Checking out repos 2026-06-03 01:27:49.250472 | Preparing playbooks 2026-06-03 01:27:52.522498 | Running Ansible setup 2026-06-03 01:27:57.538698 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-06-03 01:27:58.295448 | 2026-06-03 01:27:58.295643 | PLAY [localhost] 2026-06-03 01:27:58.307649 | 2026-06-03 01:27:58.307827 | TASK [Gathering Facts] 2026-06-03 01:27:59.586408 | localhost | ok 2026-06-03 01:27:59.596573 | 2026-06-03 01:27:59.596709 | TASK [Setup log path fact] 2026-06-03 01:27:59.622867 | localhost | ok 2026-06-03 01:27:59.634328 | 2026-06-03 01:27:59.634710 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-03 01:27:59.663332 | localhost | ok 2026-06-03 01:27:59.670378 | 2026-06-03 01:27:59.670511 | TASK [emit-job-header : Print job information] 2026-06-03 01:27:59.711026 | # Job Information 2026-06-03 01:27:59.711225 | Ansible Version: 2.16.18 2026-06-03 01:27:59.711275 | Job: ansible-collection-containers-molecule-docker-rockylinux-9 2026-06-03 01:27:59.711310 | Pipeline: check 2026-06-03 01:27:59.711341 | Executor: 2d72f0692154 2026-06-03 01:27:59.711372 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/133 2026-06-03 01:27:59.711406 | Event ID: 2d998f80-5eeb-11f1-92ca-7829ebcb9ee8 2026-06-03 01:27:59.715983 | 2026-06-03 01:27:59.716093 | LOOP [emit-job-header : Print node information] 2026-06-03 01:27:59.813124 | localhost | ok: 2026-06-03 01:27:59.813525 | localhost | # Node Information 2026-06-03 01:27:59.813561 | localhost | Inventory Hostname: rockylinux-9 2026-06-03 01:27:59.813585 | localhost | Hostname: np0000178360 2026-06-03 01:27:59.813606 | localhost | Username: zuul 2026-06-03 01:27:59.813629 | localhost | Distro: Rocky 9.7 2026-06-03 01:27:59.813650 | localhost | Provider: yul1 2026-06-03 01:27:59.813671 | localhost | Region: ca-ymq-1 2026-06-03 01:27:59.813690 | localhost | Label: rockylinux-9 2026-06-03 01:27:59.813709 | localhost | Product Name: OpenStack Nova 2026-06-03 01:27:59.813728 | localhost | Interface IP: 199.19.213.156 2026-06-03 01:27:59.823547 | 2026-06-03 01:27:59.823695 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-06-03 01:28:00.227024 | localhost -> localhost | changed 2026-06-03 01:28:00.232859 | 2026-06-03 01:28:00.233119 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-06-03 01:28:01.193762 | localhost -> localhost | changed 2026-06-03 01:28:01.200268 | 2026-06-03 01:28:01.200370 | PLAY [all] 2026-06-03 01:28:01.218303 | 2026-06-03 01:28:01.218447 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-06-03 01:28:01.516920 | rockylinux-9 -> localhost | ok 2026-06-03 01:28:01.525697 | 2026-06-03 01:28:01.525868 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-06-03 01:28:01.557104 | rockylinux-9 | ok 2026-06-03 01:28:01.574166 | rockylinux-9 | included: /var/lib/zuul/builds/ee442054155d401c8046f07ec5b024c2/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-06-03 01:28:01.582702 | 2026-06-03 01:28:01.582821 | TASK [add-build-sshkey : Create Temp SSH key] 2026-06-03 01:28:02.530453 | rockylinux-9 -> localhost | Generating public/private rsa key pair. 2026-06-03 01:28:02.530761 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/ee442054155d401c8046f07ec5b024c2/work/ee442054155d401c8046f07ec5b024c2_id_rsa 2026-06-03 01:28:02.530835 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/ee442054155d401c8046f07ec5b024c2/work/ee442054155d401c8046f07ec5b024c2_id_rsa.pub 2026-06-03 01:28:02.530888 | rockylinux-9 -> localhost | The key fingerprint is: 2026-06-03 01:28:02.530939 | rockylinux-9 -> localhost | SHA256:gNo7S695vz9JTNwpj/B1qheGqZcd5fPxTCw9UV0bUrA zuul-build-sshkey 2026-06-03 01:28:02.531009 | rockylinux-9 -> localhost | The key's randomart image is: 2026-06-03 01:28:02.531058 | rockylinux-9 -> localhost | +---[RSA 3072]----+ 2026-06-03 01:28:02.531215 | rockylinux-9 -> localhost | | ooo+| 2026-06-03 01:28:02.531281 | rockylinux-9 -> localhost | | . o =| 2026-06-03 01:28:02.531331 | rockylinux-9 -> localhost | | . . . . E ..| 2026-06-03 01:28:02.531378 | rockylinux-9 -> localhost | | o .. + + o. | 2026-06-03 01:28:02.531425 | rockylinux-9 -> localhost | | . . S= B = o.| 2026-06-03 01:28:02.531472 | rockylinux-9 -> localhost | | . B * +o+| 2026-06-03 01:28:02.531517 | rockylinux-9 -> localhost | | + o * o *+| 2026-06-03 01:28:02.531564 | rockylinux-9 -> localhost | | . =. . * o +| 2026-06-03 01:28:02.531617 | rockylinux-9 -> localhost | | +o..o+.o | 2026-06-03 01:28:02.531665 | rockylinux-9 -> localhost | +----[SHA256]-----+ 2026-06-03 01:28:02.531775 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.365616 2026-06-03 01:28:02.537369 | 2026-06-03 01:28:02.537441 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-06-03 01:28:02.581224 | rockylinux-9 | ok 2026-06-03 01:28:02.589431 | rockylinux-9 | included: /var/lib/zuul/builds/ee442054155d401c8046f07ec5b024c2/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-06-03 01:28:02.596438 | 2026-06-03 01:28:02.596504 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-06-03 01:28:02.622032 | rockylinux-9 | skipping: Conditional result was False 2026-06-03 01:28:02.634670 | 2026-06-03 01:28:02.634764 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-06-03 01:28:03.133963 | rockylinux-9 | changed 2026-06-03 01:28:03.141745 | 2026-06-03 01:28:03.141879 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-06-03 01:28:03.418195 | rockylinux-9 | ok 2026-06-03 01:28:03.425185 | 2026-06-03 01:28:03.425282 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-06-03 01:28:04.329915 | rockylinux-9 | changed 2026-06-03 01:28:04.334610 | 2026-06-03 01:28:04.334678 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-06-03 01:28:05.261874 | rockylinux-9 | changed 2026-06-03 01:28:05.623652 | 2026-06-03 01:28:05.623797 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-06-03 01:28:05.653774 | rockylinux-9 | skipping: Conditional result was False 2026-06-03 01:28:05.660467 | 2026-06-03 01:28:05.660578 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-06-03 01:28:06.141530 | rockylinux-9 -> localhost | changed 2026-06-03 01:28:06.155979 | 2026-06-03 01:28:06.156187 | TASK [add-build-sshkey : Add back temp key] 2026-06-03 01:28:06.507211 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/ee442054155d401c8046f07ec5b024c2/work/ee442054155d401c8046f07ec5b024c2_id_rsa (zuul-build-sshkey) 2026-06-03 01:28:08.253364 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.019286 2026-06-03 01:28:08.260669 | 2026-06-03 01:28:08.260877 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-06-03 01:28:08.765973 | rockylinux-9 | ok 2026-06-03 01:28:08.780388 | 2026-06-03 01:28:08.780593 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-06-03 01:28:08.826058 | rockylinux-9 | skipping: Conditional result was False 2026-06-03 01:28:08.835537 | 2026-06-03 01:28:08.835608 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-06-03 01:28:09.301880 | rockylinux-9 | ok 2026-06-03 01:28:09.307960 | 2026-06-03 01:28:09.308046 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-06-03 01:28:10.302658 | rockylinux-9 | Output suppressed because no_log was given 2026-06-03 01:28:10.445539 | 2026-06-03 01:28:10.445694 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-06-03 01:28:10.702778 | rockylinux-9 | ok: "logs" 2026-06-03 01:28:10.703103 | rockylinux-9 | ok: All items complete 2026-06-03 01:28:10.703147 | 2026-06-03 01:28:10.918796 | rockylinux-9 | ok: "artifacts" 2026-06-03 01:28:11.136738 | rockylinux-9 | ok: "docs" 2026-06-03 01:28:11.145110 | 2026-06-03 01:28:11.145245 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-06-03 01:28:11.428157 | rockylinux-9 | changed: "logs" 2026-06-03 01:28:11.749654 | rockylinux-9 | changed: "artifacts" 2026-06-03 01:28:11.891213 | rockylinux-9 | changed: "docs" 2026-06-03 01:28:11.902471 | 2026-06-03 01:28:11.902598 | PLAY RECAP 2026-06-03 01:28:11.902649 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-06-03 01:28:11.902681 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-06-03 01:28:11.902704 | 2026-06-03 01:28:12.067405 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-06-03 01:28:12.068811 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-06-03 01:28:12.759063 | 2026-06-03 01:28:12.759265 | PLAY [all] 2026-06-03 01:28:12.772331 | 2026-06-03 01:28:12.772510 | TASK [setup-uv : Extract archive] 2026-06-03 01:28:15.500067 | rockylinux-9 | changed 2026-06-03 01:28:15.507392 | 2026-06-03 01:28:15.507511 | TASK [setup-uv : Print version] 2026-06-03 01:28:15.537136 | [rockylinux-9] Waiting on logger 2026-06-03 01:28:16.054419 | rockylinux-9 | ok: Runtime: 0:00:00.015144 2026-06-03 01:28:16.071677 | 2026-06-03 01:28:16.071889 | TASK [Install Ansible collection siblings] 2026-06-03 01:28:16.586819 | rockylinux-9 | ok: 2026-06-03 01:28:16.587016 | rockylinux-9 | Skipping ansible.posix: no checked-out sibling 2026-06-03 01:28:16.587047 | rockylinux-9 | Skipping community.general: no checked-out sibling 2026-06-03 01:28:16.591247 | 2026-06-03 01:28:16.591301 | PLAY RECAP 2026-06-03 01:28:16.591346 | rockylinux-9 | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-06-03 01:28:16.591368 | 2026-06-03 01:28:16.762157 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-06-03 01:28:16.771414 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-06-03 01:28:17.434342 | 2026-06-03 01:28:17.434488 | PLAY [all] 2026-06-03 01:28:17.445752 | 2026-06-03 01:28:17.445860 | TASK [Copy inventory file for Zuul] 2026-06-03 01:28:18.754899 | rockylinux-9 | changed 2026-06-03 01:28:18.759665 | 2026-06-03 01:28:18.759737 | TASK [Switch "ansible_host" to private IP] 2026-06-03 01:28:19.236416 | rockylinux-9 | changed: 1 replacements made 2026-06-03 01:28:19.243250 | 2026-06-03 01:28:19.243337 | TASK [Run Molecule scenario] 2026-06-03 01:28:19.269650 | [rockylinux-9] Waiting on logger 2026-06-03 01:28:27.398204 | [rockylinux-9] Waiting on logger 2026-06-03 01:28:36.559147 | [rockylinux-9] Waiting on logger 2026-06-03 01:28:46.725959 | [rockylinux-9] Waiting on logger 2026-06-03 01:28:56.902042 | [rockylinux-9] Waiting on logger 2026-06-03 01:29:08.024810 | [rockylinux-9] Waiting on logger 2026-06-03 01:29:16.619399 | [rockylinux-9] Waiting on logger 2026-06-03 01:29:26.789974 | [rockylinux-9] Waiting on logger 2026-06-03 01:29:36.966077 | [rockylinux-9] Waiting on logger 2026-06-03 01:29:47.142299 | [rockylinux-9] Waiting on logger 2026-06-03 01:29:57.321999 | [rockylinux-9] Waiting on logger 2026-06-03 01:30:07.496736 | [rockylinux-9] Waiting on logger 2026-06-03 01:30:16.651226 | [rockylinux-9] Waiting on logger 2026-06-03 01:30:26.826198 | [rockylinux-9] Waiting on logger 2026-06-03 01:30:30.189198 | rockylinux-9 | ok: Runtime: 0:02:09.805080 2026-06-03 01:30:30.196717 | 2026-06-03 01:30:30.196778 | PLAY RECAP 2026-06-03 01:30:30.196825 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-06-03 01:30:30.196848 | 2026-06-03 01:30:30.332242 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-06-03 01:30:30.334932 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-06-03 01:30:31.019454 | 2026-06-03 01:30:31.019590 | PLAY [all] 2026-06-03 01:30:31.030151 | 2026-06-03 01:30:31.030231 | TASK [fetch-output : Set log path for multiple nodes] 2026-06-03 01:30:31.075581 | rockylinux-9 | skipping: Conditional result was False 2026-06-03 01:30:31.082988 | 2026-06-03 01:30:31.083097 | TASK [fetch-output : Set log path for single node] 2026-06-03 01:30:31.125198 | rockylinux-9 | ok 2026-06-03 01:30:31.130965 | 2026-06-03 01:30:31.131078 | LOOP [fetch-output : Ensure local output dirs] 2026-06-03 01:30:31.657634 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/ee442054155d401c8046f07ec5b024c2/work/logs" 2026-06-03 01:30:32.037692 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/ee442054155d401c8046f07ec5b024c2/work/artifacts" 2026-06-03 01:30:32.250290 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/ee442054155d401c8046f07ec5b024c2/work/docs" 2026-06-03 01:30:32.275323 | 2026-06-03 01:30:32.275454 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-06-03 01:30:32.978084 | rockylinux-9 | changed: .d..t...... ./ 2026-06-03 01:30:32.978355 | rockylinux-9 | changed: All items complete 2026-06-03 01:30:32.978395 | 2026-06-03 01:30:33.489244 | rockylinux-9 | changed: .d..t...... ./ 2026-06-03 01:30:33.995059 | rockylinux-9 | changed: .d..t...... ./ 2026-06-03 01:30:34.018859 | 2026-06-03 01:30:34.019000 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-06-03 01:30:34.481307 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.008578 2026-06-03 01:30:34.720024 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.008982 2026-06-03 01:30:34.743029 | 2026-06-03 01:30:34.743181 | PLAY [all] 2026-06-03 01:30:34.749072 | 2026-06-03 01:30:34.749139 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-06-03 01:30:35.226908 | rockylinux-9 | changed 2026-06-03 01:30:35.234007 | 2026-06-03 01:30:35.234078 | PLAY RECAP 2026-06-03 01:30:35.234123 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-06-03 01:30:35.234145 | 2026-06-03 01:30:35.371331 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-06-03 01:30:35.372551 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-06-03 01:30:35.942624 | 2026-06-03 01:30:35.943009 | PLAY [localhost] 2026-06-03 01:30:35.953278 | 2026-06-03 01:30:35.953354 | TASK [Generate Zuul manifest] 2026-06-03 01:30:35.974342 | localhost | ok 2026-06-03 01:30:35.993146 | 2026-06-03 01:30:35.993264 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-06-03 01:30:36.384401 | localhost | changed 2026-06-03 01:30:36.395083 | 2026-06-03 01:30:36.395195 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-06-03 01:30:36.427128 | localhost | ok 2026-06-03 01:30:36.435811 | 2026-06-03 01:30:36.435887 | TASK [Upload logs] 2026-06-03 01:30:36.457719 | localhost | ok 2026-06-03 01:30:36.515650 | 2026-06-03 01:30:36.515792 | TASK [Set zuul-log-path fact] 2026-06-03 01:30:36.538499 | localhost | ok 2026-06-03 01:30:36.551078 | 2026-06-03 01:30:36.551146 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-03 01:30:36.580493 | localhost | ok 2026-06-03 01:30:36.587911 | 2026-06-03 01:30:36.587980 | TASK [upload-logs : Create log directories] 2026-06-03 01:30:37.021000 | localhost | changed 2026-06-03 01:30:37.029477 | 2026-06-03 01:30:37.029696 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-06-03 01:30:37.444751 | localhost -> localhost | ok: Runtime: 0:00:00.004308 2026-06-03 01:30:37.453244 | 2026-06-03 01:30:37.453324 | TASK [upload-logs : Upload logs to log server] 2026-06-03 01:30:37.916440 | localhost | Output suppressed because no_log was given 2026-06-03 01:30:37.921795 | 2026-06-03 01:30:37.921888 | LOOP [upload-logs : Compress console log and json output] 2026-06-03 01:30:37.969062 | localhost | skipping: Conditional result was False 2026-06-03 01:30:37.976259 | localhost | skipping: Conditional result was False 2026-06-03 01:30:37.986816 | 2026-06-03 01:30:37.987004 | LOOP [upload-logs : Upload compressed console log and json output] 2026-06-03 01:30:38.031240 | localhost | skipping: Conditional result was False 2026-06-03 01:30:38.031494 | 2026-06-03 01:30:38.035396 | localhost | skipping: Conditional result was False 2026-06-03 01:30:38.049233 | 2026-06-03 01:30:38.049377 | LOOP [upload-logs : Upload console log and json output]