2026-04-15 00:59:48.144225 | Job console starting
2026-04-15 00:59:48.153400 | Updating git repos
2026-04-15 00:59:48.173947 | Cloning repos into workspace
2026-04-15 00:59:48.219363 | Restoring repo states
2026-04-15 00:59:48.225085 | Merging changes
2026-04-15 00:59:48.506559 | Checking out repos
2026-04-15 00:59:48.540324 | Preparing playbooks
2026-04-15 00:59:49.598137 | Running Ansible setup
2026-04-15 00:59:53.362408 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-15 00:59:53.955666 | 
2026-04-15 00:59:53.955798 | PLAY [localhost]
2026-04-15 00:59:53.963618 | 
2026-04-15 00:59:53.963692 | TASK [Gathering Facts]
2026-04-15 00:59:54.830383 | localhost | ok
2026-04-15 00:59:54.839639 | 
2026-04-15 00:59:54.839709 | TASK [Setup log path fact]
2026-04-15 00:59:54.857054 | localhost | ok
2026-04-15 00:59:54.870762 | 
2026-04-15 00:59:54.870874 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-15 00:59:54.900561 | localhost | ok
2026-04-15 00:59:54.910924 | 
2026-04-15 00:59:54.911078 | TASK [emit-job-header : Print job information]
2026-04-15 00:59:54.951958 | # Job Information
2026-04-15 00:59:54.952190 | Ansible Version: 2.16.16
2026-04-15 00:59:54.952261 | Job: ansible-collection-containers-molecule-nerdctl-rockylinux-9
2026-04-15 00:59:54.952310 | Pipeline: check
2026-04-15 00:59:54.952436 | Executor: 0a8996d2b663
2026-04-15 00:59:54.952498 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/109
2026-04-15 00:59:54.952549 | Event ID: 276b5040-3866-11f1-87ec-4c6fd3e486d6
2026-04-15 00:59:54.957076 | 
2026-04-15 00:59:54.957138 | LOOP [emit-job-header : Print node information]
2026-04-15 00:59:55.042861 | localhost | ok:
2026-04-15 00:59:55.043032 | localhost | # Node Information
2026-04-15 00:59:55.043066 | localhost | Inventory Hostname: rockylinux-9
2026-04-15 00:59:55.043097 | localhost | Hostname: np0000166332
2026-04-15 00:59:55.043122 | localhost | Username: zuul
2026-04-15 00:59:55.043149 | localhost | Distro: Rocky 9.7
2026-04-15 00:59:55.043173 | localhost | Provider: yul1
2026-04-15 00:59:55.043196 | localhost | Region: ca-ymq-1
2026-04-15 00:59:55.043218 | localhost | Label: rockylinux-9
2026-04-15 00:59:55.043241 | localhost | Product Name: OpenStack Nova
2026-04-15 00:59:55.043264 | localhost | Interface IP: 199.204.45.113
2026-04-15 00:59:55.053893 | 
2026-04-15 00:59:55.053985 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-04-15 00:59:55.431661 | localhost -> localhost | changed
2026-04-15 00:59:55.436391 | 
2026-04-15 00:59:55.436518 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-04-15 00:59:56.446186 | localhost -> localhost | changed
2026-04-15 00:59:56.459416 | 
2026-04-15 00:59:56.459511 | PLAY [all]
2026-04-15 00:59:56.467524 | 
2026-04-15 00:59:56.467599 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-04-15 00:59:56.678099 | rockylinux-9 -> localhost | ok
2026-04-15 00:59:56.683823 | 
2026-04-15 00:59:56.683888 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-04-15 00:59:56.714546 | rockylinux-9 | ok
2026-04-15 00:59:56.728876 | rockylinux-9 | included: /var/lib/zuul/builds/93b24b281ad44a3a8920664f0683fb37/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-04-15 00:59:56.733921 | 
2026-04-15 00:59:56.733981 | TASK [add-build-sshkey : Create Temp SSH key]
2026-04-15 00:59:58.157908 | rockylinux-9 -> localhost | Generating public/private rsa key pair.
2026-04-15 00:59:58.158142 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/93b24b281ad44a3a8920664f0683fb37/work/93b24b281ad44a3a8920664f0683fb37_id_rsa
2026-04-15 00:59:58.158186 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/93b24b281ad44a3a8920664f0683fb37/work/93b24b281ad44a3a8920664f0683fb37_id_rsa.pub
2026-04-15 00:59:58.158217 | rockylinux-9 -> localhost | The key fingerprint is:
2026-04-15 00:59:58.158247 | rockylinux-9 -> localhost | SHA256:fvWlw/jnzitx9bnUPBpalZt1OXw0iGzR9N6eQDcy9cA zuul-build-sshkey
2026-04-15 00:59:58.158292 | rockylinux-9 -> localhost | The key's randomart image is:
2026-04-15 00:59:58.158322 | rockylinux-9 -> localhost | +---[RSA 3072]----+
2026-04-15 00:59:58.158367 | rockylinux-9 -> localhost | |          ..=oo. |
2026-04-15 00:59:58.158399 | rockylinux-9 -> localhost | |           + oEoo|
2026-04-15 00:59:58.158426 | rockylinux-9 -> localhost | |          .  +.==|
2026-04-15 00:59:58.158453 | rockylinux-9 -> localhost | |            . =*B|
2026-04-15 00:59:58.158480 | rockylinux-9 -> localhost | |        S   ...o#|
2026-04-15 00:59:58.158507 | rockylinux-9 -> localhost | |       .   . B.@=|
2026-04-15 00:59:58.158534 | rockylinux-9 -> localhost | |        . . + @.+|
2026-04-15 00:59:58.158561 | rockylinux-9 -> localhost | |         . . + +.|
2026-04-15 00:59:58.158592 | rockylinux-9 -> localhost | |              o==|
2026-04-15 00:59:58.158620 | rockylinux-9 -> localhost | +----[SHA256]-----+
2026-04-15 00:59:58.158691 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.948838
2026-04-15 00:59:58.165394 | 
2026-04-15 00:59:58.165465 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-04-15 00:59:58.199114 | rockylinux-9 | ok
2026-04-15 00:59:58.210267 | rockylinux-9 | included: /var/lib/zuul/builds/93b24b281ad44a3a8920664f0683fb37/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-04-15 00:59:58.218371 | 
2026-04-15 00:59:58.218453 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-04-15 00:59:58.243545 | rockylinux-9 | skipping: Conditional result was False
2026-04-15 00:59:58.252402 | 
2026-04-15 00:59:58.252526 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-04-15 00:59:58.749361 | rockylinux-9 | changed
2026-04-15 00:59:58.754330 | 
2026-04-15 00:59:58.754403 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-04-15 00:59:59.015919 | rockylinux-9 | ok
2026-04-15 00:59:59.020693 | 
2026-04-15 00:59:59.020754 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-04-15 00:59:59.989029 | rockylinux-9 | changed
2026-04-15 00:59:59.994993 | 
2026-04-15 00:59:59.995057 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-04-15 01:00:00.945883 | rockylinux-9 | changed
2026-04-15 01:00:00.951175 | 
2026-04-15 01:00:00.951244 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-04-15 01:00:00.975160 | rockylinux-9 | skipping: Conditional result was False
2026-04-15 01:00:00.984509 | 
2026-04-15 01:00:00.984623 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-04-15 01:00:01.357955 | rockylinux-9 -> localhost | changed
2026-04-15 01:00:01.367083 | 
2026-04-15 01:00:01.367144 | TASK [add-build-sshkey : Add back temp key]
2026-04-15 01:00:01.637647 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/93b24b281ad44a3a8920664f0683fb37/work/93b24b281ad44a3a8920664f0683fb37_id_rsa (zuul-build-sshkey)
2026-04-15 01:00:01.637843 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.013339
2026-04-15 01:00:01.643901 | 
2026-04-15 01:00:01.643963 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-04-15 01:00:02.035398 | rockylinux-9 | ok
2026-04-15 01:00:02.040055 | 
2026-04-15 01:00:02.040129 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-04-15 01:00:02.068325 | rockylinux-9 | skipping: Conditional result was False
2026-04-15 01:00:02.077331 | 
2026-04-15 01:00:02.077437 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-04-15 01:00:02.494545 | rockylinux-9 | ok
2026-04-15 01:00:02.503007 | 
2026-04-15 01:00:02.503072 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-04-15 01:00:03.482813 | rockylinux-9 | Output suppressed because no_log was given
2026-04-15 01:00:03.492997 | 
2026-04-15 01:00:03.493084 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-04-15 01:00:03.754998 | rockylinux-9 | ok: "logs"
2026-04-15 01:00:03.755288 | rockylinux-9 | ok: All items complete
2026-04-15 01:00:03.755331 | 
2026-04-15 01:00:03.977899 | rockylinux-9 | ok: "artifacts"
2026-04-15 01:00:04.203306 | rockylinux-9 | ok: "docs"
2026-04-15 01:00:04.220305 | 
2026-04-15 01:00:04.220432 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-04-15 01:00:04.497934 | rockylinux-9 | changed: "logs"
2026-04-15 01:00:04.727525 | rockylinux-9 | changed: "artifacts"
2026-04-15 01:00:04.961349 | rockylinux-9 | changed: "docs"
2026-04-15 01:00:04.971363 | 
2026-04-15 01:00:04.971428 | PLAY RECAP
2026-04-15 01:00:04.971473 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-15 01:00:04.971502 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-04-15 01:00:04.971525 | 
2026-04-15 01:00:05.068474 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-15 01:00:05.070413 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-15 01:00:05.670376 | 
2026-04-15 01:00:05.670535 | PLAY [all]
2026-04-15 01:00:05.683062 | 
2026-04-15 01:00:05.683153 | TASK [setup-uv : Extract archive]
2026-04-15 01:00:08.552063 | rockylinux-9 | changed
2026-04-15 01:00:08.559630 | 
2026-04-15 01:00:08.559749 | TASK [setup-uv : Print version]
2026-04-15 01:00:08.584522 | [rockylinux-9] Waiting on logger
2026-04-15 01:00:09.097074 | rockylinux-9 | ok: Runtime: 0:00:00.016107
2026-04-15 01:00:09.424381 | 
2026-04-15 01:00:09.424494 | PLAY RECAP
2026-04-15 01:00:09.424556 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-15 01:00:09.424590 | 
2026-04-15 01:00:09.555794 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-15 01:00:09.557533 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-04-15 01:00:10.190510 | 
2026-04-15 01:00:10.190656 | PLAY [all]
2026-04-15 01:00:10.201396 | 
2026-04-15 01:00:10.201496 | TASK [Copy inventory file for Zuul]
2026-04-15 01:00:11.546355 | rockylinux-9 | changed
2026-04-15 01:00:11.553404 | 
2026-04-15 01:00:11.553510 | TASK [Switch "ansible_host" to private IP]
2026-04-15 01:00:11.898934 | rockylinux-9 | changed: 1 replacements made
2026-04-15 01:00:11.908031 | 
2026-04-15 01:00:11.908121 | TASK [Run Molecule scenario]
2026-04-15 01:00:11.932535 | [rockylinux-9] Waiting on logger
2026-04-15 01:00:20.073734 | [rockylinux-9] Waiting on logger
2026-04-15 01:00:30.245883 | [rockylinux-9] Waiting on logger
2026-04-15 01:00:40.490079 | [rockylinux-9] Waiting on logger
2026-04-15 01:00:49.643066 | [rockylinux-9] Waiting on logger
2026-04-15 01:00:59.813956 | [rockylinux-9] Waiting on logger
2026-04-15 01:01:05.426520 | rockylinux-9 | ERROR
2026-04-15 01:01:05.426803 | rockylinux-9 | {
2026-04-15 01:01:05.426873 | rockylinux-9 |   "delta": "0:00:52.015746",
2026-04-15 01:01:05.426925 | rockylinux-9 |   "end": "2026-04-15 01:01:04.148062",
2026-04-15 01:01:05.426968 | rockylinux-9 |   "msg": "non-zero return code",
2026-04-15 01:01:05.427010 | rockylinux-9 |   "rc": 2,
2026-04-15 01:01:05.427183 | rockylinux-9 |   "start": "2026-04-15 01:00:12.132316"
2026-04-15 01:01:05.427260 | rockylinux-9 | }
failure
2026-04-15 01:01:05.430681 | 
2026-04-15 01:01:05.430735 | PLAY RECAP
2026-04-15 01:01:05.430777 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-04-15 01:01:05.430798 | 
2026-04-15 01:01:05.524233 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-04-15 01:01:05.526600 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-15 01:01:06.135057 | 
2026-04-15 01:01:06.135235 | PLAY [all]
2026-04-15 01:01:06.146581 | 
2026-04-15 01:01:06.146665 | TASK [fetch-output : Set log path for multiple nodes]
2026-04-15 01:01:06.194573 | rockylinux-9 | skipping: Conditional result was False
2026-04-15 01:01:06.201826 | 
2026-04-15 01:01:06.201939 | TASK [fetch-output : Set log path for single node]
2026-04-15 01:01:06.244741 | rockylinux-9 | ok
2026-04-15 01:01:06.252083 | 
2026-04-15 01:01:06.252176 | LOOP [fetch-output : Ensure local output dirs]
2026-04-15 01:01:06.670059 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/93b24b281ad44a3a8920664f0683fb37/work/logs"
2026-04-15 01:01:06.870825 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/93b24b281ad44a3a8920664f0683fb37/work/artifacts"
2026-04-15 01:01:07.078636 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/93b24b281ad44a3a8920664f0683fb37/work/docs"
2026-04-15 01:01:07.093609 | 
2026-04-15 01:01:07.093793 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-04-15 01:01:07.790390 | rockylinux-9 | ok
2026-04-15 01:01:07.790640 | rockylinux-9 | changed: All items complete
2026-04-15 01:01:07.790679 | 
2026-04-15 01:01:08.323357 | rockylinux-9 | changed: .d..t...... ./
2026-04-15 01:01:08.841114 | rockylinux-9 | changed: .d..t...... ./
2026-04-15 01:01:08.862641 | 
2026-04-15 01:01:08.862810 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-04-15 01:01:09.286223 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.009209
2026-04-15 01:01:09.518071 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.007542
2026-04-15 01:01:09.537083 | 
2026-04-15 01:01:09.537279 | PLAY [all]
2026-04-15 01:01:09.544815 | 
2026-04-15 01:01:09.544910 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-04-15 01:01:10.052327 | rockylinux-9 | changed
2026-04-15 01:01:10.067018 | 
2026-04-15 01:01:10.072143 | PLAY RECAP
2026-04-15 01:01:10.072294 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-04-15 01:01:10.072349 | 
2026-04-15 01:01:10.180791 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-15 01:01:10.182111 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-04-15 01:01:10.718297 | 
2026-04-15 01:01:10.718408 | PLAY [localhost]
2026-04-15 01:01:10.730276 | 
2026-04-15 01:01:10.730348 | TASK [Generate Zuul manifest]
2026-04-15 01:01:10.752373 | localhost | ok
2026-04-15 01:01:10.767478 | 
2026-04-15 01:01:10.767555 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-04-15 01:01:11.099441 | localhost | changed
2026-04-15 01:01:11.112628 | 
2026-04-15 01:01:11.114184 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-04-15 01:01:11.147495 | localhost | ok
2026-04-15 01:01:11.159850 | 
2026-04-15 01:01:11.159942 | TASK [Upload logs]
2026-04-15 01:01:11.180855 | localhost | ok
2026-04-15 01:01:11.246055 | 
2026-04-15 01:01:11.246237 | TASK [Set zuul-log-path fact]
2026-04-15 01:01:11.270567 | localhost | ok
2026-04-15 01:01:11.284619 | 
2026-04-15 01:01:11.284692 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-15 01:01:11.327444 | localhost | ok
2026-04-15 01:01:11.347904 | 
2026-04-15 01:01:11.347980 | TASK [upload-logs : Create log directories]
2026-04-15 01:01:11.738373 | localhost | changed
2026-04-15 01:01:11.743471 | 
2026-04-15 01:01:11.743538 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-04-15 01:01:12.186389 | localhost -> localhost | ok: Runtime: 0:00:00.006479
2026-04-15 01:01:12.191515 | 
2026-04-15 01:01:12.191578 | TASK [upload-logs : Upload logs to log server]
2026-04-15 01:01:12.733318 | localhost | Output suppressed because no_log was given
2026-04-15 01:01:12.737215 | 
2026-04-15 01:01:12.737280 | LOOP [upload-logs : Compress console log and json output]
2026-04-15 01:01:12.781350 | localhost | skipping: Conditional result was False
2026-04-15 01:01:12.788266 | localhost | skipping: Conditional result was False
2026-04-15 01:01:12.799550 | 
2026-04-15 01:01:12.799666 | LOOP [upload-logs : Upload compressed console log and json output]
2026-04-15 01:01:12.838821 | localhost | skipping: Conditional result was False
2026-04-15 01:01:12.839183 | 
2026-04-15 01:01:12.843125 | localhost | skipping: Conditional result was False
2026-04-15 01:01:12.852428 | 
2026-04-15 01:01:12.852548 | LOOP [upload-logs : Upload console log and json output]