2026-04-14 02:50:25.306319 | Job console starting 2026-04-14 02:50:25.322500 | Updating git repos 2026-04-14 02:50:25.357448 | Cloning repos into workspace 2026-04-14 02:50:25.455740 | Restoring repo states 2026-04-14 02:50:25.462378 | Merging changes 2026-04-14 02:50:25.773107 | Checking out repos 2026-04-14 02:50:25.837873 | Preparing playbooks 2026-04-14 02:50:27.912175 | Running Ansible setup 2026-04-14 02:50:32.257857 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-14 02:50:32.983022 | 2026-04-14 02:50:33.305907 | PLAY [localhost] 2026-04-14 02:50:33.318721 | 2026-04-14 02:50:33.318823 | TASK [Gathering Facts] 2026-04-14 02:50:34.459437 | localhost | ok 2026-04-14 02:50:34.473933 | 2026-04-14 02:50:34.474068 | TASK [Setup log path fact] 2026-04-14 02:50:34.499431 | localhost | ok 2026-04-14 02:50:34.515211 | 2026-04-14 02:50:34.515641 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-14 02:50:34.552415 | localhost | ok 2026-04-14 02:50:34.563769 | 2026-04-14 02:50:34.563878 | TASK [emit-job-header : Print job information] 2026-04-14 02:50:34.607918 | # Job Information 2026-04-14 02:50:34.608167 | Ansible Version: 2.16.16 2026-04-14 02:50:34.608221 | Job: ansible-collection-containers-molecule-containerd-rockylinux-9 2026-04-14 02:50:34.608255 | Pipeline: check 2026-04-14 02:50:34.608287 | Executor: 0a8996d2b663 2026-04-14 02:50:34.608319 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/112 2026-04-14 02:50:34.608353 | Event ID: 9246c490-37ac-11f1-8fb9-92e03caab9e4 2026-04-14 02:50:34.612595 | 2026-04-14 02:50:34.612681 | LOOP [emit-job-header : Print node information] 2026-04-14 02:50:34.752263 | localhost | ok: 2026-04-14 02:50:34.752450 | localhost | # Node Information 2026-04-14 02:50:34.752483 | localhost | Inventory Hostname: rockylinux-9 2026-04-14 02:50:34.752512 | localhost | Hostname: np0000166001 2026-04-14 02:50:34.752534 | localhost | Username: zuul 2026-04-14 02:50:34.752769 | localhost | Distro: Rocky 9.7 2026-04-14 02:50:34.752794 | localhost | Provider: yul1 2026-04-14 02:50:34.752814 | localhost | Region: ca-ymq-1 2026-04-14 02:50:34.752832 | localhost | Label: rockylinux-9 2026-04-14 02:50:34.752851 | localhost | Product Name: OpenStack Nova 2026-04-14 02:50:34.752870 | localhost | Interface IP: 199.19.213.100 2026-04-14 02:50:34.771193 | 2026-04-14 02:50:34.771370 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-04-14 02:50:35.242509 | localhost -> localhost | changed 2026-04-14 02:50:35.247534 | 2026-04-14 02:50:35.247605 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-04-14 02:50:36.450736 | localhost -> localhost | changed 2026-04-14 02:50:36.462495 | 2026-04-14 02:50:36.462550 | PLAY [all] 2026-04-14 02:50:36.474622 | 2026-04-14 02:50:36.474718 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-04-14 02:50:36.765791 | rockylinux-9 -> localhost | ok 2026-04-14 02:50:36.771813 | 2026-04-14 02:50:36.771892 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-04-14 02:50:36.804469 | rockylinux-9 | ok 2026-04-14 02:50:36.820251 | rockylinux-9 | included: /var/lib/zuul/builds/481ea0ee8aa04d4b9fc48f115698e44d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-04-14 02:50:36.825574 | 2026-04-14 02:50:36.825635 | TASK [add-build-sshkey : Create Temp SSH key] 2026-04-14 02:50:38.378612 | rockylinux-9 -> localhost | Generating public/private rsa key pair. 2026-04-14 02:50:38.378851 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/481ea0ee8aa04d4b9fc48f115698e44d/work/481ea0ee8aa04d4b9fc48f115698e44d_id_rsa 2026-04-14 02:50:38.378897 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/481ea0ee8aa04d4b9fc48f115698e44d/work/481ea0ee8aa04d4b9fc48f115698e44d_id_rsa.pub 2026-04-14 02:50:38.378928 | rockylinux-9 -> localhost | The key fingerprint is: 2026-04-14 02:50:38.378958 | rockylinux-9 -> localhost | SHA256:AgYNoN1fftpEdiA/CE3EtLXKNH196906B6HtwrxlMHk zuul-build-sshkey 2026-04-14 02:50:38.379007 | rockylinux-9 -> localhost | The key's randomart image is: 2026-04-14 02:50:38.379034 | rockylinux-9 -> localhost | +---[RSA 3072]----+ 2026-04-14 02:50:38.379068 | rockylinux-9 -> localhost | |..oo .*= o | 2026-04-14 02:50:38.379096 | rockylinux-9 -> localhost | |.. o. .oB o . | 2026-04-14 02:50:38.379123 | rockylinux-9 -> localhost | |. . + * B o . .| 2026-04-14 02:50:38.379151 | rockylinux-9 -> localhost | | . o = = + ....| 2026-04-14 02:50:38.379176 | rockylinux-9 -> localhost | | o S o +oEo | 2026-04-14 02:50:38.379201 | rockylinux-9 -> localhost | | . = .++ o| 2026-04-14 02:50:38.379229 | rockylinux-9 -> localhost | | . .o .ooo| 2026-04-14 02:50:38.379255 | rockylinux-9 -> localhost | | +oo..| 2026-04-14 02:50:38.379285 | rockylinux-9 -> localhost | | .o.o | 2026-04-14 02:50:38.379318 | rockylinux-9 -> localhost | +----[SHA256]-----+ 2026-04-14 02:50:38.379380 | rockylinux-9 -> localhost | ok: Runtime: 0:00:01.069155 2026-04-14 02:50:38.384404 | 2026-04-14 02:50:38.384476 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-04-14 02:50:38.420760 | rockylinux-9 | ok 2026-04-14 02:50:38.432528 | rockylinux-9 | included: /var/lib/zuul/builds/481ea0ee8aa04d4b9fc48f115698e44d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-04-14 02:50:38.440704 | 2026-04-14 02:50:38.440782 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-04-14 02:50:38.471708 | rockylinux-9 | skipping: Conditional result was False 2026-04-14 02:50:38.478537 | 2026-04-14 02:50:38.478638 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-04-14 02:50:39.016344 | rockylinux-9 | changed 2026-04-14 02:50:39.022431 | 2026-04-14 02:50:39.022508 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-04-14 02:50:39.304257 | rockylinux-9 | ok 2026-04-14 02:50:39.309060 | 2026-04-14 02:50:39.309133 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-04-14 02:50:40.274675 | rockylinux-9 | changed 2026-04-14 02:50:40.280428 | 2026-04-14 02:50:40.280545 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-04-14 02:50:41.237632 | rockylinux-9 | changed 2026-04-14 02:50:41.242309 | 2026-04-14 02:50:41.242376 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-04-14 02:50:41.268529 | rockylinux-9 | skipping: Conditional result was False 2026-04-14 02:50:41.276541 | 2026-04-14 02:50:41.276643 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-04-14 02:50:41.961980 | rockylinux-9 -> localhost | changed 2026-04-14 02:50:41.975586 | 2026-04-14 02:50:41.975703 | TASK [add-build-sshkey : Add back temp key] 2026-04-14 02:50:42.249624 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/481ea0ee8aa04d4b9fc48f115698e44d/work/481ea0ee8aa04d4b9fc48f115698e44d_id_rsa (zuul-build-sshkey) 2026-04-14 02:50:42.249832 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.012719 2026-04-14 02:50:42.256980 | 2026-04-14 02:50:42.257050 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-04-14 02:50:42.667568 | rockylinux-9 | ok 2026-04-14 02:50:42.674808 | 2026-04-14 02:50:42.675006 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-04-14 02:50:42.701081 | rockylinux-9 | skipping: Conditional result was False 2026-04-14 02:50:42.719001 | 2026-04-14 02:50:42.719187 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-04-14 02:50:43.134869 | rockylinux-9 | ok 2026-04-14 02:50:43.142027 | 2026-04-14 02:50:43.142092 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-04-14 02:50:44.141730 | rockylinux-9 | Output suppressed because no_log was given 2026-04-14 02:50:44.152630 | 2026-04-14 02:50:44.152761 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-04-14 02:50:44.398448 | rockylinux-9 | ok: "logs" 2026-04-14 02:50:44.488782 | rockylinux-9 | ok: All items complete 2026-04-14 02:50:44.489014 | 2026-04-14 02:50:44.624464 | rockylinux-9 | ok: "artifacts" 2026-04-14 02:50:44.876027 | rockylinux-9 | ok: "docs" 2026-04-14 02:50:44.892122 | 2026-04-14 02:50:44.892214 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-04-14 02:50:45.172786 | rockylinux-9 | changed: "logs" 2026-04-14 02:50:45.370956 | rockylinux-9 | changed: "artifacts" 2026-04-14 02:50:45.599003 | rockylinux-9 | changed: "docs" 2026-04-14 02:50:45.615282 | 2026-04-14 02:50:45.615415 | PLAY RECAP 2026-04-14 02:50:45.615464 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-14 02:50:45.615495 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-04-14 02:50:45.615515 | 2026-04-14 02:50:45.781494 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-14 02:50:45.782761 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-14 02:50:46.449900 | 2026-04-14 02:50:46.450092 | PLAY [all] 2026-04-14 02:50:46.462127 | 2026-04-14 02:50:46.462271 | TASK [setup-uv : Extract archive] 2026-04-14 02:50:49.209494 | rockylinux-9 | changed 2026-04-14 02:50:49.215346 | 2026-04-14 02:50:49.215437 | TASK [setup-uv : Print version] 2026-04-14 02:50:49.237425 | [rockylinux-9] Waiting on logger 2026-04-14 02:50:49.751857 | rockylinux-9 | ok: Runtime: 0:00:00.014681 2026-04-14 02:50:49.758688 | 2026-04-14 02:50:49.758741 | PLAY RECAP 2026-04-14 02:50:49.758788 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-14 02:50:49.758811 | 2026-04-14 02:50:49.882154 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-14 02:50:49.883729 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-14 02:50:50.581933 | 2026-04-14 02:50:50.582092 | PLAY [all] 2026-04-14 02:50:50.592834 | 2026-04-14 02:50:50.592929 | TASK [Copy inventory file for Zuul] 2026-04-14 02:50:51.959167 | rockylinux-9 | changed 2026-04-14 02:50:52.206198 | 2026-04-14 02:50:52.206379 | TASK [Switch "ansible_host" to private IP] 2026-04-14 02:50:52.580669 | rockylinux-9 | changed: 1 replacements made 2026-04-14 02:50:52.589360 | 2026-04-14 02:50:52.589458 | TASK [Run Molecule scenario] 2026-04-14 02:50:52.610222 | [rockylinux-9] Waiting on logger 2026-04-14 02:51:00.742101 | [rockylinux-9] Waiting on logger 2026-04-14 02:51:10.917944 | [rockylinux-9] Waiting on logger 2026-04-14 02:51:21.094383 | [rockylinux-9] Waiting on logger 2026-04-14 02:51:30.251628 | [rockylinux-9] Waiting on logger 2026-04-14 02:51:40.422153 | [rockylinux-9] Waiting on logger 2026-04-14 02:51:50.598231 | [rockylinux-9] Waiting on logger 2026-04-14 02:52:00.774101 | [rockylinux-9] Waiting on logger 2026-04-14 02:52:10.950193 | [rockylinux-9] Waiting on logger 2026-04-14 02:52:21.158236 | [rockylinux-9] Waiting on logger 2026-04-14 02:52:30.315377 | [rockylinux-9] Waiting on logger 2026-04-14 02:52:40.486472 | [rockylinux-9] Waiting on logger 2026-04-14 02:52:50.662110 | [rockylinux-9] Waiting on logger 2026-04-14 02:53:00.838204 | [rockylinux-9] Waiting on logger 2026-04-14 02:53:11.014372 | [rockylinux-9] Waiting on logger 2026-04-14 02:53:21.190324 | [rockylinux-9] Waiting on logger 2026-04-14 02:53:30.347580 | [rockylinux-9] Waiting on logger 2026-04-14 02:53:40.523567 | [rockylinux-9] Waiting on logger 2026-04-14 02:53:50.694213 | [rockylinux-9] Waiting on logger 2026-04-14 02:54:00.870347 | [rockylinux-9] Waiting on logger 2026-04-14 02:54:11.049956 | [rockylinux-9] Waiting on logger 2026-04-14 02:54:21.222086 | [rockylinux-9] Waiting on logger 2026-04-14 02:54:30.375114 | [rockylinux-9] Waiting on logger 2026-04-14 02:54:40.554340 | [rockylinux-9] Waiting on logger 2026-04-14 02:54:50.726192 | [rockylinux-9] Waiting on logger 2026-04-14 02:55:00.902249 | [rockylinux-9] Waiting on logger 2026-04-14 02:55:11.078127 | [rockylinux-9] Waiting on logger 2026-04-14 02:55:20.422080 | [rockylinux-9] Waiting on logger 2026-04-14 02:55:30.598069 | [rockylinux-9] Waiting on logger 2026-04-14 02:55:40.774177 | [rockylinux-9] Waiting on logger 2026-04-14 02:55:43.022308 | rockylinux-9 | ok: Runtime: 0:04:49.595406 2026-04-14 02:55:43.044581 | 2026-04-14 02:55:43.044708 | PLAY RECAP 2026-04-14 02:55:43.044760 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-14 02:55:43.044782 | 2026-04-14 02:55:43.201109 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-14 02:55:43.203663 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-14 02:55:43.842899 | 2026-04-14 02:55:43.843560 | PLAY [all] 2026-04-14 02:55:43.855493 | 2026-04-14 02:55:43.855613 | TASK [fetch-output : Set log path for multiple nodes] 2026-04-14 02:55:43.902213 | rockylinux-9 | skipping: Conditional result was False 2026-04-14 02:55:43.909380 | 2026-04-14 02:55:43.909500 | TASK [fetch-output : Set log path for single node] 2026-04-14 02:55:43.954849 | rockylinux-9 | ok 2026-04-14 02:55:43.962998 | 2026-04-14 02:55:43.963078 | LOOP [fetch-output : Ensure local output dirs] 2026-04-14 02:55:44.350268 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/481ea0ee8aa04d4b9fc48f115698e44d/work/logs" 2026-04-14 02:55:44.554014 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/481ea0ee8aa04d4b9fc48f115698e44d/work/artifacts" 2026-04-14 02:55:44.765515 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/481ea0ee8aa04d4b9fc48f115698e44d/work/docs" 2026-04-14 02:55:44.776158 | 2026-04-14 02:55:44.776328 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-04-14 02:55:45.500840 | rockylinux-9 | changed: .d..t...... ./ 2026-04-14 02:55:45.501166 | rockylinux-9 | changed: All items complete 2026-04-14 02:55:45.501208 | 2026-04-14 02:55:46.030085 | rockylinux-9 | changed: .d..t...... ./ 2026-04-14 02:55:46.554917 | rockylinux-9 | changed: .d..t...... ./ 2026-04-14 02:55:46.576695 | 2026-04-14 02:55:46.576868 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-04-14 02:55:47.027745 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.008429 2026-04-14 02:55:47.340167 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.009643 2026-04-14 02:55:47.356559 | 2026-04-14 02:55:47.356752 | PLAY [all] 2026-04-14 02:55:47.365686 | 2026-04-14 02:55:47.365769 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-04-14 02:55:47.821405 | rockylinux-9 | changed 2026-04-14 02:55:47.828921 | 2026-04-14 02:55:47.829038 | PLAY RECAP 2026-04-14 02:55:47.829122 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-04-14 02:55:47.829168 | 2026-04-14 02:55:47.975094 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-14 02:55:47.977415 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-04-14 02:55:48.525093 | 2026-04-14 02:55:48.525502 | PLAY [localhost] 2026-04-14 02:55:48.535241 | 2026-04-14 02:55:48.535312 | TASK [Generate Zuul manifest] 2026-04-14 02:55:48.556841 | localhost | ok 2026-04-14 02:55:48.572603 | 2026-04-14 02:55:48.572694 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-04-14 02:55:48.929537 | localhost | changed 2026-04-14 02:55:48.943309 | 2026-04-14 02:55:48.943440 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-04-14 02:55:48.976189 | localhost | ok 2026-04-14 02:55:48.985658 | 2026-04-14 02:55:48.985806 | TASK [Upload logs] 2026-04-14 02:55:49.009114 | localhost | ok 2026-04-14 02:55:49.074635 | 2026-04-14 02:55:49.074772 | TASK [Set zuul-log-path fact] 2026-04-14 02:55:49.092802 | localhost | ok 2026-04-14 02:55:49.103855 | 2026-04-14 02:55:49.103927 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-14 02:55:49.136031 | localhost | ok 2026-04-14 02:55:49.145094 | 2026-04-14 02:55:49.145160 | TASK [upload-logs : Create log directories] 2026-04-14 02:55:49.558585 | localhost | changed 2026-04-14 02:55:49.565145 | 2026-04-14 02:55:49.565239 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-04-14 02:55:49.987994 | localhost -> localhost | ok: Runtime: 0:00:00.005667 2026-04-14 02:55:49.995104 | 2026-04-14 02:55:49.995194 | TASK [upload-logs : Upload logs to log server] 2026-04-14 02:55:50.462344 | localhost | Output suppressed because no_log was given 2026-04-14 02:55:50.468040 | 2026-04-14 02:55:50.468148 | LOOP [upload-logs : Compress console log and json output] 2026-04-14 02:55:50.513701 | localhost | skipping: Conditional result was False 2026-04-14 02:55:50.520354 | localhost | skipping: Conditional result was False 2026-04-14 02:55:50.533303 | 2026-04-14 02:55:50.533451 | LOOP [upload-logs : Upload compressed console log and json output] 2026-04-14 02:55:50.579753 | localhost | skipping: Conditional result was False 2026-04-14 02:55:50.580262 | 2026-04-14 02:55:50.583588 | localhost | skipping: Conditional result was False 2026-04-14 02:55:50.599649 | 2026-04-14 02:55:50.599809 | LOOP [upload-logs : Upload console log and json output]