2026-04-15 00:59:52.312404 | Job console starting 2026-04-15 00:59:52.319256 | Updating git repos 2026-04-15 00:59:52.337437 | Cloning repos into workspace 2026-04-15 00:59:52.396291 | Restoring repo states 2026-04-15 00:59:52.402214 | Merging changes 2026-04-15 00:59:52.660494 | Checking out repos 2026-04-15 00:59:52.689068 | Preparing playbooks 2026-04-15 00:59:53.744724 | Running Ansible setup 2026-04-15 00:59:57.302884 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-15 00:59:57.895996 | 2026-04-15 00:59:58.025922 | PLAY [localhost] 2026-04-15 00:59:58.039295 | 2026-04-15 00:59:58.039374 | TASK [Gathering Facts] 2026-04-15 00:59:58.926578 | localhost | ok 2026-04-15 00:59:58.932990 | 2026-04-15 00:59:58.933101 | TASK [Setup log path fact] 2026-04-15 00:59:58.952426 | localhost | ok 2026-04-15 00:59:58.964644 | 2026-04-15 00:59:58.964723 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-15 00:59:58.994765 | localhost | ok 2026-04-15 00:59:59.004152 | 2026-04-15 00:59:59.004286 | TASK [emit-job-header : Print job information] 2026-04-15 00:59:59.044326 | # Job Information 2026-04-15 00:59:59.044511 | Ansible Version: 2.16.16 2026-04-15 00:59:59.044557 | Job: ansible-collection-containers-molecule-containerd-rockylinux-9 2026-04-15 00:59:59.044590 | Pipeline: check 2026-04-15 00:59:59.044619 | Executor: 0a8996d2b663 2026-04-15 00:59:59.044648 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/102 2026-04-15 00:59:59.044680 | Event ID: 24745760-3866-11f1-9631-53252c2f9552 2026-04-15 00:59:59.048677 | 2026-04-15 00:59:59.048764 | LOOP [emit-job-header : Print node information] 2026-04-15 00:59:59.132376 | localhost | ok: 2026-04-15 00:59:59.132613 | localhost | # Node Information 2026-04-15 00:59:59.132652 | localhost | Inventory Hostname: rockylinux-9 2026-04-15 00:59:59.132686 | localhost | Hostname: np0000166312 2026-04-15 00:59:59.132714 | localhost | Username: zuul 2026-04-15 00:59:59.132743 | localhost | Distro: Rocky 9.7 2026-04-15 00:59:59.132770 | localhost | Provider: yul1 2026-04-15 00:59:59.132796 | localhost | Region: ca-ymq-1 2026-04-15 00:59:59.132822 | localhost | Label: rockylinux-9 2026-04-15 00:59:59.132847 | localhost | Product Name: OpenStack Nova 2026-04-15 00:59:59.132872 | localhost | Interface IP: 199.204.45.74 2026-04-15 00:59:59.145046 | 2026-04-15 00:59:59.145177 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-04-15 00:59:59.563320 | localhost -> localhost | changed 2026-04-15 00:59:59.571654 | 2026-04-15 00:59:59.571764 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-04-15 01:00:00.660063 | localhost -> localhost | changed 2026-04-15 01:00:00.670252 | 2026-04-15 01:00:00.670340 | PLAY [all] 2026-04-15 01:00:00.678558 | 2026-04-15 01:00:00.678628 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-04-15 01:00:00.918762 | rockylinux-9 -> localhost | ok 2026-04-15 01:00:00.924485 | 2026-04-15 01:00:00.924573 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-04-15 01:00:00.956008 | rockylinux-9 | ok 2026-04-15 01:00:00.972877 | rockylinux-9 | included: /var/lib/zuul/builds/b7effa472f1c4e20afab74da2be9b2a7/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-04-15 01:00:00.978075 | 2026-04-15 01:00:00.978149 | TASK [add-build-sshkey : Create Temp SSH key] 2026-04-15 01:00:01.739676 | rockylinux-9 -> localhost | Generating public/private rsa key pair. 2026-04-15 01:00:01.739814 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/b7effa472f1c4e20afab74da2be9b2a7/work/b7effa472f1c4e20afab74da2be9b2a7_id_rsa 2026-04-15 01:00:01.739845 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/b7effa472f1c4e20afab74da2be9b2a7/work/b7effa472f1c4e20afab74da2be9b2a7_id_rsa.pub 2026-04-15 01:00:01.739867 | rockylinux-9 -> localhost | The key fingerprint is: 2026-04-15 01:00:01.739888 | rockylinux-9 -> localhost | SHA256:X741U55N1PNfL1mYm8Gfw8ccaXlFUAN6byDuCvlUcMM zuul-build-sshkey 2026-04-15 01:00:01.739917 | rockylinux-9 -> localhost | The key's randomart image is: 2026-04-15 01:00:01.739939 | rockylinux-9 -> localhost | +---[RSA 3072]----+ 2026-04-15 01:00:01.739962 | rockylinux-9 -> localhost | | .o+o| 2026-04-15 01:00:01.739982 | rockylinux-9 -> localhost | | . . .o| 2026-04-15 01:00:01.740002 | rockylinux-9 -> localhost | | . E o .+| 2026-04-15 01:00:01.740022 | rockylinux-9 -> localhost | | + +.o+*| 2026-04-15 01:00:01.740041 | rockylinux-9 -> localhost | | S o. =BB| 2026-04-15 01:00:01.740061 | rockylinux-9 -> localhost | | ..oo +&X| 2026-04-15 01:00:01.740080 | rockylinux-9 -> localhost | | o .... O=X| 2026-04-15 01:00:01.740099 | rockylinux-9 -> localhost | | + . o +o| 2026-04-15 01:00:01.740121 | rockylinux-9 -> localhost | | o . | 2026-04-15 01:00:01.740141 | rockylinux-9 -> localhost | +----[SHA256]-----+ 2026-04-15 01:00:01.740186 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.363997 2026-04-15 01:00:01.745147 | 2026-04-15 01:00:01.745209 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-04-15 01:00:01.790263 | rockylinux-9 | ok 2026-04-15 01:00:01.807897 | rockylinux-9 | included: /var/lib/zuul/builds/b7effa472f1c4e20afab74da2be9b2a7/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-04-15 01:00:01.823682 | 2026-04-15 01:00:01.823840 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-04-15 01:00:01.852097 | rockylinux-9 | skipping: Conditional result was False 2026-04-15 01:00:01.864553 | 2026-04-15 01:00:01.864755 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-04-15 01:00:02.326420 | rockylinux-9 | changed 2026-04-15 01:00:02.330865 | 2026-04-15 01:00:02.330924 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-04-15 01:00:02.577314 | rockylinux-9 | ok 2026-04-15 01:00:02.584547 | 2026-04-15 01:00:02.584645 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-04-15 01:00:03.894137 | rockylinux-9 | changed 2026-04-15 01:00:03.900070 | 2026-04-15 01:00:03.900132 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-04-15 01:00:04.784859 | rockylinux-9 | changed 2026-04-15 01:00:04.792552 | 2026-04-15 01:00:04.792684 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-04-15 01:00:04.817294 | rockylinux-9 | skipping: Conditional result was False 2026-04-15 01:00:04.825845 | 2026-04-15 01:00:04.825939 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-04-15 01:00:05.204195 | rockylinux-9 -> localhost | changed 2026-04-15 01:00:05.233772 | 2026-04-15 01:00:05.233895 | TASK [add-build-sshkey : Add back temp key] 2026-04-15 01:00:05.863569 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/b7effa472f1c4e20afab74da2be9b2a7/work/b7effa472f1c4e20afab74da2be9b2a7_id_rsa (zuul-build-sshkey) 2026-04-15 01:00:05.863754 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.012497 2026-04-15 01:00:05.885204 | 2026-04-15 01:00:05.885385 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-04-15 01:00:06.286246 | rockylinux-9 | ok 2026-04-15 01:00:06.291379 | 2026-04-15 01:00:06.291454 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-04-15 01:00:06.316257 | rockylinux-9 | skipping: Conditional result was False 2026-04-15 01:00:06.326838 | 2026-04-15 01:00:06.326929 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-04-15 01:00:06.826068 | rockylinux-9 | ok 2026-04-15 01:00:06.831381 | 2026-04-15 01:00:06.831456 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-04-15 01:00:07.873492 | rockylinux-9 | Output suppressed because no_log was given 2026-04-15 01:00:07.881022 | 2026-04-15 01:00:07.881098 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-04-15 01:00:08.167389 | rockylinux-9 | ok: "logs" 2026-04-15 01:00:08.167764 | rockylinux-9 | ok: All items complete 2026-04-15 01:00:08.167812 | 2026-04-15 01:00:08.399250 | rockylinux-9 | ok: "artifacts" 2026-04-15 01:00:08.621544 | rockylinux-9 | ok: "docs" 2026-04-15 01:00:08.634287 | 2026-04-15 01:00:08.634353 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-04-15 01:00:08.895397 | rockylinux-9 | changed: "logs" 2026-04-15 01:00:09.113210 | rockylinux-9 | changed: "artifacts" 2026-04-15 01:00:09.415741 | rockylinux-9 | changed: "docs" 2026-04-15 01:00:09.425308 | 2026-04-15 01:00:09.425390 | PLAY RECAP 2026-04-15 01:00:09.425439 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-15 01:00:09.425469 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-04-15 01:00:09.425492 | 2026-04-15 01:00:09.546847 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-15 01:00:09.551159 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-15 01:00:10.155452 | 2026-04-15 01:00:10.155575 | PLAY [all] 2026-04-15 01:00:10.167275 | 2026-04-15 01:00:10.167358 | TASK [setup-uv : Extract archive] 2026-04-15 01:00:12.885665 | rockylinux-9 | changed 2026-04-15 01:00:12.911094 | 2026-04-15 01:00:12.911275 | TASK [setup-uv : Print version] 2026-04-15 01:00:12.941906 | [rockylinux-9] Waiting on logger 2026-04-15 01:00:13.459112 | rockylinux-9 | ok: Runtime: 0:00:00.019409 2026-04-15 01:00:13.524876 | 2026-04-15 01:00:13.525004 | PLAY RECAP 2026-04-15 01:00:13.525063 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-15 01:00:13.525088 | 2026-04-15 01:00:13.632534 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-15 01:00:13.635363 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-15 01:00:14.204238 | 2026-04-15 01:00:14.325702 | PLAY [all] 2026-04-15 01:00:14.340715 | 2026-04-15 01:00:14.340786 | TASK [Copy inventory file for Zuul] 2026-04-15 01:00:15.675166 | rockylinux-9 | changed 2026-04-15 01:00:15.679926 | 2026-04-15 01:00:15.680006 | TASK [Switch "ansible_host" to private IP] 2026-04-15 01:00:16.047754 | rockylinux-9 | changed: 1 replacements made 2026-04-15 01:00:16.208253 | 2026-04-15 01:00:16.208373 | TASK [Run Molecule scenario] 2026-04-15 01:00:16.229112 | [rockylinux-9] Waiting on logger 2026-04-15 01:00:24.361970 | [rockylinux-9] Waiting on logger 2026-04-15 01:00:34.534095 | [rockylinux-9] Waiting on logger 2026-04-15 01:00:44.710633 | [rockylinux-9] Waiting on logger 2026-04-15 01:00:54.027438 | [rockylinux-9] Waiting on logger 2026-04-15 01:01:04.198275 | [rockylinux-9] Waiting on logger 2026-04-15 01:01:14.373901 | [rockylinux-9] Waiting on logger 2026-04-15 01:01:24.550186 | [rockylinux-9] Waiting on logger 2026-04-15 01:01:34.730088 | [rockylinux-9] Waiting on logger 2026-04-15 01:01:44.902021 | [rockylinux-9] Waiting on logger 2026-04-15 01:01:54.059049 | [rockylinux-9] Waiting on logger 2026-04-15 01:02:04.233986 | [rockylinux-9] Waiting on logger 2026-04-15 01:02:14.405967 | [rockylinux-9] Waiting on logger 2026-04-15 01:02:24.582072 | [rockylinux-9] Waiting on logger 2026-04-15 01:02:34.758278 | [rockylinux-9] Waiting on logger 2026-04-15 01:02:44.934172 | [rockylinux-9] Waiting on logger 2026-04-15 01:02:54.085997 | [rockylinux-9] Waiting on logger 2026-04-15 01:03:04.262141 | [rockylinux-9] Waiting on logger 2026-04-15 01:03:14.438692 | [rockylinux-9] Waiting on logger 2026-04-15 01:03:24.646328 | [rockylinux-9] Waiting on logger 2026-04-15 01:03:34.822163 | [rockylinux-9] Waiting on logger 2026-04-15 01:03:43.979480 | [rockylinux-9] Waiting on logger 2026-04-15 01:03:54.150575 | [rockylinux-9] Waiting on logger 2026-04-15 01:04:04.326210 | [rockylinux-9] Waiting on logger 2026-04-15 01:04:14.502490 | [rockylinux-9] Waiting on logger 2026-04-15 01:04:24.678224 | [rockylinux-9] Waiting on logger 2026-04-15 01:04:34.854341 | [rockylinux-9] Waiting on logger 2026-04-15 01:04:44.011563 | [rockylinux-9] Waiting on logger 2026-04-15 01:04:54.182442 | [rockylinux-9] Waiting on logger 2026-04-15 01:04:56.430173 | rockylinux-9 | ok: Runtime: 0:04:38.830155 2026-04-15 01:04:56.437386 | 2026-04-15 01:04:56.437439 | PLAY RECAP 2026-04-15 01:04:56.437482 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-15 01:04:56.437505 | 2026-04-15 01:04:56.527483 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-15 01:04:56.530105 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-15 01:04:57.061260 | 2026-04-15 01:04:57.061371 | PLAY [all] 2026-04-15 01:04:57.071252 | 2026-04-15 01:04:57.071325 | TASK [fetch-output : Set log path for multiple nodes] 2026-04-15 01:04:57.115287 | rockylinux-9 | skipping: Conditional result was False 2026-04-15 01:04:57.119991 | 2026-04-15 01:04:57.120068 | TASK [fetch-output : Set log path for single node] 2026-04-15 01:04:57.163062 | rockylinux-9 | ok 2026-04-15 01:04:57.169770 | 2026-04-15 01:04:57.169835 | LOOP [fetch-output : Ensure local output dirs] 2026-04-15 01:04:57.563124 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/b7effa472f1c4e20afab74da2be9b2a7/work/logs" 2026-04-15 01:04:57.760111 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/b7effa472f1c4e20afab74da2be9b2a7/work/artifacts" 2026-04-15 01:04:57.957347 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/b7effa472f1c4e20afab74da2be9b2a7/work/docs" 2026-04-15 01:04:57.976464 | 2026-04-15 01:04:57.976584 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-04-15 01:04:58.644013 | rockylinux-9 | changed: .d..t...... ./ 2026-04-15 01:04:58.644253 | rockylinux-9 | changed: All items complete 2026-04-15 01:04:58.644310 | 2026-04-15 01:04:59.138095 | rockylinux-9 | changed: .d..t...... ./ 2026-04-15 01:04:59.649072 | rockylinux-9 | changed: .d..t...... ./ 2026-04-15 01:04:59.678068 | 2026-04-15 01:04:59.678181 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-04-15 01:05:00.056187 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.006449 2026-04-15 01:05:00.270236 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.006806 2026-04-15 01:05:00.283056 | 2026-04-15 01:05:00.283140 | PLAY [all] 2026-04-15 01:05:00.289567 | 2026-04-15 01:05:00.289629 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-04-15 01:05:00.706108 | rockylinux-9 | changed 2026-04-15 01:05:00.710898 | 2026-04-15 01:05:00.710946 | PLAY RECAP 2026-04-15 01:05:00.710988 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-04-15 01:05:00.711011 | 2026-04-15 01:05:00.798887 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-15 01:05:00.801270 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-04-15 01:05:01.318358 | 2026-04-15 01:05:01.318465 | PLAY [localhost] 2026-04-15 01:05:01.327656 | 2026-04-15 01:05:01.327724 | TASK [Generate Zuul manifest] 2026-04-15 01:05:01.347873 | localhost | ok 2026-04-15 01:05:01.362798 | 2026-04-15 01:05:01.362872 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-04-15 01:05:01.666064 | localhost | changed 2026-04-15 01:05:01.677935 | 2026-04-15 01:05:01.678003 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-04-15 01:05:01.709762 | localhost | ok 2026-04-15 01:05:01.717538 | 2026-04-15 01:05:01.717604 | TASK [Upload logs] 2026-04-15 01:05:01.739542 | localhost | ok 2026-04-15 01:05:01.796695 | 2026-04-15 01:05:01.796782 | TASK [Set zuul-log-path fact] 2026-04-15 01:05:01.817345 | localhost | ok 2026-04-15 01:05:01.829249 | 2026-04-15 01:05:01.829317 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-15 01:05:01.857633 | localhost | ok 2026-04-15 01:05:01.866421 | 2026-04-15 01:05:01.866485 | TASK [upload-logs : Create log directories] 2026-04-15 01:05:02.242181 | localhost | changed 2026-04-15 01:05:02.249022 | 2026-04-15 01:05:02.249092 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-04-15 01:05:02.655523 | localhost -> localhost | ok: Runtime: 0:00:00.002942 2026-04-15 01:05:02.661092 | 2026-04-15 01:05:02.661157 | TASK [upload-logs : Upload logs to log server] 2026-04-15 01:05:03.088257 | localhost | Output suppressed because no_log was given 2026-04-15 01:05:03.092986 | 2026-04-15 01:05:03.093072 | LOOP [upload-logs : Compress console log and json output] 2026-04-15 01:05:03.146373 | localhost | skipping: Conditional result was False 2026-04-15 01:05:03.153288 | localhost | skipping: Conditional result was False 2026-04-15 01:05:03.166634 | 2026-04-15 01:05:03.166779 | LOOP [upload-logs : Upload compressed console log and json output] 2026-04-15 01:05:03.210496 | localhost | skipping: Conditional result was False 2026-04-15 01:05:03.210893 | 2026-04-15 01:05:03.214626 | localhost | skipping: Conditional result was False 2026-04-15 01:05:03.230642 | 2026-04-15 01:05:03.230756 | LOOP [upload-logs : Upload console log and json output]