2026-04-23 04:38:03.851410 | Job console starting 2026-04-23 04:38:03.888801 | Updating git repos 2026-04-23 04:38:04.014651 | Cloning repos into workspace 2026-04-23 04:38:05.134895 | Restoring repo states 2026-04-23 04:38:05.141290 | Merging changes 2026-04-23 04:38:05.666699 | Checking out repos 2026-04-23 04:38:05.918813 | Preparing playbooks 2026-04-23 04:38:09.394433 | Running Ansible setup 2026-04-23 04:38:14.359801 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-23 04:38:15.173761 | 2026-04-23 04:38:15.174079 | PLAY [localhost] 2026-04-23 04:38:15.192852 | 2026-04-23 04:38:15.193677 | TASK [Gathering Facts] 2026-04-23 04:38:16.575573 | localhost | ok 2026-04-23 04:38:16.584873 | 2026-04-23 04:38:16.585106 | TASK [Setup log path fact] 2026-04-23 04:38:16.613148 | localhost | ok 2026-04-23 04:38:16.803807 | 2026-04-23 04:38:16.803970 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-23 04:38:16.833371 | localhost | ok 2026-04-23 04:38:16.843889 | 2026-04-23 04:38:16.844051 | TASK [emit-job-header : Print job information] 2026-04-23 04:38:16.888354 | # Job Information 2026-04-23 04:38:16.888590 | Ansible Version: 2.16.16 2026-04-23 04:38:16.888647 | Job: ansible-collection-containers-molecule-containerd-rockylinux-9 2026-04-23 04:38:16.888685 | Pipeline: check 2026-04-23 04:38:16.888715 | Executor: 0a8996d2b663 2026-04-23 04:38:16.888742 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/118 2026-04-23 04:38:16.888772 | Event ID: 138f9cc0-3ece-11f1-96c6-0144615fd1ac 2026-04-23 04:38:16.894100 | 2026-04-23 04:38:16.894471 | LOOP [emit-job-header : Print node information] 2026-04-23 04:38:17.001004 | localhost | ok: 2026-04-23 04:38:17.001195 | localhost | # Node Information 2026-04-23 04:38:17.001226 | localhost | Inventory Hostname: rockylinux-9 2026-04-23 04:38:17.001255 | localhost | Hostname: np0000169673 2026-04-23 04:38:17.001275 | localhost | Username: zuul 2026-04-23 04:38:17.001298 | localhost | Distro: Rocky 9.7 2026-04-23 04:38:17.001318 | localhost | Provider: yul1 2026-04-23 04:38:17.001337 | localhost | Region: ca-ymq-1 2026-04-23 04:38:17.001355 | localhost | Label: rockylinux-9 2026-04-23 04:38:17.001374 | localhost | Product Name: OpenStack Nova 2026-04-23 04:38:17.001393 | localhost | Interface IP: 199.204.45.169 2026-04-23 04:38:17.023548 | 2026-04-23 04:38:17.371320 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-04-23 04:38:17.954849 | localhost -> localhost | changed 2026-04-23 04:38:17.962671 | 2026-04-23 04:38:17.962882 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-04-23 04:38:19.108519 | localhost -> localhost | changed 2026-04-23 04:38:19.120648 | 2026-04-23 04:38:19.120847 | PLAY [all] 2026-04-23 04:38:19.149449 | 2026-04-23 04:38:19.149715 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-04-23 04:38:19.555763 | rockylinux-9 -> localhost | ok 2026-04-23 04:38:19.564072 | 2026-04-23 04:38:19.564188 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-04-23 04:38:19.594785 | rockylinux-9 | ok 2026-04-23 04:38:19.610677 | rockylinux-9 | included: /var/lib/zuul/builds/c28907f1e27d4a14bfebccef223c601d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-04-23 04:38:19.616168 | 2026-04-23 04:38:19.616266 | TASK [add-build-sshkey : Create Temp SSH key] 2026-04-23 04:38:20.934031 | rockylinux-9 -> localhost | Generating public/private rsa key pair. 2026-04-23 04:38:20.934227 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/c28907f1e27d4a14bfebccef223c601d/work/c28907f1e27d4a14bfebccef223c601d_id_rsa 2026-04-23 04:38:20.934265 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/c28907f1e27d4a14bfebccef223c601d/work/c28907f1e27d4a14bfebccef223c601d_id_rsa.pub 2026-04-23 04:38:20.934293 | rockylinux-9 -> localhost | The key fingerprint is: 2026-04-23 04:38:20.934317 | rockylinux-9 -> localhost | SHA256:jQfaiEcHOw4i3DHajoJFdSqJsRckIVXTgcWMYbU0oVs zuul-build-sshkey 2026-04-23 04:38:20.934357 | rockylinux-9 -> localhost | The key's randomart image is: 2026-04-23 04:38:20.934380 | rockylinux-9 -> localhost | +---[RSA 3072]----+ 2026-04-23 04:38:20.934411 | rockylinux-9 -> localhost | |=+==*XXo | 2026-04-23 04:38:20.934435 | rockylinux-9 -> localhost | |o*++=*+= | 2026-04-23 04:38:20.934457 | rockylinux-9 -> localhost | |+o*o+ E o | 2026-04-23 04:38:20.934478 | rockylinux-9 -> localhost | |.+oo B * + | 2026-04-23 04:38:20.934499 | rockylinux-9 -> localhost | |o. .o = S o | 2026-04-23 04:38:20.934521 | rockylinux-9 -> localhost | |. . . | 2026-04-23 04:38:20.934542 | rockylinux-9 -> localhost | | | 2026-04-23 04:38:20.934564 | rockylinux-9 -> localhost | | | 2026-04-23 04:38:20.934588 | rockylinux-9 -> localhost | | | 2026-04-23 04:38:20.934609 | rockylinux-9 -> localhost | +----[SHA256]-----+ 2026-04-23 04:38:20.934691 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.718679 2026-04-23 04:38:20.939678 | 2026-04-23 04:38:20.939761 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-04-23 04:38:20.971276 | rockylinux-9 | ok 2026-04-23 04:38:20.990008 | rockylinux-9 | included: /var/lib/zuul/builds/c28907f1e27d4a14bfebccef223c601d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-04-23 04:38:21.017614 | 2026-04-23 04:38:21.017779 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-04-23 04:38:21.054699 | rockylinux-9 | skipping: Conditional result was False 2026-04-23 04:38:21.061883 | 2026-04-23 04:38:21.061969 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-04-23 04:38:21.616824 | rockylinux-9 | changed 2026-04-23 04:38:21.622796 | 2026-04-23 04:38:21.622878 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-04-23 04:38:21.873920 | rockylinux-9 | ok 2026-04-23 04:38:21.937015 | 2026-04-23 04:38:21.937133 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-04-23 04:38:22.881772 | rockylinux-9 | changed 2026-04-23 04:38:22.985155 | 2026-04-23 04:38:22.985301 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-04-23 04:38:23.976895 | rockylinux-9 | changed 2026-04-23 04:38:23.988208 | 2026-04-23 04:38:23.989225 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-04-23 04:38:24.004341 | rockylinux-9 | skipping: Conditional result was False 2026-04-23 04:38:24.011790 | 2026-04-23 04:38:24.011940 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-04-23 04:38:24.608151 | rockylinux-9 -> localhost | changed 2026-04-23 04:38:24.619181 | 2026-04-23 04:38:24.619375 | TASK [add-build-sshkey : Add back temp key] 2026-04-23 04:38:25.079254 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/c28907f1e27d4a14bfebccef223c601d/work/c28907f1e27d4a14bfebccef223c601d_id_rsa (zuul-build-sshkey) 2026-04-23 04:38:25.079503 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.026897 2026-04-23 04:38:25.086444 | 2026-04-23 04:38:25.086592 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-04-23 04:38:25.456727 | rockylinux-9 | ok 2026-04-23 04:38:25.460883 | 2026-04-23 04:38:25.461033 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-04-23 04:38:25.485115 | rockylinux-9 | skipping: Conditional result was False 2026-04-23 04:38:25.502427 | 2026-04-23 04:38:25.502585 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-04-23 04:38:25.912716 | rockylinux-9 | ok 2026-04-23 04:38:25.918693 | 2026-04-23 04:38:25.918802 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-04-23 04:38:26.966110 | rockylinux-9 | Output suppressed because no_log was given 2026-04-23 04:38:26.977713 | 2026-04-23 04:38:26.977823 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-04-23 04:38:27.226229 | rockylinux-9 | ok: "logs" 2026-04-23 04:38:27.226441 | rockylinux-9 | ok: All items complete 2026-04-23 04:38:27.226471 | 2026-04-23 04:38:27.452420 | rockylinux-9 | ok: "artifacts" 2026-04-23 04:38:27.706231 | rockylinux-9 | ok: "docs" 2026-04-23 04:38:27.719081 | 2026-04-23 04:38:27.810278 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-04-23 04:38:28.059622 | rockylinux-9 | changed: "logs" 2026-04-23 04:38:28.298550 | rockylinux-9 | changed: "artifacts" 2026-04-23 04:38:28.514503 | rockylinux-9 | changed: "docs" 2026-04-23 04:38:28.534890 | 2026-04-23 04:38:28.535010 | PLAY RECAP 2026-04-23 04:38:28.535071 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-23 04:38:28.535114 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-04-23 04:38:28.535144 | 2026-04-23 04:38:28.654073 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-23 04:38:28.655802 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-23 04:38:29.395083 | 2026-04-23 04:38:29.395206 | PLAY [all] 2026-04-23 04:38:29.405989 | 2026-04-23 04:38:29.406114 | TASK [setup-uv : Extract archive] 2026-04-23 04:38:32.315001 | rockylinux-9 | changed 2026-04-23 04:38:32.512487 | 2026-04-23 04:38:32.512682 | TASK [setup-uv : Print version] 2026-04-23 04:38:32.546162 | [rockylinux-9] Waiting on logger 2026-04-23 04:38:33.061320 | rockylinux-9 | ok: Runtime: 0:00:00.014955 2026-04-23 04:38:33.067360 | 2026-04-23 04:38:33.067420 | PLAY RECAP 2026-04-23 04:38:33.067484 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-23 04:38:33.067511 | 2026-04-23 04:38:33.178694 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-23 04:38:33.574742 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-23 04:38:34.250575 | 2026-04-23 04:38:34.250935 | PLAY [all] 2026-04-23 04:38:34.262391 | 2026-04-23 04:38:34.262492 | TASK [Copy inventory file for Zuul] 2026-04-23 04:38:35.670833 | rockylinux-9 | changed 2026-04-23 04:38:35.676315 | 2026-04-23 04:38:35.676403 | TASK [Switch "ansible_host" to private IP] 2026-04-23 04:38:36.057307 | rockylinux-9 | changed: 1 replacements made 2026-04-23 04:38:36.152788 | 2026-04-23 04:38:36.152912 | TASK [Run Molecule scenario] 2026-04-23 04:38:36.192606 | [rockylinux-9] Waiting on logger 2026-04-23 04:38:44.326041 | [rockylinux-9] Waiting on logger 2026-04-23 04:38:54.505815 | [rockylinux-9] Waiting on logger 2026-04-23 04:39:03.659455 | [rockylinux-9] Waiting on logger 2026-04-23 04:39:13.829975 | [rockylinux-9] Waiting on logger 2026-04-23 04:39:24.006568 | [rockylinux-9] Waiting on logger 2026-04-23 04:39:34.182225 | [rockylinux-9] Waiting on logger 2026-04-23 04:39:44.358035 | [rockylinux-9] Waiting on logger 2026-04-23 04:39:54.534096 | [rockylinux-9] Waiting on logger 2026-04-23 04:40:03.687279 | [rockylinux-9] Waiting on logger 2026-04-23 04:40:13.866007 | [rockylinux-9] Waiting on logger 2026-04-23 04:40:24.038198 | [rockylinux-9] Waiting on logger 2026-04-23 04:40:34.213929 | [rockylinux-9] Waiting on logger 2026-04-23 04:40:44.389728 | [rockylinux-9] Waiting on logger 2026-04-23 04:40:54.566130 | [rockylinux-9] Waiting on logger 2026-04-23 04:41:03.717877 | [rockylinux-9] Waiting on logger 2026-04-23 04:41:13.894133 | [rockylinux-9] Waiting on logger 2026-04-23 04:41:24.070075 | [rockylinux-9] Waiting on logger 2026-04-23 04:41:34.250444 | [rockylinux-9] Waiting on logger 2026-04-23 04:41:44.422052 | [rockylinux-9] Waiting on logger 2026-04-23 04:41:53.579264 | [rockylinux-9] Waiting on logger 2026-04-23 04:42:03.749956 | [rockylinux-9] Waiting on logger 2026-04-23 04:42:13.927175 | [rockylinux-9] Waiting on logger 2026-04-23 04:42:24.102080 | [rockylinux-9] Waiting on logger 2026-04-23 04:42:34.277866 | [rockylinux-9] Waiting on logger 2026-04-23 04:42:44.454053 | [rockylinux-9] Waiting on logger 2026-04-23 04:42:53.611433 | [rockylinux-9] Waiting on logger 2026-04-23 04:43:03.782086 | [rockylinux-9] Waiting on logger 2026-04-23 04:43:08.270116 | rockylinux-9 | ok: Runtime: 0:04:30.892465 2026-04-23 04:43:08.276892 | 2026-04-23 04:43:08.276974 | PLAY RECAP 2026-04-23 04:43:08.277027 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-23 04:43:08.277050 | 2026-04-23 04:43:08.368930 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-23 04:43:08.371105 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-23 04:43:08.923842 | 2026-04-23 04:43:08.923958 | PLAY [all] 2026-04-23 04:43:08.934189 | 2026-04-23 04:43:08.934264 | TASK [fetch-output : Set log path for multiple nodes] 2026-04-23 04:43:08.978391 | rockylinux-9 | skipping: Conditional result was False 2026-04-23 04:43:08.987160 | 2026-04-23 04:43:08.987286 | TASK [fetch-output : Set log path for single node] 2026-04-23 04:43:09.030402 | rockylinux-9 | ok 2026-04-23 04:43:09.037551 | 2026-04-23 04:43:09.037642 | LOOP [fetch-output : Ensure local output dirs] 2026-04-23 04:43:09.400382 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/c28907f1e27d4a14bfebccef223c601d/work/logs" 2026-04-23 04:43:09.615726 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/c28907f1e27d4a14bfebccef223c601d/work/artifacts" 2026-04-23 04:43:09.838500 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/c28907f1e27d4a14bfebccef223c601d/work/docs" 2026-04-23 04:43:09.847492 | 2026-04-23 04:43:09.847563 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-04-23 04:43:10.517310 | rockylinux-9 | changed: .d..t...... ./ 2026-04-23 04:43:10.517501 | rockylinux-9 | changed: All items complete 2026-04-23 04:43:10.517529 | 2026-04-23 04:43:11.022053 | rockylinux-9 | changed: .d..t...... ./ 2026-04-23 04:43:11.521395 | rockylinux-9 | changed: .d..t...... ./ 2026-04-23 04:43:11.543113 | 2026-04-23 04:43:11.543232 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-04-23 04:43:11.940939 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.006748 2026-04-23 04:43:12.150265 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.007032 2026-04-23 04:43:12.168658 | 2026-04-23 04:43:12.169085 | PLAY [all] 2026-04-23 04:43:12.175208 | 2026-04-23 04:43:12.175273 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-04-23 04:43:12.594874 | rockylinux-9 | changed 2026-04-23 04:43:12.601332 | 2026-04-23 04:43:12.601399 | PLAY RECAP 2026-04-23 04:43:12.601457 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-04-23 04:43:12.601488 | 2026-04-23 04:43:12.699566 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-23 04:43:12.701649 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-04-23 04:43:13.249327 | 2026-04-23 04:43:13.249446 | PLAY [localhost] 2026-04-23 04:43:13.259151 | 2026-04-23 04:43:13.259224 | TASK [Generate Zuul manifest] 2026-04-23 04:43:13.279064 | localhost | ok 2026-04-23 04:43:13.295259 | 2026-04-23 04:43:13.295357 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-04-23 04:43:13.599861 | localhost | changed 2026-04-23 04:43:13.614744 | 2026-04-23 04:43:13.655456 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-04-23 04:43:13.687483 | localhost | ok 2026-04-23 04:43:13.696865 | 2026-04-23 04:43:13.696969 | TASK [Upload logs] 2026-04-23 04:43:13.717109 | localhost | ok 2026-04-23 04:43:13.775085 | 2026-04-23 04:43:13.775193 | TASK [Set zuul-log-path fact] 2026-04-23 04:43:13.792233 | localhost | ok 2026-04-23 04:43:13.803019 | 2026-04-23 04:43:13.803083 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-23 04:43:13.830311 | localhost | ok 2026-04-23 04:43:13.837319 | 2026-04-23 04:43:13.837381 | TASK [upload-logs : Create log directories] 2026-04-23 04:43:14.233616 | localhost | changed 2026-04-23 04:43:14.239562 | 2026-04-23 04:43:14.239632 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-04-23 04:43:14.636496 | localhost -> localhost | ok: Runtime: 0:00:00.005715 2026-04-23 04:43:14.643002 | 2026-04-23 04:43:14.643106 | TASK [upload-logs : Upload logs to log server] 2026-04-23 04:43:15.099944 | localhost | Output suppressed because no_log was given 2026-04-23 04:43:15.105248 | 2026-04-23 04:43:15.105382 | LOOP [upload-logs : Compress console log and json output] 2026-04-23 04:43:15.153057 | localhost | skipping: Conditional result was False 2026-04-23 04:43:15.160182 | localhost | skipping: Conditional result was False 2026-04-23 04:43:15.167152 | 2026-04-23 04:43:15.167311 | LOOP [upload-logs : Upload compressed console log and json output] 2026-04-23 04:43:15.213729 | localhost | skipping: Conditional result was False 2026-04-23 04:43:15.214174 | 2026-04-23 04:43:15.217759 | localhost | skipping: Conditional result was False 2026-04-23 04:43:15.230991 | 2026-04-23 04:43:15.231185 | LOOP [upload-logs : Upload console log and json output]