2026-03-11 00:44:09.839390 | Job console starting 2026-03-11 00:44:09.852366 | Updating git repos 2026-03-11 00:44:09.882807 | Cloning repos into workspace 2026-03-11 00:44:09.971204 | Restoring repo states 2026-03-11 00:44:09.982463 | Merging changes 2026-03-11 00:44:10.253527 | Checking out repos 2026-03-11 00:44:10.289076 | Preparing playbooks 2026-03-11 00:44:11.204561 | Running Ansible setup 2026-03-11 00:44:14.792590 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-03-11 00:44:15.349649 | 2026-03-11 00:44:15.373042 | PLAY [localhost] 2026-03-11 00:44:15.385481 | 2026-03-11 00:44:15.385581 | TASK [Gathering Facts] 2026-03-11 00:44:16.407599 | localhost | ok 2026-03-11 00:44:16.416584 | 2026-03-11 00:44:16.416685 | TASK [Setup log path fact] 2026-03-11 00:44:16.434520 | localhost | ok 2026-03-11 00:44:16.449705 | 2026-03-11 00:44:16.449792 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-11 00:44:16.480450 | localhost | ok 2026-03-11 00:44:16.489571 | 2026-03-11 00:44:16.489638 | TASK [emit-job-header : Print job information] 2026-03-11 00:44:16.529998 | # Job Information 2026-03-11 00:44:16.530206 | Ansible Version: 2.16.16 2026-03-11 00:44:16.530278 | Job: ansible-collection-containers-molecule-containerd-rockylinux-9 2026-03-11 00:44:16.530327 | Pipeline: check 2026-03-11 00:44:16.530372 | Executor: 0a8996d2b663 2026-03-11 00:44:16.530414 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/99 2026-03-11 00:44:16.530463 | Event ID: 53b91b30-1ce3-11f1-884c-c2e8535f96e3 2026-03-11 00:44:16.535976 | 2026-03-11 00:44:16.536065 | LOOP [emit-job-header : Print node information] 2026-03-11 00:44:16.628303 | localhost | ok: 2026-03-11 00:44:16.628531 | localhost | # Node Information 2026-03-11 00:44:16.628596 | localhost | Inventory Hostname: rockylinux-9 2026-03-11 00:44:16.628650 | localhost | Hostname: np0000158289 2026-03-11 00:44:16.628695 | localhost | Username: zuul 2026-03-11 00:44:16.628745 | localhost | Distro: Rocky 9.7 2026-03-11 00:44:16.628789 | localhost | Provider: yul1 2026-03-11 00:44:16.628830 | localhost | Region: ca-ymq-1 2026-03-11 00:44:16.628871 | localhost | Label: rockylinux-9 2026-03-11 00:44:16.628911 | localhost | Product Name: OpenStack Nova 2026-03-11 00:44:16.628987 | localhost | Interface IP: 162.253.55.74 2026-03-11 00:44:16.640220 | 2026-03-11 00:44:16.640317 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-03-11 00:44:17.141370 | localhost -> localhost | changed 2026-03-11 00:44:17.146191 | 2026-03-11 00:44:17.146257 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-03-11 00:44:18.066689 | localhost -> localhost | changed 2026-03-11 00:44:18.075146 | 2026-03-11 00:44:18.075202 | PLAY [all] 2026-03-11 00:44:18.083464 | 2026-03-11 00:44:18.083601 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-03-11 00:44:18.311806 | rockylinux-9 -> localhost | ok 2026-03-11 00:44:18.321046 | 2026-03-11 00:44:18.321216 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-03-11 00:44:18.351324 | rockylinux-9 | ok 2026-03-11 00:44:18.367183 | rockylinux-9 | included: /var/lib/zuul/builds/b2b5a5cf73774336a23c404ce2ceb679/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-03-11 00:44:18.372284 | 2026-03-11 00:44:18.376747 | TASK [add-build-sshkey : Create Temp SSH key] 2026-03-11 00:44:18.997859 | rockylinux-9 -> localhost | Generating public/private rsa key pair. 2026-03-11 00:44:18.998055 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/b2b5a5cf73774336a23c404ce2ceb679/work/b2b5a5cf73774336a23c404ce2ceb679_id_rsa 2026-03-11 00:44:18.998244 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/b2b5a5cf73774336a23c404ce2ceb679/work/b2b5a5cf73774336a23c404ce2ceb679_id_rsa.pub 2026-03-11 00:44:18.998307 | rockylinux-9 -> localhost | The key fingerprint is: 2026-03-11 00:44:18.998342 | rockylinux-9 -> localhost | SHA256:+EJ2co1b7vaA8O4wJGpsfbmlQetomMtiKTomU4Whuik zuul-build-sshkey 2026-03-11 00:44:18.998386 | rockylinux-9 -> localhost | The key's randomart image is: 2026-03-11 00:44:18.998416 | rockylinux-9 -> localhost | +---[RSA 3072]----+ 2026-03-11 00:44:18.998451 | rockylinux-9 -> localhost | | | 2026-03-11 00:44:18.998481 | rockylinux-9 -> localhost | | . | 2026-03-11 00:44:18.998509 | rockylinux-9 -> localhost | | . o | 2026-03-11 00:44:18.998537 | rockylinux-9 -> localhost | |. . . . o | 2026-03-11 00:44:18.998565 | rockylinux-9 -> localhost | |. .. O S o | 2026-03-11 00:44:18.998792 | rockylinux-9 -> localhost | |...o * X = | 2026-03-11 00:44:18.998836 | rockylinux-9 -> localhost | | +*o. O * o | 2026-03-11 00:44:18.998866 | rockylinux-9 -> localhost | |EBo .+ X ... | 2026-03-11 00:44:18.998898 | rockylinux-9 -> localhost | |O+oo. +.o.... | 2026-03-11 00:44:18.998926 | rockylinux-9 -> localhost | +----[SHA256]-----+ 2026-03-11 00:44:18.998989 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.205495 2026-03-11 00:44:19.005933 | 2026-03-11 00:44:19.005995 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-03-11 00:44:19.039923 | rockylinux-9 | ok 2026-03-11 00:44:19.048388 | rockylinux-9 | included: /var/lib/zuul/builds/b2b5a5cf73774336a23c404ce2ceb679/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-03-11 00:44:19.055862 | 2026-03-11 00:44:19.055922 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-03-11 00:44:19.080267 | rockylinux-9 | skipping: Conditional result was False 2026-03-11 00:44:19.097429 | 2026-03-11 00:44:19.097496 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-03-11 00:44:19.545959 | rockylinux-9 | changed 2026-03-11 00:44:19.556423 | 2026-03-11 00:44:19.556509 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-03-11 00:44:19.806382 | rockylinux-9 | ok 2026-03-11 00:44:19.814034 | 2026-03-11 00:44:19.814123 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-03-11 00:44:20.707901 | rockylinux-9 | changed 2026-03-11 00:44:20.714123 | 2026-03-11 00:44:20.714184 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-03-11 00:44:21.616705 | rockylinux-9 | changed 2026-03-11 00:44:21.631823 | 2026-03-11 00:44:21.631909 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-03-11 00:44:21.651126 | rockylinux-9 | skipping: Conditional result was False 2026-03-11 00:44:21.657162 | 2026-03-11 00:44:21.657250 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-03-11 00:44:22.040478 | rockylinux-9 -> localhost | changed 2026-03-11 00:44:22.056119 | 2026-03-11 00:44:22.056189 | TASK [add-build-sshkey : Add back temp key] 2026-03-11 00:44:22.369804 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/b2b5a5cf73774336a23c404ce2ceb679/work/b2b5a5cf73774336a23c404ce2ceb679_id_rsa (zuul-build-sshkey) 2026-03-11 00:44:22.370087 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.021077 2026-03-11 00:44:22.381919 | 2026-03-11 00:44:22.382635 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-03-11 00:44:22.781910 | rockylinux-9 | ok 2026-03-11 00:44:22.787088 | 2026-03-11 00:44:22.787153 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-03-11 00:44:22.831373 | rockylinux-9 | skipping: Conditional result was False 2026-03-11 00:44:22.844759 | 2026-03-11 00:44:22.844849 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-03-11 00:44:23.233917 | rockylinux-9 | ok 2026-03-11 00:44:23.241809 | 2026-03-11 00:44:23.241872 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-03-11 00:44:24.184334 | rockylinux-9 | Output suppressed because no_log was given 2026-03-11 00:44:24.192128 | 2026-03-11 00:44:24.192188 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-03-11 00:44:24.441768 | rockylinux-9 | ok: "logs" 2026-03-11 00:44:24.442414 | rockylinux-9 | ok: All items complete 2026-03-11 00:44:24.442447 | 2026-03-11 00:44:24.661758 | rockylinux-9 | ok: "artifacts" 2026-03-11 00:44:24.874825 | rockylinux-9 | ok: "docs" 2026-03-11 00:44:24.886477 | 2026-03-11 00:44:24.886661 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-03-11 00:44:25.138638 | rockylinux-9 | changed: "logs" 2026-03-11 00:44:25.366274 | rockylinux-9 | changed: "artifacts" 2026-03-11 00:44:25.603058 | rockylinux-9 | changed: "docs" 2026-03-11 00:44:25.620521 | 2026-03-11 00:44:25.620668 | PLAY RECAP 2026-03-11 00:44:25.620719 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-11 00:44:25.620801 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-03-11 00:44:25.620831 | 2026-03-11 00:44:25.777541 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-03-11 00:44:25.778637 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-03-11 00:44:26.329763 | 2026-03-11 00:44:26.329897 | PLAY [all] 2026-03-11 00:44:26.340806 | 2026-03-11 00:44:26.340881 | TASK [setup-uv : Extract archive] 2026-03-11 00:44:29.148406 | rockylinux-9 | changed 2026-03-11 00:44:29.328186 | 2026-03-11 00:44:29.328335 | TASK [setup-uv : Print version] 2026-03-11 00:44:29.361045 | [rockylinux-9] Waiting on logger 2026-03-11 00:44:29.876078 | rockylinux-9 | ok: Runtime: 0:00:00.015503 2026-03-11 00:44:29.883618 | 2026-03-11 00:44:29.883687 | PLAY RECAP 2026-03-11 00:44:29.883748 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-11 00:44:29.883784 | 2026-03-11 00:44:29.993461 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-03-11 00:44:29.994697 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-03-11 00:44:30.574707 | 2026-03-11 00:44:30.574836 | PLAY [all] 2026-03-11 00:44:30.592112 | 2026-03-11 00:44:30.592232 | TASK [Copy inventory file for Zuul] 2026-03-11 00:44:31.932318 | rockylinux-9 | changed 2026-03-11 00:44:31.939150 | 2026-03-11 00:44:31.939259 | TASK [Switch "ansible_host" to private IP] 2026-03-11 00:44:32.261391 | rockylinux-9 | changed: 1 replacements made 2026-03-11 00:44:32.267203 | 2026-03-11 00:44:32.267301 | TASK [Run Molecule scenario] 2026-03-11 00:44:32.288605 | [rockylinux-9] Waiting on logger 2026-03-11 00:44:40.427170 | [rockylinux-9] Waiting on logger 2026-03-11 00:44:50.597941 | [rockylinux-9] Waiting on logger 2026-03-11 00:45:00.773790 | [rockylinux-9] Waiting on logger 2026-03-11 00:45:10.953879 | [rockylinux-9] Waiting on logger 2026-03-11 00:45:21.126098 | [rockylinux-9] Waiting on logger 2026-03-11 00:45:31.302273 | [rockylinux-9] Waiting on logger 2026-03-11 00:45:40.463018 | [rockylinux-9] Waiting on logger 2026-03-11 00:45:50.630018 | [rockylinux-9] Waiting on logger 2026-03-11 00:46:00.806222 | [rockylinux-9] Waiting on logger 2026-03-11 00:46:10.982130 | [rockylinux-9] Waiting on logger 2026-03-11 00:46:21.158515 | [rockylinux-9] Waiting on logger 2026-03-11 00:46:31.333959 | [rockylinux-9] Waiting on logger 2026-03-11 00:46:40.486152 | [rockylinux-9] Waiting on logger 2026-03-11 00:46:50.662355 | [rockylinux-9] Waiting on logger 2026-03-11 00:47:00.838237 | [rockylinux-9] Waiting on logger 2026-03-11 00:47:11.013859 | [rockylinux-9] Waiting on logger 2026-03-11 00:47:21.193929 | [rockylinux-9] Waiting on logger 2026-03-11 00:47:31.369870 | [rockylinux-9] Waiting on logger 2026-03-11 00:47:40.521937 | [rockylinux-9] Waiting on logger 2026-03-11 00:47:50.693928 | [rockylinux-9] Waiting on logger 2026-03-11 00:48:00.870208 | [rockylinux-9] Waiting on logger 2026-03-11 00:48:11.045960 | [rockylinux-9] Waiting on logger 2026-03-11 00:48:21.221965 | [rockylinux-9] Waiting on logger 2026-03-11 00:48:30.379238 | [rockylinux-9] Waiting on logger 2026-03-11 00:48:40.550268 | [rockylinux-9] Waiting on logger 2026-03-11 00:48:50.726073 | [rockylinux-9] Waiting on logger 2026-03-11 00:48:54.094100 | rockylinux-9 | ok: Runtime: 0:04:21.150310 2026-03-11 00:48:54.100736 | 2026-03-11 00:48:54.100786 | PLAY RECAP 2026-03-11 00:48:54.100831 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-11 00:48:54.100853 | 2026-03-11 00:48:54.220842 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-03-11 00:48:54.223150 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-03-11 00:48:54.822157 | 2026-03-11 00:48:54.822406 | PLAY [all] 2026-03-11 00:48:54.833547 | 2026-03-11 00:48:54.833633 | TASK [fetch-output : Set log path for multiple nodes] 2026-03-11 00:48:54.880102 | rockylinux-9 | skipping: Conditional result was False 2026-03-11 00:48:54.889907 | 2026-03-11 00:48:54.889989 | TASK [fetch-output : Set log path for single node] 2026-03-11 00:48:54.932436 | rockylinux-9 | ok 2026-03-11 00:48:54.939436 | 2026-03-11 00:48:54.940045 | LOOP [fetch-output : Ensure local output dirs] 2026-03-11 00:48:55.311430 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/b2b5a5cf73774336a23c404ce2ceb679/work/logs" 2026-03-11 00:48:55.507845 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/b2b5a5cf73774336a23c404ce2ceb679/work/artifacts" 2026-03-11 00:48:55.706344 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/b2b5a5cf73774336a23c404ce2ceb679/work/docs" 2026-03-11 00:48:55.721994 | 2026-03-11 00:48:55.722139 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-03-11 00:48:56.427803 | rockylinux-9 | changed: .d..t...... ./ 2026-03-11 00:48:56.428068 | rockylinux-9 | changed: All items complete 2026-03-11 00:48:56.428107 | 2026-03-11 00:48:56.939069 | rockylinux-9 | changed: .d..t...... ./ 2026-03-11 00:48:57.454295 | rockylinux-9 | changed: .d..t...... ./ 2026-03-11 00:48:57.476124 | 2026-03-11 00:48:57.476296 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-03-11 00:48:57.873380 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.008161 2026-03-11 00:48:58.103799 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.009092 2026-03-11 00:48:58.123846 | 2026-03-11 00:48:58.123982 | PLAY [all] 2026-03-11 00:48:58.129717 | 2026-03-11 00:48:58.129787 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-03-11 00:48:58.585531 | rockylinux-9 | changed 2026-03-11 00:48:58.592567 | 2026-03-11 00:48:58.592624 | PLAY RECAP 2026-03-11 00:48:58.592676 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-03-11 00:48:58.592699 | 2026-03-11 00:48:58.739915 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-03-11 00:48:58.740943 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-03-11 00:48:59.268994 | 2026-03-11 00:48:59.269116 | PLAY [localhost] 2026-03-11 00:48:59.278694 | 2026-03-11 00:48:59.278793 | TASK [Generate Zuul manifest] 2026-03-11 00:48:59.299319 | localhost | ok 2026-03-11 00:48:59.315443 | 2026-03-11 00:48:59.315546 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-03-11 00:48:59.610316 | localhost | changed 2026-03-11 00:48:59.620937 | 2026-03-11 00:48:59.621007 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-03-11 00:48:59.649086 | localhost | ok 2026-03-11 00:48:59.655588 | 2026-03-11 00:48:59.655648 | TASK [Upload logs] 2026-03-11 00:48:59.673503 | localhost | ok 2026-03-11 00:48:59.729635 | 2026-03-11 00:48:59.729713 | TASK [Set zuul-log-path fact] 2026-03-11 00:48:59.747639 | localhost | ok 2026-03-11 00:48:59.758149 | 2026-03-11 00:48:59.758213 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-11 00:48:59.786462 | localhost | ok 2026-03-11 00:48:59.793566 | 2026-03-11 00:48:59.793629 | TASK [upload-logs : Create log directories] 2026-03-11 00:49:00.205060 | localhost | changed 2026-03-11 00:49:00.210752 | 2026-03-11 00:49:00.210822 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-03-11 00:49:00.591539 | localhost -> localhost | ok: Runtime: 0:00:00.005520 2026-03-11 00:49:00.597944 | 2026-03-11 00:49:00.598006 | TASK [upload-logs : Upload logs to log server] 2026-03-11 00:49:01.036350 | localhost | Output suppressed because no_log was given 2026-03-11 00:49:01.041445 | 2026-03-11 00:49:01.041533 | LOOP [upload-logs : Compress console log and json output] 2026-03-11 00:49:01.088215 | localhost | skipping: Conditional result was False 2026-03-11 00:49:01.097299 | localhost | skipping: Conditional result was False 2026-03-11 00:49:01.109359 | 2026-03-11 00:49:01.109552 | LOOP [upload-logs : Upload compressed console log and json output] 2026-03-11 00:49:01.150739 | localhost | skipping: Conditional result was False 2026-03-11 00:49:01.151001 | 2026-03-11 00:49:01.154985 | localhost | skipping: Conditional result was False 2026-03-11 00:49:01.164698 | 2026-03-11 00:49:01.164817 | LOOP [upload-logs : Upload console log and json output]