2026-10-14 00:55:22.307379 | Job console starting
2026-10-14 00:55:22.323385 | Updating git repos
2026-10-14 00:55:22.354614 | Cloning repos into workspace
2026-10-14 00:55:22.395271 | Restoring repo states
2026-10-14 00:55:22.401924 | Merging changes
2026-10-14 00:55:22.969082 | Checking out repos
2026-10-14 00:55:23.009764 | Preparing playbooks
2026-10-14 00:55:24.084983 | Running Ansible setup
2026-10-14 00:55:28.779038 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-10-14 00:55:29.345268 | 
2026-10-14 00:55:29.345401 | PLAY [localhost]
2026-10-14 00:55:29.352119 | 
2026-10-14 00:55:29.352184 | TASK [Gathering Facts]
2026-10-14 00:55:30.321464 | localhost | ok
2026-10-14 00:55:30.335022 | 
2026-10-14 00:55:30.335128 | TASK [Setup log path fact]
2026-10-14 00:55:30.365943 | localhost | ok
2026-10-14 00:55:30.384547 | 
2026-10-14 00:55:30.384685 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-10-14 00:55:30.427219 | localhost | ok
2026-10-14 00:55:30.439805 | 
2026-10-14 00:55:30.439982 | TASK [emit-job-header : Print job information]
2026-10-14 00:55:30.486028 | # Job Information
2026-10-14 00:55:30.486425 | Ansible Version: 2.16.18
2026-10-14 00:55:30.486521 | Job: pre-commit
2026-10-14 00:55:30.486590 | Pipeline: check
2026-10-14 00:55:30.486679 | Executor: 2d72f0692154
2026-10-14 00:55:30.486742 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/149
2026-10-14 00:55:30.486810 | Event ID: e0ff7400-c768-11f1-965c-c0e2fa2e1b2b
2026-10-14 00:55:30.495550 | 
2026-10-14 00:55:30.495789 | LOOP [emit-job-header : Print node information]
2026-10-14 00:55:30.640469 | localhost | ok:
2026-10-14 00:55:30.640851 | localhost | # Node Information
2026-10-14 00:55:30.640927 | localhost | Inventory Hostname: ubuntu-noble
2026-10-14 00:55:30.640983 | localhost | Hostname: np0000195492
2026-10-14 00:55:30.641035 | localhost | Username: zuul
2026-10-14 00:55:30.641093 | localhost | Distro: Ubuntu 24.04
2026-10-14 00:55:30.641137 | localhost | Provider: yul1
2026-10-14 00:55:30.641174 | localhost | Region: ca-ymq-1
2026-10-14 00:55:30.641210 | localhost | Label: ubuntu-noble
2026-10-14 00:55:30.641244 | localhost | Product Name: OpenStack Nova
2026-10-14 00:55:30.641279 | localhost | Interface IP: 162.253.55.92
2026-10-14 00:55:30.659721 | 
2026-10-14 00:55:30.659966 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-10-14 00:55:31.092306 | localhost -> localhost | changed
2026-10-14 00:55:31.099398 | 
2026-10-14 00:55:31.099670 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-10-14 00:55:31.992937 | localhost -> localhost | changed
2026-10-14 00:55:32.006579 | 
2026-10-14 00:55:32.006783 | PLAY [all]
2026-10-14 00:55:32.024299 | 
2026-10-14 00:55:32.024433 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-10-14 00:55:32.295315 | ubuntu-noble -> localhost | ok
2026-10-14 00:55:32.328490 | 
2026-10-14 00:55:32.328850 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-10-14 00:55:32.371690 | ubuntu-noble | ok
2026-10-14 00:55:32.394275 | ubuntu-noble | included: /var/lib/zuul/builds/67e0484fc4074f75bc292f7d73ece2e3/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-10-14 00:55:32.402196 | 
2026-10-14 00:55:32.402275 | TASK [add-build-sshkey : Create Temp SSH key]
2026-10-14 00:55:33.383309 | ubuntu-noble -> localhost | Generating public/private rsa key pair.
2026-10-14 00:55:33.383534 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/67e0484fc4074f75bc292f7d73ece2e3/work/67e0484fc4074f75bc292f7d73ece2e3_id_rsa
2026-10-14 00:55:33.383583 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/67e0484fc4074f75bc292f7d73ece2e3/work/67e0484fc4074f75bc292f7d73ece2e3_id_rsa.pub
2026-10-14 00:55:33.383615 | ubuntu-noble -> localhost | The key fingerprint is:
2026-10-14 00:55:33.383659 | ubuntu-noble -> localhost | SHA256:lhr+BRZg6qaXk5+fdMCk8rmOLpCAEh3J3vZ/RlSHw4U zuul-build-sshkey
2026-10-14 00:55:33.383706 | ubuntu-noble -> localhost | The key's randomart image is:
2026-10-14 00:55:33.383730 | ubuntu-noble -> localhost | +---[RSA 3072]----+
2026-10-14 00:55:33.383768 | ubuntu-noble -> localhost | | o.o  o    . +.  |
2026-10-14 00:55:33.383795 | ubuntu-noble -> localhost | |. +  o .    E .  |
2026-10-14 00:55:33.383816 | ubuntu-noble -> localhost | |.o ..   o  . o   |
2026-10-14 00:55:33.383838 | ubuntu-noble -> localhost | |+ ..o  + o.      |
2026-10-14 00:55:33.383858 | ubuntu-noble -> localhost | |o. .+.o S.       |
2026-10-14 00:55:33.383879 | ubuntu-noble -> localhost | |o  o *.* o.      |
2026-10-14 00:55:33.383900 | ubuntu-noble -> localhost | | .. = =...o      |
2026-10-14 00:55:33.383920 | ubuntu-noble -> localhost | |  .. + =.+o      |
2026-10-14 00:55:33.383942 | ubuntu-noble -> localhost | |   oo.=.+o       |
2026-10-14 00:55:33.383964 | ubuntu-noble -> localhost | +----[SHA256]-----+
2026-10-14 00:55:33.384012 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.546592
2026-10-14 00:55:33.389019 | 
2026-10-14 00:55:33.389081 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-10-14 00:55:33.440931 | ubuntu-noble | ok
2026-10-14 00:55:33.453003 | ubuntu-noble | included: /var/lib/zuul/builds/67e0484fc4074f75bc292f7d73ece2e3/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-10-14 00:55:33.461374 | 
2026-10-14 00:55:33.461436 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-10-14 00:55:33.477116 | ubuntu-noble | skipping: Conditional result was False
2026-10-14 00:55:33.484355 | 
2026-10-14 00:55:33.484440 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-10-14 00:55:34.028324 | ubuntu-noble | changed
2026-10-14 00:55:34.039654 | 
2026-10-14 00:55:34.039810 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-10-14 00:55:34.270958 | ubuntu-noble | ok
2026-10-14 00:55:34.282237 | 
2026-10-14 00:55:34.282448 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-10-14 00:55:34.896136 | ubuntu-noble | changed
2026-10-14 00:55:34.906673 | 
2026-10-14 00:55:34.906825 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-10-14 00:55:35.508603 | ubuntu-noble | changed
2026-10-14 00:55:35.521068 | 
2026-10-14 00:55:35.521156 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-10-14 00:55:35.544459 | ubuntu-noble | skipping: Conditional result was False
2026-10-14 00:55:35.557245 | 
2026-10-14 00:55:35.557388 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-10-14 00:55:35.947295 | ubuntu-noble -> localhost | changed
2026-10-14 00:55:35.957935 | 
2026-10-14 00:55:35.958043 | TASK [add-build-sshkey : Add back temp key]
2026-10-14 00:55:36.268020 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/67e0484fc4074f75bc292f7d73ece2e3/work/67e0484fc4074f75bc292f7d73ece2e3_id_rsa (zuul-build-sshkey)
2026-10-14 00:55:36.268393 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.015831
2026-10-14 00:55:36.280431 | 
2026-10-14 00:55:36.280602 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-10-14 00:55:36.664959 | ubuntu-noble | ok
2026-10-14 00:55:36.676694 | 
2026-10-14 00:55:36.676836 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-10-14 00:55:36.702789 | ubuntu-noble | skipping: Conditional result was False
2026-10-14 00:55:36.725516 | 
2026-10-14 00:55:36.725680 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-10-14 00:55:37.126789 | ubuntu-noble | ok
2026-10-14 00:55:37.192641 | 
2026-10-14 00:55:37.192814 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-10-14 00:55:38.032118 | ubuntu-noble | Output suppressed because no_log was given
2026-10-14 00:55:38.043863 | 
2026-10-14 00:55:38.043963 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-10-14 00:55:38.291149 | ubuntu-noble | ok: "logs"
2026-10-14 00:55:38.291520 | ubuntu-noble | ok: All items complete
2026-10-14 00:55:38.291599 | 
2026-10-14 00:55:38.510547 | ubuntu-noble | ok: "artifacts"
2026-10-14 00:55:38.715318 | ubuntu-noble | ok: "docs"
2026-10-14 00:55:38.731112 | 
2026-10-14 00:55:38.731289 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-10-14 00:55:38.964078 | ubuntu-noble | changed: "logs"
2026-10-14 00:55:39.145137 | ubuntu-noble | changed: "artifacts"
2026-10-14 00:55:39.358195 | ubuntu-noble | changed: "docs"
2026-10-14 00:55:39.373003 | 
2026-10-14 00:55:39.373144 | PLAY RECAP
2026-10-14 00:55:39.373190 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-10-14 00:55:39.373219 | ubuntu-noble | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-10-14 00:55:39.373241 | 
2026-10-14 00:55:39.533947 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-10-14 00:55:39.535089 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/pre-commit/pre.yaml@main]
2026-10-14 00:55:40.121661 | 
2026-10-14 00:55:40.122135 | PLAY [all]
2026-10-14 00:55:40.133715 | 
2026-10-14 00:55:40.133785 | TASK [setup-uv : Extract archive]
2026-10-14 00:55:42.688406 | ubuntu-noble | changed
2026-10-14 00:55:42.700131 | 
2026-10-14 00:55:42.700295 | TASK [setup-uv : Print version]
2026-10-14 00:55:43.117492 | ubuntu-noble | uv 0.8.13
2026-10-14 00:55:43.247470 | ubuntu-noble | ok: Runtime: 0:00:00.020122
2026-10-14 00:55:43.261556 | 
2026-10-14 00:55:43.261755 | PLAY RECAP
2026-10-14 00:55:43.261842 | ubuntu-noble | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-10-14 00:55:43.261888 | 
2026-10-14 00:55:43.491872 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/pre-commit/pre.yaml@main]
2026-10-14 00:55:43.494193 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/pre-commit/run.yaml@main]
2026-10-14 00:55:44.150705 | 
2026-10-14 00:55:44.150878 | PLAY [all]
2026-10-14 00:55:44.165197 | 
2026-10-14 00:55:44.165359 | TASK [Run "pre-commit"]
2026-10-14 00:55:45.418528 | ubuntu-noble | Downloading virtualenv (5.2MiB)
2026-10-14 00:55:45.562866 | ubuntu-noble |  Downloading virtualenv
2026-10-14 00:55:45.579891 | ubuntu-noble | Installed 11 packages in 16ms
2026-10-14 00:55:45.947013 | ubuntu-noble | [INFO] Initializing environment for https://github.com/pre-commit/pre-commit-hooks.
2026-10-14 00:55:46.537896 | ubuntu-noble | [WARNING] repo `https://github.com/pre-commit/pre-commit-hooks` uses deprecated stage names (commit, push) which will be removed in a future version.  Hint: often `pre-commit autoupdate --repo https://github.com/pre-commit/pre-commit-hooks` will fix this.  if it does not -- consider reporting an issue to that repo.
2026-10-14 00:55:46.545355 | ubuntu-noble | [INFO] Initializing environment for https://github.com/psf/black.
2026-10-14 00:55:48.077110 | ubuntu-noble | [INFO] Initializing environment for https://github.com/pycqa/flake8.
2026-10-14 00:55:48.563157 | ubuntu-noble | [INFO] Initializing environment for https://github.com/pycqa/isort.
2026-10-14 00:55:49.100019 | ubuntu-noble | [WARNING] repo `https://github.com/pycqa/isort` uses deprecated stage names (commit, merge-commit, push) which will be removed in a future version.  Hint: often `pre-commit autoupdate --repo https://github.com/pycqa/isort` will fix this.  if it does not -- consider reporting an issue to that repo.
2026-10-14 00:55:49.101671 | ubuntu-noble | [INFO] Initializing environment for https://github.com/ansible/ansible-lint.git.
2026-10-14 00:55:49.723102 | ubuntu-noble | [INFO] Initializing environment for https://github.com/ansible/ansible-lint.git:ansible-core>=2.16.0.
2026-10-14 00:55:50.349336 | ubuntu-noble | [INFO] Installing environment for https://github.com/pre-commit/pre-commit-hooks.
2026-10-14 00:55:50.349420 | ubuntu-noble | [INFO] Once installed this environment will be reused.
2026-10-14 00:55:50.349438 | ubuntu-noble | [INFO] This may take a few minutes...
2026-10-14 00:55:55.265021 | ubuntu-noble | [INFO] Installing environment for https://github.com/psf/black.
2026-10-14 00:55:55.265104 | ubuntu-noble | [INFO] Once installed this environment will be reused.
2026-10-14 00:55:55.265115 | ubuntu-noble | [INFO] This may take a few minutes...
2026-10-14 00:56:02.469942 | ubuntu-noble | [INFO] Installing environment for https://github.com/pycqa/flake8.
2026-10-14 00:56:02.470121 | ubuntu-noble | [INFO] Once installed this environment will be reused.
2026-10-14 00:56:02.470215 | ubuntu-noble | [INFO] This may take a few minutes...
2026-10-14 00:56:06.176190 | ubuntu-noble | [INFO] Installing environment for https://github.com/pycqa/isort.
2026-10-14 00:56:06.176274 | ubuntu-noble | [INFO] Once installed this environment will be reused.
2026-10-14 00:56:06.176280 | ubuntu-noble | [INFO] This may take a few minutes...
2026-10-14 00:56:09.232034 | ubuntu-noble | [INFO] Installing environment for https://github.com/ansible/ansible-lint.git.
2026-10-14 00:56:09.232154 | ubuntu-noble | [INFO] Once installed this environment will be reused.
2026-10-14 00:56:09.232178 | ubuntu-noble | [INFO] This may take a few minutes...
2026-10-14 00:56:22.303614 | ubuntu-noble | fix end of files.........................................................Passed
2026-10-14 00:56:22.365421 | ubuntu-noble | trim trailing whitespace.................................................Passed
2026-10-14 00:56:22.648842 | ubuntu-noble | black....................................................................Passed
2026-10-14 00:56:22.870846 | ubuntu-noble | flake8...................................................................Passed
2026-10-14 00:56:23.011334 | ubuntu-noble | isort....................................................................Passed
2026-10-14 00:56:41.710373 | ubuntu-noble | Ansible-lint.............................................................Passed
2026-10-14 00:56:42.230139 | ubuntu-noble | ok: Runtime: 0:00:57.123209
2026-10-14 00:56:42.233019 | 
2026-10-14 00:56:42.233081 | PLAY RECAP
2026-10-14 00:56:42.233139 | ubuntu-noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-10-14 00:56:42.233161 | 
2026-10-14 00:56:42.460143 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/pre-commit/run.yaml@main]
2026-10-14 00:56:42.466574 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-10-14 00:56:43.094243 | 
2026-10-14 00:56:43.094370 | PLAY [all]
2026-10-14 00:56:43.105502 | 
2026-10-14 00:56:43.105584 | TASK [fetch-output : Set log path for multiple nodes]
2026-10-14 00:56:43.150635 | ubuntu-noble | skipping: Conditional result was False
2026-10-14 00:56:43.156106 | 
2026-10-14 00:56:43.156179 | TASK [fetch-output : Set log path for single node]
2026-10-14 00:56:43.199983 | ubuntu-noble | ok
2026-10-14 00:56:43.290001 | 
2026-10-14 00:56:43.290180 | LOOP [fetch-output : Ensure local output dirs]
2026-10-14 00:56:43.759412 | ubuntu-noble -> localhost | ok: "/var/lib/zuul/builds/67e0484fc4074f75bc292f7d73ece2e3/work/logs"
2026-10-14 00:56:43.984675 | ubuntu-noble -> localhost | changed: "/var/lib/zuul/builds/67e0484fc4074f75bc292f7d73ece2e3/work/artifacts"
2026-10-14 00:56:44.223652 | ubuntu-noble -> localhost | changed: "/var/lib/zuul/builds/67e0484fc4074f75bc292f7d73ece2e3/work/docs"
2026-10-14 00:56:44.232445 | 
2026-10-14 00:56:44.232585 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-10-14 00:56:44.983467 | ubuntu-noble | changed: .d..t...... ./
2026-10-14 00:56:44.983696 | ubuntu-noble | changed: All items complete
2026-10-14 00:56:44.983738 | 
2026-10-14 00:56:45.508924 | ubuntu-noble | changed: .d..t...... ./
2026-10-14 00:56:46.021972 | ubuntu-noble | changed: .d..t...... ./
2026-10-14 00:56:46.045881 | 
2026-10-14 00:56:46.046058 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-10-14 00:56:46.492952 | ubuntu-noble -> localhost | ok: Item: artifacts Runtime: 0:00:00.007414
2026-10-14 00:56:46.738331 | ubuntu-noble -> localhost | ok: Item: docs Runtime: 0:00:00.007864
2026-10-14 00:56:46.791910 | 
2026-10-14 00:56:46.792104 | PLAY [all]
2026-10-14 00:56:46.804757 | 
2026-10-14 00:56:46.804886 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-10-14 00:56:47.298516 | ubuntu-noble | changed
2026-10-14 00:56:47.319840 | 
2026-10-14 00:56:47.319940 | PLAY RECAP
2026-10-14 00:56:47.320000 | ubuntu-noble | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-10-14 00:56:47.320027 | 
2026-10-14 00:56:47.455932 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-10-14 00:56:47.457249 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-10-14 00:56:48.003288 | 
2026-10-14 00:56:48.003595 | PLAY [localhost]
2026-10-14 00:56:48.013006 | 
2026-10-14 00:56:48.013075 | TASK [Generate Zuul manifest]
2026-10-14 00:56:48.029133 | localhost | ok
2026-10-14 00:56:48.041856 | 
2026-10-14 00:56:48.041952 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-10-14 00:56:48.417110 | localhost | changed
2026-10-14 00:56:48.445302 | 
2026-10-14 00:56:48.445482 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-10-14 00:56:48.474187 | localhost | ok
2026-10-14 00:56:48.483266 | 
2026-10-14 00:56:48.483366 | TASK [Upload logs]
2026-10-14 00:56:48.500842 | localhost | ok
2026-10-14 00:56:48.555453 | 
2026-10-14 00:56:48.555561 | TASK [Set zuul-log-path fact]
2026-10-14 00:56:48.571968 | localhost | ok
2026-10-14 00:56:48.581218 | 
2026-10-14 00:56:48.581282 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-10-14 00:56:48.609608 | localhost | ok
2026-10-14 00:56:48.617457 | 
2026-10-14 00:56:48.617537 | TASK [upload-logs : Create log directories]
2026-10-14 00:56:49.052826 | localhost | changed
2026-10-14 00:56:49.057371 | 
2026-10-14 00:56:49.057440 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-10-14 00:56:49.464120 | localhost -> localhost | ok: Runtime: 0:00:00.005201
2026-10-14 00:56:49.473249 | 
2026-10-14 00:56:49.473381 | TASK [upload-logs : Upload logs to log server]
2026-10-14 00:56:49.971645 | localhost | Output suppressed because no_log was given
2026-10-14 00:56:49.978318 | 
2026-10-14 00:56:49.978489 | LOOP [upload-logs : Compress console log and json output]
2026-10-14 00:56:50.042295 | localhost | skipping: Conditional result was False
2026-10-14 00:56:50.063337 | localhost | skipping: Conditional result was False
2026-10-14 00:56:50.077766 | 
2026-10-14 00:56:50.077973 | LOOP [upload-logs : Upload compressed console log and json output]
2026-10-14 00:56:50.122438 | localhost | skipping: Conditional result was False
2026-10-14 00:56:50.122918 | 
2026-10-14 00:56:50.141433 | localhost | skipping: Conditional result was False
2026-10-14 00:56:50.162061 | 
2026-10-14 00:56:50.162389 | LOOP [upload-logs : Upload console log and json output]