2026-05-28 21:09:46.623001 | Job console starting
2026-05-28 21:09:46.638479 | Updating git repos
2026-05-28 21:09:46.670702 | Cloning repos into workspace
2026-05-28 21:09:46.757907 | Restoring repo states
2026-05-28 21:09:46.766166 | Merging changes
2026-05-28 21:09:47.155366 | Checking out repos
2026-05-28 21:09:47.191622 | Preparing playbooks
2026-05-28 21:09:49.032552 | Running Ansible setup
2026-05-28 21:09:53.924714 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-05-28 21:09:54.609931 | 
2026-05-28 21:09:54.610098 | PLAY [localhost]
2026-05-28 21:09:54.618644 | 
2026-05-28 21:09:54.618741 | TASK [Gathering Facts]
2026-05-28 21:09:55.704516 | localhost | ok
2026-05-28 21:09:55.714173 | 
2026-05-28 21:09:55.714328 | TASK [Setup log path fact]
2026-05-28 21:09:55.734451 | localhost | ok
2026-05-28 21:09:55.746047 | 
2026-05-28 21:09:55.746142 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-05-28 21:09:55.775411 | localhost | ok
2026-05-28 21:09:55.781643 | 
2026-05-28 21:09:55.781727 | TASK [emit-job-header : Print job information]
2026-05-28 21:09:55.817888 | # Job Information
2026-05-28 21:09:55.940458 | Ansible Version: 2.16.18
2026-05-28 21:09:55.940700 | Job: ansible-collection-containers-molecule-docker-rockylinux-9
2026-05-28 21:09:55.940771 | Pipeline: check
2026-05-28 21:09:55.941123 | Executor: 2d72f0692154
2026-05-28 21:09:55.941180 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/131
2026-05-28 21:09:55.941233 | Event ID: 716f5200-5ad9-11f1-91a5-93c65174d954
2026-05-28 21:09:55.947712 | 
2026-05-28 21:09:55.947807 | LOOP [emit-job-header : Print node information]
2026-05-28 21:09:56.040880 | localhost | ok:
2026-05-28 21:09:56.041159 | localhost | # Node Information
2026-05-28 21:09:56.041192 | localhost | Inventory Hostname: rockylinux-9
2026-05-28 21:09:56.041214 | localhost | Hostname: np0000177240
2026-05-28 21:09:56.041234 | localhost | Username: zuul
2026-05-28 21:09:56.041259 | localhost | Distro: Rocky 9.7
2026-05-28 21:09:56.041280 | localhost | Provider: yul1
2026-05-28 21:09:56.041302 | localhost | Region: ca-ymq-1
2026-05-28 21:09:56.041321 | localhost | Label: rockylinux-9
2026-05-28 21:09:56.041340 | localhost | Product Name: OpenStack Nova
2026-05-28 21:09:56.041359 | localhost | Interface IP: 199.204.45.144
2026-05-28 21:09:56.054216 | 
2026-05-28 21:09:56.054403 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-05-28 21:09:56.668636 | localhost -> localhost | changed
2026-05-28 21:09:56.678048 | 
2026-05-28 21:09:56.678127 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-05-28 21:09:57.544932 | localhost -> localhost | changed
2026-05-28 21:09:57.553677 | 
2026-05-28 21:09:57.553732 | PLAY [all]
2026-05-28 21:09:57.562065 | 
2026-05-28 21:09:57.562135 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-05-28 21:09:57.856890 | rockylinux-9 -> localhost | ok
2026-05-28 21:09:57.863373 | 
2026-05-28 21:09:57.863441 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-05-28 21:09:57.894554 | rockylinux-9 | ok
2026-05-28 21:09:57.993207 | rockylinux-9 | included: /var/lib/zuul/builds/354a6ce77004441081570b1d1c60da13/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-05-28 21:09:58.000373 | 
2026-05-28 21:09:58.000497 | TASK [add-build-sshkey : Create Temp SSH key]
2026-05-28 21:09:59.088302 | rockylinux-9 -> localhost | Generating public/private rsa key pair.
2026-05-28 21:09:59.088469 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/354a6ce77004441081570b1d1c60da13/work/354a6ce77004441081570b1d1c60da13_id_rsa
2026-05-28 21:09:59.088499 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/354a6ce77004441081570b1d1c60da13/work/354a6ce77004441081570b1d1c60da13_id_rsa.pub
2026-05-28 21:09:59.088521 | rockylinux-9 -> localhost | The key fingerprint is:
2026-05-28 21:09:59.088543 | rockylinux-9 -> localhost | SHA256:/ULZ1PbBCPvAYJkzSdUKdLDxEwJYl+hQ4Gdz0DuN8CQ zuul-build-sshkey
2026-05-28 21:09:59.088577 | rockylinux-9 -> localhost | The key's randomart image is:
2026-05-28 21:09:59.088598 | rockylinux-9 -> localhost | +---[RSA 3072]----+
2026-05-28 21:09:59.088626 | rockylinux-9 -> localhost | |     .==*XB=.    |
2026-05-28 21:09:59.088648 | rockylinux-9 -> localhost | |    .o E+@O +oo  |
2026-05-28 21:09:59.088668 | rockylinux-9 -> localhost | |     .o+=oB*o.oo |
2026-05-28 21:09:59.088688 | rockylinux-9 -> localhost | |      o.o* *+. ..|
2026-05-28 21:09:59.088708 | rockylinux-9 -> localhost | |        S = ..  .|
2026-05-28 21:09:59.088727 | rockylinux-9 -> localhost | |         . .     |
2026-05-28 21:09:59.088747 | rockylinux-9 -> localhost | |          . .    |
2026-05-28 21:09:59.088766 | rockylinux-9 -> localhost | |           .     |
2026-05-28 21:09:59.088788 | rockylinux-9 -> localhost | |                 |
2026-05-28 21:09:59.088808 | rockylinux-9 -> localhost | +----[SHA256]-----+
2026-05-28 21:09:59.088854 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.650306
2026-05-28 21:09:59.093603 | 
2026-05-28 21:09:59.093667 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-05-28 21:09:59.121581 | rockylinux-9 | ok
2026-05-28 21:09:59.129742 | rockylinux-9 | included: /var/lib/zuul/builds/354a6ce77004441081570b1d1c60da13/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-05-28 21:09:59.137182 | 
2026-05-28 21:09:59.137243 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-05-28 21:09:59.152729 | rockylinux-9 | skipping: Conditional result was False
2026-05-28 21:09:59.164110 | 
2026-05-28 21:09:59.164182 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-05-28 21:09:59.651936 | rockylinux-9 | changed
2026-05-28 21:09:59.663070 | 
2026-05-28 21:09:59.663312 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-05-28 21:09:59.925598 | rockylinux-9 | ok
2026-05-28 21:09:59.940299 | 
2026-05-28 21:09:59.940425 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-05-28 21:10:00.863202 | rockylinux-9 | changed
2026-05-28 21:10:00.867903 | 
2026-05-28 21:10:00.867968 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-05-28 21:10:01.849107 | rockylinux-9 | changed
2026-05-28 21:10:01.855454 | 
2026-05-28 21:10:01.855533 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-05-28 21:10:01.891044 | rockylinux-9 | skipping: Conditional result was False
2026-05-28 21:10:01.904370 | 
2026-05-28 21:10:01.904580 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-05-28 21:10:02.355282 | rockylinux-9 -> localhost | changed
2026-05-28 21:10:02.364527 | 
2026-05-28 21:10:02.364610 | TASK [add-build-sshkey : Add back temp key]
2026-05-28 21:10:02.679785 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/354a6ce77004441081570b1d1c60da13/work/354a6ce77004441081570b1d1c60da13_id_rsa (zuul-build-sshkey)
2026-05-28 21:10:02.680093 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.022726
2026-05-28 21:10:02.686170 | 
2026-05-28 21:10:02.686287 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-05-28 21:10:03.113674 | rockylinux-9 | ok
2026-05-28 21:10:03.118046 | 
2026-05-28 21:10:03.118113 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-05-28 21:10:03.144358 | rockylinux-9 | skipping: Conditional result was False
2026-05-28 21:10:03.154961 | 
2026-05-28 21:10:03.155086 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-05-28 21:10:03.620101 | rockylinux-9 | ok
2026-05-28 21:10:03.625646 | 
2026-05-28 21:10:03.625711 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-05-28 21:10:04.730153 | rockylinux-9 | Output suppressed because no_log was given
2026-05-28 21:10:04.737667 | 
2026-05-28 21:10:04.737731 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-05-28 21:10:04.984822 | rockylinux-9 | ok: "logs"
2026-05-28 21:10:04.985167 | rockylinux-9 | ok: All items complete
2026-05-28 21:10:04.985209 | 
2026-05-28 21:10:05.213086 | rockylinux-9 | ok: "artifacts"
2026-05-28 21:10:05.438660 | rockylinux-9 | ok: "docs"
2026-05-28 21:10:05.454877 | 
2026-05-28 21:10:05.455007 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-05-28 21:10:05.708393 | rockylinux-9 | changed: "logs"
2026-05-28 21:10:05.989484 | rockylinux-9 | changed: "artifacts"
2026-05-28 21:10:06.163792 | rockylinux-9 | changed: "docs"
2026-05-28 21:10:06.181828 | 
2026-05-28 21:10:06.182162 | PLAY RECAP
2026-05-28 21:10:06.182212 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-05-28 21:10:06.182244 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-05-28 21:10:06.182265 | 
2026-05-28 21:10:06.325696 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-05-28 21:10:06.326945 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-05-28 21:10:06.911534 | 
2026-05-28 21:10:06.912000 | PLAY [all]
2026-05-28 21:10:06.923087 | 
2026-05-28 21:10:06.923170 | TASK [setup-uv : Extract archive]
2026-05-28 21:10:09.794608 | rockylinux-9 | changed
2026-05-28 21:10:09.800147 | 
2026-05-28 21:10:09.800229 | TASK [setup-uv : Print version]
2026-05-28 21:10:09.822324 | [rockylinux-9] Waiting on logger
2026-05-28 21:10:10.333300 | rockylinux-9 | ok: Runtime: 0:00:00.014696
2026-05-28 21:10:10.341442 | 
2026-05-28 21:10:10.341518 | TASK [Install Ansible collection siblings]
2026-05-28 21:10:10.818048 | rockylinux-9 | ok:
2026-05-28 21:10:10.818265 | rockylinux-9 | Skipping ansible.posix: no checked-out sibling
2026-05-28 21:10:10.818295 | rockylinux-9 | Skipping community.general: no checked-out sibling
2026-05-28 21:10:10.824897 | 
2026-05-28 21:10:10.824975 | PLAY RECAP
2026-05-28 21:10:10.825028 | rockylinux-9 | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-05-28 21:10:10.825050 | 
2026-05-28 21:10:11.004290 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-05-28 21:10:11.007591 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-05-28 21:10:11.634876 | 
2026-05-28 21:10:11.635051 | PLAY [all]
2026-05-28 21:10:11.646278 | 
2026-05-28 21:10:11.646357 | TASK [Copy inventory file for Zuul]
2026-05-28 21:10:12.993649 | rockylinux-9 | changed
2026-05-28 21:10:12.999479 | 
2026-05-28 21:10:12.999601 | TASK [Switch "ansible_host" to private IP]
2026-05-28 21:10:13.397719 | rockylinux-9 | changed: 1 replacements made
2026-05-28 21:10:13.404667 | 
2026-05-28 21:10:13.404777 | TASK [Run Molecule scenario]
2026-05-28 21:10:13.428335 | [rockylinux-9] Waiting on logger
2026-05-28 21:10:21.573881 | [rockylinux-9] Waiting on logger
2026-05-28 21:10:31.750048 | [rockylinux-9] Waiting on logger
2026-05-28 21:10:40.907745 | [rockylinux-9] Waiting on logger
2026-05-28 21:10:51.082058 | [rockylinux-9] Waiting on logger
2026-05-28 21:11:01.254199 | [rockylinux-9] Waiting on logger
2026-05-28 21:11:11.433858 | [rockylinux-9] Waiting on logger
2026-05-28 21:11:21.606225 | [rockylinux-9] Waiting on logger
2026-05-28 21:11:31.782313 | [rockylinux-9] Waiting on logger
2026-05-28 21:11:40.939253 | [rockylinux-9] Waiting on logger
2026-05-28 21:11:51.109911 | [rockylinux-9] Waiting on logger
2026-05-28 21:12:01.286074 | [rockylinux-9] Waiting on logger
2026-05-28 21:12:11.462005 | [rockylinux-9] Waiting on logger
2026-05-28 21:12:21.638095 | [rockylinux-9] Waiting on logger
2026-05-28 21:12:30.702171 | rockylinux-9 | ok: Runtime: 0:02:15.837564
2026-05-28 21:12:30.725055 | 
2026-05-28 21:12:30.725141 | PLAY RECAP
2026-05-28 21:12:30.725207 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-05-28 21:12:30.725240 | 
2026-05-28 21:12:30.843750 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-05-28 21:12:30.846442 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-05-28 21:12:31.461370 | 
2026-05-28 21:12:31.461513 | PLAY [all]
2026-05-28 21:12:31.473328 | 
2026-05-28 21:12:31.473425 | TASK [fetch-output : Set log path for multiple nodes]
2026-05-28 21:12:31.519101 | rockylinux-9 | skipping: Conditional result was False
2026-05-28 21:12:31.528700 | 
2026-05-28 21:12:31.528867 | TASK [fetch-output : Set log path for single node]
2026-05-28 21:12:31.573782 | rockylinux-9 | ok
2026-05-28 21:12:31.581672 | 
2026-05-28 21:12:31.581743 | LOOP [fetch-output : Ensure local output dirs]
2026-05-28 21:12:31.968817 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/354a6ce77004441081570b1d1c60da13/work/logs"
2026-05-28 21:12:32.178234 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/354a6ce77004441081570b1d1c60da13/work/artifacts"
2026-05-28 21:12:32.400686 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/354a6ce77004441081570b1d1c60da13/work/docs"
2026-05-28 21:12:32.423779 | 
2026-05-28 21:12:32.423948 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-05-28 21:12:33.141502 | rockylinux-9 | changed: .d..t...... ./
2026-05-28 21:12:33.141785 | rockylinux-9 | changed: All items complete
2026-05-28 21:12:33.141823 | 
2026-05-28 21:12:33.662811 | rockylinux-9 | changed: .d..t...... ./
2026-05-28 21:12:34.191003 | rockylinux-9 | changed: .d..t...... ./
2026-05-28 21:12:34.213849 | 
2026-05-28 21:12:34.214019 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-05-28 21:12:34.656422 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.008539
2026-05-28 21:12:34.887332 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.007847
2026-05-28 21:12:34.905790 | 
2026-05-28 21:12:34.905865 | PLAY [all]
2026-05-28 21:12:34.913480 | 
2026-05-28 21:12:34.913566 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-05-28 21:12:35.394805 | rockylinux-9 | changed
2026-05-28 21:12:35.400278 | 
2026-05-28 21:12:35.400367 | PLAY RECAP
2026-05-28 21:12:35.400418 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-05-28 21:12:35.400447 | 
2026-05-28 21:12:35.547268 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-05-28 21:12:35.549120 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-05-28 21:12:36.124980 | 
2026-05-28 21:12:36.125649 | PLAY [localhost]
2026-05-28 21:12:36.136194 | 
2026-05-28 21:12:36.136265 | TASK [Generate Zuul manifest]
2026-05-28 21:12:36.158526 | localhost | ok
2026-05-28 21:12:36.175617 | 
2026-05-28 21:12:36.175692 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-05-28 21:12:36.553261 | localhost | changed
2026-05-28 21:12:36.564382 | 
2026-05-28 21:12:36.564462 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-05-28 21:12:36.601386 | localhost | ok
2026-05-28 21:12:36.608527 | 
2026-05-28 21:12:36.608593 | TASK [Upload logs]
2026-05-28 21:12:36.631437 | localhost | ok
2026-05-28 21:12:36.692084 | 
2026-05-28 21:12:36.692249 | TASK [Set zuul-log-path fact]
2026-05-28 21:12:36.715594 | localhost | ok
2026-05-28 21:12:36.728395 | 
2026-05-28 21:12:36.728460 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-05-28 21:12:36.760381 | localhost | ok
2026-05-28 21:12:36.769410 | 
2026-05-28 21:12:36.769493 | TASK [upload-logs : Create log directories]
2026-05-28 21:12:37.178820 | localhost | changed
2026-05-28 21:12:37.186630 | 
2026-05-28 21:12:37.186732 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-05-28 21:12:37.595261 | localhost -> localhost | ok: Runtime: 0:00:00.005725
2026-05-28 21:12:37.602873 | 
2026-05-28 21:12:37.602978 | TASK [upload-logs : Upload logs to log server]
2026-05-28 21:12:38.082099 | localhost | Output suppressed because no_log was given
2026-05-28 21:12:38.087800 | 
2026-05-28 21:12:38.087865 | LOOP [upload-logs : Compress console log and json output]
2026-05-28 21:12:38.135695 | localhost | skipping: Conditional result was False
2026-05-28 21:12:38.142367 | localhost | skipping: Conditional result was False
2026-05-28 21:12:38.151631 | 
2026-05-28 21:12:38.151809 | LOOP [upload-logs : Upload compressed console log and json output]
2026-05-28 21:12:38.198642 | localhost | skipping: Conditional result was False
2026-05-28 21:12:38.199217 | 
2026-05-28 21:12:38.202605 | localhost | skipping: Conditional result was False
2026-05-28 21:12:38.219106 | 
2026-05-28 21:12:38.219290 | LOOP [upload-logs : Upload console log and json output]