2026-01-21 18:15:26.378431 | Job console starting 2026-01-21 18:15:26.387154 | Updating git repos 2026-01-21 18:15:26.417645 | Cloning repos into workspace 2026-01-21 18:15:26.520354 | Restoring repo states 2026-01-21 18:15:26.527833 | Merging changes 2026-01-21 18:15:26.773395 | Checking out repos 2026-01-21 18:15:26.803173 | Preparing playbooks 2026-01-21 18:15:28.496764 | Running Ansible setup 2026-01-21 18:15:32.293041 | PRE-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main] 2026-01-21 18:15:32.901351 | 2026-01-21 18:15:32.901505 | PLAY [localhost] 2026-01-21 18:15:32.909438 | 2026-01-21 18:15:32.909510 | TASK [Gathering Facts] 2026-01-21 18:15:33.782703 | localhost | ok 2026-01-21 18:15:33.789554 | 2026-01-21 18:15:33.789635 | TASK [Setup log path fact] 2026-01-21 18:15:33.808508 | localhost | ok 2026-01-21 18:15:33.821559 | 2026-01-21 18:15:33.821626 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-01-21 18:15:33.845588 | localhost | ok 2026-01-21 18:15:33.851568 | 2026-01-21 18:15:33.851637 | TASK [emit-job-header : Print job information] 2026-01-21 18:15:33.892502 | # Job Information 2026-01-21 18:15:33.892734 | Ansible Version: 2.16.15 2026-01-21 18:15:33.892783 | Job: ansible-collection-containers-molecule-containerd-rockylinux-9 2026-01-21 18:15:33.892816 | Pipeline: check 2026-01-21 18:15:33.892846 | Executor: 3a2793d2bd32 2026-01-21 18:15:33.892875 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/94 2026-01-21 18:15:33.892907 | Event ID: 1131c460-f6f5-11f0-99c8-3dea310db7ca 2026-01-21 18:15:33.897319 | 2026-01-21 18:15:33.897422 | LOOP [emit-job-header : Print node information] 2026-01-21 18:15:33.994742 | localhost | ok: 2026-01-21 18:15:33.995016 | localhost | # Node Information 2026-01-21 18:15:33.995069 | localhost | Inventory Hostname: rockylinux-9 2026-01-21 18:15:33.995106 | localhost | Hostname: np0000154374 2026-01-21 18:15:33.995141 | localhost | Username: zuul 2026-01-21 18:15:33.995179 | localhost | Distro: Rocky 9.7 2026-01-21 18:15:33.995213 | localhost | Provider: yul1 2026-01-21 18:15:33.995245 | localhost | Region: ca-ymq-1 2026-01-21 18:15:33.995276 | localhost | Label: rockylinux-9 2026-01-21 18:15:33.995307 | localhost | Product Name: OpenStack Nova 2026-01-21 18:15:33.995333 | localhost | Interface IP: 162.253.55.212 2026-01-21 18:15:34.007515 | 2026-01-21 18:15:34.007680 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-01-21 18:15:34.371795 | localhost -> localhost | changed 2026-01-21 18:15:34.377151 | 2026-01-21 18:15:34.377227 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-01-21 18:15:35.300489 | localhost -> localhost | changed 2026-01-21 18:15:35.309871 | 2026-01-21 18:15:35.309927 | PLAY [all] 2026-01-21 18:15:35.317040 | 2026-01-21 18:15:35.317108 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-01-21 18:15:35.554126 | rockylinux-9 -> localhost | ok 2026-01-21 18:15:35.562021 | 2026-01-21 18:15:35.562120 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-01-21 18:15:35.585061 | rockylinux-9 | ok 2026-01-21 18:15:35.603095 | rockylinux-9 | included: /var/lib/zuul/builds/cc1398cd27a34ebc8936bf8919d8cfff/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-01-21 18:15:35.609589 | 2026-01-21 18:15:35.609654 | TASK [add-build-sshkey : Create Temp SSH key] 2026-01-21 18:15:37.331234 | rockylinux-9 -> localhost | Generating public/private rsa key pair. 2026-01-21 18:15:37.331442 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/cc1398cd27a34ebc8936bf8919d8cfff/work/cc1398cd27a34ebc8936bf8919d8cfff_id_rsa 2026-01-21 18:15:37.331474 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/cc1398cd27a34ebc8936bf8919d8cfff/work/cc1398cd27a34ebc8936bf8919d8cfff_id_rsa.pub 2026-01-21 18:15:37.331497 | rockylinux-9 -> localhost | The key fingerprint is: 2026-01-21 18:15:37.331518 | rockylinux-9 -> localhost | SHA256:y75TAhicf09JBhrx1u1FA06F338/cWcVKDJlegmkuUU zuul-build-sshkey 2026-01-21 18:15:37.331554 | rockylinux-9 -> localhost | The key's randomart image is: 2026-01-21 18:15:37.331576 | rockylinux-9 -> localhost | +---[RSA 3072]----+ 2026-01-21 18:15:37.331602 | rockylinux-9 -> localhost | | . .o.oE.oo+= | 2026-01-21 18:15:37.331624 | rockylinux-9 -> localhost | | + +++*=oo o | 2026-01-21 18:15:37.331644 | rockylinux-9 -> localhost | | +.oo=+++....| 2026-01-21 18:15:37.331664 | rockylinux-9 -> localhost | | . o.+ +. .. o| 2026-01-21 18:15:37.331683 | rockylinux-9 -> localhost | | +So . o| 2026-01-21 18:15:37.331704 | rockylinux-9 -> localhost | | ...o .*| 2026-01-21 18:15:37.331724 | rockylinux-9 -> localhost | | oo .*| 2026-01-21 18:15:37.331760 | rockylinux-9 -> localhost | | .. ..| 2026-01-21 18:15:37.331785 | rockylinux-9 -> localhost | | oo .| 2026-01-21 18:15:37.331805 | rockylinux-9 -> localhost | +----[SHA256]-----+ 2026-01-21 18:15:37.331858 | rockylinux-9 -> localhost | ok: Runtime: 0:00:01.282350 2026-01-21 18:15:37.336767 | 2026-01-21 18:15:37.336838 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-01-21 18:15:37.370712 | rockylinux-9 | ok 2026-01-21 18:15:37.380059 | rockylinux-9 | included: /var/lib/zuul/builds/cc1398cd27a34ebc8936bf8919d8cfff/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-01-21 18:15:37.387090 | 2026-01-21 18:15:37.387150 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-01-21 18:15:37.411812 | rockylinux-9 | skipping: Conditional result was False 2026-01-21 18:15:37.420571 | 2026-01-21 18:15:37.420666 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-01-21 18:15:37.913857 | rockylinux-9 | changed 2026-01-21 18:15:37.918652 | 2026-01-21 18:15:37.918721 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-01-21 18:15:38.150756 | rockylinux-9 | ok 2026-01-21 18:15:38.155512 | 2026-01-21 18:15:38.155579 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-01-21 18:15:39.015520 | rockylinux-9 | changed 2026-01-21 18:15:39.020395 | 2026-01-21 18:15:39.020465 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-01-21 18:15:39.782119 | rockylinux-9 | changed 2026-01-21 18:15:39.787982 | 2026-01-21 18:15:39.788104 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-01-21 18:15:39.815126 | rockylinux-9 | skipping: Conditional result was False 2026-01-21 18:15:39.825996 | 2026-01-21 18:15:39.826067 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-01-21 18:15:40.195402 | rockylinux-9 -> localhost | changed 2026-01-21 18:15:40.210182 | 2026-01-21 18:15:40.210287 | TASK [add-build-sshkey : Add back temp key] 2026-01-21 18:15:40.525891 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/cc1398cd27a34ebc8936bf8919d8cfff/work/cc1398cd27a34ebc8936bf8919d8cfff_id_rsa (zuul-build-sshkey) 2026-01-21 18:15:40.526133 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.025308 2026-01-21 18:15:40.532280 | 2026-01-21 18:15:40.532342 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-01-21 18:15:40.908685 | rockylinux-9 | ok 2026-01-21 18:15:40.916361 | 2026-01-21 18:15:40.916455 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-01-21 18:15:40.941656 | rockylinux-9 | skipping: Conditional result was False 2026-01-21 18:15:40.956251 | 2026-01-21 18:15:40.956422 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-01-21 18:15:41.350557 | rockylinux-9 | ok 2026-01-21 18:15:41.356520 | 2026-01-21 18:15:41.356590 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-01-21 18:15:42.233694 | rockylinux-9 | Output suppressed because no_log was given 2026-01-21 18:15:42.247843 | 2026-01-21 18:15:42.247953 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-01-21 18:15:42.490825 | rockylinux-9 | ok: "logs" 2026-01-21 18:15:42.491118 | rockylinux-9 | ok: All items complete 2026-01-21 18:15:42.491148 | 2026-01-21 18:15:42.708846 | rockylinux-9 | ok: "artifacts" 2026-01-21 18:15:42.950309 | rockylinux-9 | ok: "docs" 2026-01-21 18:15:42.971606 | 2026-01-21 18:15:42.971755 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-01-21 18:15:43.230103 | rockylinux-9 | changed: "logs" 2026-01-21 18:15:43.465964 | rockylinux-9 | changed: "artifacts" 2026-01-21 18:15:43.680830 | rockylinux-9 | changed: "docs" 2026-01-21 18:15:43.702439 | 2026-01-21 18:15:43.702643 | PLAY RECAP 2026-01-21 18:15:43.702702 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-01-21 18:15:43.702735 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-01-21 18:15:43.702759 | 2026-01-21 18:15:43.860612 | PRE-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main] 2026-01-21 18:15:43.863727 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-01-21 18:15:44.449445 | 2026-01-21 18:15:44.449929 | PLAY [all] 2026-01-21 18:15:44.461844 | 2026-01-21 18:15:44.461919 | TASK [setup-uv : Extract archive] 2026-01-21 18:15:47.171867 | rockylinux-9 | changed 2026-01-21 18:15:47.181719 | 2026-01-21 18:15:47.181915 | TASK [setup-uv : Print version] 2026-01-21 18:15:47.209411 | [rockylinux-9] Waiting on logger 2026-01-21 18:15:47.724679 | rockylinux-9 | ok: Runtime: 0:00:00.014804 2026-01-21 18:15:47.737921 | 2026-01-21 18:15:47.738046 | PLAY RECAP 2026-01-21 18:15:47.738099 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-01-21 18:15:47.738125 | 2026-01-21 18:15:47.866451 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-01-21 18:15:47.868221 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-01-21 18:15:48.452241 | 2026-01-21 18:15:48.452434 | PLAY [all] 2026-01-21 18:15:48.465966 | 2026-01-21 18:15:48.466153 | TASK [Copy inventory file for Zuul] 2026-01-21 18:15:49.672053 | rockylinux-9 | changed 2026-01-21 18:15:49.679696 | 2026-01-21 18:15:49.679870 | TASK [Switch "ansible_host" to private IP] 2026-01-21 18:15:50.015087 | rockylinux-9 | changed: 1 replacements made 2026-01-21 18:15:50.022183 | 2026-01-21 18:15:50.022276 | TASK [Run Molecule scenario] 2026-01-21 18:15:50.044626 | [rockylinux-9] Waiting on logger 2026-01-21 18:15:59.205960 | [rockylinux-9] Waiting on logger 2026-01-21 18:16:08.357840 | [rockylinux-9] Waiting on logger 2026-01-21 18:16:18.537832 | [rockylinux-9] Waiting on logger 2026-01-21 18:16:28.710087 | [rockylinux-9] Waiting on logger 2026-01-21 18:16:38.886006 | [rockylinux-9] Waiting on logger 2026-01-21 18:16:49.094112 | [rockylinux-9] Waiting on logger 2026-01-21 18:16:58.283400 | [rockylinux-9] Waiting on logger 2026-01-21 18:17:08.454074 | [rockylinux-9] Waiting on logger 2026-01-21 18:17:18.629829 | [rockylinux-9] Waiting on logger 2026-01-21 18:17:28.806047 | [rockylinux-9] Waiting on logger 2026-01-21 18:17:38.986106 | [rockylinux-9] Waiting on logger 2026-01-21 18:17:49.164478 | [rockylinux-9] Waiting on logger 2026-01-21 18:17:58.315238 | [rockylinux-9] Waiting on logger 2026-01-21 18:18:08.486031 | [rockylinux-9] Waiting on logger 2026-01-21 18:18:18.662172 | [rockylinux-9] Waiting on logger 2026-01-21 18:18:28.838165 | [rockylinux-9] Waiting on logger 2026-01-21 18:18:39.046309 | [rockylinux-9] Waiting on logger 2026-01-21 18:18:49.222089 | [rockylinux-9] Waiting on logger 2026-01-21 18:18:58.374088 | [rockylinux-9] Waiting on logger 2026-01-21 18:19:08.553908 | [rockylinux-9] Waiting on logger 2026-01-21 18:19:18.726079 | [rockylinux-9] Waiting on logger 2026-01-21 18:19:28.902436 | [rockylinux-9] Waiting on logger 2026-01-21 18:19:39.078066 | [rockylinux-9] Waiting on logger 2026-01-21 18:19:48.234904 | [rockylinux-9] Waiting on logger 2026-01-21 18:19:58.406047 | [rockylinux-9] Waiting on logger 2026-01-21 18:20:08.582059 | [rockylinux-9] Waiting on logger 2026-01-21 18:20:18.758029 | [rockylinux-9] Waiting on logger 2026-01-21 18:20:28.934478 | [rockylinux-9] Waiting on logger 2026-01-21 18:20:39.109932 | [rockylinux-9] Waiting on logger 2026-01-21 18:20:48.267421 | [rockylinux-9] Waiting on logger 2026-01-21 18:20:57.230682 | rockylinux-9 | ok: Runtime: 0:05:06.018728 2026-01-21 18:20:57.238299 | 2026-01-21 18:20:57.238351 | PLAY RECAP 2026-01-21 18:20:57.238393 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-01-21 18:20:57.238415 | 2026-01-21 18:20:57.347797 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-01-21 18:20:57.349970 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main] 2026-01-21 18:20:57.942598 | 2026-01-21 18:20:57.942740 | PLAY [all] 2026-01-21 18:20:57.953715 | 2026-01-21 18:20:57.953791 | TASK [fetch-output : Set log path for multiple nodes] 2026-01-21 18:20:58.000334 | rockylinux-9 | skipping: Conditional result was False 2026-01-21 18:20:58.010036 | 2026-01-21 18:20:58.010170 | TASK [fetch-output : Set log path for single node] 2026-01-21 18:20:58.052845 | rockylinux-9 | ok 2026-01-21 18:20:58.058662 | 2026-01-21 18:20:58.058728 | LOOP [fetch-output : Ensure local output dirs] 2026-01-21 18:20:58.440768 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/cc1398cd27a34ebc8936bf8919d8cfff/work/logs" 2026-01-21 18:20:58.644074 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/cc1398cd27a34ebc8936bf8919d8cfff/work/artifacts" 2026-01-21 18:20:58.846587 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/cc1398cd27a34ebc8936bf8919d8cfff/work/docs" 2026-01-21 18:20:58.861662 | 2026-01-21 18:20:58.861834 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-01-21 18:20:59.535678 | rockylinux-9 | changed: .d..t...... ./ 2026-01-21 18:20:59.535945 | rockylinux-9 | changed: All items complete 2026-01-21 18:20:59.535982 | 2026-01-21 18:21:00.014633 | rockylinux-9 | changed: .d..t...... ./ 2026-01-21 18:21:00.495198 | rockylinux-9 | changed: .d..t...... ./ 2026-01-21 18:21:00.516168 | 2026-01-21 18:21:00.516333 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-01-21 18:21:00.967773 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.008535 2026-01-21 18:21:01.191948 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.007980 2026-01-21 18:21:01.202738 | 2026-01-21 18:21:01.202899 | PLAY [all] 2026-01-21 18:21:01.208979 | 2026-01-21 18:21:01.209055 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-01-21 18:21:01.689256 | rockylinux-9 | changed 2026-01-21 18:21:01.696740 | 2026-01-21 18:21:01.696791 | PLAY RECAP 2026-01-21 18:21:01.696839 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-01-21 18:21:01.696861 | 2026-01-21 18:21:01.822751 | POST-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main] 2026-01-21 18:21:01.824906 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post-logs.yaml@main] 2026-01-21 18:21:02.379419 | 2026-01-21 18:21:02.379583 | PLAY [localhost] 2026-01-21 18:21:02.390577 | 2026-01-21 18:21:02.390652 | TASK [Generate Zuul manifest] 2026-01-21 18:21:02.410042 | localhost | ok 2026-01-21 18:21:02.427039 | 2026-01-21 18:21:02.427118 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-01-21 18:21:02.750709 | localhost | changed 2026-01-21 18:21:02.764509 | 2026-01-21 18:21:02.764593 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-01-21 18:21:02.799155 | localhost | ok 2026-01-21 18:21:02.806339 | 2026-01-21 18:21:02.806401 | TASK [Upload logs] 2026-01-21 18:21:02.827655 | localhost | ok 2026-01-21 18:21:02.890112 | 2026-01-21 18:21:02.890258 | TASK [Set zuul-log-path fact] 2026-01-21 18:21:02.909674 | localhost | ok 2026-01-21 18:21:02.923219 | 2026-01-21 18:21:02.923295 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-01-21 18:21:02.955443 | localhost | ok 2026-01-21 18:21:02.962578 | 2026-01-21 18:21:02.962645 | TASK [upload-logs : Create log directories] 2026-01-21 18:21:03.360662 | localhost | changed 2026-01-21 18:21:03.367448 | 2026-01-21 18:21:03.367520 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-01-21 18:21:03.785033 | localhost -> localhost | ok: Runtime: 0:00:00.005631 2026-01-21 18:21:03.791614 | 2026-01-21 18:21:03.791705 | TASK [upload-logs : Upload logs to log server] 2026-01-21 18:21:04.257976 | localhost | Output suppressed because no_log was given 2026-01-21 18:21:04.263513 | 2026-01-21 18:21:04.263576 | LOOP [upload-logs : Compress console log and json output] 2026-01-21 18:21:04.306430 | localhost | skipping: Conditional result was False 2026-01-21 18:21:04.312812 | localhost | skipping: Conditional result was False 2026-01-21 18:21:04.327353 | 2026-01-21 18:21:04.327498 | LOOP [upload-logs : Upload compressed console log and json output] 2026-01-21 18:21:04.367391 | localhost | skipping: Conditional result was False 2026-01-21 18:21:04.367714 | 2026-01-21 18:21:04.370945 | localhost | skipping: Conditional result was False 2026-01-21 18:21:04.381952 | 2026-01-21 18:21:04.382084 | LOOP [upload-logs : Upload console log and json output]