2026-04-14 00:58:28.204437 | Job console starting
2026-04-14 00:58:28.215741 | Updating git repos
2026-04-14 00:58:28.245075 | Cloning repos into workspace
2026-04-14 00:58:28.296240 | Restoring repo states
2026-04-14 00:58:28.306568 | Merging changes
2026-04-14 00:58:28.962201 | Checking out repos
2026-04-14 00:58:29.095230 | Preparing playbooks
2026-04-14 00:58:31.673146 | Running Ansible setup
2026-04-14 00:58:35.671700 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-14 00:58:36.328816 | 
2026-04-14 00:58:36.457895 | PLAY [localhost]
2026-04-14 00:58:36.478055 | 
2026-04-14 00:58:36.478197 | TASK [Gathering Facts]
2026-04-14 00:58:37.373363 | localhost | ok
2026-04-14 00:58:37.383383 | 
2026-04-14 00:58:37.383529 | TASK [Setup log path fact]
2026-04-14 00:58:37.413298 | localhost | ok
2026-04-14 00:58:37.425403 | 
2026-04-14 00:58:37.425499 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-14 00:58:37.467841 | localhost | ok
2026-04-14 00:58:37.479684 | 
2026-04-14 00:58:37.479766 | TASK [emit-job-header : Print job information]
2026-04-14 00:58:37.518773 | # Job Information
2026-04-14 00:58:37.518942 | Ansible Version: 2.16.16
2026-04-14 00:58:37.518975 | Job: ansible-collection-containers-molecule-nerdctl-rockylinux-9
2026-04-14 00:58:37.518998 | Pipeline: check
2026-04-14 00:58:37.519019 | Executor: 0a8996d2b663
2026-04-14 00:58:37.519040 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/109
2026-04-14 00:58:37.519063 | Event ID: eeec5e90-379c-11f1-893a-d9171dbe9bef
2026-04-14 00:58:37.522509 | 
2026-04-14 00:58:37.522581 | LOOP [emit-job-header : Print node information]
2026-04-14 00:58:37.618327 | localhost | ok:
2026-04-14 00:58:37.618560 | localhost | # Node Information
2026-04-14 00:58:37.618606 | localhost | Inventory Hostname: rockylinux-9
2026-04-14 00:58:37.618640 | localhost | Hostname: np0000165877
2026-04-14 00:58:37.618666 | localhost | Username: zuul
2026-04-14 00:58:37.618694 | localhost | Distro: Rocky 9.7
2026-04-14 00:58:37.618719 | localhost | Provider: yul1
2026-04-14 00:58:37.618742 | localhost | Region: ca-ymq-1
2026-04-14 00:58:37.618765 | localhost | Label: rockylinux-9
2026-04-14 00:58:37.618788 | localhost | Product Name: OpenStack Nova
2026-04-14 00:58:37.618811 | localhost | Interface IP: 199.204.45.3
2026-04-14 00:58:37.636248 | 
2026-04-14 00:58:37.636411 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-04-14 00:58:38.107236 | localhost -> localhost | changed
2026-04-14 00:58:38.113673 | 
2026-04-14 00:58:38.113743 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-04-14 00:58:39.072374 | localhost -> localhost | changed
2026-04-14 00:58:39.078946 | 
2026-04-14 00:58:39.079006 | PLAY [all]
2026-04-14 00:58:39.086475 | 
2026-04-14 00:58:39.086542 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-04-14 00:58:39.414317 | rockylinux-9 -> localhost | ok
2026-04-14 00:58:39.424091 | 
2026-04-14 00:58:39.424163 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-04-14 00:58:39.467374 | rockylinux-9 | ok
2026-04-14 00:58:39.485725 | rockylinux-9 | included: /var/lib/zuul/builds/fccf6a8cc6a449138e5e64ec1e8625a3/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-04-14 00:58:39.490943 | 
2026-04-14 00:58:39.491001 | TASK [add-build-sshkey : Create Temp SSH key]
2026-04-14 00:58:40.707578 | rockylinux-9 -> localhost | Generating public/private rsa key pair.
2026-04-14 00:58:40.707848 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/fccf6a8cc6a449138e5e64ec1e8625a3/work/fccf6a8cc6a449138e5e64ec1e8625a3_id_rsa
2026-04-14 00:58:40.707885 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/fccf6a8cc6a449138e5e64ec1e8625a3/work/fccf6a8cc6a449138e5e64ec1e8625a3_id_rsa.pub
2026-04-14 00:58:40.707908 | rockylinux-9 -> localhost | The key fingerprint is:
2026-04-14 00:58:40.707930 | rockylinux-9 -> localhost | SHA256:YKnett+YIeZ5+3gX7xybduCUByjwGtGP1VZEPl/rSDg zuul-build-sshkey
2026-04-14 00:58:40.707964 | rockylinux-9 -> localhost | The key's randomart image is:
2026-04-14 00:58:40.707986 | rockylinux-9 -> localhost | +---[RSA 3072]----+
2026-04-14 00:58:40.708011 | rockylinux-9 -> localhost | |         .   . ++|
2026-04-14 00:58:40.708033 | rockylinux-9 -> localhost | |       .o . . o. |
2026-04-14 00:58:40.708056 | rockylinux-9 -> localhost | |      +  + + o .o|
2026-04-14 00:58:40.708078 | rockylinux-9 -> localhost | |     o .. + + . =|
2026-04-14 00:58:40.708098 | rockylinux-9 -> localhost | |    .   So E . +.|
2026-04-14 00:58:40.708118 | rockylinux-9 -> localhost | |   . .  .   + * .|
2026-04-14 00:58:40.708137 | rockylinux-9 -> localhost | |    . = .    *.+ |
2026-04-14 00:58:40.708156 | rockylinux-9 -> localhost | |     + +.*. ..++.|
2026-04-14 00:58:40.708179 | rockylinux-9 -> localhost | |      +o*+o. o=. |
2026-04-14 00:58:40.708199 | rockylinux-9 -> localhost | +----[SHA256]-----+
2026-04-14 00:58:40.708247 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.571653
2026-04-14 00:58:40.720791 | 
2026-04-14 00:58:40.720899 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-04-14 00:58:40.760811 | rockylinux-9 | ok
2026-04-14 00:58:40.770496 | rockylinux-9 | included: /var/lib/zuul/builds/fccf6a8cc6a449138e5e64ec1e8625a3/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-04-14 00:58:40.779108 | 
2026-04-14 00:58:40.779257 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-04-14 00:58:40.804132 | rockylinux-9 | skipping: Conditional result was False
2026-04-14 00:58:40.814325 | 
2026-04-14 00:58:40.814435 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-04-14 00:58:41.328593 | rockylinux-9 | changed
2026-04-14 00:58:41.348654 | 
2026-04-14 00:58:41.348864 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-04-14 00:58:41.635642 | rockylinux-9 | ok
2026-04-14 00:58:41.702988 | 
2026-04-14 00:58:41.703194 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-04-14 00:58:42.664018 | rockylinux-9 | changed
2026-04-14 00:58:42.680321 | 
2026-04-14 00:58:42.680469 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-04-14 00:58:43.556536 | rockylinux-9 | changed
2026-04-14 00:58:43.561532 | 
2026-04-14 00:58:43.561591 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-04-14 00:58:43.585146 | rockylinux-9 | skipping: Conditional result was False
2026-04-14 00:58:43.590756 | 
2026-04-14 00:58:43.590827 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-04-14 00:58:44.047312 | rockylinux-9 -> localhost | changed
2026-04-14 00:58:44.063756 | 
2026-04-14 00:58:44.063860 | TASK [add-build-sshkey : Add back temp key]
2026-04-14 00:58:44.353117 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/fccf6a8cc6a449138e5e64ec1e8625a3/work/fccf6a8cc6a449138e5e64ec1e8625a3_id_rsa (zuul-build-sshkey)
2026-04-14 00:58:44.353348 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.015835
2026-04-14 00:58:44.361331 | 
2026-04-14 00:58:44.361414 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-04-14 00:58:44.778201 | rockylinux-9 | ok
2026-04-14 00:58:44.786003 | 
2026-04-14 00:58:44.786080 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-04-14 00:58:44.811562 | rockylinux-9 | skipping: Conditional result was False
2026-04-14 00:58:44.821045 | 
2026-04-14 00:58:44.821113 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-04-14 00:58:45.204202 | rockylinux-9 | ok
2026-04-14 00:58:45.209734 | 
2026-04-14 00:58:45.209798 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-04-14 00:58:46.139676 | rockylinux-9 | Output suppressed because no_log was given
2026-04-14 00:58:46.149533 | 
2026-04-14 00:58:46.149620 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-04-14 00:58:46.393553 | rockylinux-9 | ok: "logs"
2026-04-14 00:58:46.393822 | rockylinux-9 | ok: All items complete
2026-04-14 00:58:46.393851 | 
2026-04-14 00:58:46.614410 | rockylinux-9 | ok: "artifacts"
2026-04-14 00:58:46.825060 | rockylinux-9 | ok: "docs"
2026-04-14 00:58:46.844045 | 
2026-04-14 00:58:46.844189 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-04-14 00:58:47.095111 | rockylinux-9 | changed: "logs"
2026-04-14 00:58:47.306480 | rockylinux-9 | changed: "artifacts"
2026-04-14 00:58:47.526685 | rockylinux-9 | changed: "docs"
2026-04-14 00:58:47.536854 | 
2026-04-14 00:58:47.536990 | PLAY RECAP
2026-04-14 00:58:47.537040 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-14 00:58:47.537071 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-04-14 00:58:47.537092 | 
2026-04-14 00:58:47.681635 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-14 00:58:47.682929 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-14 00:58:48.306043 | 
2026-04-14 00:58:48.306191 | PLAY [all]
2026-04-14 00:58:48.326566 | 
2026-04-14 00:58:48.326818 | TASK [setup-uv : Extract archive]
2026-04-14 00:58:51.286435 | rockylinux-9 | changed
2026-04-14 00:58:51.294309 | 
2026-04-14 00:58:51.294407 | TASK [setup-uv : Print version]
2026-04-14 00:58:51.320758 | [rockylinux-9] Waiting on logger
2026-04-14 00:58:51.833786 | rockylinux-9 | ok: Runtime: 0:00:00.015686
2026-04-14 00:58:51.839283 | 
2026-04-14 00:58:51.839335 | PLAY RECAP
2026-04-14 00:58:51.839381 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-14 00:58:51.839408 | 
2026-04-14 00:58:51.954796 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-14 00:58:51.957128 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-04-14 00:58:52.510617 | 
2026-04-14 00:58:52.510742 | PLAY [all]
2026-04-14 00:58:52.520704 | 
2026-04-14 00:58:52.520775 | TASK [Copy inventory file for Zuul]
2026-04-14 00:58:53.865878 | rockylinux-9 | changed
2026-04-14 00:58:53.870692 | 
2026-04-14 00:58:53.870768 | TASK [Switch "ansible_host" to private IP]
2026-04-14 00:58:54.261557 | rockylinux-9 | changed: 1 replacements made
2026-04-14 00:58:54.269516 | 
2026-04-14 00:58:54.269592 | TASK [Run Molecule scenario]
2026-04-14 00:58:54.293349 | [rockylinux-9] Waiting on logger
2026-04-14 00:59:02.438161 | [rockylinux-9] Waiting on logger
2026-04-14 00:59:12.617981 | [rockylinux-9] Waiting on logger
2026-04-14 00:59:22.793819 | [rockylinux-9] Waiting on logger
2026-04-14 00:59:26.155988 | rockylinux-9 | ERROR
2026-04-14 00:59:26.156241 | rockylinux-9 | {
2026-04-14 00:59:26.156283 | rockylinux-9 |   "delta": "0:00:31.205980",
2026-04-14 00:59:26.156347 | rockylinux-9 |   "end": "2026-04-14 00:59:24.662094",
2026-04-14 00:59:26.156376 | rockylinux-9 |   "msg": "non-zero return code",
2026-04-14 00:59:26.156406 | rockylinux-9 |   "rc": 2,
2026-04-14 00:59:26.156439 | rockylinux-9 |   "start": "2026-04-14 00:58:53.456114"
2026-04-14 00:59:26.156465 | rockylinux-9 | }
failure
2026-04-14 00:59:26.160141 | 
2026-04-14 00:59:26.160205 | PLAY RECAP
2026-04-14 00:59:26.160263 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-04-14 00:59:26.160294 | 
2026-04-14 00:59:26.268463 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-04-14 00:59:26.271105 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-14 00:59:26.932664 | 
2026-04-14 00:59:26.932808 | PLAY [all]
2026-04-14 00:59:26.945546 | 
2026-04-14 00:59:26.945683 | TASK [fetch-output : Set log path for multiple nodes]
2026-04-14 00:59:26.991453 | rockylinux-9 | skipping: Conditional result was False
2026-04-14 00:59:26.997018 | 
2026-04-14 00:59:26.997111 | TASK [fetch-output : Set log path for single node]
2026-04-14 00:59:27.037158 | rockylinux-9 | ok
2026-04-14 00:59:27.043291 | 
2026-04-14 00:59:27.043369 | LOOP [fetch-output : Ensure local output dirs]
2026-04-14 00:59:27.454668 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/fccf6a8cc6a449138e5e64ec1e8625a3/work/logs"
2026-04-14 00:59:27.683605 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/fccf6a8cc6a449138e5e64ec1e8625a3/work/artifacts"
2026-04-14 00:59:27.892730 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/fccf6a8cc6a449138e5e64ec1e8625a3/work/docs"
2026-04-14 00:59:27.904401 | 
2026-04-14 00:59:27.904574 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-04-14 00:59:28.616665 | rockylinux-9 | changed: .d..t...... ./
2026-04-14 00:59:28.616921 | rockylinux-9 | changed: All items complete
2026-04-14 00:59:28.616974 | 
2026-04-14 00:59:29.134187 | rockylinux-9 | changed: .d..t...... ./
2026-04-14 00:59:29.698592 | rockylinux-9 | changed: .d..t...... ./
2026-04-14 00:59:29.719986 | 
2026-04-14 00:59:29.723086 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-04-14 00:59:30.163795 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.008084
2026-04-14 00:59:30.396008 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.009341
2026-04-14 00:59:30.413020 | 
2026-04-14 00:59:30.413167 | PLAY [all]
2026-04-14 00:59:30.419771 | 
2026-04-14 00:59:30.419839 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-04-14 00:59:30.895455 | rockylinux-9 | changed
2026-04-14 00:59:30.902289 | 
2026-04-14 00:59:30.902359 | PLAY RECAP
2026-04-14 00:59:30.902421 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-04-14 00:59:30.902453 | 
2026-04-14 00:59:31.018669 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-14 00:59:31.019869 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-04-14 00:59:31.616770 | 
2026-04-14 00:59:31.616912 | PLAY [localhost]
2026-04-14 00:59:31.627291 | 
2026-04-14 00:59:31.627363 | TASK [Generate Zuul manifest]
2026-04-14 00:59:31.649961 | localhost | ok
2026-04-14 00:59:31.666293 | 
2026-04-14 00:59:31.666369 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-04-14 00:59:31.988529 | localhost | changed
2026-04-14 00:59:31.999765 | 
2026-04-14 00:59:31.999842 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-04-14 00:59:32.041732 | localhost | ok
2026-04-14 00:59:32.048225 | 
2026-04-14 00:59:32.048293 | TASK [Upload logs]
2026-04-14 00:59:32.079542 | localhost | ok
2026-04-14 00:59:32.137960 | 
2026-04-14 00:59:32.138063 | TASK [Set zuul-log-path fact]
2026-04-14 00:59:32.157536 | localhost | ok
2026-04-14 00:59:32.171527 | 
2026-04-14 00:59:32.171592 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-14 00:59:32.203903 | localhost | ok
2026-04-14 00:59:32.214052 | 
2026-04-14 00:59:32.214116 | TASK [upload-logs : Create log directories]
2026-04-14 00:59:32.617134 | localhost | changed
2026-04-14 00:59:32.621921 | 
2026-04-14 00:59:32.621993 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-04-14 00:59:33.042272 | localhost -> localhost | ok: Runtime: 0:00:00.005085
2026-04-14 00:59:33.048044 | 
2026-04-14 00:59:33.048127 | TASK [upload-logs : Upload logs to log server]
2026-04-14 00:59:33.509045 | localhost | Output suppressed because no_log was given
2026-04-14 00:59:33.513980 | 
2026-04-14 00:59:33.514071 | LOOP [upload-logs : Compress console log and json output]
2026-04-14 00:59:33.580139 | localhost | skipping: Conditional result was False
2026-04-14 00:59:33.589731 | localhost | skipping: Conditional result was False
2026-04-14 00:59:33.599538 | 
2026-04-14 00:59:33.599716 | LOOP [upload-logs : Upload compressed console log and json output]
2026-04-14 00:59:33.649037 | localhost | skipping: Conditional result was False
2026-04-14 00:59:33.649479 | 
2026-04-14 00:59:33.653110 | localhost | skipping: Conditional result was False
2026-04-14 00:59:33.666664 | 
2026-04-14 00:59:33.667097 | LOOP [upload-logs : Upload console log and json output]