2026-04-21 00:58:50.033033 | Job console starting
2026-04-21 00:58:50.042610 | Updating git repos
2026-04-21 00:58:50.077436 | Cloning repos into workspace
2026-04-21 00:58:50.126502 | Restoring repo states
2026-04-21 00:58:50.133615 | Merging changes
2026-04-21 00:58:50.388474 | Checking out repos
2026-04-21 00:58:50.427602 | Preparing playbooks
2026-04-21 00:58:51.462038 | Running Ansible setup
2026-04-21 00:58:55.042091 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-21 00:58:55.608871 | 
2026-04-21 00:58:55.609020 | PLAY [localhost]
2026-04-21 00:58:55.616281 | 
2026-04-21 00:58:55.616349 | TASK [Gathering Facts]
2026-04-21 00:58:56.553082 | localhost | ok
2026-04-21 00:58:56.563975 | 
2026-04-21 00:58:56.564050 | TASK [Setup log path fact]
2026-04-21 00:58:56.581674 | localhost | ok
2026-04-21 00:58:56.592453 | 
2026-04-21 00:58:56.592514 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-21 00:58:56.618870 | localhost | ok
2026-04-21 00:58:56.624538 | 
2026-04-21 00:58:56.624601 | TASK [emit-job-header : Print job information]
2026-04-21 00:58:56.662221 | # Job Information
2026-04-21 00:58:56.662373 | Ansible Version: 2.16.16
2026-04-21 00:58:56.662403 | Job: ansible-collection-containers-molecule-containerd-rockylinux-9
2026-04-21 00:58:56.662425 | Pipeline: check
2026-04-21 00:58:56.662445 | Executor: 0a8996d2b663
2026-04-21 00:58:56.662464 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/117
2026-04-21 00:58:56.662487 | Event ID: 2be61180-3d1d-11f1-9516-aed3e94cde44
2026-04-21 00:58:56.665274 | 
2026-04-21 00:58:56.665338 | LOOP [emit-job-header : Print node information]
2026-04-21 00:58:56.754418 | localhost | ok:
2026-04-21 00:58:56.755204 | localhost | # Node Information
2026-04-21 00:58:56.755251 | localhost | Inventory Hostname: rockylinux-9
2026-04-21 00:58:56.755291 | localhost | Hostname: np0000168613
2026-04-21 00:58:56.755321 | localhost | Username: zuul
2026-04-21 00:58:56.755356 | localhost | Distro: Rocky 9.7
2026-04-21 00:58:56.755385 | localhost | Provider: yul1
2026-04-21 00:58:56.755412 | localhost | Region: ca-ymq-1
2026-04-21 00:58:56.755439 | localhost | Label: rockylinux-9
2026-04-21 00:58:56.755465 | localhost | Product Name: OpenStack Nova
2026-04-21 00:58:56.755492 | localhost | Interface IP: 199.204.45.248
2026-04-21 00:58:56.766047 | 
2026-04-21 00:58:56.766197 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-04-21 00:58:57.165794 | localhost -> localhost | changed
2026-04-21 00:58:57.170946 | 
2026-04-21 00:58:57.171018 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-04-21 00:58:58.096345 | localhost -> localhost | changed
2026-04-21 00:58:58.106850 | 
2026-04-21 00:58:58.106906 | PLAY [all]
2026-04-21 00:58:58.113854 | 
2026-04-21 00:58:58.113917 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-04-21 00:58:58.340788 | rockylinux-9 -> localhost | ok
2026-04-21 00:58:58.348299 | 
2026-04-21 00:58:58.348386 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-04-21 00:58:58.379147 | rockylinux-9 | ok
2026-04-21 00:58:58.399123 | rockylinux-9 | included: /var/lib/zuul/builds/1bfa3033627445a7a626437e320d3b53/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-04-21 00:58:58.406510 | 
2026-04-21 00:58:58.406593 | TASK [add-build-sshkey : Create Temp SSH key]
2026-04-21 00:58:59.539101 | rockylinux-9 -> localhost | Generating public/private rsa key pair.
2026-04-21 00:58:59.539271 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/1bfa3033627445a7a626437e320d3b53/work/1bfa3033627445a7a626437e320d3b53_id_rsa
2026-04-21 00:58:59.539303 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/1bfa3033627445a7a626437e320d3b53/work/1bfa3033627445a7a626437e320d3b53_id_rsa.pub
2026-04-21 00:58:59.539326 | rockylinux-9 -> localhost | The key fingerprint is:
2026-04-21 00:58:59.539348 | rockylinux-9 -> localhost | SHA256:VU3DihdWx1ZgAqHYaxNA12y6JEBBHaCwxikym+VQnpQ zuul-build-sshkey
2026-04-21 00:58:59.539380 | rockylinux-9 -> localhost | The key's randomart image is:
2026-04-21 00:58:59.539401 | rockylinux-9 -> localhost | +---[RSA 3072]----+
2026-04-21 00:58:59.539427 | rockylinux-9 -> localhost | |. o+==+..oooo=*o+|
2026-04-21 00:58:59.539448 | rockylinux-9 -> localhost | |.*Eo. .= .+.ooooo|
2026-04-21 00:58:59.539469 | rockylinux-9 -> localhost | |*+=  .. +o.o o . |
2026-04-21 00:58:59.539489 | rockylinux-9 -> localhost | |+B    . o+. o    |
2026-04-21 00:58:59.539509 | rockylinux-9 -> localhost | |o .    oS. .     |
2026-04-21 00:58:59.539529 | rockylinux-9 -> localhost | |       ...       |
2026-04-21 00:58:59.539549 | rockylinux-9 -> localhost | |                 |
2026-04-21 00:58:59.539568 | rockylinux-9 -> localhost | |                 |
2026-04-21 00:58:59.539591 | rockylinux-9 -> localhost | |                 |
2026-04-21 00:58:59.539611 | rockylinux-9 -> localhost | +----[SHA256]-----+
2026-04-21 00:58:59.539663 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.685593
2026-04-21 00:58:59.544355 | 
2026-04-21 00:58:59.544419 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-04-21 00:58:59.576668 | rockylinux-9 | ok
2026-04-21 00:58:59.585901 | rockylinux-9 | included: /var/lib/zuul/builds/1bfa3033627445a7a626437e320d3b53/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-04-21 00:58:59.593667 | 
2026-04-21 00:58:59.593731 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-04-21 00:58:59.618051 | rockylinux-9 | skipping: Conditional result was False
2026-04-21 00:58:59.626328 | 
2026-04-21 00:58:59.626420 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-04-21 00:59:00.079033 | rockylinux-9 | changed
2026-04-21 00:59:00.085828 | 
2026-04-21 00:59:00.085921 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-04-21 00:59:00.333461 | rockylinux-9 | ok
2026-04-21 00:59:00.340489 | 
2026-04-21 00:59:00.340588 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-04-21 00:59:01.267247 | rockylinux-9 | changed
2026-04-21 00:59:01.274623 | 
2026-04-21 00:59:01.274709 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-04-21 00:59:02.176371 | rockylinux-9 | changed
2026-04-21 00:59:02.182689 | 
2026-04-21 00:59:02.182758 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-04-21 00:59:02.207446 | rockylinux-9 | skipping: Conditional result was False
2026-04-21 00:59:02.216087 | 
2026-04-21 00:59:02.216363 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-04-21 00:59:02.579364 | rockylinux-9 -> localhost | changed
2026-04-21 00:59:02.595967 | 
2026-04-21 00:59:02.596046 | TASK [add-build-sshkey : Add back temp key]
2026-04-21 00:59:02.877787 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/1bfa3033627445a7a626437e320d3b53/work/1bfa3033627445a7a626437e320d3b53_id_rsa (zuul-build-sshkey)
2026-04-21 00:59:02.878033 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.015070
2026-04-21 00:59:02.884827 | 
2026-04-21 00:59:02.884897 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-04-21 00:59:03.298730 | rockylinux-9 | ok
2026-04-21 00:59:03.303587 | 
2026-04-21 00:59:03.303654 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-04-21 00:59:03.328835 | rockylinux-9 | skipping: Conditional result was False
2026-04-21 00:59:03.345403 | 
2026-04-21 00:59:03.345491 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-04-21 00:59:03.747043 | rockylinux-9 | ok
2026-04-21 00:59:03.760039 | 
2026-04-21 00:59:03.760112 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-04-21 00:59:04.776486 | rockylinux-9 | Output suppressed because no_log was given
2026-04-21 00:59:04.784895 | 
2026-04-21 00:59:04.793183 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-04-21 00:59:05.042077 | rockylinux-9 | ok: "logs"
2026-04-21 00:59:05.042314 | rockylinux-9 | ok: All items complete
2026-04-21 00:59:05.042343 | 
2026-04-21 00:59:05.265265 | rockylinux-9 | ok: "artifacts"
2026-04-21 00:59:05.501267 | rockylinux-9 | ok: "docs"
2026-04-21 00:59:05.519251 | 
2026-04-21 00:59:05.519342 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-04-21 00:59:05.770855 | rockylinux-9 | changed: "logs"
2026-04-21 00:59:05.995734 | rockylinux-9 | changed: "artifacts"
2026-04-21 00:59:06.232373 | rockylinux-9 | changed: "docs"
2026-04-21 00:59:06.245518 | 
2026-04-21 00:59:06.245667 | PLAY RECAP
2026-04-21 00:59:06.245730 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-21 00:59:06.245771 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-04-21 00:59:06.245802 | 
2026-04-21 00:59:06.379204 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-21 00:59:06.381244 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-21 00:59:06.965910 | 
2026-04-21 00:59:06.966081 | PLAY [all]
2026-04-21 00:59:06.976865 | 
2026-04-21 00:59:06.976947 | TASK [setup-uv : Extract archive]
2026-04-21 00:59:09.816923 | rockylinux-9 | changed
2026-04-21 00:59:09.827352 | 
2026-04-21 00:59:09.827630 | TASK [setup-uv : Print version]
2026-04-21 00:59:09.852908 | [rockylinux-9] Waiting on logger
2026-04-21 00:59:10.368684 | rockylinux-9 | ok: Runtime: 0:00:00.014514
2026-04-21 00:59:10.374324 | 
2026-04-21 00:59:10.374369 | PLAY RECAP
2026-04-21 00:59:10.374415 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-21 00:59:10.374438 | 
2026-04-21 00:59:10.483357 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-21 00:59:10.485730 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-04-21 00:59:11.060066 | 
2026-04-21 00:59:11.060199 | PLAY [all]
2026-04-21 00:59:11.070460 | 
2026-04-21 00:59:11.070554 | TASK [Copy inventory file for Zuul]
2026-04-21 00:59:12.435633 | rockylinux-9 | changed
2026-04-21 00:59:12.440440 | 
2026-04-21 00:59:12.440517 | TASK [Switch "ansible_host" to private IP]
2026-04-21 00:59:12.777294 | rockylinux-9 | changed: 1 replacements made
2026-04-21 00:59:12.785535 | 
2026-04-21 00:59:12.785627 | TASK [Run Molecule scenario]
2026-04-21 00:59:12.811070 | [rockylinux-9] Waiting on logger
2026-04-21 00:59:20.939152 | [rockylinux-9] Waiting on logger
2026-04-21 00:59:31.110092 | [rockylinux-9] Waiting on logger
2026-04-21 00:59:41.285957 | [rockylinux-9] Waiting on logger
2026-04-21 00:59:48.108943 | rockylinux-9 | ERROR
2026-04-21 00:59:48.109648 | rockylinux-9 | {
2026-04-21 00:59:48.109697 | rockylinux-9 |   "delta": "0:00:34.082281",
2026-04-21 00:59:48.109728 | rockylinux-9 |   "end": "2026-04-21 00:59:46.996231",
2026-04-21 00:59:48.109756 | rockylinux-9 |   "msg": "non-zero return code",
2026-04-21 00:59:48.109795 | rockylinux-9 |   "rc": 2,
2026-04-21 00:59:48.109833 | rockylinux-9 |   "start": "2026-04-21 00:59:12.913950"
2026-04-21 00:59:48.109862 | rockylinux-9 | }
failure
2026-04-21 00:59:48.113400 | 
2026-04-21 00:59:48.113475 | PLAY RECAP
2026-04-21 00:59:48.113533 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-04-21 00:59:48.113565 | 
2026-04-21 00:59:48.229619 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-04-21 00:59:48.231970 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-21 00:59:48.788484 | 
2026-04-21 00:59:48.789050 | PLAY [all]
2026-04-21 00:59:48.799700 | 
2026-04-21 00:59:48.799772 | TASK [fetch-output : Set log path for multiple nodes]
2026-04-21 00:59:48.845129 | rockylinux-9 | skipping: Conditional result was False
2026-04-21 00:59:48.852360 | 
2026-04-21 00:59:48.852464 | TASK [fetch-output : Set log path for single node]
2026-04-21 00:59:48.896255 | rockylinux-9 | ok
2026-04-21 00:59:48.903462 | 
2026-04-21 00:59:48.903532 | LOOP [fetch-output : Ensure local output dirs]
2026-04-21 00:59:49.282811 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/1bfa3033627445a7a626437e320d3b53/work/logs"
2026-04-21 00:59:49.487179 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/1bfa3033627445a7a626437e320d3b53/work/artifacts"
2026-04-21 00:59:49.693443 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/1bfa3033627445a7a626437e320d3b53/work/docs"
2026-04-21 00:59:49.711310 | 
2026-04-21 00:59:49.711448 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-04-21 00:59:50.406604 | rockylinux-9 | changed: .d..t...... ./
2026-04-21 00:59:50.406828 | rockylinux-9 | changed: All items complete
2026-04-21 00:59:50.406858 | 
2026-04-21 00:59:50.929363 | rockylinux-9 | changed: .d..t...... ./
2026-04-21 00:59:51.444487 | rockylinux-9 | changed: .d..t...... ./
2026-04-21 00:59:51.470844 | 
2026-04-21 00:59:51.471013 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-04-21 00:59:51.886341 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.006065
2026-04-21 00:59:52.116286 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.005723
2026-04-21 00:59:52.129828 | 
2026-04-21 00:59:52.129987 | PLAY [all]
2026-04-21 00:59:52.136346 | 
2026-04-21 00:59:52.136445 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-04-21 00:59:52.613145 | rockylinux-9 | changed
2026-04-21 00:59:52.618513 | 
2026-04-21 00:59:52.618588 | PLAY RECAP
2026-04-21 00:59:52.618642 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-04-21 00:59:52.618668 | 
2026-04-21 00:59:52.762506 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-21 00:59:52.764588 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-04-21 00:59:53.349464 | 
2026-04-21 00:59:53.350094 | PLAY [localhost]
2026-04-21 00:59:53.360176 | 
2026-04-21 00:59:53.360251 | TASK [Generate Zuul manifest]
2026-04-21 00:59:53.393049 | localhost | ok
2026-04-21 00:59:53.416433 | 
2026-04-21 00:59:53.416657 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-04-21 00:59:53.750113 | localhost | changed
2026-04-21 00:59:53.767548 | 
2026-04-21 00:59:53.767759 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-04-21 00:59:54.055134 | localhost | ok
2026-04-21 00:59:54.062742 | 
2026-04-21 00:59:54.062815 | TASK [Upload logs]
2026-04-21 00:59:54.087060 | localhost | ok
2026-04-21 00:59:54.153846 | 
2026-04-21 00:59:54.154010 | TASK [Set zuul-log-path fact]
2026-04-21 00:59:54.173300 | localhost | ok
2026-04-21 00:59:54.186505 | 
2026-04-21 00:59:54.186575 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-21 00:59:54.218660 | localhost | ok
2026-04-21 00:59:54.227664 | 
2026-04-21 00:59:54.227739 | TASK [upload-logs : Create log directories]
2026-04-21 00:59:54.664216 | localhost | changed
2026-04-21 00:59:54.668939 | 
2026-04-21 00:59:54.669107 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-04-21 00:59:55.148266 | localhost -> localhost | ok: Runtime: 0:00:00.006353
2026-04-21 00:59:55.154060 | 
2026-04-21 00:59:55.154140 | TASK [upload-logs : Upload logs to log server]
2026-04-21 00:59:55.626768 | localhost | Output suppressed because no_log was given
2026-04-21 00:59:55.632257 | 
2026-04-21 00:59:55.632356 | LOOP [upload-logs : Compress console log and json output]
2026-04-21 00:59:55.681072 | localhost | skipping: Conditional result was False
2026-04-21 00:59:55.688636 | localhost | skipping: Conditional result was False
2026-04-21 00:59:55.703897 | 
2026-04-21 00:59:55.704067 | LOOP [upload-logs : Upload compressed console log and json output]
2026-04-21 00:59:55.746568 | localhost | skipping: Conditional result was False
2026-04-21 00:59:55.746871 | 
2026-04-21 00:59:55.751090 | localhost | skipping: Conditional result was False
2026-04-21 00:59:55.767690 | 
2026-04-21 00:59:55.767854 | LOOP [upload-logs : Upload console log and json output]