2026-04-26 11:23:48.536367 | Job console starting 2026-04-26 11:23:48.546286 | Updating git repos 2026-04-26 11:23:48.581875 | Cloning repos into workspace 2026-04-26 11:23:52.409449 | Restoring repo states 2026-04-26 11:23:52.418185 | Merging changes 2026-04-26 11:23:54.369370 | Checking out repos 2026-04-26 11:23:57.367107 | Preparing playbooks 2026-04-26 11:25:03.748491 | Running Ansible setup 2026-04-26 11:25:08.602524 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-26 11:25:09.268037 | 2026-04-26 11:25:09.268540 | PLAY [localhost] 2026-04-26 11:25:09.277159 | 2026-04-26 11:25:09.277259 | TASK [Gathering Facts] 2026-04-26 11:25:10.304055 | localhost | ok 2026-04-26 11:25:10.313373 | 2026-04-26 11:25:10.313571 | TASK [Setup log path fact] 2026-04-26 11:25:10.333107 | localhost | ok 2026-04-26 11:25:10.344134 | 2026-04-26 11:25:10.344272 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-26 11:25:10.375073 | localhost | ok 2026-04-26 11:25:10.382675 | 2026-04-26 11:25:10.382760 | TASK [emit-job-header : Print job information] 2026-04-26 11:25:10.424229 | # Job Information 2026-04-26 11:25:10.424430 | Ansible Version: 2.16.16 2026-04-26 11:25:10.424479 | Job: ansible-collection-containers-molecule-docker-rockylinux-9 2026-04-26 11:25:10.424513 | Pipeline: check 2026-04-26 11:25:10.424543 | Executor: 0a8996d2b663 2026-04-26 11:25:10.424573 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/122 2026-04-26 11:25:10.424606 | Event ID: 21689ae0-4162-11f1-9bf5-ceff5f6c5044 2026-04-26 11:25:10.428637 | 2026-04-26 11:25:10.428728 | LOOP [emit-job-header : Print node information] 2026-04-26 11:25:10.526611 | localhost | ok: 2026-04-26 11:25:10.526795 | localhost | # Node Information 2026-04-26 11:25:10.526825 | localhost | Inventory Hostname: rockylinux-9 2026-04-26 11:25:10.526848 | localhost | Hostname: np0000170270 2026-04-26 11:25:10.526870 | localhost | Username: zuul 2026-04-26 11:25:10.526895 | localhost | Distro: Rocky 9.7 2026-04-26 11:25:10.526916 | localhost | Provider: yul1 2026-04-26 11:25:10.526936 | localhost | Region: ca-ymq-1 2026-04-26 11:25:10.526955 | localhost | Label: rockylinux-9 2026-04-26 11:25:10.526974 | localhost | Product Name: OpenStack Nova 2026-04-26 11:25:10.526994 | localhost | Interface IP: 199.204.45.205 2026-04-26 11:25:10.541870 | 2026-04-26 11:25:10.541997 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-04-26 11:25:10.975632 | localhost -> localhost | changed 2026-04-26 11:25:10.983609 | 2026-04-26 11:25:10.983719 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-04-26 11:25:12.229121 | localhost -> localhost | changed 2026-04-26 11:25:12.237193 | 2026-04-26 11:25:12.237271 | PLAY [all] 2026-04-26 11:25:12.247393 | 2026-04-26 11:25:12.247490 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-04-26 11:25:12.593357 | rockylinux-9 -> localhost | ok 2026-04-26 11:25:12.603602 | 2026-04-26 11:25:12.603717 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-04-26 11:25:12.637778 | rockylinux-9 | ok 2026-04-26 11:25:12.653128 | rockylinux-9 | included: /var/lib/zuul/builds/16d87d48cb0b4ce0bf24dbfc35381085/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-04-26 11:25:12.660040 | 2026-04-26 11:25:12.660145 | TASK [add-build-sshkey : Create Temp SSH key] 2026-04-26 11:25:15.970507 | rockylinux-9 -> localhost | Generating public/private rsa key pair. 2026-04-26 11:25:15.970675 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/16d87d48cb0b4ce0bf24dbfc35381085/work/16d87d48cb0b4ce0bf24dbfc35381085_id_rsa 2026-04-26 11:25:15.970708 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/16d87d48cb0b4ce0bf24dbfc35381085/work/16d87d48cb0b4ce0bf24dbfc35381085_id_rsa.pub 2026-04-26 11:25:15.970734 | rockylinux-9 -> localhost | The key fingerprint is: 2026-04-26 11:25:15.970757 | rockylinux-9 -> localhost | SHA256:Fbgi2zOd6KXPdhJd0p8Psz/fFyueAlGqjCS/kvGJsnw zuul-build-sshkey 2026-04-26 11:25:15.970793 | rockylinux-9 -> localhost | The key's randomart image is: 2026-04-26 11:25:15.970815 | rockylinux-9 -> localhost | +---[RSA 3072]----+ 2026-04-26 11:25:15.970843 | rockylinux-9 -> localhost | | .. | 2026-04-26 11:25:15.970868 | rockylinux-9 -> localhost | | . o | 2026-04-26 11:25:15.970893 | rockylinux-9 -> localhost | | .+. | 2026-04-26 11:25:15.970915 | rockylinux-9 -> localhost | | .... .+. o | 2026-04-26 11:25:15.970936 | rockylinux-9 -> localhost | | ++o+So.o . . | 2026-04-26 11:25:15.970957 | rockylinux-9 -> localhost | | ..o=o=.. =. | 2026-04-26 11:25:15.970978 | rockylinux-9 -> localhost | | =.o= .. =o| 2026-04-26 11:25:15.970999 | rockylinux-9 -> localhost | |.. E +o.o .. .oo+| 2026-04-26 11:25:15.971023 | rockylinux-9 -> localhost | | o+ . ooo oo..B| 2026-04-26 11:25:15.971044 | rockylinux-9 -> localhost | +----[SHA256]-----+ 2026-04-26 11:25:15.971090 | rockylinux-9 -> localhost | ok: Runtime: 0:00:02.834032 2026-04-26 11:25:15.977350 | 2026-04-26 11:25:15.977416 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-04-26 11:25:16.011151 | rockylinux-9 | ok 2026-04-26 11:25:16.031383 | rockylinux-9 | included: /var/lib/zuul/builds/16d87d48cb0b4ce0bf24dbfc35381085/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-04-26 11:25:16.041029 | 2026-04-26 11:25:16.041162 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-04-26 11:25:16.065862 | rockylinux-9 | skipping: Conditional result was False 2026-04-26 11:25:16.073324 | 2026-04-26 11:25:16.073421 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-04-26 11:25:16.544940 | rockylinux-9 | changed 2026-04-26 11:25:16.551837 | 2026-04-26 11:25:16.551932 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-04-26 11:25:16.808581 | rockylinux-9 | ok 2026-04-26 11:25:16.815968 | 2026-04-26 11:25:16.816143 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-04-26 11:25:17.844637 | rockylinux-9 | changed 2026-04-26 11:25:17.849981 | 2026-04-26 11:25:17.850051 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-04-26 11:25:18.820536 | rockylinux-9 | changed 2026-04-26 11:25:18.826218 | 2026-04-26 11:25:18.826292 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-04-26 11:25:18.854861 | rockylinux-9 | skipping: Conditional result was False 2026-04-26 11:25:18.865643 | 2026-04-26 11:25:18.865745 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-04-26 11:25:19.279329 | rockylinux-9 -> localhost | changed 2026-04-26 11:25:19.289014 | 2026-04-26 11:25:19.289141 | TASK [add-build-sshkey : Add back temp key] 2026-04-26 11:25:19.632823 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/16d87d48cb0b4ce0bf24dbfc35381085/work/16d87d48cb0b4ce0bf24dbfc35381085_id_rsa (zuul-build-sshkey) 2026-04-26 11:25:19.633062 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.018467 2026-04-26 11:25:19.641950 | 2026-04-26 11:25:19.642097 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-04-26 11:25:20.027393 | rockylinux-9 | ok 2026-04-26 11:25:20.033455 | 2026-04-26 11:25:20.033522 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-04-26 11:25:20.057804 | rockylinux-9 | skipping: Conditional result was False 2026-04-26 11:25:20.070417 | 2026-04-26 11:25:20.070482 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-04-26 11:25:20.548821 | rockylinux-9 | ok 2026-04-26 11:25:20.554602 | 2026-04-26 11:25:20.554690 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-04-26 11:25:21.715275 | rockylinux-9 | Output suppressed because no_log was given 2026-04-26 11:25:21.729781 | 2026-04-26 11:25:21.729908 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-04-26 11:25:22.004828 | rockylinux-9 | ok: "logs" 2026-04-26 11:25:22.005332 | rockylinux-9 | ok: All items complete 2026-04-26 11:25:22.005375 | 2026-04-26 11:25:22.241056 | rockylinux-9 | ok: "artifacts" 2026-04-26 11:25:22.473443 | rockylinux-9 | ok: "docs" 2026-04-26 11:25:23.260149 | 2026-04-26 11:25:23.260313 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-04-26 11:25:23.542639 | rockylinux-9 | changed: "logs" 2026-04-26 11:25:23.794857 | rockylinux-9 | changed: "artifacts" 2026-04-26 11:25:24.047355 | rockylinux-9 | changed: "docs" 2026-04-26 11:25:24.061508 | 2026-04-26 11:25:24.061648 | PLAY RECAP 2026-04-26 11:25:24.061719 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-26 11:25:24.061757 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-04-26 11:25:24.061782 | 2026-04-26 11:25:24.284054 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-26 11:25:24.285289 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-26 11:25:24.909608 | 2026-04-26 11:25:24.909732 | PLAY [all] 2026-04-26 11:25:24.920414 | 2026-04-26 11:25:24.920495 | TASK [setup-uv : Extract archive] 2026-04-26 11:25:27.795710 | rockylinux-9 | changed 2026-04-26 11:25:27.802157 | 2026-04-26 11:25:27.802264 | TASK [setup-uv : Print version] 2026-04-26 11:25:27.837292 | [rockylinux-9] Waiting on logger 2026-04-26 11:25:28.252208 | rockylinux-9 | ok: Runtime: 0:00:00.012567 2026-04-26 11:25:28.257861 | 2026-04-26 11:25:28.257910 | PLAY RECAP 2026-04-26 11:25:28.257961 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-26 11:25:28.257985 | 2026-04-26 11:25:28.376442 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-26 11:25:28.378203 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-26 11:25:28.976923 | 2026-04-26 11:25:29.030953 | PLAY [all] 2026-04-26 11:25:29.047492 | 2026-04-26 11:25:29.047690 | TASK [Copy inventory file for Zuul] 2026-04-26 11:25:30.450096 | rockylinux-9 | changed 2026-04-26 11:25:30.457621 | 2026-04-26 11:25:30.457837 | TASK [Switch "ansible_host" to private IP] 2026-04-26 11:25:30.842953 | rockylinux-9 | changed: 1 replacements made 2026-04-26 11:25:30.851001 | 2026-04-26 11:25:30.851080 | TASK [Run Molecule scenario] 2026-04-26 11:25:30.876673 | [rockylinux-9] Waiting on logger 2026-04-26 11:25:37.990594 | [rockylinux-9] Waiting on logger 2026-04-26 11:25:48.170004 | [rockylinux-9] Waiting on logger 2026-04-26 11:25:58.342311 | [rockylinux-9] Waiting on logger 2026-04-26 11:26:08.521203 | [rockylinux-9] Waiting on logger 2026-04-26 11:26:18.693905 | [rockylinux-9] Waiting on logger 2026-04-26 11:26:27.870180 | [rockylinux-9] Waiting on logger 2026-04-26 11:26:38.054313 | [rockylinux-9] Waiting on logger 2026-04-26 11:26:48.233925 | [rockylinux-9] Waiting on logger 2026-04-26 11:26:58.406272 | [rockylinux-9] Waiting on logger 2026-04-26 11:27:08.582668 | [rockylinux-9] Waiting on logger 2026-04-26 11:27:18.822084 | [rockylinux-9] Waiting on logger 2026-04-26 11:27:27.974025 | [rockylinux-9] Waiting on logger 2026-04-26 11:27:38.150189 | [rockylinux-9] Waiting on logger 2026-04-26 11:27:48.326286 | [rockylinux-9] Waiting on logger 2026-04-26 11:27:58.502217 | [rockylinux-9] Waiting on logger 2026-04-26 11:28:08.678037 | [rockylinux-9] Waiting on logger 2026-04-26 11:28:09.808458 | rockylinux-9 | ok: Runtime: 0:02:38.433825 2026-04-26 11:28:09.815676 | 2026-04-26 11:28:09.815728 | PLAY RECAP 2026-04-26 11:28:09.815774 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-26 11:28:09.815796 | 2026-04-26 11:28:09.939743 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-26 11:28:09.942173 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-26 11:28:10.488754 | 2026-04-26 11:28:10.488887 | PLAY [all] 2026-04-26 11:28:10.499534 | 2026-04-26 11:28:10.499631 | TASK [fetch-output : Set log path for multiple nodes] 2026-04-26 11:28:10.545288 | rockylinux-9 | skipping: Conditional result was False 2026-04-26 11:28:10.555307 | 2026-04-26 11:28:10.555390 | TASK [fetch-output : Set log path for single node] 2026-04-26 11:28:10.602636 | rockylinux-9 | ok 2026-04-26 11:28:10.606895 | 2026-04-26 11:28:10.606961 | LOOP [fetch-output : Ensure local output dirs] 2026-04-26 11:28:10.986876 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/16d87d48cb0b4ce0bf24dbfc35381085/work/logs" 2026-04-26 11:28:11.196223 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/16d87d48cb0b4ce0bf24dbfc35381085/work/artifacts" 2026-04-26 11:28:11.412506 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/16d87d48cb0b4ce0bf24dbfc35381085/work/docs" 2026-04-26 11:28:11.432089 | 2026-04-26 11:28:11.432268 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-04-26 11:28:12.124427 | rockylinux-9 | changed: .d..t...... ./ 2026-04-26 11:28:12.124693 | rockylinux-9 | changed: All items complete 2026-04-26 11:28:12.124730 | 2026-04-26 11:28:12.637467 | rockylinux-9 | changed: .d..t...... ./ 2026-04-26 11:28:13.150950 | rockylinux-9 | changed: .d..t...... ./ 2026-04-26 11:28:13.165420 | 2026-04-26 11:28:13.165574 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-04-26 11:28:13.585672 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.008845 2026-04-26 11:28:13.809647 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.008649 2026-04-26 11:28:13.832346 | 2026-04-26 11:28:13.832493 | PLAY [all] 2026-04-26 11:28:13.838441 | 2026-04-26 11:28:13.838509 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-04-26 11:28:14.288512 | rockylinux-9 | changed 2026-04-26 11:28:14.297226 | 2026-04-26 11:28:14.297297 | PLAY RECAP 2026-04-26 11:28:14.297359 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-04-26 11:28:14.297388 | 2026-04-26 11:28:14.435567 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-26 11:28:14.437861 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-04-26 11:28:14.992279 | 2026-04-26 11:28:14.992409 | PLAY [localhost] 2026-04-26 11:28:15.002503 | 2026-04-26 11:28:15.002581 | TASK [Generate Zuul manifest] 2026-04-26 11:28:15.022196 | localhost | ok 2026-04-26 11:28:15.038069 | 2026-04-26 11:28:15.038203 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-04-26 11:28:15.364990 | localhost | changed 2026-04-26 11:28:15.377538 | 2026-04-26 11:28:15.377672 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-04-26 11:28:15.408694 | localhost | ok 2026-04-26 11:28:15.419152 | 2026-04-26 11:28:15.419214 | TASK [Upload logs] 2026-04-26 11:28:15.438855 | localhost | ok 2026-04-26 11:28:15.502097 | 2026-04-26 11:28:15.502299 | TASK [Set zuul-log-path fact] 2026-04-26 11:28:15.527344 | localhost | ok 2026-04-26 11:28:15.544424 | 2026-04-26 11:28:15.544542 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-26 11:28:15.576505 | localhost | ok 2026-04-26 11:28:15.587156 | 2026-04-26 11:28:15.587250 | TASK [upload-logs : Create log directories] 2026-04-26 11:28:15.986689 | localhost | changed 2026-04-26 11:28:15.991521 | 2026-04-26 11:28:15.991592 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-04-26 11:28:16.429234 | localhost -> localhost | ok: Runtime: 0:00:00.006535 2026-04-26 11:28:16.436231 | 2026-04-26 11:28:16.436328 | TASK [upload-logs : Upload logs to log server] 2026-04-26 11:28:16.894139 | localhost | Output suppressed because no_log was given 2026-04-26 11:28:16.898064 | 2026-04-26 11:28:16.898131 | LOOP [upload-logs : Compress console log and json output] 2026-04-26 11:28:16.944201 | localhost | skipping: Conditional result was False 2026-04-26 11:28:16.951339 | localhost | skipping: Conditional result was False 2026-04-26 11:28:16.962149 | 2026-04-26 11:28:16.962292 | LOOP [upload-logs : Upload compressed console log and json output] 2026-04-26 11:28:17.008564 | localhost | skipping: Conditional result was False 2026-04-26 11:28:17.009089 | 2026-04-26 11:28:17.012580 | localhost | skipping: Conditional result was False 2026-04-26 11:28:17.028763 | 2026-04-26 11:28:17.029058 | LOOP [upload-logs : Upload console log and json output]