2026-04-20 02:13:08.960101 | Job console starting
2026-04-20 02:13:08.969304 | Updating git repos
2026-04-20 02:13:08.997204 | Cloning repos into workspace
2026-04-20 02:13:10.417536 | Restoring repo states
2026-04-20 02:13:10.438173 | Merging changes
2026-04-20 02:13:10.718737 | Checking out repos
2026-04-20 02:13:10.767227 | Preparing playbooks
2026-04-20 02:13:16.181645 | Running Ansible setup
2026-04-20 02:13:21.890939 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-20 02:13:22.535680 | 
2026-04-20 02:13:22.536121 | PLAY [localhost]
2026-04-20 02:13:22.545885 | 
2026-04-20 02:13:22.546036 | TASK [Gathering Facts]
2026-04-20 02:13:23.558465 | localhost | ok
2026-04-20 02:13:23.568739 | 
2026-04-20 02:13:23.568876 | TASK [Setup log path fact]
2026-04-20 02:13:23.588452 | localhost | ok
2026-04-20 02:13:23.606892 | 
2026-04-20 02:13:23.607156 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-20 02:13:23.639696 | localhost | ok
2026-04-20 02:13:23.650049 | 
2026-04-20 02:13:23.650185 | TASK [emit-job-header : Print job information]
2026-04-20 02:13:23.692837 | # Job Information
2026-04-20 02:13:23.713864 | Ansible Version: 2.16.16
2026-04-20 02:13:23.714006 | Job: ansible-collection-containers-molecule-nerdctl-rockylinux-9
2026-04-20 02:13:23.714044 | Pipeline: check
2026-04-20 02:13:23.714080 | Executor: 0a8996d2b663
2026-04-20 02:13:23.714113 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/108
2026-04-20 02:13:23.714145 | Event ID: d8bdab90-3c5d-11f1-9e83-70ae08c54185
2026-04-20 02:13:23.719193 | 
2026-04-20 02:13:23.719351 | LOOP [emit-job-header : Print node information]
2026-04-20 02:13:23.830191 | localhost | ok:
2026-04-20 02:13:23.830682 | localhost | # Node Information
2026-04-20 02:13:23.830729 | localhost | Inventory Hostname: rockylinux-9
2026-04-20 02:13:23.830854 | localhost | Hostname: np0000167919
2026-04-20 02:13:23.830897 | localhost | Username: zuul
2026-04-20 02:13:23.830931 | localhost | Distro: Rocky 9.7
2026-04-20 02:13:23.830959 | localhost | Provider: yul1
2026-04-20 02:13:23.830985 | localhost | Region: ca-ymq-1
2026-04-20 02:13:23.831198 | localhost | Label: rockylinux-9
2026-04-20 02:13:23.831259 | localhost | Product Name: OpenStack Nova
2026-04-20 02:13:23.831291 | localhost | Interface IP: 199.19.213.43
2026-04-20 02:13:23.850615 | 
2026-04-20 02:13:23.850812 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-04-20 02:13:24.315306 | localhost -> localhost | changed
2026-04-20 02:13:24.320872 | 
2026-04-20 02:13:24.321187 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-04-20 02:13:25.393161 | localhost -> localhost | changed
2026-04-20 02:13:25.400023 | 
2026-04-20 02:13:25.400113 | PLAY [all]
2026-04-20 02:13:25.416494 | 
2026-04-20 02:13:25.416693 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-04-20 02:13:25.695916 | rockylinux-9 -> localhost | ok
2026-04-20 02:13:25.701457 | 
2026-04-20 02:13:25.701545 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-04-20 02:13:25.731468 | rockylinux-9 | ok
2026-04-20 02:13:25.760334 | rockylinux-9 | included: /var/lib/zuul/builds/b885b82110ba4c7289650ba31d4b1c1d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-04-20 02:13:25.766037 | 
2026-04-20 02:13:25.766122 | TASK [add-build-sshkey : Create Temp SSH key]
2026-04-20 02:13:26.998851 | rockylinux-9 -> localhost | Generating public/private rsa key pair.
2026-04-20 02:13:26.999070 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/b885b82110ba4c7289650ba31d4b1c1d/work/b885b82110ba4c7289650ba31d4b1c1d_id_rsa
2026-04-20 02:13:26.999115 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/b885b82110ba4c7289650ba31d4b1c1d/work/b885b82110ba4c7289650ba31d4b1c1d_id_rsa.pub
2026-04-20 02:13:26.999153 | rockylinux-9 -> localhost | The key fingerprint is:
2026-04-20 02:13:26.999187 | rockylinux-9 -> localhost | SHA256:91j22ctysT0b+vcZ8NF9WczVSdnd+hNHRd74yx00HKw zuul-build-sshkey
2026-04-20 02:13:26.999234 | rockylinux-9 -> localhost | The key's randomart image is:
2026-04-20 02:13:26.999268 | rockylinux-9 -> localhost | +---[RSA 3072]----+
2026-04-20 02:13:26.999313 | rockylinux-9 -> localhost | |              o+%|
2026-04-20 02:13:26.999347 | rockylinux-9 -> localhost | |              .OX|
2026-04-20 02:13:26.999370 | rockylinux-9 -> localhost | |              o**|
2026-04-20 02:13:26.999400 | rockylinux-9 -> localhost | |             EooB|
2026-04-20 02:13:26.999429 | rockylinux-9 -> localhost | |        S . o. =B|
2026-04-20 02:13:26.999455 | rockylinux-9 -> localhost | |         . = .+=B|
2026-04-20 02:13:26.999482 | rockylinux-9 -> localhost | |          . . o*B|
2026-04-20 02:13:26.999509 | rockylinux-9 -> localhost | |             .o+B|
2026-04-20 02:13:26.999541 | rockylinux-9 -> localhost | |             .+**|
2026-04-20 02:13:26.999569 | rockylinux-9 -> localhost | +----[SHA256]-----+
2026-04-20 02:13:26.999639 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.739114
2026-04-20 02:13:27.005516 | 
2026-04-20 02:13:27.005643 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-04-20 02:13:27.037489 | rockylinux-9 | ok
2026-04-20 02:13:27.054074 | rockylinux-9 | included: /var/lib/zuul/builds/b885b82110ba4c7289650ba31d4b1c1d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-04-20 02:13:27.069360 | 
2026-04-20 02:13:27.069502 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-04-20 02:13:27.095463 | rockylinux-9 | skipping: Conditional result was False
2026-04-20 02:13:27.106845 | 
2026-04-20 02:13:27.107096 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-04-20 02:13:27.624920 | rockylinux-9 | changed
2026-04-20 02:13:27.632755 | 
2026-04-20 02:13:27.632872 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-04-20 02:13:27.924059 | rockylinux-9 | ok
2026-04-20 02:13:27.937942 | 
2026-04-20 02:13:27.938119 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-04-20 02:13:28.937051 | rockylinux-9 | changed
2026-04-20 02:13:28.945106 | 
2026-04-20 02:13:28.945226 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-04-20 02:13:29.895963 | rockylinux-9 | changed
2026-04-20 02:13:29.903221 | 
2026-04-20 02:13:29.903334 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-04-20 02:13:29.929504 | rockylinux-9 | skipping: Conditional result was False
2026-04-20 02:13:29.938763 | 
2026-04-20 02:13:29.938992 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-04-20 02:13:32.124856 | rockylinux-9 -> localhost | changed
2026-04-20 02:13:32.137988 | 
2026-04-20 02:13:32.138176 | TASK [add-build-sshkey : Add back temp key]
2026-04-20 02:13:32.470215 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/b885b82110ba4c7289650ba31d4b1c1d/work/b885b82110ba4c7289650ba31d4b1c1d_id_rsa (zuul-build-sshkey)
2026-04-20 02:13:32.470464 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.012482
2026-04-20 02:13:32.479927 | 
2026-04-20 02:13:32.480365 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-04-20 02:13:32.913668 | rockylinux-9 | ok
2026-04-20 02:13:32.920160 | 
2026-04-20 02:13:32.920300 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-04-20 02:13:32.956440 | rockylinux-9 | skipping: Conditional result was False
2026-04-20 02:13:32.976255 | 
2026-04-20 02:13:32.976456 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-04-20 02:13:33.417038 | rockylinux-9 | ok
2026-04-20 02:13:33.424941 | 
2026-04-20 02:13:33.425098 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-04-20 02:13:34.530922 | rockylinux-9 | Output suppressed because no_log was given
2026-04-20 02:13:34.544704 | 
2026-04-20 02:13:34.544839 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-04-20 02:13:34.813028 | rockylinux-9 | ok: "logs"
2026-04-20 02:13:35.029843 | rockylinux-9 | ok: All items complete
2026-04-20 02:13:35.030004 | 
2026-04-20 02:13:35.043933 | rockylinux-9 | ok: "artifacts"
2026-04-20 02:13:35.278045 | rockylinux-9 | ok: "docs"
2026-04-20 02:13:35.286277 | 
2026-04-20 02:13:35.286426 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-04-20 02:13:35.539167 | rockylinux-9 | changed: "logs"
2026-04-20 02:13:35.800232 | rockylinux-9 | changed: "artifacts"
2026-04-20 02:13:36.037571 | rockylinux-9 | changed: "docs"
2026-04-20 02:13:36.059941 | 
2026-04-20 02:13:36.060068 | PLAY RECAP
2026-04-20 02:13:36.060114 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-20 02:13:36.060145 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-04-20 02:13:36.060166 | 
2026-04-20 02:13:36.274820 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-20 02:13:36.277659 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-20 02:13:36.976928 | 
2026-04-20 02:13:36.977110 | PLAY [all]
2026-04-20 02:13:37.004837 | 
2026-04-20 02:13:37.005070 | TASK [setup-uv : Extract archive]
2026-04-20 02:13:39.793279 | rockylinux-9 | changed
2026-04-20 02:13:39.798741 | 
2026-04-20 02:13:39.798825 | TASK [setup-uv : Print version]
2026-04-20 02:13:39.822032 | [rockylinux-9] Waiting on logger
2026-04-20 02:13:40.351376 | rockylinux-9 | ok: Runtime: 0:00:00.015555
2026-04-20 02:13:40.357499 | 
2026-04-20 02:13:40.357568 | PLAY RECAP
2026-04-20 02:13:40.357618 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-20 02:13:40.357643 | 
2026-04-20 02:13:40.514796 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-20 02:13:40.516924 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-04-20 02:13:41.226688 | 
2026-04-20 02:13:41.227212 | PLAY [all]
2026-04-20 02:13:41.250725 | 
2026-04-20 02:13:41.250930 | TASK [Copy inventory file for Zuul]
2026-04-20 02:13:42.656086 | rockylinux-9 | changed
2026-04-20 02:13:42.660952 | 
2026-04-20 02:13:42.661114 | TASK [Switch "ansible_host" to private IP]
2026-04-20 02:13:43.037974 | rockylinux-9 | changed: 1 replacements made
2026-04-20 02:13:43.048360 | 
2026-04-20 02:13:43.048538 | TASK [Run Molecule scenario]
2026-04-20 02:13:43.070988 | [rockylinux-9] Waiting on logger
2026-04-20 02:13:51.206231 | [rockylinux-9] Waiting on logger
2026-04-20 02:14:01.382047 | [rockylinux-9] Waiting on logger
2026-04-20 02:14:11.562012 | [rockylinux-9] Waiting on logger
2026-04-20 02:14:21.737862 | [rockylinux-9] Waiting on logger
2026-04-20 02:14:30.923190 | [rockylinux-9] Waiting on logger
2026-04-20 02:14:41.094445 | [rockylinux-9] Waiting on logger
2026-04-20 02:14:51.274344 | [rockylinux-9] Waiting on logger
2026-04-20 02:15:01.446421 | [rockylinux-9] Waiting on logger
2026-04-20 02:15:11.621825 | [rockylinux-9] Waiting on logger
2026-04-20 02:15:21.797880 | [rockylinux-9] Waiting on logger
2026-04-20 02:15:26.382838 | rockylinux-9 | ok: Runtime: 0:01:42.785861
2026-04-20 02:15:26.390141 | 
2026-04-20 02:15:26.390279 | PLAY RECAP
2026-04-20 02:15:26.390339 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-20 02:15:26.390371 | 
2026-04-20 02:15:26.557786 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-04-20 02:15:26.559162 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-20 02:15:27.260255 | 
2026-04-20 02:15:27.260437 | PLAY [all]
2026-04-20 02:15:27.274312 | 
2026-04-20 02:15:27.274513 | TASK [fetch-output : Set log path for multiple nodes]
2026-04-20 02:15:27.320998 | rockylinux-9 | skipping: Conditional result was False
2026-04-20 02:15:27.329750 | 
2026-04-20 02:15:27.329940 | TASK [fetch-output : Set log path for single node]
2026-04-20 02:15:27.373021 | rockylinux-9 | ok
2026-04-20 02:15:27.380454 | 
2026-04-20 02:15:27.380557 | LOOP [fetch-output : Ensure local output dirs]
2026-04-20 02:15:27.854765 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/b885b82110ba4c7289650ba31d4b1c1d/work/logs"
2026-04-20 02:15:28.140312 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/b885b82110ba4c7289650ba31d4b1c1d/work/artifacts"
2026-04-20 02:15:28.386892 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/b885b82110ba4c7289650ba31d4b1c1d/work/docs"
2026-04-20 02:15:28.403083 | 
2026-04-20 02:15:28.403323 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-04-20 02:15:29.222667 | rockylinux-9 | changed: .d..t...... ./
2026-04-20 02:15:29.222923 | rockylinux-9 | changed: All items complete
2026-04-20 02:15:29.222963 | 
2026-04-20 02:15:29.736402 | rockylinux-9 | changed: .d..t...... ./
2026-04-20 02:15:30.296574 | rockylinux-9 | changed: .d..t...... ./
2026-04-20 02:15:30.340261 | 
2026-04-20 02:15:30.340509 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-04-20 02:15:30.842811 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.006448
2026-04-20 02:15:31.111920 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.007985
2026-04-20 02:15:31.125852 | 
2026-04-20 02:15:31.126019 | PLAY [all]
2026-04-20 02:15:31.133527 | 
2026-04-20 02:15:31.133617 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-04-20 02:15:31.949964 | rockylinux-9 | changed
2026-04-20 02:15:31.956806 | 
2026-04-20 02:15:31.956865 | PLAY RECAP
2026-04-20 02:15:31.956915 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-04-20 02:15:31.956940 | 
2026-04-20 02:15:32.111481 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-20 02:15:32.113204 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-04-20 02:15:32.772571 | 
2026-04-20 02:15:32.792601 | PLAY [localhost]
2026-04-20 02:15:32.815554 | 
2026-04-20 02:15:32.815744 | TASK [Generate Zuul manifest]
2026-04-20 02:15:32.839143 | localhost | ok
2026-04-20 02:15:32.858444 | 
2026-04-20 02:15:32.858626 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-04-20 02:15:33.228866 | localhost | changed
2026-04-20 02:15:33.245530 | 
2026-04-20 02:15:33.245700 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-04-20 02:15:33.319124 | localhost | ok
2026-04-20 02:15:33.348587 | 
2026-04-20 02:15:33.348774 | TASK [Upload logs]
2026-04-20 02:15:33.371161 | localhost | ok
2026-04-20 02:15:33.439198 | 
2026-04-20 02:15:33.439336 | TASK [Set zuul-log-path fact]
2026-04-20 02:15:33.460604 | localhost | ok
2026-04-20 02:15:33.475552 | 
2026-04-20 02:15:33.475692 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-20 02:15:33.508063 | localhost | ok
2026-04-20 02:15:33.518450 | 
2026-04-20 02:15:33.518758 | TASK [upload-logs : Create log directories]
2026-04-20 02:15:34.042307 | localhost | changed
2026-04-20 02:15:34.048334 | 
2026-04-20 02:15:34.048401 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-04-20 02:15:34.558678 | localhost -> localhost | ok: Runtime: 0:00:00.006819
2026-04-20 02:15:34.563623 | 
2026-04-20 02:15:34.564317 | TASK [upload-logs : Upload logs to log server]
2026-04-20 02:15:35.131829 | localhost | Output suppressed because no_log was given
2026-04-20 02:15:35.135943 | 
2026-04-20 02:15:35.136019 | LOOP [upload-logs : Compress console log and json output]
2026-04-20 02:15:35.188006 | localhost | skipping: Conditional result was False
2026-04-20 02:15:35.197509 | localhost | skipping: Conditional result was False
2026-04-20 02:15:35.208983 | 
2026-04-20 02:15:35.209177 | LOOP [upload-logs : Upload compressed console log and json output]
2026-04-20 02:15:35.259226 | localhost | skipping: Conditional result was False
2026-04-20 02:15:35.259556 | 
2026-04-20 02:15:35.264610 | localhost | skipping: Conditional result was False
2026-04-20 02:15:35.276432 | 
2026-04-20 02:15:35.276835 | LOOP [upload-logs : Upload console log and json output]