2026-04-14 02:57:09.519264 | Job console starting
2026-04-14 02:57:09.527949 | Updating git repos
2026-04-14 02:57:09.561944 | Cloning repos into workspace
2026-04-14 02:57:09.759621 | Restoring repo states
2026-04-14 02:57:09.783164 | Merging changes
2026-04-14 02:57:10.063824 | Checking out repos
2026-04-14 02:57:10.097485 | Preparing playbooks
2026-04-14 02:57:11.423403 | Running Ansible setup
2026-04-14 02:57:15.319976 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-14 02:57:15.985205 | 
2026-04-14 02:57:15.985437 | PLAY [localhost]
2026-04-14 02:57:15.994602 | 
2026-04-14 02:57:15.994703 | TASK [Gathering Facts]
2026-04-14 02:57:16.926883 | localhost | ok
2026-04-14 02:57:16.934609 | 
2026-04-14 02:57:16.934741 | TASK [Setup log path fact]
2026-04-14 02:57:16.956616 | localhost | ok
2026-04-14 02:57:16.970805 | 
2026-04-14 02:57:16.970944 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-14 02:57:17.002156 | localhost | ok
2026-04-14 02:57:17.010791 | 
2026-04-14 02:57:17.010882 | TASK [emit-job-header : Print job information]
2026-04-14 02:57:17.052120 | # Job Information
2026-04-14 02:57:17.052329 | Ansible Version: 2.16.16
2026-04-14 02:57:17.052392 | Job: ansible-collection-containers-molecule-docker-rockylinux-9
2026-04-14 02:57:17.052427 | Pipeline: check
2026-04-14 02:57:17.052532 | Executor: 0a8996d2b663
2026-04-14 02:57:17.052580 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/113
2026-04-14 02:57:17.052615 | Event ID: 81b63d30-37ad-11f1-8198-64bbaeb12eb2
2026-04-14 02:57:17.057271 | 
2026-04-14 02:57:17.057342 | LOOP [emit-job-header : Print node information]
2026-04-14 02:57:17.231067 | localhost | ok:
2026-04-14 02:57:17.231376 | localhost | # Node Information
2026-04-14 02:57:17.231432 | localhost | Inventory Hostname: rockylinux-9
2026-04-14 02:57:17.231467 | localhost | Hostname: np0000166028
2026-04-14 02:57:17.231496 | localhost | Username: zuul
2026-04-14 02:57:17.231528 | localhost | Distro: Rocky 9.7
2026-04-14 02:57:17.231556 | localhost | Provider: yul1
2026-04-14 02:57:17.231583 | localhost | Region: ca-ymq-1
2026-04-14 02:57:17.231611 | localhost | Label: rockylinux-9
2026-04-14 02:57:17.231637 | localhost | Product Name: OpenStack Nova
2026-04-14 02:57:17.231664 | localhost | Interface IP: 199.19.213.212
2026-04-14 02:57:17.251903 | 
2026-04-14 02:57:17.252084 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-04-14 02:57:17.805167 | localhost -> localhost | changed
2026-04-14 02:57:17.813511 | 
2026-04-14 02:57:17.813677 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-04-14 02:57:19.083252 | localhost -> localhost | changed
2026-04-14 02:57:19.099835 | 
2026-04-14 02:57:19.099992 | PLAY [all]
2026-04-14 02:57:19.116162 | 
2026-04-14 02:57:19.116280 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-04-14 02:57:19.386438 | rockylinux-9 -> localhost | ok
2026-04-14 02:57:19.408885 | 
2026-04-14 02:57:19.409047 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-04-14 02:57:19.450059 | rockylinux-9 | ok
2026-04-14 02:57:19.465995 | rockylinux-9 | included: /var/lib/zuul/builds/7dbddf33a51946cd97a94b6d718a5ff9/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-04-14 02:57:19.471567 | 
2026-04-14 02:57:19.471634 | TASK [add-build-sshkey : Create Temp SSH key]
2026-04-14 02:57:21.329525 | rockylinux-9 -> localhost | Generating public/private rsa key pair.
2026-04-14 02:57:21.329774 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/7dbddf33a51946cd97a94b6d718a5ff9/work/7dbddf33a51946cd97a94b6d718a5ff9_id_rsa
2026-04-14 02:57:21.329822 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/7dbddf33a51946cd97a94b6d718a5ff9/work/7dbddf33a51946cd97a94b6d718a5ff9_id_rsa.pub
2026-04-14 02:57:21.329855 | rockylinux-9 -> localhost | The key fingerprint is:
2026-04-14 02:57:21.329887 | rockylinux-9 -> localhost | SHA256:c08CTTEStSDos9T4QsKIS8gb5m0KQfFAm0iFGhSqtGE zuul-build-sshkey
2026-04-14 02:57:21.329942 | rockylinux-9 -> localhost | The key's randomart image is:
2026-04-14 02:57:21.329975 | rockylinux-9 -> localhost | +---[RSA 3072]----+
2026-04-14 02:57:21.330016 | rockylinux-9 -> localhost | |oB=. .. +o=.     |
2026-04-14 02:57:21.330049 | rockylinux-9 -> localhost | |=o= .  . = o     |
2026-04-14 02:57:21.330079 | rockylinux-9 -> localhost | |OE o o  . o      |
2026-04-14 02:57:21.330108 | rockylinux-9 -> localhost | |X== * .  .       |
2026-04-14 02:57:21.330137 | rockylinux-9 -> localhost | |=+++ +  S o .    |
2026-04-14 02:57:21.330166 | rockylinux-9 -> localhost | |oo oo .  o +     |
2026-04-14 02:57:21.330194 | rockylinux-9 -> localhost | |. o  .      .    |
2026-04-14 02:57:21.330222 | rockylinux-9 -> localhost | | .               |
2026-04-14 02:57:21.330255 | rockylinux-9 -> localhost | |                 |
2026-04-14 02:57:21.330286 | rockylinux-9 -> localhost | +----[SHA256]-----+
2026-04-14 02:57:21.330363 | rockylinux-9 -> localhost | ok: Runtime: 0:00:01.163738
2026-04-14 02:57:21.338023 | 
2026-04-14 02:57:21.338134 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-04-14 02:57:21.371317 | rockylinux-9 | ok
2026-04-14 02:57:21.386035 | rockylinux-9 | included: /var/lib/zuul/builds/7dbddf33a51946cd97a94b6d718a5ff9/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-04-14 02:57:21.400189 | 
2026-04-14 02:57:21.400346 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-04-14 02:57:21.416529 | rockylinux-9 | skipping: Conditional result was False
2026-04-14 02:57:21.424116 | 
2026-04-14 02:57:21.424265 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-04-14 02:57:21.941101 | rockylinux-9 | changed
2026-04-14 02:57:21.946450 | 
2026-04-14 02:57:21.946532 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-04-14 02:57:22.198885 | rockylinux-9 | ok
2026-04-14 02:57:22.204103 | 
2026-04-14 02:57:22.204170 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-04-14 02:57:23.146243 | rockylinux-9 | changed
2026-04-14 02:57:23.152302 | 
2026-04-14 02:57:23.152390 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-04-14 02:57:24.102978 | rockylinux-9 | changed
2026-04-14 02:57:24.107721 | 
2026-04-14 02:57:24.107784 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-04-14 02:57:24.131298 | rockylinux-9 | skipping: Conditional result was False
2026-04-14 02:57:24.135998 | 
2026-04-14 02:57:24.136066 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-04-14 02:57:24.680514 | rockylinux-9 -> localhost | changed
2026-04-14 02:57:24.698949 | 
2026-04-14 02:57:24.699108 | TASK [add-build-sshkey : Add back temp key]
2026-04-14 02:57:24.993983 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/7dbddf33a51946cd97a94b6d718a5ff9/work/7dbddf33a51946cd97a94b6d718a5ff9_id_rsa (zuul-build-sshkey)
2026-04-14 02:57:24.994162 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.014040
2026-04-14 02:57:25.010640 | 
2026-04-14 02:57:25.010805 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-04-14 02:57:25.400155 | rockylinux-9 | ok
2026-04-14 02:57:25.404716 | 
2026-04-14 02:57:25.404780 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-04-14 02:57:25.429707 | rockylinux-9 | skipping: Conditional result was False
2026-04-14 02:57:25.439724 | 
2026-04-14 02:57:25.439795 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-04-14 02:57:25.828709 | rockylinux-9 | ok
2026-04-14 02:57:25.835035 | 
2026-04-14 02:57:25.835100 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-04-14 02:57:26.748490 | rockylinux-9 | Output suppressed because no_log was given
2026-04-14 02:57:26.766060 | 
2026-04-14 02:57:26.766309 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-04-14 02:57:27.041662 | rockylinux-9 | ok: "logs"
2026-04-14 02:57:27.042027 | rockylinux-9 | ok: All items complete
2026-04-14 02:57:27.042069 | 
2026-04-14 02:57:27.265310 | rockylinux-9 | ok: "artifacts"
2026-04-14 02:57:27.484446 | rockylinux-9 | ok: "docs"
2026-04-14 02:57:27.492873 | 
2026-04-14 02:57:27.493065 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-04-14 02:57:27.761007 | rockylinux-9 | changed: "logs"
2026-04-14 02:57:27.992531 | rockylinux-9 | changed: "artifacts"
2026-04-14 02:57:28.244507 | rockylinux-9 | changed: "docs"
2026-04-14 02:57:28.260284 | 
2026-04-14 02:57:28.260419 | PLAY RECAP
2026-04-14 02:57:28.260480 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-14 02:57:28.260522 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-04-14 02:57:28.260554 | 
2026-04-14 02:57:28.397464 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-14 02:57:28.398631 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-14 02:57:29.003651 | 
2026-04-14 02:57:29.004156 | PLAY [all]
2026-04-14 02:57:29.014779 | 
2026-04-14 02:57:29.014855 | TASK [setup-uv : Extract archive]
2026-04-14 02:57:32.238886 | rockylinux-9 | changed
2026-04-14 02:57:32.245690 | 
2026-04-14 02:57:32.245786 | TASK [setup-uv : Print version]
2026-04-14 02:57:32.281499 | [rockylinux-9] Waiting on logger
2026-04-14 02:57:32.792810 | rockylinux-9 | ok: Runtime: 0:00:00.014812
2026-04-14 02:57:32.798558 | 
2026-04-14 02:57:32.798613 | PLAY RECAP
2026-04-14 02:57:32.798660 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-14 02:57:32.798683 | 
2026-04-14 02:57:32.928265 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-14 02:57:32.929562 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-04-14 02:57:33.577835 | 
2026-04-14 02:57:33.578349 | PLAY [all]
2026-04-14 02:57:33.589035 | 
2026-04-14 02:57:33.589124 | TASK [Copy inventory file for Zuul]
2026-04-14 02:57:34.899648 | rockylinux-9 | changed
2026-04-14 02:57:34.905078 | 
2026-04-14 02:57:34.905172 | TASK [Switch "ansible_host" to private IP]
2026-04-14 02:57:35.261414 | rockylinux-9 | changed: 1 replacements made
2026-04-14 02:57:35.268378 | 
2026-04-14 02:57:35.268481 | TASK [Run Molecule scenario]
2026-04-14 02:57:35.292069 | [rockylinux-9] Waiting on logger
2026-04-14 02:57:43.430303 | [rockylinux-9] Waiting on logger
2026-04-14 02:57:53.606114 | [rockylinux-9] Waiting on logger
2026-04-14 02:58:03.781912 | [rockylinux-9] Waiting on logger
2026-04-14 02:58:13.958193 | [rockylinux-9] Waiting on logger
2026-04-14 02:58:24.134048 | [rockylinux-9] Waiting on logger
2026-04-14 02:58:33.291559 | [rockylinux-9] Waiting on logger
2026-04-14 02:58:43.462097 | [rockylinux-9] Waiting on logger
2026-04-14 02:58:53.638084 | [rockylinux-9] Waiting on logger
2026-04-14 02:59:03.814425 | [rockylinux-9] Waiting on logger
2026-04-14 02:59:13.990030 | [rockylinux-9] Waiting on logger
2026-04-14 02:59:24.166302 | [rockylinux-9] Waiting on logger
2026-04-14 02:59:33.323495 | [rockylinux-9] Waiting on logger
2026-04-14 02:59:37.809829 | rockylinux-9 | ok: Runtime: 0:02:01.658270
2026-04-14 02:59:37.815651 | 
2026-04-14 02:59:37.815723 | PLAY RECAP
2026-04-14 02:59:37.815771 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-14 02:59:37.815794 | 
2026-04-14 02:59:37.938999 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-04-14 02:59:37.941851 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-14 02:59:38.517384 | 
2026-04-14 02:59:38.517544 | PLAY [all]
2026-04-14 02:59:38.528269 | 
2026-04-14 02:59:38.528351 | TASK [fetch-output : Set log path for multiple nodes]
2026-04-14 02:59:38.573031 | rockylinux-9 | skipping: Conditional result was False
2026-04-14 02:59:38.577782 | 
2026-04-14 02:59:38.577858 | TASK [fetch-output : Set log path for single node]
2026-04-14 02:59:38.618761 | rockylinux-9 | ok
2026-04-14 02:59:38.622893 | 
2026-04-14 02:59:38.622960 | LOOP [fetch-output : Ensure local output dirs]
2026-04-14 02:59:39.016105 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/7dbddf33a51946cd97a94b6d718a5ff9/work/logs"
2026-04-14 02:59:39.239918 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/7dbddf33a51946cd97a94b6d718a5ff9/work/artifacts"
2026-04-14 02:59:39.445528 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/7dbddf33a51946cd97a94b6d718a5ff9/work/docs"
2026-04-14 02:59:39.460697 | 
2026-04-14 02:59:39.460895 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-04-14 02:59:40.179746 | rockylinux-9 | changed: .d..t...... ./
2026-04-14 02:59:40.180069 | rockylinux-9 | changed: All items complete
2026-04-14 02:59:40.180099 | 
2026-04-14 02:59:40.702135 | rockylinux-9 | changed: .d..t...... ./
2026-04-14 02:59:41.203990 | rockylinux-9 | changed: .d..t...... ./
2026-04-14 02:59:41.225908 | 
2026-04-14 02:59:41.226035 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-04-14 02:59:41.668856 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.008395
2026-04-14 02:59:41.894113 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.008754
2026-04-14 02:59:41.915377 | 
2026-04-14 02:59:41.915539 | PLAY [all]
2026-04-14 02:59:41.921723 | 
2026-04-14 02:59:41.921787 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-04-14 02:59:42.396270 | rockylinux-9 | changed
2026-04-14 02:59:42.403405 | 
2026-04-14 02:59:42.403466 | PLAY RECAP
2026-04-14 02:59:42.403512 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-04-14 02:59:42.403533 | 
2026-04-14 02:59:42.547094 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-14 02:59:42.549361 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-04-14 02:59:43.145861 | 
2026-04-14 02:59:43.146012 | PLAY [localhost]
2026-04-14 02:59:43.156553 | 
2026-04-14 02:59:43.156631 | TASK [Generate Zuul manifest]
2026-04-14 02:59:43.180943 | localhost | ok
2026-04-14 02:59:43.197687 | 
2026-04-14 02:59:43.197775 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-04-14 02:59:43.514078 | localhost | changed
2026-04-14 02:59:43.528270 | 
2026-04-14 02:59:43.528370 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-04-14 02:59:43.567212 | localhost | ok
2026-04-14 02:59:43.573650 | 
2026-04-14 02:59:43.573718 | TASK [Upload logs]
2026-04-14 02:59:43.591895 | localhost | ok
2026-04-14 02:59:43.649525 | 
2026-04-14 02:59:43.649675 | TASK [Set zuul-log-path fact]
2026-04-14 02:59:43.682172 | localhost | ok
2026-04-14 02:59:43.694526 | 
2026-04-14 02:59:43.694598 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-14 02:59:43.727212 | localhost | ok
2026-04-14 02:59:43.735871 | 
2026-04-14 02:59:43.735940 | TASK [upload-logs : Create log directories]
2026-04-14 02:59:44.186566 | localhost | changed
2026-04-14 02:59:44.191587 | 
2026-04-14 02:59:44.191665 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-04-14 02:59:44.611075 | localhost -> localhost | ok: Runtime: 0:00:00.006596
2026-04-14 02:59:44.616307 | 
2026-04-14 02:59:44.616383 | TASK [upload-logs : Upload logs to log server]
2026-04-14 02:59:45.105207 | localhost | Output suppressed because no_log was given
2026-04-14 02:59:45.110387 | 
2026-04-14 02:59:45.110474 | LOOP [upload-logs : Compress console log and json output]
2026-04-14 02:59:45.157627 | localhost | skipping: Conditional result was False
2026-04-14 02:59:45.164984 | localhost | skipping: Conditional result was False
2026-04-14 02:59:45.176902 | 
2026-04-14 02:59:45.177145 | LOOP [upload-logs : Upload compressed console log and json output]
2026-04-14 02:59:45.223681 | localhost | skipping: Conditional result was False
2026-04-14 02:59:45.224405 | 
2026-04-14 02:59:45.227531 | localhost | skipping: Conditional result was False
2026-04-14 02:59:45.244735 | 
2026-04-14 02:59:45.244900 | LOOP [upload-logs : Upload console log and json output]