2026-04-18 16:35:08.911544 | Job console starting 2026-04-18 16:35:08.925816 | Updating git repos 2026-04-18 16:35:08.951324 | Cloning repos into workspace 2026-04-18 16:35:09.054030 | Restoring repo states 2026-04-18 16:35:09.062400 | Merging changes 2026-04-18 16:35:09.447844 | Checking out repos 2026-04-18 16:35:09.495180 | Preparing playbooks 2026-04-18 16:35:10.331318 | Running Ansible setup 2026-04-18 16:35:14.135614 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-18 16:35:14.827913 | 2026-04-18 16:35:14.828053 | PLAY [localhost] 2026-04-18 16:35:14.836239 | 2026-04-18 16:35:14.836323 | TASK [Gathering Facts] 2026-04-18 16:35:15.703951 | localhost | ok 2026-04-18 16:35:15.715579 | 2026-04-18 16:35:15.715717 | TASK [Setup log path fact] 2026-04-18 16:35:15.743453 | localhost | ok 2026-04-18 16:35:15.756233 | 2026-04-18 16:35:15.756373 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-18 16:35:15.801448 | localhost | ok 2026-04-18 16:35:15.812329 | 2026-04-18 16:35:15.812409 | TASK [emit-job-header : Print job information] 2026-04-18 16:35:15.868689 | # Job Information 2026-04-18 16:35:15.868861 | Ansible Version: 2.16.16 2026-04-18 16:35:15.868908 | Job: ansible-collection-containers-molecule-docker-rockylinux-9 2026-04-18 16:35:15.868940 | Pipeline: check 2026-04-18 16:35:15.868999 | Executor: 0a8996d2b663 2026-04-18 16:35:15.869031 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/110 2026-04-18 16:35:15.869063 | Event ID: 34c38600-3b44-11f1-92f3-e4509f68404d 2026-04-18 16:35:15.875442 | 2026-04-18 16:35:15.875510 | LOOP [emit-job-header : Print node information] 2026-04-18 16:35:15.961726 | localhost | ok: 2026-04-18 16:35:15.961984 | localhost | # Node Information 2026-04-18 16:35:15.962033 | localhost | Inventory Hostname: rockylinux-9 2026-04-18 16:35:15.962069 | localhost | Hostname: np0000167320 2026-04-18 16:35:15.962100 | localhost | Username: zuul 2026-04-18 16:35:15.962135 | localhost | Distro: Rocky 9.7 2026-04-18 16:35:15.962167 | localhost | Provider: yul1 2026-04-18 16:35:15.962198 | localhost | Region: ca-ymq-1 2026-04-18 16:35:15.962229 | localhost | Label: rockylinux-9 2026-04-18 16:35:15.962260 | localhost | Product Name: OpenStack Nova 2026-04-18 16:35:15.962291 | localhost | Interface IP: 199.19.213.75 2026-04-18 16:35:15.984880 | 2026-04-18 16:35:15.985020 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-04-18 16:35:16.385382 | localhost -> localhost | changed 2026-04-18 16:35:16.393362 | 2026-04-18 16:35:16.393475 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-04-18 16:35:17.305208 | localhost -> localhost | changed 2026-04-18 16:35:17.311124 | 2026-04-18 16:35:17.311198 | PLAY [all] 2026-04-18 16:35:17.324431 | 2026-04-18 16:35:17.324561 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-04-18 16:35:17.628681 | rockylinux-9 -> localhost | ok 2026-04-18 16:35:17.634802 | 2026-04-18 16:35:17.634883 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-04-18 16:35:17.666355 | rockylinux-9 | ok 2026-04-18 16:35:17.688706 | rockylinux-9 | included: /var/lib/zuul/builds/0d92090d8e684403a5c2d1eaf90cef87/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-04-18 16:35:17.696654 | 2026-04-18 16:35:17.696812 | TASK [add-build-sshkey : Create Temp SSH key] 2026-04-18 16:35:19.350298 | rockylinux-9 -> localhost | Generating public/private rsa key pair. 2026-04-18 16:35:19.350485 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/0d92090d8e684403a5c2d1eaf90cef87/work/0d92090d8e684403a5c2d1eaf90cef87_id_rsa 2026-04-18 16:35:19.350530 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/0d92090d8e684403a5c2d1eaf90cef87/work/0d92090d8e684403a5c2d1eaf90cef87_id_rsa.pub 2026-04-18 16:35:19.350564 | rockylinux-9 -> localhost | The key fingerprint is: 2026-04-18 16:35:19.350596 | rockylinux-9 -> localhost | SHA256:UG2nhDBt39jaoyW9Pj/o6RwIUVlLNFJ6wxvdXV5aGuE zuul-build-sshkey 2026-04-18 16:35:19.350644 | rockylinux-9 -> localhost | The key's randomart image is: 2026-04-18 16:35:19.350676 | rockylinux-9 -> localhost | +---[RSA 3072]----+ 2026-04-18 16:35:19.350715 | rockylinux-9 -> localhost | | oo.oo=* o.+| 2026-04-18 16:35:19.350747 | rockylinux-9 -> localhost | | o+.==.= B+| 2026-04-18 16:35:19.350778 | rockylinux-9 -> localhost | | ...+.** E +| 2026-04-18 16:35:19.350808 | rockylinux-9 -> localhost | | . .+.o+ | 2026-04-18 16:35:19.350838 | rockylinux-9 -> localhost | | S +. | 2026-04-18 16:35:19.350867 | rockylinux-9 -> localhost | | .o.= | 2026-04-18 16:35:19.351087 | rockylinux-9 -> localhost | | .+.+ | 2026-04-18 16:35:19.351157 | rockylinux-9 -> localhost | | ..+o. | 2026-04-18 16:35:19.351209 | rockylinux-9 -> localhost | | +*o.. | 2026-04-18 16:35:19.351243 | rockylinux-9 -> localhost | +----[SHA256]-----+ 2026-04-18 16:35:19.351313 | rockylinux-9 -> localhost | ok: Runtime: 0:00:01.237210 2026-04-18 16:35:19.362724 | 2026-04-18 16:35:19.362816 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-04-18 16:35:19.395517 | rockylinux-9 | ok 2026-04-18 16:35:19.406768 | rockylinux-9 | included: /var/lib/zuul/builds/0d92090d8e684403a5c2d1eaf90cef87/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-04-18 16:35:19.416547 | 2026-04-18 16:35:19.416628 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-04-18 16:35:19.441111 | rockylinux-9 | skipping: Conditional result was False 2026-04-18 16:35:19.447827 | 2026-04-18 16:35:19.447915 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-04-18 16:35:19.933747 | rockylinux-9 | changed 2026-04-18 16:35:19.941362 | 2026-04-18 16:35:19.941717 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-04-18 16:35:20.198354 | rockylinux-9 | ok 2026-04-18 16:35:20.204818 | 2026-04-18 16:35:20.204905 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-04-18 16:35:21.209503 | rockylinux-9 | changed 2026-04-18 16:35:21.215424 | 2026-04-18 16:35:21.215491 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-04-18 16:35:22.130958 | rockylinux-9 | changed 2026-04-18 16:35:22.179814 | 2026-04-18 16:35:22.179915 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-04-18 16:35:22.197783 | rockylinux-9 | skipping: Conditional result was False 2026-04-18 16:35:22.203062 | 2026-04-18 16:35:22.203130 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-04-18 16:35:22.642273 | rockylinux-9 -> localhost | changed 2026-04-18 16:35:22.671906 | 2026-04-18 16:35:22.672128 | TASK [add-build-sshkey : Add back temp key] 2026-04-18 16:35:22.960053 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/0d92090d8e684403a5c2d1eaf90cef87/work/0d92090d8e684403a5c2d1eaf90cef87_id_rsa (zuul-build-sshkey) 2026-04-18 16:35:22.960319 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.012148 2026-04-18 16:35:22.968864 | 2026-04-18 16:35:22.968934 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-04-18 16:35:23.349368 | rockylinux-9 | ok 2026-04-18 16:35:23.587410 | 2026-04-18 16:35:23.587528 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-04-18 16:35:23.613638 | rockylinux-9 | skipping: Conditional result was False 2026-04-18 16:35:23.627029 | 2026-04-18 16:35:23.627174 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-04-18 16:35:24.043170 | rockylinux-9 | ok 2026-04-18 16:35:24.050849 | 2026-04-18 16:35:24.050963 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-04-18 16:35:25.000920 | rockylinux-9 | Output suppressed because no_log was given 2026-04-18 16:35:25.011069 | 2026-04-18 16:35:25.011267 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-04-18 16:35:25.258157 | rockylinux-9 | ok: "logs" 2026-04-18 16:35:25.258407 | rockylinux-9 | ok: All items complete 2026-04-18 16:35:25.258442 | 2026-04-18 16:35:25.475411 | rockylinux-9 | ok: "artifacts" 2026-04-18 16:35:25.710949 | rockylinux-9 | ok: "docs" 2026-04-18 16:35:25.733690 | 2026-04-18 16:35:25.733801 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-04-18 16:35:26.005800 | rockylinux-9 | changed: "logs" 2026-04-18 16:35:26.240521 | rockylinux-9 | changed: "artifacts" 2026-04-18 16:35:26.483648 | rockylinux-9 | changed: "docs" 2026-04-18 16:35:26.496188 | 2026-04-18 16:35:26.496290 | PLAY RECAP 2026-04-18 16:35:26.496337 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-18 16:35:26.496367 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-04-18 16:35:26.496391 | 2026-04-18 16:35:26.611261 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-18 16:35:26.612522 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-18 16:35:27.247780 | 2026-04-18 16:35:27.247895 | PLAY [all] 2026-04-18 16:35:27.259810 | 2026-04-18 16:35:27.260109 | TASK [setup-uv : Extract archive] 2026-04-18 16:35:30.536268 | rockylinux-9 | changed 2026-04-18 16:35:31.717559 | 2026-04-18 16:35:31.717704 | TASK [setup-uv : Print version] 2026-04-18 16:35:31.752392 | [rockylinux-9] Waiting on logger 2026-04-18 16:35:32.267453 | rockylinux-9 | ok: Runtime: 0:00:00.015712 2026-04-18 16:35:32.274778 | 2026-04-18 16:35:32.274844 | PLAY RECAP 2026-04-18 16:35:32.274886 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-18 16:35:32.274910 | 2026-04-18 16:35:32.486138 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-18 16:35:32.487657 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-18 16:35:33.089307 | 2026-04-18 16:35:33.089428 | PLAY [all] 2026-04-18 16:35:33.099849 | 2026-04-18 16:35:33.099936 | TASK [Copy inventory file for Zuul] 2026-04-18 16:35:34.516336 | rockylinux-9 | changed 2026-04-18 16:35:34.524822 | 2026-04-18 16:35:34.525089 | TASK [Switch "ansible_host" to private IP] 2026-04-18 16:35:34.885805 | rockylinux-9 | changed: 1 replacements made 2026-04-18 16:35:34.893038 | 2026-04-18 16:35:34.893171 | TASK [Run Molecule scenario] 2026-04-18 16:35:34.916947 | [rockylinux-9] Waiting on logger 2026-04-18 16:35:43.049718 | [rockylinux-9] Waiting on logger 2026-04-18 16:35:53.610288 | [rockylinux-9] Waiting on logger 2026-04-18 16:36:03.109871 | [rockylinux-9] Waiting on logger 2026-04-18 16:36:13.286272 | [rockylinux-9] Waiting on logger 2026-04-18 16:36:23.589915 | [rockylinux-9] Waiting on logger 2026-04-18 16:36:32.779211 | [rockylinux-9] Waiting on logger 2026-04-18 16:36:43.658037 | [rockylinux-9] Waiting on logger 2026-04-18 16:36:53.029874 | [rockylinux-9] Waiting on logger 2026-04-18 16:37:03.205885 | [rockylinux-9] Waiting on logger 2026-04-18 16:37:13.382063 | [rockylinux-9] Waiting on logger 2026-04-18 16:37:23.398150 | [rockylinux-9] Waiting on logger 2026-04-18 16:37:31.341965 | rockylinux-9 | ok: Runtime: 0:01:55.725444 2026-04-18 16:37:31.347810 | 2026-04-18 16:37:31.347852 | PLAY RECAP 2026-04-18 16:37:31.347891 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-18 16:37:31.347912 | 2026-04-18 16:37:31.450272 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-18 16:37:31.451661 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-18 16:37:32.030139 | 2026-04-18 16:37:32.030259 | PLAY [all] 2026-04-18 16:37:32.040926 | 2026-04-18 16:37:32.041021 | TASK [fetch-output : Set log path for multiple nodes] 2026-04-18 16:37:32.085556 | rockylinux-9 | skipping: Conditional result was False 2026-04-18 16:37:32.095464 | 2026-04-18 16:37:32.095634 | TASK [fetch-output : Set log path for single node] 2026-04-18 16:37:32.141300 | rockylinux-9 | ok 2026-04-18 16:37:32.402724 | 2026-04-18 16:37:32.402823 | LOOP [fetch-output : Ensure local output dirs] 2026-04-18 16:37:32.821017 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/0d92090d8e684403a5c2d1eaf90cef87/work/logs" 2026-04-18 16:37:33.033038 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/0d92090d8e684403a5c2d1eaf90cef87/work/artifacts" 2026-04-18 16:37:33.282389 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/0d92090d8e684403a5c2d1eaf90cef87/work/docs" 2026-04-18 16:37:33.297702 | 2026-04-18 16:37:33.297805 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-04-18 16:37:33.948537 | rockylinux-9 | changed: .d..t...... ./ 2026-04-18 16:37:33.948806 | rockylinux-9 | changed: All items complete 2026-04-18 16:37:33.948834 | 2026-04-18 16:37:34.460300 | rockylinux-9 | changed: .d..t...... ./ 2026-04-18 16:37:35.005350 | rockylinux-9 | changed: .d..t...... ./ 2026-04-18 16:37:35.033237 | 2026-04-18 16:37:35.033348 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-04-18 16:37:36.043934 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.223198 2026-04-18 16:37:36.323853 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.005859 2026-04-18 16:37:36.341852 | 2026-04-18 16:37:36.341972 | PLAY [all] 2026-04-18 16:37:36.347362 | 2026-04-18 16:37:36.347427 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-04-18 16:37:36.800617 | rockylinux-9 | changed 2026-04-18 16:37:36.810971 | 2026-04-18 16:37:36.811032 | PLAY RECAP 2026-04-18 16:37:36.811076 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-04-18 16:37:36.811099 | 2026-04-18 16:37:37.012455 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-18 16:37:37.458974 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-04-18 16:37:38.019340 | 2026-04-18 16:37:38.019447 | PLAY [localhost] 2026-04-18 16:37:38.028815 | 2026-04-18 16:37:38.028887 | TASK [Generate Zuul manifest] 2026-04-18 16:37:38.048820 | localhost | ok 2026-04-18 16:37:38.064012 | 2026-04-18 16:37:38.064089 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-04-18 16:37:38.754793 | localhost | changed 2026-04-18 16:37:38.774866 | 2026-04-18 16:37:38.775104 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-04-18 16:37:38.838621 | localhost | ok 2026-04-18 16:37:38.849745 | 2026-04-18 16:37:38.849848 | TASK [Upload logs] 2026-04-18 16:37:38.869296 | localhost | ok 2026-04-18 16:37:38.927241 | 2026-04-18 16:37:39.359006 | TASK [Set zuul-log-path fact] 2026-04-18 16:37:39.399304 | localhost | ok 2026-04-18 16:37:39.413484 | 2026-04-18 16:37:39.413553 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-18 16:37:39.444318 | localhost | ok 2026-04-18 16:37:39.454262 | 2026-04-18 16:37:39.454334 | TASK [upload-logs : Create log directories] 2026-04-18 16:37:39.921560 | localhost | changed 2026-04-18 16:37:39.928398 | 2026-04-18 16:37:39.928500 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-04-18 16:37:40.507881 | localhost -> localhost | ok: Runtime: 0:00:00.007397 2026-04-18 16:37:40.513961 | 2026-04-18 16:37:40.514042 | TASK [upload-logs : Upload logs to log server] 2026-04-18 16:37:41.013602 | localhost | Output suppressed because no_log was given 2026-04-18 16:37:41.020501 | 2026-04-18 16:37:41.020593 | LOOP [upload-logs : Compress console log and json output] 2026-04-18 16:37:41.067488 | localhost | skipping: Conditional result was False 2026-04-18 16:37:41.075119 | localhost | skipping: Conditional result was False 2026-04-18 16:37:41.082500 | 2026-04-18 16:37:41.082726 | LOOP [upload-logs : Upload compressed console log and json output] 2026-04-18 16:37:41.119208 | localhost | skipping: Conditional result was False 2026-04-18 16:37:41.119474 | 2026-04-18 16:37:41.123150 | localhost | skipping: Conditional result was False 2026-04-18 16:37:41.134581 | 2026-04-18 16:37:41.134757 | LOOP [upload-logs : Upload console log and json output]