2026-04-28 06:23:11.732366 | Job console starting 2026-04-28 06:23:11.749650 | Updating git repos 2026-04-28 06:23:11.798118 | Cloning repos into workspace 2026-04-28 06:23:12.143191 | Restoring repo states 2026-04-28 06:23:12.157224 | Merging changes 2026-04-28 06:23:13.027183 | Checking out repos 2026-04-28 06:23:13.260489 | Preparing playbooks 2026-04-28 06:23:18.140855 | Running Ansible setup 2026-04-28 06:23:24.381183 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-28 06:23:25.034602 | 2026-04-28 06:23:25.034762 | PLAY [localhost] 2026-04-28 06:23:25.042471 | 2026-04-28 06:23:25.042546 | TASK [Gathering Facts] 2026-04-28 06:23:26.058127 | localhost | ok 2026-04-28 06:23:26.069564 | 2026-04-28 06:23:26.069700 | TASK [Setup log path fact] 2026-04-28 06:23:26.094835 | localhost | ok 2026-04-28 06:23:26.109216 | 2026-04-28 06:23:26.109334 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-28 06:23:26.142551 | localhost | ok 2026-04-28 06:23:26.148948 | 2026-04-28 06:23:26.149040 | TASK [emit-job-header : Print job information] 2026-04-28 06:23:26.196616 | # Job Information 2026-04-28 06:23:26.197088 | Ansible Version: 2.16.16 2026-04-28 06:23:26.197390 | Job: ansible-collection-containers-molecule-docker-rockylinux-9 2026-04-28 06:23:26.197421 | Pipeline: check 2026-04-28 06:23:26.197443 | Executor: 0a8996d2b663 2026-04-28 06:23:26.197463 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/123 2026-04-28 06:23:26.197485 | Event ID: 9e54a350-42ca-11f1-84ff-fc21fd7517c8 2026-04-28 06:23:26.200641 | 2026-04-28 06:23:26.200715 | LOOP [emit-job-header : Print node information] 2026-04-28 06:23:26.288923 | localhost | ok: 2026-04-28 06:23:26.289175 | localhost | # Node Information 2026-04-28 06:23:26.289206 | localhost | Inventory Hostname: rockylinux-9 2026-04-28 06:23:26.289228 | localhost | Hostname: np0000170945 2026-04-28 06:23:26.289248 | localhost | Username: zuul 2026-04-28 06:23:26.289270 | localhost | Distro: Rocky 9.7 2026-04-28 06:23:26.289290 | localhost | Provider: yul1 2026-04-28 06:23:26.289309 | localhost | Region: ca-ymq-1 2026-04-28 06:23:26.289328 | localhost | Label: rockylinux-9 2026-04-28 06:23:26.289346 | localhost | Product Name: OpenStack Nova 2026-04-28 06:23:26.289364 | localhost | Interface IP: 199.204.45.222 2026-04-28 06:23:26.304831 | 2026-04-28 06:23:26.305055 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-04-28 06:23:26.717700 | localhost -> localhost | changed 2026-04-28 06:23:26.724283 | 2026-04-28 06:23:26.724391 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-04-28 06:23:27.626142 | localhost -> localhost | changed 2026-04-28 06:23:27.633153 | 2026-04-28 06:23:27.633213 | PLAY [all] 2026-04-28 06:23:27.642526 | 2026-04-28 06:23:27.642655 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-04-28 06:23:27.891501 | rockylinux-9 -> localhost | ok 2026-04-28 06:23:27.900314 | 2026-04-28 06:23:27.900393 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-04-28 06:23:27.925910 | rockylinux-9 | ok 2026-04-28 06:23:27.939954 | rockylinux-9 | included: /var/lib/zuul/builds/f7672f01fc5741b6990e4c9782f34048/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-04-28 06:23:27.944992 | 2026-04-28 06:23:27.945054 | TASK [add-build-sshkey : Create Temp SSH key] 2026-04-28 06:23:28.895762 | rockylinux-9 -> localhost | Generating public/private rsa key pair. 2026-04-28 06:23:28.896010 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/f7672f01fc5741b6990e4c9782f34048/work/f7672f01fc5741b6990e4c9782f34048_id_rsa 2026-04-28 06:23:28.896049 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/f7672f01fc5741b6990e4c9782f34048/work/f7672f01fc5741b6990e4c9782f34048_id_rsa.pub 2026-04-28 06:23:28.896073 | rockylinux-9 -> localhost | The key fingerprint is: 2026-04-28 06:23:28.896094 | rockylinux-9 -> localhost | SHA256:7lirQ0eECJztjzZmsvlWPKto2hy1yGBwGSH1zGy8p5o zuul-build-sshkey 2026-04-28 06:23:28.896131 | rockylinux-9 -> localhost | The key's randomart image is: 2026-04-28 06:23:28.896152 | rockylinux-9 -> localhost | +---[RSA 3072]----+ 2026-04-28 06:23:28.896179 | rockylinux-9 -> localhost | |.o== . . | 2026-04-28 06:23:28.896200 | rockylinux-9 -> localhost | | .oOo . . | 2026-04-28 06:23:28.896220 | rockylinux-9 -> localhost | |. o.B . | 2026-04-28 06:23:28.896239 | rockylinux-9 -> localhost | |.. ... . | 2026-04-28 06:23:28.896258 | rockylinux-9 -> localhost | |.. o+..S | 2026-04-28 06:23:28.896277 | rockylinux-9 -> localhost | |.o.o*+*.. | 2026-04-28 06:23:28.896296 | rockylinux-9 -> localhost | | +Bo+ +o | 2026-04-28 06:23:28.896315 | rockylinux-9 -> localhost | | o+=. o+ . | 2026-04-28 06:23:28.896337 | rockylinux-9 -> localhost | |.oEoo.ooo | 2026-04-28 06:23:28.896357 | rockylinux-9 -> localhost | +----[SHA256]-----+ 2026-04-28 06:23:28.896404 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.458753 2026-04-28 06:23:28.901662 | 2026-04-28 06:23:28.901790 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-04-28 06:23:28.931441 | rockylinux-9 | ok 2026-04-28 06:23:28.942732 | rockylinux-9 | included: /var/lib/zuul/builds/f7672f01fc5741b6990e4c9782f34048/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-04-28 06:23:28.951392 | 2026-04-28 06:23:28.951454 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-04-28 06:23:28.975014 | rockylinux-9 | skipping: Conditional result was False 2026-04-28 06:23:28.981210 | 2026-04-28 06:23:28.981300 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-04-28 06:23:29.516899 | rockylinux-9 | changed 2026-04-28 06:23:29.525607 | 2026-04-28 06:23:29.525722 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-04-28 06:23:29.795736 | rockylinux-9 | ok 2026-04-28 06:23:29.801185 | 2026-04-28 06:23:29.801330 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-04-28 06:23:30.773198 | rockylinux-9 | changed 2026-04-28 06:23:30.779861 | 2026-04-28 06:23:30.779983 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-04-28 06:23:31.681514 | rockylinux-9 | changed 2026-04-28 06:23:31.772097 | 2026-04-28 06:23:31.772192 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-04-28 06:23:31.801284 | rockylinux-9 | skipping: Conditional result was False 2026-04-28 06:23:31.808661 | 2026-04-28 06:23:31.808924 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-04-28 06:23:32.230804 | rockylinux-9 -> localhost | changed 2026-04-28 06:23:32.244274 | 2026-04-28 06:23:32.244364 | TASK [add-build-sshkey : Add back temp key] 2026-04-28 06:23:32.508243 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/f7672f01fc5741b6990e4c9782f34048/work/f7672f01fc5741b6990e4c9782f34048_id_rsa (zuul-build-sshkey) 2026-04-28 06:23:32.508489 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.013886 2026-04-28 06:23:32.540687 | 2026-04-28 06:23:32.540780 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-04-28 06:23:33.009736 | rockylinux-9 | ok 2026-04-28 06:23:33.544718 | 2026-04-28 06:23:33.544878 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-04-28 06:23:33.569626 | rockylinux-9 | skipping: Conditional result was False 2026-04-28 06:23:33.583885 | 2026-04-28 06:23:33.584036 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-04-28 06:23:34.069847 | rockylinux-9 | ok 2026-04-28 06:23:34.076765 | 2026-04-28 06:23:34.076836 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-04-28 06:23:35.114240 | rockylinux-9 | Output suppressed because no_log was given 2026-04-28 06:23:35.126378 | 2026-04-28 06:23:35.126507 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-04-28 06:23:35.391132 | rockylinux-9 | ok: "logs" 2026-04-28 06:23:36.391387 | rockylinux-9 | ok: All items complete 2026-04-28 06:23:36.391568 | 2026-04-28 06:23:36.400149 | rockylinux-9 | ok: "artifacts" 2026-04-28 06:23:36.407045 | rockylinux-9 | ok: "docs" 2026-04-28 06:23:36.422903 | 2026-04-28 06:23:36.423076 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-04-28 06:23:36.693621 | rockylinux-9 | changed: "logs" 2026-04-28 06:23:36.912775 | rockylinux-9 | changed: "artifacts" 2026-04-28 06:23:37.205966 | rockylinux-9 | changed: "docs" 2026-04-28 06:23:37.236105 | 2026-04-28 06:23:37.236223 | PLAY RECAP 2026-04-28 06:23:37.236280 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-28 06:23:37.236320 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-04-28 06:23:37.236350 | 2026-04-28 06:23:37.387150 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-28 06:23:37.388981 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-28 06:23:38.037784 | 2026-04-28 06:23:38.495411 | PLAY [all] 2026-04-28 06:23:38.513661 | 2026-04-28 06:23:38.513799 | TASK [setup-uv : Extract archive] 2026-04-28 06:23:41.381259 | rockylinux-9 | changed 2026-04-28 06:23:41.387384 | 2026-04-28 06:23:41.387476 | TASK [setup-uv : Print version] 2026-04-28 06:23:41.408438 | [rockylinux-9] Waiting on logger 2026-04-28 06:23:41.920724 | rockylinux-9 | ok: Runtime: 0:00:00.012945 2026-04-28 06:23:41.927299 | 2026-04-28 06:23:41.927345 | PLAY RECAP 2026-04-28 06:23:41.927392 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-28 06:23:41.927416 | 2026-04-28 06:23:42.072208 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-28 06:23:42.195666 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-28 06:23:42.795866 | 2026-04-28 06:23:42.944310 | PLAY [all] 2026-04-28 06:23:42.962772 | 2026-04-28 06:23:42.962869 | TASK [Copy inventory file for Zuul] 2026-04-28 06:23:44.389803 | rockylinux-9 | changed 2026-04-28 06:23:44.398479 | 2026-04-28 06:23:44.398590 | TASK [Switch "ansible_host" to private IP] 2026-04-28 06:23:44.745199 | rockylinux-9 | changed: 1 replacements made 2026-04-28 06:23:44.750113 | 2026-04-28 06:23:44.750177 | TASK [Run Molecule scenario] 2026-04-28 06:23:44.772887 | [rockylinux-9] Waiting on logger 2026-04-28 06:23:52.902021 | [rockylinux-9] Waiting on logger 2026-04-28 06:24:03.078118 | [rockylinux-9] Waiting on logger 2026-04-28 06:24:13.254231 | [rockylinux-9] Waiting on logger 2026-04-28 06:24:22.411384 | [rockylinux-9] Waiting on logger 2026-04-28 06:24:32.581967 | [rockylinux-9] Waiting on logger 2026-04-28 06:24:42.758015 | [rockylinux-9] Waiting on logger 2026-04-28 06:24:52.934272 | [rockylinux-9] Waiting on logger 2026-04-28 06:25:03.110144 | [rockylinux-9] Waiting on logger 2026-04-28 06:25:13.290171 | [rockylinux-9] Waiting on logger 2026-04-28 06:25:22.447652 | [rockylinux-9] Waiting on logger 2026-04-28 06:25:32.614097 | [rockylinux-9] Waiting on logger 2026-04-28 06:25:42.790090 | [rockylinux-9] Waiting on logger 2026-04-28 06:25:52.966031 | [rockylinux-9] Waiting on logger 2026-04-28 06:26:03.142029 | [rockylinux-9] Waiting on logger 2026-04-28 06:26:12.210730 | rockylinux-9 | ok: Runtime: 0:02:26.025534 2026-04-28 06:26:12.215770 | 2026-04-28 06:26:12.215820 | PLAY RECAP 2026-04-28 06:26:12.215863 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-28 06:26:12.215885 | 2026-04-28 06:26:12.314660 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-28 06:26:12.316213 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-28 06:26:12.895779 | 2026-04-28 06:26:12.978178 | PLAY [all] 2026-04-28 06:26:12.995822 | 2026-04-28 06:26:12.995920 | TASK [fetch-output : Set log path for multiple nodes] 2026-04-28 06:26:13.041271 | rockylinux-9 | skipping: Conditional result was False 2026-04-28 06:26:13.048569 | 2026-04-28 06:26:13.048672 | TASK [fetch-output : Set log path for single node] 2026-04-28 06:26:13.092147 | rockylinux-9 | ok 2026-04-28 06:26:13.096701 | 2026-04-28 06:26:13.096770 | LOOP [fetch-output : Ensure local output dirs] 2026-04-28 06:26:13.464487 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/f7672f01fc5741b6990e4c9782f34048/work/logs" 2026-04-28 06:26:13.681895 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/f7672f01fc5741b6990e4c9782f34048/work/artifacts" 2026-04-28 06:26:14.084824 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/f7672f01fc5741b6990e4c9782f34048/work/docs" 2026-04-28 06:26:14.097119 | 2026-04-28 06:26:14.097296 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-04-28 06:26:14.818111 | rockylinux-9 | changed: .d..t...... ./ 2026-04-28 06:26:14.818360 | rockylinux-9 | changed: All items complete 2026-04-28 06:26:14.818400 | 2026-04-28 06:26:15.339447 | rockylinux-9 | changed: .d..t...... ./ 2026-04-28 06:26:15.924709 | rockylinux-9 | changed: .d..t...... ./ 2026-04-28 06:26:15.942679 | 2026-04-28 06:26:15.942847 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-04-28 06:26:16.350852 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.008959 2026-04-28 06:26:16.615487 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.009140 2026-04-28 06:26:16.635182 | 2026-04-28 06:26:16.635323 | PLAY [all] 2026-04-28 06:26:16.641099 | 2026-04-28 06:26:16.641168 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-04-28 06:26:17.120325 | rockylinux-9 | changed 2026-04-28 06:26:17.126529 | 2026-04-28 06:26:17.126588 | PLAY RECAP 2026-04-28 06:26:17.126632 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-04-28 06:26:17.126654 | 2026-04-28 06:26:17.237708 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-28 06:26:17.239273 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-04-28 06:26:17.771632 | 2026-04-28 06:26:17.771751 | PLAY [localhost] 2026-04-28 06:26:17.781463 | 2026-04-28 06:26:17.781562 | TASK [Generate Zuul manifest] 2026-04-28 06:26:17.804135 | localhost | ok 2026-04-28 06:26:17.819595 | 2026-04-28 06:26:17.819670 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-04-28 06:26:18.158418 | localhost | changed 2026-04-28 06:26:18.171428 | 2026-04-28 06:26:18.171523 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-04-28 06:26:18.233564 | localhost | ok 2026-04-28 06:26:18.242182 | 2026-04-28 06:26:18.242245 | TASK [Upload logs] 2026-04-28 06:26:18.262818 | localhost | ok 2026-04-28 06:26:18.319041 | 2026-04-28 06:26:18.331232 | TASK [Set zuul-log-path fact] 2026-04-28 06:26:18.353726 | localhost | ok 2026-04-28 06:26:18.366851 | 2026-04-28 06:26:18.366918 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-28 06:26:18.398370 | localhost | ok 2026-04-28 06:26:18.405653 | 2026-04-28 06:26:18.405716 | TASK [upload-logs : Create log directories] 2026-04-28 06:26:18.786713 | localhost | changed 2026-04-28 06:26:18.793589 | 2026-04-28 06:26:18.793655 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-04-28 06:26:19.193869 | localhost -> localhost | ok: Runtime: 0:00:00.005673 2026-04-28 06:26:19.200196 | 2026-04-28 06:26:19.200316 | TASK [upload-logs : Upload logs to log server] 2026-04-28 06:26:19.661406 | localhost | Output suppressed because no_log was given 2026-04-28 06:26:19.666922 | 2026-04-28 06:26:19.666989 | LOOP [upload-logs : Compress console log and json output] 2026-04-28 06:26:19.713750 | localhost | skipping: Conditional result was False 2026-04-28 06:26:19.721565 | localhost | skipping: Conditional result was False 2026-04-28 06:26:19.729343 | 2026-04-28 06:26:19.729474 | LOOP [upload-logs : Upload compressed console log and json output] 2026-04-28 06:26:19.770132 | localhost | skipping: Conditional result was False 2026-04-28 06:26:19.770566 | 2026-04-28 06:26:19.774213 | localhost | skipping: Conditional result was False 2026-04-28 06:26:19.790390 | 2026-04-28 06:26:19.828213 | LOOP [upload-logs : Upload console log and json output]