2026-05-21 22:44:08.841024 | Job console starting
2026-05-21 22:44:08.853974 | Updating git repos
2026-05-21 22:44:08.877521 | Cloning repos into workspace
2026-05-21 22:44:08.971290 | Restoring repo states
2026-05-21 22:44:08.976579 | Merging changes
2026-05-21 22:44:09.223241 | Checking out repos
2026-05-21 22:44:09.251105 | Preparing playbooks
2026-05-21 22:44:10.477164 | Running Ansible setup
2026-05-21 22:44:14.107808 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-05-21 22:44:14.703747 | 
2026-05-21 22:44:14.703896 | PLAY [localhost]
2026-05-21 22:44:14.711791 | 
2026-05-21 22:44:14.711864 | TASK [Gathering Facts]
2026-05-21 22:44:15.560728 | localhost | ok
2026-05-21 22:44:15.570774 | 
2026-05-21 22:44:15.570886 | TASK [Setup log path fact]
2026-05-21 22:44:15.593871 | localhost | ok
2026-05-21 22:44:15.606202 | 
2026-05-21 22:44:15.606298 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-05-21 22:44:15.638085 | localhost | ok
2026-05-21 22:44:15.647934 | 
2026-05-21 22:44:15.648126 | TASK [emit-job-header : Print job information]
2026-05-21 22:44:15.691868 | # Job Information
2026-05-21 22:44:15.692166 | Ansible Version: 2.16.18
2026-05-21 22:44:15.692239 | Job: pre-commit
2026-05-21 22:44:15.692290 | Pipeline: check
2026-05-21 22:44:15.692335 | Executor: 2d72f0692154
2026-05-21 22:44:15.692381 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/130
2026-05-21 22:44:15.692432 | Event ID: 7e688440-5566-11f1-87a2-04a2a7b79ebe
2026-05-21 22:44:15.696843 | 
2026-05-21 22:44:15.697007 | LOOP [emit-job-header : Print node information]
2026-05-21 22:44:15.802716 | localhost | ok:
2026-05-21 22:44:15.802912 | localhost | # Node Information
2026-05-21 22:44:15.802942 | localhost | Inventory Hostname: ubuntu-noble
2026-05-21 22:44:15.802965 | localhost | Hostname: np0000176032
2026-05-21 22:44:15.802986 | localhost | Username: zuul
2026-05-21 22:44:15.803010 | localhost | Distro: Ubuntu 24.04
2026-05-21 22:44:15.803031 | localhost | Provider: yul1
2026-05-21 22:44:15.803051 | localhost | Region: ca-ymq-1
2026-05-21 22:44:15.803070 | localhost | Label: ubuntu-noble
2026-05-21 22:44:15.803089 | localhost | Product Name: OpenStack Nova
2026-05-21 22:44:15.803108 | localhost | Interface IP: 199.204.45.89
2026-05-21 22:44:15.817096 | 
2026-05-21 22:44:15.817203 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-05-21 22:44:16.258038 | localhost -> localhost | changed
2026-05-21 22:44:16.269897 | 
2026-05-21 22:44:16.270021 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-05-21 22:44:17.700882 | localhost -> localhost | changed
2026-05-21 22:44:17.708558 | 
2026-05-21 22:44:17.708629 | PLAY [all]
2026-05-21 22:44:17.722689 | 
2026-05-21 22:44:17.722783 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-05-21 22:44:18.202095 | ubuntu-noble -> localhost | ok
2026-05-21 22:44:18.208891 | 
2026-05-21 22:44:18.209001 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-05-21 22:44:18.250635 | ubuntu-noble | ok
2026-05-21 22:44:18.268299 | ubuntu-noble | included: /var/lib/zuul/builds/15fcc94ae1f14e6f933820664e5876ae/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-05-21 22:44:18.274773 | 
2026-05-21 22:44:18.274835 | TASK [add-build-sshkey : Create Temp SSH key]
2026-05-21 22:44:20.080649 | ubuntu-noble -> localhost | Generating public/private rsa key pair.
2026-05-21 22:44:20.081054 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/15fcc94ae1f14e6f933820664e5876ae/work/15fcc94ae1f14e6f933820664e5876ae_id_rsa
2026-05-21 22:44:20.081091 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/15fcc94ae1f14e6f933820664e5876ae/work/15fcc94ae1f14e6f933820664e5876ae_id_rsa.pub
2026-05-21 22:44:20.081115 | ubuntu-noble -> localhost | The key fingerprint is:
2026-05-21 22:44:20.081137 | ubuntu-noble -> localhost | SHA256:6AiVLyDIhMGGK3O+Ki679WXe0P/PDZsB1HiU63uuCB4 zuul-build-sshkey
2026-05-21 22:44:20.081177 | ubuntu-noble -> localhost | The key's randomart image is:
2026-05-21 22:44:20.081199 | ubuntu-noble -> localhost | +---[RSA 3072]----+
2026-05-21 22:44:20.081223 | ubuntu-noble -> localhost | |=o            .. |
2026-05-21 22:44:20.081245 | ubuntu-noble -> localhost | |*o   .       +.  |
2026-05-21 22:44:20.081266 | ubuntu-noble -> localhost | |+o. o       o o. |
2026-05-21 22:44:20.081286 | ubuntu-noble -> localhost | |+..o . .   . ..  |
2026-05-21 22:44:20.081306 | ubuntu-noble -> localhost | |.+. . o S   ..   |
2026-05-21 22:44:20.081326 | ubuntu-noble -> localhost | |  .. + .     ..  |
2026-05-21 22:44:20.081345 | ubuntu-noble -> localhost | |  ... = . E   o. |
2026-05-21 22:44:20.081365 | ubuntu-noble -> localhost | |o... + o o o o.*.|
2026-05-21 22:44:20.081387 | ubuntu-noble -> localhost | |B=  . . . o.o.*+o|
2026-05-21 22:44:20.081408 | ubuntu-noble -> localhost | +----[SHA256]-----+
2026-05-21 22:44:20.081454 | ubuntu-noble -> localhost | ok: Runtime: 0:00:01.355425
2026-05-21 22:44:20.086913 | 
2026-05-21 22:44:20.086973 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-05-21 22:44:20.128317 | ubuntu-noble | ok
2026-05-21 22:44:20.139746 | ubuntu-noble | included: /var/lib/zuul/builds/15fcc94ae1f14e6f933820664e5876ae/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-05-21 22:44:20.147607 | 
2026-05-21 22:44:20.147682 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-05-21 22:44:20.172419 | ubuntu-noble | skipping: Conditional result was False
2026-05-21 22:44:20.178669 | 
2026-05-21 22:44:20.178745 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-05-21 22:44:20.730603 | ubuntu-noble | changed
2026-05-21 22:44:20.738334 | 
2026-05-21 22:44:20.738409 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-05-21 22:44:20.965492 | ubuntu-noble | ok
2026-05-21 22:44:20.973621 | 
2026-05-21 22:44:20.973683 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-05-21 22:44:21.544385 | ubuntu-noble | changed
2026-05-21 22:44:21.561388 | 
2026-05-21 22:44:21.561603 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-05-21 22:44:22.131355 | ubuntu-noble | changed
2026-05-21 22:44:22.543047 | 
2026-05-21 22:44:22.543208 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-05-21 22:44:22.568649 | ubuntu-noble | skipping: Conditional result was False
2026-05-21 22:44:22.576086 | 
2026-05-21 22:44:22.576196 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-05-21 22:44:23.027149 | ubuntu-noble -> localhost | changed
2026-05-21 22:44:23.038775 | 
2026-05-21 22:44:23.038864 | TASK [add-build-sshkey : Add back temp key]
2026-05-21 22:44:23.556291 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/15fcc94ae1f14e6f933820664e5876ae/work/15fcc94ae1f14e6f933820664e5876ae_id_rsa (zuul-build-sshkey)
2026-05-21 22:44:23.556514 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.014561
2026-05-21 22:44:23.562970 | 
2026-05-21 22:44:23.563085 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-05-21 22:44:23.936367 | ubuntu-noble | ok
2026-05-21 22:44:23.941491 | 
2026-05-21 22:44:23.941556 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-05-21 22:44:23.965685 | ubuntu-noble | skipping: Conditional result was False
2026-05-21 22:44:23.981479 | 
2026-05-21 22:44:24.190411 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-05-21 22:44:24.566810 | ubuntu-noble | ok
2026-05-21 22:44:24.623848 | 
2026-05-21 22:44:24.623972 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-05-21 22:44:25.474499 | ubuntu-noble | Output suppressed because no_log was given
2026-05-21 22:44:25.506848 | 
2026-05-21 22:44:25.506931 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-05-21 22:44:25.741545 | ubuntu-noble | ok: "logs"
2026-05-21 22:44:25.741757 | ubuntu-noble | ok: All items complete
2026-05-21 22:44:25.741798 | 
2026-05-21 22:44:25.940377 | ubuntu-noble | ok: "artifacts"
2026-05-21 22:44:26.129334 | ubuntu-noble | ok: "docs"
2026-05-21 22:44:26.143945 | 
2026-05-21 22:44:26.144092 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-05-21 22:44:26.368170 | ubuntu-noble | changed: "logs"
2026-05-21 22:44:26.551997 | ubuntu-noble | changed: "artifacts"
2026-05-21 22:44:26.733736 | ubuntu-noble | changed: "docs"
2026-05-21 22:44:26.751543 | 
2026-05-21 22:44:26.751671 | PLAY RECAP
2026-05-21 22:44:26.751723 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-05-21 22:44:26.751755 | ubuntu-noble | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-05-21 22:44:26.751777 | 
2026-05-21 22:44:26.961429 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-05-21 22:44:26.965717 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/pre-commit/pre.yaml@main]
2026-05-21 22:44:27.668604 | 
2026-05-21 22:44:27.668767 | PLAY [all]
2026-05-21 22:44:27.682520 | 
2026-05-21 22:44:27.682610 | TASK [setup-uv : Extract archive]
2026-05-21 22:44:30.040971 | ubuntu-noble | changed
2026-05-21 22:44:30.047683 | 
2026-05-21 22:44:30.047767 | TASK [setup-uv : Print version]
2026-05-21 22:44:29.282281 | ubuntu-noble | uv 0.8.13
2026-05-21 22:44:30.597367 | ubuntu-noble | ok: Runtime: 0:00:00.018167
2026-05-21 22:44:30.607982 | 
2026-05-21 22:44:30.608033 | PLAY RECAP
2026-05-21 22:44:30.608196 | ubuntu-noble | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-05-21 22:44:30.608245 | 
2026-05-21 22:44:30.797637 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/pre-commit/pre.yaml@main]
2026-05-21 22:44:30.798933 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/pre-commit/run.yaml@main]
2026-05-21 22:44:31.487352 | 
2026-05-21 22:44:31.535084 | PLAY [all]
2026-05-21 22:44:31.558196 | 
2026-05-21 22:44:31.558291 | TASK [Run "pre-commit"]
2026-05-21 22:44:30.975334 | ubuntu-noble | Downloading virtualenv (7.2MiB)
2026-05-21 22:44:31.078803 | ubuntu-noble |  Downloading virtualenv
2026-05-21 22:44:31.092527 | ubuntu-noble | Installed 10 packages in 13ms
2026-05-21 22:44:31.460765 | ubuntu-noble | [INFO] Initializing environment for https://github.com/pre-commit/pre-commit-hooks.
2026-05-21 22:44:32.775985 | 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-05-21 22:44:32.781363 | ubuntu-noble | [INFO] Initializing environment for https://github.com/psf/black.
2026-05-21 22:44:33.327774 | ubuntu-noble | [INFO] Initializing environment for https://github.com/pycqa/flake8.
2026-05-21 22:44:33.726716 | ubuntu-noble | [INFO] Initializing environment for https://github.com/pycqa/isort.
2026-05-21 22:44:34.149816 | 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-05-21 22:44:34.151450 | ubuntu-noble | [INFO] Initializing environment for https://github.com/ansible/ansible-lint.git.
2026-05-21 22:44:35.910760 | ubuntu-noble | [INFO] Initializing environment for https://github.com/ansible/ansible-lint.git:ansible-core>=2.16.0.
2026-05-21 22:44:36.448173 | ubuntu-noble | [INFO] Installing environment for https://github.com/pre-commit/pre-commit-hooks.
2026-05-21 22:44:36.448223 | ubuntu-noble | [INFO] Once installed this environment will be reused.
2026-05-21 22:44:36.448235 | ubuntu-noble | [INFO] This may take a few minutes...
2026-05-21 22:44:40.464660 | ubuntu-noble | [INFO] Installing environment for https://github.com/psf/black.
2026-05-21 22:44:40.464752 | ubuntu-noble | [INFO] Once installed this environment will be reused.
2026-05-21 22:44:40.464772 | ubuntu-noble | [INFO] This may take a few minutes...
2026-05-21 22:44:44.631920 | ubuntu-noble | [INFO] Installing environment for https://github.com/pycqa/flake8.
2026-05-21 22:44:44.632099 | ubuntu-noble | [INFO] Once installed this environment will be reused.
2026-05-21 22:44:44.632115 | ubuntu-noble | [INFO] This may take a few minutes...
2026-05-21 22:44:47.900376 | ubuntu-noble | [INFO] Installing environment for https://github.com/pycqa/isort.
2026-05-21 22:44:47.900476 | ubuntu-noble | [INFO] Once installed this environment will be reused.
2026-05-21 22:44:47.900488 | ubuntu-noble | [INFO] This may take a few minutes...
2026-05-21 22:44:50.498043 | ubuntu-noble | [INFO] Installing environment for https://github.com/ansible/ansible-lint.git.
2026-05-21 22:44:50.498100 | ubuntu-noble | [INFO] Once installed this environment will be reused.
2026-05-21 22:44:50.498111 | ubuntu-noble | [INFO] This may take a few minutes...
2026-05-21 22:45:00.162085 | ubuntu-noble | fix end of files.........................................................Passed
2026-05-21 22:45:00.216710 | ubuntu-noble | trim trailing whitespace.................................................Passed
2026-05-21 22:45:00.471762 | ubuntu-noble | black....................................................................Passed
2026-05-21 22:45:00.660479 | ubuntu-noble | flake8...................................................................Passed
2026-05-21 22:45:00.786290 | ubuntu-noble | isort....................................................................Passed
2026-05-21 22:45:17.353329 | ubuntu-noble | Ansible-lint.............................................................Passed
2026-05-21 22:45:17.679747 | ubuntu-noble | ok: Runtime: 0:00:46.587408
2026-05-21 22:45:17.682265 | 
2026-05-21 22:45:17.682332 | PLAY RECAP
2026-05-21 22:45:17.682390 | ubuntu-noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-05-21 22:45:17.682421 | 
2026-05-21 22:45:17.845173 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/pre-commit/run.yaml@main]
2026-05-21 22:45:17.847588 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-05-21 22:45:18.430231 | 
2026-05-21 22:45:18.430356 | PLAY [all]
2026-05-21 22:45:18.442604 | 
2026-05-21 22:45:18.442706 | TASK [fetch-output : Set log path for multiple nodes]
2026-05-21 22:45:18.487670 | ubuntu-noble | skipping: Conditional result was False
2026-05-21 22:45:18.497443 | 
2026-05-21 22:45:18.497552 | TASK [fetch-output : Set log path for single node]
2026-05-21 22:45:18.542053 | ubuntu-noble | ok
2026-05-21 22:45:18.547028 | 
2026-05-21 22:45:18.547203 | LOOP [fetch-output : Ensure local output dirs]
2026-05-21 22:45:18.924743 | ubuntu-noble -> localhost | ok: "/var/lib/zuul/builds/15fcc94ae1f14e6f933820664e5876ae/work/logs"
2026-05-21 22:45:19.137343 | ubuntu-noble -> localhost | changed: "/var/lib/zuul/builds/15fcc94ae1f14e6f933820664e5876ae/work/artifacts"
2026-05-21 22:45:19.340032 | ubuntu-noble -> localhost | changed: "/var/lib/zuul/builds/15fcc94ae1f14e6f933820664e5876ae/work/docs"
2026-05-21 22:45:19.353770 | 
2026-05-21 22:45:19.353892 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-05-21 22:45:19.984711 | ubuntu-noble | changed: .d..t...... ./
2026-05-21 22:45:19.985026 | ubuntu-noble | changed: All items complete
2026-05-21 22:45:19.985070 | 
2026-05-21 22:45:20.433761 | ubuntu-noble | changed: .d..t...... ./
2026-05-21 22:45:20.910470 | ubuntu-noble | changed: .d..t...... ./
2026-05-21 22:45:20.932725 | 
2026-05-21 22:45:20.932894 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-05-21 22:45:21.376943 | ubuntu-noble -> localhost | ok: Item: artifacts Runtime: 0:00:00.006979
2026-05-21 22:45:21.596883 | ubuntu-noble -> localhost | ok: Item: docs Runtime: 0:00:00.006605
2026-05-21 22:45:21.619071 | 
2026-05-21 22:45:21.619240 | PLAY [all]
2026-05-21 22:45:21.626068 | 
2026-05-21 22:45:21.626138 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-05-21 22:45:22.100092 | ubuntu-noble | changed
2026-05-21 22:45:22.106841 | 
2026-05-21 22:45:22.106890 | PLAY RECAP
2026-05-21 22:45:22.106937 | ubuntu-noble | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-05-21 22:45:22.106959 | 
2026-05-21 22:45:22.247318 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-05-21 22:45:22.250198 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-05-21 22:45:22.840765 | 
2026-05-21 22:45:22.840891 | PLAY [localhost]
2026-05-21 22:45:22.851472 | 
2026-05-21 22:45:22.851547 | TASK [Generate Zuul manifest]
2026-05-21 22:45:22.870682 | localhost | ok
2026-05-21 22:45:22.886834 | 
2026-05-21 22:45:22.886916 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-05-21 22:45:23.285563 | localhost | changed
2026-05-21 22:45:23.297131 | 
2026-05-21 22:45:23.297260 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-05-21 22:45:23.329046 | localhost | ok
2026-05-21 22:45:23.338806 | 
2026-05-21 22:45:23.338876 | TASK [Upload logs]
2026-05-21 22:45:23.360264 | localhost | ok
2026-05-21 22:45:23.419096 | 
2026-05-21 22:45:23.419234 | TASK [Set zuul-log-path fact]
2026-05-21 22:45:23.440699 | localhost | ok
2026-05-21 22:45:23.453845 | 
2026-05-21 22:45:23.453928 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-05-21 22:45:23.485624 | localhost | ok
2026-05-21 22:45:23.494565 | 
2026-05-21 22:45:23.494634 | TASK [upload-logs : Create log directories]
2026-05-21 22:45:23.994380 | localhost | changed
2026-05-21 22:45:23.999797 | 
2026-05-21 22:45:23.999870 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-05-21 22:45:24.407753 | localhost -> localhost | ok: Runtime: 0:00:00.005697
2026-05-21 22:45:24.413068 | 
2026-05-21 22:45:24.413139 | TASK [upload-logs : Upload logs to log server]
2026-05-21 22:45:24.941478 | localhost | Output suppressed because no_log was given
2026-05-21 22:45:24.949101 | 
2026-05-21 22:45:24.949175 | LOOP [upload-logs : Compress console log and json output]
2026-05-21 22:45:24.999477 | localhost | skipping: Conditional result was False
2026-05-21 22:45:25.007861 | localhost | skipping: Conditional result was False
2026-05-21 22:45:25.019653 | 
2026-05-21 22:45:25.019836 | LOOP [upload-logs : Upload compressed console log and json output]
2026-05-21 22:45:25.062330 | localhost | skipping: Conditional result was False
2026-05-21 22:45:25.062723 | 
2026-05-21 22:45:25.066692 | localhost | skipping: Conditional result was False
2026-05-21 22:45:25.083921 | 
2026-05-21 22:45:25.084267 | LOOP [upload-logs : Upload console log and json output]