2026-04-01 00:58:06.473529 | Job console starting
2026-04-01 00:58:06.501529 | Updating git repos
2026-04-01 00:58:06.534568 | Cloning repos into workspace
2026-04-01 00:58:06.588589 | Restoring repo states
2026-04-01 00:58:06.598682 | Merging changes
2026-04-01 00:58:06.844054 | Checking out repos
2026-04-01 00:58:06.888021 | Preparing playbooks
2026-04-01 00:58:08.904541 | Running Ansible setup
2026-04-01 00:58:12.443099 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-01 00:58:13.036797 | 
2026-04-01 00:58:13.038075 | PLAY [localhost]
2026-04-01 00:58:13.045904 | 
2026-04-01 00:58:13.046000 | TASK [Gathering Facts]
2026-04-01 00:58:14.025222 | localhost | ok
2026-04-01 00:58:14.034982 | 
2026-04-01 00:58:14.035081 | TASK [Setup log path fact]
2026-04-01 00:58:14.055568 | localhost | ok
2026-04-01 00:58:14.067324 | 
2026-04-01 00:58:14.067393 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-01 00:58:14.095764 | localhost | ok
2026-04-01 00:58:14.103079 | 
2026-04-01 00:58:14.103153 | TASK [emit-job-header : Print job information]
2026-04-01 00:58:14.132841 | # Job Information
2026-04-01 00:58:14.133065 | Ansible Version: 2.16.16
2026-04-01 00:58:14.133137 | Job: ansible-collection-containers-molecule-docker-rockylinux-9
2026-04-01 00:58:14.133187 | Pipeline: check
2026-04-01 00:58:14.133232 | Executor: 0a8996d2b663
2026-04-01 00:58:14.133274 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/105
2026-04-01 00:58:14.133323 | Event ID: ba6bf4a0-2d65-11f1-99f2-ed7bf96431dc
2026-04-01 00:58:14.137523 | 
2026-04-01 00:58:14.137606 | LOOP [emit-job-header : Print node information]
2026-04-01 00:58:14.219328 | localhost | ok:
2026-04-01 00:58:14.219851 | localhost | # Node Information
2026-04-01 00:58:14.219883 | localhost | Inventory Hostname: rockylinux-9
2026-04-01 00:58:14.219904 | localhost | Hostname: np0000162039
2026-04-01 00:58:14.219924 | localhost | Username: zuul
2026-04-01 00:58:14.219946 | localhost | Distro: Rocky 9.7
2026-04-01 00:58:14.219966 | localhost | Provider: yul1
2026-04-01 00:58:14.219985 | localhost | Region: ca-ymq-1
2026-04-01 00:58:14.220004 | localhost | Label: rockylinux-9
2026-04-01 00:58:14.220022 | localhost | Product Name: OpenStack Nova
2026-04-01 00:58:14.220041 | localhost | Interface IP: 199.204.45.132
2026-04-01 00:58:14.232154 | 
2026-04-01 00:58:14.232277 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-04-01 00:58:14.631140 | localhost -> localhost | changed
2026-04-01 00:58:14.638332 | 
2026-04-01 00:58:14.638424 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-04-01 00:58:15.540857 | localhost -> localhost | changed
2026-04-01 00:58:15.547459 | 
2026-04-01 00:58:15.547524 | PLAY [all]
2026-04-01 00:58:15.555361 | 
2026-04-01 00:58:15.555430 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-04-01 00:58:15.874379 | rockylinux-9 -> localhost | ok
2026-04-01 00:58:15.882148 | 
2026-04-01 00:58:15.882223 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-04-01 00:58:15.909983 | rockylinux-9 | ok
2026-04-01 00:58:15.924579 | rockylinux-9 | included: /var/lib/zuul/builds/db98c66528404d0980359d5a3ff986b7/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-04-01 00:58:15.929722 | 
2026-04-01 00:58:15.929785 | TASK [add-build-sshkey : Create Temp SSH key]
2026-04-01 00:58:17.485187 | rockylinux-9 -> localhost | Generating public/private rsa key pair.
2026-04-01 00:58:17.485345 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/db98c66528404d0980359d5a3ff986b7/work/db98c66528404d0980359d5a3ff986b7_id_rsa
2026-04-01 00:58:17.485378 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/db98c66528404d0980359d5a3ff986b7/work/db98c66528404d0980359d5a3ff986b7_id_rsa.pub
2026-04-01 00:58:17.485401 | rockylinux-9 -> localhost | The key fingerprint is:
2026-04-01 00:58:17.485423 | rockylinux-9 -> localhost | SHA256:+GFZPfloSl3vg3WvvXmAaQlZZ6qhMYDFskDKaV3aFhQ zuul-build-sshkey
2026-04-01 00:58:17.485456 | rockylinux-9 -> localhost | The key's randomart image is:
2026-04-01 00:58:17.485478 | rockylinux-9 -> localhost | +---[RSA 3072]----+
2026-04-01 00:58:17.485502 | rockylinux-9 -> localhost | | .. .Eo          |
2026-04-01 00:58:17.485523 | rockylinux-9 -> localhost | |..+ * +    ...o  |
2026-04-01 00:58:17.485544 | rockylinux-9 -> localhost | |.+ + = .  .o++.  |
2026-04-01 00:58:17.485565 | rockylinux-9 -> localhost | |.   o  .oo+..= . |
2026-04-01 00:58:17.485585 | rockylinux-9 -> localhost | |      . S+.+++..o|
2026-04-01 00:58:17.485605 | rockylinux-9 -> localhost | |       o.o.o= +.o|
2026-04-01 00:58:17.485624 | rockylinux-9 -> localhost | |        . .. . oo|
2026-04-01 00:58:17.485644 | rockylinux-9 -> localhost | |               o+|
2026-04-01 00:58:17.485666 | rockylinux-9 -> localhost | |              .o+|
2026-04-01 00:58:17.485686 | rockylinux-9 -> localhost | +----[SHA256]-----+
2026-04-01 00:58:17.485732 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.951495
2026-04-01 00:58:17.490720 | 
2026-04-01 00:58:17.490792 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-04-01 00:58:17.521526 | rockylinux-9 | ok
2026-04-01 00:58:17.530789 | rockylinux-9 | included: /var/lib/zuul/builds/db98c66528404d0980359d5a3ff986b7/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-04-01 00:58:17.538768 | 
2026-04-01 00:58:17.538832 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-04-01 00:58:17.552694 | rockylinux-9 | skipping: Conditional result was False
2026-04-01 00:58:17.562806 | 
2026-04-01 00:58:17.562870 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-04-01 00:58:18.029186 | rockylinux-9 | changed
2026-04-01 00:58:18.042518 | 
2026-04-01 00:58:18.042592 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-04-01 00:58:18.334374 | rockylinux-9 | ok
2026-04-01 00:58:18.339113 | 
2026-04-01 00:58:18.339175 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-04-01 00:58:19.273001 | rockylinux-9 | changed
2026-04-01 00:58:19.277496 | 
2026-04-01 00:58:19.286766 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-04-01 00:58:20.170107 | rockylinux-9 | changed
2026-04-01 00:58:20.175082 | 
2026-04-01 00:58:20.176401 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-04-01 00:58:20.201844 | rockylinux-9 | skipping: Conditional result was False
2026-04-01 00:58:20.209267 | 
2026-04-01 00:58:20.209365 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-04-01 00:58:20.619363 | rockylinux-9 -> localhost | changed
2026-04-01 00:58:20.633444 | 
2026-04-01 00:58:20.633533 | TASK [add-build-sshkey : Add back temp key]
2026-04-01 00:58:20.908892 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/db98c66528404d0980359d5a3ff986b7/work/db98c66528404d0980359d5a3ff986b7_id_rsa (zuul-build-sshkey)
2026-04-01 00:58:20.909138 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.014570
2026-04-01 00:58:20.917192 | 
2026-04-01 00:58:20.917259 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-04-01 00:58:21.277439 | rockylinux-9 | ok
2026-04-01 00:58:21.288377 | 
2026-04-01 00:58:21.288668 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-04-01 00:58:21.326294 | rockylinux-9 | skipping: Conditional result was False
2026-04-01 00:58:21.341254 | 
2026-04-01 00:58:21.426729 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-04-01 00:58:21.817414 | rockylinux-9 | ok
2026-04-01 00:58:21.828221 | 
2026-04-01 00:58:21.828472 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-04-01 00:58:22.852780 | rockylinux-9 | Output suppressed because no_log was given
2026-04-01 00:58:22.908040 | 
2026-04-01 00:58:22.908170 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-04-01 00:58:23.178854 | rockylinux-9 | ok: "logs"
2026-04-01 00:58:23.179186 | rockylinux-9 | ok: All items complete
2026-04-01 00:58:23.179229 | 
2026-04-01 00:58:23.384172 | rockylinux-9 | ok: "artifacts"
2026-04-01 00:58:23.604086 | rockylinux-9 | ok: "docs"
2026-04-01 00:58:23.620662 | 
2026-04-01 00:58:23.707834 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-04-01 00:58:24.010419 | rockylinux-9 | changed: "logs"
2026-04-01 00:58:24.233563 | rockylinux-9 | changed: "artifacts"
2026-04-01 00:58:24.450948 | rockylinux-9 | changed: "docs"
2026-04-01 00:58:24.492381 | 
2026-04-01 00:58:24.492519 | PLAY RECAP
2026-04-01 00:58:24.492589 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-01 00:58:24.492626 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-04-01 00:58:24.492648 | 
2026-04-01 00:58:24.740386 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-01 00:58:24.752265 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-01 00:58:25.377439 | 
2026-04-01 00:58:25.377609 | PLAY [all]
2026-04-01 00:58:25.389772 | 
2026-04-01 00:58:25.389857 | TASK [setup-uv : Extract archive]
2026-04-01 00:58:28.233593 | rockylinux-9 | changed
2026-04-01 00:58:28.241108 | 
2026-04-01 00:58:28.241225 | TASK [setup-uv : Print version]
2026-04-01 00:58:28.263929 | [rockylinux-9] Waiting on logger
2026-04-01 00:58:28.778182 | rockylinux-9 | ok: Runtime: 0:00:00.014402
2026-04-01 00:58:28.799149 | 
2026-04-01 00:58:28.810102 | PLAY RECAP
2026-04-01 00:58:28.810278 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-01 00:58:28.810372 | 
2026-04-01 00:58:28.906521 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-01 00:58:28.909283 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-04-01 00:58:29.452189 | 
2026-04-01 00:58:29.506538 | PLAY [all]
2026-04-01 00:58:29.521824 | 
2026-04-01 00:58:29.521902 | TASK [Copy inventory file for Zuul]
2026-04-01 00:58:30.785556 | rockylinux-9 | changed
2026-04-01 00:58:30.798883 | 
2026-04-01 00:58:30.799023 | TASK [Switch "ansible_host" to private IP]
2026-04-01 00:58:31.138818 | rockylinux-9 | changed: 1 replacements made
2026-04-01 00:58:31.144147 | 
2026-04-01 00:58:31.144226 | TASK [Run Molecule scenario]
2026-04-01 00:58:31.166224 | [rockylinux-9] Waiting on logger
2026-04-01 00:58:39.311289 | [rockylinux-9] Waiting on logger
2026-04-01 00:58:49.477900 | [rockylinux-9] Waiting on logger
2026-04-01 00:58:59.653997 | [rockylinux-9] Waiting on logger
2026-04-01 00:59:09.830093 | [rockylinux-9] Waiting on logger
2026-04-01 00:59:20.006224 | [rockylinux-9] Waiting on logger
2026-04-01 00:59:30.182338 | [rockylinux-9] Waiting on logger
2026-04-01 00:59:39.339812 | [rockylinux-9] Waiting on logger
2026-04-01 00:59:49.514084 | [rockylinux-9] Waiting on logger
2026-04-01 00:59:59.686290 | [rockylinux-9] Waiting on logger
2026-04-01 01:00:09.861850 | [rockylinux-9] Waiting on logger
2026-04-01 01:00:20.037815 | [rockylinux-9] Waiting on logger
2026-04-01 01:00:30.213781 | [rockylinux-9] Waiting on logger
2026-04-01 01:00:39.371945 | [rockylinux-9] Waiting on logger
2026-04-01 01:00:49.542081 | [rockylinux-9] Waiting on logger
2026-04-01 01:00:51.795916 | rockylinux-9 | ok: Runtime: 0:02:19.374598
2026-04-01 01:00:51.802702 | 
2026-04-01 01:00:51.802750 | PLAY RECAP
2026-04-01 01:00:51.802845 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-01 01:00:51.802878 | 
2026-04-01 01:00:51.899119 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-04-01 01:00:51.901404 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-01 01:00:52.447002 | 
2026-04-01 01:00:52.447142 | PLAY [all]
2026-04-01 01:00:52.457542 | 
2026-04-01 01:00:52.457617 | TASK [fetch-output : Set log path for multiple nodes]
2026-04-01 01:00:52.501733 | rockylinux-9 | skipping: Conditional result was False
2026-04-01 01:00:52.507962 | 
2026-04-01 01:00:52.508071 | TASK [fetch-output : Set log path for single node]
2026-04-01 01:00:52.552895 | rockylinux-9 | ok
2026-04-01 01:00:52.559366 | 
2026-04-01 01:00:52.559436 | LOOP [fetch-output : Ensure local output dirs]
2026-04-01 01:00:52.915663 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/db98c66528404d0980359d5a3ff986b7/work/logs"
2026-04-01 01:00:53.113632 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/db98c66528404d0980359d5a3ff986b7/work/artifacts"
2026-04-01 01:00:53.335678 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/db98c66528404d0980359d5a3ff986b7/work/docs"
2026-04-01 01:00:53.351279 | 
2026-04-01 01:00:53.351374 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-04-01 01:00:54.047082 | rockylinux-9 | ok
2026-04-01 01:00:54.162963 | rockylinux-9 | changed: All items complete
2026-04-01 01:00:54.163069 | 
2026-04-01 01:00:54.575449 | rockylinux-9 | changed: .d..t...... ./
2026-04-01 01:00:55.113609 | rockylinux-9 | changed: .d..t...... ./
2026-04-01 01:00:55.136061 | 
2026-04-01 01:00:55.136148 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-04-01 01:00:55.644538 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.007635
2026-04-01 01:00:55.873658 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.008555
2026-04-01 01:00:55.892924 | 
2026-04-01 01:00:55.893064 | PLAY [all]
2026-04-01 01:00:55.899729 | 
2026-04-01 01:00:55.899793 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-04-01 01:00:56.328446 | rockylinux-9 | changed
2026-04-01 01:00:56.334496 | 
2026-04-01 01:00:56.334545 | PLAY RECAP
2026-04-01 01:00:56.334590 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-04-01 01:00:56.334612 | 
2026-04-01 01:00:56.437631 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-01 01:00:56.439967 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-04-01 01:00:56.964422 | 
2026-04-01 01:00:56.964627 | PLAY [localhost]
2026-04-01 01:00:56.973961 | 
2026-04-01 01:00:56.974028 | TASK [Generate Zuul manifest]
2026-04-01 01:00:56.993784 | localhost | ok
2026-04-01 01:00:57.010770 | 
2026-04-01 01:00:57.010849 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-04-01 01:00:57.330466 | localhost | changed
2026-04-01 01:00:57.340808 | 
2026-04-01 01:00:57.340883 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-04-01 01:00:57.368763 | localhost | ok
2026-04-01 01:00:57.377393 | 
2026-04-01 01:00:57.377455 | TASK [Upload logs]
2026-04-01 01:00:57.394640 | localhost | ok
2026-04-01 01:00:57.451011 | 
2026-04-01 01:00:57.451118 | TASK [Set zuul-log-path fact]
2026-04-01 01:00:57.468869 | localhost | ok
2026-04-01 01:00:57.480520 | 
2026-04-01 01:00:57.480585 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-01 01:00:57.507895 | localhost | ok
2026-04-01 01:00:57.517712 | 
2026-04-01 01:00:57.517799 | TASK [upload-logs : Create log directories]
2026-04-01 01:00:57.907792 | localhost | changed
2026-04-01 01:00:57.913409 | 
2026-04-01 01:00:57.913488 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-04-01 01:00:58.308116 | localhost -> localhost | ok: Runtime: 0:00:00.005421
2026-04-01 01:00:58.315097 | 
2026-04-01 01:00:58.315164 | TASK [upload-logs : Upload logs to log server]
2026-04-01 01:00:58.850278 | localhost | Output suppressed because no_log was given
2026-04-01 01:00:58.855244 | 
2026-04-01 01:00:58.855308 | LOOP [upload-logs : Compress console log and json output]
2026-04-01 01:00:58.899212 | localhost | skipping: Conditional result was False
2026-04-01 01:00:58.906027 | localhost | skipping: Conditional result was False
2026-04-01 01:00:58.918707 | 
2026-04-01 01:00:58.918950 | LOOP [upload-logs : Upload compressed console log and json output]
2026-04-01 01:00:58.953918 | localhost | skipping: Conditional result was False
2026-04-01 01:00:58.954164 | 
2026-04-01 01:00:58.958456 | localhost | skipping: Conditional result was False
2026-04-01 01:00:58.968318 | 
2026-04-01 01:00:58.968441 | LOOP [upload-logs : Upload console log and json output]