2026-04-20 02:14:19.038863 | Job console starting 2026-04-20 02:14:19.050278 | Updating git repos 2026-04-20 02:14:19.078944 | Cloning repos into workspace 2026-04-20 02:14:19.130632 | Restoring repo states 2026-04-20 02:14:19.138555 | Merging changes 2026-04-20 02:14:19.544129 | Checking out repos 2026-04-20 02:14:19.580326 | Preparing playbooks 2026-04-20 02:14:20.790126 | Running Ansible setup 2026-04-20 02:14:24.846363 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-20 02:14:25.575988 | 2026-04-20 02:14:25.576173 | PLAY [localhost] 2026-04-20 02:14:25.588851 | 2026-04-20 02:14:25.588991 | TASK [Gathering Facts] 2026-04-20 02:14:26.653390 | localhost | ok 2026-04-20 02:14:26.663107 | 2026-04-20 02:14:26.663267 | TASK [Setup log path fact] 2026-04-20 02:14:26.689850 | localhost | ok 2026-04-20 02:14:26.863909 | 2026-04-20 02:14:26.864062 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-20 02:14:26.896068 | localhost | ok 2026-04-20 02:14:26.904938 | 2026-04-20 02:14:26.905079 | TASK [emit-job-header : Print job information] 2026-04-20 02:14:26.948686 | # Job Information 2026-04-20 02:14:26.949030 | Ansible Version: 2.16.16 2026-04-20 02:14:26.949147 | Job: ansible-collection-containers-molecule-docker-rockylinux-9 2026-04-20 02:14:26.949216 | Pipeline: check 2026-04-20 02:14:26.949268 | Executor: 0a8996d2b663 2026-04-20 02:14:26.949316 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/107 2026-04-20 02:14:26.949371 | Event ID: dc51ced0-3c5d-11f1-9347-b696a2645859 2026-04-20 02:14:26.955929 | 2026-04-20 02:14:26.956000 | LOOP [emit-job-header : Print node information] 2026-04-20 02:14:27.054069 | localhost | ok: 2026-04-20 02:14:27.054600 | localhost | # Node Information 2026-04-20 02:14:27.054656 | localhost | Inventory Hostname: rockylinux-9 2026-04-20 02:14:27.054689 | localhost | Hostname: np0000167942 2026-04-20 02:14:27.054718 | localhost | Username: zuul 2026-04-20 02:14:27.054749 | localhost | Distro: Rocky 9.7 2026-04-20 02:14:27.054778 | localhost | Provider: yul1 2026-04-20 02:14:27.054805 | localhost | Region: ca-ymq-1 2026-04-20 02:14:27.054830 | localhost | Label: rockylinux-9 2026-04-20 02:14:27.054856 | localhost | Product Name: OpenStack Nova 2026-04-20 02:14:27.054883 | localhost | Interface IP: 199.19.213.242 2026-04-20 02:14:27.075419 | 2026-04-20 02:14:27.075614 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-04-20 02:14:27.578691 | localhost -> localhost | changed 2026-04-20 02:14:27.584650 | 2026-04-20 02:14:27.584732 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-04-20 02:14:28.627192 | localhost -> localhost | changed 2026-04-20 02:14:28.637132 | 2026-04-20 02:14:28.637286 | PLAY [all] 2026-04-20 02:14:28.646487 | 2026-04-20 02:14:28.646583 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-04-20 02:14:28.904516 | rockylinux-9 -> localhost | ok 2026-04-20 02:14:28.914573 | 2026-04-20 02:14:28.914756 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-04-20 02:14:28.950014 | rockylinux-9 | ok 2026-04-20 02:14:28.968465 | rockylinux-9 | included: /var/lib/zuul/builds/64c91475a3cd40949e457199de6c9973/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-04-20 02:14:28.974671 | 2026-04-20 02:14:28.976338 | TASK [add-build-sshkey : Create Temp SSH key] 2026-04-20 02:14:30.293793 | rockylinux-9 -> localhost | Generating public/private rsa key pair. 2026-04-20 02:14:30.294026 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/64c91475a3cd40949e457199de6c9973/work/64c91475a3cd40949e457199de6c9973_id_rsa 2026-04-20 02:14:30.294170 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/64c91475a3cd40949e457199de6c9973/work/64c91475a3cd40949e457199de6c9973_id_rsa.pub 2026-04-20 02:14:30.294227 | rockylinux-9 -> localhost | The key fingerprint is: 2026-04-20 02:14:30.294263 | rockylinux-9 -> localhost | SHA256:zlrZvP0Qjn8BBdzWfmO09YCbf1Uwzy+eAubkN049rl8 zuul-build-sshkey 2026-04-20 02:14:30.294316 | rockylinux-9 -> localhost | The key's randomart image is: 2026-04-20 02:14:30.294347 | rockylinux-9 -> localhost | +---[RSA 3072]----+ 2026-04-20 02:14:30.294387 | rockylinux-9 -> localhost | | ..+oo | 2026-04-20 02:14:30.294418 | rockylinux-9 -> localhost | | o ==+| 2026-04-20 02:14:30.294446 | rockylinux-9 -> localhost | | =.oB| 2026-04-20 02:14:30.294474 | rockylinux-9 -> localhost | | + +*| 2026-04-20 02:14:30.294501 | rockylinux-9 -> localhost | | S + .oo =| 2026-04-20 02:14:30.294527 | rockylinux-9 -> localhost | | o O + +oo.| 2026-04-20 02:14:30.294554 | rockylinux-9 -> localhost | | = * B =oE| 2026-04-20 02:14:30.294581 | rockylinux-9 -> localhost | | o B =.o | 2026-04-20 02:14:30.294611 | rockylinux-9 -> localhost | | . . ==+ | 2026-04-20 02:14:30.294639 | rockylinux-9 -> localhost | +----[SHA256]-----+ 2026-04-20 02:14:30.294711 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.842157 2026-04-20 02:14:30.301684 | 2026-04-20 02:14:30.301806 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-04-20 02:14:30.339342 | rockylinux-9 | ok 2026-04-20 02:14:30.352548 | rockylinux-9 | included: /var/lib/zuul/builds/64c91475a3cd40949e457199de6c9973/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-04-20 02:14:30.361657 | 2026-04-20 02:14:30.361763 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-04-20 02:14:30.387216 | rockylinux-9 | skipping: Conditional result was False 2026-04-20 02:14:30.397680 | 2026-04-20 02:14:30.397760 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-04-20 02:14:30.890776 | rockylinux-9 | changed 2026-04-20 02:14:30.898566 | 2026-04-20 02:14:30.898642 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-04-20 02:14:31.156244 | rockylinux-9 | ok 2026-04-20 02:14:31.161763 | 2026-04-20 02:14:31.161854 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-04-20 02:14:38.078234 | rockylinux-9 | changed 2026-04-20 02:14:38.085455 | 2026-04-20 02:14:38.085565 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-04-20 02:14:39.009385 | rockylinux-9 | changed 2026-04-20 02:14:39.018636 | 2026-04-20 02:14:39.018737 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-04-20 02:14:39.047308 | rockylinux-9 | skipping: Conditional result was False 2026-04-20 02:14:39.058964 | 2026-04-20 02:14:39.059126 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-04-20 02:14:39.452986 | rockylinux-9 -> localhost | changed 2026-04-20 02:14:39.465364 | 2026-04-20 02:14:39.465473 | TASK [add-build-sshkey : Add back temp key] 2026-04-20 02:14:39.798689 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/64c91475a3cd40949e457199de6c9973/work/64c91475a3cd40949e457199de6c9973_id_rsa (zuul-build-sshkey) 2026-04-20 02:14:39.798963 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.016027 2026-04-20 02:14:39.809285 | 2026-04-20 02:14:39.809424 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-04-20 02:14:40.224543 | rockylinux-9 | ok 2026-04-20 02:14:40.230618 | 2026-04-20 02:14:40.230692 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-04-20 02:14:40.265889 | rockylinux-9 | skipping: Conditional result was False 2026-04-20 02:14:40.278417 | 2026-04-20 02:14:40.278490 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-04-20 02:14:40.695693 | rockylinux-9 | ok 2026-04-20 02:14:40.702740 | 2026-04-20 02:14:40.702842 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-04-20 02:14:41.645464 | rockylinux-9 | Output suppressed because no_log was given 2026-04-20 02:14:41.653737 | 2026-04-20 02:14:41.653814 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-04-20 02:14:41.908921 | rockylinux-9 | ok: "logs" 2026-04-20 02:14:41.909280 | rockylinux-9 | ok: All items complete 2026-04-20 02:14:41.909321 | 2026-04-20 02:14:42.125553 | rockylinux-9 | ok: "artifacts" 2026-04-20 02:14:42.346018 | rockylinux-9 | ok: "docs" 2026-04-20 02:14:42.359553 | 2026-04-20 02:14:42.359706 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-04-20 02:14:42.609878 | rockylinux-9 | changed: "logs" 2026-04-20 02:14:42.850247 | rockylinux-9 | changed: "artifacts" 2026-04-20 02:14:43.102493 | rockylinux-9 | changed: "docs" 2026-04-20 02:14:43.131052 | 2026-04-20 02:14:43.131165 | PLAY RECAP 2026-04-20 02:14:43.131215 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-20 02:14:43.131246 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-04-20 02:14:43.131269 | 2026-04-20 02:14:43.311765 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-20 02:14:43.313426 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-20 02:14:43.989169 | 2026-04-20 02:14:43.989350 | PLAY [all] 2026-04-20 02:14:44.001313 | 2026-04-20 02:14:44.001428 | TASK [setup-uv : Extract archive] 2026-04-20 02:14:46.767825 | rockylinux-9 | changed 2026-04-20 02:14:46.776155 | 2026-04-20 02:14:46.776324 | TASK [setup-uv : Print version] 2026-04-20 02:14:46.801089 | [rockylinux-9] Waiting on logger 2026-04-20 02:14:47.315837 | rockylinux-9 | ok: Runtime: 0:00:00.016170 2026-04-20 02:14:47.334676 | 2026-04-20 02:14:47.334845 | PLAY RECAP 2026-04-20 02:14:47.334936 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-20 02:14:47.334984 | 2026-04-20 02:14:47.507468 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-20 02:14:47.508856 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-20 02:14:48.336724 | 2026-04-20 02:14:48.336967 | PLAY [all] 2026-04-20 02:14:48.348839 | 2026-04-20 02:14:48.348972 | TASK [Copy inventory file for Zuul] 2026-04-20 02:14:49.787861 | rockylinux-9 | changed 2026-04-20 02:14:49.795256 | 2026-04-20 02:14:49.795429 | TASK [Switch "ansible_host" to private IP] 2026-04-20 02:14:50.172132 | rockylinux-9 | changed: 1 replacements made 2026-04-20 02:14:50.180059 | 2026-04-20 02:14:50.180196 | TASK [Run Molecule scenario] 2026-04-20 02:14:50.203461 | [rockylinux-9] Waiting on logger 2026-04-20 02:14:58.346085 | [rockylinux-9] Waiting on logger 2026-04-20 02:15:08.518081 | [rockylinux-9] Waiting on logger 2026-04-20 02:15:18.693832 | [rockylinux-9] Waiting on logger 2026-04-20 02:15:27.853670 | [rockylinux-9] Waiting on logger 2026-04-20 02:15:38.505820 | [rockylinux-9] Waiting on logger 2026-04-20 02:15:48.681982 | [rockylinux-9] Waiting on logger 2026-04-20 02:15:57.839204 | [rockylinux-9] Waiting on logger 2026-04-20 02:16:08.005821 | [rockylinux-9] Waiting on logger 2026-04-20 02:16:18.182732 | [rockylinux-9] Waiting on logger 2026-04-20 02:16:28.362050 | [rockylinux-9] Waiting on logger 2026-04-20 02:16:38.533959 | [rockylinux-9] Waiting on logger 2026-04-20 02:16:48.713940 | [rockylinux-9] Waiting on logger 2026-04-20 02:16:54.415290 | rockylinux-9 | ok: Runtime: 0:02:02.676370 2026-04-20 02:16:54.422193 | 2026-04-20 02:16:54.422327 | PLAY RECAP 2026-04-20 02:16:54.422393 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-20 02:16:54.422491 | 2026-04-20 02:16:54.569049 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-20 02:16:54.570965 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-20 02:16:55.228695 | 2026-04-20 02:16:55.228857 | PLAY [all] 2026-04-20 02:16:55.242881 | 2026-04-20 02:16:55.243025 | TASK [fetch-output : Set log path for multiple nodes] 2026-04-20 02:16:55.288469 | rockylinux-9 | skipping: Conditional result was False 2026-04-20 02:16:55.295255 | 2026-04-20 02:16:55.295425 | TASK [fetch-output : Set log path for single node] 2026-04-20 02:16:55.339913 | rockylinux-9 | ok 2026-04-20 02:16:55.346147 | 2026-04-20 02:16:55.346230 | LOOP [fetch-output : Ensure local output dirs] 2026-04-20 02:16:55.718880 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/64c91475a3cd40949e457199de6c9973/work/logs" 2026-04-20 02:16:55.941016 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/64c91475a3cd40949e457199de6c9973/work/artifacts" 2026-04-20 02:16:56.177068 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/64c91475a3cd40949e457199de6c9973/work/docs" 2026-04-20 02:16:56.195300 | 2026-04-20 02:16:56.195443 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-04-20 02:16:56.906455 | rockylinux-9 | changed: .d..t...... ./ 2026-04-20 02:16:56.906749 | rockylinux-9 | changed: All items complete 2026-04-20 02:16:56.906786 | 2026-04-20 02:16:57.431615 | rockylinux-9 | changed: .d..t...... ./ 2026-04-20 02:16:57.946036 | rockylinux-9 | changed: .d..t...... ./ 2026-04-20 02:16:57.971776 | 2026-04-20 02:16:57.971904 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-04-20 02:16:58.408363 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.007512 2026-04-20 02:16:58.916335 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.300564 2026-04-20 02:16:58.930922 | 2026-04-20 02:16:58.931078 | PLAY [all] 2026-04-20 02:16:58.939323 | 2026-04-20 02:16:58.939432 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-04-20 02:16:59.399306 | rockylinux-9 | changed 2026-04-20 02:16:59.406325 | 2026-04-20 02:16:59.406400 | PLAY RECAP 2026-04-20 02:16:59.406445 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-04-20 02:16:59.406466 | 2026-04-20 02:16:59.544234 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-20 02:16:59.545506 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-04-20 02:17:00.124032 | 2026-04-20 02:17:00.252303 | PLAY [localhost] 2026-04-20 02:17:00.266804 | 2026-04-20 02:17:00.266912 | TASK [Generate Zuul manifest] 2026-04-20 02:17:00.288031 | localhost | ok 2026-04-20 02:17:00.304288 | 2026-04-20 02:17:00.304408 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-04-20 02:17:00.688368 | localhost | changed 2026-04-20 02:17:00.701651 | 2026-04-20 02:17:00.701768 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-04-20 02:17:00.825273 | localhost | ok 2026-04-20 02:17:00.832610 | 2026-04-20 02:17:00.832689 | TASK [Upload logs] 2026-04-20 02:17:00.853372 | localhost | ok 2026-04-20 02:17:00.911898 | 2026-04-20 02:17:00.916135 | TASK [Set zuul-log-path fact] 2026-04-20 02:17:00.936200 | localhost | ok 2026-04-20 02:17:00.951631 | 2026-04-20 02:17:00.951777 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-20 02:17:00.984401 | localhost | ok 2026-04-20 02:17:00.992830 | 2026-04-20 02:17:00.993017 | TASK [upload-logs : Create log directories] 2026-04-20 02:17:01.420513 | localhost | changed 2026-04-20 02:17:01.425554 | 2026-04-20 02:17:01.425668 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-04-20 02:17:02.027773 | localhost -> localhost | ok: Runtime: 0:00:00.005787 2026-04-20 02:17:02.036818 | 2026-04-20 02:17:02.036982 | TASK [upload-logs : Upload logs to log server] 2026-04-20 02:17:02.492780 | localhost | Output suppressed because no_log was given 2026-04-20 02:17:02.496571 | 2026-04-20 02:17:02.496646 | LOOP [upload-logs : Compress console log and json output] 2026-04-20 02:17:02.557886 | localhost | skipping: Conditional result was False 2026-04-20 02:17:02.569263 | localhost | skipping: Conditional result was False 2026-04-20 02:17:02.585991 | 2026-04-20 02:17:02.586147 | LOOP [upload-logs : Upload compressed console log and json output] 2026-04-20 02:17:02.625285 | localhost | skipping: Conditional result was False 2026-04-20 02:17:02.625523 | 2026-04-20 02:17:02.631878 | localhost | skipping: Conditional result was False 2026-04-20 02:17:02.649400 | 2026-04-20 02:17:02.649547 | LOOP [upload-logs : Upload console log and json output]