2026-04-18 16:34:38.182750 | Job console starting 2026-04-18 16:34:38.194436 | Updating git repos 2026-04-18 16:34:38.230918 | Cloning repos into workspace 2026-04-18 16:34:38.293107 | Restoring repo states 2026-04-18 16:34:38.299084 | Merging changes 2026-04-18 16:34:38.550639 | Checking out repos 2026-04-18 16:34:38.612322 | Preparing playbooks 2026-04-18 16:34:39.816745 | Running Ansible setup 2026-04-18 16:34:43.569323 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-18 16:34:44.248323 | 2026-04-18 16:34:44.248523 | PLAY [localhost] 2026-04-18 16:34:44.258378 | 2026-04-18 16:34:44.258539 | TASK [Gathering Facts] 2026-04-18 16:34:45.183068 | localhost | ok 2026-04-18 16:34:45.190113 | 2026-04-18 16:34:45.190232 | TASK [Setup log path fact] 2026-04-18 16:34:45.208793 | localhost | ok 2026-04-18 16:34:45.221672 | 2026-04-18 16:34:45.221791 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-18 16:34:45.252484 | localhost | ok 2026-04-18 16:34:45.259732 | 2026-04-18 16:34:45.259804 | TASK [emit-job-header : Print job information] 2026-04-18 16:34:45.298880 | # Job Information 2026-04-18 16:34:45.299548 | Ansible Version: 2.16.16 2026-04-18 16:34:45.299582 | Job: ansible-collection-containers-molecule-containerd-rockylinux-9 2026-04-18 16:34:45.299605 | Pipeline: check 2026-04-18 16:34:45.299626 | Executor: 0a8996d2b663 2026-04-18 16:34:45.299646 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/104 2026-04-18 16:34:45.299668 | Event ID: 3b9743e0-3b44-11f1-8b36-e33dc4de3ce3 2026-04-18 16:34:45.302804 | 2026-04-18 16:34:45.302878 | LOOP [emit-job-header : Print node information] 2026-04-18 16:34:45.404359 | localhost | ok: 2026-04-18 16:34:45.404518 | localhost | # Node Information 2026-04-18 16:34:45.404547 | localhost | Inventory Hostname: rockylinux-9 2026-04-18 16:34:45.404572 | localhost | Hostname: np0000167382 2026-04-18 16:34:45.404592 | localhost | Username: zuul 2026-04-18 16:34:45.404616 | localhost | Distro: Rocky 9.7 2026-04-18 16:34:45.404636 | localhost | Provider: yul1 2026-04-18 16:34:45.404654 | localhost | Region: ca-ymq-1 2026-04-18 16:34:45.404672 | localhost | Label: rockylinux-9 2026-04-18 16:34:45.404690 | localhost | Product Name: OpenStack Nova 2026-04-18 16:34:45.404709 | localhost | Interface IP: 199.19.213.23 2026-04-18 16:34:45.421029 | 2026-04-18 16:34:45.421258 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-04-18 16:34:45.848251 | localhost -> localhost | changed 2026-04-18 16:34:45.857983 | 2026-04-18 16:34:45.858121 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-04-18 16:34:46.853930 | localhost -> localhost | changed 2026-04-18 16:34:46.862223 | 2026-04-18 16:34:46.862281 | PLAY [all] 2026-04-18 16:34:46.869265 | 2026-04-18 16:34:46.869334 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-04-18 16:34:47.236731 | rockylinux-9 -> localhost | ok 2026-04-18 16:34:47.245601 | 2026-04-18 16:34:47.245733 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-04-18 16:34:47.287073 | rockylinux-9 | ok 2026-04-18 16:34:47.307349 | rockylinux-9 | included: /var/lib/zuul/builds/8f8519b5d8334f049235aeca2784f702/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-04-18 16:34:47.315460 | 2026-04-18 16:34:47.315608 | TASK [add-build-sshkey : Create Temp SSH key] 2026-04-18 16:34:49.207801 | rockylinux-9 -> localhost | Generating public/private rsa key pair. 2026-04-18 16:34:49.208009 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/8f8519b5d8334f049235aeca2784f702/work/8f8519b5d8334f049235aeca2784f702_id_rsa 2026-04-18 16:34:49.208057 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/8f8519b5d8334f049235aeca2784f702/work/8f8519b5d8334f049235aeca2784f702_id_rsa.pub 2026-04-18 16:34:49.208091 | rockylinux-9 -> localhost | The key fingerprint is: 2026-04-18 16:34:49.208124 | rockylinux-9 -> localhost | SHA256:gb/ttwqt8HAa2kyn2/q2zN2UTHV7S806kyhk/vZ/ir8 zuul-build-sshkey 2026-04-18 16:34:49.208173 | rockylinux-9 -> localhost | The key's randomart image is: 2026-04-18 16:34:49.208206 | rockylinux-9 -> localhost | +---[RSA 3072]----+ 2026-04-18 16:34:49.208246 | rockylinux-9 -> localhost | | | 2026-04-18 16:34:49.208277 | rockylinux-9 -> localhost | | . | 2026-04-18 16:34:49.208307 | rockylinux-9 -> localhost | | . . . .| 2026-04-18 16:34:49.208337 | rockylinux-9 -> localhost | | . . . oo| 2026-04-18 16:34:49.208375 | rockylinux-9 -> localhost | | S o . o+| 2026-04-18 16:34:49.208442 | rockylinux-9 -> localhost | | B o o.oo| 2026-04-18 16:34:49.208478 | rockylinux-9 -> localhost | | = = = = =. | 2026-04-18 16:34:49.208509 | rockylinux-9 -> localhost | | = &.= =o. o.| 2026-04-18 16:34:49.208542 | rockylinux-9 -> localhost | | . B=O.++=+E+o| 2026-04-18 16:34:49.208572 | rockylinux-9 -> localhost | +----[SHA256]-----+ 2026-04-18 16:34:49.208725 | rockylinux-9 -> localhost | ok: Runtime: 0:00:01.280780 2026-04-18 16:34:49.213627 | 2026-04-18 16:34:49.213695 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-04-18 16:34:49.246409 | rockylinux-9 | ok 2026-04-18 16:34:49.320288 | rockylinux-9 | included: /var/lib/zuul/builds/8f8519b5d8334f049235aeca2784f702/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-04-18 16:34:49.329877 | 2026-04-18 16:34:49.329946 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-04-18 16:34:49.354217 | rockylinux-9 | skipping: Conditional result was False 2026-04-18 16:34:49.363135 | 2026-04-18 16:34:49.363213 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-04-18 16:34:49.882583 | rockylinux-9 | changed 2026-04-18 16:34:49.888323 | 2026-04-18 16:34:49.888393 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-04-18 16:34:50.148408 | rockylinux-9 | ok 2026-04-18 16:34:50.160476 | 2026-04-18 16:34:50.160605 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-04-18 16:34:51.063709 | rockylinux-9 | changed 2026-04-18 16:34:51.074144 | 2026-04-18 16:34:51.074237 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-04-18 16:34:51.964048 | rockylinux-9 | changed 2026-04-18 16:34:51.972831 | 2026-04-18 16:34:51.973012 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-04-18 16:34:51.988948 | rockylinux-9 | skipping: Conditional result was False 2026-04-18 16:34:51.995505 | 2026-04-18 16:34:51.995658 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-04-18 16:34:52.407305 | rockylinux-9 -> localhost | changed 2026-04-18 16:34:52.415946 | 2026-04-18 16:34:52.416023 | TASK [add-build-sshkey : Add back temp key] 2026-04-18 16:34:53.113383 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/8f8519b5d8334f049235aeca2784f702/work/8f8519b5d8334f049235aeca2784f702_id_rsa (zuul-build-sshkey) 2026-04-18 16:34:53.113597 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.009016 2026-04-18 16:34:53.124228 | 2026-04-18 16:34:53.124433 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-04-18 16:34:53.602832 | rockylinux-9 | ok 2026-04-18 16:34:53.607338 | 2026-04-18 16:34:53.607406 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-04-18 16:34:53.635604 | rockylinux-9 | skipping: Conditional result was False 2026-04-18 16:34:53.648833 | 2026-04-18 16:34:53.648939 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-04-18 16:34:54.043561 | rockylinux-9 | ok 2026-04-18 16:34:54.053222 | 2026-04-18 16:34:54.053303 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-04-18 16:34:54.972943 | rockylinux-9 | Output suppressed because no_log was given 2026-04-18 16:34:54.981320 | 2026-04-18 16:34:54.981392 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-04-18 16:34:55.258645 | rockylinux-9 | ok: "logs" 2026-04-18 16:34:55.258940 | rockylinux-9 | ok: All items complete 2026-04-18 16:34:55.258970 | 2026-04-18 16:34:55.513611 | rockylinux-9 | ok: "artifacts" 2026-04-18 16:34:55.742404 | rockylinux-9 | ok: "docs" 2026-04-18 16:34:55.984579 | 2026-04-18 16:34:55.984714 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-04-18 16:34:56.300858 | rockylinux-9 | changed: "logs" 2026-04-18 16:34:56.525993 | rockylinux-9 | changed: "artifacts" 2026-04-18 16:34:56.744645 | rockylinux-9 | changed: "docs" 2026-04-18 16:34:56.754915 | 2026-04-18 16:34:56.755011 | PLAY RECAP 2026-04-18 16:34:56.755055 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-18 16:34:56.755087 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-04-18 16:34:56.755109 | 2026-04-18 16:34:56.885987 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-18 16:34:56.887016 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-18 16:34:57.555316 | 2026-04-18 16:34:57.555517 | PLAY [all] 2026-04-18 16:34:57.570065 | 2026-04-18 16:34:57.570203 | TASK [setup-uv : Extract archive] 2026-04-18 16:35:00.439478 | rockylinux-9 | changed 2026-04-18 16:35:00.446462 | 2026-04-18 16:35:00.446668 | TASK [setup-uv : Print version] 2026-04-18 16:35:00.474326 | [rockylinux-9] Waiting on logger 2026-04-18 16:35:00.995799 | rockylinux-9 | ok: Runtime: 0:00:00.013194 2026-04-18 16:35:01.005048 | 2026-04-18 16:35:01.005895 | PLAY RECAP 2026-04-18 16:35:01.005984 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-18 16:35:01.006021 | 2026-04-18 16:35:01.213977 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-18 16:35:01.219767 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-18 16:35:01.797990 | 2026-04-18 16:35:01.798129 | PLAY [all] 2026-04-18 16:35:01.808896 | 2026-04-18 16:35:01.808987 | TASK [Copy inventory file for Zuul] 2026-04-18 16:35:03.255527 | rockylinux-9 | changed 2026-04-18 16:35:03.261828 | 2026-04-18 16:35:03.261914 | TASK [Switch "ansible_host" to private IP] 2026-04-18 16:35:03.618883 | rockylinux-9 | changed: 1 replacements made 2026-04-18 16:35:03.625626 | 2026-04-18 16:35:03.625761 | TASK [Run Molecule scenario] 2026-04-18 16:35:03.651662 | [rockylinux-9] Waiting on logger 2026-04-18 16:35:11.813853 | [rockylinux-9] Waiting on logger 2026-04-18 16:35:21.993868 | [rockylinux-9] Waiting on logger 2026-04-18 16:35:32.325884 | [rockylinux-9] Waiting on logger 2026-04-18 16:35:41.487206 | [rockylinux-9] Waiting on logger 2026-04-18 16:35:51.653770 | [rockylinux-9] Waiting on logger 2026-04-18 16:36:01.830056 | [rockylinux-9] Waiting on logger 2026-04-18 16:36:11.845929 | [rockylinux-9] Waiting on logger 2026-04-18 16:36:22.021854 | [rockylinux-9] Waiting on logger 2026-04-18 16:36:32.197835 | [rockylinux-9] Waiting on logger 2026-04-18 16:36:42.383973 | [rockylinux-9] Waiting on logger 2026-04-18 16:36:51.539718 | [rockylinux-9] Waiting on logger 2026-04-18 16:37:01.705814 | [rockylinux-9] Waiting on logger 2026-04-18 16:37:11.877849 | [rockylinux-9] Waiting on logger 2026-04-18 16:37:22.057875 | [rockylinux-9] Waiting on logger 2026-04-18 16:37:32.453929 | [rockylinux-9] Waiting on logger 2026-04-18 16:37:41.605775 | [rockylinux-9] Waiting on logger 2026-04-18 16:37:51.782161 | [rockylinux-9] Waiting on logger 2026-04-18 16:38:01.798016 | [rockylinux-9] Waiting on logger 2026-04-18 16:38:11.974427 | [rockylinux-9] Waiting on logger 2026-04-18 16:38:22.149806 | [rockylinux-9] Waiting on logger 2026-04-18 16:38:32.325809 | [rockylinux-9] Waiting on logger 2026-04-18 16:38:41.483053 | [rockylinux-9] Waiting on logger 2026-04-18 16:38:51.782360 | [rockylinux-9] Waiting on logger 2026-04-18 16:39:02.314330 | [rockylinux-9] Waiting on logger 2026-04-18 16:39:12.485841 | [rockylinux-9] Waiting on logger 2026-04-18 16:39:21.641667 | [rockylinux-9] Waiting on logger 2026-04-18 16:39:31.813813 | [rockylinux-9] Waiting on logger 2026-04-18 16:39:41.989866 | [rockylinux-9] Waiting on logger 2026-04-18 16:39:52.165848 | [rockylinux-9] Waiting on logger 2026-04-18 16:40:02.345925 | [rockylinux-9] Waiting on logger 2026-04-18 16:40:11.499145 | [rockylinux-9] Waiting on logger 2026-04-18 16:40:21.669943 | [rockylinux-9] Waiting on logger 2026-04-18 16:40:26.154691 | rockylinux-9 | ok: Runtime: 0:05:21.039114 2026-04-18 16:40:26.162823 | 2026-04-18 16:40:26.162888 | PLAY RECAP 2026-04-18 16:40:26.162950 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-18 16:40:26.162984 | 2026-04-18 16:40:26.263547 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-18 16:40:26.265748 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-18 16:40:26.825371 | 2026-04-18 16:40:26.825487 | PLAY [all] 2026-04-18 16:40:26.835779 | 2026-04-18 16:40:26.835854 | TASK [fetch-output : Set log path for multiple nodes] 2026-04-18 16:40:26.880469 | rockylinux-9 | skipping: Conditional result was False 2026-04-18 16:40:26.888302 | 2026-04-18 16:40:26.888427 | TASK [fetch-output : Set log path for single node] 2026-04-18 16:40:26.931446 | rockylinux-9 | ok 2026-04-18 16:40:26.939026 | 2026-04-18 16:40:26.939124 | LOOP [fetch-output : Ensure local output dirs] 2026-04-18 16:40:27.325673 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/8f8519b5d8334f049235aeca2784f702/work/logs" 2026-04-18 16:40:27.534049 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/8f8519b5d8334f049235aeca2784f702/work/artifacts" 2026-04-18 16:40:27.763399 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/8f8519b5d8334f049235aeca2784f702/work/docs" 2026-04-18 16:40:27.783300 | 2026-04-18 16:40:27.783438 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-04-18 16:40:28.468495 | rockylinux-9 | ok 2026-04-18 16:40:28.468717 | rockylinux-9 | changed: All items complete 2026-04-18 16:40:28.468757 | 2026-04-18 16:40:28.967827 | rockylinux-9 | changed: .d..t...... ./ 2026-04-18 16:40:29.487981 | rockylinux-9 | changed: .d..t...... ./ 2026-04-18 16:40:29.506955 | 2026-04-18 16:40:29.507225 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-04-18 16:40:29.909970 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.008276 2026-04-18 16:40:30.122412 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.007573 2026-04-18 16:40:30.136205 | 2026-04-18 16:40:30.136332 | PLAY [all] 2026-04-18 16:40:30.142139 | 2026-04-18 16:40:30.142205 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-04-18 16:40:30.584452 | rockylinux-9 | changed 2026-04-18 16:40:30.589181 | 2026-04-18 16:40:30.589229 | PLAY RECAP 2026-04-18 16:40:30.589270 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-04-18 16:40:30.589292 | 2026-04-18 16:40:30.681435 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-18 16:40:30.682796 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-04-18 16:40:31.283009 | 2026-04-18 16:40:31.283633 | PLAY [localhost] 2026-04-18 16:40:31.293824 | 2026-04-18 16:40:31.293910 | TASK [Generate Zuul manifest] 2026-04-18 16:40:31.311482 | localhost | ok 2026-04-18 16:40:31.325647 | 2026-04-18 16:40:31.325757 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-04-18 16:40:31.638645 | localhost | changed 2026-04-18 16:40:31.651802 | 2026-04-18 16:40:31.651873 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-04-18 16:40:31.682297 | localhost | ok 2026-04-18 16:40:31.691334 | 2026-04-18 16:40:31.691418 | TASK [Upload logs] 2026-04-18 16:40:31.711912 | localhost | ok 2026-04-18 16:40:31.772669 | 2026-04-18 16:40:31.772791 | TASK [Set zuul-log-path fact] 2026-04-18 16:40:31.790748 | localhost | ok 2026-04-18 16:40:31.803378 | 2026-04-18 16:40:31.803441 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-18 16:40:31.833277 | localhost | ok 2026-04-18 16:40:31.842537 | 2026-04-18 16:40:31.842621 | TASK [upload-logs : Create log directories] 2026-04-18 16:40:32.210142 | localhost | changed 2026-04-18 16:40:32.218049 | 2026-04-18 16:40:32.218165 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-04-18 16:40:32.583351 | localhost -> localhost | ok: Runtime: 0:00:00.004688 2026-04-18 16:40:32.588697 | 2026-04-18 16:40:32.588766 | TASK [upload-logs : Upload logs to log server] 2026-04-18 16:40:33.047291 | localhost | Output suppressed because no_log was given 2026-04-18 16:40:33.052386 | 2026-04-18 16:40:33.052478 | LOOP [upload-logs : Compress console log and json output] 2026-04-18 16:40:33.099519 | localhost | skipping: Conditional result was False 2026-04-18 16:40:33.106171 | localhost | skipping: Conditional result was False 2026-04-18 16:40:33.117972 | 2026-04-18 16:40:33.118141 | LOOP [upload-logs : Upload compressed console log and json output] 2026-04-18 16:40:33.163090 | localhost | skipping: Conditional result was False 2026-04-18 16:40:33.163463 | 2026-04-18 16:40:33.167249 | localhost | skipping: Conditional result was False 2026-04-18 16:40:33.184384 | 2026-04-18 16:40:33.184583 | LOOP [upload-logs : Upload console log and json output]