2026-04-19 01:06:24.126388 | Job console starting
2026-04-19 01:06:24.143082 | Updating git repos
2026-04-19 01:06:24.182830 | Cloning repos into workspace
2026-04-19 01:06:24.323849 | Restoring repo states
2026-04-19 01:06:24.330855 | Merging changes
2026-04-19 01:06:24.605998 | Checking out repos
2026-04-19 01:06:24.683955 | Preparing playbooks
2026-04-19 01:06:27.344898 | Running Ansible setup
2026-04-19 01:06:32.647799 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-19 01:06:33.234547 | 
2026-04-19 01:06:33.755697 | PLAY [localhost]
2026-04-19 01:06:33.768158 | 
2026-04-19 01:06:33.768292 | TASK [Gathering Facts]
2026-04-19 01:06:34.697025 | localhost | ok
2026-04-19 01:06:34.705493 | 
2026-04-19 01:06:34.705625 | TASK [Setup log path fact]
2026-04-19 01:06:34.726671 | localhost | ok
2026-04-19 01:06:34.745326 | 
2026-04-19 01:06:34.745476 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-19 01:06:34.775428 | localhost | ok
2026-04-19 01:06:34.782905 | 
2026-04-19 01:06:34.783009 | TASK [emit-job-header : Print job information]
2026-04-19 01:06:34.822384 | # Job Information
2026-04-19 01:06:34.822546 | Ansible Version: 2.16.16
2026-04-19 01:06:34.822592 | Job: ansible-collection-containers-molecule-nerdctl-rockylinux-9
2026-04-19 01:06:34.822622 | Pipeline: check
2026-04-19 01:06:34.822650 | Executor: 0a8996d2b663
2026-04-19 01:06:34.822677 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/102
2026-04-19 01:06:34.822707 | Event ID: 175c64a0-3b8b-11f1-9cee-b51e243a86c4
2026-04-19 01:06:34.826563 | 
2026-04-19 01:06:34.826650 | LOOP [emit-job-header : Print node information]
2026-04-19 01:06:34.947603 | localhost | ok:
2026-04-19 01:06:34.947809 | localhost | # Node Information
2026-04-19 01:06:34.947850 | localhost | Inventory Hostname: rockylinux-9
2026-04-19 01:06:34.947886 | localhost | Hostname: np0000167721
2026-04-19 01:06:34.947915 | localhost | Username: zuul
2026-04-19 01:06:34.947948 | localhost | Distro: Rocky 9.7
2026-04-19 01:06:34.947976 | localhost | Provider: yul1
2026-04-19 01:06:34.948003 | localhost | Region: ca-ymq-1
2026-04-19 01:06:34.948030 | localhost | Label: rockylinux-9
2026-04-19 01:06:34.948056 | localhost | Product Name: OpenStack Nova
2026-04-19 01:06:34.948083 | localhost | Interface IP: 199.204.45.229
2026-04-19 01:06:34.967260 | 
2026-04-19 01:06:34.967400 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-04-19 01:06:36.016699 | localhost -> localhost | changed
2026-04-19 01:06:36.024498 | 
2026-04-19 01:06:36.024592 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-04-19 01:06:36.880227 | localhost -> localhost | changed
2026-04-19 01:06:36.888707 | 
2026-04-19 01:06:36.888779 | PLAY [all]
2026-04-19 01:06:36.897222 | 
2026-04-19 01:06:36.897286 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-04-19 01:06:37.123603 | rockylinux-9 -> localhost | ok
2026-04-19 01:06:37.133038 | 
2026-04-19 01:06:37.133136 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-04-19 01:06:37.166609 | rockylinux-9 | ok
2026-04-19 01:06:37.184276 | rockylinux-9 | included: /var/lib/zuul/builds/5ece7876884d476b91319b5483d9176a/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-04-19 01:06:37.189310 | 
2026-04-19 01:06:37.189372 | TASK [add-build-sshkey : Create Temp SSH key]
2026-04-19 01:06:38.062047 | rockylinux-9 -> localhost | Generating public/private rsa key pair.
2026-04-19 01:06:38.062209 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/5ece7876884d476b91319b5483d9176a/work/5ece7876884d476b91319b5483d9176a_id_rsa
2026-04-19 01:06:38.062240 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/5ece7876884d476b91319b5483d9176a/work/5ece7876884d476b91319b5483d9176a_id_rsa.pub
2026-04-19 01:06:38.062262 | rockylinux-9 -> localhost | The key fingerprint is:
2026-04-19 01:06:38.062283 | rockylinux-9 -> localhost | SHA256:i8VHeU5tmjBBdHrLUdXigPsgm3csTb6/4ipLFAmlX6k zuul-build-sshkey
2026-04-19 01:06:38.062314 | rockylinux-9 -> localhost | The key's randomart image is:
2026-04-19 01:06:38.062334 | rockylinux-9 -> localhost | +---[RSA 3072]----+
2026-04-19 01:06:38.062359 | rockylinux-9 -> localhost | |      ...o+.. ..o|
2026-04-19 01:06:38.062380 | rockylinux-9 -> localhost | |       o ..*.o. .|
2026-04-19 01:06:38.062401 | rockylinux-9 -> localhost | |      . o O.=oo. |
2026-04-19 01:06:38.062420 | rockylinux-9 -> localhost | |       o.=oO.*.  |
2026-04-19 01:06:38.062440 | rockylinux-9 -> localhost | |        E+.B*    |
2026-04-19 01:06:38.062459 | rockylinux-9 -> localhost | |       +ooo *    |
2026-04-19 01:06:38.062478 | rockylinux-9 -> localhost | |      . o. o .   |
2026-04-19 01:06:38.062498 | rockylinux-9 -> localhost | |       ..   o    |
2026-04-19 01:06:38.062519 | rockylinux-9 -> localhost | |        .o.o.oo. |
2026-04-19 01:06:38.062539 | rockylinux-9 -> localhost | +----[SHA256]-----+
2026-04-19 01:06:38.062587 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.451087
2026-04-19 01:06:38.067108 | 
2026-04-19 01:06:38.067168 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-04-19 01:06:38.099526 | rockylinux-9 | ok
2026-04-19 01:06:38.108353 | rockylinux-9 | included: /var/lib/zuul/builds/5ece7876884d476b91319b5483d9176a/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-04-19 01:06:38.116942 | 
2026-04-19 01:06:38.117017 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-04-19 01:06:38.140770 | rockylinux-9 | skipping: Conditional result was False
2026-04-19 01:06:38.153326 | 
2026-04-19 01:06:38.153547 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-04-19 01:06:38.641803 | rockylinux-9 | changed
2026-04-19 01:06:38.648179 | 
2026-04-19 01:06:38.648280 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-04-19 01:06:38.924302 | rockylinux-9 | ok
2026-04-19 01:06:38.930937 | 
2026-04-19 01:06:38.931035 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-04-19 01:06:39.838809 | rockylinux-9 | changed
2026-04-19 01:06:39.845118 | 
2026-04-19 01:06:39.845200 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-04-19 01:06:40.767745 | rockylinux-9 | changed
2026-04-19 01:06:40.774815 | 
2026-04-19 01:06:40.774877 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-04-19 01:06:40.799955 | rockylinux-9 | skipping: Conditional result was False
2026-04-19 01:06:40.810071 | 
2026-04-19 01:06:40.810205 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-04-19 01:06:41.180192 | rockylinux-9 -> localhost | changed
2026-04-19 01:06:41.195547 | 
2026-04-19 01:06:41.195655 | TASK [add-build-sshkey : Add back temp key]
2026-04-19 01:06:41.462309 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/5ece7876884d476b91319b5483d9176a/work/5ece7876884d476b91319b5483d9176a_id_rsa (zuul-build-sshkey)
2026-04-19 01:06:41.462609 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.012873
2026-04-19 01:06:41.471913 | 
2026-04-19 01:06:41.471973 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-04-19 01:06:41.849871 | rockylinux-9 | ok
2026-04-19 01:06:41.854183 | 
2026-04-19 01:06:41.854243 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-04-19 01:06:41.877119 | rockylinux-9 | skipping: Conditional result was False
2026-04-19 01:06:41.892105 | 
2026-04-19 01:06:41.892170 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-04-19 01:06:42.310826 | rockylinux-9 | ok
2026-04-19 01:06:42.317448 | 
2026-04-19 01:06:42.317510 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-04-19 01:06:43.243692 | rockylinux-9 | Output suppressed because no_log was given
2026-04-19 01:06:43.253925 | 
2026-04-19 01:06:43.253994 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-04-19 01:06:43.519544 | rockylinux-9 | ok: "logs"
2026-04-19 01:06:43.519821 | rockylinux-9 | ok: All items complete
2026-04-19 01:06:43.519851 | 
2026-04-19 01:06:43.763063 | rockylinux-9 | ok: "artifacts"
2026-04-19 01:06:43.982349 | rockylinux-9 | ok: "docs"
2026-04-19 01:06:43.999182 | 
2026-04-19 01:06:43.999378 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-04-19 01:06:44.262807 | rockylinux-9 | changed: "logs"
2026-04-19 01:06:44.487396 | rockylinux-9 | changed: "artifacts"
2026-04-19 01:06:44.727349 | rockylinux-9 | changed: "docs"
2026-04-19 01:06:44.747718 | 
2026-04-19 01:06:44.747835 | PLAY RECAP
2026-04-19 01:06:44.747882 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-19 01:06:44.747912 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-04-19 01:06:44.747932 | 
2026-04-19 01:06:44.864191 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-19 01:06:44.865572 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-19 01:06:45.420703 | 
2026-04-19 01:06:45.420828 | PLAY [all]
2026-04-19 01:06:45.431831 | 
2026-04-19 01:06:45.431926 | TASK [setup-uv : Extract archive]
2026-04-19 01:06:48.245447 | rockylinux-9 | changed
2026-04-19 01:06:48.253948 | 
2026-04-19 01:06:48.254068 | TASK [setup-uv : Print version]
2026-04-19 01:06:48.278766 | [rockylinux-9] Waiting on logger
2026-04-19 01:06:48.696317 | rockylinux-9 | ok: Runtime: 0:00:00.012837
2026-04-19 01:06:48.704316 | 
2026-04-19 01:06:48.704366 | PLAY RECAP
2026-04-19 01:06:48.704416 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-19 01:06:48.704440 | 
2026-04-19 01:06:48.846925 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-19 01:06:48.849090 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-04-19 01:06:49.426349 | 
2026-04-19 01:06:49.426556 | PLAY [all]
2026-04-19 01:06:49.437492 | 
2026-04-19 01:06:49.437567 | TASK [Copy inventory file for Zuul]
2026-04-19 01:06:50.756369 | rockylinux-9 | changed
2026-04-19 01:06:50.761207 | 
2026-04-19 01:06:50.761282 | TASK [Switch "ansible_host" to private IP]
2026-04-19 01:06:51.107108 | rockylinux-9 | changed: 1 replacements made
2026-04-19 01:06:51.115699 | 
2026-04-19 01:06:51.338757 | TASK [Run Molecule scenario]
2026-04-19 01:06:51.368023 | [rockylinux-9] Waiting on logger
2026-04-19 01:06:58.502397 | [rockylinux-9] Waiting on logger
2026-04-19 01:07:09.125938 | [rockylinux-9] Waiting on logger
2026-04-19 01:07:18.283974 | [rockylinux-9] Waiting on logger
2026-04-19 01:07:28.454274 | [rockylinux-9] Waiting on logger
2026-04-19 01:07:38.629960 | [rockylinux-9] Waiting on logger
2026-04-19 01:07:48.806065 | [rockylinux-9] Waiting on logger
2026-04-19 01:07:58.982712 | [rockylinux-9] Waiting on logger
2026-04-19 01:08:09.158041 | [rockylinux-9] Waiting on logger
2026-04-19 01:08:18.315429 | [rockylinux-9] Waiting on logger
2026-04-19 01:08:28.485947 | [rockylinux-9] Waiting on logger
2026-04-19 01:08:38.662176 | [rockylinux-9] Waiting on logger
2026-04-19 01:08:48.842011 | [rockylinux-9] Waiting on logger
2026-04-19 01:08:59.014024 | [rockylinux-9] Waiting on logger
2026-04-19 01:09:02.477518 | rockylinux-9 | ok: Runtime: 0:02:10.215279
2026-04-19 01:09:02.486618 | 
2026-04-19 01:09:02.486668 | PLAY RECAP
2026-04-19 01:09:02.486711 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-19 01:09:02.486734 | 
2026-04-19 01:09:02.580777 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-04-19 01:09:02.581968 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-19 01:09:03.117613 | 
2026-04-19 01:09:03.117727 | PLAY [all]
2026-04-19 01:09:03.127705 | 
2026-04-19 01:09:03.127776 | TASK [fetch-output : Set log path for multiple nodes]
2026-04-19 01:09:03.173160 | rockylinux-9 | skipping: Conditional result was False
2026-04-19 01:09:03.182821 | 
2026-04-19 01:09:03.182905 | TASK [fetch-output : Set log path for single node]
2026-04-19 01:09:03.226493 | rockylinux-9 | ok
2026-04-19 01:09:03.233579 | 
2026-04-19 01:09:03.233668 | LOOP [fetch-output : Ensure local output dirs]
2026-04-19 01:09:03.599798 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/5ece7876884d476b91319b5483d9176a/work/logs"
2026-04-19 01:09:03.801348 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/5ece7876884d476b91319b5483d9176a/work/artifacts"
2026-04-19 01:09:03.998904 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/5ece7876884d476b91319b5483d9176a/work/docs"
2026-04-19 01:09:04.015125 | 
2026-04-19 01:09:04.015348 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-04-19 01:09:04.691694 | rockylinux-9 | changed: .d..t...... ./
2026-04-19 01:09:04.691952 | rockylinux-9 | changed: All items complete
2026-04-19 01:09:04.691996 | 
2026-04-19 01:09:05.205441 | rockylinux-9 | changed: .d..t...... ./
2026-04-19 01:09:05.703914 | rockylinux-9 | changed: .d..t...... ./
2026-04-19 01:09:05.726679 | 
2026-04-19 01:09:05.726797 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-04-19 01:09:06.110887 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.006818
2026-04-19 01:09:06.329526 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.007607
2026-04-19 01:09:06.349762 | 
2026-04-19 01:09:06.349881 | PLAY [all]
2026-04-19 01:09:06.355314 | 
2026-04-19 01:09:06.355378 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-04-19 01:09:06.803578 | rockylinux-9 | changed
2026-04-19 01:09:06.808744 | 
2026-04-19 01:09:06.808797 | PLAY RECAP
2026-04-19 01:09:06.808841 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-04-19 01:09:06.808865 | 
2026-04-19 01:09:06.917209 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-19 01:09:06.919218 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-04-19 01:09:07.450618 | 
2026-04-19 01:09:07.450740 | PLAY [localhost]
2026-04-19 01:09:07.460050 | 
2026-04-19 01:09:07.460120 | TASK [Generate Zuul manifest]
2026-04-19 01:09:07.481430 | localhost | ok
2026-04-19 01:09:07.496388 | 
2026-04-19 01:09:07.496467 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-04-19 01:09:07.800227 | localhost | changed
2026-04-19 01:09:07.812974 | 
2026-04-19 01:09:07.813048 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-04-19 01:09:07.843810 | localhost | ok
2026-04-19 01:09:07.852376 | 
2026-04-19 01:09:07.852458 | TASK [Upload logs]
2026-04-19 01:09:07.873851 | localhost | ok
2026-04-19 01:09:07.932145 | 
2026-04-19 01:09:07.932240 | TASK [Set zuul-log-path fact]
2026-04-19 01:09:07.962951 | localhost | ok
2026-04-19 01:09:07.976373 | 
2026-04-19 01:09:07.976439 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-19 01:09:08.007496 | localhost | ok
2026-04-19 01:09:08.019172 | 
2026-04-19 01:09:08.019236 | TASK [upload-logs : Create log directories]
2026-04-19 01:09:08.391869 | localhost | changed
2026-04-19 01:09:08.397366 | 
2026-04-19 01:09:08.397433 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-04-19 01:09:08.772684 | localhost -> localhost | ok: Runtime: 0:00:00.004693
2026-04-19 01:09:08.778627 | 
2026-04-19 01:09:08.778689 | TASK [upload-logs : Upload logs to log server]
2026-04-19 01:09:09.214596 | localhost | Output suppressed because no_log was given
2026-04-19 01:09:09.220671 | 
2026-04-19 01:09:09.220737 | LOOP [upload-logs : Compress console log and json output]
2026-04-19 01:09:09.264315 | localhost | skipping: Conditional result was False
2026-04-19 01:09:09.271300 | localhost | skipping: Conditional result was False
2026-04-19 01:09:09.284075 | 
2026-04-19 01:09:09.284258 | LOOP [upload-logs : Upload compressed console log and json output]
2026-04-19 01:09:09.325907 | localhost | skipping: Conditional result was False
2026-04-19 01:09:09.326275 | 
2026-04-19 01:09:09.330086 | localhost | skipping: Conditional result was False
2026-04-19 01:09:09.347816 | 
2026-04-19 01:09:09.347938 | LOOP [upload-logs : Upload console log and json output]