2026-04-17 00:59:02.131220 | Job console starting
2026-04-17 00:59:02.145839 | Updating git repos
2026-04-17 00:59:02.176002 | Cloning repos into workspace
2026-04-17 00:59:06.617305 | Restoring repo states
2026-04-17 00:59:06.626483 | Merging changes
2026-04-17 00:59:08.890453 | Checking out repos
2026-04-17 00:59:10.669278 | Preparing playbooks
2026-04-17 00:59:51.906565 | Running Ansible setup
2026-04-17 00:59:55.777231 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-17 00:59:56.372777 | 
2026-04-17 00:59:56.372922 | PLAY [localhost]
2026-04-17 00:59:56.380910 | 
2026-04-17 00:59:56.381005 | TASK [Gathering Facts]
2026-04-17 00:59:57.413272 | localhost | ok
2026-04-17 00:59:57.420569 | 
2026-04-17 00:59:57.420650 | TASK [Setup log path fact]
2026-04-17 00:59:57.442043 | localhost | ok
2026-04-17 00:59:57.452736 | 
2026-04-17 00:59:57.452832 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-17 00:59:57.482150 | localhost | ok
2026-04-17 00:59:57.491035 | 
2026-04-17 00:59:57.491114 | TASK [emit-job-header : Print job information]
2026-04-17 00:59:57.530464 | # Job Information
2026-04-17 00:59:57.530676 | Ansible Version: 2.16.16
2026-04-17 00:59:57.530707 | Job: ansible-collection-containers-molecule-docker-rockylinux-9
2026-04-17 00:59:57.530730 | Pipeline: check
2026-04-17 00:59:57.530751 | Executor: 0a8996d2b663
2026-04-17 00:59:57.530772 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/109
2026-04-17 00:59:57.530794 | Event ID: 64e04cd0-39f8-11f1-9088-4230c452b1b4
2026-04-17 00:59:57.533784 | 
2026-04-17 00:59:57.533874 | LOOP [emit-job-header : Print node information]
2026-04-17 00:59:57.624287 | localhost | ok:
2026-04-17 00:59:57.624809 | localhost | # Node Information
2026-04-17 00:59:57.624853 | localhost | Inventory Hostname: rockylinux-9
2026-04-17 00:59:57.624884 | localhost | Hostname: np0000166925
2026-04-17 00:59:57.624912 | localhost | Username: zuul
2026-04-17 00:59:57.624943 | localhost | Distro: Rocky 9.7
2026-04-17 00:59:57.625013 | localhost | Provider: yul1
2026-04-17 00:59:57.625042 | localhost | Region: ca-ymq-1
2026-04-17 00:59:57.625068 | localhost | Label: rockylinux-9
2026-04-17 00:59:57.625094 | localhost | Product Name: OpenStack Nova
2026-04-17 00:59:57.625121 | localhost | Interface IP: 199.204.45.138
2026-04-17 00:59:57.638710 | 
2026-04-17 00:59:57.638893 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-04-17 00:59:58.029310 | localhost -> localhost | changed
2026-04-17 00:59:58.038346 | 
2026-04-17 00:59:58.038496 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-04-17 00:59:58.892510 | localhost -> localhost | changed
2026-04-17 00:59:58.898743 | 
2026-04-17 00:59:58.898807 | PLAY [all]
2026-04-17 00:59:58.906237 | 
2026-04-17 00:59:58.906305 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-04-17 00:59:59.214492 | rockylinux-9 -> localhost | ok
2026-04-17 00:59:59.220337 | 
2026-04-17 00:59:59.220402 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-04-17 00:59:59.250173 | rockylinux-9 | ok
2026-04-17 00:59:59.268863 | rockylinux-9 | included: /var/lib/zuul/builds/14075a5b14e84f41b26cbc3e864390ea/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-04-17 00:59:59.275038 | 
2026-04-17 00:59:59.275119 | TASK [add-build-sshkey : Create Temp SSH key]
2026-04-17 01:00:00.376851 | rockylinux-9 -> localhost | Generating public/private rsa key pair.
2026-04-17 01:00:00.377307 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/14075a5b14e84f41b26cbc3e864390ea/work/14075a5b14e84f41b26cbc3e864390ea_id_rsa
2026-04-17 01:00:00.377484 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/14075a5b14e84f41b26cbc3e864390ea/work/14075a5b14e84f41b26cbc3e864390ea_id_rsa.pub
2026-04-17 01:00:00.377565 | rockylinux-9 -> localhost | The key fingerprint is:
2026-04-17 01:00:00.377635 | rockylinux-9 -> localhost | SHA256:FZy0mOBjAC1Vqtqiz+DPzYfdsZodtAHvVewFbVnnZ7U zuul-build-sshkey
2026-04-17 01:00:00.377784 | rockylinux-9 -> localhost | The key's randomart image is:
2026-04-17 01:00:00.377838 | rockylinux-9 -> localhost | +---[RSA 3072]----+
2026-04-17 01:00:00.377881 | rockylinux-9 -> localhost | |  .+o.o  oo. .. *|
2026-04-17 01:00:00.377915 | rockylinux-9 -> localhost | |  . .+ . ooo. .=+|
2026-04-17 01:00:00.377947 | rockylinux-9 -> localhost | |   .. +.o o  o.E+|
2026-04-17 01:00:00.378063 | rockylinux-9 -> localhost | |   . . .o.  o ...|
2026-04-17 01:00:00.378102 | rockylinux-9 -> localhost | |  .     S+ . .   |
2026-04-17 01:00:00.378137 | rockylinux-9 -> localhost | | o      o.+      |
2026-04-17 01:00:00.378167 | rockylinux-9 -> localhost | |+ .   o .+o      |
2026-04-17 01:00:00.378197 | rockylinux-9 -> localhost | |o+. o. ooo.      |
2026-04-17 01:00:00.378231 | rockylinux-9 -> localhost | |.o+o o.o..       |
2026-04-17 01:00:00.378261 | rockylinux-9 -> localhost | +----[SHA256]-----+
2026-04-17 01:00:00.378363 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.666863
2026-04-17 01:00:00.385247 | 
2026-04-17 01:00:00.385353 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-04-17 01:00:00.420529 | rockylinux-9 | ok
2026-04-17 01:00:00.432285 | rockylinux-9 | included: /var/lib/zuul/builds/14075a5b14e84f41b26cbc3e864390ea/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-04-17 01:00:00.445343 | 
2026-04-17 01:00:00.445549 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-04-17 01:00:00.471594 | rockylinux-9 | skipping: Conditional result was False
2026-04-17 01:00:00.483609 | 
2026-04-17 01:00:00.483765 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-04-17 01:00:01.012765 | rockylinux-9 | changed
2026-04-17 01:00:01.018936 | 
2026-04-17 01:00:01.019014 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-04-17 01:00:01.272121 | rockylinux-9 | ok
2026-04-17 01:00:01.287536 | 
2026-04-17 01:00:01.287628 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-04-17 01:00:02.222079 | rockylinux-9 | changed
2026-04-17 01:00:02.227911 | 
2026-04-17 01:00:02.227977 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-04-17 01:00:03.182727 | rockylinux-9 | changed
2026-04-17 01:00:03.191460 | 
2026-04-17 01:00:03.191531 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-04-17 01:00:03.220444 | rockylinux-9 | skipping: Conditional result was False
2026-04-17 01:00:03.225967 | 
2026-04-17 01:00:03.226035 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-04-17 01:00:03.607792 | rockylinux-9 -> localhost | changed
2026-04-17 01:00:03.639885 | 
2026-04-17 01:00:03.640025 | TASK [add-build-sshkey : Add back temp key]
2026-04-17 01:00:03.945572 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/14075a5b14e84f41b26cbc3e864390ea/work/14075a5b14e84f41b26cbc3e864390ea_id_rsa (zuul-build-sshkey)
2026-04-17 01:00:03.945817 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.014634
2026-04-17 01:00:03.953537 | 
2026-04-17 01:00:03.953600 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-04-17 01:00:04.352408 | rockylinux-9 | ok
2026-04-17 01:00:04.358486 | 
2026-04-17 01:00:04.358592 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-04-17 01:00:04.383923 | rockylinux-9 | skipping: Conditional result was False
2026-04-17 01:00:04.396811 | 
2026-04-17 01:00:04.396879 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-04-17 01:00:04.803427 | rockylinux-9 | ok
2026-04-17 01:00:04.810398 | 
2026-04-17 01:00:04.810541 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-04-17 01:00:06.341494 | rockylinux-9 | Output suppressed because no_log was given
2026-04-17 01:00:06.352184 | 
2026-04-17 01:00:06.352252 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-04-17 01:00:06.607332 | rockylinux-9 | ok: "logs"
2026-04-17 01:00:06.608192 | rockylinux-9 | ok: All items complete
2026-04-17 01:00:06.608237 | 
2026-04-17 01:00:06.836366 | rockylinux-9 | ok: "artifacts"
2026-04-17 01:00:07.051702 | rockylinux-9 | ok: "docs"
2026-04-17 01:00:07.068616 | 
2026-04-17 01:00:07.068799 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-04-17 01:00:07.327045 | rockylinux-9 | changed: "logs"
2026-04-17 01:00:07.558044 | rockylinux-9 | changed: "artifacts"
2026-04-17 01:00:07.784432 | rockylinux-9 | changed: "docs"
2026-04-17 01:00:07.805083 | 
2026-04-17 01:00:07.805260 | PLAY RECAP
2026-04-17 01:00:07.805333 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-17 01:00:07.805384 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-04-17 01:00:07.805426 | 
2026-04-17 01:00:07.960904 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-17 01:00:07.963358 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-17 01:00:08.540052 | 
2026-04-17 01:00:08.540177 | PLAY [all]
2026-04-17 01:00:08.551586 | 
2026-04-17 01:00:08.551669 | TASK [setup-uv : Extract archive]
2026-04-17 01:00:11.370619 | rockylinux-9 | changed
2026-04-17 01:00:11.378207 | 
2026-04-17 01:00:11.378320 | TASK [setup-uv : Print version]
2026-04-17 01:00:11.401830 | [rockylinux-9] Waiting on logger
2026-04-17 01:00:11.918510 | rockylinux-9 | ok: Runtime: 0:00:00.015586
2026-04-17 01:00:11.926756 | 
2026-04-17 01:00:11.926824 | PLAY RECAP
2026-04-17 01:00:11.926886 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-17 01:00:11.926920 | 
2026-04-17 01:00:12.065102 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-17 01:00:12.067489 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-04-17 01:00:12.682312 | 
2026-04-17 01:00:12.682525 | PLAY [all]
2026-04-17 01:00:12.693515 | 
2026-04-17 01:00:12.693608 | TASK [Copy inventory file for Zuul]
2026-04-17 01:00:14.028988 | rockylinux-9 | changed
2026-04-17 01:00:14.035696 | 
2026-04-17 01:00:14.035802 | TASK [Switch "ansible_host" to private IP]
2026-04-17 01:00:14.393448 | rockylinux-9 | changed: 1 replacements made
2026-04-17 01:00:14.398995 | 
2026-04-17 01:00:14.399090 | TASK [Run Molecule scenario]
2026-04-17 01:00:14.423486 | [rockylinux-9] Waiting on logger
2026-04-17 01:00:22.565899 | [rockylinux-9] Waiting on logger
2026-04-17 01:00:32.742254 | [rockylinux-9] Waiting on logger
2026-04-17 01:00:42.918406 | [rockylinux-9] Waiting on logger
2026-04-17 01:00:53.094163 | [rockylinux-9] Waiting on logger
2026-04-17 01:01:03.270275 | [rockylinux-9] Waiting on logger
2026-04-17 01:01:12.431437 | [rockylinux-9] Waiting on logger
2026-04-17 01:01:22.598158 | [rockylinux-9] Waiting on logger
2026-04-17 01:01:24.846487 | rockylinux-9 | ERROR
2026-04-17 01:01:24.846801 | rockylinux-9 | {
2026-04-17 01:01:24.846869 | rockylinux-9 |   "delta": "0:01:08.903282",
2026-04-17 01:01:24.846916 | rockylinux-9 |   "end": "2026-04-17 01:01:24.244446",
2026-04-17 01:01:24.846960 | rockylinux-9 |   "msg": "non-zero return code",
2026-04-17 01:01:24.847001 | rockylinux-9 |   "rc": 2,
2026-04-17 01:01:24.847049 | rockylinux-9 |   "start": "2026-04-17 01:00:15.341164"
2026-04-17 01:01:24.847161 | rockylinux-9 | }
failure
2026-04-17 01:01:24.850708 | 
2026-04-17 01:01:24.850754 | PLAY RECAP
2026-04-17 01:01:24.850797 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-04-17 01:01:24.850820 | 
2026-04-17 01:01:25.005855 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-04-17 01:01:25.009470 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-17 01:01:25.602181 | 
2026-04-17 01:01:25.602345 | PLAY [all]
2026-04-17 01:01:25.614327 | 
2026-04-17 01:01:25.614428 | TASK [fetch-output : Set log path for multiple nodes]
2026-04-17 01:01:25.660070 | rockylinux-9 | skipping: Conditional result was False
2026-04-17 01:01:25.669588 | 
2026-04-17 01:01:25.669702 | TASK [fetch-output : Set log path for single node]
2026-04-17 01:01:25.716688 | rockylinux-9 | ok
2026-04-17 01:01:25.721018 | 
2026-04-17 01:01:25.721092 | LOOP [fetch-output : Ensure local output dirs]
2026-04-17 01:01:26.104526 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/14075a5b14e84f41b26cbc3e864390ea/work/logs"
2026-04-17 01:01:26.498956 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/14075a5b14e84f41b26cbc3e864390ea/work/artifacts"
2026-04-17 01:01:26.739788 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/14075a5b14e84f41b26cbc3e864390ea/work/docs"
2026-04-17 01:01:26.754658 | 
2026-04-17 01:01:26.754922 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-04-17 01:01:27.457641 | rockylinux-9 | ok
2026-04-17 01:01:27.457873 | rockylinux-9 | changed: All items complete
2026-04-17 01:01:27.457902 | 
2026-04-17 01:01:27.982316 | rockylinux-9 | changed: .d..t...... ./
2026-04-17 01:01:28.481254 | rockylinux-9 | changed: .d..t...... ./
2026-04-17 01:01:28.506304 | 
2026-04-17 01:01:28.509686 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-04-17 01:01:28.953162 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.007357
2026-04-17 01:01:29.198630 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.008983
2026-04-17 01:01:29.219279 | 
2026-04-17 01:01:29.219408 | PLAY [all]
2026-04-17 01:01:29.225380 | 
2026-04-17 01:01:29.225446 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-04-17 01:01:29.703986 | rockylinux-9 | changed
2026-04-17 01:01:29.712005 | 
2026-04-17 01:01:29.712067 | PLAY RECAP
2026-04-17 01:01:29.712110 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-04-17 01:01:29.712131 | 
2026-04-17 01:01:29.820399 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-17 01:01:29.822765 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-04-17 01:01:30.376453 | 
2026-04-17 01:01:30.376926 | PLAY [localhost]
2026-04-17 01:01:30.386695 | 
2026-04-17 01:01:30.386768 | TASK [Generate Zuul manifest]
2026-04-17 01:01:30.409984 | localhost | ok
2026-04-17 01:01:30.432472 | 
2026-04-17 01:01:30.432552 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-04-17 01:01:30.764975 | localhost | changed
2026-04-17 01:01:30.781149 | 
2026-04-17 01:01:30.781255 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-04-17 01:01:30.811126 | localhost | ok
2026-04-17 01:01:30.820349 | 
2026-04-17 01:01:30.820438 | TASK [Upload logs]
2026-04-17 01:01:30.840729 | localhost | ok
2026-04-17 01:01:30.901443 | 
2026-04-17 01:01:30.901581 | TASK [Set zuul-log-path fact]
2026-04-17 01:01:30.921269 | localhost | ok
2026-04-17 01:01:30.935661 | 
2026-04-17 01:01:30.935729 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-17 01:01:30.966978 | localhost | ok
2026-04-17 01:01:31.594375 | 
2026-04-17 01:01:31.594549 | TASK [upload-logs : Create log directories]
2026-04-17 01:01:31.991438 | localhost | changed
2026-04-17 01:01:32.000354 | 
2026-04-17 01:01:32.000424 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-04-17 01:01:32.423572 | localhost -> localhost | ok: Runtime: 0:00:00.006304
2026-04-17 01:01:32.428451 | 
2026-04-17 01:01:32.428519 | TASK [upload-logs : Upload logs to log server]
2026-04-17 01:01:32.905672 | localhost | Output suppressed because no_log was given
2026-04-17 01:01:32.910858 | 
2026-04-17 01:01:32.910953 | LOOP [upload-logs : Compress console log and json output]
2026-04-17 01:01:32.957072 | localhost | skipping: Conditional result was False
2026-04-17 01:01:32.963746 | localhost | skipping: Conditional result was False
2026-04-17 01:01:32.974138 | 
2026-04-17 01:01:32.974306 | LOOP [upload-logs : Upload compressed console log and json output]
2026-04-17 01:01:33.029810 | localhost | skipping: Conditional result was False
2026-04-17 01:01:33.030500 | 
2026-04-17 01:01:33.034261 | localhost | skipping: Conditional result was False
2026-04-17 01:01:33.050482 | 
2026-04-17 01:01:33.050689 | LOOP [upload-logs : Upload console log and json output]