2026-03-16 00:55:43.348655 | Job console starting
2026-03-16 00:55:43.394826 | Updating git repos
2026-03-16 00:55:43.447443 | Cloning repos into workspace
2026-03-16 00:55:43.704709 | Restoring repo states
2026-03-16 00:55:43.710881 | Merging changes
2026-03-16 00:55:44.028725 | Checking out repos
2026-03-16 00:55:44.091163 | Preparing playbooks
2026-03-16 00:55:45.336341 | Running Ansible setup
2026-03-16 00:55:49.121567 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-03-16 00:55:49.736621 | 
2026-03-16 00:55:49.736765 | PLAY [localhost]
2026-03-16 00:55:49.746713 | 
2026-03-16 00:55:49.746785 | TASK [Gathering Facts]
2026-03-16 00:55:50.590007 | localhost | ok
2026-03-16 00:55:50.600839 | 
2026-03-16 00:55:50.601008 | TASK [Setup log path fact]
2026-03-16 00:55:50.622614 | localhost | ok
2026-03-16 00:55:50.636809 | 
2026-03-16 00:55:50.636936 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-03-16 00:55:50.668945 | localhost | ok
2026-03-16 00:55:50.676361 | 
2026-03-16 00:55:50.676460 | TASK [emit-job-header : Print job information]
2026-03-16 00:55:50.720771 | # Job Information
2026-03-16 00:55:50.721004 | Ansible Version: 2.16.16
2026-03-16 00:55:50.721055 | Job: ansible-collection-containers-molecule-docker-rockylinux-9
2026-03-16 00:55:50.721089 | Pipeline: check
2026-03-16 00:55:50.721119 | Executor: 0a8996d2b663
2026-03-16 00:55:50.721148 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/101
2026-03-16 00:55:50.721181 | Event ID: 77b9c3c0-20d2-11f1-8fa9-da7cf51ce81d
2026-03-16 00:55:50.725230 | 
2026-03-16 00:55:50.725326 | LOOP [emit-job-header : Print node information]
2026-03-16 00:55:50.821102 | localhost | ok:
2026-03-16 00:55:50.962616 | localhost | # Node Information
2026-03-16 00:55:50.962788 | localhost | Inventory Hostname: rockylinux-9
2026-03-16 00:55:50.962846 | localhost | Hostname: np0000158888
2026-03-16 00:55:50.962895 | localhost | Username: zuul
2026-03-16 00:55:50.962947 | localhost | Distro: Rocky 9.7
2026-03-16 00:55:50.962992 | localhost | Provider: yul1
2026-03-16 00:55:50.963034 | localhost | Region: ca-ymq-1
2026-03-16 00:55:50.963075 | localhost | Label: rockylinux-9
2026-03-16 00:55:50.963115 | localhost | Product Name: OpenStack Nova
2026-03-16 00:55:50.963155 | localhost | Interface IP: 162.253.55.204
2026-03-16 00:55:50.980740 | 
2026-03-16 00:55:50.980932 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-03-16 00:55:51.427299 | localhost -> localhost | changed
2026-03-16 00:55:51.432827 | 
2026-03-16 00:55:51.432907 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-03-16 00:55:52.393068 | localhost -> localhost | changed
2026-03-16 00:55:52.408154 | 
2026-03-16 00:55:52.408234 | PLAY [all]
2026-03-16 00:55:52.426334 | 
2026-03-16 00:55:52.426588 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-03-16 00:55:52.656042 | rockylinux-9 -> localhost | ok
2026-03-16 00:55:52.666826 | 
2026-03-16 00:55:52.666930 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-03-16 00:55:52.698915 | rockylinux-9 | ok
2026-03-16 00:55:52.714823 | rockylinux-9 | included: /var/lib/zuul/builds/aa24a9c603a74cd9a21e8b96d6850b42/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-03-16 00:55:52.719700 | 
2026-03-16 00:55:52.719758 | TASK [add-build-sshkey : Create Temp SSH key]
2026-03-16 00:55:53.626886 | rockylinux-9 -> localhost | Generating public/private rsa key pair.
2026-03-16 00:55:53.627083 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/aa24a9c603a74cd9a21e8b96d6850b42/work/aa24a9c603a74cd9a21e8b96d6850b42_id_rsa
2026-03-16 00:55:53.627115 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/aa24a9c603a74cd9a21e8b96d6850b42/work/aa24a9c603a74cd9a21e8b96d6850b42_id_rsa.pub
2026-03-16 00:55:53.627139 | rockylinux-9 -> localhost | The key fingerprint is:
2026-03-16 00:55:53.627161 | rockylinux-9 -> localhost | SHA256:QpM6Tq/SrV0rBAkXGMxCWEvaG2hDHn/OF4EnRwWIOvQ zuul-build-sshkey
2026-03-16 00:55:53.627192 | rockylinux-9 -> localhost | The key's randomart image is:
2026-03-16 00:55:53.627214 | rockylinux-9 -> localhost | +---[RSA 3072]----+
2026-03-16 00:55:53.627240 | rockylinux-9 -> localhost | |oB+oo.++o.       |
2026-03-16 00:55:53.627262 | rockylinux-9 -> localhost | |=*Bo.+ +.        |
2026-03-16 00:55:53.627281 | rockylinux-9 -> localhost | |+=*+ oB.         |
2026-03-16 00:55:53.627301 | rockylinux-9 -> localhost | |.o.E*o ..        |
2026-03-16 00:55:53.627320 | rockylinux-9 -> localhost | |  o ++..S        |
2026-03-16 00:55:53.627339 | rockylinux-9 -> localhost | |   o oo.         |
2026-03-16 00:55:53.627358 | rockylinux-9 -> localhost | |   ..o. .        |
2026-03-16 00:55:53.627377 | rockylinux-9 -> localhost | |  . .oo. .       |
2026-03-16 00:55:53.627398 | rockylinux-9 -> localhost | |   .o....        |
2026-03-16 00:55:53.627419 | rockylinux-9 -> localhost | +----[SHA256]-----+
2026-03-16 00:55:53.627467 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.504817
2026-03-16 00:55:53.632120 | 
2026-03-16 00:55:53.632181 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-03-16 00:55:53.666070 | rockylinux-9 | ok
2026-03-16 00:55:53.676648 | rockylinux-9 | included: /var/lib/zuul/builds/aa24a9c603a74cd9a21e8b96d6850b42/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-03-16 00:55:53.684657 | 
2026-03-16 00:55:53.684721 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-03-16 00:55:53.710317 | rockylinux-9 | skipping: Conditional result was False
2026-03-16 00:55:53.718558 | 
2026-03-16 00:55:53.718655 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-03-16 00:55:54.198201 | rockylinux-9 | changed
2026-03-16 00:55:54.203087 | 
2026-03-16 00:55:54.203155 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-03-16 00:55:54.445269 | rockylinux-9 | ok
2026-03-16 00:55:54.450066 | 
2026-03-16 00:55:54.450132 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-03-16 00:55:55.371357 | rockylinux-9 | changed
2026-03-16 00:55:55.413307 | 
2026-03-16 00:55:55.413448 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-03-16 00:55:56.347565 | rockylinux-9 | changed
2026-03-16 00:55:56.352669 | 
2026-03-16 00:55:56.352738 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-03-16 00:55:56.378919 | rockylinux-9 | skipping: Conditional result was False
2026-03-16 00:55:56.390202 | 
2026-03-16 00:55:56.390380 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-03-16 00:55:56.827882 | rockylinux-9 -> localhost | changed
2026-03-16 00:55:56.841903 | 
2026-03-16 00:55:56.841997 | TASK [add-build-sshkey : Add back temp key]
2026-03-16 00:55:57.168016 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/aa24a9c603a74cd9a21e8b96d6850b42/work/aa24a9c603a74cd9a21e8b96d6850b42_id_rsa (zuul-build-sshkey)
2026-03-16 00:55:57.168228 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.016774
2026-03-16 00:55:57.174880 | 
2026-03-16 00:55:57.174956 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-03-16 00:55:57.575272 | rockylinux-9 | ok
2026-03-16 00:55:57.580887 | 
2026-03-16 00:55:57.580998 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-03-16 00:55:57.616513 | rockylinux-9 | skipping: Conditional result was False
2026-03-16 00:55:57.631827 | 
2026-03-16 00:55:57.631984 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-03-16 00:55:58.033645 | rockylinux-9 | ok
2026-03-16 00:55:58.039686 | 
2026-03-16 00:55:58.039751 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-03-16 00:55:58.962153 | rockylinux-9 | Output suppressed because no_log was given
2026-03-16 00:55:58.972095 | 
2026-03-16 00:55:58.972164 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-03-16 00:55:59.225963 | rockylinux-9 | ok: "logs"
2026-03-16 00:55:59.226334 | rockylinux-9 | ok: All items complete
2026-03-16 00:55:59.226372 | 
2026-03-16 00:55:59.445012 | rockylinux-9 | ok: "artifacts"
2026-03-16 00:55:59.665347 | rockylinux-9 | ok: "docs"
2026-03-16 00:55:59.680576 | 
2026-03-16 00:55:59.680732 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-03-16 00:55:59.931886 | rockylinux-9 | changed: "logs"
2026-03-16 00:56:00.157521 | rockylinux-9 | changed: "artifacts"
2026-03-16 00:56:00.379807 | rockylinux-9 | changed: "docs"
2026-03-16 00:56:00.392267 | 
2026-03-16 00:56:00.392399 | PLAY RECAP
2026-03-16 00:56:00.392445 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-03-16 00:56:00.392475 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-03-16 00:56:00.392495 | 
2026-03-16 00:56:00.520785 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-03-16 00:56:00.523146 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-03-16 00:56:01.066981 | 
2026-03-16 00:56:01.067094 | PLAY [all]
2026-03-16 00:56:01.078138 | 
2026-03-16 00:56:01.078217 | TASK [setup-uv : Extract archive]
2026-03-16 00:56:03.930834 | rockylinux-9 | changed
2026-03-16 00:56:03.937644 | 
2026-03-16 00:56:03.937761 | TASK [setup-uv : Print version]
2026-03-16 00:56:03.965996 | [rockylinux-9] Waiting on logger
2026-03-16 00:56:04.482881 | rockylinux-9 | ok: Runtime: 0:00:00.014922
2026-03-16 00:56:04.489129 | 
2026-03-16 00:56:04.489181 | PLAY RECAP
2026-03-16 00:56:04.489232 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-03-16 00:56:04.489256 | 
2026-03-16 00:56:04.604320 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-03-16 00:56:04.606665 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-03-16 00:56:05.164995 | 
2026-03-16 00:56:05.165130 | PLAY [all]
2026-03-16 00:56:05.175800 | 
2026-03-16 00:56:05.175873 | TASK [Copy inventory file for Zuul]
2026-03-16 00:56:06.484757 | rockylinux-9 | changed
2026-03-16 00:56:06.491832 | 
2026-03-16 00:56:06.491939 | TASK [Switch "ansible_host" to private IP]
2026-03-16 00:56:06.831320 | rockylinux-9 | changed: 1 replacements made
2026-03-16 00:56:06.837228 | 
2026-03-16 00:56:06.837308 | TASK [Run Molecule scenario]
2026-03-16 00:56:06.859529 | [rockylinux-9] Waiting on logger
2026-03-16 00:56:14.986817 | [rockylinux-9] Waiting on logger
2026-03-16 00:56:23.950976 | rockylinux-9 | ERROR
2026-03-16 00:56:23.951277 | rockylinux-9 | {
2026-03-16 00:56:23.951347 | rockylinux-9 |   "delta": "0:00:16.163225",
2026-03-16 00:56:23.951393 | rockylinux-9 |   "end": "2026-03-16 00:56:23.725821",
2026-03-16 00:56:23.951436 | rockylinux-9 |   "msg": "non-zero return code",
2026-03-16 00:56:23.951477 | rockylinux-9 |   "rc": 4,
2026-03-16 00:56:23.951522 | rockylinux-9 |   "start": "2026-03-16 00:56:07.562596"
2026-03-16 00:56:23.951568 | rockylinux-9 | }
failure
2026-03-16 00:56:23.954447 | 
2026-03-16 00:56:23.954512 | PLAY RECAP
2026-03-16 00:56:23.954563 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-03-16 00:56:23.954606 | 
2026-03-16 00:56:24.406532 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-03-16 00:56:24.407700 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-03-16 00:56:24.982595 | 
2026-03-16 00:56:24.982762 | PLAY [all]
2026-03-16 00:56:24.993746 | 
2026-03-16 00:56:24.993823 | TASK [fetch-output : Set log path for multiple nodes]
2026-03-16 00:56:25.040948 | rockylinux-9 | skipping: Conditional result was False
2026-03-16 00:56:25.049570 | 
2026-03-16 00:56:25.049681 | TASK [fetch-output : Set log path for single node]
2026-03-16 00:56:25.085760 | rockylinux-9 | ok
2026-03-16 00:56:25.091593 | 
2026-03-16 00:56:25.091680 | LOOP [fetch-output : Ensure local output dirs]
2026-03-16 00:56:25.488238 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/aa24a9c603a74cd9a21e8b96d6850b42/work/logs"
2026-03-16 00:56:25.710175 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/aa24a9c603a74cd9a21e8b96d6850b42/work/artifacts"
2026-03-16 00:56:25.940254 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/aa24a9c603a74cd9a21e8b96d6850b42/work/docs"
2026-03-16 00:56:25.961407 | 
2026-03-16 00:56:25.961592 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-03-16 00:56:26.667152 | rockylinux-9 | ok
2026-03-16 00:56:26.667422 | rockylinux-9 | changed: All items complete
2026-03-16 00:56:26.667462 | 
2026-03-16 00:56:27.192955 | rockylinux-9 | changed: .d..t...... ./
2026-03-16 00:56:27.729560 | rockylinux-9 | changed: .d..t...... ./
2026-03-16 00:56:27.748089 | 
2026-03-16 00:56:27.748204 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-03-16 00:56:28.160757 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.008913
2026-03-16 00:56:28.373219 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.008936
2026-03-16 00:56:28.389159 | 
2026-03-16 00:56:28.389308 | PLAY [all]
2026-03-16 00:56:28.394975 | 
2026-03-16 00:56:28.395051 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-03-16 00:56:28.869345 | rockylinux-9 | changed
2026-03-16 00:56:28.876132 | 
2026-03-16 00:56:28.876182 | PLAY RECAP
2026-03-16 00:56:28.876228 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-03-16 00:56:28.876249 | 
2026-03-16 00:56:29.129085 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-03-16 00:56:29.130633 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-03-16 00:56:29.709688 | 
2026-03-16 00:56:29.709843 | PLAY [localhost]
2026-03-16 00:56:29.719912 | 
2026-03-16 00:56:29.719988 | TASK [Generate Zuul manifest]
2026-03-16 00:56:29.740572 | localhost | ok
2026-03-16 00:56:29.755267 | 
2026-03-16 00:56:29.755344 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-03-16 00:56:30.079113 | localhost | changed
2026-03-16 00:56:30.092361 | 
2026-03-16 00:56:30.092448 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-03-16 00:56:30.133767 | localhost | ok
2026-03-16 00:56:30.140277 | 
2026-03-16 00:56:30.140341 | TASK [Upload logs]
2026-03-16 00:56:30.159472 | localhost | ok
2026-03-16 00:56:30.361562 | 
2026-03-16 00:56:30.361718 | TASK [Set zuul-log-path fact]
2026-03-16 00:56:30.383789 | localhost | ok
2026-03-16 00:56:30.397369 | 
2026-03-16 00:56:30.397437 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-03-16 00:56:30.428515 | localhost | ok
2026-03-16 00:56:30.438553 | 
2026-03-16 00:56:30.438633 | TASK [upload-logs : Create log directories]
2026-03-16 00:56:31.020192 | localhost | changed
2026-03-16 00:56:31.027154 | 
2026-03-16 00:56:31.027251 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-03-16 00:56:31.493060 | localhost -> localhost | ok: Runtime: 0:00:00.005747
2026-03-16 00:56:31.498726 | 
2026-03-16 00:56:31.498831 | TASK [upload-logs : Upload logs to log server]
2026-03-16 00:56:31.964929 | localhost | Output suppressed because no_log was given
2026-03-16 00:56:31.970303 | 
2026-03-16 00:56:31.970374 | LOOP [upload-logs : Compress console log and json output]
2026-03-16 00:56:32.016722 | localhost | skipping: Conditional result was False
2026-03-16 00:56:32.023589 | localhost | skipping: Conditional result was False
2026-03-16 00:56:32.036114 | 
2026-03-16 00:56:32.036296 | LOOP [upload-logs : Upload compressed console log and json output]
2026-03-16 00:56:32.076594 | localhost | skipping: Conditional result was False
2026-03-16 00:56:32.077061 | 
2026-03-16 00:56:32.080781 | localhost | skipping: Conditional result was False
2026-03-16 00:56:32.090938 | 
2026-03-16 00:56:32.091172 | LOOP [upload-logs : Upload console log and json output]