2026-04-22 13:46:30.257420 | Job console starting
2026-04-22 13:46:30.268071 | Updating git repos
2026-04-22 13:46:30.296878 | Cloning repos into workspace
2026-04-22 13:46:30.343075 | Restoring repo states
2026-04-22 13:46:30.350284 | Merging changes
2026-04-22 13:46:30.611620 | Checking out repos
2026-04-22 13:46:30.642838 | Preparing playbooks
2026-04-22 13:46:32.378698 | Running Ansible setup
2026-04-22 13:46:36.842895 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-22 13:46:37.586631 | 
2026-04-22 13:46:37.586786 | PLAY [localhost]
2026-04-22 13:46:37.594321 | 
2026-04-22 13:46:37.594404 | TASK [Gathering Facts]
2026-04-22 13:46:38.616685 | localhost | ok
2026-04-22 13:46:38.626311 | 
2026-04-22 13:46:38.626474 | TASK [Setup log path fact]
2026-04-22 13:46:38.664345 | localhost | ok
2026-04-22 13:46:38.678056 | 
2026-04-22 13:46:38.678161 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-22 13:46:38.708301 | localhost | ok
2026-04-22 13:46:38.714848 | 
2026-04-22 13:46:38.714924 | TASK [emit-job-header : Print job information]
2026-04-22 13:46:38.748661 | # Job Information
2026-04-22 13:46:38.749026 | Ansible Version: 2.16.16
2026-04-22 13:46:38.749102 | Job: ansible-collection-containers-molecule-docker-rockylinux-9
2026-04-22 13:46:38.749140 | Pipeline: check
2026-04-22 13:46:38.749170 | Executor: 0a8996d2b663
2026-04-22 13:46:38.749198 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/118
2026-04-22 13:46:38.749230 | Event ID: 87752330-3e51-11f1-97de-a4705693b126
2026-04-22 13:46:38.754157 | 
2026-04-22 13:46:38.754223 | LOOP [emit-job-header : Print node information]
2026-04-22 13:46:38.852418 | localhost | ok:
2026-04-22 13:46:38.852673 | localhost | # Node Information
2026-04-22 13:46:38.852710 | localhost | Inventory Hostname: rockylinux-9
2026-04-22 13:46:38.852733 | localhost | Hostname: np0000169456
2026-04-22 13:46:38.852753 | localhost | Username: zuul
2026-04-22 13:46:38.852777 | localhost | Distro: Rocky 9.7
2026-04-22 13:46:38.852798 | localhost | Provider: yul1
2026-04-22 13:46:38.852816 | localhost | Region: ca-ymq-1
2026-04-22 13:46:38.852835 | localhost | Label: rockylinux-9
2026-04-22 13:46:38.852853 | localhost | Product Name: OpenStack Nova
2026-04-22 13:46:38.852872 | localhost | Interface IP: 199.19.213.179
2026-04-22 13:46:38.870316 | 
2026-04-22 13:46:38.870460 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-04-22 13:46:39.334357 | localhost -> localhost | changed
2026-04-22 13:46:39.339207 | 
2026-04-22 13:46:39.339321 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-04-22 13:46:40.596621 | localhost -> localhost | changed
2026-04-22 13:46:40.603058 | 
2026-04-22 13:46:40.603145 | PLAY [all]
2026-04-22 13:46:40.612080 | 
2026-04-22 13:46:40.612183 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-04-22 13:46:40.894795 | rockylinux-9 -> localhost | ok
2026-04-22 13:46:40.911350 | 
2026-04-22 13:46:40.918678 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-04-22 13:46:40.948069 | rockylinux-9 | ok
2026-04-22 13:46:40.964080 | rockylinux-9 | included: /var/lib/zuul/builds/a24ab6e1f10d4d75a027a35899515d50/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-04-22 13:46:40.969480 | 
2026-04-22 13:46:40.969572 | TASK [add-build-sshkey : Create Temp SSH key]
2026-04-22 13:46:42.096343 | rockylinux-9 -> localhost | Generating public/private rsa key pair.
2026-04-22 13:46:42.096523 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/a24ab6e1f10d4d75a027a35899515d50/work/a24ab6e1f10d4d75a027a35899515d50_id_rsa
2026-04-22 13:46:42.096558 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/a24ab6e1f10d4d75a027a35899515d50/work/a24ab6e1f10d4d75a027a35899515d50_id_rsa.pub
2026-04-22 13:46:42.096581 | rockylinux-9 -> localhost | The key fingerprint is:
2026-04-22 13:46:42.096602 | rockylinux-9 -> localhost | SHA256:PxPhsFUi97QiKf8K14N84snxKOy6pmF11IdVk17zHMI zuul-build-sshkey
2026-04-22 13:46:42.096635 | rockylinux-9 -> localhost | The key's randomart image is:
2026-04-22 13:46:42.096657 | rockylinux-9 -> localhost | +---[RSA 3072]----+
2026-04-22 13:46:42.096685 | rockylinux-9 -> localhost | |        . +.*.   |
2026-04-22 13:46:42.096709 | rockylinux-9 -> localhost | |       . * =.Eo. |
2026-04-22 13:46:42.096730 | rockylinux-9 -> localhost | |      o * =.o..+.|
2026-04-22 13:46:42.096752 | rockylinux-9 -> localhost | |     . o B o.   o|
2026-04-22 13:46:42.096773 | rockylinux-9 -> localhost | |    . . S o      |
2026-04-22 13:46:42.096795 | rockylinux-9 -> localhost | |   . . . = .     |
2026-04-22 13:46:42.096815 | rockylinux-9 -> localhost | |  o  .. * O      |
2026-04-22 13:46:42.096837 | rockylinux-9 -> localhost | | . .. o= O +     |
2026-04-22 13:46:42.096862 | rockylinux-9 -> localhost | |  .oo+..* .      |
2026-04-22 13:46:42.096883 | rockylinux-9 -> localhost | +----[SHA256]-----+
2026-04-22 13:46:42.096954 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.467891
2026-04-22 13:46:42.102374 | 
2026-04-22 13:46:42.102454 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-04-22 13:46:42.132084 | rockylinux-9 | ok
2026-04-22 13:46:42.140431 | rockylinux-9 | included: /var/lib/zuul/builds/a24ab6e1f10d4d75a027a35899515d50/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-04-22 13:46:42.153089 | 
2026-04-22 13:46:42.153257 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-04-22 13:46:42.168070 | rockylinux-9 | skipping: Conditional result was False
2026-04-22 13:46:42.175215 | 
2026-04-22 13:46:42.175325 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-04-22 13:46:42.743617 | rockylinux-9 | changed
2026-04-22 13:46:42.748778 | 
2026-04-22 13:46:42.748881 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-04-22 13:46:43.035841 | rockylinux-9 | ok
2026-04-22 13:46:43.043305 | 
2026-04-22 13:46:43.043381 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-04-22 13:46:44.018904 | rockylinux-9 | changed
2026-04-22 13:46:44.122303 | 
2026-04-22 13:46:44.122456 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-04-22 13:46:45.052013 | rockylinux-9 | changed
2026-04-22 13:46:45.057974 | 
2026-04-22 13:46:45.058054 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-04-22 13:46:45.082230 | rockylinux-9 | skipping: Conditional result was False
2026-04-22 13:46:45.093090 | 
2026-04-22 13:46:45.093225 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-04-22 13:46:45.723962 | rockylinux-9 -> localhost | changed
2026-04-22 13:46:45.737226 | 
2026-04-22 13:46:45.737325 | TASK [add-build-sshkey : Add back temp key]
2026-04-22 13:46:46.073491 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/a24ab6e1f10d4d75a027a35899515d50/work/a24ab6e1f10d4d75a027a35899515d50_id_rsa (zuul-build-sshkey)
2026-04-22 13:46:46.073703 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.018661
2026-04-22 13:46:46.093637 | 
2026-04-22 13:46:46.093782 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-04-22 13:46:46.477723 | rockylinux-9 | ok
2026-04-22 13:46:46.484854 | 
2026-04-22 13:46:46.485015 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-04-22 13:46:46.510335 | rockylinux-9 | skipping: Conditional result was False
2026-04-22 13:46:46.519360 | 
2026-04-22 13:46:46.519442 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-04-22 13:46:46.977808 | rockylinux-9 | ok
2026-04-22 13:46:47.015909 | 
2026-04-22 13:46:47.016012 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-04-22 13:46:48.109780 | rockylinux-9 | Output suppressed because no_log was given
2026-04-22 13:46:48.139416 | 
2026-04-22 13:46:48.139559 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-04-22 13:46:48.410515 | rockylinux-9 | ok: "logs"
2026-04-22 13:46:48.410849 | rockylinux-9 | ok: All items complete
2026-04-22 13:46:48.410890 | 
2026-04-22 13:46:48.658025 | rockylinux-9 | ok: "artifacts"
2026-04-22 13:46:48.874649 | rockylinux-9 | ok: "docs"
2026-04-22 13:46:48.883556 | 
2026-04-22 13:46:48.883729 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-04-22 13:46:49.123003 | rockylinux-9 | changed: "logs"
2026-04-22 13:46:49.337370 | rockylinux-9 | changed: "artifacts"
2026-04-22 13:46:49.578120 | rockylinux-9 | changed: "docs"
2026-04-22 13:46:49.596923 | 
2026-04-22 13:46:49.597089 | PLAY RECAP
2026-04-22 13:46:49.597170 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-22 13:46:49.597216 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-04-22 13:46:49.597249 | 
2026-04-22 13:46:49.780794 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-22 13:46:49.783822 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-22 13:46:50.476079 | 
2026-04-22 13:46:50.476226 | PLAY [all]
2026-04-22 13:46:50.487491 | 
2026-04-22 13:46:50.487574 | TASK [setup-uv : Extract archive]
2026-04-22 13:46:53.368767 | rockylinux-9 | changed
2026-04-22 13:46:53.375775 | 
2026-04-22 13:46:53.375853 | TASK [setup-uv : Print version]
2026-04-22 13:46:53.397646 | [rockylinux-9] Waiting on logger
2026-04-22 13:46:53.913477 | rockylinux-9 | ok: Runtime: 0:00:00.015649
2026-04-22 13:46:53.921540 | 
2026-04-22 13:46:53.921593 | PLAY RECAP
2026-04-22 13:46:53.921687 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-22 13:46:53.921733 | 
2026-04-22 13:46:54.061721 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-22 13:46:54.069332 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-04-22 13:46:54.692282 | 
2026-04-22 13:46:54.692467 | PLAY [all]
2026-04-22 13:46:54.708497 | 
2026-04-22 13:46:54.708848 | TASK [Copy inventory file for Zuul]
2026-04-22 13:46:56.123370 | rockylinux-9 | changed
2026-04-22 13:46:56.131875 | 
2026-04-22 13:46:56.132140 | TASK [Switch "ansible_host" to private IP]
2026-04-22 13:46:56.483166 | rockylinux-9 | changed: 1 replacements made
2026-04-22 13:46:56.541373 | 
2026-04-22 13:46:56.541527 | TASK [Run Molecule scenario]
2026-04-22 13:46:56.568215 | [rockylinux-9] Waiting on logger
2026-04-22 13:47:04.710346 | [rockylinux-9] Waiting on logger
2026-04-22 13:47:14.917948 | [rockylinux-9] Waiting on logger
2026-04-22 13:47:25.094218 | [rockylinux-9] Waiting on logger
2026-04-22 13:47:35.270299 | [rockylinux-9] Waiting on logger
2026-04-22 13:47:44.430777 | [rockylinux-9] Waiting on logger
2026-04-22 13:47:54.598085 | [rockylinux-9] Waiting on logger
2026-04-22 13:48:04.774109 | [rockylinux-9] Waiting on logger
2026-04-22 13:48:14.950267 | [rockylinux-9] Waiting on logger
2026-04-22 13:48:25.126164 | [rockylinux-9] Waiting on logger
2026-04-22 13:48:35.302211 | [rockylinux-9] Waiting on logger
2026-04-22 13:48:44.460093 | [rockylinux-9] Waiting on logger
2026-04-22 13:48:54.629912 | [rockylinux-9] Waiting on logger
2026-04-22 13:49:00.237318 | rockylinux-9 | ok: Runtime: 0:02:02.434550
2026-04-22 13:49:00.244659 | 
2026-04-22 13:49:00.244721 | PLAY RECAP
2026-04-22 13:49:00.244768 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-22 13:49:00.244792 | 
2026-04-22 13:49:00.390369 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-04-22 13:49:00.391999 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-22 13:49:01.031500 | 
2026-04-22 13:49:01.031666 | PLAY [all]
2026-04-22 13:49:01.042705 | 
2026-04-22 13:49:01.042796 | TASK [fetch-output : Set log path for multiple nodes]
2026-04-22 13:49:01.081813 | rockylinux-9 | skipping: Conditional result was False
2026-04-22 13:49:01.089345 | 
2026-04-22 13:49:01.089461 | TASK [fetch-output : Set log path for single node]
2026-04-22 13:49:01.134619 | rockylinux-9 | ok
2026-04-22 13:49:01.140257 | 
2026-04-22 13:49:01.140348 | LOOP [fetch-output : Ensure local output dirs]
2026-04-22 13:49:01.545842 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/a24ab6e1f10d4d75a027a35899515d50/work/logs"
2026-04-22 13:49:01.773123 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/a24ab6e1f10d4d75a027a35899515d50/work/artifacts"
2026-04-22 13:49:02.015948 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/a24ab6e1f10d4d75a027a35899515d50/work/docs"
2026-04-22 13:49:02.032427 | 
2026-04-22 13:49:02.032554 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-04-22 13:49:02.745439 | rockylinux-9 | changed: .d..t...... ./
2026-04-22 13:49:02.745840 | rockylinux-9 | changed: All items complete
2026-04-22 13:49:02.745901 | 
2026-04-22 13:49:03.251234 | rockylinux-9 | changed: .d..t...... ./
2026-04-22 13:49:03.793067 | rockylinux-9 | changed: .d..t...... ./
2026-04-22 13:49:03.811583 | 
2026-04-22 13:49:03.811747 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-04-22 13:49:04.286734 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.008001
2026-04-22 13:49:04.536807 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.006503
2026-04-22 13:49:04.547353 | 
2026-04-22 13:49:04.547490 | PLAY [all]
2026-04-22 13:49:04.554169 | 
2026-04-22 13:49:04.554255 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-04-22 13:49:05.013386 | rockylinux-9 | changed
2026-04-22 13:49:05.022629 | 
2026-04-22 13:49:05.022798 | PLAY RECAP
2026-04-22 13:49:05.022891 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-04-22 13:49:05.022922 | 
2026-04-22 13:49:05.181182 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-22 13:49:05.182677 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-04-22 13:49:05.757262 | 
2026-04-22 13:49:05.757426 | PLAY [localhost]
2026-04-22 13:49:05.767738 | 
2026-04-22 13:49:05.767842 | TASK [Generate Zuul manifest]
2026-04-22 13:49:05.788053 | localhost | ok
2026-04-22 13:49:05.806313 | 
2026-04-22 13:49:05.806423 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-04-22 13:49:06.160882 | localhost | changed
2026-04-22 13:49:06.173565 | 
2026-04-22 13:49:06.173643 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-04-22 13:49:07.028905 | localhost | ok
2026-04-22 13:49:07.035736 | 
2026-04-22 13:49:07.035819 | TASK [Upload logs]
2026-04-22 13:49:07.058370 | localhost | ok
2026-04-22 13:49:07.116884 | 
2026-04-22 13:49:07.117213 | TASK [Set zuul-log-path fact]
2026-04-22 13:49:07.138047 | localhost | ok
2026-04-22 13:49:07.152180 | 
2026-04-22 13:49:07.152247 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-22 13:49:07.183355 | localhost | ok
2026-04-22 13:49:07.192660 | 
2026-04-22 13:49:07.192723 | TASK [upload-logs : Create log directories]
2026-04-22 13:49:07.609907 | localhost | changed
2026-04-22 13:49:07.616181 | 
2026-04-22 13:49:07.616258 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-04-22 13:49:08.064502 | localhost -> localhost | ok: Runtime: 0:00:00.005306
2026-04-22 13:49:08.071058 | 
2026-04-22 13:49:08.071145 | TASK [upload-logs : Upload logs to log server]
2026-04-22 13:49:08.565199 | localhost | Output suppressed because no_log was given
2026-04-22 13:49:08.568741 | 
2026-04-22 13:49:08.568808 | LOOP [upload-logs : Compress console log and json output]
2026-04-22 13:49:08.614344 | localhost | skipping: Conditional result was False
2026-04-22 13:49:08.620875 | localhost | skipping: Conditional result was False
2026-04-22 13:49:08.634031 | 
2026-04-22 13:49:08.634231 | LOOP [upload-logs : Upload compressed console log and json output]
2026-04-22 13:49:08.686011 | localhost | skipping: Conditional result was False
2026-04-22 13:49:08.686465 | 
2026-04-22 13:49:08.690199 | localhost | skipping: Conditional result was False
2026-04-22 13:49:08.707625 | 
2026-04-22 13:49:08.707784 | LOOP [upload-logs : Upload console log and json output]